[14:08:25.532] <TB1> INFO: *** Welcome to pxar ***
[14:08:25.532] <TB1> INFO: *** Today: 2016/05/11
[14:08:25.623] <TB1> INFO: *** Version: 9751-dirty
[14:08:25.623] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C15.dat
[14:08:25.624] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:08:25.624] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//defaultMaskFile.dat
[14:08:25.624] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters_C15.dat
[14:08:25.696] <TB1> INFO: clk: 4
[14:08:25.696] <TB1> INFO: ctr: 4
[14:08:25.696] <TB1> INFO: sda: 19
[14:08:25.696] <TB1> INFO: tin: 9
[14:08:25.696] <TB1> INFO: level: 15
[14:08:25.696] <TB1> INFO: triggerdelay: 0
[14:08:25.696] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[14:08:25.696] <TB1> INFO: Log level: INFO
[14:08:25.703] <TB1> INFO: Found DTB DTB_WXBYFL
[14:08:25.713] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[14:08:25.715] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[14:08:25.717] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[14:08:27.263] <TB1> INFO: DUT info:
[14:08:27.263] <TB1> INFO: The DUT currently contains the following objects:
[14:08:27.263] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:08:27.263] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:08:27.263] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:08:27.263] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:08:27.264] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.264] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:08:27.665] <TB1> INFO: enter 'restricted' command line mode
[14:08:27.665] <TB1> INFO: enter test to run
[14:08:27.666] <TB1> INFO: test: pretest no parameter change
[14:08:27.666] <TB1> INFO: running: pretest
[14:08:27.671] <TB1> INFO: ----------------------------------------------------------------------
[14:08:27.671] <TB1> INFO: PixTestPretest::programROC()
[14:08:27.671] <TB1> INFO: ----------------------------------------------------------------------
[14:08:45.687] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:08:45.687] <TB1> INFO: IA differences per ROC: 18.5 19.3 18.5 16.9 18.5 19.3 17.7 20.1 16.9 17.7 19.3 18.5 18.5 20.1 18.5 19.3
[14:08:45.791] <TB1> INFO: enter test to run
[14:08:45.791] <TB1> INFO: test: pretest no parameter change
[14:08:45.791] <TB1> INFO: running: pretest
[14:08:45.792] <TB1> INFO: ----------------------------------------------------------------------
[14:08:45.792] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:08:45.792] <TB1> INFO: ----------------------------------------------------------------------
[14:09:07.052] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[14:09:07.052] <TB1> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 19.3 19.3 18.5 18.5 18.5 19.3 18.5 18.5 19.3 19.3 19.3 19.3 17.7
[14:09:07.079] <TB1> INFO: enter test to run
[14:09:07.079] <TB1> INFO: test: pretest no parameter change
[14:09:07.079] <TB1> INFO: running: pretest
[14:09:07.079] <TB1> INFO: ----------------------------------------------------------------------
[14:09:07.079] <TB1> INFO: PixTestPretest::findTiming()
[14:09:07.079] <TB1> INFO: ----------------------------------------------------------------------
[14:09:07.079] <TB1> INFO: PixTestCmd::init()

[14:10:34.875] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[14:10:34.875] <TB1> INFO: (success/tries = 100/100), width = 4
[14:10:34.876] <TB1> INFO: enter test to run
[14:10:34.876] <TB1> INFO: test: pretest no parameter change
[14:10:34.876] <TB1> INFO: running: pretest
[14:10:34.878] <TB1> INFO: ----------------------------------------------------------------------
[14:10:34.878] <TB1> INFO: PixTestPretest::findWorkingPixel()
[14:10:34.878] <TB1> INFO: ----------------------------------------------------------------------
[14:10:34.972] <TB1> INFO: Expecting 231680 events.


