[12:27:59.857] <TB3> INFO: *** Welcome to pxar ***
[12:27:59.857] <TB3> INFO: *** Today: 2016/03/22
[12:27:59.891] <TB3> INFO: *** Version: 9751-dirty
[12:27:59.891] <TB3> INFO: readRocDacs: /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C0.dat .. /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C15.dat
[12:27:59.908] <TB3> INFO: readTbmDacs: /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//tbmParameters_C0b.dat
[12:27:59.910] <TB3> INFO: readMaskFile: /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//defaultMaskFile.dat
[12:27:59.911] <TB3> INFO: readTrimFile: /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters_C0.dat .. /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters_C15.dat
[12:28:00.032] <TB3> INFO: clk: 4
[12:28:00.032] <TB3> INFO: ctr: 4
[12:28:00.032] <TB3> INFO: sda: 19
[12:28:00.032] <TB3> INFO: tin: 9
[12:28:00.032] <TB3> INFO: level: 15
[12:28:00.032] <TB3> INFO: triggerdelay: 0
[12:28:00.032] <TB3> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[12:28:00.032] <TB3> INFO: Log level: INFO
[12:28:00.039] <TB3> INFO: Found DTB DTB_WWVASW
[12:28:00.049] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[12:28:00.051] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[12:28:00.053] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[12:28:01.575] <TB3> INFO: DUT info:
[12:28:01.575] <TB3> INFO: The DUT currently contains the following objects:
[12:28:01.575] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:28:01.575] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:28:01.575] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:28:01.575] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:28:01.575] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.575] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.575] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.575] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.576] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:01.977] <TB3> INFO: enter 'restricted' command line mode
[12:28:01.977] <TB3> INFO: enter test to run
[12:28:01.978] <TB3> INFO: test: pretest no parameter change
[12:28:01.978] <TB3> INFO: running: pretest
[12:28:01.982] <TB3> INFO: ----------------------------------------------------------------------
[12:28:01.982] <TB3> INFO: PixTestPretest::programROC()
[12:28:01.982] <TB3> INFO: ----------------------------------------------------------------------
[12:28:20.000] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:28:20.000] <TB3> INFO: IA differences per ROC: 19.3 18.5 19.3 20.1 20.1 21.7 19.3 20.1 19.3 21.7 20.1 20.1 17.7 19.3 20.9 20.1
[12:28:20.114] <TB3> INFO: enter test to run
[12:28:20.114] <TB3> INFO: test: pretest no parameter change
[12:28:20.114] <TB3> INFO: running: pretest
[12:28:20.116] <TB3> INFO: ----------------------------------------------------------------------
[12:28:20.116] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:28:20.116] <TB3> INFO: ----------------------------------------------------------------------
[12:28:26.128] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 377.8 mA = 23.6125 mA/ROC
[12:28:26.129] <TB3> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 18.5 19.3 19.3 19.3 18.5 17.7 18.5 18.5 18.5 18.5 17.7 18.5 18.5
[12:28:26.173] <TB3> INFO: enter test to run
[12:28:26.173] <TB3> INFO: test: pretest no parameter change
[12:28:26.173] <TB3> INFO: running: pretest
[12:28:26.174] <TB3> INFO: ----------------------------------------------------------------------
[12:28:26.174] <TB3> INFO: PixTestPretest::findTiming()
[12:28:26.174] <TB3> INFO: ----------------------------------------------------------------------
[12:28:26.174] <TB3> INFO: PixTestCmd::init()
[12:28:27.130] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[12:29:57.881] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[12:29:57.881] <TB3> INFO: (success/tries = 100/100), width = 4
[12:29:57.882] <TB3> INFO: enter test to run
[12:29:57.882] <TB3> INFO: test: pretest no parameter change
[12:29:57.882] <TB3> INFO: running: pretest
[12:29:57.884] <TB3> INFO: ----------------------------------------------------------------------
[12:29:57.884] <TB3> INFO: PixTestPretest::findWorkingPixel()
[12:29:57.884] <TB3> INFO: ----------------------------------------------------------------------
[12:29:57.985] <TB3> INFO: Expecting 231680 events.
[12:30:03.752] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[12:30:03.804] <TB3> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[12:30:22.320] <TB3> INFO: 231680 events read in total (23778ms).
[12:30:22.324] <TB3> INFO: Test took 24434ms.
[12:30:22.567] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:30:22.647] <TB3> INFO: enter test to run
[12:30:22.647] <TB3> INFO: test: pretest no parameter change
[12:30:22.647] <TB3> INFO: running: pretest
[12:30:22.649] <TB3> INFO: ----------------------------------------------------------------------
[12:30:22.649] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[12:30:22.649] <TB3> INFO: ----------------------------------------------------------------------
[12:30:22.745] <TB3> INFO: Expecting 231680 events.
[12:30:46.784] <TB3> INFO: 231680 events read in total (23482ms).
[12:30:46.787] <TB3> INFO: Test took 24133ms.
[12:30:47.054] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[12:30:47.054] <TB3> INFO: CalDel: 152 140 156 142 146 141 141 160 136 139 151 131 135 142 161 147
[12:30:47.054] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:30:47.087] <TB3> INFO: enter test to run
[12:30:47.087] <TB3> INFO: test: pretest no parameter change
[12:30:47.087] <TB3> INFO: running: pretest
[12:30:47.163] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C0.dat
[12:30:47.168] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C1.dat
[12:30:47.173] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C2.dat
[12:30:47.178] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C3.dat
[12:30:47.184] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C4.dat
[12:30:47.189] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C5.dat
[12:30:47.194] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C6.dat
[12:30:47.199] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C7.dat
[12:30:47.204] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C8.dat
[12:30:47.209] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C9.dat
[12:30:47.214] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C10.dat
[12:30:47.219] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C11.dat
[12:30:47.224] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C12.dat
[12:30:47.229] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C13.dat
[12:30:47.234] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C14.dat
[12:30:47.239] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters_C15.dat
[12:30:47.242] <TB3> INFO: enter test to run
[12:30:47.242] <TB3> INFO: test: fulltest no parameter change
[12:30:47.242] <TB3> INFO: running: fulltest
[12:30:47.242] <TB3> INFO: ######################################################################
[12:30:47.242] <TB3> INFO: PixTestFullTest::doTest()
[12:30:47.242] <TB3> INFO: ######################################################################
[12:30:47.243] <TB3> INFO: ######################################################################
[12:30:47.243] <TB3> INFO: PixTestAlive::doTest()
[12:30:47.243] <TB3> INFO: ######################################################################
[12:30:47.245] <TB3> INFO: ----------------------------------------------------------------------
[12:30:47.245] <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)
[12:30:47.245] <TB3> INFO: ----------------------------------------------------------------------
[12:30:47.514] <TB3> INFO: Expecting 41600 events.
[12:30:55.466] <TB3> INFO: 41600 events read in total (7395ms).
[12:30:55.467] <TB3> INFO: Test took 8220ms.
[12:30:55.714] <TB3> INFO: PixTestAlive::aliveTest() done
[12:30:55.714] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:30:55.716] <TB3> INFO: ----------------------------------------------------------------------
[12:30:55.716] <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)
[12:30:55.716] <TB3> INFO: ----------------------------------------------------------------------
[12:30:55.982] <TB3> INFO: Expecting 41600 events.
[12:31:01.066] <TB3> INFO: 41600 events read in total (4527ms).
[12:31:01.066] <TB3> INFO: Test took 5348ms.
[12:31:01.066] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:31:01.329] <TB3> INFO: PixTestAlive::maskTest() done
[12:31:01.329] <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
[12:31:01.330] <TB3> INFO: ----------------------------------------------------------------------
[12:31:01.330] <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)
[12:31:01.330] <TB3> INFO: ----------------------------------------------------------------------
[12:31:01.597] <TB3> INFO: Expecting 41600 events.
[12:31:09.571] <TB3> INFO: 41600 events read in total (7417ms).
[12:31:09.572] <TB3> INFO: Test took 8240ms.
[12:31:09.823] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[12:31:09.823] <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
[12:31:09.824] <TB3> INFO: PixTestAlive::doTest() done, duration: 22 seconds
[12:31:09.824] <TB3> INFO: Decoding statistics:
[12:31:09.824] <TB3> INFO: General information:
[12:31:09.824] <TB3> INFO: 16bit words read: 0
[12:31:09.824] <TB3> INFO: valid events total: 0
[12:31:09.824] <TB3> INFO: empty events: 0
[12:31:09.824] <TB3> INFO: valid events with pixels: 0
[12:31:09.824] <TB3> INFO: valid pixel hits: 0
[12:31:09.824] <TB3> INFO: Event errors: 0
[12:31:09.824] <TB3> INFO: start marker: 0
[12:31:09.824] <TB3> INFO: stop marker: 0
[12:31:09.824] <TB3> INFO: overflow: 0
[12:31:09.824] <TB3> INFO: invalid 5bit words: 0
[12:31:09.824] <TB3> INFO: invalid XOR eye diagram: 0
[12:31:09.824] <TB3> INFO: frame (failed synchr.): 0
[12:31:09.824] <TB3> INFO: idle data (no TBM trl): 0
[12:31:09.824] <TB3> INFO: no data (only TBM hdr): 0
[12:31:09.824] <TB3> INFO: TBM errors: 0
[12:31:09.824] <TB3> INFO: flawed TBM headers: 0
[12:31:09.824] <TB3> INFO: flawed TBM trailers: 0
[12:31:09.824] <TB3> INFO: event ID mismatches: 0
[12:31:09.824] <TB3> INFO: ROC errors: 0
[12:31:09.824] <TB3> INFO: missing ROC header(s): 0
[12:31:09.824] <TB3> INFO: misplaced readback start: 0
[12:31:09.824] <TB3> INFO: Pixel decoding errors: 0
[12:31:09.824] <TB3> INFO: pixel data incomplete: 0
[12:31:09.824] <TB3> INFO: pixel address: 0
[12:31:09.824] <TB3> INFO: pulse height fill bit: 0
[12:31:09.824] <TB3> INFO: buffer corruption: 0
[12:31:09.832] <TB3> INFO: ######################################################################
[12:31:09.832] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:31:09.832] <TB3> INFO: ######################################################################
[12:31:09.837] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:31:09.849] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:31:09.849] <TB3> INFO: run 1 of 1
[12:31:10.124] <TB3> INFO: Expecting 3120000 events.
[12:32:58.609] <TB3> INFO: 870320 events read in total (107928ms).
[12:34:46.853] <TB3> INFO: 1731645 events read in total (216172ms).
[12:36:35.772] <TB3> INFO: 2606460 events read in total (325092ms).
[12:37:38.593] <TB3> INFO: 3120000 events read in total (387912ms).
[12:37:38.653] <TB3> INFO: Test took 388804ms.
[12:38:02.518] <TB3> INFO: PixTestBBMap::doTest() done, duration: 412 seconds
[12:38:02.519] <TB3> INFO: number of dead bumps (per ROC): 5 0 0 0 1 0 0 1 1 0 1 0 0 1 7 24
[12:38:02.519] <TB3> INFO: separation cut (per ROC): 72 81 66 71 82 105 78 80 84 94 90 83 87 69 78 67
[12:38:02.519] <TB3> INFO: Decoding statistics:
[12:38:02.519] <TB3> INFO: General information:
[12:38:02.519] <TB3> INFO: 16bit words read: 0
[12:38:02.519] <TB3> INFO: valid events total: 0
[12:38:02.519] <TB3> INFO: empty events: 0
[12:38:02.519] <TB3> INFO: valid events with pixels: 0
[12:38:02.519] <TB3> INFO: valid pixel hits: 0
[12:38:02.519] <TB3> INFO: Event errors: 0
[12:38:02.519] <TB3> INFO: start marker: 0
[12:38:02.519] <TB3> INFO: stop marker: 0
[12:38:02.519] <TB3> INFO: overflow: 0
[12:38:02.519] <TB3> INFO: invalid 5bit words: 0
[12:38:02.519] <TB3> INFO: invalid XOR eye diagram: 0
[12:38:02.519] <TB3> INFO: frame (failed synchr.): 0
[12:38:02.519] <TB3> INFO: idle data (no TBM trl): 0
[12:38:02.519] <TB3> INFO: no data (only TBM hdr): 0
[12:38:02.519] <TB3> INFO: TBM errors: 0
[12:38:02.519] <TB3> INFO: flawed TBM headers: 0
[12:38:02.519] <TB3> INFO: flawed TBM trailers: 0
[12:38:02.519] <TB3> INFO: event ID mismatches: 0
[12:38:02.519] <TB3> INFO: ROC errors: 0
[12:38:02.519] <TB3> INFO: missing ROC header(s): 0
[12:38:02.519] <TB3> INFO: misplaced readback start: 0
[12:38:02.519] <TB3> INFO: Pixel decoding errors: 0
[12:38:02.519] <TB3> INFO: pixel data incomplete: 0
[12:38:02.519] <TB3> INFO: pixel address: 0
[12:38:02.519] <TB3> INFO: pulse height fill bit: 0
[12:38:02.519] <TB3> INFO: buffer corruption: 0
[12:38:02.595] <TB3> INFO: ######################################################################
[12:38:02.595] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:38:02.595] <TB3> INFO: ######################################################################
[12:38:02.595] <TB3> INFO: ----------------------------------------------------------------------
[12:38:02.595] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:38:02.595] <TB3> INFO: ----------------------------------------------------------------------
[12:38:02.595] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:38:02.603] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[12:38:02.603] <TB3> INFO: run 1 of 1
[12:38:02.863] <TB3> INFO: Expecting 26208000 events.
[12:39:43.433] <TB3> INFO: 855150 events read in total (100013ms).
[12:41:23.709] <TB3> INFO: 1699950 events read in total (200289ms).
[12:43:03.662] <TB3> INFO: 2543350 events read in total (300242ms).
[12:44:44.080] <TB3> INFO: 3385800 events read in total (400660ms).
[12:46:23.909] <TB3> INFO: 4230300 events read in total (500489ms).
[12:48:04.292] <TB3> INFO: 5074750 events read in total (600872ms).
[12:49:44.182] <TB3> INFO: 5917450 events read in total (700762ms).
[12:51:24.310] <TB3> INFO: 6760450 events read in total (800890ms).
[12:53:03.328] <TB3> INFO: 7603050 events read in total (899908ms).
[12:54:42.301] <TB3> INFO: 8444600 events read in total (998881ms).
[12:56:21.998] <TB3> INFO: 9287400 events read in total (1098578ms).
[12:58:02.193] <TB3> INFO: 10128950 events read in total (1198773ms).
[12:59:42.457] <TB3> INFO: 10967750 events read in total (1299037ms).
[13:01:22.595] <TB3> INFO: 11809900 events read in total (1399175ms).
[13:03:02.623] <TB3> INFO: 12651700 events read in total (1499203ms).
[13:04:42.312] <TB3> INFO: 13484400 events read in total (1598892ms).
[13:06:22.162] <TB3> INFO: 14317750 events read in total (1698742ms).
[13:08:02.578] <TB3> INFO: 15150600 events read in total (1799158ms).
[13:09:42.324] <TB3> INFO: 15980250 events read in total (1898904ms).
[13:11:22.058] <TB3> INFO: 16809950 events read in total (1998638ms).
[13:13:01.817] <TB3> INFO: 17639250 events read in total (2098397ms).
[13:14:41.840] <TB3> INFO: 18468050 events read in total (2198420ms).
[13:16:21.930] <TB3> INFO: 19298150 events read in total (2298510ms).
[13:18:01.858] <TB3> INFO: 20126300 events read in total (2398438ms).
[13:19:42.136] <TB3> INFO: 20954400 events read in total (2498716ms).
[13:21:22.264] <TB3> INFO: 21785800 events read in total (2598844ms).
[13:23:01.628] <TB3> INFO: 22614650 events read in total (2698208ms).
[13:24:41.447] <TB3> INFO: 23446700 events read in total (2798027ms).
[13:26:21.617] <TB3> INFO: 24279550 events read in total (2898197ms).
[13:28:01.599] <TB3> INFO: 25111600 events read in total (2998179ms).
[13:29:41.651] <TB3> INFO: 25955250 events read in total (3098231ms).
[13:30:11.194] <TB3> INFO: 26208000 events read in total (3127774ms).
[13:30:11.224] <TB3> INFO: Test took 3128621ms.
[13:30:11.485] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:13.095] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:14.665] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:16.260] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:17.792] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:19.422] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:20.953] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:22.586] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:24.093] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:25.627] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:27.130] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:28.621] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:30.251] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:31.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:33.338] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:34.871] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:30:36.521] <TB3> INFO: PixTestScurves::scurves() done
[13:30:36.521] <TB3> INFO: Vcal mean: 82.45 81.46 67.12 74.15 79.12 99.95 80.49 85.55 88.77 87.34 92.01 78.79 89.48 72.21 83.73 78.74
[13:30:36.521] <TB3> INFO: Vcal RMS: 4.38 4.48 4.66 4.68 4.91 5.84 4.74 5.14 5.68 5.90 7.27 4.03 6.31 4.71 5.21 4.13
[13:30:36.521] <TB3> INFO: PixTestScurves::fullTest() done, duration: 3153 seconds
[13:30:36.521] <TB3> INFO: Decoding statistics:
[13:30:36.521] <TB3> INFO: General information:
[13:30:36.521] <TB3> INFO: 16bit words read: 0
[13:30:36.521] <TB3> INFO: valid events total: 0
[13:30:36.521] <TB3> INFO: empty events: 0
[13:30:36.521] <TB3> INFO: valid events with pixels: 0
[13:30:36.521] <TB3> INFO: valid pixel hits: 0
[13:30:36.521] <TB3> INFO: Event errors: 0
[13:30:36.521] <TB3> INFO: start marker: 0
[13:30:36.521] <TB3> INFO: stop marker: 0
[13:30:36.521] <TB3> INFO: overflow: 0
[13:30:36.521] <TB3> INFO: invalid 5bit words: 0
[13:30:36.521] <TB3> INFO: invalid XOR eye diagram: 0
[13:30:36.521] <TB3> INFO: frame (failed synchr.): 0
[13:30:36.521] <TB3> INFO: idle data (no TBM trl): 0
[13:30:36.521] <TB3> INFO: no data (only TBM hdr): 0
[13:30:36.521] <TB3> INFO: TBM errors: 0
[13:30:36.521] <TB3> INFO: flawed TBM headers: 0
[13:30:36.521] <TB3> INFO: flawed TBM trailers: 0
[13:30:36.521] <TB3> INFO: event ID mismatches: 0
[13:30:36.521] <TB3> INFO: ROC errors: 0
[13:30:36.521] <TB3> INFO: missing ROC header(s): 0
[13:30:36.521] <TB3> INFO: misplaced readback start: 0
[13:30:36.521] <TB3> INFO: Pixel decoding errors: 0
[13:30:36.521] <TB3> INFO: pixel data incomplete: 0
[13:30:36.521] <TB3> INFO: pixel address: 0
[13:30:36.521] <TB3> INFO: pulse height fill bit: 0
[13:30:36.521] <TB3> INFO: buffer corruption: 0
[13:30:36.595] <TB3> INFO: ######################################################################
[13:30:36.595] <TB3> INFO: PixTestTrim::doTest()
[13:30:36.595] <TB3> INFO: ######################################################################
[13:30:36.597] <TB3> INFO: ----------------------------------------------------------------------
[13:30:36.597] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[13:30:36.597] <TB3> INFO: ----------------------------------------------------------------------
[13:30:36.677] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:30:36.677] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:30:36.685] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:30:36.685] <TB3> INFO: run 1 of 1
[13:30:36.947] <TB3> INFO: Expecting 5025280 events.
[13:32:26.467] <TB3> INFO: 1077096 events read in total (108963ms).
[13:34:15.206] <TB3> INFO: 2147192 events read in total (217702ms).
[13:36:03.853] <TB3> INFO: 3217608 events read in total (326349ms).
[13:37:51.930] <TB3> INFO: 4298776 events read in total (434426ms).
[13:39:04.657] <TB3> INFO: 5025280 events read in total (507153ms).
[13:39:04.690] <TB3> INFO: Test took 508005ms.
[13:39:22.790] <TB3> INFO: ROC 0 VthrComp = 87
[13:39:22.791] <TB3> INFO: ROC 1 VthrComp = 86
[13:39:22.791] <TB3> INFO: ROC 2 VthrComp = 73
[13:39:22.791] <TB3> INFO: ROC 3 VthrComp = 78
[13:39:22.791] <TB3> INFO: ROC 4 VthrComp = 80
[13:39:22.791] <TB3> INFO: ROC 5 VthrComp = 101
[13:39:22.791] <TB3> INFO: ROC 6 VthrComp = 83
[13:39:22.791] <TB3> INFO: ROC 7 VthrComp = 88
[13:39:22.792] <TB3> INFO: ROC 8 VthrComp = 87
[13:39:22.792] <TB3> INFO: ROC 9 VthrComp = 90
[13:39:22.792] <TB3> INFO: ROC 10 VthrComp = 86
[13:39:22.792] <TB3> INFO: ROC 11 VthrComp = 84
[13:39:22.792] <TB3> INFO: ROC 12 VthrComp = 87
[13:39:22.792] <TB3> INFO: ROC 13 VthrComp = 76
[13:39:22.792] <TB3> INFO: ROC 14 VthrComp = 84
[13:39:22.792] <TB3> INFO: ROC 15 VthrComp = 80
[13:39:22.793] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:39:22.793] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:39:22.800] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:39:22.800] <TB3> INFO: run 1 of 1
[13:39:23.062] <TB3> INFO: Expecting 5025280 events.
[13:41:07.574] <TB3> INFO: 756616 events read in total (103955ms).
[13:42:52.077] <TB3> INFO: 1512160 events read in total (208458ms).
[13:44:36.582] <TB3> INFO: 2267736 events read in total (312963ms).
[13:46:20.651] <TB3> INFO: 3019360 events read in total (417032ms).
[13:48:05.023] <TB3> INFO: 3766184 events read in total (521404ms).
[13:49:47.315] <TB3> INFO: 4511584 events read in total (623696ms).
[13:50:59.923] <TB3> INFO: 5025280 events read in total (696304ms).
[13:50:59.973] <TB3> INFO: Test took 697173ms.
[13:51:25.624] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.0785 for pixel 21/69 mean/min/max = 45.069/32.994/57.1441
[13:51:25.625] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 57.0685 for pixel 24/4 mean/min/max = 44.4658/31.8593/57.0722
[13:51:25.625] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.8357 for pixel 0/2 mean/min/max = 45.9667/34.8592/57.0742
[13:51:25.626] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.4102 for pixel 11/9 mean/min/max = 46.5525/34.6833/58.4218
[13:51:25.626] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.3983 for pixel 33/79 mean/min/max = 45.9483/32.4536/59.443
[13:51:25.626] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 61.3587 for pixel 4/12 mean/min/max = 46.8119/32.1705/61.4533
[13:51:25.626] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.8039 for pixel 23/79 mean/min/max = 45.4823/32.1058/58.8588
[13:51:25.627] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 59.0584 for pixel 14/70 mean/min/max = 46.0188/32.7869/59.2507
[13:51:25.627] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 60.3894 for pixel 0/63 mean/min/max = 46.092/31.7905/60.3936
[13:51:25.627] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.4108 for pixel 20/75 mean/min/max = 46.3379/32.1913/60.4845
[13:51:25.627] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 66.6512 for pixel 1/71 mean/min/max = 48.5581/30.3989/66.7172
[13:51:25.627] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 56.4793 for pixel 14/5 mean/min/max = 44.294/32.0293/56.5588
[13:51:25.628] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 61.9246 for pixel 23/75 mean/min/max = 46.2875/30.6042/61.9708
[13:51:25.628] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.3271 for pixel 15/79 mean/min/max = 46.8914/35.2392/58.5437
[13:51:25.628] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.3521 for pixel 51/72 mean/min/max = 46.1264/32.8855/59.3673
[13:51:25.628] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 57.2472 for pixel 23/11 mean/min/max = 45.2271/33.1917/57.2625
[13:51:25.629] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:51:25.718] <TB3> INFO: Expecting 411648 events.
[13:51:58.580] <TB3> INFO: 411648 events read in total (32305ms).
[13:51:58.585] <TB3> INFO: Expecting 411648 events.
[13:52:30.925] <TB3> INFO: 411648 events read in total (31915ms).
[13:52:30.931] <TB3> INFO: Expecting 411648 events.
[13:53:03.341] <TB3> INFO: 411648 events read in total (31985ms).
[13:53:03.349] <TB3> INFO: Expecting 411648 events.
[13:53:35.830] <TB3> INFO: 411648 events read in total (32055ms).
[13:53:35.840] <TB3> INFO: Expecting 411648 events.
[13:54:08.778] <TB3> INFO: 411648 events read in total (32521ms).
[13:54:08.790] <TB3> INFO: Expecting 411648 events.
[13:54:41.541] <TB3> INFO: 411648 events read in total (32336ms).
[13:54:41.555] <TB3> INFO: Expecting 411648 events.
[13:55:14.130] <TB3> INFO: 411648 events read in total (32156ms).
[13:55:14.145] <TB3> INFO: Expecting 411648 events.
[13:55:46.886] <TB3> INFO: 411648 events read in total (32318ms).
[13:55:46.903] <TB3> INFO: Expecting 411648 events.
[13:56:19.762] <TB3> INFO: 411648 events read in total (32441ms).
[13:56:19.781] <TB3> INFO: Expecting 411648 events.
[13:56:52.906] <TB3> INFO: 411648 events read in total (32710ms).
[13:56:52.928] <TB3> INFO: Expecting 411648 events.
[13:57:26.018] <TB3> INFO: 411648 events read in total (32686ms).
[13:57:26.042] <TB3> INFO: Expecting 411648 events.
[13:57:58.876] <TB3> INFO: 411648 events read in total (32431ms).
[13:57:58.901] <TB3> INFO: Expecting 411648 events.
[13:58:31.684] <TB3> INFO: 411648 events read in total (32380ms).
[13:58:31.712] <TB3> INFO: Expecting 411648 events.
[13:59:04.136] <TB3> INFO: 411648 events read in total (32022ms).
[13:59:04.165] <TB3> INFO: Expecting 411648 events.
[13:59:36.197] <TB3> INFO: 411648 events read in total (31630ms).
[13:59:36.230] <TB3> INFO: Expecting 411648 events.
[14:00:08.622] <TB3> INFO: 411648 events read in total (31988ms).
[14:00:08.656] <TB3> INFO: Test took 523027ms.
[14:00:09.560] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:00:09.568] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:00:09.568] <TB3> INFO: run 1 of 1
[14:00:09.840] <TB3> INFO: Expecting 5025280 events.
[14:01:54.631] <TB3> INFO: 740488 events read in total (104234ms).
[14:03:38.418] <TB3> INFO: 1480568 events read in total (208021ms).
[14:05:22.091] <TB3> INFO: 2220616 events read in total (311694ms).
[14:07:05.646] <TB3> INFO: 2956208 events read in total (415249ms).
[14:08:49.450] <TB3> INFO: 3685952 events read in total (519053ms).
[14:10:32.340] <TB3> INFO: 4413336 events read in total (621943ms).
[14:11:59.402] <TB3> INFO: 5025280 events read in total (709005ms).
[14:11:59.450] <TB3> INFO: Test took 709882ms.
[14:12:25.296] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 7.616640 .. 255.000000
[14:12:25.371] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 7 .. 255 (-1/-1) hits flags = 528 (plus default)
[14:12:25.379] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:12:25.379] <TB3> INFO: run 1 of 1
[14:12:25.640] <TB3> INFO: Expecting 8286720 events.
[14:14:09.533] <TB3> INFO: 703048 events read in total (103335ms).
[14:15:53.611] <TB3> INFO: 1406248 events read in total (207413ms).
[14:17:37.450] <TB3> INFO: 2109904 events read in total (311252ms).
[14:19:20.679] <TB3> INFO: 2813680 events read in total (414481ms).
[14:21:04.070] <TB3> INFO: 3517392 events read in total (517872ms).
[14:22:47.386] <TB3> INFO: 4221272 events read in total (621188ms).
[14:24:30.083] <TB3> INFO: 4924648 events read in total (723885ms).
[14:26:13.583] <TB3> INFO: 5627160 events read in total (827385ms).
[14:27:57.071] <TB3> INFO: 6329192 events read in total (930873ms).
[14:29:40.801] <TB3> INFO: 7030680 events read in total (1034603ms).
[14:31:24.286] <TB3> INFO: 7731920 events read in total (1138088ms).
[14:32:45.559] <TB3> INFO: 8286720 events read in total (1219361ms).
[14:32:45.639] <TB3> INFO: Test took 1220260ms.
[14:33:14.702] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.765208 .. 47.863777
[14:33:14.789] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 4 .. 57 (-1/-1) hits flags = 528 (plus default)
[14:33:14.797] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:33:14.797] <TB3> INFO: run 1 of 1
[14:33:15.088] <TB3> INFO: Expecting 1797120 events.
[14:35:02.429] <TB3> INFO: 928456 events read in total (106784ms).
[14:36:42.317] <TB3> INFO: 1797120 events read in total (206672ms).
[14:36:42.335] <TB3> INFO: Test took 207539ms.
[14:36:56.745] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.829410 .. 45.535188
[14:36:56.828] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 9 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:36:56.836] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:36:56.836] <TB3> INFO: run 1 of 1
[14:36:57.105] <TB3> INFO: Expecting 1564160 events.
[14:38:44.562] <TB3> INFO: 917160 events read in total (106900ms).
[14:40:00.232] <TB3> INFO: 1564160 events read in total (182570ms).
[14:40:00.263] <TB3> INFO: Test took 183428ms.
[14:40:14.894] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 22.362534 .. 45.249989
[14:40:14.980] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 12 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:40:14.989] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:40:14.989] <TB3> INFO: run 1 of 1
[14:40:15.262] <TB3> INFO: Expecting 1464320 events.
[14:42:02.197] <TB3> INFO: 898464 events read in total (106379ms).
[14:43:09.711] <TB3> INFO: 1464320 events read in total (173893ms).
[14:43:09.725] <TB3> INFO: Test took 174736ms.
[14:43:23.372] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:43:23.372] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:43:23.381] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:43:23.382] <TB3> INFO: run 1 of 1
[14:43:23.662] <TB3> INFO: Expecting 1364480 events.
[14:45:11.304] <TB3> INFO: 879040 events read in total (107084ms).
[14:46:10.736] <TB3> INFO: 1364480 events read in total (166516ms).
[14:46:10.749] <TB3> INFO: Test took 167368ms.
[14:46:24.612] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C0.dat
[14:46:24.617] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C1.dat
[14:46:24.620] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C2.dat
[14:46:24.624] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C3.dat
[14:46:24.628] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C4.dat
[14:46:24.632] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C5.dat
[14:46:24.635] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C6.dat
[14:46:24.639] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C7.dat
[14:46:24.643] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C8.dat
[14:46:24.647] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C9.dat
[14:46:24.650] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C10.dat
[14:46:24.654] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C11.dat
[14:46:24.658] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C12.dat
[14:46:24.662] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C13.dat
[14:46:24.665] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C14.dat
[14:46:24.669] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C15.dat
[14:46:24.673] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C0.dat
[14:46:24.685] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C1.dat
[14:46:24.696] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C2.dat
[14:46:24.710] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C3.dat
[14:46:24.722] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C4.dat
[14:46:24.734] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C5.dat
[14:46:24.747] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C6.dat
[14:46:24.759] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C7.dat
[14:46:24.771] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C8.dat
[14:46:24.784] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C9.dat
[14:46:24.796] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C10.dat
[14:46:24.807] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C11.dat
[14:46:24.820] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C12.dat
[14:46:24.833] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C13.dat
[14:46:24.845] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C14.dat
[14:46:24.857] <TB3> INFO: write trim parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//trimParameters35_C15.dat
[14:46:24.869] <TB3> INFO: PixTestTrim::trimTest() done
[14:46:24.869] <TB3> INFO: vtrim: 92 87 88 91 93 103 89 90 108 107 129 87 108 90 80 83
[14:46:24.869] <TB3> INFO: vthrcomp: 87 86 73 78 80 101 83 88 87 90 86 84 87 76 84 80
[14:46:24.869] <TB3> INFO: vcal mean: 35.02 34.94 34.98 34.98 34.99 34.98 34.96 34.98 35.00 34.98 34.98 34.97 34.90 34.98 35.02 34.97
[14:46:24.869] <TB3> INFO: vcal RMS: 0.92 0.91 0.84 0.90 0.97 0.97 0.95 0.93 1.00 0.91 1.30 0.87 1.01 0.85 0.92 0.91
[14:46:24.869] <TB3> INFO: bits mean: 9.71 10.12 8.84 8.85 9.29 9.26 9.53 9.22 9.54 9.53 9.66 10.03 9.80 8.47 9.07 9.82
[14:46:24.869] <TB3> INFO: bits RMS: 2.52 2.47 2.56 2.58 2.69 2.67 2.71 2.69 2.68 2.61 2.63 2.59 2.66 2.58 2.74 2.43
[14:46:24.877] <TB3> INFO: ----------------------------------------------------------------------
[14:46:24.877] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:46:24.877] <TB3> INFO: ----------------------------------------------------------------------
[14:46:24.881] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:46:24.889] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:46:24.889] <TB3> INFO: run 1 of 1
[14:46:25.165] <TB3> INFO: Expecting 4160000 events.
[14:48:14.807] <TB3> INFO: 896755 events read in total (109085ms).
[14:50:03.248] <TB3> INFO: 1789700 events read in total (217526ms).
[14:51:51.731] <TB3> INFO: 2675865 events read in total (326009ms).
[14:53:40.593] <TB3> INFO: 3559620 events read in total (434871ms).
[14:54:53.407] <TB3> INFO: 4160000 events read in total (507686ms).
[14:54:53.446] <TB3> INFO: Test took 508556ms.
[14:55:21.472] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 190 (-1/-1) hits flags = 528 (plus default)
[14:55:21.480] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:55:21.480] <TB3> INFO: run 1 of 1
[14:55:21.742] <TB3> INFO: Expecting 3972800 events.
[14:57:09.670] <TB3> INFO: 880885 events read in total (107371ms).
[14:58:57.518] <TB3> INFO: 1758300 events read in total (215220ms).
[15:00:44.975] <TB3> INFO: 2628605 events read in total (322676ms).
[15:02:31.443] <TB3> INFO: 3497450 events read in total (429144ms).
[15:03:30.142] <TB3> INFO: 3972800 events read in total (487843ms).
[15:03:30.182] <TB3> INFO: Test took 488701ms.
[15:04:00.274] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[15:04:00.283] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:04:00.283] <TB3> INFO: run 1 of 1
[15:04:00.580] <TB3> INFO: Expecting 3619200 events.
[15:05:49.571] <TB3> INFO: 909465 events read in total (108434ms).
[15:07:38.860] <TB3> INFO: 1813875 events read in total (217723ms).
[15:09:27.542] <TB3> INFO: 2709020 events read in total (326406ms).
[15:11:17.061] <TB3> INFO: 3608550 events read in total (435924ms).
[15:11:18.680] <TB3> INFO: 3619200 events read in total (437543ms).
[15:11:18.721] <TB3> INFO: Test took 438437ms.
[15:11:44.859] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[15:11:44.867] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:11:44.867] <TB3> INFO: run 1 of 1
[15:11:45.129] <TB3> INFO: Expecting 3224000 events.
[15:13:35.417] <TB3> INFO: 950875 events read in total (109732ms).
[15:15:24.361] <TB3> INFO: 1893040 events read in total (218676ms).
[15:17:13.893] <TB3> INFO: 2827145 events read in total (328209ms).
[15:18:00.288] <TB3> INFO: 3224000 events read in total (374603ms).
[15:18:00.324] <TB3> INFO: Test took 375456ms.
[15:18:25.424] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[15:18:25.432] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:18:25.432] <TB3> INFO: run 1 of 1
[15:18:25.707] <TB3> INFO: Expecting 3224000 events.
[15:20:16.431] <TB3> INFO: 949805 events read in total (110167ms).
[15:22:06.905] <TB3> INFO: 1890765 events read in total (220641ms).
[15:23:55.653] <TB3> INFO: 2824300 events read in total (329390ms).
[15:24:42.986] <TB3> INFO: 3224000 events read in total (376722ms).
[15:24:43.021] <TB3> INFO: Test took 377588ms.
[15:25:08.091] <TB3> INFO: PixTestTrim::trimBitTest() done
[15:25:08.092] <TB3> INFO: PixTestTrim::doTest() done, duration: 6871 seconds
[15:25:08.092] <TB3> INFO: Decoding statistics:
[15:25:08.092] <TB3> INFO: General information:
[15:25:08.092] <TB3> INFO: 16bit words read: 0
[15:25:08.092] <TB3> INFO: valid events total: 0
[15:25:08.092] <TB3> INFO: empty events: 0
[15:25:08.092] <TB3> INFO: valid events with pixels: 0
[15:25:08.092] <TB3> INFO: valid pixel hits: 0
[15:25:08.092] <TB3> INFO: Event errors: 0
[15:25:08.092] <TB3> INFO: start marker: 0
[15:25:08.092] <TB3> INFO: stop marker: 0
[15:25:08.092] <TB3> INFO: overflow: 0
[15:25:08.092] <TB3> INFO: invalid 5bit words: 0
[15:25:08.092] <TB3> INFO: invalid XOR eye diagram: 0
[15:25:08.092] <TB3> INFO: frame (failed synchr.): 0
[15:25:08.092] <TB3> INFO: idle data (no TBM trl): 0
[15:25:08.092] <TB3> INFO: no data (only TBM hdr): 0
[15:25:08.092] <TB3> INFO: TBM errors: 0
[15:25:08.092] <TB3> INFO: flawed TBM headers: 0
[15:25:08.092] <TB3> INFO: flawed TBM trailers: 0
[15:25:08.092] <TB3> INFO: event ID mismatches: 0
[15:25:08.092] <TB3> INFO: ROC errors: 0
[15:25:08.092] <TB3> INFO: missing ROC header(s): 0
[15:25:08.092] <TB3> INFO: misplaced readback start: 0
[15:25:08.092] <TB3> INFO: Pixel decoding errors: 0
[15:25:08.092] <TB3> INFO: pixel data incomplete: 0
[15:25:08.092] <TB3> INFO: pixel address: 0
[15:25:08.092] <TB3> INFO: pulse height fill bit: 0
[15:25:08.093] <TB3> INFO: buffer corruption: 0
[15:25:08.769] <TB3> INFO: ######################################################################
[15:25:08.769] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:25:08.769] <TB3> INFO: ######################################################################
[15:25:09.034] <TB3> INFO: Expecting 41600 events.
[15:25:17.141] <TB3> INFO: 41600 events read in total (7550ms).
[15:25:17.142] <TB3> INFO: Test took 8371ms.
[15:25:17.613] <TB3> INFO: Expecting 41600 events.
[15:25:25.797] <TB3> INFO: 41600 events read in total (7627ms).
[15:25:25.798] <TB3> INFO: Test took 8449ms.
[15:25:26.088] <TB3> INFO: Expecting 41600 events.
[15:25:34.131] <TB3> INFO: 41600 events read in total (7486ms).
[15:25:34.132] <TB3> INFO: Test took 8313ms.
[15:25:34.391] <TB3> INFO: Expecting 2560 events.
[15:25:35.380] <TB3> INFO: 2560 events read in total (432ms).
[15:25:35.380] <TB3> INFO: Test took 1234ms.
[15:25:35.687] <TB3> INFO: Expecting 2560 events.
[15:25:36.681] <TB3> INFO: 2560 events read in total (437ms).
[15:25:36.681] <TB3> INFO: Test took 1300ms.
[15:25:36.989] <TB3> INFO: Expecting 2560 events.
[15:25:37.981] <TB3> INFO: 2560 events read in total (435ms).
[15:25:37.982] <TB3> INFO: Test took 1300ms.
[15:25:38.289] <TB3> INFO: Expecting 2560 events.
[15:25:39.280] <TB3> INFO: 2560 events read in total (434ms).
[15:25:39.280] <TB3> INFO: Test took 1298ms.
[15:25:39.587] <TB3> INFO: Expecting 2560 events.
[15:25:40.578] <TB3> INFO: 2560 events read in total (434ms).
[15:25:40.578] <TB3> INFO: Test took 1297ms.
[15:25:40.885] <TB3> INFO: Expecting 2560 events.
[15:25:41.878] <TB3> INFO: 2560 events read in total (435ms).
[15:25:41.878] <TB3> INFO: Test took 1299ms.
[15:25:42.185] <TB3> INFO: Expecting 2560 events.
[15:25:43.176] <TB3> INFO: 2560 events read in total (434ms).
[15:25:43.176] <TB3> INFO: Test took 1297ms.
[15:25:43.483] <TB3> INFO: Expecting 2560 events.
[15:25:44.477] <TB3> INFO: 2560 events read in total (437ms).
[15:25:44.477] <TB3> INFO: Test took 1300ms.
[15:25:44.785] <TB3> INFO: Expecting 2560 events.
[15:25:45.779] <TB3> INFO: 2560 events read in total (438ms).
[15:25:45.780] <TB3> INFO: Test took 1302ms.
[15:25:46.087] <TB3> INFO: Expecting 2560 events.
[15:25:47.083] <TB3> INFO: 2560 events read in total (439ms).
[15:25:47.083] <TB3> INFO: Test took 1303ms.
[15:25:47.390] <TB3> INFO: Expecting 2560 events.
[15:25:48.388] <TB3> INFO: 2560 events read in total (441ms).
[15:25:48.389] <TB3> INFO: Test took 1305ms.
[15:25:48.697] <TB3> INFO: Expecting 2560 events.
[15:25:49.690] <TB3> INFO: 2560 events read in total (436ms).
[15:25:49.690] <TB3> INFO: Test took 1300ms.
[15:25:49.998] <TB3> INFO: Expecting 2560 events.
[15:25:50.999] <TB3> INFO: 2560 events read in total (444ms).
[15:25:50.000] <TB3> INFO: Test took 1309ms.
[15:25:51.307] <TB3> INFO: Expecting 2560 events.
[15:25:52.303] <TB3> INFO: 2560 events read in total (439ms).
[15:25:52.304] <TB3> INFO: Test took 1304ms.
[15:25:52.612] <TB3> INFO: Expecting 2560 events.
[15:25:53.614] <TB3> INFO: 2560 events read in total (445ms).
[15:25:53.615] <TB3> INFO: Test took 1310ms.
[15:25:53.922] <TB3> INFO: Expecting 2560 events.
[15:25:54.918] <TB3> INFO: 2560 events read in total (439ms).
[15:25:54.919] <TB3> INFO: Test took 1303ms.
[15:25:54.923] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:25:55.228] <TB3> INFO: Expecting 655360 events.
[15:26:50.093] <TB3> INFO: 655360 events read in total (54308ms).
[15:26:50.102] <TB3> INFO: Expecting 655360 events.
[15:27:44.798] <TB3> INFO: 655360 events read in total (54293ms).
[15:27:44.810] <TB3> INFO: Expecting 655360 events.
[15:28:39.617] <TB3> INFO: 655360 events read in total (54404ms).
[15:28:39.634] <TB3> INFO: Expecting 655360 events.
[15:29:34.284] <TB3> INFO: 655360 events read in total (54248ms).
[15:29:34.303] <TB3> INFO: Expecting 655360 events.
[15:30:28.991] <TB3> INFO: 655360 events read in total (54286ms).
[15:30:29.015] <TB3> INFO: Expecting 655360 events.
[15:31:23.675] <TB3> INFO: 655360 events read in total (54257ms).
[15:31:23.706] <TB3> INFO: Expecting 655360 events.
[15:32:18.546] <TB3> INFO: 655360 events read in total (54437ms).
[15:32:18.591] <TB3> INFO: Expecting 655360 events.
[15:33:13.379] <TB3> INFO: 655360 events read in total (54386ms).
[15:33:13.418] <TB3> INFO: Expecting 655360 events.
[15:34:08.004] <TB3> INFO: 655360 events read in total (54184ms).
[15:34:08.042] <TB3> INFO: Expecting 655360 events.
[15:35:02.784] <TB3> INFO: 655360 events read in total (54340ms).
[15:35:02.827] <TB3> INFO: Expecting 655360 events.
[15:35:57.548] <TB3> INFO: 655360 events read in total (54318ms).
[15:35:57.592] <TB3> INFO: Expecting 655360 events.
[15:36:51.757] <TB3> INFO: 655360 events read in total (53761ms).
[15:36:51.804] <TB3> INFO: Expecting 655360 events.
[15:37:46.345] <TB3> INFO: 655360 events read in total (54139ms).
[15:37:46.396] <TB3> INFO: Expecting 655360 events.
[15:38:40.935] <TB3> INFO: 655360 events read in total (54136ms).
[15:38:40.988] <TB3> INFO: Expecting 655360 events.
[15:39:34.813] <TB3> INFO: 655360 events read in total (53422ms).
[15:39:34.874] <TB3> INFO: Expecting 655360 events.
[15:40:29.009] <TB3> INFO: 655360 events read in total (53732ms).
[15:40:29.072] <TB3> INFO: Test took 874149ms.
[15:40:29.153] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:40:29.382] <TB3> INFO: Expecting 655360 events.
[15:41:23.883] <TB3> INFO: 655360 events read in total (53944ms).
[15:41:23.892] <TB3> INFO: Expecting 655360 events.
[15:42:17.866] <TB3> INFO: 655360 events read in total (53572ms).
[15:42:17.879] <TB3> INFO: Expecting 655360 events.
[15:43:12.325] <TB3> INFO: 655360 events read in total (54044ms).
[15:43:12.347] <TB3> INFO: Expecting 655360 events.
[15:44:06.121] <TB3> INFO: 655360 events read in total (53371ms).
[15:44:06.141] <TB3> INFO: Expecting 655360 events.
[15:45:00.201] <TB3> INFO: 655360 events read in total (53657ms).
[15:45:00.227] <TB3> INFO: Expecting 655360 events.
[15:45:54.264] <TB3> INFO: 655360 events read in total (53634ms).
[15:45:54.290] <TB3> INFO: Expecting 655360 events.
[15:46:47.628] <TB3> INFO: 655360 events read in total (52936ms).
[15:46:47.659] <TB3> INFO: Expecting 655360 events.
[15:47:41.975] <TB3> INFO: 655360 events read in total (53914ms).
[15:47:42.009] <TB3> INFO: Expecting 655360 events.
[15:48:35.107] <TB3> INFO: 655360 events read in total (52695ms).
[15:48:35.147] <TB3> INFO: Expecting 655360 events.
[15:49:29.358] <TB3> INFO: 655360 events read in total (53809ms).
[15:49:29.398] <TB3> INFO: Expecting 655360 events.
[15:50:23.108] <TB3> INFO: 655360 events read in total (53307ms).
[15:50:23.151] <TB3> INFO: Expecting 655360 events.
[15:51:16.927] <TB3> INFO: 655360 events read in total (53374ms).
[15:51:16.972] <TB3> INFO: Expecting 655360 events.
[15:52:10.170] <TB3> INFO: 655360 events read in total (52796ms).
[15:52:10.220] <TB3> INFO: Expecting 655360 events.
[15:53:03.507] <TB3> INFO: 655360 events read in total (52884ms).
[15:53:03.560] <TB3> INFO: Expecting 655360 events.
[15:53:57.064] <TB3> INFO: 655360 events read in total (53102ms).
[15:53:57.120] <TB3> INFO: Expecting 655360 events.
[15:54:51.078] <TB3> INFO: 655360 events read in total (53555ms).
[15:54:51.140] <TB3> INFO: Test took 861987ms.
[15:54:51.401] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.411] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.422] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.431] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.438] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.445] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.455] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.465] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.473] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.479] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.486] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[15:54:51.493] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[15:54:51.502] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[15:54:51.512] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[15:54:51.519] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[15:54:51.530] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[15:54:51.540] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.550] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.557] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.564] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.571] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.578] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:54:51.638] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C0.dat
[15:54:51.660] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C1.dat
[15:54:51.665] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C2.dat
[15:54:51.670] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C3.dat
[15:54:51.677] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C4.dat
[15:54:51.682] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C5.dat
[15:54:51.687] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C6.dat
[15:54:51.692] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C7.dat
[15:54:51.697] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C8.dat
[15:54:51.704] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C9.dat
[15:54:51.709] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C10.dat
[15:54:51.714] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C11.dat
[15:54:51.719] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C12.dat
[15:54:51.724] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C13.dat
[15:54:51.731] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C14.dat
[15:54:51.737] <TB3> INFO: write dac parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//dacParameters35_C15.dat
[15:54:52.007] <TB3> INFO: Expecting 41600 events.
[15:54:59.642] <TB3> INFO: 41600 events read in total (7078ms).
[15:54:59.643] <TB3> INFO: Test took 7899ms.
[15:55:00.096] <TB3> INFO: Expecting 41600 events.
[15:55:07.750] <TB3> INFO: 41600 events read in total (7097ms).
[15:55:07.751] <TB3> INFO: Test took 7919ms.
[15:55:08.206] <TB3> INFO: Expecting 41600 events.
[15:55:15.831] <TB3> INFO: 41600 events read in total (7068ms).
[15:55:15.832] <TB3> INFO: Test took 7891ms.
[15:55:16.027] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:16.117] <TB3> INFO: Expecting 2560 events.
[15:55:17.125] <TB3> INFO: 2560 events read in total (451ms).
[15:55:17.126] <TB3> INFO: Test took 1099ms.
[15:55:17.129] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:17.432] <TB3> INFO: Expecting 2560 events.
[15:55:18.442] <TB3> INFO: 2560 events read in total (453ms).
[15:55:18.443] <TB3> INFO: Test took 1314ms.
[15:55:18.446] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:18.750] <TB3> INFO: Expecting 2560 events.
[15:55:19.759] <TB3> INFO: 2560 events read in total (452ms).
[15:55:19.760] <TB3> INFO: Test took 1314ms.
[15:55:19.763] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:20.066] <TB3> INFO: Expecting 2560 events.
[15:55:21.074] <TB3> INFO: 2560 events read in total (451ms).
[15:55:21.075] <TB3> INFO: Test took 1312ms.
[15:55:21.077] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:21.382] <TB3> INFO: Expecting 2560 events.
[15:55:22.390] <TB3> INFO: 2560 events read in total (451ms).
[15:55:22.391] <TB3> INFO: Test took 1314ms.
[15:55:22.393] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:22.698] <TB3> INFO: Expecting 2560 events.
[15:55:23.709] <TB3> INFO: 2560 events read in total (454ms).
[15:55:23.710] <TB3> INFO: Test took 1317ms.
[15:55:23.712] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:24.017] <TB3> INFO: Expecting 2560 events.
[15:55:25.025] <TB3> INFO: 2560 events read in total (452ms).
[15:55:25.025] <TB3> INFO: Test took 1313ms.
[15:55:25.028] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:25.332] <TB3> INFO: Expecting 2560 events.
[15:55:26.339] <TB3> INFO: 2560 events read in total (450ms).
[15:55:26.340] <TB3> INFO: Test took 1312ms.
[15:55:26.342] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:26.648] <TB3> INFO: Expecting 2560 events.
[15:55:27.662] <TB3> INFO: 2560 events read in total (457ms).
[15:55:27.662] <TB3> INFO: Test took 1320ms.
[15:55:27.666] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:27.969] <TB3> INFO: Expecting 2560 events.
[15:55:28.981] <TB3> INFO: 2560 events read in total (455ms).
[15:55:28.981] <TB3> INFO: Test took 1316ms.
[15:55:28.985] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:29.288] <TB3> INFO: Expecting 2560 events.
[15:55:30.297] <TB3> INFO: 2560 events read in total (452ms).
[15:55:30.298] <TB3> INFO: Test took 1313ms.
[15:55:30.300] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:30.605] <TB3> INFO: Expecting 2560 events.
[15:55:31.619] <TB3> INFO: 2560 events read in total (457ms).
[15:55:31.620] <TB3> INFO: Test took 1320ms.
[15:55:31.623] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:31.927] <TB3> INFO: Expecting 2560 events.
[15:55:32.936] <TB3> INFO: 2560 events read in total (452ms).
[15:55:32.936] <TB3> INFO: Test took 1313ms.
[15:55:32.939] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:33.244] <TB3> INFO: Expecting 2560 events.
[15:55:34.257] <TB3> INFO: 2560 events read in total (456ms).
[15:55:34.258] <TB3> INFO: Test took 1319ms.
[15:55:34.262] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:34.566] <TB3> INFO: Expecting 2560 events.
[15:55:35.574] <TB3> INFO: 2560 events read in total (451ms).
[15:55:35.575] <TB3> INFO: Test took 1313ms.
[15:55:35.578] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:35.882] <TB3> INFO: Expecting 2560 events.
[15:55:36.893] <TB3> INFO: 2560 events read in total (454ms).
[15:55:36.893] <TB3> INFO: Test took 1315ms.
[15:55:36.896] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:37.200] <TB3> INFO: Expecting 2560 events.
[15:55:38.209] <TB3> INFO: 2560 events read in total (452ms).
[15:55:38.209] <TB3> INFO: Test took 1314ms.
[15:55:38.213] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:38.516] <TB3> INFO: Expecting 2560 events.
[15:55:39.528] <TB3> INFO: 2560 events read in total (456ms).
[15:55:39.529] <TB3> INFO: Test took 1316ms.
[15:55:39.532] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:39.836] <TB3> INFO: Expecting 2560 events.
[15:55:40.846] <TB3> INFO: 2560 events read in total (454ms).
[15:55:40.847] <TB3> INFO: Test took 1315ms.
[15:55:40.850] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:41.153] <TB3> INFO: Expecting 2560 events.
[15:55:42.161] <TB3> INFO: 2560 events read in total (451ms).
[15:55:42.162] <TB3> INFO: Test took 1312ms.
[15:55:42.164] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:42.468] <TB3> INFO: Expecting 2560 events.
[15:55:43.474] <TB3> INFO: 2560 events read in total (449ms).
[15:55:43.475] <TB3> INFO: Test took 1311ms.
[15:55:43.477] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:43.782] <TB3> INFO: Expecting 2560 events.
[15:55:44.793] <TB3> INFO: 2560 events read in total (454ms).
[15:55:44.794] <TB3> INFO: Test took 1317ms.
[15:55:44.797] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:45.101] <TB3> INFO: Expecting 2560 events.
[15:55:46.116] <TB3> INFO: 2560 events read in total (458ms).
[15:55:46.116] <TB3> INFO: Test took 1319ms.
[15:55:46.120] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:46.424] <TB3> INFO: Expecting 2560 events.
[15:55:47.441] <TB3> INFO: 2560 events read in total (460ms).
[15:55:47.441] <TB3> INFO: Test took 1321ms.
[15:55:47.445] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:47.748] <TB3> INFO: Expecting 2560 events.
[15:55:48.758] <TB3> INFO: 2560 events read in total (453ms).
[15:55:48.758] <TB3> INFO: Test took 1314ms.
[15:55:48.761] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:49.065] <TB3> INFO: Expecting 2560 events.
[15:55:50.073] <TB3> INFO: 2560 events read in total (451ms).
[15:55:50.073] <TB3> INFO: Test took 1312ms.
[15:55:50.076] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:50.381] <TB3> INFO: Expecting 2560 events.
[15:55:51.395] <TB3> INFO: 2560 events read in total (457ms).
[15:55:51.395] <TB3> INFO: Test took 1319ms.
[15:55:51.398] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:51.702] <TB3> INFO: Expecting 2560 events.
[15:55:52.711] <TB3> INFO: 2560 events read in total (452ms).
[15:55:52.711] <TB3> INFO: Test took 1313ms.
[15:55:52.714] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:53.019] <TB3> INFO: Expecting 2560 events.
[15:55:54.031] <TB3> INFO: 2560 events read in total (454ms).
[15:55:54.031] <TB3> INFO: Test took 1317ms.
[15:55:54.036] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:54.340] <TB3> INFO: Expecting 2560 events.
[15:55:55.354] <TB3> INFO: 2560 events read in total (457ms).
[15:55:55.355] <TB3> INFO: Test took 1320ms.
[15:55:55.358] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:55.661] <TB3> INFO: Expecting 2560 events.
[15:55:56.674] <TB3> INFO: 2560 events read in total (456ms).
[15:55:56.674] <TB3> INFO: Test took 1316ms.
[15:55:56.678] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:55:56.982] <TB3> INFO: Expecting 2560 events.
[15:55:57.991] <TB3> INFO: 2560 events read in total (452ms).
[15:55:57.992] <TB3> INFO: Test took 1315ms.
[15:55:58.490] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 1849 seconds
[15:55:58.490] <TB3> INFO: PH scale (per ROC): 86 91 97 86 86 83 84 78 77 80 80 99 80 87 87 80
[15:55:58.490] <TB3> INFO: PH offset (per ROC): 157 163 141 160 161 172 163 166 171 164 177 139 173 162 161 173
[15:55:58.497] <TB3> INFO: Decoding statistics:
[15:55:58.497] <TB3> INFO: General information:
[15:55:58.497] <TB3> INFO: 16bit words read: 91928
[15:55:58.497] <TB3> INFO: valid events total: 10240
[15:55:58.497] <TB3> INFO: empty events: 7738
[15:55:58.497] <TB3> INFO: valid events with pixels: 2502
[15:55:58.497] <TB3> INFO: valid pixel hits: 2502
[15:55:58.497] <TB3> INFO: Event errors: 0
[15:55:58.497] <TB3> INFO: start marker: 0
[15:55:58.497] <TB3> INFO: stop marker: 0
[15:55:58.497] <TB3> INFO: overflow: 0
[15:55:58.497] <TB3> INFO: invalid 5bit words: 0
[15:55:58.497] <TB3> INFO: invalid XOR eye diagram: 0
[15:55:58.497] <TB3> INFO: frame (failed synchr.): 0
[15:55:58.497] <TB3> INFO: idle data (no TBM trl): 0
[15:55:58.497] <TB3> INFO: no data (only TBM hdr): 0
[15:55:58.497] <TB3> INFO: TBM errors: 0
[15:55:58.497] <TB3> INFO: flawed TBM headers: 0
[15:55:58.497] <TB3> INFO: flawed TBM trailers: 0
[15:55:58.497] <TB3> INFO: event ID mismatches: 0
[15:55:58.497] <TB3> INFO: ROC errors: 0
[15:55:58.497] <TB3> INFO: missing ROC header(s): 0
[15:55:58.497] <TB3> INFO: misplaced readback start: 0
[15:55:58.497] <TB3> INFO: Pixel decoding errors: 0
[15:55:58.497] <TB3> INFO: pixel data incomplete: 0
[15:55:58.497] <TB3> INFO: pixel address: 0
[15:55:58.497] <TB3> INFO: pulse height fill bit: 0
[15:55:58.497] <TB3> INFO: buffer corruption: 0
[15:55:58.665] <TB3> INFO: ######################################################################
[15:55:58.665] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:55:58.665] <TB3> INFO: ######################################################################
[15:55:58.676] <TB3> INFO: scanning low vcal = 10
[15:55:58.936] <TB3> INFO: Expecting 41600 events.
[15:56:04.558] <TB3> INFO: 41600 events read in total (5065ms).
[15:56:04.559] <TB3> INFO: Test took 5883ms.
[15:56:04.563] <TB3> INFO: scanning low vcal = 20
[15:56:04.865] <TB3> INFO: Expecting 41600 events.
[15:56:10.456] <TB3> INFO: 41600 events read in total (5034ms).
[15:56:10.457] <TB3> INFO: Test took 5894ms.
[15:56:10.460] <TB3> INFO: scanning low vcal = 30
[15:56:10.763] <TB3> INFO: Expecting 41600 events.
[15:56:16.465] <TB3> INFO: 41600 events read in total (5144ms).
[15:56:16.465] <TB3> INFO: Test took 6005ms.
[15:56:16.468] <TB3> INFO: scanning low vcal = 40
[15:56:16.764] <TB3> INFO: Expecting 41600 events.
[15:56:25.015] <TB3> INFO: 41600 events read in total (7695ms).
[15:56:25.016] <TB3> INFO: Test took 8548ms.
[15:56:25.020] <TB3> INFO: scanning low vcal = 50
[15:56:25.281] <TB3> INFO: Expecting 41600 events.
[15:56:33.733] <TB3> INFO: 41600 events read in total (7896ms).
[15:56:33.734] <TB3> INFO: Test took 8714ms.
[15:56:33.738] <TB3> INFO: scanning low vcal = 60
[15:56:34.035] <TB3> INFO: Expecting 41600 events.
[15:56:42.366] <TB3> INFO: 41600 events read in total (7774ms).
[15:56:42.367] <TB3> INFO: Test took 8629ms.
[15:56:42.370] <TB3> INFO: scanning low vcal = 70
[15:56:42.632] <TB3> INFO: Expecting 41600 events.
[15:56:50.956] <TB3> INFO: 41600 events read in total (7767ms).
[15:56:50.957] <TB3> INFO: Test took 8586ms.
[15:56:50.960] <TB3> INFO: scanning low vcal = 80
[15:56:51.220] <TB3> INFO: Expecting 41600 events.
[15:56:59.554] <TB3> INFO: 41600 events read in total (7777ms).
[15:56:59.555] <TB3> INFO: Test took 8595ms.
[15:56:59.558] <TB3> INFO: scanning low vcal = 90
[15:56:59.819] <TB3> INFO: Expecting 41600 events.
[15:57:08.162] <TB3> INFO: 41600 events read in total (7786ms).
[15:57:08.163] <TB3> INFO: Test took 8605ms.
[15:57:08.167] <TB3> INFO: scanning low vcal = 100
[15:57:08.428] <TB3> INFO: Expecting 41600 events.
[15:57:16.767] <TB3> INFO: 41600 events read in total (7782ms).
[15:57:16.767] <TB3> INFO: Test took 8600ms.
[15:57:16.771] <TB3> INFO: scanning low vcal = 110
[15:57:17.032] <TB3> INFO: Expecting 41600 events.
[15:57:25.363] <TB3> INFO: 41600 events read in total (7775ms).
[15:57:25.364] <TB3> INFO: Test took 8593ms.
[15:57:25.368] <TB3> INFO: scanning low vcal = 120
[15:57:25.628] <TB3> INFO: Expecting 41600 events.
[15:57:33.983] <TB3> INFO: 41600 events read in total (7798ms).
[15:57:33.984] <TB3> INFO: Test took 8616ms.
[15:57:33.987] <TB3> INFO: scanning low vcal = 130
[15:57:34.248] <TB3> INFO: Expecting 41600 events.
[15:57:42.586] <TB3> INFO: 41600 events read in total (7782ms).
[15:57:42.587] <TB3> INFO: Test took 8600ms.
[15:57:42.590] <TB3> INFO: scanning low vcal = 140
[15:57:42.850] <TB3> INFO: Expecting 41600 events.
[15:57:51.176] <TB3> INFO: 41600 events read in total (7769ms).
[15:57:51.177] <TB3> INFO: Test took 8587ms.
[15:57:51.180] <TB3> INFO: scanning low vcal = 150
[15:57:51.441] <TB3> INFO: Expecting 41600 events.
[15:57:59.766] <TB3> INFO: 41600 events read in total (7768ms).
[15:57:59.767] <TB3> INFO: Test took 8587ms.
[15:57:59.770] <TB3> INFO: scanning low vcal = 160
[15:58:00.036] <TB3> INFO: Expecting 41600 events.
[15:58:08.355] <TB3> INFO: 41600 events read in total (7762ms).
[15:58:08.356] <TB3> INFO: Test took 8586ms.
[15:58:08.360] <TB3> INFO: scanning low vcal = 170
[15:58:08.618] <TB3> INFO: Expecting 41600 events.
[15:58:16.954] <TB3> INFO: 41600 events read in total (7779ms).
[15:58:16.955] <TB3> INFO: Test took 8595ms.
[15:58:16.959] <TB3> INFO: scanning low vcal = 180
[15:58:17.219] <TB3> INFO: Expecting 41600 events.
[15:58:25.557] <TB3> INFO: 41600 events read in total (7781ms).
[15:58:25.557] <TB3> INFO: Test took 8598ms.
[15:58:25.561] <TB3> INFO: scanning low vcal = 190
[15:58:25.819] <TB3> INFO: Expecting 41600 events.
[15:58:34.223] <TB3> INFO: 41600 events read in total (7847ms).
[15:58:34.224] <TB3> INFO: Test took 8663ms.
[15:58:34.227] <TB3> INFO: scanning low vcal = 200
[15:58:34.488] <TB3> INFO: Expecting 41600 events.
[15:58:42.934] <TB3> INFO: 41600 events read in total (7889ms).
[15:58:42.936] <TB3> INFO: Test took 8709ms.
[15:58:42.939] <TB3> INFO: scanning low vcal = 210
[15:58:43.224] <TB3> INFO: Expecting 41600 events.
[15:58:51.620] <TB3> INFO: 41600 events read in total (7840ms).
[15:58:51.620] <TB3> INFO: Test took 8680ms.
[15:58:51.624] <TB3> INFO: scanning low vcal = 220
[15:58:51.889] <TB3> INFO: Expecting 41600 events.
[15:59:00.311] <TB3> INFO: 41600 events read in total (7865ms).
[15:59:00.312] <TB3> INFO: Test took 8688ms.
[15:59:00.316] <TB3> INFO: scanning low vcal = 230
[15:59:00.606] <TB3> INFO: Expecting 41600 events.
[15:59:09.088] <TB3> INFO: 41600 events read in total (7925ms).
[15:59:09.090] <TB3> INFO: Test took 8774ms.
[15:59:09.093] <TB3> INFO: scanning low vcal = 240
[15:59:09.390] <TB3> INFO: Expecting 41600 events.
[15:59:17.722] <TB3> INFO: 41600 events read in total (7775ms).
[15:59:17.723] <TB3> INFO: Test took 8629ms.
[15:59:17.726] <TB3> INFO: scanning low vcal = 250
[15:59:17.985] <TB3> INFO: Expecting 41600 events.
[15:59:26.343] <TB3> INFO: 41600 events read in total (7801ms).
[15:59:26.344] <TB3> INFO: Test took 8618ms.
[15:59:26.350] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[15:59:26.610] <TB3> INFO: Expecting 41600 events.
[15:59:34.948] <TB3> INFO: 41600 events read in total (7781ms).
[15:59:34.948] <TB3> INFO: Test took 8598ms.
[15:59:34.952] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[15:59:35.213] <TB3> INFO: Expecting 41600 events.
[15:59:43.511] <TB3> INFO: 41600 events read in total (7741ms).
[15:59:43.512] <TB3> INFO: Test took 8560ms.
[15:59:43.515] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[15:59:43.776] <TB3> INFO: Expecting 41600 events.
[15:59:52.222] <TB3> INFO: 41600 events read in total (7889ms).
[15:59:52.223] <TB3> INFO: Test took 8707ms.
[15:59:52.227] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[15:59:52.515] <TB3> INFO: Expecting 41600 events.
[16:00:00.853] <TB3> INFO: 41600 events read in total (7781ms).
[16:00:00.853] <TB3> INFO: Test took 8626ms.
[16:00:00.856] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:00:01.114] <TB3> INFO: Expecting 41600 events.
[16:00:09.528] <TB3> INFO: 41600 events read in total (7857ms).
[16:00:09.529] <TB3> INFO: Test took 8672ms.
[16:00:10.582] <TB3> INFO: PixTestGainPedestal::measure() done
[16:00:42.503] <TB3> INFO: PixTestGainPedestal::fit() done
[16:00:42.504] <TB3> INFO: non-linearity mean: 0.963 0.955 0.957 0.959 0.956 0.952 0.960 0.955 0.959 0.963 0.962 0.963 0.954 0.950 0.957 0.950
[16:00:42.504] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.005 0.005 0.005 0.007 0.005 0.006 0.005 0.004 0.006 0.005 0.006 0.006 0.006 0.006
[16:00:42.506] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[16:00:42.779] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[16:00:42.824] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[16:00:42.859] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[16:00:42.897] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[16:00:42.935] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[16:00:42.976] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[16:00:43.014] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[16:00:43.052] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[16:00:43.091] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[16:00:43.129] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[16:00:43.166] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[16:00:43.203] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[16:00:43.239] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[16:00:43.275] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[16:00:43.312] <TB3> INFO: write gain/ped parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[16:00:43.347] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 284 seconds
[16:00:43.347] <TB3> INFO: Decoding statistics:
[16:00:43.347] <TB3> INFO: General information:
[16:00:43.347] <TB3> INFO: 16bit words read: 2662400
[16:00:43.347] <TB3> INFO: valid events total: 166400
[16:00:43.347] <TB3> INFO: empty events: 0
[16:00:43.347] <TB3> INFO: valid events with pixels: 166400
[16:00:43.347] <TB3> INFO: valid pixel hits: 665560
[16:00:43.347] <TB3> INFO: Event errors: 0
[16:00:43.347] <TB3> INFO: start marker: 0
[16:00:43.347] <TB3> INFO: stop marker: 0
[16:00:43.348] <TB3> INFO: overflow: 0
[16:00:43.348] <TB3> INFO: invalid 5bit words: 0
[16:00:43.348] <TB3> INFO: invalid XOR eye diagram: 0
[16:00:43.348] <TB3> INFO: frame (failed synchr.): 0
[16:00:43.348] <TB3> INFO: idle data (no TBM trl): 0
[16:00:43.348] <TB3> INFO: no data (only TBM hdr): 0
[16:00:43.348] <TB3> INFO: TBM errors: 0
[16:00:43.348] <TB3> INFO: flawed TBM headers: 0
[16:00:43.348] <TB3> INFO: flawed TBM trailers: 0
[16:00:43.348] <TB3> INFO: event ID mismatches: 0
[16:00:43.348] <TB3> INFO: ROC errors: 0
[16:00:43.348] <TB3> INFO: missing ROC header(s): 0
[16:00:43.348] <TB3> INFO: misplaced readback start: 0
[16:00:43.348] <TB3> INFO: Pixel decoding errors: 0
[16:00:43.348] <TB3> INFO: pixel data incomplete: 0
[16:00:43.348] <TB3> INFO: pixel address: 0
[16:00:43.348] <TB3> INFO: pulse height fill bit: 0
[16:00:43.348] <TB3> INFO: buffer corruption: 0
[16:00:43.355] <TB3> INFO: readReadbackCal: /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C0.dat .. /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C15.dat
[16:00:43.535] <TB3> INFO: ######################################################################
[16:00:43.535] <TB3> INFO: PixTestReadback::doTest()
[16:00:43.535] <TB3> INFO: ######################################################################
[16:00:43.535] <TB3> INFO: ----------------------------------------------------------------------
[16:00:43.535] <TB3> INFO: PixTestReadback::CalibrateVd()
[16:00:43.535] <TB3> INFO: ----------------------------------------------------------------------
[16:00:53.153] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C0.dat
[16:00:53.219] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C1.dat
[16:00:53.286] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C2.dat
[16:00:53.353] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C3.dat
[16:00:53.420] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C4.dat
[16:00:53.487] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C5.dat
[16:00:53.554] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C6.dat
[16:00:53.630] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C7.dat
[16:00:53.697] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C8.dat
[16:00:53.764] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C9.dat
[16:00:53.822] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C10.dat
[16:00:53.923] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C11.dat
[16:00:53.982] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C12.dat
[16:00:54.049] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C13.dat
[16:00:54.107] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C14.dat
[16:00:54.166] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C15.dat
[16:00:54.274] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:00:54.274] <TB3> INFO: ----------------------------------------------------------------------
[16:00:54.274] <TB3> INFO: PixTestReadback::CalibrateVa()
[16:00:54.274] <TB3> INFO: ----------------------------------------------------------------------
[16:01:03.856] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C0.dat
[16:01:03.861] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C1.dat
[16:01:03.866] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C2.dat
[16:01:03.871] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C3.dat
[16:01:03.876] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C4.dat
[16:01:03.881] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C5.dat
[16:01:03.887] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C6.dat
[16:01:03.894] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C7.dat
[16:01:03.899] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C8.dat
[16:01:03.904] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C9.dat
[16:01:03.911] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C10.dat
[16:01:03.916] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C11.dat
[16:01:03.921] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C12.dat
[16:01:03.927] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C13.dat
[16:01:03.932] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C14.dat
[16:01:03.937] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C15.dat
[16:01:03.987] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:01:03.987] <TB3> INFO: ----------------------------------------------------------------------
[16:01:03.987] <TB3> INFO: PixTestReadback::readbackVbg()
[16:01:03.987] <TB3> INFO: ----------------------------------------------------------------------
[16:01:11.360] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:01:11.360] <TB3> INFO: ----------------------------------------------------------------------
[16:01:11.360] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[16:01:11.360] <TB3> INFO: ----------------------------------------------------------------------
[16:01:11.361] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.2calibrated Vbg = 1.23019 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.2calibrated Vbg = 1.22363 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 145.8calibrated Vbg = 1.2309 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.7calibrated Vbg = 1.24132 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.4calibrated Vbg = 1.24842 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 173calibrated Vbg = 1.24753 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.2calibrated Vbg = 1.24951 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147.7calibrated Vbg = 1.25019 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 158.2calibrated Vbg = 1.24838 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.3calibrated Vbg = 1.24907 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.4calibrated Vbg = 1.23877 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.2calibrated Vbg = 1.23517 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.6calibrated Vbg = 1.23253 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.5calibrated Vbg = 1.23134 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150.3calibrated Vbg = 1.22891 :::*/*/*/*/
[16:01:11.361] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.8calibrated Vbg = 1.22856 :::*/*/*/*/
[16:01:11.363] <TB3> INFO: ----------------------------------------------------------------------
[16:01:11.363] <TB3> INFO: PixTestReadback::CalibrateIa()
[16:01:11.363] <TB3> INFO: ----------------------------------------------------------------------
[16:03:47.450] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C0.dat
[16:03:47.455] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C1.dat
[16:03:47.460] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C2.dat
[16:03:47.465] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C3.dat
[16:03:47.471] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C4.dat
[16:03:47.476] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C5.dat
[16:03:47.481] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C6.dat
[16:03:47.485] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C7.dat
[16:03:47.491] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C8.dat
[16:03:47.496] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C9.dat
[16:03:47.500] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C10.dat
[16:03:47.505] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C11.dat
[16:03:47.511] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C12.dat
[16:03:47.515] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C13.dat
[16:03:47.520] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C14.dat
[16:03:47.525] <TB3> INFO: write readback calibration parameters into /home/Reception/FullQualification/M2187_FullQualification_2016-03-22_13h14m_1458648895//000_FulltestPxar_m20//readbackCal_C15.dat
[16:03:47.573] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:03:47.573] <TB3> INFO: PixTestReadback::doTest() done
[16:03:47.573] <TB3> INFO: Decoding statistics:
[16:03:47.573] <TB3> INFO: General information:
[16:03:47.573] <TB3> INFO: 16bit words read: 1024
[16:03:47.573] <TB3> INFO: valid events total: 128
[16:03:47.573] <TB3> INFO: empty events: 128
[16:03:47.573] <TB3> INFO: valid events with pixels: 0
[16:03:47.573] <TB3> INFO: valid pixel hits: 0
[16:03:47.573] <TB3> INFO: Event errors: 0
[16:03:47.573] <TB3> INFO: start marker: 0
[16:03:47.573] <TB3> INFO: stop marker: 0
[16:03:47.573] <TB3> INFO: overflow: 0
[16:03:47.573] <TB3> INFO: invalid 5bit words: 0
[16:03:47.574] <TB3> INFO: invalid XOR eye diagram: 0
[16:03:47.574] <TB3> INFO: frame (failed synchr.): 0
[16:03:47.574] <TB3> INFO: idle data (no TBM trl): 0
[16:03:47.574] <TB3> INFO: no data (only TBM hdr): 0
[16:03:47.574] <TB3> INFO: TBM errors: 0
[16:03:47.574] <TB3> INFO: flawed TBM headers: 0
[16:03:47.574] <TB3> INFO: flawed TBM trailers: 0
[16:03:47.574] <TB3> INFO: event ID mismatches: 0
[16:03:47.574] <TB3> INFO: ROC errors: 0
[16:03:47.574] <TB3> INFO: missing ROC header(s): 0
[16:03:47.574] <TB3> INFO: misplaced readback start: 0
[16:03:47.574] <TB3> INFO: Pixel decoding errors: 0
[16:03:47.574] <TB3> INFO: pixel data incomplete: 0
[16:03:47.574] <TB3> INFO: pixel address: 0
[16:03:47.574] <TB3> INFO: pulse height fill bit: 0
[16:03:47.574] <TB3> INFO: buffer corruption: 0
[16:03:47.592] <TB3> INFO: Decoding statistics:
[16:03:47.592] <TB3> INFO: General information:
[16:03:47.592] <TB3> INFO: 16bit words read: 2755352
[16:03:47.592] <TB3> INFO: valid events total: 176768
[16:03:47.592] <TB3> INFO: empty events: 7866
[16:03:47.592] <TB3> INFO: valid events with pixels: 168902
[16:03:47.592] <TB3> INFO: valid pixel hits: 668062
[16:03:47.592] <TB3> INFO: Event errors: 0
[16:03:47.592] <TB3> INFO: start marker: 0
[16:03:47.592] <TB3> INFO: stop marker: 0
[16:03:47.592] <TB3> INFO: overflow: 0
[16:03:47.592] <TB3> INFO: invalid 5bit words: 0
[16:03:47.592] <TB3> INFO: invalid XOR eye diagram: 0
[16:03:47.592] <TB3> INFO: frame (failed synchr.): 0
[16:03:47.592] <TB3> INFO: idle data (no TBM trl): 0
[16:03:47.592] <TB3> INFO: no data (only TBM hdr): 0
[16:03:47.592] <TB3> INFO: TBM errors: 0
[16:03:47.592] <TB3> INFO: flawed TBM headers: 0
[16:03:47.592] <TB3> INFO: flawed TBM trailers: 0
[16:03:47.592] <TB3> INFO: event ID mismatches: 0
[16:03:47.592] <TB3> INFO: ROC errors: 0
[16:03:47.592] <TB3> INFO: missing ROC header(s): 0
[16:03:47.592] <TB3> INFO: misplaced readback start: 0
[16:03:47.592] <TB3> INFO: Pixel decoding errors: 0
[16:03:47.592] <TB3> INFO: pixel data incomplete: 0
[16:03:47.592] <TB3> INFO: pixel address: 0
[16:03:47.592] <TB3> INFO: pulse height fill bit: 0
[16:03:47.592] <TB3> INFO: buffer corruption: 0
[16:03:47.592] <TB3> INFO: enter test to run
[16:03:47.592] <TB3> INFO: test: exit no parameter change
[16:03:47.636] <TB3> QUIET: Connection to board 126 closed.
[16:03:47.715] <TB3> INFO: pXar: this is the end, my friend