[12:59:31.056] <TB3> INFO: *** Welcome to pxar ***
[12:59:31.056] <TB3> INFO: *** Today: 2016/10/10
[12:59:31.164] <TB3> INFO: *** Version: 0b00-dirty
[12:59:31.164] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C15.dat
[12:59:31.165] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//tbmParameters_C0b.dat
[12:59:31.165] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//defaultMaskFile.dat
[12:59:31.165] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters_C15.dat
[12:59:31.236] <TB3> INFO: clk: 4
[12:59:31.236] <TB3> INFO: ctr: 4
[12:59:31.236] <TB3> INFO: sda: 19
[12:59:31.236] <TB3> INFO: tin: 9
[12:59:31.236] <TB3> INFO: level: 15
[12:59:31.236] <TB3> INFO: triggerdelay: 0
[12:59:31.236] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[12:59:31.236] <TB3> INFO: Log level: INFO
[12:59:31.245] <TB3> INFO: Found DTB DTB_WZ4I6J
[12:59:31.255] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[12:59:31.257] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[12:59:31.259] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[12:59:32.783] <TB3> INFO: DUT info:
[12:59:32.783] <TB3> INFO: The DUT currently contains the following objects:
[12:59:32.783] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:59:32.783] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:59:32.784] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:59:32.784] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:59:32.784] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:32.784] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:33.185] <TB3> INFO: enter 'restricted' command line mode
[12:59:33.185] <TB3> INFO: enter test to run
[12:59:33.185] <TB3> INFO: test: pretest no parameter change
[12:59:33.185] <TB3> INFO: running: pretest
[12:59:33.194] <TB3> INFO: ----------------------------------------------------------------------
[12:59:33.194] <TB3> INFO: PixTestPretest::programROC()
[12:59:33.194] <TB3> INFO: ----------------------------------------------------------------------
[12:59:51.215] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:59:51.215] <TB3> INFO: IA differences per ROC: 18.5 17.7 19.3 18.5 17.7 17.7 18.5 16.9 17.7 16.1 20.9 19.3 19.3 18.5 19.3 19.3
[12:59:51.335] <TB3> INFO: enter test to run
[12:59:51.335] <TB3> INFO: test: pretest no parameter change
[12:59:51.335] <TB3> INFO: running: pretest
[12:59:51.336] <TB3> INFO: ----------------------------------------------------------------------
[12:59:51.336] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:59:51.336] <TB3> INFO: ----------------------------------------------------------------------
[13:00:12.633] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[13:00:12.633] <TB3> INFO: i(loss) [mA/ROC]: 19.2 19.2 19.2 19.2 19.2 18.4 19.2 19.2 19.2 17.6 19.2 19.2 18.4 18.4 17.6 17.6
[13:00:12.679] <TB3> INFO: enter test to run
[13:00:12.679] <TB3> INFO: test: pretest no parameter change
[13:00:12.679] <TB3> INFO: running: pretest
[13:00:12.679] <TB3> INFO: ----------------------------------------------------------------------
[13:00:12.679] <TB3> INFO: PixTestPretest::findTiming()
[13:00:12.679] <TB3> INFO: ----------------------------------------------------------------------
[13:00:12.679] <TB3> INFO: PixTestCmd::init()

[13:01:41.673] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 4, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[13:01:41.673] <TB3> INFO: (success/tries = 100/100), width = 5
[13:01:41.674] <TB3> INFO: enter test to run
[13:01:41.674] <TB3> INFO: test: pretest no parameter change
[13:01:41.674] <TB3> INFO: running: pretest
[13:01:41.675] <TB3> INFO: ----------------------------------------------------------------------
[13:01:41.675] <TB3> INFO: PixTestPretest::findWorkingPixel()
[13:01:41.675] <TB3> INFO: ----------------------------------------------------------------------
[13:01:41.768] <TB3> INFO: Expecting 231680 events.