[14:10:45.119] <TB1> INFO: 231680 events read in total (9591ms).
[14:10:45.122] <TB1> INFO: Test took 10239ms.
[14:10:45.347] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:10:45.407] <TB1> INFO: enter test to run
[14:10:45.407] <TB1> INFO: test: pretest no parameter change
[14:10:45.407] <TB1> INFO: running: pretest
[14:10:45.408] <TB1> INFO: ----------------------------------------------------------------------
[14:10:45.408] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[14:10:45.408] <TB1> INFO: ----------------------------------------------------------------------
[14:10:45.500] <TB1> INFO: Expecting 231680 events.
[14:10:55.752] <TB1> INFO: 231680 events read in total (9695ms).
[14:10:55.756] <TB1> INFO: Test took 10344ms.
[14:10:56.006] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[14:10:56.006] <TB1> INFO: CalDel: 119 114 143 154 123 129 143 156 149 134 137 131 147 155 133 139
[14:10:56.006] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:10:56.047] <TB1> INFO: enter test to run
[14:10:56.047] <TB1> INFO: test: pretest no parameter change
[14:10:56.047] <TB1> INFO: running: pretest
[14:10:56.047] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C0.dat
[14:10:56.047] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C1.dat
[14:10:56.047] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C2.dat
[14:10:56.047] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C3.dat
[14:10:56.047] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C4.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C5.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C6.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C7.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C8.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C9.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C10.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C11.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C12.dat
[14:10:56.048] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C13.dat
[14:10:56.049] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C14.dat
[14:10:56.049] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters_C15.dat
[14:10:56.049] <TB1> INFO: enter test to run
[14:10:56.049] <TB1> INFO: test: fulltest no parameter change
[14:10:56.049] <TB1> INFO: running: fulltest
[14:10:56.049] <TB1> INFO: ######################################################################
[14:10:56.049] <TB1> INFO: PixTestFullTest::doTest()
[14:10:56.049] <TB1> INFO: ######################################################################
[14:10:56.050] <TB1> INFO: ######################################################################
[14:10:56.050] <TB1> INFO: PixTestAlive::doTest()
[14:10:56.050] <TB1> INFO: ######################################################################
[14:10:56.051] <TB1> INFO: ----------------------------------------------------------------------
[14:10:56.051] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:10:56.051] <TB1> INFO: ----------------------------------------------------------------------
[14:10:56.346] <TB1> INFO: Expecting 41600 events.
[14:11:00.158] <TB1> INFO: 41600 events read in total (3256ms).
[14:11:00.159] <TB1> INFO: Test took 4106ms.
[14:11:00.392] <TB1> INFO: PixTestAlive::aliveTest() done
[14:11:00.392] <TB1> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:11:00.394] <TB1> INFO: ----------------------------------------------------------------------
[14:11:00.394] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:11:00.394] <TB1> INFO: ----------------------------------------------------------------------
[14:11:00.670] <TB1> INFO: Expecting 41600 events.
[14:11:03.657] <TB1> INFO: 41600 events read in total (2431ms).
[14:11:03.657] <TB1> INFO: Test took 3261ms.
[14:11:03.658] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:11:03.898] <TB1> INFO: PixTestAlive::maskTest() done
[14:11:03.898] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:11:03.899] <TB1> INFO: ----------------------------------------------------------------------
[14:11:03.899] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:11:03.899] <TB1> INFO: ----------------------------------------------------------------------
[14:11:04.163] <TB1> INFO: Expecting 41600 events.
[14:11:07.926] <TB1> INFO: 41600 events read in total (3207ms).
[14:11:07.926] <TB1> INFO: Test took 4025ms.
[14:11:08.158] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:11:08.158] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:11:08.159] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[14:11:08.159] <TB1> INFO: Decoding statistics:
[14:11:08.159] <TB1> INFO: General information:
[14:11:08.159] <TB1> INFO: 16bit words read: 0
[14:11:08.159] <TB1> INFO: valid events total: 0
[14:11:08.159] <TB1> INFO: empty events: 0
[14:11:08.159] <TB1> INFO: valid events with pixels: 0
[14:11:08.159] <TB1> INFO: valid pixel hits: 0
[14:11:08.159] <TB1> INFO: Event errors: 0
[14:11:08.159] <TB1> INFO: start marker: 0
[14:11:08.159] <TB1> INFO: stop marker: 0
[14:11:08.159] <TB1> INFO: overflow: 0
[14:11:08.159] <TB1> INFO: invalid 5bit words: 0
[14:11:08.159] <TB1> INFO: invalid XOR eye diagram: 0
[14:11:08.159] <TB1> INFO: frame (failed synchr.): 0
[14:11:08.159] <TB1> INFO: idle data (no TBM trl): 0
[14:11:08.159] <TB1> INFO: no data (only TBM hdr): 0
[14:11:08.159] <TB1> INFO: TBM errors: 0
[14:11:08.159] <TB1> INFO: flawed TBM headers: 0
[14:11:08.159] <TB1> INFO: flawed TBM trailers: 0
[14:11:08.159] <TB1> INFO: event ID mismatches: 0
[14:11:08.159] <TB1> INFO: ROC errors: 0
[14:11:08.159] <TB1> INFO: missing ROC header(s): 0
[14:11:08.159] <TB1> INFO: misplaced readback start: 0
[14:11:08.159] <TB1> INFO: Pixel decoding errors: 0
[14:11:08.159] <TB1> INFO: pixel data incomplete: 0
[14:11:08.159] <TB1> INFO: pixel address: 0
[14:11:08.159] <TB1> INFO: pulse height fill bit: 0
[14:11:08.159] <TB1> INFO: buffer corruption: 0
[14:11:08.166] <TB1> INFO: ######################################################################
[14:11:08.166] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:11:08.166] <TB1> INFO: ######################################################################
[14:11:08.168] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:11:08.178] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:11:08.179] <TB1> INFO: run 1 of 1
[14:11:08.451] <TB1> INFO: Expecting 3120000 events.
[14:11:49.565] <TB1> INFO: 876740 events read in total (40557ms).
[14:12:30.020] <TB1> INFO: 1746340 events read in total (81013ms).
[14:13:11.018] <TB1> INFO: 2627755 events read in total (122010ms).
[14:13:33.943] <TB1> INFO: 3120000 events read in total (144935ms).
[14:13:33.976] <TB1> INFO: Test took 145797ms.
[14:13:58.153] <TB1> INFO: PixTestBBMap::doTest() done, duration: 169 seconds
[14:13:58.153] <TB1> INFO: number of dead bumps (per ROC): 9 0 0 0 0 0 0 0 1 0 0 0 0 2 0 2
[14:13:58.153] <TB1> INFO: separation cut (per ROC): 94 87 87 99 92 89 78 93 89 97 99 95 95 94 85 90
[14:13:58.153] <TB1> INFO: Decoding statistics:
[14:13:58.153] <TB1> INFO: General information:
[14:13:58.153] <TB1> INFO: 16bit words read: 0
[14:13:58.153] <TB1> INFO: valid events total: 0
[14:13:58.153] <TB1> INFO: empty events: 0
[14:13:58.153] <TB1> INFO: valid events with pixels: 0
[14:13:58.153] <TB1> INFO: valid pixel hits: 0
[14:13:58.153] <TB1> INFO: Event errors: 0
[14:13:58.153] <TB1> INFO: start marker: 0
[14:13:58.153] <TB1> INFO: stop marker: 0
[14:13:58.153] <TB1> INFO: overflow: 0
[14:13:58.153] <TB1> INFO: invalid 5bit words: 0
[14:13:58.153] <TB1> INFO: invalid XOR eye diagram: 0
[14:13:58.153] <TB1> INFO: frame (failed synchr.): 0
[14:13:58.153] <TB1> INFO: idle data (no TBM trl): 0
[14:13:58.153] <TB1> INFO: no data (only TBM hdr): 0
[14:13:58.153] <TB1> INFO: TBM errors: 0
[14:13:58.153] <TB1> INFO: flawed TBM headers: 0
[14:13:58.153] <TB1> INFO: flawed TBM trailers: 0
[14:13:58.153] <TB1> INFO: event ID mismatches: 0
[14:13:58.153] <TB1> INFO: ROC errors: 0
[14:13:58.153] <TB1> INFO: missing ROC header(s): 0
[14:13:58.153] <TB1> INFO: misplaced readback start: 0
[14:13:58.153] <TB1> INFO: Pixel decoding errors: 0
[14:13:58.153] <TB1> INFO: pixel data incomplete: 0
[14:13:58.153] <TB1> INFO: pixel address: 0
[14:13:58.153] <TB1> INFO: pulse height fill bit: 0
[14:13:58.153] <TB1> INFO: buffer corruption: 0
[14:13:58.224] <TB1> INFO: ######################################################################
[14:13:58.224] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:13:58.224] <TB1> INFO: ######################################################################
[14:13:58.224] <TB1> INFO: ----------------------------------------------------------------------
[14:13:58.224] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:13:58.224] <TB1> INFO: ----------------------------------------------------------------------
[14:13:58.224] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:13:58.232] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[14:13:58.232] <TB1> INFO: run 1 of 1
[14:13:58.495] <TB1> INFO: Expecting 26208000 events.
[14:14:32.908] <TB1> INFO: 938500 events read in total (33857ms).
[14:15:06.406] <TB1> INFO: 1862350 events read in total (67355ms).
[14:15:40.034] <TB1> INFO: 2783450 events read in total (100983ms).
[14:16:13.075] <TB1> INFO: 3703100 events read in total (134024ms).
[14:16:46.379] <TB1> INFO: 4622500 events read in total (167328ms).
[14:17:19.710] <TB1> INFO: 5540450 events read in total (200659ms).
[14:17:53.006] <TB1> INFO: 6456300 events read in total (233955ms).
[14:18:25.799] <TB1> INFO: 7369350 events read in total (266748ms).
[14:18:58.877] <TB1> INFO: 8283050 events read in total (299826ms).
[14:19:32.708] <TB1> INFO: 9201400 events read in total (333657ms).
[14:20:06.051] <TB1> INFO: 10112600 events read in total (367000ms).
[14:20:39.354] <TB1> INFO: 11024050 events read in total (400303ms).
[14:21:12.382] <TB1> INFO: 11936400 events read in total (433331ms).
[14:21:45.351] <TB1> INFO: 12845700 events read in total (466300ms).
[14:22:18.810] <TB1> INFO: 13749400 events read in total (499759ms).
[14:22:52.152] <TB1> INFO: 14647700 events read in total (533101ms).
[14:23:25.323] <TB1> INFO: 15546050 events read in total (566272ms).
[14:23:58.030] <TB1> INFO: 16441300 events read in total (598979ms).
[14:24:31.317] <TB1> INFO: 17337200 events read in total (632266ms).
[14:25:04.288] <TB1> INFO: 18233050 events read in total (665237ms).
[14:25:37.381] <TB1> INFO: 19128450 events read in total (698330ms).
[14:26:10.331] <TB1> INFO: 20021400 events read in total (731280ms).
[14:26:43.191] <TB1> INFO: 20917650 events read in total (764140ms).
[14:27:16.566] <TB1> INFO: 21814250 events read in total (797515ms).
[14:27:49.495] <TB1> INFO: 22706000 events read in total (830444ms).
[14:28:22.423] <TB1> INFO: 23599800 events read in total (863372ms).
[14:28:55.391] <TB1> INFO: 24497550 events read in total (896340ms).
[14:29:28.195] <TB1> INFO: 25394450 events read in total (929144ms).
[14:29:57.941] <TB1> INFO: 26208000 events read in total (958890ms).
[14:29:57.966] <TB1> INFO: Test took 959733ms.
[14:29:58.197] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:29:59.610] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:01.367] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:03.051] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:04.793] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:06.373] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:07.892] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:09.390] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:11.465] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:13.561] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:15.484] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:16.920] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:18.620] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:20.061] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:21.491] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:22.925] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[14:30:24.404] <TB1> INFO: PixTestScurves::scurves() done
[14:30:24.404] <TB1> INFO: Vcal mean: 109.53 92.06 90.33 102.25 97.08 91.52 87.71 100.30 91.09 98.89 97.34 97.24 95.57 91.80 91.36 90.12
[14:30:24.404] <TB1> INFO: Vcal RMS: 5.23 5.04 5.34 5.02 5.03 5.27 5.29 5.26 5.15 5.89 5.53 5.53 5.82 4.97 4.86 4.88
[14:30:24.404] <TB1> INFO: PixTestScurves::fullTest() done, duration: 986 seconds
[14:30:24.404] <TB1> INFO: Decoding statistics:
[14:30:24.404] <TB1> INFO: General information:
[14:30:24.405] <TB1> INFO: 16bit words read: 0
[14:30:24.405] <TB1> INFO: valid events total: 0
[14:30:24.405] <TB1> INFO: empty events: 0
[14:30:24.405] <TB1> INFO: valid events with pixels: 0
[14:30:24.405] <TB1> INFO: valid pixel hits: 0
[14:30:24.405] <TB1> INFO: Event errors: 0
[14:30:24.405] <TB1> INFO: start marker: 0
[14:30:24.405] <TB1> INFO: stop marker: 0
[14:30:24.405] <TB1> INFO: overflow: 0
[14:30:24.405] <TB1> INFO: invalid 5bit words: 0
[14:30:24.405] <TB1> INFO: invalid XOR eye diagram: 0
[14:30:24.405] <TB1> INFO: frame (failed synchr.): 0
[14:30:24.405] <TB1> INFO: idle data (no TBM trl): 0
[14:30:24.405] <TB1> INFO: no data (only TBM hdr): 0
[14:30:24.405] <TB1> INFO: TBM errors: 0
[14:30:24.405] <TB1> INFO: flawed TBM headers: 0
[14:30:24.405] <TB1> INFO: flawed TBM trailers: 0
[14:30:24.405] <TB1> INFO: event ID mismatches: 0
[14:30:24.405] <TB1> INFO: ROC errors: 0
[14:30:24.405] <TB1> INFO: missing ROC header(s): 0
[14:30:24.405] <TB1> INFO: misplaced readback start: 0
[14:30:24.405] <TB1> INFO: Pixel decoding errors: 0
[14:30:24.405] <TB1> INFO: pixel data incomplete: 0
[14:30:24.405] <TB1> INFO: pixel address: 0
[14:30:24.405] <TB1> INFO: pulse height fill bit: 0
[14:30:24.405] <TB1> INFO: buffer corruption: 0
[14:30:24.476] <TB1> INFO: ######################################################################
[14:30:24.476] <TB1> INFO: PixTestTrim::doTest()
[14:30:24.476] <TB1> INFO: ######################################################################
[14:30:24.477] <TB1> INFO: ----------------------------------------------------------------------
[14:30:24.477] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[14:30:24.477] <TB1> INFO: ----------------------------------------------------------------------
[14:30:24.562] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[14:30:24.562] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:30:24.570] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:30:24.570] <TB1> INFO: run 1 of 1
[14:30:24.849] <TB1> INFO: Expecting 6281600 events.
[14:31:04.862] <TB1> INFO: 1092470 events read in total (39457ms).
[14:31:44.475] <TB1> INFO: 2178850 events read in total (79070ms).
[14:32:23.915] <TB1> INFO: 3261040 events read in total (118510ms).
[14:33:03.287] <TB1> INFO: 4349350 events read in total (157882ms).
[14:33:42.962] <TB1> INFO: 5440810 events read in total (197557ms).
[14:34:13.431] <TB1> INFO: 6281600 events read in total (228026ms).
[14:34:13.461] <TB1> INFO: Test took 228891ms.
[14:34:32.927] <TB1> INFO: ROC 0 VthrComp = 106
[14:34:32.927] <TB1> INFO: ROC 1 VthrComp = 95
[14:34:32.928] <TB1> INFO: ROC 2 VthrComp = 89
[14:34:32.928] <TB1> INFO: ROC 3 VthrComp = 101
[14:34:32.928] <TB1> INFO: ROC 4 VthrComp = 101
[14:34:32.928] <TB1> INFO: ROC 5 VthrComp = 90
[14:34:32.928] <TB1> INFO: ROC 6 VthrComp = 87
[14:34:32.928] <TB1> INFO: ROC 7 VthrComp = 99
[14:34:32.928] <TB1> INFO: ROC 8 VthrComp = 89
[14:34:32.928] <TB1> INFO: ROC 9 VthrComp = 98
[14:34:32.928] <TB1> INFO: ROC 10 VthrComp = 97
[14:34:32.928] <TB1> INFO: ROC 11 VthrComp = 98
[14:34:32.929] <TB1> INFO: ROC 12 VthrComp = 95
[14:34:32.929] <TB1> INFO: ROC 13 VthrComp = 94
[14:34:32.929] <TB1> INFO: ROC 14 VthrComp = 92
[14:34:32.929] <TB1> INFO: ROC 15 VthrComp = 91
[14:34:32.929] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[14:34:32.929] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:34:32.937] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:34:32.937] <TB1> INFO: run 1 of 1
[14:34:33.200] <TB1> INFO: Expecting 6281600 events.
[14:35:08.607] <TB1> INFO: 756990 events read in total (34850ms).
[14:35:43.094] <TB1> INFO: 1512230 events read in total (69337ms).
[14:36:18.180] <TB1> INFO: 2267190 events read in total (104423ms).
[14:36:52.811] <TB1> INFO: 3022940 events read in total (139054ms).
[14:37:27.600] <TB1> INFO: 3773070 events read in total (173843ms).
[14:38:02.230] <TB1> INFO: 4519290 events read in total (208473ms).
[14:38:36.513] <TB1> INFO: 5263310 events read in total (242756ms).
[14:39:10.919] <TB1> INFO: 6006560 events read in total (277162ms).
[14:39:24.079] <TB1> INFO: 6281600 events read in total (290322ms).
[14:39:24.123] <TB1> INFO: Test took 291186ms.
[14:39:49.711] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 62.7508 for pixel 3/34 mean/min/max = 48.7179/34.5351/62.9007
[14:39:49.711] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 55.8381 for pixel 20/41 mean/min/max = 44.0983/32.3535/55.8431
[14:39:49.711] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.6352 for pixel 14/6 mean/min/max = 46.5886/33.4819/59.6953
[14:39:49.711] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.3989 for pixel 17/5 mean/min/max = 45.2743/32.1481/58.4004
[14:39:49.712] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 55.4669 for pixel 22/79 mean/min/max = 43.7237/31.6064/55.841
[14:39:49.712] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.922 for pixel 16/79 mean/min/max = 46.9232/33.9235/59.923
[14:39:49.712] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.5687 for pixel 13/79 mean/min/max = 45.5198/32.2496/58.7901
[14:39:49.712] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.9989 for pixel 17/73 mean/min/max = 45.1304/32.2011/58.0598
[14:39:49.712] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.9816 for pixel 1/11 mean/min/max = 47.1742/34.3439/60.0044
[14:39:49.713] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.6113 for pixel 0/11 mean/min/max = 45.4821/31.2795/59.6848
[14:39:49.713] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.7385 for pixel 5/68 mean/min/max = 44.6514/31.4039/57.8988
[14:39:49.713] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.6621 for pixel 3/79 mean/min/max = 44.8636/31.6769/58.0503
[14:39:49.713] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 60.6186 for pixel 17/70 mean/min/max = 46.3975/32.112/60.6829
[14:39:49.714] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.8297 for pixel 5/76 mean/min/max = 45.309/32.7294/57.8887
[14:39:49.714] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.3683 for pixel 2/69 mean/min/max = 45.0255/33.6457/56.4053
[14:39:49.714] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.5046 for pixel 15/66 mean/min/max = 45.5448/33.4683/57.6213
[14:39:49.714] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:39:49.802] <TB1> INFO: Expecting 514560 events.
[14:40:02.599] <TB1> INFO: 514560 events read in total (12241ms).
[14:40:02.604] <TB1> INFO: Expecting 514560 events.
[14:40:15.399] <TB1> INFO: 514560 events read in total (12372ms).
[14:40:15.406] <TB1> INFO: Expecting 514560 events.
[14:40:28.247] <TB1> INFO: 514560 events read in total (12423ms).
[14:40:28.256] <TB1> INFO: Expecting 514560 events.
[14:40:41.201] <TB1> INFO: 514560 events read in total (12528ms).
[14:40:41.212] <TB1> INFO: Expecting 514560 events.
[14:40:54.034] <TB1> INFO: 514560 events read in total (12409ms).
[14:40:54.046] <TB1> INFO: Expecting 514560 events.
[14:41:06.906] <TB1> INFO: 514560 events read in total (12452ms).
[14:41:06.920] <TB1> INFO: Expecting 514560 events.
[14:41:19.669] <TB1> INFO: 514560 events read in total (12335ms).
[14:41:19.685] <TB1> INFO: Expecting 514560 events.
[14:41:32.583] <TB1> INFO: 514560 events read in total (12487ms).
[14:41:32.602] <TB1> INFO: Expecting 514560 events.
[14:41:45.465] <TB1> INFO: 514560 events read in total (12455ms).
[14:41:45.485] <TB1> INFO: Expecting 514560 events.
[14:41:58.275] <TB1> INFO: 514560 events read in total (12378ms).
[14:41:58.297] <TB1> INFO: Expecting 514560 events.
[14:42:11.062] <TB1> INFO: 514560 events read in total (12356ms).
[14:42:11.085] <TB1> INFO: Expecting 514560 events.
[14:42:23.877] <TB1> INFO: 514560 events read in total (12390ms).
[14:42:23.902] <TB1> INFO: Expecting 514560 events.
[14:42:36.744] <TB1> INFO: 514560 events read in total (12436ms).
[14:42:36.770] <TB1> INFO: Expecting 514560 events.
[14:42:49.641] <TB1> INFO: 514560 events read in total (12469ms).
[14:42:49.671] <TB1> INFO: Expecting 514560 events.
[14:43:02.483] <TB1> INFO: 514560 events read in total (12410ms).
[14:43:02.515] <TB1> INFO: Expecting 514560 events.
[14:43:15.374] <TB1> INFO: 514560 events read in total (12458ms).
[14:43:15.408] <TB1> INFO: Test took 205694ms.
[14:43:16.385] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:43:16.395] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:43:16.395] <TB1> INFO: run 1 of 1
[14:43:16.667] <TB1> INFO: Expecting 6281600 events.
[14:43:52.160] <TB1> INFO: 742550 events read in total (34937ms).
[14:44:26.636] <TB1> INFO: 1483980 events read in total (69413ms).
[14:45:00.730] <TB1> INFO: 2225100 events read in total (103507ms).
[14:45:35.030] <TB1> INFO: 2967220 events read in total (137807ms).
[14:46:09.583] <TB1> INFO: 3703980 events read in total (172360ms).
[14:46:43.552] <TB1> INFO: 4436450 events read in total (206329ms).
[14:47:18.183] <TB1> INFO: 5166360 events read in total (240960ms).
[14:47:52.707] <TB1> INFO: 5895820 events read in total (275484ms).
[14:48:11.158] <TB1> INFO: 6281600 events read in total (293935ms).
[14:48:11.203] <TB1> INFO: Test took 294808ms.
[14:48:36.277] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.079334 .. 255.000000
[14:48:36.352] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[14:48:36.360] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:48:36.360] <TB1> INFO: run 1 of 1
[14:48:36.619] <TB1> INFO: Expecting 10649600 events.
[14:49:11.213] <TB1> INFO: 712540 events read in total (34038ms).
[14:49:45.023] <TB1> INFO: 1425390 events read in total (67848ms).
[14:50:19.489] <TB1> INFO: 2138080 events read in total (102314ms).
[14:50:53.604] <TB1> INFO: 2850850 events read in total (136429ms).
[14:51:28.064] <TB1> INFO: 3563850 events read in total (170889ms).
[14:52:02.522] <TB1> INFO: 4276800 events read in total (205347ms).
[14:52:37.082] <TB1> INFO: 4990110 events read in total (239907ms).
[14:53:11.454] <TB1> INFO: 5703110 events read in total (274279ms).
[14:53:45.629] <TB1> INFO: 6415760 events read in total (308454ms).
[14:54:20.203] <TB1> INFO: 7127630 events read in total (343028ms).
[14:54:54.482] <TB1> INFO: 7839260 events read in total (377307ms).
[14:55:28.777] <TB1> INFO: 8549930 events read in total (411602ms).
[14:56:03.087] <TB1> INFO: 9260870 events read in total (445912ms).
[14:56:37.897] <TB1> INFO: 9971720 events read in total (480722ms).
[14:57:10.584] <TB1> INFO: 10649600 events read in total (513409ms).
[14:57:10.683] <TB1> INFO: Test took 514323ms.
[14:57:41.746] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 1.024757 .. 45.999716
[14:57:41.869] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:57:41.880] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:57:41.880] <TB1> INFO: run 1 of 1
[14:57:42.227] <TB1> INFO: Expecting 2288000 events.
[14:58:20.031] <TB1> INFO: 959110 events read in total (37248ms).
[14:58:58.182] <TB1> INFO: 1916670 events read in total (75399ms).
[14:59:13.109] <TB1> INFO: 2288000 events read in total (90326ms).
[14:59:13.120] <TB1> INFO: Test took 91239ms.
[14:59:27.711] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 0.787107 .. 41.795374
[14:59:27.787] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 0 .. 51 (-1/-1) hits flags = 528 (plus default)
[14:59:27.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:59:27.795] <TB1> INFO: run 1 of 1
[14:59:28.065] <TB1> INFO: Expecting 2163200 events.
[15:00:06.990] <TB1> INFO: 1001000 events read in total (38368ms).
[15:00:45.719] <TB1> INFO: 2000530 events read in total (77097ms).
[15:00:52.251] <TB1> INFO: 2163200 events read in total (83629ms).
[15:00:52.261] <TB1> INFO: Test took 84466ms.
[15:01:05.854] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 0.787107 .. 41.416028
[15:01:05.931] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 51 (-1/-1) hits flags = 528 (plus default)
[15:01:05.939] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:01:05.939] <TB1> INFO: run 1 of 1
[15:01:06.205] <TB1> INFO: Expecting 2163200 events.
[15:01:45.503] <TB1> INFO: 1001220 events read in total (38742ms).
[15:02:24.533] <TB1> INFO: 2002180 events read in total (77772ms).
[15:02:31.057] <TB1> INFO: 2163200 events read in total (84296ms).
[15:02:31.066] <TB1> INFO: Test took 85127ms.
[15:02:44.275] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:02:44.275] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:02:44.283] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:02:44.283] <TB1> INFO: run 1 of 1
[15:02:44.550] <TB1> INFO: Expecting 1705600 events.
[15:03:21.965] <TB1> INFO: 878500 events read in total (36859ms).
[15:03:57.097] <TB1> INFO: 1705600 events read in total (71991ms).
[15:03:57.111] <TB1> INFO: Test took 72828ms.
[15:04:11.990] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:04:11.991] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:04:11.992] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:04:11.993] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:04:12.001] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:04:12.007] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:04:12.014] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:04:12.020] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:04:12.027] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:04:12.033] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:04:12.044] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:04:12.055] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:04:12.067] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:04:12.078] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:04:12.090] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:04:12.101] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:04:12.112] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:04:12.122] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:04:12.129] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:04:12.136] <TB1> INFO: PixTestTrim::trimTest() done
[15:04:12.136] <TB1> INFO: vtrim: 121 96 105 100 91 104 98 103 95 96 94 91 112 103 90 93
[15:04:12.136] <TB1> INFO: vthrcomp: 106 95 89 101 101 90 87 99 89 98 97 98 95 94 92 91
[15:04:12.136] <TB1> INFO: vcal mean: 34.94 34.92 34.99 34.88 34.95 34.98 34.93 34.97 34.95 34.96 34.87 34.96 34.97 35.00 35.04 34.99
[15:04:12.136] <TB1> INFO: vcal RMS: 0.87 0.98 0.88 1.22 0.86 0.86 0.90 0.89 0.84 0.87 0.87 0.85 0.90 0.85 0.82 0.83
[15:04:12.136] <TB1> INFO: bits mean: 8.51 10.00 9.30 9.76 10.14 8.95 9.40 9.74 8.82 9.26 9.77 9.35 9.55 9.54 9.43 9.42
[15:04:12.136] <TB1> INFO: bits RMS: 2.51 2.50 2.53 2.52 2.58 2.60 2.75 2.64 2.60 3.00 2.77 2.91 2.61 2.60 2.59 2.57
[15:04:12.142] <TB1> INFO: ----------------------------------------------------------------------
[15:04:12.142] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:04:12.142] <TB1> INFO: ----------------------------------------------------------------------
[15:04:12.144] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:04:12.153] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:04:12.153] <TB1> INFO: run 1 of 1
[15:04:12.441] <TB1> INFO: Expecting 4160000 events.
[15:04:54.887] <TB1> INFO: 962190 events read in total (41889ms).
[15:05:37.087] <TB1> INFO: 1914845 events read in total (84089ms).
[15:06:19.153] <TB1> INFO: 2856605 events read in total (126155ms).
[15:07:00.821] <TB1> INFO: 3796360 events read in total (167823ms).
[15:07:17.180] <TB1> INFO: 4160000 events read in total (184183ms).
[15:07:17.213] <TB1> INFO: Test took 185060ms.
[15:07:45.691] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[15:07:45.699] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:07:45.699] <TB1> INFO: run 1 of 1
[15:07:45.959] <TB1> INFO: Expecting 3681600 events.
[15:08:29.222] <TB1> INFO: 976225 events read in total (42707ms).
[15:09:12.105] <TB1> INFO: 1943430 events read in total (85590ms).
[15:09:54.196] <TB1> INFO: 2900500 events read in total (127681ms).
[15:10:28.330] <TB1> INFO: 3681600 events read in total (161815ms).
[15:10:28.361] <TB1> INFO: Test took 162662ms.
[15:10:55.622] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[15:10:55.630] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:10:55.630] <TB1> INFO: run 1 of 1
[15:10:55.909] <TB1> INFO: Expecting 3452800 events.
[15:11:39.952] <TB1> INFO: 1007390 events read in total (43487ms).
[15:12:22.967] <TB1> INFO: 2002340 events read in total (86502ms).
[15:13:05.843] <TB1> INFO: 2987180 events read in total (129378ms).
[15:13:26.363] <TB1> INFO: 3452800 events read in total (149898ms).
[15:13:26.392] <TB1> INFO: Test took 150762ms.
[15:13:50.740] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[15:13:50.749] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:13:50.749] <TB1> INFO: run 1 of 1
[15:13:51.017] <TB1> INFO: Expecting 3452800 events.
[15:14:35.034] <TB1> INFO: 1007020 events read in total (43461ms).
[15:15:17.702] <TB1> INFO: 2001650 events read in total (86129ms).
[15:16:00.240] <TB1> INFO: 2986210 events read in total (128667ms).
[15:16:20.387] <TB1> INFO: 3452800 events read in total (148814ms).
[15:16:20.421] <TB1> INFO: Test took 149672ms.
[15:16:46.069] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[15:16:46.077] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:16:46.077] <TB1> INFO: run 1 of 1
[15:16:46.336] <TB1> INFO: Expecting 3452800 events.
[15:17:29.305] <TB1> INFO: 1006670 events read in total (42412ms).
[15:18:12.084] <TB1> INFO: 2000980 events read in total (85191ms).
[15:18:54.882] <TB1> INFO: 2985375 events read in total (127989ms).
[15:19:15.060] <TB1> INFO: 3452800 events read in total (148167ms).
[15:19:15.086] <TB1> INFO: Test took 149009ms.
[15:19:42.011] <TB1> INFO: PixTestTrim::trimBitTest() done
[15:19:42.012] <TB1> INFO: PixTestTrim::doTest() done, duration: 2957 seconds
[15:19:42.012] <TB1> INFO: Decoding statistics:
[15:19:42.012] <TB1> INFO: General information:
[15:19:42.013] <TB1> INFO: 16bit words read: 0
[15:19:42.013] <TB1> INFO: valid events total: 0
[15:19:42.013] <TB1> INFO: empty events: 0
[15:19:42.013] <TB1> INFO: valid events with pixels: 0
[15:19:42.013] <TB1> INFO: valid pixel hits: 0
[15:19:42.013] <TB1> INFO: Event errors: 0
[15:19:42.013] <TB1> INFO: start marker: 0
[15:19:42.013] <TB1> INFO: stop marker: 0
[15:19:42.013] <TB1> INFO: overflow: 0
[15:19:42.013] <TB1> INFO: invalid 5bit words: 0
[15:19:42.013] <TB1> INFO: invalid XOR eye diagram: 0
[15:19:42.013] <TB1> INFO: frame (failed synchr.): 0
[15:19:42.013] <TB1> INFO: idle data (no TBM trl): 0
[15:19:42.013] <TB1> INFO: no data (only TBM hdr): 0
[15:19:42.013] <TB1> INFO: TBM errors: 0
[15:19:42.013] <TB1> INFO: flawed TBM headers: 0
[15:19:42.013] <TB1> INFO: flawed TBM trailers: 0
[15:19:42.013] <TB1> INFO: event ID mismatches: 0
[15:19:42.013] <TB1> INFO: ROC errors: 0
[15:19:42.013] <TB1> INFO: missing ROC header(s): 0
[15:19:42.013] <TB1> INFO: misplaced readback start: 0
[15:19:42.013] <TB1> INFO: Pixel decoding errors: 0
[15:19:42.013] <TB1> INFO: pixel data incomplete: 0
[15:19:42.013] <TB1> INFO: pixel address: 0
[15:19:42.013] <TB1> INFO: pulse height fill bit: 0
[15:19:42.013] <TB1> INFO: buffer corruption: 0
[15:19:42.729] <TB1> INFO: ######################################################################
[15:19:42.729] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:19:42.729] <TB1> INFO: ######################################################################
[15:19:42.988] <TB1> INFO: Expecting 41600 events.
[15:19:46.748] <TB1> INFO: 41600 events read in total (3204ms).
[15:19:46.749] <TB1> INFO: Test took 4019ms.
[15:19:47.218] <TB1> INFO: Expecting 41600 events.
[15:19:51.019] <TB1> INFO: 41600 events read in total (3245ms).
[15:19:51.019] <TB1> INFO: Test took 4068ms.
[15:19:51.303] <TB1> INFO: Expecting 41600 events.
[15:19:55.132] <TB1> INFO: 41600 events read in total (3273ms).
[15:19:55.132] <TB1> INFO: Test took 4091ms.
[15:19:55.388] <TB1> INFO: Expecting 2560 events.
[15:19:56.242] <TB1> INFO: 2560 events read in total (298ms).
[15:19:56.242] <TB1> INFO: Test took 1097ms.
[15:19:56.548] <TB1> INFO: Expecting 2560 events.
[15:19:57.400] <TB1> INFO: 2560 events read in total (296ms).
[15:19:57.400] <TB1> INFO: Test took 1157ms.
[15:19:57.705] <TB1> INFO: Expecting 2560 events.
[15:19:58.558] <TB1> INFO: 2560 events read in total (296ms).
[15:19:58.559] <TB1> INFO: Test took 1158ms.
[15:19:58.863] <TB1> INFO: Expecting 2560 events.
[15:19:59.718] <TB1> INFO: 2560 events read in total (299ms).
[15:19:59.718] <TB1> INFO: Test took 1159ms.
[15:20:00.022] <TB1> INFO: Expecting 2560 events.
[15:20:00.880] <TB1> INFO: 2560 events read in total (301ms).
[15:20:00.880] <TB1> INFO: Test took 1161ms.
[15:20:01.186] <TB1> INFO: Expecting 2560 events.
[15:20:02.038] <TB1> INFO: 2560 events read in total (296ms).
[15:20:02.038] <TB1> INFO: Test took 1157ms.
[15:20:02.343] <TB1> INFO: Expecting 2560 events.
[15:20:03.196] <TB1> INFO: 2560 events read in total (297ms).
[15:20:03.197] <TB1> INFO: Test took 1158ms.
[15:20:03.501] <TB1> INFO: Expecting 2560 events.
[15:20:04.355] <TB1> INFO: 2560 events read in total (298ms).
[15:20:04.356] <TB1> INFO: Test took 1159ms.
[15:20:04.660] <TB1> INFO: Expecting 2560 events.
[15:20:05.515] <TB1> INFO: 2560 events read in total (299ms).
[15:20:05.515] <TB1> INFO: Test took 1159ms.
[15:20:05.820] <TB1> INFO: Expecting 2560 events.
[15:20:06.677] <TB1> INFO: 2560 events read in total (301ms).
[15:20:06.677] <TB1> INFO: Test took 1162ms.
[15:20:06.981] <TB1> INFO: Expecting 2560 events.
[15:20:07.834] <TB1> INFO: 2560 events read in total (297ms).
[15:20:07.834] <TB1> INFO: Test took 1157ms.
[15:20:08.139] <TB1> INFO: Expecting 2560 events.
[15:20:08.992] <TB1> INFO: 2560 events read in total (297ms).
[15:20:08.992] <TB1> INFO: Test took 1158ms.
[15:20:09.297] <TB1> INFO: Expecting 2560 events.
[15:20:10.152] <TB1> INFO: 2560 events read in total (299ms).
[15:20:10.152] <TB1> INFO: Test took 1159ms.
[15:20:10.457] <TB1> INFO: Expecting 2560 events.
[15:20:11.310] <TB1> INFO: 2560 events read in total (297ms).
[15:20:11.311] <TB1> INFO: Test took 1159ms.
[15:20:11.616] <TB1> INFO: Expecting 2560 events.
[15:20:12.471] <TB1> INFO: 2560 events read in total (299ms).
[15:20:12.471] <TB1> INFO: Test took 1160ms.
[15:20:12.776] <TB1> INFO: Expecting 2560 events.
[15:20:13.631] <TB1> INFO: 2560 events read in total (299ms).
[15:20:13.631] <TB1> INFO: Test took 1160ms.
[15:20:13.634] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:20:13.937] <TB1> INFO: Expecting 655360 events.
[15:20:30.515] <TB1> INFO: 655360 events read in total (16022ms).
[15:20:30.523] <TB1> INFO: Expecting 655360 events.
[15:20:47.172] <TB1> INFO: 655360 events read in total (16247ms).
[15:20:47.183] <TB1> INFO: Expecting 655360 events.
[15:21:03.824] <TB1> INFO: 655360 events read in total (16239ms).
[15:21:03.840] <TB1> INFO: Expecting 655360 events.
[15:21:20.338] <TB1> INFO: 655360 events read in total (16097ms).
[15:21:20.360] <TB1> INFO: Expecting 655360 events.
[15:21:36.929] <TB1> INFO: 655360 events read in total (16167ms).
[15:21:36.951] <TB1> INFO: Expecting 655360 events.
[15:21:53.696] <TB1> INFO: 655360 events read in total (16343ms).
[15:21:53.725] <TB1> INFO: Expecting 655360 events.
[15:22:10.283] <TB1> INFO: 655360 events read in total (16156ms).
[15:22:10.312] <TB1> INFO: Expecting 655360 events.
[15:22:26.965] <TB1> INFO: 655360 events read in total (16251ms).
[15:22:27.000] <TB1> INFO: Expecting 655360 events.
[15:22:43.659] <TB1> INFO: 655360 events read in total (16258ms).
[15:22:43.711] <TB1> INFO: Expecting 655360 events.
[15:23:00.234] <TB1> INFO: 655360 events read in total (16121ms).
[15:23:00.274] <TB1> INFO: Expecting 655360 events.
[15:23:17.014] <TB1> INFO: 655360 events read in total (16339ms).
[15:23:17.058] <TB1> INFO: Expecting 655360 events.
[15:23:33.970] <TB1> INFO: 655360 events read in total (16510ms).
[15:23:34.023] <TB1> INFO: Expecting 655360 events.
[15:23:50.731] <TB1> INFO: 655360 events read in total (16306ms).
[15:23:50.781] <TB1> INFO: Expecting 655360 events.
[15:24:07.471] <TB1> INFO: 655360 events read in total (16288ms).
[15:24:07.524] <TB1> INFO: Expecting 655360 events.
[15:24:24.203] <TB1> INFO: 655360 events read in total (16277ms).
[15:24:24.267] <TB1> INFO: Expecting 655360 events.
[15:24:41.020] <TB1> INFO: 655360 events read in total (16352ms).
[15:24:41.081] <TB1> INFO: Test took 267447ms.
[15:24:41.162] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:24:41.392] <TB1> INFO: Expecting 655360 events.
[15:24:58.232] <TB1> INFO: 655360 events read in total (16285ms).
[15:24:58.241] <TB1> INFO: Expecting 655360 events.
[15:25:14.924] <TB1> INFO: 655360 events read in total (16281ms).
[15:25:14.936] <TB1> INFO: Expecting 655360 events.
[15:25:31.368] <TB1> INFO: 655360 events read in total (16030ms).
[15:25:31.384] <TB1> INFO: Expecting 655360 events.
[15:25:48.027] <TB1> INFO: 655360 events read in total (16242ms).
[15:25:48.046] <TB1> INFO: Expecting 655360 events.
[15:26:04.773] <TB1> INFO: 655360 events read in total (16325ms).
[15:26:04.799] <TB1> INFO: Expecting 655360 events.
[15:26:21.554] <TB1> INFO: 655360 events read in total (16354ms).
[15:26:21.583] <TB1> INFO: Expecting 655360 events.
[15:26:38.136] <TB1> INFO: 655360 events read in total (16151ms).
[15:26:38.177] <TB1> INFO: Expecting 655360 events.
[15:26:54.716] <TB1> INFO: 655360 events read in total (16138ms).
[15:26:54.754] <TB1> INFO: Expecting 655360 events.
[15:27:11.433] <TB1> INFO: 655360 events read in total (16278ms).
[15:27:11.468] <TB1> INFO: Expecting 655360 events.
[15:27:28.085] <TB1> INFO: 655360 events read in total (16215ms).
[15:27:28.125] <TB1> INFO: Expecting 655360 events.
[15:27:44.755] <TB1> INFO: 655360 events read in total (16228ms).
[15:27:44.797] <TB1> INFO: Expecting 655360 events.
[15:28:01.570] <TB1> INFO: 655360 events read in total (16371ms).
[15:28:01.617] <TB1> INFO: Expecting 655360 events.
[15:28:18.256] <TB1> INFO: 655360 events read in total (16238ms).
[15:28:18.305] <TB1> INFO: Expecting 655360 events.
[15:28:35.039] <TB1> INFO: 655360 events read in total (16333ms).
[15:28:35.095] <TB1> INFO: Expecting 655360 events.
[15:28:51.909] <TB1> INFO: 655360 events read in total (16413ms).
[15:28:51.965] <TB1> INFO: Expecting 655360 events.
[15:29:08.710] <TB1> INFO: 655360 events read in total (16343ms).
[15:29:08.774] <TB1> INFO: Test took 267612ms.
[15:29:08.977] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:08.984] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:08.991] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:08.998] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.005] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.013] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[15:29:09.020] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[15:29:09.030] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[15:29:09.038] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[15:29:09.045] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[15:29:09.052] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[15:29:09.059] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[15:29:09.066] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.073] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.081] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.088] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.095] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.102] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.109] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.116] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.123] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.131] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.138] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[15:29:09.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:29:09.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:29:09.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:29:09.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:29:09.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:29:09.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:29:09.175] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:29:09.175] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:29:09.175] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:29:09.176] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:29:09.176] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:29:09.176] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:29:09.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:29:09.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:29:09.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:29:09.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:29:09.456] <TB1> INFO: Expecting 41600 events.
[15:29:12.984] <TB1> INFO: 41600 events read in total (2972ms).
[15:29:12.985] <TB1> INFO: Test took 3804ms.
[15:29:13.423] <TB1> INFO: Expecting 41600 events.
[15:29:16.886] <TB1> INFO: 41600 events read in total (2907ms).
[15:29:16.887] <TB1> INFO: Test took 3728ms.
[15:29:17.334] <TB1> INFO: Expecting 41600 events.
[15:29:20.801] <TB1> INFO: 41600 events read in total (2911ms).
[15:29:20.801] <TB1> INFO: Test took 3740ms.
[15:29:20.981] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:21.069] <TB1> INFO: Expecting 2560 events.
[15:29:21.928] <TB1> INFO: 2560 events read in total (303ms).
[15:29:21.929] <TB1> INFO: Test took 948ms.
[15:29:21.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:22.234] <TB1> INFO: Expecting 2560 events.
[15:29:23.088] <TB1> INFO: 2560 events read in total (298ms).
[15:29:23.088] <TB1> INFO: Test took 1157ms.
[15:29:23.089] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:23.394] <TB1> INFO: Expecting 2560 events.
[15:29:24.247] <TB1> INFO: 2560 events read in total (297ms).
[15:29:24.248] <TB1> INFO: Test took 1159ms.
[15:29:24.249] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:24.553] <TB1> INFO: Expecting 2560 events.
[15:29:25.406] <TB1> INFO: 2560 events read in total (297ms).
[15:29:25.407] <TB1> INFO: Test took 1158ms.
[15:29:25.408] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:25.712] <TB1> INFO: Expecting 2560 events.
[15:29:26.571] <TB1> INFO: 2560 events read in total (303ms).
[15:29:26.571] <TB1> INFO: Test took 1163ms.
[15:29:26.574] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:26.877] <TB1> INFO: Expecting 2560 events.
[15:29:27.735] <TB1> INFO: 2560 events read in total (302ms).
[15:29:27.735] <TB1> INFO: Test took 1161ms.
[15:29:27.738] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:28.041] <TB1> INFO: Expecting 2560 events.
[15:29:28.897] <TB1> INFO: 2560 events read in total (300ms).
[15:29:28.898] <TB1> INFO: Test took 1160ms.
[15:29:28.900] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:29.204] <TB1> INFO: Expecting 2560 events.
[15:29:30.059] <TB1> INFO: 2560 events read in total (299ms).
[15:29:30.059] <TB1> INFO: Test took 1160ms.
[15:29:30.062] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:30.364] <TB1> INFO: Expecting 2560 events.
[15:29:31.220] <TB1> INFO: 2560 events read in total (300ms).
[15:29:31.220] <TB1> INFO: Test took 1158ms.
[15:29:31.221] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:31.525] <TB1> INFO: Expecting 2560 events.
[15:29:32.380] <TB1> INFO: 2560 events read in total (298ms).
[15:29:32.380] <TB1> INFO: Test took 1159ms.
[15:29:32.382] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:32.685] <TB1> INFO: Expecting 2560 events.
[15:29:33.538] <TB1> INFO: 2560 events read in total (297ms).
[15:29:33.538] <TB1> INFO: Test took 1156ms.
[15:29:33.540] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:33.844] <TB1> INFO: Expecting 2560 events.
[15:29:34.697] <TB1> INFO: 2560 events read in total (297ms).
[15:29:34.697] <TB1> INFO: Test took 1157ms.
[15:29:34.700] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:35.003] <TB1> INFO: Expecting 2560 events.
[15:29:35.856] <TB1> INFO: 2560 events read in total (297ms).
[15:29:35.856] <TB1> INFO: Test took 1156ms.
[15:29:35.857] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:36.161] <TB1> INFO: Expecting 2560 events.
[15:29:37.015] <TB1> INFO: 2560 events read in total (298ms).
[15:29:37.015] <TB1> INFO: Test took 1158ms.
[15:29:37.017] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:37.320] <TB1> INFO: Expecting 2560 events.
[15:29:38.174] <TB1> INFO: 2560 events read in total (297ms).
[15:29:38.174] <TB1> INFO: Test took 1157ms.
[15:29:38.176] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:38.480] <TB1> INFO: Expecting 2560 events.
[15:29:39.333] <TB1> INFO: 2560 events read in total (297ms).
[15:29:39.333] <TB1> INFO: Test took 1157ms.
[15:29:39.336] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:39.638] <TB1> INFO: Expecting 2560 events.
[15:29:40.497] <TB1> INFO: 2560 events read in total (303ms).
[15:29:40.497] <TB1> INFO: Test took 1162ms.
[15:29:40.499] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:40.803] <TB1> INFO: Expecting 2560 events.
[15:29:41.656] <TB1> INFO: 2560 events read in total (297ms).
[15:29:41.656] <TB1> INFO: Test took 1157ms.
[15:29:41.658] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:41.963] <TB1> INFO: Expecting 2560 events.
[15:29:42.821] <TB1> INFO: 2560 events read in total (302ms).
[15:29:42.821] <TB1> INFO: Test took 1163ms.
[15:29:42.823] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:43.128] <TB1> INFO: Expecting 2560 events.
[15:29:43.987] <TB1> INFO: 2560 events read in total (303ms).
[15:29:43.987] <TB1> INFO: Test took 1164ms.
[15:29:43.989] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:44.293] <TB1> INFO: Expecting 2560 events.
[15:29:45.152] <TB1> INFO: 2560 events read in total (303ms).
[15:29:45.152] <TB1> INFO: Test took 1163ms.
[15:29:45.154] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:45.458] <TB1> INFO: Expecting 2560 events.
[15:29:46.316] <TB1> INFO: 2560 events read in total (302ms).
[15:29:46.316] <TB1> INFO: Test took 1163ms.
[15:29:46.318] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:46.622] <TB1> INFO: Expecting 2560 events.
[15:29:47.475] <TB1> INFO: 2560 events read in total (297ms).
[15:29:47.476] <TB1> INFO: Test took 1158ms.
[15:29:47.478] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:47.781] <TB1> INFO: Expecting 2560 events.
[15:29:48.635] <TB1> INFO: 2560 events read in total (298ms).
[15:29:48.636] <TB1> INFO: Test took 1158ms.
[15:29:48.638] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:48.941] <TB1> INFO: Expecting 2560 events.
[15:29:49.794] <TB1> INFO: 2560 events read in total (297ms).
[15:29:49.794] <TB1> INFO: Test took 1156ms.
[15:29:49.796] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:50.100] <TB1> INFO: Expecting 2560 events.
[15:29:50.953] <TB1> INFO: 2560 events read in total (297ms).
[15:29:50.953] <TB1> INFO: Test took 1157ms.
[15:29:50.955] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:51.258] <TB1> INFO: Expecting 2560 events.
[15:29:52.114] <TB1> INFO: 2560 events read in total (299ms).
[15:29:52.114] <TB1> INFO: Test took 1159ms.
[15:29:52.116] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:52.420] <TB1> INFO: Expecting 2560 events.
[15:29:53.273] <TB1> INFO: 2560 events read in total (297ms).
[15:29:53.273] <TB1> INFO: Test took 1157ms.
[15:29:53.275] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:53.578] <TB1> INFO: Expecting 2560 events.
[15:29:54.432] <TB1> INFO: 2560 events read in total (298ms).
[15:29:54.432] <TB1> INFO: Test took 1158ms.
[15:29:54.434] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:54.738] <TB1> INFO: Expecting 2560 events.
[15:29:55.591] <TB1> INFO: 2560 events read in total (297ms).
[15:29:55.591] <TB1> INFO: Test took 1157ms.
[15:29:55.594] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:55.896] <TB1> INFO: Expecting 2560 events.
[15:29:56.749] <TB1> INFO: 2560 events read in total (297ms).
[15:29:56.750] <TB1> INFO: Test took 1156ms.
[15:29:56.751] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:57.055] <TB1> INFO: Expecting 2560 events.
[15:29:57.907] <TB1> INFO: 2560 events read in total (296ms).
[15:29:57.908] <TB1> INFO: Test took 1157ms.
[15:29:58.373] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[15:29:58.373] <TB1> INFO: PH scale (per ROC): 76 79 67 73 80 69 80 70 72 76 71 80 69 76 76 76
[15:29:58.373] <TB1> INFO: PH offset (per ROC): 193 164 182 162 176 193 188 189 173 176 177 175 187 159 173 188
[15:29:58.379] <TB1> INFO: Decoding statistics:
[15:29:58.379] <TB1> INFO: General information:
[15:29:58.379] <TB1> INFO: 16bit words read: 91944
[15:29:58.379] <TB1> INFO: valid events total: 10240
[15:29:58.379] <TB1> INFO: empty events: 7734
[15:29:58.379] <TB1> INFO: valid events with pixels: 2506
[15:29:58.379] <TB1> INFO: valid pixel hits: 2506
[15:29:58.379] <TB1> INFO: Event errors: 0
[15:29:58.379] <TB1> INFO: start marker: 0
[15:29:58.379] <TB1> INFO: stop marker: 0
[15:29:58.379] <TB1> INFO: overflow: 0
[15:29:58.379] <TB1> INFO: invalid 5bit words: 0
[15:29:58.379] <TB1> INFO: invalid XOR eye diagram: 0
[15:29:58.379] <TB1> INFO: frame (failed synchr.): 0
[15:29:58.379] <TB1> INFO: idle data (no TBM trl): 0
[15:29:58.379] <TB1> INFO: no data (only TBM hdr): 0
[15:29:58.379] <TB1> INFO: TBM errors: 0
[15:29:58.379] <TB1> INFO: flawed TBM headers: 0
[15:29:58.379] <TB1> INFO: flawed TBM trailers: 0
[15:29:58.379] <TB1> INFO: event ID mismatches: 0
[15:29:58.379] <TB1> INFO: ROC errors: 0
[15:29:58.379] <TB1> INFO: missing ROC header(s): 0
[15:29:58.379] <TB1> INFO: misplaced readback start: 0
[15:29:58.379] <TB1> INFO: Pixel decoding errors: 0
[15:29:58.379] <TB1> INFO: pixel data incomplete: 0
[15:29:58.379] <TB1> INFO: pixel address: 0
[15:29:58.379] <TB1> INFO: pulse height fill bit: 0
[15:29:58.379] <TB1> INFO: buffer corruption: 0
[15:29:58.571] <TB1> INFO: ######################################################################
[15:29:58.571] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:29:58.571] <TB1> INFO: ######################################################################
[15:29:58.580] <TB1> INFO: scanning low vcal = 10
[15:29:58.852] <TB1> INFO: Expecting 41600 events.
[15:30:02.405] <TB1> INFO: 41600 events read in total (2997ms).
[15:30:02.406] <TB1> INFO: Test took 3826ms.
[15:30:02.408] <TB1> INFO: scanning low vcal = 20
[15:30:02.710] <TB1> INFO: Expecting 41600 events.
[15:30:06.266] <TB1> INFO: 41600 events read in total (3000ms).
[15:30:06.266] <TB1> INFO: Test took 3858ms.
[15:30:06.268] <TB1> INFO: scanning low vcal = 30
[15:30:06.571] <TB1> INFO: Expecting 41600 events.
[15:30:10.126] <TB1> INFO: 41600 events read in total (2999ms).
[15:30:10.127] <TB1> INFO: Test took 3859ms.
[15:30:10.129] <TB1> INFO: scanning low vcal = 40
[15:30:10.425] <TB1> INFO: Expecting 41600 events.
[15:30:14.602] <TB1> INFO: 41600 events read in total (3621ms).
[15:30:14.602] <TB1> INFO: Test took 4473ms.
[15:30:14.605] <TB1> INFO: scanning low vcal = 50
[15:30:14.888] <TB1> INFO: Expecting 41600 events.
[15:30:19.111] <TB1> INFO: 41600 events read in total (3667ms).
[15:30:19.111] <TB1> INFO: Test took 4506ms.
[15:30:19.114] <TB1> INFO: scanning low vcal = 60
[15:30:19.401] <TB1> INFO: Expecting 41600 events.
[15:30:23.601] <TB1> INFO: 41600 events read in total (3643ms).
[15:30:23.602] <TB1> INFO: Test took 4488ms.
[15:30:23.604] <TB1> INFO: scanning low vcal = 70
[15:30:23.865] <TB1> INFO: Expecting 41600 events.
[15:30:28.073] <TB1> INFO: 41600 events read in total (3652ms).
[15:30:28.074] <TB1> INFO: Test took 4470ms.
[15:30:28.076] <TB1> INFO: scanning low vcal = 80
[15:30:28.353] <TB1> INFO: Expecting 41600 events.
[15:30:32.577] <TB1> INFO: 41600 events read in total (3668ms).
[15:30:32.577] <TB1> INFO: Test took 4501ms.
[15:30:32.580] <TB1> INFO: scanning low vcal = 90
[15:30:32.842] <TB1> INFO: Expecting 41600 events.
[15:30:37.082] <TB1> INFO: 41600 events read in total (3684ms).
[15:30:37.083] <TB1> INFO: Test took 4503ms.
[15:30:37.086] <TB1> INFO: scanning low vcal = 100
[15:30:37.371] <TB1> INFO: Expecting 41600 events.
[15:30:41.577] <TB1> INFO: 41600 events read in total (3650ms).
[15:30:41.578] <TB1> INFO: Test took 4492ms.
[15:30:41.580] <TB1> INFO: scanning low vcal = 110
[15:30:41.849] <TB1> INFO: Expecting 41600 events.
[15:30:46.052] <TB1> INFO: 41600 events read in total (3647ms).
[15:30:46.052] <TB1> INFO: Test took 4472ms.
[15:30:46.055] <TB1> INFO: scanning low vcal = 120
[15:30:46.316] <TB1> INFO: Expecting 41600 events.
[15:30:50.538] <TB1> INFO: 41600 events read in total (3665ms).
[15:30:50.538] <TB1> INFO: Test took 4483ms.
[15:30:50.541] <TB1> INFO: scanning low vcal = 130
[15:30:50.810] <TB1> INFO: Expecting 41600 events.
[15:30:55.054] <TB1> INFO: 41600 events read in total (3688ms).
[15:30:55.054] <TB1> INFO: Test took 4513ms.
[15:30:55.057] <TB1> INFO: scanning low vcal = 140
[15:30:55.316] <TB1> INFO: Expecting 41600 events.
[15:30:59.522] <TB1> INFO: 41600 events read in total (3650ms).
[15:30:59.523] <TB1> INFO: Test took 4466ms.
[15:30:59.525] <TB1> INFO: scanning low vcal = 150
[15:30:59.796] <TB1> INFO: Expecting 41600 events.
[15:31:04.007] <TB1> INFO: 41600 events read in total (3655ms).
[15:31:04.008] <TB1> INFO: Test took 4483ms.
[15:31:04.010] <TB1> INFO: scanning low vcal = 160
[15:31:04.300] <TB1> INFO: Expecting 41600 events.
[15:31:08.493] <TB1> INFO: 41600 events read in total (3637ms).
[15:31:08.493] <TB1> INFO: Test took 4482ms.
[15:31:08.496] <TB1> INFO: scanning low vcal = 170
[15:31:08.758] <TB1> INFO: Expecting 41600 events.
[15:31:12.947] <TB1> INFO: 41600 events read in total (3633ms).
[15:31:12.947] <TB1> INFO: Test took 4451ms.
[15:31:12.951] <TB1> INFO: scanning low vcal = 180
[15:31:13.219] <TB1> INFO: Expecting 41600 events.
[15:31:17.412] <TB1> INFO: 41600 events read in total (3637ms).
[15:31:17.412] <TB1> INFO: Test took 4461ms.
[15:31:17.415] <TB1> INFO: scanning low vcal = 190
[15:31:17.684] <TB1> INFO: Expecting 41600 events.
[15:31:21.860] <TB1> INFO: 41600 events read in total (3620ms).
[15:31:21.860] <TB1> INFO: Test took 4445ms.
[15:31:21.863] <TB1> INFO: scanning low vcal = 200
[15:31:22.127] <TB1> INFO: Expecting 41600 events.
[15:31:26.356] <TB1> INFO: 41600 events read in total (3673ms).
[15:31:26.357] <TB1> INFO: Test took 4494ms.
[15:31:26.359] <TB1> INFO: scanning low vcal = 210
[15:31:26.653] <TB1> INFO: Expecting 41600 events.
[15:31:30.848] <TB1> INFO: 41600 events read in total (3639ms).
[15:31:30.849] <TB1> INFO: Test took 4489ms.
[15:31:30.851] <TB1> INFO: scanning low vcal = 220
[15:31:31.110] <TB1> INFO: Expecting 41600 events.
[15:31:35.311] <TB1> INFO: 41600 events read in total (3645ms).
[15:31:35.312] <TB1> INFO: Test took 4461ms.
[15:31:35.314] <TB1> INFO: scanning low vcal = 230
[15:31:35.573] <TB1> INFO: Expecting 41600 events.
[15:31:39.778] <TB1> INFO: 41600 events read in total (3649ms).
[15:31:39.779] <TB1> INFO: Test took 4465ms.
[15:31:39.781] <TB1> INFO: scanning low vcal = 240
[15:31:40.041] <TB1> INFO: Expecting 41600 events.
[15:31:44.257] <TB1> INFO: 41600 events read in total (3660ms).
[15:31:44.257] <TB1> INFO: Test took 4476ms.
[15:31:44.260] <TB1> INFO: scanning low vcal = 250
[15:31:44.523] <TB1> INFO: Expecting 41600 events.
[15:31:48.732] <TB1> INFO: 41600 events read in total (3653ms).
[15:31:48.733] <TB1> INFO: Test took 4473ms.
[15:31:48.737] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[15:31:49.015] <TB1> INFO: Expecting 41600 events.
[15:31:53.217] <TB1> INFO: 41600 events read in total (3646ms).
[15:31:53.218] <TB1> INFO: Test took 4481ms.
[15:31:53.221] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[15:31:53.496] <TB1> INFO: Expecting 41600 events.
[15:31:57.688] <TB1> INFO: 41600 events read in total (3636ms).
[15:31:57.689] <TB1> INFO: Test took 4468ms.
[15:31:57.691] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[15:31:57.965] <TB1> INFO: Expecting 41600 events.
[15:32:02.164] <TB1> INFO: 41600 events read in total (3643ms).
[15:32:02.164] <TB1> INFO: Test took 4472ms.
[15:32:02.167] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[15:32:02.429] <TB1> INFO: Expecting 41600 events.
[15:32:06.611] <TB1> INFO: 41600 events read in total (3625ms).
[15:32:06.611] <TB1> INFO: Test took 4444ms.
[15:32:06.614] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:32:06.878] <TB1> INFO: Expecting 41600 events.
[15:32:11.094] <TB1> INFO: 41600 events read in total (3659ms).
[15:32:11.095] <TB1> INFO: Test took 4481ms.
[15:32:11.571] <TB1> INFO: PixTestGainPedestal::measure() done
[15:32:46.189] <TB1> INFO: PixTestGainPedestal::fit() done
[15:32:46.189] <TB1> INFO: non-linearity mean: 0.964 0.960 0.956 0.955 0.966 0.951 0.961 0.957 0.955 0.951 0.955 0.960 0.953 0.949 0.959 0.961
[15:32:46.189] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.007 0.007 0.004 0.009 0.006 0.007 0.007 0.006 0.007 0.006 0.008 0.006 0.007 0.007
[15:32:46.190] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[15:32:46.208] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[15:32:46.226] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[15:32:46.253] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[15:32:46.271] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[15:32:46.289] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[15:32:46.307] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[15:32:46.325] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[15:32:46.351] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[15:32:46.378] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[15:32:46.396] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[15:32:46.421] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[15:32:46.446] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[15:32:46.464] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[15:32:46.483] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[15:32:46.510] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[15:32:46.528] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[15:32:46.528] <TB1> INFO: Decoding statistics:
[15:32:46.528] <TB1> INFO: General information:
[15:32:46.528] <TB1> INFO: 16bit words read: 2662400
[15:32:46.528] <TB1> INFO: valid events total: 166400
[15:32:46.528] <TB1> INFO: empty events: 0
[15:32:46.528] <TB1> INFO: valid events with pixels: 166400
[15:32:46.528] <TB1> INFO: valid pixel hits: 665565
[15:32:46.528] <TB1> INFO: Event errors: 0
[15:32:46.528] <TB1> INFO: start marker: 0
[15:32:46.528] <TB1> INFO: stop marker: 0
[15:32:46.528] <TB1> INFO: overflow: 0
[15:32:46.528] <TB1> INFO: invalid 5bit words: 0
[15:32:46.528] <TB1> INFO: invalid XOR eye diagram: 0
[15:32:46.528] <TB1> INFO: frame (failed synchr.): 0
[15:32:46.528] <TB1> INFO: idle data (no TBM trl): 0
[15:32:46.528] <TB1> INFO: no data (only TBM hdr): 0
[15:32:46.528] <TB1> INFO: TBM errors: 0
[15:32:46.528] <TB1> INFO: flawed TBM headers: 0
[15:32:46.528] <TB1> INFO: flawed TBM trailers: 0
[15:32:46.528] <TB1> INFO: event ID mismatches: 0
[15:32:46.528] <TB1> INFO: ROC errors: 0
[15:32:46.528] <TB1> INFO: missing ROC header(s): 0
[15:32:46.528] <TB1> INFO: misplaced readback start: 0
[15:32:46.528] <TB1> INFO: Pixel decoding errors: 0
[15:32:46.528] <TB1> INFO: pixel data incomplete: 0
[15:32:46.528] <TB1> INFO: pixel address: 0
[15:32:46.528] <TB1> INFO: pulse height fill bit: 0
[15:32:46.528] <TB1> INFO: buffer corruption: 0
[15:32:46.538] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C15.dat
[15:32:46.539] <TB1> INFO: ######################################################################
[15:32:46.539] <TB1> INFO: PixTestReadback::doTest()
[15:32:46.539] <TB1> INFO: ######################################################################
[15:32:46.539] <TB1> INFO: ----------------------------------------------------------------------
[15:32:46.539] <TB1> INFO: PixTestReadback::CalibrateVd()
[15:32:46.539] <TB1> INFO: ----------------------------------------------------------------------
[15:32:56.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C0.dat
[15:32:56.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C1.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C2.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C3.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C4.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C5.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C6.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C7.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C8.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C9.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C10.dat
[15:32:56.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C11.dat
[15:32:56.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C12.dat
[15:32:56.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C13.dat
[15:32:56.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C14.dat
[15:32:56.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C15.dat
[15:32:56.042] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:32:56.043] <TB1> INFO: ----------------------------------------------------------------------
[15:32:56.043] <TB1> INFO: PixTestReadback::CalibrateVa()
[15:32:56.043] <TB1> INFO: ----------------------------------------------------------------------
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C0.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C1.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C2.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C3.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C4.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C5.dat
[15:33:05.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C6.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C7.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C8.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C9.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C10.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C11.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C12.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C13.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C14.dat
[15:33:05.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C15.dat
[15:33:05.557] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:33:05.557] <TB1> INFO: ----------------------------------------------------------------------
[15:33:05.557] <TB1> INFO: PixTestReadback::readbackVbg()
[15:33:05.557] <TB1> INFO: ----------------------------------------------------------------------
[15:33:12.845] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:33:12.845] <TB1> INFO: ----------------------------------------------------------------------
[15:33:12.845] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[15:33:12.845] <TB1> INFO: ----------------------------------------------------------------------
[15:33:12.845] <TB1> INFO: Vbg will be calibrated using Vd calibration
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 158.1calibrated Vbg = 1.19544 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.3calibrated Vbg = 1.199 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.5calibrated Vbg = 1.19843 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.3calibrated Vbg = 1.2071 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.8calibrated Vbg = 1.21006 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.6calibrated Vbg = 1.21059 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154calibrated Vbg = 1.21376 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147calibrated Vbg = 1.21447 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155calibrated Vbg = 1.2189 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.4calibrated Vbg = 1.21044 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 158.9calibrated Vbg = 1.20543 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 161.5calibrated Vbg = 1.20935 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.9calibrated Vbg = 1.20039 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 144.4calibrated Vbg = 1.20184 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.9calibrated Vbg = 1.20658 :::*/*/*/*/
[15:33:12.845] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.1calibrated Vbg = 1.20415 :::*/*/*/*/
[15:33:12.847] <TB1> INFO: ----------------------------------------------------------------------
[15:33:12.847] <TB1> INFO: PixTestReadback::CalibrateIa()
[15:33:12.847] <TB1> INFO: ----------------------------------------------------------------------
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C0.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C1.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C2.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C3.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C4.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C5.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C6.dat
[15:35:47.988] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C7.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C8.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C9.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C10.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C11.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C12.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C13.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C14.dat
[15:35:47.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2234_FullQualification_2016-05-11_11h13m_1462957992//004_FulltestPxar_p17//readbackCal_C15.dat
[15:35:48.014] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:35:48.015] <TB1> INFO: PixTestReadback::doTest() done
[15:35:48.015] <TB1> INFO: Decoding statistics:
[15:35:48.015] <TB1> INFO: General information:
[15:35:48.015] <TB1> INFO: 16bit words read: 1024
[15:35:48.015] <TB1> INFO: valid events total: 128
[15:35:48.015] <TB1> INFO: empty events: 128
[15:35:48.015] <TB1> INFO: valid events with pixels: 0
[15:35:48.015] <TB1> INFO: valid pixel hits: 0
[15:35:48.015] <TB1> INFO: Event errors: 0
[15:35:48.015] <TB1> INFO: start marker: 0
[15:35:48.015] <TB1> INFO: stop marker: 0
[15:35:48.015] <TB1> INFO: overflow: 0
[15:35:48.015] <TB1> INFO: invalid 5bit words: 0
[15:35:48.015] <TB1> INFO: invalid XOR eye diagram: 0
[15:35:48.015] <TB1> INFO: frame (failed synchr.): 0
[15:35:48.015] <TB1> INFO: idle data (no TBM trl): 0
[15:35:48.015] <TB1> INFO: no data (only TBM hdr): 0
[15:35:48.015] <TB1> INFO: TBM errors: 0
[15:35:48.015] <TB1> INFO: flawed TBM headers: 0
[15:35:48.015] <TB1> INFO: flawed TBM trailers: 0
[15:35:48.015] <TB1> INFO: event ID mismatches: 0
[15:35:48.016] <TB1> INFO: ROC errors: 0
[15:35:48.016] <TB1> INFO: missing ROC header(s): 0
[15:35:48.016] <TB1> INFO: misplaced readback start: 0
[15:35:48.016] <TB1> INFO: Pixel decoding errors: 0
[15:35:48.016] <TB1> INFO: pixel data incomplete: 0
[15:35:48.016] <TB1> INFO: pixel address: 0
[15:35:48.016] <TB1> INFO: pulse height fill bit: 0
[15:35:48.016] <TB1> INFO: buffer corruption: 0
[15:35:48.041] <TB1> INFO: Decoding statistics:
[15:35:48.041] <TB1> INFO: General information:
[15:35:48.041] <TB1> INFO: 16bit words read: 2755368
[15:35:48.041] <TB1> INFO: valid events total: 176768
[15:35:48.041] <TB1> INFO: empty events: 7862
[15:35:48.041] <TB1> INFO: valid events with pixels: 168906
[15:35:48.042] <TB1> INFO: valid pixel hits: 668071
[15:35:48.042] <TB1> INFO: Event errors: 0
[15:35:48.042] <TB1> INFO: start marker: 0
[15:35:48.042] <TB1> INFO: stop marker: 0
[15:35:48.042] <TB1> INFO: overflow: 0
[15:35:48.042] <TB1> INFO: invalid 5bit words: 0
[15:35:48.042] <TB1> INFO: invalid XOR eye diagram: 0
[15:35:48.042] <TB1> INFO: frame (failed synchr.): 0
[15:35:48.042] <TB1> INFO: idle data (no TBM trl): 0
[15:35:48.042] <TB1> INFO: no data (only TBM hdr): 0
[15:35:48.042] <TB1> INFO: TBM errors: 0
[15:35:48.042] <TB1> INFO: flawed TBM headers: 0
[15:35:48.042] <TB1> INFO: flawed TBM trailers: 0
[15:35:48.042] <TB1> INFO: event ID mismatches: 0
[15:35:48.042] <TB1> INFO: ROC errors: 0
[15:35:48.042] <TB1> INFO: missing ROC header(s): 0
[15:35:48.042] <TB1> INFO: misplaced readback start: 0
[15:35:48.042] <TB1> INFO: Pixel decoding errors: 0
[15:35:48.042] <TB1> INFO: pixel data incomplete: 0
[15:35:48.042] <TB1> INFO: pixel address: 0
[15:35:48.042] <TB1> INFO: pulse height fill bit: 0
[15:35:48.042] <TB1> INFO: buffer corruption: 0
[15:35:48.042] <TB1> INFO: enter test to run
[15:35:48.042] <TB1> INFO: test: exit no parameter change
[15:35:48.188] <TB1> QUIET: Connection to board 153 closed.
[15:35:48.269] <TB1> INFO: pXar: this is the end, my friend