[15:51:01.429] <TB0> INFO: *** Welcome to pxar ***
[15:51:01.429] <TB0> INFO: *** Today: 2016/09/26
[15:51:01.940] <TB0> INFO: *** Version: fe94
[15:51:01.940] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C15.dat
[15:51:01.940] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:51:01.940] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//defaultMaskFile.dat
[15:51:01.940] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters_C15.dat
[15:51:02.007] <TB0> INFO: clk: 4
[15:51:02.007] <TB0> INFO: ctr: 4
[15:51:02.007] <TB0> INFO: sda: 19
[15:51:02.007] <TB0> INFO: tin: 9
[15:51:02.007] <TB0> INFO: level: 15
[15:51:02.007] <TB0> INFO: triggerdelay: 0
[15:51:02.007] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[15:51:02.007] <TB0> INFO: Log level: INFO
[15:51:02.014] <TB0> INFO: Found DTB DTB_WS6AYH
[15:51:02.025] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[15:51:02.027] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[15:51:02.029] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[15:51:03.527] <TB0> INFO: DUT info:
[15:51:03.527] <TB0> INFO: The DUT currently contains the following objects:
[15:51:03.527] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:51:03.527] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:51:03.527] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:51:03.527] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:51:03.527] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.527] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:03.928] <TB0> INFO: enter 'restricted' command line mode
[15:51:03.928] <TB0> INFO: enter test to run
[15:51:03.928] <TB0> INFO: test: pretest no parameter change
[15:51:03.928] <TB0> INFO: running: pretest
[15:51:03.932] <TB0> INFO: ----------------------------------------------------------------------
[15:51:03.932] <TB0> INFO: PixTestPretest::programROC()
[15:51:03.932] <TB0> INFO: ----------------------------------------------------------------------
[15:51:21.946] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:51:21.947] <TB0> INFO: IA differences per ROC: 16.9 16.9 19.3 19.3 16.9 20.1 20.1 20.9 19.3 19.3 19.3 21.7 19.3 20.9 20.9 20.1
[15:51:22.047] <TB0> INFO: enter test to run
[15:51:22.047] <TB0> INFO: test: pretest no parameter change
[15:51:22.047] <TB0> INFO: running: pretest
[15:51:22.049] <TB0> INFO: ----------------------------------------------------------------------
[15:51:22.049] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:51:22.049] <TB0> INFO: ----------------------------------------------------------------------
[15:51:27.630] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[15:51:27.630] <TB0> INFO: i(loss) [mA/ROC]: 18.5 18.5 18.5 18.5 18.5 18.5 18.5 19.3 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5
[15:51:27.656] <TB0> INFO: enter test to run
[15:51:27.656] <TB0> INFO: test: pretest no parameter change
[15:51:27.656] <TB0> INFO: running: pretest
[15:51:27.656] <TB0> INFO: ----------------------------------------------------------------------
[15:51:27.656] <TB0> INFO: PixTestPretest::findTiming()
[15:51:27.656] <TB0> INFO: ----------------------------------------------------------------------
[15:51:27.656] <TB0> INFO: PixTestCmd::init()
[15:51:28.173] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:52:58.869] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:52:58.869] <TB0> INFO: (success/tries = 100/100), width = 5
[15:52:58.870] <TB0> INFO: enter test to run
[15:52:58.870] <TB0> INFO: test: pretest no parameter change
[15:52:58.870] <TB0> INFO: running: pretest
[15:52:58.872] <TB0> INFO: ----------------------------------------------------------------------
[15:52:58.872] <TB0> INFO: PixTestPretest::findWorkingPixel()
[15:52:58.872] <TB0> INFO: ----------------------------------------------------------------------
[15:52:58.965] <TB0> INFO: Expecting 231680 events.
[15:53:04.564] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:53:04.694] <TB0> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:53:08.217] <TB0> INFO: 231680 events read in total (8696ms).
[15:53:08.222] <TB0> INFO: Test took 9344ms.
[15:53:08.443] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:53:08.515] <TB0> INFO: enter test to run
[15:53:08.515] <TB0> INFO: test: pretest no parameter change
[15:53:08.515] <TB0> INFO: running: pretest
[15:53:08.516] <TB0> INFO: ----------------------------------------------------------------------
[15:53:08.516] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[15:53:08.516] <TB0> INFO: ----------------------------------------------------------------------
[15:53:08.609] <TB0> INFO: Expecting 231680 events.
[15:53:17.774] <TB0> INFO: 231680 events read in total (8609ms).
[15:53:17.779] <TB0> INFO: Test took 9259ms.
[15:53:17.987] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[15:53:17.987] <TB0> INFO: CalDel: 156 140 139 150 139 148 144 160 141 156 145 158 138 142 132 146
[15:53:17.987] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:53:18.020] <TB0> INFO: enter test to run
[15:53:18.020] <TB0> INFO: test: pretest no parameter change
[15:53:18.020] <TB0> INFO: running: pretest
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C0.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C1.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C2.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C3.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C4.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C5.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C6.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C7.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C8.dat
[15:53:18.021] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C9.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C10.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C11.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C12.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C13.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C14.dat
[15:53:18.022] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters_C15.dat
[15:53:18.022] <TB0> INFO: enter test to run
[15:53:18.022] <TB0> INFO: test: fulltest no parameter change
[15:53:18.022] <TB0> INFO: running: fulltest
[15:53:18.023] <TB0> INFO: ######################################################################
[15:53:18.023] <TB0> INFO: PixTestFullTest::doTest()
[15:53:18.023] <TB0> INFO: ######################################################################
[15:53:18.024] <TB0> INFO: ######################################################################
[15:53:18.024] <TB0> INFO: PixTestAlive::doTest()
[15:53:18.024] <TB0> INFO: ######################################################################
[15:53:18.025] <TB0> INFO: ----------------------------------------------------------------------
[15:53:18.025] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:18.025] <TB0> INFO: ----------------------------------------------------------------------
[15:53:18.288] <TB0> INFO: Expecting 41600 events.
[15:53:21.769] <TB0> INFO: 41600 events read in total (2925ms).
[15:53:21.770] <TB0> INFO: Test took 3744ms.
[15:53:21.991] <TB0> INFO: PixTestAlive::aliveTest() done
[15:53:21.992] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:53:21.994] <TB0> INFO: ----------------------------------------------------------------------
[15:53:21.995] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:21.995] <TB0> INFO: ----------------------------------------------------------------------
[15:53:22.265] <TB0> INFO: Expecting 41600 events.
[15:53:25.030] <TB0> INFO: 41600 events read in total (2209ms).
[15:53:25.030] <TB0> INFO: Test took 3034ms.
[15:53:25.030] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:53:25.264] <TB0> INFO: PixTestAlive::maskTest() done
[15:53:25.264] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:53:25.266] <TB0> INFO: ----------------------------------------------------------------------
[15:53:25.266] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:25.266] <TB0> INFO: ----------------------------------------------------------------------
[15:53:25.531] <TB0> INFO: Expecting 41600 events.
[15:53:28.946] <TB0> INFO: 41600 events read in total (2859ms).
[15:53:28.946] <TB0> INFO: Test took 3679ms.
[15:53:29.173] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[15:53:29.174] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:53:29.174] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:53:29.174] <TB0> INFO: Decoding statistics:
[15:53:29.174] <TB0> INFO: General information:
[15:53:29.174] <TB0> INFO: 16bit words read: 0
[15:53:29.174] <TB0> INFO: valid events total: 0
[15:53:29.174] <TB0> INFO: empty events: 0
[15:53:29.174] <TB0> INFO: valid events with pixels: 0
[15:53:29.174] <TB0> INFO: valid pixel hits: 0
[15:53:29.174] <TB0> INFO: Event errors: 0
[15:53:29.174] <TB0> INFO: start marker: 0
[15:53:29.174] <TB0> INFO: stop marker: 0
[15:53:29.174] <TB0> INFO: overflow: 0
[15:53:29.174] <TB0> INFO: invalid 5bit words: 0
[15:53:29.174] <TB0> INFO: invalid XOR eye diagram: 0
[15:53:29.174] <TB0> INFO: frame (failed synchr.): 0
[15:53:29.174] <TB0> INFO: idle data (no TBM trl): 0
[15:53:29.174] <TB0> INFO: no data (only TBM hdr): 0
[15:53:29.174] <TB0> INFO: TBM errors: 0
[15:53:29.174] <TB0> INFO: flawed TBM headers: 0
[15:53:29.174] <TB0> INFO: flawed TBM trailers: 0
[15:53:29.174] <TB0> INFO: event ID mismatches: 0
[15:53:29.174] <TB0> INFO: ROC errors: 0
[15:53:29.174] <TB0> INFO: missing ROC header(s): 0
[15:53:29.174] <TB0> INFO: misplaced readback start: 0
[15:53:29.174] <TB0> INFO: Pixel decoding errors: 0
[15:53:29.174] <TB0> INFO: pixel data incomplete: 0
[15:53:29.174] <TB0> INFO: pixel address: 0
[15:53:29.174] <TB0> INFO: pulse height fill bit: 0
[15:53:29.174] <TB0> INFO: buffer corruption: 0
[15:53:29.185] <TB0> INFO: ######################################################################
[15:53:29.185] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:53:29.185] <TB0> INFO: ######################################################################
[15:53:29.188] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:53:29.198] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:53:29.198] <TB0> INFO: run 1 of 1
[15:53:29.463] <TB0> INFO: Expecting 3120000 events.
[15:54:07.335] <TB0> INFO: 890835 events read in total (37316ms).
[15:54:44.264] <TB0> INFO: 1771005 events read in total (74246ms).
[15:55:21.509] <TB0> INFO: 2666225 events read in total (111490ms).
[15:55:40.381] <TB0> INFO: 3120000 events read in total (130362ms).
[15:55:40.462] <TB0> INFO: Test took 131265ms.
[15:56:04.665] <TB0> INFO: PixTestBBMap::doTest() done, duration: 155 seconds
[15:56:04.665] <TB0> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:56:04.665] <TB0> INFO: separation cut (per ROC): 83 87 90 78 91 72 87 88 85 83 85 86 82 92 85 91
[15:56:04.665] <TB0> INFO: Decoding statistics:
[15:56:04.665] <TB0> INFO: General information:
[15:56:04.665] <TB0> INFO: 16bit words read: 0
[15:56:04.665] <TB0> INFO: valid events total: 0
[15:56:04.665] <TB0> INFO: empty events: 0
[15:56:04.665] <TB0> INFO: valid events with pixels: 0
[15:56:04.665] <TB0> INFO: valid pixel hits: 0
[15:56:04.665] <TB0> INFO: Event errors: 0
[15:56:04.665] <TB0> INFO: start marker: 0
[15:56:04.665] <TB0> INFO: stop marker: 0
[15:56:04.665] <TB0> INFO: overflow: 0
[15:56:04.665] <TB0> INFO: invalid 5bit words: 0
[15:56:04.665] <TB0> INFO: invalid XOR eye diagram: 0
[15:56:04.665] <TB0> INFO: frame (failed synchr.): 0
[15:56:04.665] <TB0> INFO: idle data (no TBM trl): 0
[15:56:04.665] <TB0> INFO: no data (only TBM hdr): 0
[15:56:04.665] <TB0> INFO: TBM errors: 0
[15:56:04.665] <TB0> INFO: flawed TBM headers: 0
[15:56:04.665] <TB0> INFO: flawed TBM trailers: 0
[15:56:04.665] <TB0> INFO: event ID mismatches: 0
[15:56:04.665] <TB0> INFO: ROC errors: 0
[15:56:04.665] <TB0> INFO: missing ROC header(s): 0
[15:56:04.665] <TB0> INFO: misplaced readback start: 0
[15:56:04.665] <TB0> INFO: Pixel decoding errors: 0
[15:56:04.665] <TB0> INFO: pixel data incomplete: 0
[15:56:04.665] <TB0> INFO: pixel address: 0
[15:56:04.665] <TB0> INFO: pulse height fill bit: 0
[15:56:04.665] <TB0> INFO: buffer corruption: 0
[15:56:04.742] <TB0> INFO: ######################################################################
[15:56:04.742] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:56:04.742] <TB0> INFO: ######################################################################
[15:56:04.742] <TB0> INFO: ----------------------------------------------------------------------
[15:56:04.742] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:56:04.742] <TB0> INFO: ----------------------------------------------------------------------
[15:56:04.742] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[15:56:04.750] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[15:56:04.750] <TB0> INFO: run 1 of 1
[15:56:05.012] <TB0> INFO: Expecting 36608000 events.
[15:56:35.558] <TB0> INFO: 833700 events read in total (29984ms).
[15:57:03.684] <TB0> INFO: 1653450 events read in total (58110ms).
[15:57:32.780] <TB0> INFO: 2472600 events read in total (87206ms).
[15:58:01.676] <TB0> INFO: 3291250 events read in total (116102ms).
[15:58:30.807] <TB0> INFO: 4110500 events read in total (145233ms).
[15:58:59.352] <TB0> INFO: 4930050 events read in total (173778ms).
[15:59:28.153] <TB0> INFO: 5746200 events read in total (202579ms).
[15:59:56.827] <TB0> INFO: 6564200 events read in total (231253ms).
[16:00:25.142] <TB0> INFO: 7380800 events read in total (259568ms).
[16:00:53.979] <TB0> INFO: 8200150 events read in total (288405ms).
[16:01:22.628] <TB0> INFO: 9017550 events read in total (317054ms).
[16:01:51.779] <TB0> INFO: 9835150 events read in total (346205ms).
[16:02:20.209] <TB0> INFO: 10652100 events read in total (374635ms).
[16:02:48.834] <TB0> INFO: 11466300 events read in total (403260ms).
[16:03:16.984] <TB0> INFO: 12282200 events read in total (431410ms).
[16:03:45.601] <TB0> INFO: 13099100 events read in total (460027ms).
[16:04:14.069] <TB0> INFO: 13911400 events read in total (488495ms).
[16:04:42.544] <TB0> INFO: 14727100 events read in total (516970ms).
[16:05:11.246] <TB0> INFO: 15539400 events read in total (545672ms).
[16:05:39.479] <TB0> INFO: 16351650 events read in total (573905ms).
[16:06:07.966] <TB0> INFO: 17162550 events read in total (602392ms).
[16:06:36.953] <TB0> INFO: 17973400 events read in total (631379ms).
[16:07:05.126] <TB0> INFO: 18779350 events read in total (659552ms).
[16:07:33.549] <TB0> INFO: 19585700 events read in total (687975ms).
[16:08:02.406] <TB0> INFO: 20391550 events read in total (716832ms).
[16:08:30.879] <TB0> INFO: 21197250 events read in total (745305ms).
[16:08:59.600] <TB0> INFO: 22004400 events read in total (774026ms).
[16:09:28.263] <TB0> INFO: 22807700 events read in total (802689ms).
[16:09:56.751] <TB0> INFO: 23611050 events read in total (831177ms).
[16:10:25.109] <TB0> INFO: 24415100 events read in total (859535ms).
[16:10:53.939] <TB0> INFO: 25216750 events read in total (888365ms).
[16:11:22.365] <TB0> INFO: 26019700 events read in total (916791ms).
[16:11:50.875] <TB0> INFO: 26819900 events read in total (945301ms).
[16:12:19.283] <TB0> INFO: 27620850 events read in total (973709ms).
[16:12:47.854] <TB0> INFO: 28421500 events read in total (1002280ms).
[16:13:16.344] <TB0> INFO: 29221850 events read in total (1030770ms).
[16:13:44.911] <TB0> INFO: 30021950 events read in total (1059337ms).
[16:14:13.811] <TB0> INFO: 30822700 events read in total (1088237ms).
[16:14:42.242] <TB0> INFO: 31623400 events read in total (1116668ms).
[16:15:10.564] <TB0> INFO: 32424300 events read in total (1144990ms).
[16:15:39.477] <TB0> INFO: 33224500 events read in total (1173903ms).
[16:16:08.106] <TB0> INFO: 34024750 events read in total (1202532ms).
[16:16:36.726] <TB0> INFO: 34825050 events read in total (1231152ms).
[16:17:05.311] <TB0> INFO: 35625400 events read in total (1259737ms).
[16:17:33.987] <TB0> INFO: 36436750 events read in total (1288413ms).
[16:17:40.366] <TB0> INFO: 36608000 events read in total (1294792ms).
[16:17:40.431] <TB0> INFO: Test took 1295681ms.
[16:17:40.932] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:42.950] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:44.737] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:46.474] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:48.348] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:50.361] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:52.274] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:54.333] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:56.074] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:57.845] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:17:59.586] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:01.296] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:03.430] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:05.357] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:07.046] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:08.722] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:18:10.432] <TB0> INFO: PixTestScurves::scurves() done
[16:18:10.432] <TB0> INFO: Vcal mean: 87.32 94.95 88.75 83.51 103.94 83.88 95.13 93.38 84.63 91.93 83.99 88.95 86.38 91.11 84.46 99.33
[16:18:10.432] <TB0> INFO: Vcal RMS: 5.20 4.92 4.91 4.48 5.68 4.75 5.48 5.64 5.28 5.26 5.06 5.20 4.86 5.53 4.26 5.62
[16:18:10.433] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1325 seconds
[16:18:10.433] <TB0> INFO: Decoding statistics:
[16:18:10.433] <TB0> INFO: General information:
[16:18:10.433] <TB0> INFO: 16bit words read: 0
[16:18:10.433] <TB0> INFO: valid events total: 0
[16:18:10.433] <TB0> INFO: empty events: 0
[16:18:10.433] <TB0> INFO: valid events with pixels: 0
[16:18:10.433] <TB0> INFO: valid pixel hits: 0
[16:18:10.433] <TB0> INFO: Event errors: 0
[16:18:10.433] <TB0> INFO: start marker: 0
[16:18:10.433] <TB0> INFO: stop marker: 0
[16:18:10.433] <TB0> INFO: overflow: 0
[16:18:10.433] <TB0> INFO: invalid 5bit words: 0
[16:18:10.433] <TB0> INFO: invalid XOR eye diagram: 0
[16:18:10.433] <TB0> INFO: frame (failed synchr.): 0
[16:18:10.433] <TB0> INFO: idle data (no TBM trl): 0
[16:18:10.433] <TB0> INFO: no data (only TBM hdr): 0
[16:18:10.433] <TB0> INFO: TBM errors: 0
[16:18:10.433] <TB0> INFO: flawed TBM headers: 0
[16:18:10.433] <TB0> INFO: flawed TBM trailers: 0
[16:18:10.433] <TB0> INFO: event ID mismatches: 0
[16:18:10.433] <TB0> INFO: ROC errors: 0
[16:18:10.433] <TB0> INFO: missing ROC header(s): 0
[16:18:10.433] <TB0> INFO: misplaced readback start: 0
[16:18:10.433] <TB0> INFO: Pixel decoding errors: 0
[16:18:10.433] <TB0> INFO: pixel data incomplete: 0
[16:18:10.433] <TB0> INFO: pixel address: 0
[16:18:10.433] <TB0> INFO: pulse height fill bit: 0
[16:18:10.433] <TB0> INFO: buffer corruption: 0
[16:18:10.533] <TB0> INFO: ######################################################################
[16:18:10.533] <TB0> INFO: PixTestTrim::doTest()
[16:18:10.533] <TB0> INFO: ######################################################################
[16:18:10.534] <TB0> INFO: ----------------------------------------------------------------------
[16:18:10.534] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:18:10.534] <TB0> INFO: ----------------------------------------------------------------------
[16:18:10.656] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:18:10.657] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:18:10.667] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:18:10.667] <TB0> INFO: run 1 of 1
[16:18:10.984] <TB0> INFO: Expecting 6281600 events.
[16:18:48.797] <TB0> INFO: 1122080 events read in total (37256ms).
[16:19:25.378] <TB0> INFO: 2234980 events read in total (73837ms).
[16:20:03.023] <TB0> INFO: 3340330 events read in total (111482ms).
[16:20:40.742] <TB0> INFO: 4454220 events read in total (149201ms).
[16:21:16.520] <TB0> INFO: 5572280 events read in total (184979ms).
[16:21:39.553] <TB0> INFO: 6281600 events read in total (208012ms).
[16:21:39.600] <TB0> INFO: Test took 208933ms.
[16:21:59.469] <TB0> INFO: ROC 0 VthrComp = 85
[16:21:59.469] <TB0> INFO: ROC 1 VthrComp = 95
[16:21:59.475] <TB0> INFO: ROC 2 VthrComp = 90
[16:21:59.475] <TB0> INFO: ROC 3 VthrComp = 85
[16:21:59.475] <TB0> INFO: ROC 4 VthrComp = 100
[16:21:59.475] <TB0> INFO: ROC 5 VthrComp = 88
[16:21:59.475] <TB0> INFO: ROC 6 VthrComp = 96
[16:21:59.475] <TB0> INFO: ROC 7 VthrComp = 92
[16:21:59.475] <TB0> INFO: ROC 8 VthrComp = 87
[16:21:59.475] <TB0> INFO: ROC 9 VthrComp = 87
[16:21:59.475] <TB0> INFO: ROC 10 VthrComp = 87
[16:21:59.476] <TB0> INFO: ROC 11 VthrComp = 90
[16:21:59.476] <TB0> INFO: ROC 12 VthrComp = 87
[16:21:59.476] <TB0> INFO: ROC 13 VthrComp = 93
[16:21:59.476] <TB0> INFO: ROC 14 VthrComp = 90
[16:21:59.476] <TB0> INFO: ROC 15 VthrComp = 100
[16:21:59.476] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:21:59.476] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:21:59.484] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:21:59.484] <TB0> INFO: run 1 of 1
[16:21:59.743] <TB0> INFO: Expecting 6281600 events.
[16:22:32.085] <TB0> INFO: 760340 events read in total (31785ms).
[16:23:03.363] <TB0> INFO: 1518540 events read in total (63063ms).
[16:23:34.312] <TB0> INFO: 2277870 events read in total (94012ms).
[16:24:05.539] <TB0> INFO: 3037110 events read in total (125239ms).
[16:24:36.377] <TB0> INFO: 3790050 events read in total (156077ms).
[16:25:06.963] <TB0> INFO: 4539870 events read in total (186663ms).
[16:25:37.760] <TB0> INFO: 5287120 events read in total (217460ms).
[16:26:08.741] <TB0> INFO: 6033680 events read in total (248441ms).
[16:26:19.269] <TB0> INFO: 6281600 events read in total (258969ms).
[16:26:19.322] <TB0> INFO: Test took 259838ms.
[16:26:44.644] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 59.0464 for pixel 0/16 mean/min/max = 46.1356/33.1752/59.096
[16:26:44.644] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.2909 for pixel 25/2 mean/min/max = 45.0337/32.7743/57.2931
[16:26:44.644] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.9166 for pixel 51/17 mean/min/max = 45.8658/33.8124/57.9193
[16:26:44.645] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 55.2874 for pixel 36/3 mean/min/max = 44.0773/32.3505/55.8041
[16:26:44.645] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 61.8078 for pixel 10/26 mean/min/max = 47.1237/32.2444/62.003
[16:26:44.645] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 58.3288 for pixel 2/18 mean/min/max = 45.9706/33.5976/58.3435
[16:26:44.646] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.9378 for pixel 0/59 mean/min/max = 45.5611/31.9998/59.1223
[16:26:44.646] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 58.7633 for pixel 11/7 mean/min/max = 46.5048/34.1408/58.8688
[16:26:44.646] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.3277 for pixel 0/11 mean/min/max = 44.9053/32.2464/57.5642
[16:26:44.646] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.49 for pixel 51/27 mean/min/max = 46.4925/32.3282/60.6567
[16:26:44.647] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.5861 for pixel 2/50 mean/min/max = 44.3679/31.5437/57.1922
[16:26:44.647] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 57.3971 for pixel 17/74 mean/min/max = 45.6376/33.848/57.4272
[16:26:44.647] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 56.2567 for pixel 21/72 mean/min/max = 44.7121/32.9609/56.4632
[16:26:44.647] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.5115 for pixel 4/78 mean/min/max = 45.5471/32.3125/58.7818
[16:26:44.648] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 56.3494 for pixel 12/5 mean/min/max = 45.2543/34.1211/56.3876
[16:26:44.648] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 58.3584 for pixel 14/1 mean/min/max = 44.9579/31.4261/58.4896
[16:26:44.648] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:44.737] <TB0> INFO: Expecting 514560 events.
[16:26:55.704] <TB0> INFO: 514560 events read in total (10410ms).
[16:26:55.711] <TB0> INFO: Expecting 514560 events.
[16:27:06.819] <TB0> INFO: 514560 events read in total (10707ms).
[16:27:06.829] <TB0> INFO: Expecting 514560 events.
[16:27:17.549] <TB0> INFO: 514560 events read in total (10319ms).
[16:27:17.560] <TB0> INFO: Expecting 514560 events.
[16:27:28.234] <TB0> INFO: 514560 events read in total (10272ms).
[16:27:28.248] <TB0> INFO: Expecting 514560 events.
[16:27:38.937] <TB0> INFO: 514560 events read in total (10288ms).
[16:27:38.955] <TB0> INFO: Expecting 514560 events.
[16:27:49.814] <TB0> INFO: 514560 events read in total (10457ms).
[16:27:49.834] <TB0> INFO: Expecting 514560 events.
[16:28:00.464] <TB0> INFO: 514560 events read in total (10228ms).
[16:28:00.486] <TB0> INFO: Expecting 514560 events.
[16:28:11.207] <TB0> INFO: 514560 events read in total (10319ms).
[16:28:11.232] <TB0> INFO: Expecting 514560 events.
[16:28:21.892] <TB0> INFO: 514560 events read in total (10258ms).
[16:28:21.919] <TB0> INFO: Expecting 514560 events.
[16:28:32.595] <TB0> INFO: 514560 events read in total (10274ms).
[16:28:32.625] <TB0> INFO: Expecting 514560 events.
[16:28:43.293] <TB0> INFO: 514560 events read in total (10266ms).
[16:28:43.329] <TB0> INFO: Expecting 514560 events.
[16:28:54.099] <TB0> INFO: 514560 events read in total (10368ms).
[16:28:54.137] <TB0> INFO: Expecting 514560 events.
[16:29:04.856] <TB0> INFO: 514560 events read in total (10318ms).
[16:29:04.896] <TB0> INFO: Expecting 514560 events.
[16:29:15.585] <TB0> INFO: 514560 events read in total (10287ms).
[16:29:15.628] <TB0> INFO: Expecting 514560 events.
[16:29:26.353] <TB0> INFO: 514560 events read in total (10324ms).
[16:29:26.398] <TB0> INFO: Expecting 514560 events.
[16:29:37.222] <TB0> INFO: 514560 events read in total (10422ms).
[16:29:37.268] <TB0> INFO: Test took 172620ms.
[16:29:38.187] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:29:38.196] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:29:38.196] <TB0> INFO: run 1 of 1
[16:29:38.459] <TB0> INFO: Expecting 6281600 events.
[16:30:09.733] <TB0> INFO: 747710 events read in total (30717ms).
[16:30:40.597] <TB0> INFO: 1494330 events read in total (61581ms).
[16:31:11.141] <TB0> INFO: 2241760 events read in total (92125ms).
[16:31:42.031] <TB0> INFO: 2988980 events read in total (123015ms).
[16:32:12.818] <TB0> INFO: 3730170 events read in total (153802ms).
[16:32:43.250] <TB0> INFO: 4467310 events read in total (184234ms).
[16:33:13.806] <TB0> INFO: 5202450 events read in total (214790ms).
[16:33:44.665] <TB0> INFO: 5936560 events read in total (245649ms).
[16:33:59.669] <TB0> INFO: 6281600 events read in total (260653ms).
[16:33:59.722] <TB0> INFO: Test took 261526ms.
[16:34:25.528] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 12.219785 .. 50.415853
[16:34:25.841] <TB0> INFO: Expecting 208000 events.
[16:34:35.640] <TB0> INFO: 208000 events read in total (9231ms).
[16:34:35.641] <TB0> INFO: Test took 10112ms.
[16:34:35.725] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 2 .. 60 (-1/-1) hits flags = 528 (plus default)
[16:34:35.733] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:34:35.733] <TB0> INFO: run 1 of 1
[16:34:35.993] <TB0> INFO: Expecting 2454400 events.
[16:35:11.034] <TB0> INFO: 944110 events read in total (34485ms).
[16:35:45.516] <TB0> INFO: 1883000 events read in total (68967ms).
[16:36:06.298] <TB0> INFO: 2454400 events read in total (89749ms).
[16:36:06.323] <TB0> INFO: Test took 90591ms.
[16:36:20.493] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 21.360669 .. 44.658010
[16:36:20.757] <TB0> INFO: Expecting 208000 events.
[16:36:30.803] <TB0> INFO: 208000 events read in total (9490ms).
[16:36:30.803] <TB0> INFO: Test took 10307ms.
[16:36:30.893] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[16:36:30.901] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:36:30.902] <TB0> INFO: run 1 of 1
[16:36:31.170] <TB0> INFO: Expecting 1830400 events.
[16:37:06.692] <TB0> INFO: 929640 events read in total (34966ms).
[16:37:40.580] <TB0> INFO: 1830400 events read in total (68854ms).
[16:37:40.598] <TB0> INFO: Test took 69696ms.
[16:37:54.269] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 22.543367 .. 41.475612
[16:37:54.531] <TB0> INFO: Expecting 208000 events.
[16:38:04.458] <TB0> INFO: 208000 events read in total (9371ms).
[16:38:04.460] <TB0> INFO: Test took 10189ms.
[16:38:04.550] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[16:38:04.560] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:38:04.560] <TB0> INFO: run 1 of 1
[16:38:04.822] <TB0> INFO: Expecting 1664000 events.
[16:38:40.383] <TB0> INFO: 951980 events read in total (35005ms).
[16:39:06.927] <TB0> INFO: 1664000 events read in total (61549ms).
[16:39:06.944] <TB0> INFO: Test took 62382ms.
[16:39:20.644] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.237209 .. 41.475612
[16:39:20.911] <TB0> INFO: Expecting 208000 events.
[16:39:30.709] <TB0> INFO: 208000 events read in total (9241ms).
[16:39:30.710] <TB0> INFO: Test took 10064ms.
[16:39:30.800] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 51 (-1/-1) hits flags = 528 (plus default)
[16:39:30.808] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:39:30.808] <TB0> INFO: run 1 of 1
[16:39:31.070] <TB0> INFO: Expecting 1580800 events.
[16:40:06.102] <TB0> INFO: 938740 events read in total (34476ms).
[16:40:29.814] <TB0> INFO: 1580800 events read in total (58189ms).
[16:40:29.832] <TB0> INFO: Test took 59024ms.
[16:40:43.708] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:40:43.708] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:40:43.717] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:40:43.717] <TB0> INFO: run 1 of 1
[16:40:43.978] <TB0> INFO: Expecting 1705600 events.
[16:41:18.384] <TB0> INFO: 895270 events read in total (33849ms).
[16:41:49.138] <TB0> INFO: 1705600 events read in total (64603ms).
[16:41:49.155] <TB0> INFO: Test took 65438ms.
[16:42:03.704] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:42:03.704] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:42:03.704] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:42:03.704] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:42:03.705] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:42:03.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:42:03.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:42:03.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:42:03.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:42:03.706] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:42:03.706] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:42:03.716] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:42:03.725] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:42:03.731] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:42:03.738] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:42:03.744] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:42:03.750] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:42:03.756] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:42:03.762] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:42:03.769] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:42:03.776] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:42:03.785] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:42:03.794] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:42:03.803] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:42:03.809] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:42:03.816] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:42:03.822] <TB0> INFO: PixTestTrim::trimTest() done
[16:42:03.822] <TB0> INFO: vtrim: 93 103 96 83 96 110 92 97 96 106 96 108 95 101 104 96
[16:42:03.822] <TB0> INFO: vthrcomp: 85 95 90 85 100 88 96 92 87 87 87 90 87 93 90 100
[16:42:03.822] <TB0> INFO: vcal mean: 35.00 34.97 34.98 34.98 34.97 34.99 34.96 34.98 34.98 35.02 34.99 35.04 35.01 34.96 34.99 34.97
[16:42:03.822] <TB0> INFO: vcal RMS: 0.85 0.86 0.80 0.85 0.91 0.84 0.87 0.88 0.84 0.88 0.84 0.82 0.84 0.84 0.76 0.89
[16:42:03.822] <TB0> INFO: bits mean: 9.24 9.63 8.88 9.95 8.90 9.40 9.40 9.23 9.50 8.98 9.78 9.48 9.98 9.64 9.30 9.79
[16:42:03.822] <TB0> INFO: bits RMS: 2.68 2.57 2.64 2.55 2.82 2.54 2.81 2.52 2.75 2.81 2.75 2.44 2.42 2.62 2.56 2.71
[16:42:03.828] <TB0> INFO: ----------------------------------------------------------------------
[16:42:03.828] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:42:03.828] <TB0> INFO: ----------------------------------------------------------------------
[16:42:03.830] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:42:03.839] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:42:03.839] <TB0> INFO: run 1 of 1
[16:42:04.125] <TB0> INFO: Expecting 4160000 events.
[16:42:42.652] <TB0> INFO: 947460 events read in total (37971ms).
[16:43:19.716] <TB0> INFO: 1885975 events read in total (75035ms).
[16:43:57.297] <TB0> INFO: 2813665 events read in total (112616ms).
[16:44:34.854] <TB0> INFO: 3735750 events read in total (150173ms).
[16:44:52.100] <TB0> INFO: 4160000 events read in total (167419ms).
[16:44:52.155] <TB0> INFO: Test took 168317ms.
[16:45:21.617] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[16:45:21.625] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:45:21.625] <TB0> INFO: run 1 of 1
[16:45:21.885] <TB0> INFO: Expecting 3556800 events.
[16:46:01.229] <TB0> INFO: 979605 events read in total (38788ms).
[16:46:39.901] <TB0> INFO: 1945715 events read in total (77460ms).
[16:47:17.707] <TB0> INFO: 2898010 events read in total (115266ms).
[16:47:44.166] <TB0> INFO: 3556800 events read in total (141725ms).
[16:47:44.223] <TB0> INFO: Test took 142598ms.
[16:48:13.694] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[16:48:13.703] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:48:13.703] <TB0> INFO: run 1 of 1
[16:48:13.989] <TB0> INFO: Expecting 3244800 events.
[16:48:54.267] <TB0> INFO: 1025820 events read in total (39722ms).
[16:49:34.263] <TB0> INFO: 2032210 events read in total (79718ms).
[16:50:14.669] <TB0> INFO: 3023665 events read in total (120124ms).
[16:50:24.031] <TB0> INFO: 3244800 events read in total (129486ms).
[16:50:24.082] <TB0> INFO: Test took 130380ms.
[16:50:50.817] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[16:50:50.825] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:50:50.825] <TB0> INFO: run 1 of 1
[16:50:51.091] <TB0> INFO: Expecting 3244800 events.
[16:51:30.891] <TB0> INFO: 1025515 events read in total (39244ms).
[16:52:09.971] <TB0> INFO: 2031630 events read in total (78324ms).
[16:52:48.846] <TB0> INFO: 3022905 events read in total (117199ms).
[16:52:57.761] <TB0> INFO: 3244800 events read in total (126114ms).
[16:52:57.810] <TB0> INFO: Test took 126984ms.
[16:53:22.501] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[16:53:22.510] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:53:22.510] <TB0> INFO: run 1 of 1
[16:53:22.774] <TB0> INFO: Expecting 3244800 events.
[16:54:02.507] <TB0> INFO: 1024425 events read in total (39177ms).
[16:54:41.532] <TB0> INFO: 2029525 events read in total (78202ms).
[16:55:20.345] <TB0> INFO: 3020210 events read in total (117015ms).
[16:55:29.259] <TB0> INFO: 3244800 events read in total (125929ms).
[16:55:29.306] <TB0> INFO: Test took 126795ms.
[16:55:55.980] <TB0> INFO: PixTestTrim::trimBitTest() done
[16:55:55.981] <TB0> INFO: PixTestTrim::doTest() done, duration: 2265 seconds
[16:55:55.981] <TB0> INFO: Decoding statistics:
[16:55:55.981] <TB0> INFO: General information:
[16:55:55.981] <TB0> INFO: 16bit words read: 0
[16:55:55.981] <TB0> INFO: valid events total: 0
[16:55:55.981] <TB0> INFO: empty events: 0
[16:55:55.981] <TB0> INFO: valid events with pixels: 0
[16:55:55.981] <TB0> INFO: valid pixel hits: 0
[16:55:55.981] <TB0> INFO: Event errors: 0
[16:55:55.981] <TB0> INFO: start marker: 0
[16:55:55.981] <TB0> INFO: stop marker: 0
[16:55:55.981] <TB0> INFO: overflow: 0
[16:55:55.981] <TB0> INFO: invalid 5bit words: 0
[16:55:55.981] <TB0> INFO: invalid XOR eye diagram: 0
[16:55:55.981] <TB0> INFO: frame (failed synchr.): 0
[16:55:55.981] <TB0> INFO: idle data (no TBM trl): 0
[16:55:55.981] <TB0> INFO: no data (only TBM hdr): 0
[16:55:55.981] <TB0> INFO: TBM errors: 0
[16:55:55.981] <TB0> INFO: flawed TBM headers: 0
[16:55:55.981] <TB0> INFO: flawed TBM trailers: 0
[16:55:55.981] <TB0> INFO: event ID mismatches: 0
[16:55:55.981] <TB0> INFO: ROC errors: 0
[16:55:55.981] <TB0> INFO: missing ROC header(s): 0
[16:55:55.981] <TB0> INFO: misplaced readback start: 0
[16:55:55.981] <TB0> INFO: Pixel decoding errors: 0
[16:55:55.981] <TB0> INFO: pixel data incomplete: 0
[16:55:55.981] <TB0> INFO: pixel address: 0
[16:55:55.981] <TB0> INFO: pulse height fill bit: 0
[16:55:55.981] <TB0> INFO: buffer corruption: 0
[16:55:56.707] <TB0> INFO: ######################################################################
[16:55:56.707] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:55:56.707] <TB0> INFO: ######################################################################
[16:55:56.970] <TB0> INFO: Expecting 41600 events.
[16:56:00.376] <TB0> INFO: 41600 events read in total (2850ms).
[16:56:00.377] <TB0> INFO: Test took 3669ms.
[16:56:00.817] <TB0> INFO: Expecting 41600 events.
[16:56:04.318] <TB0> INFO: 41600 events read in total (2945ms).
[16:56:04.319] <TB0> INFO: Test took 3762ms.
[16:56:04.704] <TB0> INFO: Expecting 41600 events.
[16:56:08.199] <TB0> INFO: 41600 events read in total (2938ms).
[16:56:08.199] <TB0> INFO: Test took 3814ms.
[16:56:08.454] <TB0> INFO: Expecting 2560 events.
[16:56:09.307] <TB0> INFO: 2560 events read in total (297ms).
[16:56:09.307] <TB0> INFO: Test took 1096ms.
[16:56:09.612] <TB0> INFO: Expecting 2560 events.
[16:56:10.465] <TB0> INFO: 2560 events read in total (295ms).
[16:56:10.465] <TB0> INFO: Test took 1158ms.
[16:56:10.770] <TB0> INFO: Expecting 2560 events.
[16:56:11.621] <TB0> INFO: 2560 events read in total (295ms).
[16:56:11.621] <TB0> INFO: Test took 1156ms.
[16:56:11.926] <TB0> INFO: Expecting 2560 events.
[16:56:12.779] <TB0> INFO: 2560 events read in total (297ms).
[16:56:12.779] <TB0> INFO: Test took 1158ms.
[16:56:13.084] <TB0> INFO: Expecting 2560 events.
[16:56:13.934] <TB0> INFO: 2560 events read in total (294ms).
[16:56:13.934] <TB0> INFO: Test took 1155ms.
[16:56:14.239] <TB0> INFO: Expecting 2560 events.
[16:56:15.090] <TB0> INFO: 2560 events read in total (295ms).
[16:56:15.090] <TB0> INFO: Test took 1156ms.
[16:56:15.395] <TB0> INFO: Expecting 2560 events.
[16:56:16.246] <TB0> INFO: 2560 events read in total (295ms).
[16:56:16.247] <TB0> INFO: Test took 1157ms.
[16:56:16.551] <TB0> INFO: Expecting 2560 events.
[16:56:17.401] <TB0> INFO: 2560 events read in total (293ms).
[16:56:17.402] <TB0> INFO: Test took 1155ms.
[16:56:17.706] <TB0> INFO: Expecting 2560 events.
[16:56:18.563] <TB0> INFO: 2560 events read in total (300ms).
[16:56:18.563] <TB0> INFO: Test took 1161ms.
[16:56:18.867] <TB0> INFO: Expecting 2560 events.
[16:56:19.723] <TB0> INFO: 2560 events read in total (299ms).
[16:56:19.723] <TB0> INFO: Test took 1160ms.
[16:56:20.028] <TB0> INFO: Expecting 2560 events.
[16:56:20.879] <TB0> INFO: 2560 events read in total (295ms).
[16:56:20.879] <TB0> INFO: Test took 1155ms.
[16:56:21.184] <TB0> INFO: Expecting 2560 events.
[16:56:22.036] <TB0> INFO: 2560 events read in total (295ms).
[16:56:22.036] <TB0> INFO: Test took 1156ms.
[16:56:22.341] <TB0> INFO: Expecting 2560 events.
[16:56:23.193] <TB0> INFO: 2560 events read in total (296ms).
[16:56:23.194] <TB0> INFO: Test took 1157ms.
[16:56:23.498] <TB0> INFO: Expecting 2560 events.
[16:56:24.350] <TB0> INFO: 2560 events read in total (296ms).
[16:56:24.350] <TB0> INFO: Test took 1156ms.
[16:56:24.656] <TB0> INFO: Expecting 2560 events.
[16:56:25.508] <TB0> INFO: 2560 events read in total (296ms).
[16:56:25.508] <TB0> INFO: Test took 1156ms.
[16:56:25.813] <TB0> INFO: Expecting 2560 events.
[16:56:26.666] <TB0> INFO: 2560 events read in total (297ms).
[16:56:26.666] <TB0> INFO: Test took 1156ms.
[16:56:26.669] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:56:26.972] <TB0> INFO: Expecting 655360 events.
[16:56:41.117] <TB0> INFO: 655360 events read in total (13589ms).
[16:56:41.128] <TB0> INFO: Expecting 655360 events.
[16:56:54.955] <TB0> INFO: 655360 events read in total (13425ms).
[16:56:54.970] <TB0> INFO: Expecting 655360 events.
[16:57:08.806] <TB0> INFO: 655360 events read in total (13435ms).
[16:57:08.828] <TB0> INFO: Expecting 655360 events.
[16:57:22.720] <TB0> INFO: 655360 events read in total (13491ms).
[16:57:22.743] <TB0> INFO: Expecting 655360 events.
[16:57:36.547] <TB0> INFO: 655360 events read in total (13403ms).
[16:57:36.580] <TB0> INFO: Expecting 655360 events.
[16:57:50.346] <TB0> INFO: 655360 events read in total (13365ms).
[16:57:50.379] <TB0> INFO: Expecting 655360 events.
[16:58:04.061] <TB0> INFO: 655360 events read in total (13280ms).
[16:58:04.096] <TB0> INFO: Expecting 655360 events.
[16:58:17.810] <TB0> INFO: 655360 events read in total (13313ms).
[16:58:17.852] <TB0> INFO: Expecting 655360 events.
[16:58:31.529] <TB0> INFO: 655360 events read in total (13275ms).
[16:58:31.575] <TB0> INFO: Expecting 655360 events.
[16:58:45.309] <TB0> INFO: 655360 events read in total (13332ms).
[16:58:45.365] <TB0> INFO: Expecting 655360 events.
[16:58:59.144] <TB0> INFO: 655360 events read in total (13377ms).
[16:58:59.200] <TB0> INFO: Expecting 655360 events.
[16:59:13.012] <TB0> INFO: 655360 events read in total (13410ms).
[16:59:13.073] <TB0> INFO: Expecting 655360 events.
[16:59:26.774] <TB0> INFO: 655360 events read in total (13299ms).
[16:59:26.840] <TB0> INFO: Expecting 655360 events.
[16:59:40.779] <TB0> INFO: 655360 events read in total (13537ms).
[16:59:40.857] <TB0> INFO: Expecting 655360 events.
[16:59:54.768] <TB0> INFO: 655360 events read in total (13509ms).
[16:59:54.839] <TB0> INFO: Expecting 655360 events.
[17:00:08.562] <TB0> INFO: 655360 events read in total (13322ms).
[17:00:08.642] <TB0> INFO: Test took 221973ms.
[17:00:08.723] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:00:08.953] <TB0> INFO: Expecting 655360 events.
[17:00:22.981] <TB0> INFO: 655360 events read in total (13471ms).
[17:00:22.993] <TB0> INFO: Expecting 655360 events.
[17:00:36.705] <TB0> INFO: 655360 events read in total (13310ms).
[17:00:36.720] <TB0> INFO: Expecting 655360 events.
[17:00:50.543] <TB0> INFO: 655360 events read in total (13422ms).
[17:00:50.562] <TB0> INFO: Expecting 655360 events.
[17:01:04.398] <TB0> INFO: 655360 events read in total (13435ms).
[17:01:04.423] <TB0> INFO: Expecting 655360 events.
[17:01:18.071] <TB0> INFO: 655360 events read in total (13246ms).
[17:01:18.100] <TB0> INFO: Expecting 655360 events.
[17:01:32.059] <TB0> INFO: 655360 events read in total (13557ms).
[17:01:32.090] <TB0> INFO: Expecting 655360 events.
[17:01:45.787] <TB0> INFO: 655360 events read in total (13295ms).
[17:01:45.829] <TB0> INFO: Expecting 655360 events.
[17:01:59.476] <TB0> INFO: 655360 events read in total (13245ms).
[17:01:59.520] <TB0> INFO: Expecting 655360 events.
[17:02:13.380] <TB0> INFO: 655360 events read in total (13459ms).
[17:02:13.426] <TB0> INFO: Expecting 655360 events.
[17:02:27.237] <TB0> INFO: 655360 events read in total (13409ms).
[17:02:27.288] <TB0> INFO: Expecting 655360 events.
[17:02:41.073] <TB0> INFO: 655360 events read in total (13384ms).
[17:02:41.131] <TB0> INFO: Expecting 655360 events.
[17:02:54.919] <TB0> INFO: 655360 events read in total (13386ms).
[17:02:54.975] <TB0> INFO: Expecting 655360 events.
[17:03:08.767] <TB0> INFO: 655360 events read in total (13390ms).
[17:03:08.828] <TB0> INFO: Expecting 655360 events.
[17:03:22.676] <TB0> INFO: 655360 events read in total (13446ms).
[17:03:22.747] <TB0> INFO: Expecting 655360 events.
[17:03:36.464] <TB0> INFO: 655360 events read in total (13315ms).
[17:03:36.536] <TB0> INFO: Expecting 655360 events.
[17:03:50.370] <TB0> INFO: 655360 events read in total (13432ms).
[17:03:50.449] <TB0> INFO: Test took 221726ms.
[17:03:50.641] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.648] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.655] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.661] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:03:50.668] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:03:50.675] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:03:50.682] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:03:50.689] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:03:50.695] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.702] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.709] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.716] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.722] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.729] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.736] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.743] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.750] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.756] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.763] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.770] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:03:50.777] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:03:50.783] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:03:50.790] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:03:50.797] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:03:50.804] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.810] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:03:50.817] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:03:50.848] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:03:50.848] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:03:50.848] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:03:50.848] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:03:50.848] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:03:50.849] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:03:50.850] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:03:50.850] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:03:50.850] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:03:51.117] <TB0> INFO: Expecting 41600 events.
[17:03:54.240] <TB0> INFO: 41600 events read in total (2567ms).
[17:03:54.240] <TB0> INFO: Test took 3388ms.
[17:03:54.697] <TB0> INFO: Expecting 41600 events.
[17:03:57.781] <TB0> INFO: 41600 events read in total (2528ms).
[17:03:57.781] <TB0> INFO: Test took 3365ms.
[17:03:58.235] <TB0> INFO: Expecting 41600 events.
[17:04:01.362] <TB0> INFO: 41600 events read in total (2571ms).
[17:04:01.362] <TB0> INFO: Test took 3403ms.
[17:04:01.547] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:01.635] <TB0> INFO: Expecting 2560 events.
[17:04:02.492] <TB0> INFO: 2560 events read in total (301ms).
[17:04:02.492] <TB0> INFO: Test took 946ms.
[17:04:02.494] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:02.798] <TB0> INFO: Expecting 2560 events.
[17:04:03.655] <TB0> INFO: 2560 events read in total (301ms).
[17:04:03.655] <TB0> INFO: Test took 1161ms.
[17:04:03.659] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:03.960] <TB0> INFO: Expecting 2560 events.
[17:04:04.817] <TB0> INFO: 2560 events read in total (300ms).
[17:04:04.818] <TB0> INFO: Test took 1159ms.
[17:04:04.820] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:05.122] <TB0> INFO: Expecting 2560 events.
[17:04:05.974] <TB0> INFO: 2560 events read in total (296ms).
[17:04:05.975] <TB0> INFO: Test took 1155ms.
[17:04:05.976] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:06.280] <TB0> INFO: Expecting 2560 events.
[17:04:07.132] <TB0> INFO: 2560 events read in total (296ms).
[17:04:07.132] <TB0> INFO: Test took 1156ms.
[17:04:07.134] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:07.438] <TB0> INFO: Expecting 2560 events.
[17:04:08.294] <TB0> INFO: 2560 events read in total (300ms).
[17:04:08.295] <TB0> INFO: Test took 1161ms.
[17:04:08.297] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:08.599] <TB0> INFO: Expecting 2560 events.
[17:04:09.450] <TB0> INFO: 2560 events read in total (295ms).
[17:04:09.450] <TB0> INFO: Test took 1153ms.
[17:04:09.452] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:09.756] <TB0> INFO: Expecting 2560 events.
[17:04:10.608] <TB0> INFO: 2560 events read in total (296ms).
[17:04:10.608] <TB0> INFO: Test took 1156ms.
[17:04:10.611] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:10.913] <TB0> INFO: Expecting 2560 events.
[17:04:11.766] <TB0> INFO: 2560 events read in total (297ms).
[17:04:11.767] <TB0> INFO: Test took 1157ms.
[17:04:11.770] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:12.072] <TB0> INFO: Expecting 2560 events.
[17:04:12.923] <TB0> INFO: 2560 events read in total (295ms).
[17:04:12.923] <TB0> INFO: Test took 1154ms.
[17:04:12.925] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:13.231] <TB0> INFO: Expecting 2560 events.
[17:04:14.084] <TB0> INFO: 2560 events read in total (297ms).
[17:04:14.084] <TB0> INFO: Test took 1159ms.
[17:04:14.086] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:14.390] <TB0> INFO: Expecting 2560 events.
[17:04:15.253] <TB0> INFO: 2560 events read in total (307ms).
[17:04:15.253] <TB0> INFO: Test took 1167ms.
[17:04:15.255] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:15.558] <TB0> INFO: Expecting 2560 events.
[17:04:16.416] <TB0> INFO: 2560 events read in total (302ms).
[17:04:16.416] <TB0> INFO: Test took 1161ms.
[17:04:16.421] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:16.721] <TB0> INFO: Expecting 2560 events.
[17:04:17.577] <TB0> INFO: 2560 events read in total (300ms).
[17:04:17.578] <TB0> INFO: Test took 1157ms.
[17:04:17.581] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:17.883] <TB0> INFO: Expecting 2560 events.
[17:04:18.741] <TB0> INFO: 2560 events read in total (302ms).
[17:04:18.742] <TB0> INFO: Test took 1162ms.
[17:04:18.743] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:19.047] <TB0> INFO: Expecting 2560 events.
[17:04:19.900] <TB0> INFO: 2560 events read in total (297ms).
[17:04:19.900] <TB0> INFO: Test took 1157ms.
[17:04:19.903] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:20.206] <TB0> INFO: Expecting 2560 events.
[17:04:21.064] <TB0> INFO: 2560 events read in total (296ms).
[17:04:21.065] <TB0> INFO: Test took 1162ms.
[17:04:21.067] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:21.370] <TB0> INFO: Expecting 2560 events.
[17:04:22.223] <TB0> INFO: 2560 events read in total (297ms).
[17:04:22.223] <TB0> INFO: Test took 1156ms.
[17:04:22.225] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:22.529] <TB0> INFO: Expecting 2560 events.
[17:04:23.382] <TB0> INFO: 2560 events read in total (297ms).
[17:04:23.382] <TB0> INFO: Test took 1157ms.
[17:04:23.383] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:23.688] <TB0> INFO: Expecting 2560 events.
[17:04:24.540] <TB0> INFO: 2560 events read in total (296ms).
[17:04:24.540] <TB0> INFO: Test took 1157ms.
[17:04:24.541] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:24.845] <TB0> INFO: Expecting 2560 events.
[17:04:25.698] <TB0> INFO: 2560 events read in total (297ms).
[17:04:25.698] <TB0> INFO: Test took 1157ms.
[17:04:25.700] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:26.004] <TB0> INFO: Expecting 2560 events.
[17:04:26.858] <TB0> INFO: 2560 events read in total (298ms).
[17:04:26.858] <TB0> INFO: Test took 1158ms.
[17:04:26.861] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:27.164] <TB0> INFO: Expecting 2560 events.
[17:04:28.016] <TB0> INFO: 2560 events read in total (295ms).
[17:04:28.016] <TB0> INFO: Test took 1155ms.
[17:04:28.018] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:28.323] <TB0> INFO: Expecting 2560 events.
[17:04:29.176] <TB0> INFO: 2560 events read in total (297ms).
[17:04:29.177] <TB0> INFO: Test took 1159ms.
[17:04:29.179] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:29.482] <TB0> INFO: Expecting 2560 events.
[17:04:30.334] <TB0> INFO: 2560 events read in total (296ms).
[17:04:30.335] <TB0> INFO: Test took 1156ms.
[17:04:30.338] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:30.640] <TB0> INFO: Expecting 2560 events.
[17:04:31.492] <TB0> INFO: 2560 events read in total (296ms).
[17:04:31.492] <TB0> INFO: Test took 1154ms.
[17:04:31.494] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:31.798] <TB0> INFO: Expecting 2560 events.
[17:04:32.661] <TB0> INFO: 2560 events read in total (307ms).
[17:04:32.661] <TB0> INFO: Test took 1167ms.
[17:04:32.667] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:32.966] <TB0> INFO: Expecting 2560 events.
[17:04:33.818] <TB0> INFO: 2560 events read in total (295ms).
[17:04:33.818] <TB0> INFO: Test took 1151ms.
[17:04:33.820] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:34.124] <TB0> INFO: Expecting 2560 events.
[17:04:34.976] <TB0> INFO: 2560 events read in total (296ms).
[17:04:34.976] <TB0> INFO: Test took 1156ms.
[17:04:34.980] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:35.281] <TB0> INFO: Expecting 2560 events.
[17:04:36.143] <TB0> INFO: 2560 events read in total (306ms).
[17:04:36.143] <TB0> INFO: Test took 1164ms.
[17:04:36.145] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:36.448] <TB0> INFO: Expecting 2560 events.
[17:04:37.304] <TB0> INFO: 2560 events read in total (300ms).
[17:04:37.304] <TB0> INFO: Test took 1159ms.
[17:04:37.306] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:04:37.610] <TB0> INFO: Expecting 2560 events.
[17:04:38.463] <TB0> INFO: 2560 events read in total (296ms).
[17:04:38.463] <TB0> INFO: Test took 1157ms.
[17:04:38.929] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 522 seconds
[17:04:38.929] <TB0> INFO: PH scale (per ROC): 67 68 74 75 67 75 68 74 79 75 79 72 77 79 81 70
[17:04:38.929] <TB0> INFO: PH offset (per ROC): 179 165 176 182 187 165 174 176 160 170 186 173 179 176 165 176
[17:04:38.934] <TB0> INFO: Decoding statistics:
[17:04:38.934] <TB0> INFO: General information:
[17:04:38.934] <TB0> INFO: 16bit words read: 86922
[17:04:38.934] <TB0> INFO: valid events total: 10240
[17:04:38.934] <TB0> INFO: empty events: 7739
[17:04:38.934] <TB0> INFO: valid events with pixels: 2501
[17:04:38.934] <TB0> INFO: valid pixel hits: 2501
[17:04:38.934] <TB0> INFO: Event errors: 0
[17:04:38.934] <TB0> INFO: start marker: 0
[17:04:38.934] <TB0> INFO: stop marker: 0
[17:04:38.934] <TB0> INFO: overflow: 0
[17:04:38.934] <TB0> INFO: invalid 5bit words: 0
[17:04:38.935] <TB0> INFO: invalid XOR eye diagram: 0
[17:04:38.935] <TB0> INFO: frame (failed synchr.): 0
[17:04:38.935] <TB0> INFO: idle data (no TBM trl): 0
[17:04:38.935] <TB0> INFO: no data (only TBM hdr): 0
[17:04:38.935] <TB0> INFO: TBM errors: 0
[17:04:38.935] <TB0> INFO: flawed TBM headers: 0
[17:04:38.935] <TB0> INFO: flawed TBM trailers: 0
[17:04:38.935] <TB0> INFO: event ID mismatches: 0
[17:04:38.935] <TB0> INFO: ROC errors: 0
[17:04:38.935] <TB0> INFO: missing ROC header(s): 0
[17:04:38.935] <TB0> INFO: misplaced readback start: 0
[17:04:38.935] <TB0> INFO: Pixel decoding errors: 0
[17:04:38.935] <TB0> INFO: pixel data incomplete: 0
[17:04:38.935] <TB0> INFO: pixel address: 0
[17:04:38.935] <TB0> INFO: pulse height fill bit: 0
[17:04:38.935] <TB0> INFO: buffer corruption: 0
[17:04:39.114] <TB0> INFO: ######################################################################
[17:04:39.114] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:04:39.114] <TB0> INFO: ######################################################################
[17:04:39.123] <TB0> INFO: scanning low vcal = 10
[17:04:39.387] <TB0> INFO: Expecting 41600 events.
[17:04:42.781] <TB0> INFO: 41600 events read in total (2838ms).
[17:04:42.782] <TB0> INFO: Test took 3658ms.
[17:04:42.783] <TB0> INFO: scanning low vcal = 20
[17:04:43.080] <TB0> INFO: Expecting 41600 events.
[17:04:46.475] <TB0> INFO: 41600 events read in total (2839ms).
[17:04:46.475] <TB0> INFO: Test took 3692ms.
[17:04:46.476] <TB0> INFO: scanning low vcal = 30
[17:04:46.775] <TB0> INFO: Expecting 41600 events.
[17:04:50.202] <TB0> INFO: 41600 events read in total (2871ms).
[17:04:50.203] <TB0> INFO: Test took 3726ms.
[17:04:50.205] <TB0> INFO: scanning low vcal = 40
[17:04:50.488] <TB0> INFO: Expecting 41600 events.
[17:04:54.363] <TB0> INFO: 41600 events read in total (3319ms).
[17:04:54.364] <TB0> INFO: Test took 4159ms.
[17:04:54.366] <TB0> INFO: scanning low vcal = 50
[17:04:54.627] <TB0> INFO: Expecting 41600 events.
[17:04:58.595] <TB0> INFO: 41600 events read in total (3412ms).
[17:04:58.597] <TB0> INFO: Test took 4231ms.
[17:04:58.599] <TB0> INFO: scanning low vcal = 60
[17:04:58.864] <TB0> INFO: Expecting 41600 events.
[17:05:02.815] <TB0> INFO: 41600 events read in total (3395ms).
[17:05:02.816] <TB0> INFO: Test took 4216ms.
[17:05:02.818] <TB0> INFO: scanning low vcal = 70
[17:05:03.083] <TB0> INFO: Expecting 41600 events.
[17:05:07.031] <TB0> INFO: 41600 events read in total (3392ms).
[17:05:07.031] <TB0> INFO: Test took 4213ms.
[17:05:07.034] <TB0> INFO: scanning low vcal = 80
[17:05:07.310] <TB0> INFO: Expecting 41600 events.
[17:05:11.244] <TB0> INFO: 41600 events read in total (3378ms).
[17:05:11.244] <TB0> INFO: Test took 4210ms.
[17:05:11.247] <TB0> INFO: scanning low vcal = 90
[17:05:11.525] <TB0> INFO: Expecting 41600 events.
[17:05:15.461] <TB0> INFO: 41600 events read in total (3380ms).
[17:05:15.462] <TB0> INFO: Test took 4215ms.
[17:05:15.465] <TB0> INFO: scanning low vcal = 100
[17:05:15.729] <TB0> INFO: Expecting 41600 events.
[17:05:19.682] <TB0> INFO: 41600 events read in total (3397ms).
[17:05:19.683] <TB0> INFO: Test took 4218ms.
[17:05:19.685] <TB0> INFO: scanning low vcal = 110
[17:05:19.952] <TB0> INFO: Expecting 41600 events.
[17:05:23.897] <TB0> INFO: 41600 events read in total (3389ms).
[17:05:23.897] <TB0> INFO: Test took 4212ms.
[17:05:23.900] <TB0> INFO: scanning low vcal = 120
[17:05:24.160] <TB0> INFO: Expecting 41600 events.
[17:05:28.105] <TB0> INFO: 41600 events read in total (3389ms).
[17:05:28.105] <TB0> INFO: Test took 4205ms.
[17:05:28.108] <TB0> INFO: scanning low vcal = 130
[17:05:28.371] <TB0> INFO: Expecting 41600 events.
[17:05:32.313] <TB0> INFO: 41600 events read in total (3386ms).
[17:05:32.313] <TB0> INFO: Test took 4205ms.
[17:05:32.316] <TB0> INFO: scanning low vcal = 140
[17:05:32.587] <TB0> INFO: Expecting 41600 events.
[17:05:36.513] <TB0> INFO: 41600 events read in total (3369ms).
[17:05:36.513] <TB0> INFO: Test took 4197ms.
[17:05:36.516] <TB0> INFO: scanning low vcal = 150
[17:05:36.779] <TB0> INFO: Expecting 41600 events.
[17:05:40.707] <TB0> INFO: 41600 events read in total (3372ms).
[17:05:40.708] <TB0> INFO: Test took 4192ms.
[17:05:40.710] <TB0> INFO: scanning low vcal = 160
[17:05:40.983] <TB0> INFO: Expecting 41600 events.
[17:05:44.907] <TB0> INFO: 41600 events read in total (3368ms).
[17:05:44.907] <TB0> INFO: Test took 4196ms.
[17:05:44.910] <TB0> INFO: scanning low vcal = 170
[17:05:45.178] <TB0> INFO: Expecting 41600 events.
[17:05:49.126] <TB0> INFO: 41600 events read in total (3392ms).
[17:05:49.128] <TB0> INFO: Test took 4218ms.
[17:05:49.132] <TB0> INFO: scanning low vcal = 180
[17:05:49.407] <TB0> INFO: Expecting 41600 events.
[17:05:53.357] <TB0> INFO: 41600 events read in total (3393ms).
[17:05:53.358] <TB0> INFO: Test took 4226ms.
[17:05:53.360] <TB0> INFO: scanning low vcal = 190
[17:05:53.623] <TB0> INFO: Expecting 41600 events.
[17:05:57.563] <TB0> INFO: 41600 events read in total (3384ms).
[17:05:57.564] <TB0> INFO: Test took 4204ms.
[17:05:57.566] <TB0> INFO: scanning low vcal = 200
[17:05:57.826] <TB0> INFO: Expecting 41600 events.
[17:06:01.783] <TB0> INFO: 41600 events read in total (3401ms).
[17:06:01.784] <TB0> INFO: Test took 4218ms.
[17:06:01.786] <TB0> INFO: scanning low vcal = 210
[17:06:02.051] <TB0> INFO: Expecting 41600 events.
[17:06:05.985] <TB0> INFO: 41600 events read in total (3378ms).
[17:06:05.985] <TB0> INFO: Test took 4198ms.
[17:06:05.988] <TB0> INFO: scanning low vcal = 220
[17:06:06.263] <TB0> INFO: Expecting 41600 events.
[17:06:10.193] <TB0> INFO: 41600 events read in total (3374ms).
[17:06:10.194] <TB0> INFO: Test took 4206ms.
[17:06:10.196] <TB0> INFO: scanning low vcal = 230
[17:06:10.462] <TB0> INFO: Expecting 41600 events.
[17:06:14.436] <TB0> INFO: 41600 events read in total (3418ms).
[17:06:14.437] <TB0> INFO: Test took 4241ms.
[17:06:14.439] <TB0> INFO: scanning low vcal = 240
[17:06:14.705] <TB0> INFO: Expecting 41600 events.
[17:06:18.625] <TB0> INFO: 41600 events read in total (3364ms).
[17:06:18.625] <TB0> INFO: Test took 4187ms.
[17:06:18.628] <TB0> INFO: scanning low vcal = 250
[17:06:18.888] <TB0> INFO: Expecting 41600 events.
[17:06:22.805] <TB0> INFO: 41600 events read in total (3361ms).
[17:06:22.807] <TB0> INFO: Test took 4179ms.
[17:06:22.810] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[17:06:23.072] <TB0> INFO: Expecting 41600 events.
[17:06:27.041] <TB0> INFO: 41600 events read in total (3413ms).
[17:06:27.042] <TB0> INFO: Test took 4231ms.
[17:06:27.045] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[17:06:27.346] <TB0> INFO: Expecting 41600 events.
[17:06:31.266] <TB0> INFO: 41600 events read in total (3364ms).
[17:06:31.266] <TB0> INFO: Test took 4221ms.
[17:06:31.269] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[17:06:31.533] <TB0> INFO: Expecting 41600 events.
[17:06:35.532] <TB0> INFO: 41600 events read in total (3443ms).
[17:06:35.534] <TB0> INFO: Test took 4265ms.
[17:06:35.536] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[17:06:35.806] <TB0> INFO: Expecting 41600 events.
[17:06:39.765] <TB0> INFO: 41600 events read in total (3403ms).
[17:06:39.766] <TB0> INFO: Test took 4230ms.
[17:06:39.769] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:06:40.044] <TB0> INFO: Expecting 41600 events.
[17:06:44.017] <TB0> INFO: 41600 events read in total (3417ms).
[17:06:44.017] <TB0> INFO: Test took 4248ms.
[17:06:44.512] <TB0> INFO: PixTestGainPedestal::measure() done
[17:07:17.670] <TB0> INFO: PixTestGainPedestal::fit() done
[17:07:17.670] <TB0> INFO: non-linearity mean: 0.949 0.957 0.955 0.956 0.958 0.956 0.956 0.953 0.958 0.955 0.953 0.953 0.951 0.961 0.953 0.955
[17:07:17.670] <TB0> INFO: non-linearity RMS: 0.008 0.007 0.005 0.006 0.008 0.006 0.007 0.007 0.007 0.006 0.007 0.008 0.007 0.004 0.007 0.006
[17:07:17.670] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[17:07:17.689] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[17:07:17.707] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[17:07:17.732] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[17:07:17.752] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[17:07:17.776] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[17:07:17.794] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[17:07:17.814] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[17:07:17.834] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[17:07:17.853] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[17:07:17.873] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[17:07:17.892] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[17:07:17.912] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[17:07:17.932] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[17:07:17.951] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[17:07:17.971] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[17:07:17.991] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[17:07:17.991] <TB0> INFO: Decoding statistics:
[17:07:17.991] <TB0> INFO: General information:
[17:07:17.991] <TB0> INFO: 16bit words read: 2662400
[17:07:17.991] <TB0> INFO: valid events total: 166400
[17:07:17.991] <TB0> INFO: empty events: 0
[17:07:17.991] <TB0> INFO: valid events with pixels: 166400
[17:07:17.991] <TB0> INFO: valid pixel hits: 665600
[17:07:17.991] <TB0> INFO: Event errors: 0
[17:07:17.991] <TB0> INFO: start marker: 0
[17:07:17.991] <TB0> INFO: stop marker: 0
[17:07:17.991] <TB0> INFO: overflow: 0
[17:07:17.991] <TB0> INFO: invalid 5bit words: 0
[17:07:17.991] <TB0> INFO: invalid XOR eye diagram: 0
[17:07:17.991] <TB0> INFO: frame (failed synchr.): 0
[17:07:17.991] <TB0> INFO: idle data (no TBM trl): 0
[17:07:17.991] <TB0> INFO: no data (only TBM hdr): 0
[17:07:17.991] <TB0> INFO: TBM errors: 0
[17:07:17.991] <TB0> INFO: flawed TBM headers: 0
[17:07:17.991] <TB0> INFO: flawed TBM trailers: 0
[17:07:17.991] <TB0> INFO: event ID mismatches: 0
[17:07:17.991] <TB0> INFO: ROC errors: 0
[17:07:17.991] <TB0> INFO: missing ROC header(s): 0
[17:07:17.991] <TB0> INFO: misplaced readback start: 0
[17:07:17.991] <TB0> INFO: Pixel decoding errors: 0
[17:07:17.991] <TB0> INFO: pixel data incomplete: 0
[17:07:17.991] <TB0> INFO: pixel address: 0
[17:07:17.991] <TB0> INFO: pulse height fill bit: 0
[17:07:17.991] <TB0> INFO: buffer corruption: 0
[17:07:18.000] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C15.dat
[17:07:18.001] <TB0> INFO: ######################################################################
[17:07:18.001] <TB0> INFO: PixTestReadback::doTest()
[17:07:18.001] <TB0> INFO: ######################################################################
[17:07:18.001] <TB0> INFO: ----------------------------------------------------------------------
[17:07:18.001] <TB0> INFO: PixTestReadback::CalibrateVd()
[17:07:18.001] <TB0> INFO: ----------------------------------------------------------------------
[17:07:27.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C0.dat
[17:07:27.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C1.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C2.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C3.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C4.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C5.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C6.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C7.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C8.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C9.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C10.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C11.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C12.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C13.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C14.dat
[17:07:27.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C15.dat
[17:07:27.479] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:07:27.479] <TB0> INFO: ----------------------------------------------------------------------
[17:07:27.479] <TB0> INFO: PixTestReadback::CalibrateVa()
[17:07:27.479] <TB0> INFO: ----------------------------------------------------------------------
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C0.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C1.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C2.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C3.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C4.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C5.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C6.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C7.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C8.dat
[17:07:36.938] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C9.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C10.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C11.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C12.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C13.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C14.dat
[17:07:36.939] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C15.dat
[17:07:36.968] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:07:36.968] <TB0> INFO: ----------------------------------------------------------------------
[17:07:36.968] <TB0> INFO: PixTestReadback::readbackVbg()
[17:07:36.968] <TB0> INFO: ----------------------------------------------------------------------
[17:07:44.254] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:07:44.254] <TB0> INFO: ----------------------------------------------------------------------
[17:07:44.254] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[17:07:44.254] <TB0> INFO: ----------------------------------------------------------------------
[17:07:44.254] <TB0> INFO: Vbg will be calibrated using Vd calibration
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.1calibrated Vbg = 1.23423 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.5calibrated Vbg = 1.22715 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.2calibrated Vbg = 1.23613 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.9calibrated Vbg = 1.23282 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 162.2calibrated Vbg = 1.24333 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 147.9calibrated Vbg = 1.24051 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155calibrated Vbg = 1.23837 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 145.3calibrated Vbg = 1.23726 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.8calibrated Vbg = 1.24038 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.6calibrated Vbg = 1.23948 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.9calibrated Vbg = 1.24172 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145calibrated Vbg = 1.23989 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.3calibrated Vbg = 1.23357 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.1calibrated Vbg = 1.2377 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.2calibrated Vbg = 1.23831 :::*/*/*/*/
[17:07:44.254] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.3calibrated Vbg = 1.22983 :::*/*/*/*/
[17:07:44.256] <TB0> INFO: ----------------------------------------------------------------------
[17:07:44.256] <TB0> INFO: PixTestReadback::CalibrateIa()
[17:07:44.256] <TB0> INFO: ----------------------------------------------------------------------
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C0.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C1.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C2.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C3.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C4.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C5.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C6.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C7.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C8.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C9.dat
[17:10:19.451] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C10.dat
[17:10:19.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C11.dat
[17:10:19.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C12.dat
[17:10:19.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C13.dat
[17:10:19.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C14.dat
[17:10:19.452] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//004_FulltestPxar_p17//readbackCal_C15.dat
[17:10:19.478] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:10:19.479] <TB0> INFO: PixTestReadback::doTest() done
[17:10:19.479] <TB0> INFO: Decoding statistics:
[17:10:19.479] <TB0> INFO: General information:
[17:10:19.479] <TB0> INFO: 16bit words read: 1024
[17:10:19.479] <TB0> INFO: valid events total: 128
[17:10:19.479] <TB0> INFO: empty events: 128
[17:10:19.479] <TB0> INFO: valid events with pixels: 0
[17:10:19.479] <TB0> INFO: valid pixel hits: 0
[17:10:19.479] <TB0> INFO: Event errors: 0
[17:10:19.479] <TB0> INFO: start marker: 0
[17:10:19.479] <TB0> INFO: stop marker: 0
[17:10:19.479] <TB0> INFO: overflow: 0
[17:10:19.479] <TB0> INFO: invalid 5bit words: 0
[17:10:19.479] <TB0> INFO: invalid XOR eye diagram: 0
[17:10:19.479] <TB0> INFO: frame (failed synchr.): 0
[17:10:19.479] <TB0> INFO: idle data (no TBM trl): 0
[17:10:19.479] <TB0> INFO: no data (only TBM hdr): 0
[17:10:19.479] <TB0> INFO: TBM errors: 0
[17:10:19.479] <TB0> INFO: flawed TBM headers: 0
[17:10:19.479] <TB0> INFO: flawed TBM trailers: 0
[17:10:19.479] <TB0> INFO: event ID mismatches: 0
[17:10:19.479] <TB0> INFO: ROC errors: 0
[17:10:19.479] <TB0> INFO: missing ROC header(s): 0
[17:10:19.479] <TB0> INFO: misplaced readback start: 0
[17:10:19.479] <TB0> INFO: Pixel decoding errors: 0
[17:10:19.479] <TB0> INFO: pixel data incomplete: 0
[17:10:19.479] <TB0> INFO: pixel address: 0
[17:10:19.479] <TB0> INFO: pulse height fill bit: 0
[17:10:19.479] <TB0> INFO: buffer corruption: 0
[17:10:19.497] <TB0> INFO: Decoding statistics:
[17:10:19.497] <TB0> INFO: General information:
[17:10:19.497] <TB0> INFO: 16bit words read: 2750346
[17:10:19.497] <TB0> INFO: valid events total: 176768
[17:10:19.497] <TB0> INFO: empty events: 7867
[17:10:19.497] <TB0> INFO: valid events with pixels: 168901
[17:10:19.497] <TB0> INFO: valid pixel hits: 668101
[17:10:19.497] <TB0> INFO: Event errors: 0
[17:10:19.497] <TB0> INFO: start marker: 0
[17:10:19.497] <TB0> INFO: stop marker: 0
[17:10:19.497] <TB0> INFO: overflow: 0
[17:10:19.497] <TB0> INFO: invalid 5bit words: 0
[17:10:19.497] <TB0> INFO: invalid XOR eye diagram: 0
[17:10:19.497] <TB0> INFO: frame (failed synchr.): 0
[17:10:19.497] <TB0> INFO: idle data (no TBM trl): 0
[17:10:19.497] <TB0> INFO: no data (only TBM hdr): 0
[17:10:19.497] <TB0> INFO: TBM errors: 0
[17:10:19.497] <TB0> INFO: flawed TBM headers: 0
[17:10:19.497] <TB0> INFO: flawed TBM trailers: 0
[17:10:19.497] <TB0> INFO: event ID mismatches: 0
[17:10:19.497] <TB0> INFO: ROC errors: 0
[17:10:19.497] <TB0> INFO: missing ROC header(s): 0
[17:10:19.497] <TB0> INFO: misplaced readback start: 0
[17:10:19.497] <TB0> INFO: Pixel decoding errors: 0
[17:10:19.497] <TB0> INFO: pixel data incomplete: 0
[17:10:19.497] <TB0> INFO: pixel address: 0
[17:10:19.497] <TB0> INFO: pulse height fill bit: 0
[17:10:19.497] <TB0> INFO: buffer corruption: 0
[17:10:19.498] <TB0> INFO: enter test to run
[17:10:19.498] <TB0> INFO: test: exit no parameter change
[17:10:19.697] <TB0> QUIET: Connection to board 73 closed.
[17:10:19.777] <TB0> INFO: pXar: this is the end, my friend