[13:01:50.916] <TB3> INFO: 231680 events read in total (8591ms).
[13:01:50.922] <TB3> INFO: Test took 9243ms.
[13:01:51.137] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:01:51.221] <TB3> INFO: enter test to run
[13:01:51.221] <TB3> INFO: test: pretest no parameter change
[13:01:51.221] <TB3> INFO: running: pretest
[13:01:51.222] <TB3> INFO: ----------------------------------------------------------------------
[13:01:51.222] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[13:01:51.222] <TB3> INFO: ----------------------------------------------------------------------
[13:01:51.318] <TB3> INFO: Expecting 231680 events.
[13:02:00.544] <TB3> INFO: 231680 events read in total (8670ms).
[13:02:00.550] <TB3> INFO: Test took 9322ms.
[13:02:00.787] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[13:02:00.787] <TB3> INFO: CalDel: 120 104 133 130 144 130 131 135 139 133 130 144 137 140 129 145
[13:02:00.787] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:02:00.820] <TB3> INFO: enter test to run
[13:02:00.820] <TB3> INFO: test: pretest no parameter change
[13:02:00.820] <TB3> INFO: running: pretest
[13:02:00.820] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C0.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C1.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C2.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C3.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C4.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C5.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C6.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C7.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C8.dat
[13:02:00.821] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C9.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C10.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C11.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C12.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C13.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C14.dat
[13:02:00.822] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C15.dat
[13:02:00.822] <TB3> INFO: enter test to run
[13:02:00.822] <TB3> INFO: test: fulltest no parameter change
[13:02:00.822] <TB3> INFO: running: fulltest
[13:02:00.822] <TB3> INFO: ######################################################################
[13:02:00.822] <TB3> INFO: PixTestFullTest::doTest()
[13:02:00.822] <TB3> INFO: ######################################################################
[13:02:00.823] <TB3> INFO: ######################################################################
[13:02:00.823] <TB3> INFO: PixTestAlive::doTest()
[13:02:00.823] <TB3> INFO: ######################################################################
[13:02:00.825] <TB3> INFO: ----------------------------------------------------------------------
[13:02:00.825] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:02:00.825] <TB3> INFO: ----------------------------------------------------------------------
[13:02:01.154] <TB3> INFO: Expecting 41600 events.
[13:02:04.592] <TB3> INFO: 41600 events read in total (2881ms).
[13:02:04.593] <TB3> INFO: Test took 3766ms.
[13:02:04.831] <TB3> INFO: PixTestAlive::aliveTest() done
[13:02:04.831] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:02:04.833] <TB3> INFO: ----------------------------------------------------------------------
[13:02:04.833] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:02:04.833] <TB3> INFO: ----------------------------------------------------------------------
[13:02:05.101] <TB3> INFO: Expecting 41600 events.
[13:02:07.858] <TB3> INFO: 41600 events read in total (2200ms).
[13:02:07.858] <TB3> INFO: Test took 3023ms.
[13:02:07.859] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:02:08.112] <TB3> INFO: PixTestAlive::maskTest() done
[13:02:08.112] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:02:08.113] <TB3> INFO: ----------------------------------------------------------------------
[13:02:08.113] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:02:08.114] <TB3> INFO: ----------------------------------------------------------------------
[13:02:08.391] <TB3> INFO: Expecting 41600 events.
[13:02:11.832] <TB3> INFO: 41600 events read in total (2884ms).
[13:02:11.832] <TB3> INFO: Test took 3716ms.
[13:02:12.068] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[13:02:12.068] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:02:12.068] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[13:02:12.068] <TB3> INFO: Decoding statistics:
[13:02:12.068] <TB3> INFO: General information:
[13:02:12.068] <TB3> INFO: 16bit words read: 0
[13:02:12.068] <TB3> INFO: valid events total: 0
[13:02:12.068] <TB3> INFO: empty events: 0
[13:02:12.068] <TB3> INFO: valid events with pixels: 0
[13:02:12.068] <TB3> INFO: valid pixel hits: 0
[13:02:12.068] <TB3> INFO: Event errors: 0
[13:02:12.068] <TB3> INFO: start marker: 0
[13:02:12.068] <TB3> INFO: stop marker: 0
[13:02:12.068] <TB3> INFO: overflow: 0
[13:02:12.068] <TB3> INFO: invalid 5bit words: 0
[13:02:12.068] <TB3> INFO: invalid XOR eye diagram: 0
[13:02:12.068] <TB3> INFO: frame (failed synchr.): 0
[13:02:12.068] <TB3> INFO: idle data (no TBM trl): 0
[13:02:12.068] <TB3> INFO: no data (only TBM hdr): 0
[13:02:12.068] <TB3> INFO: TBM errors: 0
[13:02:12.068] <TB3> INFO: flawed TBM headers: 0
[13:02:12.068] <TB3> INFO: flawed TBM trailers: 0
[13:02:12.068] <TB3> INFO: event ID mismatches: 0
[13:02:12.068] <TB3> INFO: ROC errors: 0
[13:02:12.068] <TB3> INFO: missing ROC header(s): 0
[13:02:12.068] <TB3> INFO: misplaced readback start: 0
[13:02:12.068] <TB3> INFO: Pixel decoding errors: 0
[13:02:12.068] <TB3> INFO: pixel data incomplete: 0
[13:02:12.068] <TB3> INFO: pixel address: 0
[13:02:12.068] <TB3> INFO: pulse height fill bit: 0
[13:02:12.068] <TB3> INFO: buffer corruption: 0
[13:02:12.076] <TB3> INFO: ######################################################################
[13:02:12.076] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:02:12.076] <TB3> INFO: ######################################################################
[13:02:12.080] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:02:12.091] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:02:12.091] <TB3> INFO: run 1 of 1
[13:02:12.363] <TB3> INFO: Expecting 3120000 events.
[13:02:50.237] <TB3> INFO: 861495 events read in total (37317ms).
[13:03:26.940] <TB3> INFO: 1704885 events read in total (74020ms).
[13:04:03.366] <TB3> INFO: 2557120 events read in total (110446ms).
[13:04:27.829] <TB3> INFO: 3120000 events read in total (134909ms).
[13:04:27.920] <TB3> INFO: Test took 135829ms.
[13:04:52.059] <TB3> INFO: PixTestBBMap::doTest() done, duration: 159 seconds
[13:04:52.059] <TB3> INFO: number of dead bumps (per ROC): 9 0 0 0 0 0 0 0 0 0 0 0 0 0 0 10
[13:04:52.059] <TB3> INFO: separation cut (per ROC): 95 113 108 86 104 104 113 103 87 97 78 77 83 88 103 83
[13:04:52.059] <TB3> INFO: Decoding statistics:
[13:04:52.059] <TB3> INFO: General information:
[13:04:52.059] <TB3> INFO: 16bit words read: 0
[13:04:52.059] <TB3> INFO: valid events total: 0
[13:04:52.059] <TB3> INFO: empty events: 0
[13:04:52.060] <TB3> INFO: valid events with pixels: 0
[13:04:52.060] <TB3> INFO: valid pixel hits: 0
[13:04:52.060] <TB3> INFO: Event errors: 0
[13:04:52.060] <TB3> INFO: start marker: 0
[13:04:52.060] <TB3> INFO: stop marker: 0
[13:04:52.060] <TB3> INFO: overflow: 0
[13:04:52.060] <TB3> INFO: invalid 5bit words: 0
[13:04:52.060] <TB3> INFO: invalid XOR eye diagram: 0
[13:04:52.060] <TB3> INFO: frame (failed synchr.): 0
[13:04:52.060] <TB3> INFO: idle data (no TBM trl): 0
[13:04:52.060] <TB3> INFO: no data (only TBM hdr): 0
[13:04:52.060] <TB3> INFO: TBM errors: 0
[13:04:52.060] <TB3> INFO: flawed TBM headers: 0
[13:04:52.060] <TB3> INFO: flawed TBM trailers: 0
[13:04:52.060] <TB3> INFO: event ID mismatches: 0
[13:04:52.060] <TB3> INFO: ROC errors: 0
[13:04:52.060] <TB3> INFO: missing ROC header(s): 0
[13:04:52.060] <TB3> INFO: misplaced readback start: 0
[13:04:52.060] <TB3> INFO: Pixel decoding errors: 0
[13:04:52.060] <TB3> INFO: pixel data incomplete: 0
[13:04:52.060] <TB3> INFO: pixel address: 0
[13:04:52.060] <TB3> INFO: pulse height fill bit: 0
[13:04:52.060] <TB3> INFO: buffer corruption: 0
[13:04:52.134] <TB3> INFO: ######################################################################
[13:04:52.134] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:04:52.134] <TB3> INFO: ######################################################################
[13:04:52.134] <TB3> INFO: ----------------------------------------------------------------------
[13:04:52.134] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:04:52.134] <TB3> INFO: ----------------------------------------------------------------------
[13:04:52.134] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[13:04:52.142] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:52.142] <TB3> INFO: run 1 of 1
[13:04:52.410] <TB3> INFO: Expecting 36608000 events.
[13:05:22.103] <TB3> INFO: 839500 events read in total (29136ms).
[13:05:51.324] <TB3> INFO: 1662300 events read in total (58357ms).
[13:06:20.051] <TB3> INFO: 2487750 events read in total (87084ms).
[13:06:48.977] <TB3> INFO: 3312150 events read in total (116010ms).
[13:07:17.958] <TB3> INFO: 4135100 events read in total (144991ms).
[13:07:46.902] <TB3> INFO: 4959250 events read in total (173935ms).
[13:08:16.449] <TB3> INFO: 5780000 events read in total (203482ms).
[13:08:45.393] <TB3> INFO: 6602300 events read in total (232426ms).
[13:09:14.482] <TB3> INFO: 7423600 events read in total (261515ms).
[13:09:44.684] <TB3> INFO: 8244850 events read in total (291717ms).
[13:10:14.893] <TB3> INFO: 9067800 events read in total (321926ms).
[13:10:44.808] <TB3> INFO: 9888250 events read in total (351841ms).
[13:11:14.956] <TB3> INFO: 10710350 events read in total (381989ms).
[13:11:43.998] <TB3> INFO: 11531900 events read in total (411031ms).
[13:12:13.377] <TB3> INFO: 12351950 events read in total (440410ms).
[13:12:42.256] <TB3> INFO: 13172100 events read in total (469289ms).
[13:13:10.889] <TB3> INFO: 13990550 events read in total (497922ms).
[13:13:39.517] <TB3> INFO: 14812350 events read in total (526550ms).
[13:14:08.664] <TB3> INFO: 15632500 events read in total (555697ms).
[13:14:37.511] <TB3> INFO: 16452150 events read in total (584544ms).
[13:15:06.370] <TB3> INFO: 17270950 events read in total (613403ms).
[13:15:34.767] <TB3> INFO: 18086600 events read in total (641800ms).
[13:16:03.543] <TB3> INFO: 18898500 events read in total (670576ms).
[13:16:32.220] <TB3> INFO: 19710300 events read in total (699253ms).
[13:17:00.731] <TB3> INFO: 20520650 events read in total (727764ms).
[13:17:29.148] <TB3> INFO: 21329950 events read in total (756181ms).
[13:17:57.688] <TB3> INFO: 22138900 events read in total (784721ms).
[13:18:25.801] <TB3> INFO: 22946000 events read in total (812834ms).
[13:18:54.353] <TB3> INFO: 23754650 events read in total (841386ms).
[13:19:23.245] <TB3> INFO: 24563300 events read in total (870278ms).
[13:19:51.999] <TB3> INFO: 25370300 events read in total (899032ms).
[13:20:20.588] <TB3> INFO: 26179300 events read in total (927621ms).
[13:20:49.329] <TB3> INFO: 26985550 events read in total (956362ms).
[13:21:17.806] <TB3> INFO: 27791450 events read in total (984839ms).
[13:21:46.570] <TB3> INFO: 28598500 events read in total (1013603ms).
[13:22:15.370] <TB3> INFO: 29404500 events read in total (1042403ms).
[13:22:43.720] <TB3> INFO: 30209350 events read in total (1070753ms).
[13:23:12.236] <TB3> INFO: 31012700 events read in total (1099269ms).
[13:23:40.668] <TB3> INFO: 31817400 events read in total (1127701ms).
[13:24:09.485] <TB3> INFO: 32621350 events read in total (1156518ms).
[13:24:38.423] <TB3> INFO: 33427150 events read in total (1185456ms).
[13:25:07.077] <TB3> INFO: 34230750 events read in total (1214110ms).
[13:25:35.440] <TB3> INFO: 35035750 events read in total (1242473ms).
[13:26:03.787] <TB3> INFO: 35839100 events read in total (1270820ms).
[13:26:31.673] <TB3> INFO: 36608000 events read in total (1298706ms).
[13:26:31.730] <TB3> INFO: Test took 1299587ms.
[13:26:32.160] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:33.877] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:35.670] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:37.440] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:39.289] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:41.217] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:43.153] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:44.964] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:46.847] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:48.687] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:50.569] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:52.495] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:54.683] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:56.872] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:26:58.895] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:27:01.116] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:27:02.933] <TB3> INFO: PixTestScurves::scurves() done
[13:27:02.933] <TB3> INFO: Vcal mean: 90.25 94.58 100.75 98.16 93.03 86.99 87.15 90.03 90.78 92.66 86.09 88.16 101.43 89.92 99.91 80.85
[13:27:02.933] <TB3> INFO: Vcal RMS: 5.01 5.31 5.19 4.85 5.36 4.75 5.29 5.78 5.22 4.81 4.84 4.93 5.04 5.10 5.00 4.59
[13:27:02.933] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1330 seconds
[13:27:02.933] <TB3> INFO: Decoding statistics:
[13:27:02.933] <TB3> INFO: General information:
[13:27:02.933] <TB3> INFO: 16bit words read: 0
[13:27:02.933] <TB3> INFO: valid events total: 0
[13:27:02.933] <TB3> INFO: empty events: 0
[13:27:02.933] <TB3> INFO: valid events with pixels: 0
[13:27:02.933] <TB3> INFO: valid pixel hits: 0
[13:27:02.933] <TB3> INFO: Event errors: 0
[13:27:02.933] <TB3> INFO: start marker: 0
[13:27:02.933] <TB3> INFO: stop marker: 0
[13:27:02.933] <TB3> INFO: overflow: 0
[13:27:02.933] <TB3> INFO: invalid 5bit words: 0
[13:27:02.933] <TB3> INFO: invalid XOR eye diagram: 0
[13:27:02.933] <TB3> INFO: frame (failed synchr.): 0
[13:27:02.933] <TB3> INFO: idle data (no TBM trl): 0
[13:27:02.933] <TB3> INFO: no data (only TBM hdr): 0
[13:27:02.933] <TB3> INFO: TBM errors: 0
[13:27:02.933] <TB3> INFO: flawed TBM headers: 0
[13:27:02.933] <TB3> INFO: flawed TBM trailers: 0
[13:27:02.933] <TB3> INFO: event ID mismatches: 0
[13:27:02.933] <TB3> INFO: ROC errors: 0
[13:27:02.933] <TB3> INFO: missing ROC header(s): 0
[13:27:02.933] <TB3> INFO: misplaced readback start: 0
[13:27:02.933] <TB3> INFO: Pixel decoding errors: 0
[13:27:02.933] <TB3> INFO: pixel data incomplete: 0
[13:27:02.933] <TB3> INFO: pixel address: 0
[13:27:02.933] <TB3> INFO: pulse height fill bit: 0
[13:27:02.933] <TB3> INFO: buffer corruption: 0
[13:27:03.007] <TB3> INFO: ######################################################################
[13:27:03.007] <TB3> INFO: PixTestTrim::doTest()
[13:27:03.007] <TB3> INFO: ######################################################################
[13:27:03.009] <TB3> INFO: ----------------------------------------------------------------------
[13:27:03.009] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:27:03.009] <TB3> INFO: ----------------------------------------------------------------------
[13:27:03.100] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:27:03.101] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:27:03.109] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:27:03.110] <TB3> INFO: run 1 of 1
[13:27:03.382] <TB3> INFO: Expecting 6281600 events.
[13:27:40.626] <TB3> INFO: 1112180 events read in total (36687ms).
[13:28:16.812] <TB3> INFO: 2218760 events read in total (72873ms).
[13:28:53.491] <TB3> INFO: 3321460 events read in total (109553ms).
[13:29:29.974] <TB3> INFO: 4430360 events read in total (146035ms).
[13:30:06.349] <TB3> INFO: 5543250 events read in total (182411ms).
[13:30:30.932] <TB3> INFO: 6281600 events read in total (206993ms).
[13:30:30.978] <TB3> INFO: Test took 207868ms.
[13:30:50.662] <TB3> INFO: ROC 0 VthrComp = 93
[13:30:50.662] <TB3> INFO: ROC 1 VthrComp = 98
[13:30:50.662] <TB3> INFO: ROC 2 VthrComp = 103
[13:30:50.662] <TB3> INFO: ROC 3 VthrComp = 102
[13:30:50.662] <TB3> INFO: ROC 4 VthrComp = 92
[13:30:50.662] <TB3> INFO: ROC 5 VthrComp = 89
[13:30:50.663] <TB3> INFO: ROC 6 VthrComp = 91
[13:30:50.663] <TB3> INFO: ROC 7 VthrComp = 92
[13:30:50.663] <TB3> INFO: ROC 8 VthrComp = 93
[13:30:50.663] <TB3> INFO: ROC 9 VthrComp = 91
[13:30:50.663] <TB3> INFO: ROC 10 VthrComp = 90
[13:30:50.663] <TB3> INFO: ROC 11 VthrComp = 89
[13:30:50.663] <TB3> INFO: ROC 12 VthrComp = 101
[13:30:50.663] <TB3> INFO: ROC 13 VthrComp = 87
[13:30:50.664] <TB3> INFO: ROC 14 VthrComp = 99
[13:30:50.664] <TB3> INFO: ROC 15 VthrComp = 79
[13:30:50.664] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:30:50.664] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:30:50.672] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:30:50.672] <TB3> INFO: run 1 of 1
[13:30:50.934] <TB3> INFO: Expecting 6281600 events.
[13:31:23.374] <TB3> INFO: 761820 events read in total (31883ms).
[13:31:54.366] <TB3> INFO: 1521720 events read in total (62875ms).
[13:32:25.495] <TB3> INFO: 2282210 events read in total (94004ms).
[13:32:56.850] <TB3> INFO: 3041950 events read in total (125359ms).
[13:33:27.906] <TB3> INFO: 3795520 events read in total (156415ms).
[13:33:59.045] <TB3> INFO: 4545250 events read in total (187554ms).
[13:34:30.428] <TB3> INFO: 5293390 events read in total (218937ms).
[13:35:01.534] <TB3> INFO: 6041030 events read in total (250043ms).
[13:35:11.764] <TB3> INFO: 6281600 events read in total (260273ms).
[13:35:11.813] <TB3> INFO: Test took 261140ms.
[13:35:35.331] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.6932 for pixel 8/8 mean/min/max = 45.472/33.1542/57.7897
[13:35:35.331] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.1628 for pixel 3/76 mean/min/max = 45.8804/32.1547/59.6061
[13:35:35.331] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.2766 for pixel 2/7 mean/min/max = 45.1934/33.0672/57.3196
[13:35:35.331] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.5031 for pixel 6/74 mean/min/max = 44.8298/32.0638/57.5958
[13:35:35.332] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.0951 for pixel 0/19 mean/min/max = 46.1269/33.0548/59.1991
[13:35:35.332] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.8674 for pixel 13/2 mean/min/max = 45.8117/33.7347/57.8887
[13:35:35.332] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.4282 for pixel 20/2 mean/min/max = 45.6733/33.7752/57.5715
[13:35:35.332] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.0133 for pixel 0/29 mean/min/max = 46.6698/33.2035/60.1361
[13:35:35.333] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.0281 for pixel 8/9 mean/min/max = 46.281/33.4882/59.0738
[13:35:35.333] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 58.7059 for pixel 0/3 mean/min/max = 45.9968/33.2751/58.7185
[13:35:35.333] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.0454 for pixel 0/24 mean/min/max = 45.6983/33.2457/58.1508
[13:35:35.333] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.51 for pixel 3/20 mean/min/max = 46.4767/33.4117/59.5418
[13:35:35.334] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.7985 for pixel 22/8 mean/min/max = 45.5772/32.3221/58.8323
[13:35:35.334] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 59.0269 for pixel 10/70 mean/min/max = 45.5686/32.0869/59.0503
[13:35:35.334] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.7312 for pixel 9/21 mean/min/max = 44.971/32.0498/57.8921
[13:35:35.334] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 59.7948 for pixel 21/72 mean/min/max = 47.4661/35.1354/59.7968
[13:35:35.335] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:35:35.425] <TB3> INFO: Expecting 514560 events.
[13:35:46.416] <TB3> INFO: 514560 events read in total (10424ms).
[13:35:46.423] <TB3> INFO: Expecting 514560 events.
[13:35:57.255] <TB3> INFO: 514560 events read in total (10430ms).
[13:35:57.266] <TB3> INFO: Expecting 514560 events.
[13:36:08.069] <TB3> INFO: 514560 events read in total (10400ms).
[13:36:08.081] <TB3> INFO: Expecting 514560 events.
[13:36:18.903] <TB3> INFO: 514560 events read in total (10420ms).
[13:36:18.919] <TB3> INFO: Expecting 514560 events.
[13:36:29.640] <TB3> INFO: 514560 events read in total (10319ms).
[13:36:29.656] <TB3> INFO: Expecting 514560 events.
[13:36:40.532] <TB3> INFO: 514560 events read in total (10473ms).
[13:36:40.552] <TB3> INFO: Expecting 514560 events.
[13:36:51.366] <TB3> INFO: 514560 events read in total (10411ms).
[13:36:51.393] <TB3> INFO: Expecting 514560 events.
[13:37:02.129] <TB3> INFO: 514560 events read in total (10334ms).
[13:37:02.154] <TB3> INFO: Expecting 514560 events.
[13:37:12.978] <TB3> INFO: 514560 events read in total (10422ms).
[13:37:13.007] <TB3> INFO: Expecting 514560 events.
[13:37:23.668] <TB3> INFO: 514560 events read in total (10258ms).
[13:37:23.698] <TB3> INFO: Expecting 514560 events.
[13:37:34.575] <TB3> INFO: 514560 events read in total (10475ms).
[13:37:34.608] <TB3> INFO: Expecting 514560 events.
[13:37:45.407] <TB3> INFO: 514560 events read in total (10397ms).
[13:37:45.443] <TB3> INFO: Expecting 514560 events.
[13:37:56.255] <TB3> INFO: 514560 events read in total (10409ms).
[13:37:56.295] <TB3> INFO: Expecting 514560 events.
[13:38:07.059] <TB3> INFO: 514560 events read in total (10361ms).
[13:38:07.101] <TB3> INFO: Expecting 514560 events.
[13:38:17.894] <TB3> INFO: 514560 events read in total (10391ms).
[13:38:17.939] <TB3> INFO: Expecting 514560 events.
[13:38:28.712] <TB3> INFO: 514560 events read in total (10371ms).
[13:38:28.759] <TB3> INFO: Test took 173424ms.
[13:38:29.729] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:38:29.740] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:38:29.740] <TB3> INFO: run 1 of 1
[13:38:30.038] <TB3> INFO: Expecting 6281600 events.
[13:39:01.608] <TB3> INFO: 748540 events read in total (31013ms).
[13:39:32.552] <TB3> INFO: 1495090 events read in total (61957ms).
[13:40:05.041] <TB3> INFO: 2242310 events read in total (94446ms).
[13:40:37.430] <TB3> INFO: 2988760 events read in total (126835ms).
[13:41:08.925] <TB3> INFO: 3729020 events read in total (158330ms).
[13:41:39.776] <TB3> INFO: 4465020 events read in total (189181ms).
[13:42:10.426] <TB3> INFO: 5199450 events read in total (219831ms).
[13:42:41.919] <TB3> INFO: 5932840 events read in total (251324ms).
[13:42:57.220] <TB3> INFO: 6281600 events read in total (266625ms).
[13:42:57.278] <TB3> INFO: Test took 267537ms.
[13:43:20.791] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 8.318775 .. 50.891650
[13:43:21.081] <TB3> INFO: Expecting 208000 events.
[13:43:31.077] <TB3> INFO: 208000 events read in total (9439ms).
[13:43:31.078] <TB3> INFO: Test took 10285ms.
[13:43:31.169] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 8 .. 60 (-1/-1) hits flags = 528 (plus default)
[13:43:31.178] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:43:31.178] <TB3> INFO: run 1 of 1
[13:43:31.444] <TB3> INFO: Expecting 2204800 events.
[13:44:06.049] <TB3> INFO: 914100 events read in total (34048ms).
[13:44:41.299] <TB3> INFO: 1819450 events read in total (69298ms).
[13:44:55.979] <TB3> INFO: 2204800 events read in total (83978ms).
[13:44:56.003] <TB3> INFO: Test took 84825ms.
[13:45:09.774] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 22.944727 .. 45.528504
[13:45:10.041] <TB3> INFO: Expecting 208000 events.
[13:45:19.901] <TB3> INFO: 208000 events read in total (9303ms).
[13:45:19.902] <TB3> INFO: Test took 10125ms.
[13:45:19.994] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 12 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:45:20.003] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:45:20.003] <TB3> INFO: run 1 of 1
[13:45:20.273] <TB3> INFO: Expecting 1830400 events.
[13:45:54.943] <TB3> INFO: 916150 events read in total (34113ms).
[13:46:30.080] <TB3> INFO: 1829840 events read in total (69250ms).
[13:46:30.505] <TB3> INFO: 1830400 events read in total (69675ms).
[13:46:30.524] <TB3> INFO: Test took 70521ms.
[13:46:45.014] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 22.941034 .. 42.960001
[13:46:45.285] <TB3> INFO: Expecting 208000 events.
[13:46:55.258] <TB3> INFO: 208000 events read in total (9416ms).
[13:46:55.259] <TB3> INFO: Test took 10242ms.
[13:46:55.347] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:46:55.356] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:46:55.356] <TB3> INFO: run 1 of 1
[13:46:55.625] <TB3> INFO: Expecting 1705600 events.
[13:47:30.628] <TB3> INFO: 942030 events read in total (34446ms).
[13:47:58.690] <TB3> INFO: 1705600 events read in total (62508ms).
[13:47:58.709] <TB3> INFO: Test took 63354ms.
[13:48:12.164] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.425258 .. 42.960001
[13:48:12.435] <TB3> INFO: Expecting 208000 events.
[13:48:22.370] <TB3> INFO: 208000 events read in total (9378ms).
[13:48:22.370] <TB3> INFO: Test took 10203ms.
[13:48:22.462] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:48:22.471] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:48:22.471] <TB3> INFO: run 1 of 1
[13:48:22.785] <TB3> INFO: Expecting 1664000 events.
[13:48:57.724] <TB3> INFO: 936200 events read in total (34383ms).
[13:49:24.289] <TB3> INFO: 1664000 events read in total (60948ms).
[13:49:24.307] <TB3> INFO: Test took 61837ms.
[13:49:37.854] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:49:37.854] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:49:37.862] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:49:37.862] <TB3> INFO: run 1 of 1
[13:49:38.123] <TB3> INFO: Expecting 1705600 events.
[13:50:12.436] <TB3> INFO: 894500 events read in total (33756ms).
[13:50:42.918] <TB3> INFO: 1705600 events read in total (64238ms).
[13:50:42.937] <TB3> INFO: Test took 65074ms.
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:50:57.083] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:50:57.084] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:50:57.084] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C0.dat
[13:50:57.093] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C1.dat
[13:50:57.102] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C2.dat
[13:50:57.110] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C3.dat
[13:50:57.119] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C4.dat
[13:50:57.125] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C5.dat
[13:50:57.131] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C6.dat
[13:50:57.137] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C7.dat
[13:50:57.143] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C8.dat
[13:50:57.149] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C9.dat
[13:50:57.155] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C10.dat
[13:50:57.161] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C11.dat
[13:50:57.167] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C12.dat
[13:50:57.173] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C13.dat
[13:50:57.179] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C14.dat
[13:50:57.185] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C15.dat
[13:50:57.192] <TB3> INFO: PixTestTrim::trimTest() done
[13:50:57.192] <TB3> INFO: vtrim: 105 110 102 105 99 106 100 96 102 87 92 100 113 97 95 95
[13:50:57.192] <TB3> INFO: vthrcomp: 93 98 103 102 92 89 91 92 93 91 90 89 101 87 99 79
[13:50:57.192] <TB3> INFO: vcal mean: 34.99 34.96 34.95 34.97 34.98 34.98 35.00 35.00 34.80 34.98 34.98 34.95 34.96 34.94 34.94 34.99
[13:50:57.192] <TB3> INFO: vcal RMS: 0.81 0.86 0.86 0.84 0.86 0.81 0.79 0.81 0.86 0.83 0.75 0.84 0.90 0.84 0.86 0.83
[13:50:57.192] <TB3> INFO: bits mean: 9.52 9.58 9.81 9.76 8.89 9.46 9.40 8.52 8.97 9.38 8.71 9.11 9.87 9.50 9.91 8.60
[13:50:57.192] <TB3> INFO: bits RMS: 2.52 2.68 2.48 2.70 2.81 2.49 2.55 2.86 2.65 2.55 2.85 2.65 2.50 2.71 2.57 2.48
[13:50:57.200] <TB3> INFO: ----------------------------------------------------------------------
[13:50:57.200] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:50:57.200] <TB3> INFO: ----------------------------------------------------------------------
[13:50:57.204] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:50:57.212] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:50:57.212] <TB3> INFO: run 1 of 1
[13:50:57.475] <TB3> INFO: Expecting 4160000 events.
[13:51:35.966] <TB3> INFO: 948360 events read in total (37934ms).
[13:52:13.309] <TB3> INFO: 1890855 events read in total (75277ms).
[13:52:51.241] <TB3> INFO: 2823750 events read in total (113209ms).
[13:53:29.774] <TB3> INFO: 3751870 events read in total (151742ms).
[13:53:47.762] <TB3> INFO: 4160000 events read in total (169730ms).
[13:53:47.815] <TB3> INFO: Test took 170602ms.
[13:54:16.985] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[13:54:16.993] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:54:16.993] <TB3> INFO: run 1 of 1
[13:54:17.257] <TB3> INFO: Expecting 3473600 events.
[13:54:56.775] <TB3> INFO: 990065 events read in total (38961ms).
[13:55:35.433] <TB3> INFO: 1970795 events read in total (77619ms).
[13:56:14.549] <TB3> INFO: 2938985 events read in total (116735ms).
[13:56:36.370] <TB3> INFO: 3473600 events read in total (138556ms).
[13:56:36.423] <TB3> INFO: Test took 139430ms.
[13:57:02.686] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[13:57:02.694] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:57:02.694] <TB3> INFO: run 1 of 1
[13:57:02.958] <TB3> INFO: Expecting 3224000 events.
[13:57:43.345] <TB3> INFO: 1028280 events read in total (39830ms).
[13:58:22.970] <TB3> INFO: 2043250 events read in total (79455ms).
[13:59:02.630] <TB3> INFO: 3045815 events read in total (119115ms).
[13:59:09.925] <TB3> INFO: 3224000 events read in total (126410ms).
[13:59:09.969] <TB3> INFO: Test took 127275ms.
[13:59:34.023] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[13:59:34.031] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:59:34.031] <TB3> INFO: run 1 of 1
[13:59:34.293] <TB3> INFO: Expecting 3203200 events.
[14:00:14.480] <TB3> INFO: 1030600 events read in total (39630ms).
[14:00:54.070] <TB3> INFO: 2047385 events read in total (79220ms).
[14:01:33.073] <TB3> INFO: 3052225 events read in total (118223ms).
[14:01:39.257] <TB3> INFO: 3203200 events read in total (124407ms).
[14:01:39.300] <TB3> INFO: Test took 125270ms.
[14:02:04.336] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[14:02:04.345] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:02:04.345] <TB3> INFO: run 1 of 1
[14:02:04.658] <TB3> INFO: Expecting 3224000 events.
[14:02:45.370] <TB3> INFO: 1026630 events read in total (40155ms).
[14:03:24.434] <TB3> INFO: 2039745 events read in total (79219ms).
[14:04:03.862] <TB3> INFO: 3041000 events read in total (118647ms).
[14:04:11.426] <TB3> INFO: 3224000 events read in total (126211ms).
[14:04:11.472] <TB3> INFO: Test took 127126ms.
[14:04:35.959] <TB3> INFO: PixTestTrim::trimBitTest() done
[14:04:35.960] <TB3> INFO: PixTestTrim::doTest() done, duration: 2252 seconds
[14:04:35.960] <TB3> INFO: Decoding statistics:
[14:04:35.960] <TB3> INFO: General information:
[14:04:35.960] <TB3> INFO: 16bit words read: 0
[14:04:35.960] <TB3> INFO: valid events total: 0
[14:04:35.960] <TB3> INFO: empty events: 0
[14:04:35.960] <TB3> INFO: valid events with pixels: 0
[14:04:35.960] <TB3> INFO: valid pixel hits: 0
[14:04:35.960] <TB3> INFO: Event errors: 0
[14:04:35.960] <TB3> INFO: start marker: 0
[14:04:35.960] <TB3> INFO: stop marker: 0
[14:04:35.960] <TB3> INFO: overflow: 0
[14:04:35.960] <TB3> INFO: invalid 5bit words: 0
[14:04:35.960] <TB3> INFO: invalid XOR eye diagram: 0
[14:04:35.960] <TB3> INFO: frame (failed synchr.): 0
[14:04:35.960] <TB3> INFO: idle data (no TBM trl): 0
[14:04:35.960] <TB3> INFO: no data (only TBM hdr): 0
[14:04:35.960] <TB3> INFO: TBM errors: 0
[14:04:35.960] <TB3> INFO: flawed TBM headers: 0
[14:04:35.960] <TB3> INFO: flawed TBM trailers: 0
[14:04:35.960] <TB3> INFO: event ID mismatches: 0
[14:04:35.960] <TB3> INFO: ROC errors: 0
[14:04:35.960] <TB3> INFO: missing ROC header(s): 0
[14:04:35.960] <TB3> INFO: misplaced readback start: 0
[14:04:35.960] <TB3> INFO: Pixel decoding errors: 0
[14:04:35.960] <TB3> INFO: pixel data incomplete: 0
[14:04:35.960] <TB3> INFO: pixel address: 0
[14:04:35.960] <TB3> INFO: pulse height fill bit: 0
[14:04:35.960] <TB3> INFO: buffer corruption: 0
[14:04:36.710] <TB3> INFO: ######################################################################
[14:04:36.710] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:04:36.710] <TB3> INFO: ######################################################################
[14:04:36.999] <TB3> INFO: Expecting 41600 events.
[14:04:40.478] <TB3> INFO: 41600 events read in total (2922ms).
[14:04:40.479] <TB3> INFO: Test took 3768ms.
[14:04:40.946] <TB3> INFO: Expecting 41600 events.
[14:04:44.457] <TB3> INFO: 41600 events read in total (2954ms).
[14:04:44.457] <TB3> INFO: Test took 3780ms.
[14:04:44.787] <TB3> INFO: Expecting 41600 events.
[14:04:48.284] <TB3> INFO: 41600 events read in total (2941ms).
[14:04:48.285] <TB3> INFO: Test took 3801ms.
[14:04:48.541] <TB3> INFO: Expecting 2560 events.
[14:04:49.397] <TB3> INFO: 2560 events read in total (298ms).
[14:04:49.397] <TB3> INFO: Test took 1098ms.
[14:04:49.703] <TB3> INFO: Expecting 2560 events.
[14:04:50.557] <TB3> INFO: 2560 events read in total (297ms).
[14:04:50.557] <TB3> INFO: Test took 1159ms.
[14:04:50.863] <TB3> INFO: Expecting 2560 events.
[14:04:51.717] <TB3> INFO: 2560 events read in total (297ms).
[14:04:51.717] <TB3> INFO: Test took 1159ms.
[14:04:52.022] <TB3> INFO: Expecting 2560 events.
[14:04:52.876] <TB3> INFO: 2560 events read in total (297ms).
[14:04:52.876] <TB3> INFO: Test took 1159ms.
[14:04:53.182] <TB3> INFO: Expecting 2560 events.
[14:04:54.036] <TB3> INFO: 2560 events read in total (297ms).
[14:04:54.036] <TB3> INFO: Test took 1159ms.
[14:04:54.342] <TB3> INFO: Expecting 2560 events.
[14:04:55.197] <TB3> INFO: 2560 events read in total (298ms).
[14:04:55.197] <TB3> INFO: Test took 1160ms.
[14:04:55.503] <TB3> INFO: Expecting 2560 events.
[14:04:56.360] <TB3> INFO: 2560 events read in total (300ms).
[14:04:56.360] <TB3> INFO: Test took 1162ms.
[14:04:56.665] <TB3> INFO: Expecting 2560 events.
[14:04:57.523] <TB3> INFO: 2560 events read in total (301ms).
[14:04:57.523] <TB3> INFO: Test took 1162ms.
[14:04:57.829] <TB3> INFO: Expecting 2560 events.
[14:04:58.686] <TB3> INFO: 2560 events read in total (301ms).
[14:04:58.686] <TB3> INFO: Test took 1162ms.
[14:04:58.992] <TB3> INFO: Expecting 2560 events.
[14:04:59.845] <TB3> INFO: 2560 events read in total (297ms).
[14:04:59.845] <TB3> INFO: Test took 1159ms.
[14:05:00.151] <TB3> INFO: Expecting 2560 events.
[14:05:01.008] <TB3> INFO: 2560 events read in total (301ms).
[14:05:01.009] <TB3> INFO: Test took 1162ms.
[14:05:01.315] <TB3> INFO: Expecting 2560 events.
[14:05:02.173] <TB3> INFO: 2560 events read in total (302ms).
[14:05:02.173] <TB3> INFO: Test took 1164ms.
[14:05:02.478] <TB3> INFO: Expecting 2560 events.
[14:05:03.332] <TB3> INFO: 2560 events read in total (297ms).
[14:05:03.332] <TB3> INFO: Test took 1159ms.
[14:05:03.638] <TB3> INFO: Expecting 2560 events.
[14:05:04.492] <TB3> INFO: 2560 events read in total (298ms).
[14:05:04.492] <TB3> INFO: Test took 1160ms.
[14:05:04.798] <TB3> INFO: Expecting 2560 events.
[14:05:05.652] <TB3> INFO: 2560 events read in total (297ms).
[14:05:05.652] <TB3> INFO: Test took 1160ms.
[14:05:05.958] <TB3> INFO: Expecting 2560 events.
[14:05:06.811] <TB3> INFO: 2560 events read in total (297ms).
[14:05:06.811] <TB3> INFO: Test took 1152ms.
[14:05:06.816] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:05:07.118] <TB3> INFO: Expecting 655360 events.
[14:05:21.186] <TB3> INFO: 655360 events read in total (13511ms).
[14:05:21.198] <TB3> INFO: Expecting 655360 events.
[14:05:35.014] <TB3> INFO: 655360 events read in total (13413ms).
[14:05:35.029] <TB3> INFO: Expecting 655360 events.
[14:05:48.973] <TB3> INFO: 655360 events read in total (13541ms).
[14:05:48.993] <TB3> INFO: Expecting 655360 events.
[14:06:02.834] <TB3> INFO: 655360 events read in total (13439ms).
[14:06:02.858] <TB3> INFO: Expecting 655360 events.
[14:06:16.893] <TB3> INFO: 655360 events read in total (13632ms).
[14:06:16.920] <TB3> INFO: Expecting 655360 events.
[14:06:30.866] <TB3> INFO: 655360 events read in total (13544ms).
[14:06:30.900] <TB3> INFO: Expecting 655360 events.
[14:06:44.880] <TB3> INFO: 655360 events read in total (13578ms).
[14:06:44.916] <TB3> INFO: Expecting 655360 events.
[14:06:58.867] <TB3> INFO: 655360 events read in total (13548ms).
[14:06:58.908] <TB3> INFO: Expecting 655360 events.
[14:07:12.792] <TB3> INFO: 655360 events read in total (13481ms).
[14:07:12.836] <TB3> INFO: Expecting 655360 events.
[14:07:26.830] <TB3> INFO: 655360 events read in total (13591ms).
[14:07:26.877] <TB3> INFO: Expecting 655360 events.
[14:07:40.769] <TB3> INFO: 655360 events read in total (13489ms).
[14:07:40.830] <TB3> INFO: Expecting 655360 events.
[14:07:54.701] <TB3> INFO: 655360 events read in total (13468ms).
[14:07:54.756] <TB3> INFO: Expecting 655360 events.
[14:08:08.701] <TB3> INFO: 655360 events read in total (13543ms).
[14:08:08.766] <TB3> INFO: Expecting 655360 events.
[14:08:22.700] <TB3> INFO: 655360 events read in total (13532ms).
[14:08:22.766] <TB3> INFO: Expecting 655360 events.
[14:08:36.730] <TB3> INFO: 655360 events read in total (13562ms).
[14:08:36.800] <TB3> INFO: Expecting 655360 events.
[14:08:50.634] <TB3> INFO: 655360 events read in total (13432ms).
[14:08:50.710] <TB3> INFO: Test took 223894ms.
[14:08:50.795] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:08:51.023] <TB3> INFO: Expecting 655360 events.
[14:09:05.216] <TB3> INFO: 655360 events read in total (13636ms).
[14:09:05.227] <TB3> INFO: Expecting 655360 events.
[14:09:19.107] <TB3> INFO: 655360 events read in total (13477ms).
[14:09:19.122] <TB3> INFO: Expecting 655360 events.
[14:09:33.110] <TB3> INFO: 655360 events read in total (13585ms).
[14:09:33.132] <TB3> INFO: Expecting 655360 events.
[14:09:46.988] <TB3> INFO: 655360 events read in total (13454ms).
[14:09:47.011] <TB3> INFO: Expecting 655360 events.
[14:10:00.800] <TB3> INFO: 655360 events read in total (13387ms).
[14:10:00.828] <TB3> INFO: Expecting 655360 events.
[14:10:14.707] <TB3> INFO: 655360 events read in total (13477ms).
[14:10:14.746] <TB3> INFO: Expecting 655360 events.
[14:10:28.769] <TB3> INFO: 655360 events read in total (13620ms).
[14:10:28.807] <TB3> INFO: Expecting 655360 events.
[14:10:42.734] <TB3> INFO: 655360 events read in total (13525ms).
[14:10:42.775] <TB3> INFO: Expecting 655360 events.
[14:10:56.531] <TB3> INFO: 655360 events read in total (13353ms).
[14:10:56.577] <TB3> INFO: Expecting 655360 events.
[14:11:10.488] <TB3> INFO: 655360 events read in total (13509ms).
[14:11:10.538] <TB3> INFO: Expecting 655360 events.
[14:11:24.441] <TB3> INFO: 655360 events read in total (13501ms).
[14:11:24.494] <TB3> INFO: Expecting 655360 events.
[14:11:38.349] <TB3> INFO: 655360 events read in total (13454ms).
[14:11:38.407] <TB3> INFO: Expecting 655360 events.
[14:11:52.351] <TB3> INFO: 655360 events read in total (13542ms).
[14:11:52.414] <TB3> INFO: Expecting 655360 events.
[14:12:06.326] <TB3> INFO: 655360 events read in total (13510ms).
[14:12:06.402] <TB3> INFO: Expecting 655360 events.
[14:12:20.296] <TB3> INFO: 655360 events read in total (13492ms).
[14:12:20.367] <TB3> INFO: Expecting 655360 events.
[14:12:34.326] <TB3> INFO: 655360 events read in total (13557ms).
[14:12:34.410] <TB3> INFO: Test took 223615ms.
[14:12:34.609] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.617] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:12:34.624] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:12:34.631] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:12:34.639] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[14:12:34.646] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[14:12:34.653] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.660] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.668] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:12:34.675] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:12:34.682] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.689] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.696] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.703] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.710] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.718] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.725] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.732] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:12:34.739] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:12:34.746] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:12:34.753] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[14:12:34.760] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.767] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.774] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.781] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.788] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.796] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C0.dat
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C1.dat
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C2.dat
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C3.dat
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C4.dat
[14:12:34.851] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C5.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C6.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C7.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C8.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C9.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C10.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C11.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C12.dat
[14:12:34.852] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C13.dat
[14:12:34.853] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C14.dat
[14:12:34.853] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C15.dat
[14:12:35.126] <TB3> INFO: Expecting 41600 events.
[14:12:38.278] <TB3> INFO: 41600 events read in total (2595ms).
[14:12:38.279] <TB3> INFO: Test took 3422ms.
[14:12:38.773] <TB3> INFO: Expecting 41600 events.
[14:12:41.887] <TB3> INFO: 41600 events read in total (2558ms).
[14:12:41.887] <TB3> INFO: Test took 3391ms.
[14:12:42.361] <TB3> INFO: Expecting 41600 events.
[14:12:45.471] <TB3> INFO: 41600 events read in total (2554ms).
[14:12:45.472] <TB3> INFO: Test took 3396ms.
[14:12:45.664] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:45.754] <TB3> INFO: Expecting 2560 events.
[14:12:46.616] <TB3> INFO: 2560 events read in total (305ms).
[14:12:46.616] <TB3> INFO: Test took 952ms.
[14:12:46.619] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:46.922] <TB3> INFO: Expecting 2560 events.
[14:12:47.778] <TB3> INFO: 2560 events read in total (299ms).
[14:12:47.778] <TB3> INFO: Test took 1159ms.
[14:12:47.781] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:48.083] <TB3> INFO: Expecting 2560 events.
[14:12:48.939] <TB3> INFO: 2560 events read in total (300ms).
[14:12:48.940] <TB3> INFO: Test took 1159ms.
[14:12:48.943] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:49.245] <TB3> INFO: Expecting 2560 events.
[14:12:50.099] <TB3> INFO: 2560 events read in total (297ms).
[14:12:50.099] <TB3> INFO: Test took 1156ms.
[14:12:50.102] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:50.405] <TB3> INFO: Expecting 2560 events.
[14:12:51.265] <TB3> INFO: 2560 events read in total (303ms).
[14:12:51.266] <TB3> INFO: Test took 1164ms.
[14:12:51.269] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:51.571] <TB3> INFO: Expecting 2560 events.
[14:12:52.425] <TB3> INFO: 2560 events read in total (297ms).
[14:12:52.425] <TB3> INFO: Test took 1156ms.
[14:12:52.428] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:52.732] <TB3> INFO: Expecting 2560 events.
[14:12:53.586] <TB3> INFO: 2560 events read in total (298ms).
[14:12:53.586] <TB3> INFO: Test took 1159ms.
[14:12:53.588] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:53.892] <TB3> INFO: Expecting 2560 events.
[14:12:54.748] <TB3> INFO: 2560 events read in total (299ms).
[14:12:54.749] <TB3> INFO: Test took 1161ms.
[14:12:54.752] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:55.055] <TB3> INFO: Expecting 2560 events.
[14:12:55.915] <TB3> INFO: 2560 events read in total (303ms).
[14:12:55.916] <TB3> INFO: Test took 1164ms.
[14:12:55.919] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:56.221] <TB3> INFO: Expecting 2560 events.
[14:12:57.078] <TB3> INFO: 2560 events read in total (300ms).
[14:12:57.078] <TB3> INFO: Test took 1159ms.
[14:12:57.081] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:57.386] <TB3> INFO: Expecting 2560 events.
[14:12:58.240] <TB3> INFO: 2560 events read in total (298ms).
[14:12:58.240] <TB3> INFO: Test took 1159ms.
[14:12:58.244] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:58.547] <TB3> INFO: Expecting 2560 events.
[14:12:59.410] <TB3> INFO: 2560 events read in total (306ms).
[14:12:59.410] <TB3> INFO: Test took 1166ms.
[14:12:59.414] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:59.718] <TB3> INFO: Expecting 2560 events.
[14:13:00.576] <TB3> INFO: 2560 events read in total (302ms).
[14:13:00.577] <TB3> INFO: Test took 1163ms.
[14:13:00.581] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:00.883] <TB3> INFO: Expecting 2560 events.
[14:13:01.745] <TB3> INFO: 2560 events read in total (305ms).
[14:13:01.745] <TB3> INFO: Test took 1164ms.
[14:13:01.749] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:02.052] <TB3> INFO: Expecting 2560 events.
[14:13:02.914] <TB3> INFO: 2560 events read in total (305ms).
[14:13:02.914] <TB3> INFO: Test took 1165ms.
[14:13:02.920] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:03.220] <TB3> INFO: Expecting 2560 events.
[14:13:04.074] <TB3> INFO: 2560 events read in total (297ms).
[14:13:04.074] <TB3> INFO: Test took 1155ms.
[14:13:04.077] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:04.382] <TB3> INFO: Expecting 2560 events.
[14:13:05.236] <TB3> INFO: 2560 events read in total (298ms).
[14:13:05.236] <TB3> INFO: Test took 1159ms.
[14:13:05.239] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:05.542] <TB3> INFO: Expecting 2560 events.
[14:13:06.396] <TB3> INFO: 2560 events read in total (297ms).
[14:13:06.397] <TB3> INFO: Test took 1158ms.
[14:13:06.399] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:06.704] <TB3> INFO: Expecting 2560 events.
[14:13:07.559] <TB3> INFO: 2560 events read in total (298ms).
[14:13:07.559] <TB3> INFO: Test took 1160ms.
[14:13:07.562] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:07.865] <TB3> INFO: Expecting 2560 events.
[14:13:08.723] <TB3> INFO: 2560 events read in total (301ms).
[14:13:08.723] <TB3> INFO: Test took 1161ms.
[14:13:08.727] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:09.029] <TB3> INFO: Expecting 2560 events.
[14:13:09.883] <TB3> INFO: 2560 events read in total (297ms).
[14:13:09.883] <TB3> INFO: Test took 1156ms.
[14:13:09.886] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:10.190] <TB3> INFO: Expecting 2560 events.
[14:13:11.051] <TB3> INFO: 2560 events read in total (304ms).
[14:13:11.051] <TB3> INFO: Test took 1165ms.
[14:13:11.054] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:11.359] <TB3> INFO: Expecting 2560 events.
[14:13:12.215] <TB3> INFO: 2560 events read in total (300ms).
[14:13:12.216] <TB3> INFO: Test took 1162ms.
[14:13:12.220] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:12.521] <TB3> INFO: Expecting 2560 events.
[14:13:13.375] <TB3> INFO: 2560 events read in total (297ms).
[14:13:13.376] <TB3> INFO: Test took 1157ms.
[14:13:13.379] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:13.682] <TB3> INFO: Expecting 2560 events.
[14:13:14.538] <TB3> INFO: 2560 events read in total (299ms).
[14:13:14.538] <TB3> INFO: Test took 1160ms.
[14:13:14.542] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:14.845] <TB3> INFO: Expecting 2560 events.
[14:13:15.704] <TB3> INFO: 2560 events read in total (302ms).
[14:13:15.705] <TB3> INFO: Test took 1163ms.
[14:13:15.710] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:16.012] <TB3> INFO: Expecting 2560 events.
[14:13:16.875] <TB3> INFO: 2560 events read in total (306ms).
[14:13:16.875] <TB3> INFO: Test took 1166ms.
[14:13:16.879] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:17.180] <TB3> INFO: Expecting 2560 events.
[14:13:18.036] <TB3> INFO: 2560 events read in total (299ms).
[14:13:18.037] <TB3> INFO: Test took 1158ms.
[14:13:18.039] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:18.344] <TB3> INFO: Expecting 2560 events.
[14:13:19.203] <TB3> INFO: 2560 events read in total (302ms).
[14:13:19.204] <TB3> INFO: Test took 1165ms.
[14:13:19.206] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:19.511] <TB3> INFO: Expecting 2560 events.
[14:13:20.366] <TB3> INFO: 2560 events read in total (298ms).
[14:13:20.366] <TB3> INFO: Test took 1160ms.
[14:13:20.370] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:20.676] <TB3> INFO: Expecting 2560 events.
[14:13:21.530] <TB3> INFO: 2560 events read in total (297ms).
[14:13:21.530] <TB3> INFO: Test took 1160ms.
[14:13:21.533] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:13:21.837] <TB3> INFO: Expecting 2560 events.
[14:13:22.692] <TB3> INFO: 2560 events read in total (298ms).
[14:13:22.692] <TB3> INFO: Test took 1159ms.
[14:13:23.197] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 526 seconds
[14:13:23.197] <TB3> INFO: PH scale (per ROC): 76 79 77 84 65 78 78 80 80 69 76 73 67 73 67 73
[14:13:23.197] <TB3> INFO: PH offset (per ROC): 176 177 176 160 181 176 172 176 166 176 173 174 190 171 181 170
[14:13:23.205] <TB3> INFO: Decoding statistics:
[14:13:23.206] <TB3> INFO: General information:
[14:13:23.206] <TB3> INFO: 16bit words read: 86922
[14:13:23.206] <TB3> INFO: valid events total: 10240
[14:13:23.206] <TB3> INFO: empty events: 7739
[14:13:23.206] <TB3> INFO: valid events with pixels: 2501
[14:13:23.206] <TB3> INFO: valid pixel hits: 2501
[14:13:23.206] <TB3> INFO: Event errors: 0
[14:13:23.206] <TB3> INFO: start marker: 0
[14:13:23.206] <TB3> INFO: stop marker: 0
[14:13:23.206] <TB3> INFO: overflow: 0
[14:13:23.206] <TB3> INFO: invalid 5bit words: 0
[14:13:23.206] <TB3> INFO: invalid XOR eye diagram: 0
[14:13:23.206] <TB3> INFO: frame (failed synchr.): 0
[14:13:23.206] <TB3> INFO: idle data (no TBM trl): 0
[14:13:23.206] <TB3> INFO: no data (only TBM hdr): 0
[14:13:23.206] <TB3> INFO: TBM errors: 0
[14:13:23.206] <TB3> INFO: flawed TBM headers: 0
[14:13:23.206] <TB3> INFO: flawed TBM trailers: 0
[14:13:23.206] <TB3> INFO: event ID mismatches: 0
[14:13:23.206] <TB3> INFO: ROC errors: 0
[14:13:23.206] <TB3> INFO: missing ROC header(s): 0
[14:13:23.206] <TB3> INFO: misplaced readback start: 0
[14:13:23.206] <TB3> INFO: Pixel decoding errors: 0
[14:13:23.206] <TB3> INFO: pixel data incomplete: 0
[14:13:23.206] <TB3> INFO: pixel address: 0
[14:13:23.206] <TB3> INFO: pulse height fill bit: 0
[14:13:23.206] <TB3> INFO: buffer corruption: 0
[14:13:23.405] <TB3> INFO: ######################################################################
[14:13:23.405] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:13:23.405] <TB3> INFO: ######################################################################
[14:13:23.416] <TB3> INFO: scanning low vcal = 10
[14:13:23.695] <TB3> INFO: Expecting 41600 events.
[14:13:27.094] <TB3> INFO: 41600 events read in total (2843ms).
[14:13:27.095] <TB3> INFO: Test took 3679ms.
[14:13:27.097] <TB3> INFO: scanning low vcal = 20
[14:13:27.387] <TB3> INFO: Expecting 41600 events.
[14:13:30.789] <TB3> INFO: 41600 events read in total (2845ms).
[14:13:30.789] <TB3> INFO: Test took 3692ms.
[14:13:30.792] <TB3> INFO: scanning low vcal = 30
[14:13:31.088] <TB3> INFO: Expecting 41600 events.
[14:13:34.501] <TB3> INFO: 41600 events read in total (2856ms).
[14:13:34.501] <TB3> INFO: Test took 3709ms.
[14:13:34.504] <TB3> INFO: scanning low vcal = 40
[14:13:34.796] <TB3> INFO: Expecting 41600 events.
[14:13:38.684] <TB3> INFO: 41600 events read in total (3332ms).
[14:13:38.684] <TB3> INFO: Test took 4180ms.
[14:13:38.688] <TB3> INFO: scanning low vcal = 50
[14:13:38.961] <TB3> INFO: Expecting 41600 events.
[14:13:42.943] <TB3> INFO: 41600 events read in total (3425ms).
[14:13:42.944] <TB3> INFO: Test took 4256ms.
[14:13:42.947] <TB3> INFO: scanning low vcal = 60
[14:13:43.218] <TB3> INFO: Expecting 41600 events.
[14:13:47.200] <TB3> INFO: 41600 events read in total (3426ms).
[14:13:47.201] <TB3> INFO: Test took 4254ms.
[14:13:47.204] <TB3> INFO: scanning low vcal = 70
[14:13:47.467] <TB3> INFO: Expecting 41600 events.
[14:13:51.427] <TB3> INFO: 41600 events read in total (3403ms).
[14:13:51.427] <TB3> INFO: Test took 4222ms.
[14:13:51.431] <TB3> INFO: scanning low vcal = 80
[14:13:51.698] <TB3> INFO: Expecting 41600 events.
[14:13:55.671] <TB3> INFO: 41600 events read in total (3417ms).
[14:13:55.672] <TB3> INFO: Test took 4241ms.
[14:13:55.682] <TB3> INFO: scanning low vcal = 90
[14:13:55.964] <TB3> INFO: Expecting 41600 events.
[14:13:59.933] <TB3> INFO: 41600 events read in total (3412ms).
[14:13:59.934] <TB3> INFO: Test took 4252ms.
[14:13:59.938] <TB3> INFO: scanning low vcal = 100
[14:14:00.206] <TB3> INFO: Expecting 41600 events.
[14:14:04.206] <TB3> INFO: 41600 events read in total (3443ms).
[14:14:04.207] <TB3> INFO: Test took 4269ms.
[14:14:04.210] <TB3> INFO: scanning low vcal = 110
[14:14:04.493] <TB3> INFO: Expecting 41600 events.
[14:14:08.500] <TB3> INFO: 41600 events read in total (3451ms).
[14:14:08.500] <TB3> INFO: Test took 4290ms.
[14:14:08.504] <TB3> INFO: scanning low vcal = 120
[14:14:08.787] <TB3> INFO: Expecting 41600 events.
[14:14:12.781] <TB3> INFO: 41600 events read in total (3438ms).
[14:14:12.781] <TB3> INFO: Test took 4277ms.
[14:14:12.785] <TB3> INFO: scanning low vcal = 130
[14:14:13.053] <TB3> INFO: Expecting 41600 events.
[14:14:17.027] <TB3> INFO: 41600 events read in total (3418ms).
[14:14:17.028] <TB3> INFO: Test took 4243ms.
[14:14:17.032] <TB3> INFO: scanning low vcal = 140
[14:14:17.299] <TB3> INFO: Expecting 41600 events.
[14:14:21.276] <TB3> INFO: 41600 events read in total (3420ms).
[14:14:21.276] <TB3> INFO: Test took 4245ms.
[14:14:21.280] <TB3> INFO: scanning low vcal = 150
[14:14:21.544] <TB3> INFO: Expecting 41600 events.
[14:14:25.507] <TB3> INFO: 41600 events read in total (3406ms).
[14:14:25.508] <TB3> INFO: Test took 4228ms.
[14:14:25.511] <TB3> INFO: scanning low vcal = 160
[14:14:25.792] <TB3> INFO: Expecting 41600 events.
[14:14:29.717] <TB3> INFO: 41600 events read in total (3368ms).
[14:14:29.718] <TB3> INFO: Test took 4207ms.
[14:14:29.721] <TB3> INFO: scanning low vcal = 170
[14:14:29.997] <TB3> INFO: Expecting 41600 events.
[14:14:33.933] <TB3> INFO: 41600 events read in total (3379ms).
[14:14:33.934] <TB3> INFO: Test took 4213ms.
[14:14:33.939] <TB3> INFO: scanning low vcal = 180
[14:14:34.219] <TB3> INFO: Expecting 41600 events.
[14:14:38.153] <TB3> INFO: 41600 events read in total (3377ms).
[14:14:38.153] <TB3> INFO: Test took 4214ms.
[14:14:38.157] <TB3> INFO: scanning low vcal = 190
[14:14:38.423] <TB3> INFO: Expecting 41600 events.
[14:14:42.376] <TB3> INFO: 41600 events read in total (3397ms).
[14:14:42.377] <TB3> INFO: Test took 4220ms.
[14:14:42.381] <TB3> INFO: scanning low vcal = 200
[14:14:42.645] <TB3> INFO: Expecting 41600 events.
[14:14:46.582] <TB3> INFO: 41600 events read in total (3380ms).
[14:14:46.582] <TB3> INFO: Test took 4201ms.
[14:14:46.587] <TB3> INFO: scanning low vcal = 210
[14:14:46.852] <TB3> INFO: Expecting 41600 events.
[14:14:50.775] <TB3> INFO: 41600 events read in total (3367ms).
[14:14:50.776] <TB3> INFO: Test took 4189ms.
[14:14:50.781] <TB3> INFO: scanning low vcal = 220
[14:14:51.067] <TB3> INFO: Expecting 41600 events.
[14:14:55.015] <TB3> INFO: 41600 events read in total (3391ms).
[14:14:55.016] <TB3> INFO: Test took 4235ms.
[14:14:55.019] <TB3> INFO: scanning low vcal = 230
[14:14:55.361] <TB3> INFO: Expecting 41600 events.
[14:14:59.343] <TB3> INFO: 41600 events read in total (3425ms).
[14:14:59.344] <TB3> INFO: Test took 4324ms.
[14:14:59.348] <TB3> INFO: scanning low vcal = 240
[14:14:59.610] <TB3> INFO: Expecting 41600 events.
[14:15:03.556] <TB3> INFO: 41600 events read in total (3389ms).
[14:15:03.558] <TB3> INFO: Test took 4210ms.
[14:15:03.562] <TB3> INFO: scanning low vcal = 250
[14:15:03.839] <TB3> INFO: Expecting 41600 events.
[14:15:07.772] <TB3> INFO: 41600 events read in total (3376ms).
[14:15:07.772] <TB3> INFO: Test took 4210ms.
[14:15:07.782] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[14:15:08.046] <TB3> INFO: Expecting 41600 events.
[14:15:12.005] <TB3> INFO: 41600 events read in total (3403ms).
[14:15:12.006] <TB3> INFO: Test took 4223ms.
[14:15:12.010] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[14:15:12.282] <TB3> INFO: Expecting 41600 events.
[14:15:16.230] <TB3> INFO: 41600 events read in total (3391ms).
[14:15:16.230] <TB3> INFO: Test took 4220ms.
[14:15:16.234] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[14:15:16.505] <TB3> INFO: Expecting 41600 events.
[14:15:20.428] <TB3> INFO: 41600 events read in total (3366ms).
[14:15:20.429] <TB3> INFO: Test took 4194ms.
[14:15:20.433] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[14:15:20.699] <TB3> INFO: Expecting 41600 events.
[14:15:24.622] <TB3> INFO: 41600 events read in total (3367ms).
[14:15:24.622] <TB3> INFO: Test took 4189ms.
[14:15:24.626] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:15:24.892] <TB3> INFO: Expecting 41600 events.
[14:15:28.843] <TB3> INFO: 41600 events read in total (3394ms).
[14:15:28.844] <TB3> INFO: Test took 4218ms.
[14:15:29.359] <TB3> INFO: PixTestGainPedestal::measure() done
[14:16:01.309] <TB3> INFO: PixTestGainPedestal::fit() done
[14:16:01.309] <TB3> INFO: non-linearity mean: 0.963 0.963 0.956 0.954 0.953 0.957 0.950 0.948 0.957 0.962 0.947 0.958 0.950 0.958 0.958 0.954
[14:16:01.309] <TB3> INFO: non-linearity RMS: 0.005 0.006 0.005 0.008 0.007 0.007 0.008 0.008 0.007 0.006 0.008 0.007 0.008 0.008 0.006 0.007
[14:16:01.310] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[14:16:01.335] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[14:16:01.361] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[14:16:01.381] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[14:16:01.399] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[14:16:01.420] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[14:16:01.439] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[14:16:01.459] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[14:16:01.479] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[14:16:01.499] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[14:16:01.518] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[14:16:01.538] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[14:16:01.558] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[14:16:01.578] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[14:16:01.597] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[14:16:01.617] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[14:16:01.638] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[14:16:01.638] <TB3> INFO: Decoding statistics:
[14:16:01.638] <TB3> INFO: General information:
[14:16:01.638] <TB3> INFO: 16bit words read: 2662370
[14:16:01.638] <TB3> INFO: valid events total: 166400
[14:16:01.638] <TB3> INFO: empty events: 0
[14:16:01.638] <TB3> INFO: valid events with pixels: 166400
[14:16:01.638] <TB3> INFO: valid pixel hits: 665585
[14:16:01.638] <TB3> INFO: Event errors: 0
[14:16:01.638] <TB3> INFO: start marker: 0
[14:16:01.638] <TB3> INFO: stop marker: 0
[14:16:01.638] <TB3> INFO: overflow: 0
[14:16:01.638] <TB3> INFO: invalid 5bit words: 0
[14:16:01.638] <TB3> INFO: invalid XOR eye diagram: 0
[14:16:01.638] <TB3> INFO: frame (failed synchr.): 0
[14:16:01.638] <TB3> INFO: idle data (no TBM trl): 0
[14:16:01.638] <TB3> INFO: no data (only TBM hdr): 0
[14:16:01.638] <TB3> INFO: TBM errors: 0
[14:16:01.638] <TB3> INFO: flawed TBM headers: 0
[14:16:01.638] <TB3> INFO: flawed TBM trailers: 0
[14:16:01.638] <TB3> INFO: event ID mismatches: 0
[14:16:01.638] <TB3> INFO: ROC errors: 0
[14:16:01.638] <TB3> INFO: missing ROC header(s): 0
[14:16:01.638] <TB3> INFO: misplaced readback start: 0
[14:16:01.638] <TB3> INFO: Pixel decoding errors: 0
[14:16:01.638] <TB3> INFO: pixel data incomplete: 0
[14:16:01.638] <TB3> INFO: pixel address: 0
[14:16:01.638] <TB3> INFO: pulse height fill bit: 0
[14:16:01.638] <TB3> INFO: buffer corruption: 0
[14:16:01.647] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:16:01.648] <TB3> INFO: ######################################################################
[14:16:01.648] <TB3> INFO: PixTestReadback::doTest()
[14:16:01.648] <TB3> INFO: ######################################################################
[14:16:01.648] <TB3> INFO: ----------------------------------------------------------------------
[14:16:01.648] <TB3> INFO: PixTestReadback::CalibrateVd()
[14:16:01.648] <TB3> INFO: ----------------------------------------------------------------------
[14:16:11.181] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:16:11.181] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:16:11.182] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:16:11.183] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:16:11.183] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:16:11.183] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:16:11.229] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:16:11.229] <TB3> INFO: ----------------------------------------------------------------------
[14:16:11.229] <TB3> INFO: PixTestReadback::CalibrateVa()
[14:16:11.229] <TB3> INFO: ----------------------------------------------------------------------
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:16:20.747] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:16:20.748] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:16:20.748] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:16:20.794] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:16:20.794] <TB3> INFO: ----------------------------------------------------------------------
[14:16:20.794] <TB3> INFO: PixTestReadback::readbackVbg()
[14:16:20.794] <TB3> INFO: ----------------------------------------------------------------------
[14:16:28.152] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:16:28.152] <TB3> INFO: ----------------------------------------------------------------------
[14:16:28.152] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[14:16:28.152] <TB3> INFO: ----------------------------------------------------------------------
[14:16:28.152] <TB3> INFO: Vbg will be calibrated using Vd calibration
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.2calibrated Vbg = 1.20801 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.3calibrated Vbg = 1.20933 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 160.9calibrated Vbg = 1.21524 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152calibrated Vbg = 1.22405 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.9calibrated Vbg = 1.22599 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.9calibrated Vbg = 1.22727 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.2calibrated Vbg = 1.23582 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.1calibrated Vbg = 1.22569 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.6calibrated Vbg = 1.2334 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.5calibrated Vbg = 1.22639 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.3calibrated Vbg = 1.22283 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 147.5calibrated Vbg = 1.22044 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.4calibrated Vbg = 1.21223 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157.1calibrated Vbg = 1.21745 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.2calibrated Vbg = 1.21397 :::*/*/*/*/
[14:16:28.152] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.3calibrated Vbg = 1.21995 :::*/*/*/*/
[14:16:28.154] <TB3> INFO: ----------------------------------------------------------------------
[14:16:28.154] <TB3> INFO: PixTestReadback::CalibrateIa()
[14:16:28.154] <TB3> INFO: ----------------------------------------------------------------------
[14:19:04.278] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:19:04.279] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:19:04.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:19:04.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:19:04.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2359_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:19:04.325] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:19:04.326] <TB3> INFO: PixTestReadback::doTest() done
[14:19:04.326] <TB3> INFO: Decoding statistics:
[14:19:04.326] <TB3> INFO: General information:
[14:19:04.326] <TB3> INFO: 16bit words read: 1024
[14:19:04.326] <TB3> INFO: valid events total: 128
[14:19:04.326] <TB3> INFO: empty events: 128
[14:19:04.326] <TB3> INFO: valid events with pixels: 0
[14:19:04.326] <TB3> INFO: valid pixel hits: 0
[14:19:04.326] <TB3> INFO: Event errors: 0
[14:19:04.326] <TB3> INFO: start marker: 0
[14:19:04.326] <TB3> INFO: stop marker: 0
[14:19:04.326] <TB3> INFO: overflow: 0
[14:19:04.326] <TB3> INFO: invalid 5bit words: 0
[14:19:04.326] <TB3> INFO: invalid XOR eye diagram: 0
[14:19:04.326] <TB3> INFO: frame (failed synchr.): 0
[14:19:04.326] <TB3> INFO: idle data (no TBM trl): 0
[14:19:04.326] <TB3> INFO: no data (only TBM hdr): 0
[14:19:04.326] <TB3> INFO: TBM errors: 0
[14:19:04.326] <TB3> INFO: flawed TBM headers: 0
[14:19:04.326] <TB3> INFO: flawed TBM trailers: 0
[14:19:04.326] <TB3> INFO: event ID mismatches: 0
[14:19:04.326] <TB3> INFO: ROC errors: 0
[14:19:04.326] <TB3> INFO: missing ROC header(s): 0
[14:19:04.326] <TB3> INFO: misplaced readback start: 0
[14:19:04.326] <TB3> INFO: Pixel decoding errors: 0
[14:19:04.326] <TB3> INFO: pixel data incomplete: 0
[14:19:04.326] <TB3> INFO: pixel address: 0
[14:19:04.326] <TB3> INFO: pulse height fill bit: 0
[14:19:04.326] <TB3> INFO: buffer corruption: 0
[14:19:04.346] <TB3> INFO: Decoding statistics:
[14:19:04.346] <TB3> INFO: General information:
[14:19:04.346] <TB3> INFO: 16bit words read: 2750316
[14:19:04.346] <TB3> INFO: valid events total: 176768
[14:19:04.346] <TB3> INFO: empty events: 7867
[14:19:04.346] <TB3> INFO: valid events with pixels: 168901
[14:19:04.346] <TB3> INFO: valid pixel hits: 668086
[14:19:04.346] <TB3> INFO: Event errors: 0
[14:19:04.346] <TB3> INFO: start marker: 0
[14:19:04.346] <TB3> INFO: stop marker: 0
[14:19:04.346] <TB3> INFO: overflow: 0
[14:19:04.346] <TB3> INFO: invalid 5bit words: 0
[14:19:04.346] <TB3> INFO: invalid XOR eye diagram: 0
[14:19:04.346] <TB3> INFO: frame (failed synchr.): 0
[14:19:04.346] <TB3> INFO: idle data (no TBM trl): 0
[14:19:04.346] <TB3> INFO: no data (only TBM hdr): 0
[14:19:04.346] <TB3> INFO: TBM errors: 0
[14:19:04.346] <TB3> INFO: flawed TBM headers: 0
[14:19:04.346] <TB3> INFO: flawed TBM trailers: 0
[14:19:04.346] <TB3> INFO: event ID mismatches: 0
[14:19:04.346] <TB3> INFO: ROC errors: 0
[14:19:04.346] <TB3> INFO: missing ROC header(s): 0
[14:19:04.346] <TB3> INFO: misplaced readback start: 0
[14:19:04.346] <TB3> INFO: Pixel decoding errors: 0
[14:19:04.346] <TB3> INFO: pixel data incomplete: 0
[14:19:04.346] <TB3> INFO: pixel address: 0
[14:19:04.346] <TB3> INFO: pulse height fill bit: 0
[14:19:04.346] <TB3> INFO: buffer corruption: 0
[14:19:04.346] <TB3> INFO: enter test to run
[14:19:04.347] <TB3> INFO: test: exit no parameter change
[14:19:04.555] <TB3> QUIET: Connection to board 170 closed.
[14:19:04.636] <TB3> INFO: pXar: this is the end, my friend