[10:36:50.958] <TB1> INFO: *** Welcome to pxar ***
[10:36:50.958] <TB1> INFO: *** Today: 2016/05/09
[10:36:51.069] <TB1> INFO: *** Version: 9751-dirty
[10:36:51.070] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C15.dat
[10:36:51.071] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:36:51.071] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//defaultMaskFile.dat
[10:36:51.071] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters_C15.dat
[10:36:51.168] <TB1> INFO: clk: 4
[10:36:51.168] <TB1> INFO: ctr: 4
[10:36:51.168] <TB1> INFO: sda: 19
[10:36:51.168] <TB1> INFO: tin: 9
[10:36:51.168] <TB1> INFO: level: 15
[10:36:51.168] <TB1> INFO: triggerdelay: 0
[10:36:51.168] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:36:51.168] <TB1> INFO: Log level: INFO
[10:36:51.175] <TB1> INFO: Found DTB DTB_WXBYFL
[10:36:51.185] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:36:51.187] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[10:36:51.189] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:36:52.737] <TB1> INFO: DUT info:
[10:36:52.738] <TB1> INFO: The DUT currently contains the following objects:
[10:36:52.738] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:36:52.738] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:36:52.738] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:36:52.738] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:36:52.738] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:52.738] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:36:53.139] <TB1> INFO: enter 'restricted' command line mode
[10:36:53.139] <TB1> INFO: enter test to run
[10:36:53.139] <TB1> INFO: test: pretest no parameter change
[10:36:53.139] <TB1> INFO: running: pretest
[10:36:53.149] <TB1> INFO: ----------------------------------------------------------------------
[10:36:53.149] <TB1> INFO: PixTestPretest::programROC()
[10:36:53.149] <TB1> INFO: ----------------------------------------------------------------------
[10:37:11.164] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:37:11.164] <TB1> INFO: IA differences per ROC: 19.3 19.3 18.5 20.1 18.5 19.3 20.1 19.3 17.7 18.5 18.5 18.5 17.7 19.3 17.7 15.3
[10:37:11.269] <TB1> INFO: enter test to run
[10:37:11.269] <TB1> INFO: test: pretest no parameter change
[10:37:11.269] <TB1> INFO: running: pretest
[10:37:11.270] <TB1> INFO: ----------------------------------------------------------------------
[10:37:11.270] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:37:11.270] <TB1> INFO: ----------------------------------------------------------------------
[10:37:32.525] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[10:37:32.525] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 21.7 20.9 19.3 20.1 20.1 20.1 20.1 19.3 19.3 19.3 19.3 19.3 19.3
[10:37:32.551] <TB1> INFO: enter test to run
[10:37:32.551] <TB1> INFO: test: pretest no parameter change
[10:37:32.551] <TB1> INFO: running: pretest
[10:37:32.551] <TB1> INFO: ----------------------------------------------------------------------
[10:37:32.551] <TB1> INFO: PixTestPretest::findTiming()
[10:37:32.551] <TB1> INFO: ----------------------------------------------------------------------
[10:37:32.551] <TB1> INFO: PixTestCmd::init()

[10:39:01.478] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:39:01.478] <TB1> INFO: (success/tries = 100/100), width = 4
[10:39:01.479] <TB1> INFO: enter test to run
[10:39:01.479] <TB1> INFO: test: pretest no parameter change
[10:39:01.479] <TB1> INFO: running: pretest
[10:39:01.480] <TB1> INFO: ----------------------------------------------------------------------
[10:39:01.480] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:39:01.480] <TB1> INFO: ----------------------------------------------------------------------
[10:39:01.570] <TB1> INFO: Expecting 231680 events.


[10:39:11.892] <TB1> INFO: 231680 events read in total (9765ms).
[10:39:11.896] <TB1> INFO: Test took 10414ms.
[10:39:12.118] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:39:12.180] <TB1> INFO: enter test to run
[10:39:12.180] <TB1> INFO: test: pretest no parameter change
[10:39:12.180] <TB1> INFO: running: pretest
[10:39:12.181] <TB1> INFO: ----------------------------------------------------------------------
[10:39:12.181] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:39:12.181] <TB1> INFO: ----------------------------------------------------------------------
[10:39:12.272] <TB1> INFO: Expecting 231680 events.
[10:39:22.512] <TB1> INFO: 231680 events read in total (9683ms).
[10:39:22.516] <TB1> INFO: Test took 10331ms.
[10:39:22.767] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:39:22.767] <TB1> INFO: CalDel: 156 163 143 147 145 128 139 129 142 120 150 135 109 154 136 153
[10:39:22.767] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 54 51 51 51
[10:39:22.807] <TB1> INFO: enter test to run
[10:39:22.807] <TB1> INFO: test: pretest no parameter change
[10:39:22.807] <TB1> INFO: running: pretest
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C0.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C1.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C2.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C3.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C4.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C5.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C6.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C7.dat
[10:39:22.807] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C8.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C9.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C10.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C11.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C12.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C13.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C14.dat
[10:39:22.808] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters_C15.dat
[10:39:22.808] <TB1> INFO: enter test to run
[10:39:22.808] <TB1> INFO: test: fulltest no parameter change
[10:39:22.808] <TB1> INFO: running: fulltest
[10:39:22.808] <TB1> INFO: ######################################################################
[10:39:22.808] <TB1> INFO: PixTestFullTest::doTest()
[10:39:22.808] <TB1> INFO: ######################################################################
[10:39:22.810] <TB1> INFO: ######################################################################
[10:39:22.810] <TB1> INFO: PixTestAlive::doTest()
[10:39:22.810] <TB1> INFO: ######################################################################
[10:39:22.811] <TB1> INFO: ----------------------------------------------------------------------
[10:39:22.811] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:39:22.811] <TB1> INFO: ----------------------------------------------------------------------
[10:39:23.075] <TB1> INFO: Expecting 41600 events.
[10:39:26.820] <TB1> INFO: 41600 events read in total (3188ms).
[10:39:26.821] <TB1> INFO: Test took 4009ms.
[10:39:27.041] <TB1> INFO: PixTestAlive::aliveTest() done
[10:39:27.041] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[10:39:27.042] <TB1> INFO: ----------------------------------------------------------------------
[10:39:27.042] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:39:27.042] <TB1> INFO: ----------------------------------------------------------------------
[10:39:27.305] <TB1> INFO: Expecting 41600 events.
[10:39:30.273] <TB1> INFO: 41600 events read in total (2412ms).
[10:39:30.273] <TB1> INFO: Test took 3230ms.
[10:39:30.273] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:39:30.512] <TB1> INFO: PixTestAlive::maskTest() done
[10:39:30.512] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:39:30.513] <TB1> INFO: ----------------------------------------------------------------------
[10:39:30.513] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:39:30.513] <TB1> INFO: ----------------------------------------------------------------------
[10:39:30.792] <TB1> INFO: Expecting 41600 events.
[10:39:34.611] <TB1> INFO: 41600 events read in total (3263ms).
[10:39:34.612] <TB1> INFO: Test took 4098ms.
[10:39:34.845] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:39:34.845] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:39:34.845] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:39:34.845] <TB1> INFO: Decoding statistics:
[10:39:34.845] <TB1> INFO: General information:
[10:39:34.845] <TB1> INFO: 16bit words read: 0
[10:39:34.845] <TB1> INFO: valid events total: 0
[10:39:34.845] <TB1> INFO: empty events: 0
[10:39:34.845] <TB1> INFO: valid events with pixels: 0
[10:39:34.845] <TB1> INFO: valid pixel hits: 0
[10:39:34.846] <TB1> INFO: Event errors: 0
[10:39:34.846] <TB1> INFO: start marker: 0
[10:39:34.846] <TB1> INFO: stop marker: 0
[10:39:34.846] <TB1> INFO: overflow: 0
[10:39:34.846] <TB1> INFO: invalid 5bit words: 0
[10:39:34.846] <TB1> INFO: invalid XOR eye diagram: 0
[10:39:34.846] <TB1> INFO: frame (failed synchr.): 0
[10:39:34.846] <TB1> INFO: idle data (no TBM trl): 0
[10:39:34.846] <TB1> INFO: no data (only TBM hdr): 0
[10:39:34.846] <TB1> INFO: TBM errors: 0
[10:39:34.846] <TB1> INFO: flawed TBM headers: 0
[10:39:34.846] <TB1> INFO: flawed TBM trailers: 0
[10:39:34.846] <TB1> INFO: event ID mismatches: 0
[10:39:34.846] <TB1> INFO: ROC errors: 0
[10:39:34.846] <TB1> INFO: missing ROC header(s): 0
[10:39:34.846] <TB1> INFO: misplaced readback start: 0
[10:39:34.846] <TB1> INFO: Pixel decoding errors: 0
[10:39:34.846] <TB1> INFO: pixel data incomplete: 0
[10:39:34.846] <TB1> INFO: pixel address: 0
[10:39:34.846] <TB1> INFO: pulse height fill bit: 0
[10:39:34.846] <TB1> INFO: buffer corruption: 0
[10:39:34.860] <TB1> INFO: ######################################################################
[10:39:34.860] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:39:34.860] <TB1> INFO: ######################################################################
[10:39:34.862] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:39:34.874] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:39:34.874] <TB1> INFO: run 1 of 1
[10:39:35.151] <TB1> INFO: Expecting 3120000 events.
[10:40:16.432] <TB1> INFO: 873620 events read in total (40725ms).
[10:40:56.663] <TB1> INFO: 1732525 events read in total (80956ms).
[10:41:37.426] <TB1> INFO: 2600000 events read in total (121720ms).
[10:42:01.500] <TB1> INFO: 3120000 events read in total (145793ms).
[10:42:01.549] <TB1> INFO: Test took 146675ms.
[10:42:25.950] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[10:42:25.950] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 2 0 0 0 1 4 2 5
[10:42:25.950] <TB1> INFO: separation cut (per ROC): 93 100 108 95 104 95 97 99 86 106 74 85 111 87 90 72
[10:42:25.950] <TB1> INFO: Decoding statistics:
[10:42:25.950] <TB1> INFO: General information:
[10:42:25.950] <TB1> INFO: 16bit words read: 0
[10:42:25.950] <TB1> INFO: valid events total: 0
[10:42:25.951] <TB1> INFO: empty events: 0
[10:42:25.951] <TB1> INFO: valid events with pixels: 0
[10:42:25.951] <TB1> INFO: valid pixel hits: 0
[10:42:25.951] <TB1> INFO: Event errors: 0
[10:42:25.951] <TB1> INFO: start marker: 0
[10:42:25.951] <TB1> INFO: stop marker: 0
[10:42:25.951] <TB1> INFO: overflow: 0
[10:42:25.951] <TB1> INFO: invalid 5bit words: 0
[10:42:25.951] <TB1> INFO: invalid XOR eye diagram: 0
[10:42:25.951] <TB1> INFO: frame (failed synchr.): 0
[10:42:25.951] <TB1> INFO: idle data (no TBM trl): 0
[10:42:25.951] <TB1> INFO: no data (only TBM hdr): 0
[10:42:25.951] <TB1> INFO: TBM errors: 0
[10:42:25.951] <TB1> INFO: flawed TBM headers: 0
[10:42:25.951] <TB1> INFO: flawed TBM trailers: 0
[10:42:25.951] <TB1> INFO: event ID mismatches: 0
[10:42:25.951] <TB1> INFO: ROC errors: 0
[10:42:25.951] <TB1> INFO: missing ROC header(s): 0
[10:42:25.951] <TB1> INFO: misplaced readback start: 0
[10:42:25.951] <TB1> INFO: Pixel decoding errors: 0
[10:42:25.951] <TB1> INFO: pixel data incomplete: 0
[10:42:25.951] <TB1> INFO: pixel address: 0
[10:42:25.951] <TB1> INFO: pulse height fill bit: 0
[10:42:25.951] <TB1> INFO: buffer corruption: 0
[10:42:26.029] <TB1> INFO: ######################################################################
[10:42:26.029] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:42:26.029] <TB1> INFO: ######################################################################
[10:42:26.029] <TB1> INFO: ----------------------------------------------------------------------
[10:42:26.029] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:42:26.029] <TB1> INFO: ----------------------------------------------------------------------
[10:42:26.029] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:42:26.037] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:42:26.037] <TB1> INFO: run 1 of 1
[10:42:26.304] <TB1> INFO: Expecting 26208000 events.
[10:42:59.725] <TB1> INFO: 923550 events read in total (32865ms).
[10:43:32.887] <TB1> INFO: 1827700 events read in total (66027ms).
[10:44:06.009] <TB1> INFO: 2732650 events read in total (99149ms).
[10:44:38.866] <TB1> INFO: 3635000 events read in total (132006ms).
[10:45:12.492] <TB1> INFO: 4534900 events read in total (165632ms).
[10:45:45.367] <TB1> INFO: 5432000 events read in total (198507ms).
[10:46:18.494] <TB1> INFO: 6330750 events read in total (231634ms).
[10:46:51.286] <TB1> INFO: 7228400 events read in total (264426ms).
[10:47:24.226] <TB1> INFO: 8125550 events read in total (297366ms).
[10:47:57.381] <TB1> INFO: 9019900 events read in total (330521ms).
[10:48:30.050] <TB1> INFO: 9916950 events read in total (363190ms).
[10:49:02.762] <TB1> INFO: 10813300 events read in total (395902ms).
[10:49:35.364] <TB1> INFO: 11708850 events read in total (428504ms).
[10:50:07.970] <TB1> INFO: 12602300 events read in total (461110ms).
[10:50:41.046] <TB1> INFO: 13487500 events read in total (494186ms).
[10:51:12.994] <TB1> INFO: 14371000 events read in total (526134ms).
[10:51:45.548] <TB1> INFO: 15254100 events read in total (558688ms).
[10:52:17.676] <TB1> INFO: 16133700 events read in total (590816ms).
[10:52:50.509] <TB1> INFO: 17013450 events read in total (623649ms).
[10:53:23.055] <TB1> INFO: 17893750 events read in total (656195ms).
[10:53:55.286] <TB1> INFO: 18772950 events read in total (688426ms).
[10:54:27.813] <TB1> INFO: 19648800 events read in total (720953ms).
[10:55:00.145] <TB1> INFO: 20527700 events read in total (753285ms).
[10:55:32.368] <TB1> INFO: 21404400 events read in total (785508ms).
[10:56:04.406] <TB1> INFO: 22281200 events read in total (817546ms).
[10:56:36.911] <TB1> INFO: 23158150 events read in total (850051ms).
[10:57:09.387] <TB1> INFO: 24036600 events read in total (882527ms).
[10:57:42.124] <TB1> INFO: 24915800 events read in total (915264ms).
[10:58:14.540] <TB1> INFO: 25800950 events read in total (947680ms).
[10:58:29.043] <TB1> INFO: 26208000 events read in total (962183ms).
[10:58:29.076] <TB1> INFO: Test took 963039ms.
[10:58:29.366] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:30.930] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:32.379] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:33.824] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:35.304] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:36.662] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:38.094] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:39.577] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:40.964] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:42.388] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:43.762] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:45.181] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:46.632] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:48.067] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:49.556] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:50.981] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:58:52.454] <TB1> INFO: PixTestScurves::scurves() done
[10:58:52.454] <TB1> INFO: Vcal mean: 92.98 89.48 102.66 92.96 103.03 90.94 97.17 94.71 90.89 104.29 79.07 83.06 104.48 87.30 91.79 86.41
[10:58:52.454] <TB1> INFO: Vcal RMS: 5.71 5.21 6.32 5.10 5.63 5.81 5.26 5.69 5.14 6.81 4.18 4.83 7.33 5.53 5.91 5.37
[10:58:52.454] <TB1> INFO: PixTestScurves::fullTest() done, duration: 986 seconds
[10:58:52.454] <TB1> INFO: Decoding statistics:
[10:58:52.454] <TB1> INFO: General information:
[10:58:52.454] <TB1> INFO: 16bit words read: 0
[10:58:52.454] <TB1> INFO: valid events total: 0
[10:58:52.454] <TB1> INFO: empty events: 0
[10:58:52.454] <TB1> INFO: valid events with pixels: 0
[10:58:52.454] <TB1> INFO: valid pixel hits: 0
[10:58:52.454] <TB1> INFO: Event errors: 0
[10:58:52.454] <TB1> INFO: start marker: 0
[10:58:52.454] <TB1> INFO: stop marker: 0
[10:58:52.454] <TB1> INFO: overflow: 0
[10:58:52.454] <TB1> INFO: invalid 5bit words: 0
[10:58:52.454] <TB1> INFO: invalid XOR eye diagram: 0
[10:58:52.454] <TB1> INFO: frame (failed synchr.): 0
[10:58:52.454] <TB1> INFO: idle data (no TBM trl): 0
[10:58:52.454] <TB1> INFO: no data (only TBM hdr): 0
[10:58:52.454] <TB1> INFO: TBM errors: 0
[10:58:52.454] <TB1> INFO: flawed TBM headers: 0
[10:58:52.454] <TB1> INFO: flawed TBM trailers: 0
[10:58:52.454] <TB1> INFO: event ID mismatches: 0
[10:58:52.454] <TB1> INFO: ROC errors: 0
[10:58:52.454] <TB1> INFO: missing ROC header(s): 0
[10:58:52.454] <TB1> INFO: misplaced readback start: 0
[10:58:52.454] <TB1> INFO: Pixel decoding errors: 0
[10:58:52.454] <TB1> INFO: pixel data incomplete: 0
[10:58:52.454] <TB1> INFO: pixel address: 0
[10:58:52.454] <TB1> INFO: pulse height fill bit: 0
[10:58:52.454] <TB1> INFO: buffer corruption: 0
[10:58:52.529] <TB1> INFO: ######################################################################
[10:58:52.529] <TB1> INFO: PixTestTrim::doTest()
[10:58:52.529] <TB1> INFO: ######################################################################
[10:58:52.530] <TB1> INFO: ----------------------------------------------------------------------
[10:58:52.530] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:58:52.530] <TB1> INFO: ----------------------------------------------------------------------
[10:58:52.606] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:58:52.607] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:58:52.614] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:58:52.615] <TB1> INFO: run 1 of 1
[10:58:52.873] <TB1> INFO: Expecting 6281600 events.
[10:59:32.782] <TB1> INFO: 1084100 events read in total (39347ms).
[11:00:11.884] <TB1> INFO: 2161930 events read in total (78449ms).
[11:00:50.764] <TB1> INFO: 3232520 events read in total (117329ms).
[11:01:29.384] <TB1> INFO: 4309180 events read in total (155949ms).
[11:02:08.125] <TB1> INFO: 5388480 events read in total (194690ms).
[11:02:40.307] <TB1> INFO: 6281600 events read in total (226872ms).
[11:02:40.336] <TB1> INFO: Test took 227721ms.
[11:02:59.597] <TB1> INFO: ROC 0 VthrComp = 95
[11:02:59.597] <TB1> INFO: ROC 1 VthrComp = 94
[11:02:59.597] <TB1> INFO: ROC 2 VthrComp = 101
[11:02:59.597] <TB1> INFO: ROC 3 VthrComp = 98
[11:02:59.597] <TB1> INFO: ROC 4 VthrComp = 104
[11:02:59.597] <TB1> INFO: ROC 5 VthrComp = 92
[11:02:59.597] <TB1> INFO: ROC 6 VthrComp = 101
[11:02:59.597] <TB1> INFO: ROC 7 VthrComp = 100
[11:02:59.597] <TB1> INFO: ROC 8 VthrComp = 94
[11:02:59.597] <TB1> INFO: ROC 9 VthrComp = 102
[11:02:59.597] <TB1> INFO: ROC 10 VthrComp = 84
[11:02:59.598] <TB1> INFO: ROC 11 VthrComp = 89
[11:02:59.598] <TB1> INFO: ROC 12 VthrComp = 101
[11:02:59.598] <TB1> INFO: ROC 13 VthrComp = 88
[11:02:59.598] <TB1> INFO: ROC 14 VthrComp = 93
[11:02:59.598] <TB1> INFO: ROC 15 VthrComp = 84
[11:02:59.598] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:02:59.598] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:02:59.606] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:02:59.606] <TB1> INFO: run 1 of 1
[11:02:59.866] <TB1> INFO: Expecting 6281600 events.
[11:03:34.470] <TB1> INFO: 757630 events read in total (34048ms).
[11:04:09.178] <TB1> INFO: 1512590 events read in total (68756ms).
[11:04:44.120] <TB1> INFO: 2267590 events read in total (103698ms).
[11:05:19.064] <TB1> INFO: 3023610 events read in total (138642ms).
[11:05:53.788] <TB1> INFO: 3773620 events read in total (173366ms).
[11:06:28.206] <TB1> INFO: 4520130 events read in total (207784ms).
[11:07:02.669] <TB1> INFO: 5265070 events read in total (242247ms).
[11:07:36.427] <TB1> INFO: 6009550 events read in total (276005ms).
[11:07:49.169] <TB1> INFO: 6281600 events read in total (288747ms).
[11:07:49.214] <TB1> INFO: Test took 289608ms.
[11:08:13.118] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 59.6591 for pixel 0/0 mean/min/max = 45.9693/32.264/59.6747
[11:08:13.119] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.1704 for pixel 51/0 mean/min/max = 45.4351/32.676/58.1943
[11:08:13.119] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 62.7212 for pixel 28/78 mean/min/max = 46.7261/30.692/62.7601
[11:08:13.119] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.5276 for pixel 18/0 mean/min/max = 43.9612/31.2777/56.6448
[11:08:13.119] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.4951 for pixel 13/66 mean/min/max = 47.0649/33.562/60.5678
[11:08:13.120] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.5955 for pixel 3/69 mean/min/max = 46.3781/32.0876/60.6687
[11:08:13.120] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.3796 for pixel 51/79 mean/min/max = 45.2336/32.0527/58.4144
[11:08:13.120] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.3136 for pixel 15/30 mean/min/max = 45.0287/31.7405/58.3168
[11:08:13.120] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.3042 for pixel 22/69 mean/min/max = 45.5474/32.7201/58.3748
[11:08:13.120] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 65.7348 for pixel 9/79 mean/min/max = 48.3851/31.0066/65.7636
[11:08:13.121] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.1306 for pixel 22/7 mean/min/max = 44.6239/32.5719/56.6759
[11:08:13.121] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.0171 for pixel 24/79 mean/min/max = 45.5303/33.0257/58.035
[11:08:13.121] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 69.5859 for pixel 5/25 mean/min/max = 50.442/31.0961/69.788
[11:08:13.121] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 59.0006 for pixel 23/45 mean/min/max = 45.6489/32.2213/59.0765
[11:08:13.122] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 60.0552 for pixel 11/79 mean/min/max = 45.8886/31.6909/60.0863
[11:08:13.122] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 60.5229 for pixel 24/3 mean/min/max = 46.5287/32.3737/60.6836
[11:08:13.122] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:08:13.210] <TB1> INFO: Expecting 514560 events.
[11:08:26.012] <TB1> INFO: 514560 events read in total (12246ms).
[11:08:26.017] <TB1> INFO: Expecting 514560 events.
[11:08:38.674] <TB1> INFO: 514560 events read in total (12235ms).
[11:08:38.680] <TB1> INFO: Expecting 514560 events.
[11:08:51.472] <TB1> INFO: 514560 events read in total (12366ms).
[11:08:51.481] <TB1> INFO: Expecting 514560 events.
[11:09:04.293] <TB1> INFO: 514560 events read in total (12393ms).
[11:09:04.304] <TB1> INFO: Expecting 514560 events.
[11:09:17.148] <TB1> INFO: 514560 events read in total (12439ms).
[11:09:17.162] <TB1> INFO: Expecting 514560 events.
[11:09:29.966] <TB1> INFO: 514560 events read in total (12395ms).
[11:09:29.981] <TB1> INFO: Expecting 514560 events.
[11:09:42.676] <TB1> INFO: 514560 events read in total (12286ms).
[11:09:42.693] <TB1> INFO: Expecting 514560 events.
[11:09:55.417] <TB1> INFO: 514560 events read in total (12310ms).
[11:09:55.436] <TB1> INFO: Expecting 514560 events.
[11:10:08.213] <TB1> INFO: 514560 events read in total (12373ms).
[11:10:08.238] <TB1> INFO: Expecting 514560 events.
[11:10:20.899] <TB1> INFO: 514560 events read in total (12260ms).
[11:10:20.922] <TB1> INFO: Expecting 514560 events.
[11:10:33.735] <TB1> INFO: 514560 events read in total (12407ms).
[11:10:33.760] <TB1> INFO: Expecting 514560 events.
[11:10:46.437] <TB1> INFO: 514560 events read in total (12275ms).
[11:10:46.464] <TB1> INFO: Expecting 514560 events.
[11:10:59.297] <TB1> INFO: 514560 events read in total (12431ms).
[11:10:59.331] <TB1> INFO: Expecting 514560 events.
[11:11:12.070] <TB1> INFO: 514560 events read in total (12337ms).
[11:11:12.107] <TB1> INFO: Expecting 514560 events.
[11:11:24.842] <TB1> INFO: 514560 events read in total (12333ms).
[11:11:24.877] <TB1> INFO: Expecting 514560 events.
[11:11:37.577] <TB1> INFO: 514560 events read in total (12299ms).
[11:11:37.618] <TB1> INFO: Test took 204496ms.
[11:11:38.606] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:11:38.614] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:11:38.614] <TB1> INFO: run 1 of 1
[11:11:38.886] <TB1> INFO: Expecting 6281600 events.
[11:12:13.563] <TB1> INFO: 742330 events read in total (34121ms).
[11:12:47.547] <TB1> INFO: 1482190 events read in total (68105ms).
[11:13:21.724] <TB1> INFO: 2221930 events read in total (102282ms).
[11:13:56.452] <TB1> INFO: 2962500 events read in total (137010ms).
[11:14:30.832] <TB1> INFO: 3697170 events read in total (171390ms).
[11:15:04.992] <TB1> INFO: 4428320 events read in total (205550ms).
[11:15:38.697] <TB1> INFO: 5157300 events read in total (239255ms).
[11:16:13.132] <TB1> INFO: 5885920 events read in total (273690ms).
[11:16:31.638] <TB1> INFO: 6281600 events read in total (292196ms).
[11:16:31.677] <TB1> INFO: Test took 293064ms.
[11:16:56.271] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.127532 .. 255.000000
[11:16:56.347] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:16:56.355] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:16:56.355] <TB1> INFO: run 1 of 1
[11:16:56.627] <TB1> INFO: Expecting 10649600 events.
[11:17:30.934] <TB1> INFO: 711940 events read in total (33751ms).
[11:18:04.895] <TB1> INFO: 1424080 events read in total (67712ms).
[11:18:38.940] <TB1> INFO: 2136330 events read in total (101757ms).
[11:19:12.849] <TB1> INFO: 2848940 events read in total (135666ms).
[11:19:46.400] <TB1> INFO: 3561650 events read in total (169217ms).
[11:20:20.261] <TB1> INFO: 4274190 events read in total (203078ms).
[11:20:53.791] <TB1> INFO: 4986750 events read in total (236608ms).
[11:21:27.798] <TB1> INFO: 5699480 events read in total (270615ms).
[11:22:01.847] <TB1> INFO: 6411580 events read in total (304664ms).
[11:22:35.382] <TB1> INFO: 7123130 events read in total (338199ms).
[11:23:08.985] <TB1> INFO: 7834730 events read in total (371803ms).
[11:23:42.778] <TB1> INFO: 8545810 events read in total (405595ms).
[11:24:17.044] <TB1> INFO: 9256620 events read in total (439861ms).
[11:24:50.851] <TB1> INFO: 9967590 events read in total (473668ms).
[11:25:23.496] <TB1> INFO: 10649600 events read in total (506313ms).
[11:25:23.588] <TB1> INFO: Test took 507233ms.
[11:25:51.419] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.813594 .. 46.841578
[11:25:51.494] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:25:51.502] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:25:51.502] <TB1> INFO: run 1 of 1
[11:25:51.761] <TB1> INFO: Expecting 2163200 events.
[11:26:29.403] <TB1> INFO: 929760 events read in total (37086ms).
[11:27:06.902] <TB1> INFO: 1857760 events read in total (74585ms).
[11:27:19.624] <TB1> INFO: 2163200 events read in total (87307ms).
[11:27:19.641] <TB1> INFO: Test took 88140ms.
[11:27:33.135] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.636323 .. 43.628419
[11:27:33.211] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:27:33.219] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:27:33.219] <TB1> INFO: run 1 of 1
[11:27:33.482] <TB1> INFO: Expecting 1872000 events.
[11:28:11.780] <TB1> INFO: 932940 events read in total (37742ms).
[11:28:49.043] <TB1> INFO: 1864510 events read in total (75005ms).
[11:28:49.728] <TB1> INFO: 1872000 events read in total (75690ms).
[11:28:49.739] <TB1> INFO: Test took 76519ms.
[11:29:03.192] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.897635 .. 43.020974
[11:29:03.268] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:29:03.276] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:29:03.276] <TB1> INFO: run 1 of 1
[11:29:03.539] <TB1> INFO: Expecting 2163200 events.
[11:29:42.713] <TB1> INFO: 971790 events read in total (38618ms).
[11:30:21.102] <TB1> INFO: 1943570 events read in total (77007ms).
[11:30:30.026] <TB1> INFO: 2163200 events read in total (85931ms).
[11:30:30.045] <TB1> INFO: Test took 86769ms.
[11:30:43.032] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:30:43.032] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:30:43.039] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:30:43.039] <TB1> INFO: run 1 of 1
[11:30:43.300] <TB1> INFO: Expecting 1705600 events.
[11:31:20.586] <TB1> INFO: 878570 events read in total (36730ms).
[11:31:54.868] <TB1> INFO: 1705600 events read in total (71012ms).
[11:31:54.879] <TB1> INFO: Test took 71839ms.
[11:32:08.358] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:32:08.358] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:32:08.358] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:32:08.359] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:32:08.360] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:32:08.361] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:32:08.368] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:32:08.374] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:32:08.380] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:32:08.386] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:32:08.392] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:32:08.398] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:32:08.404] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:32:08.410] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:32:08.416] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:32:08.422] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:32:08.429] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:32:08.435] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:32:08.442] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:32:08.474] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:32:08.482] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:32:08.488] <TB1> INFO: PixTestTrim::trimTest() done
[11:32:08.488] <TB1> INFO: vtrim: 106 85 111 103 116 102 98 101 97 109 97 95 135 85 106 102
[11:32:08.488] <TB1> INFO: vthrcomp: 95 94 101 98 104 92 101 100 94 102 84 89 101 88 93 84
[11:32:08.488] <TB1> INFO: vcal mean: 35.00 34.98 35.00 34.98 35.03 34.96 34.96 34.96 34.96 34.98 34.99 34.95 35.03 34.96 34.97 35.01
[11:32:08.488] <TB1> INFO: vcal RMS: 0.83 0.79 0.94 0.86 0.88 0.86 0.85 0.87 0.87 0.92 0.84 0.82 1.27 0.85 1.04 0.90
[11:32:08.488] <TB1> INFO: bits mean: 8.76 8.74 9.60 10.01 9.16 9.25 8.73 9.78 9.59 8.50 9.94 9.14 9.23 9.49 9.42 9.44
[11:32:08.488] <TB1> INFO: bits RMS: 2.98 2.93 2.72 2.66 2.53 2.72 3.06 2.65 2.53 3.01 2.47 2.70 2.56 2.64 2.72 2.60
[11:32:08.494] <TB1> INFO: ----------------------------------------------------------------------
[11:32:08.494] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:32:08.494] <TB1> INFO: ----------------------------------------------------------------------
[11:32:08.497] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:32:08.506] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:32:08.506] <TB1> INFO: run 1 of 1
[11:32:08.793] <TB1> INFO: Expecting 4160000 events.
[11:32:50.676] <TB1> INFO: 944740 events read in total (41327ms).
[11:33:32.238] <TB1> INFO: 1880940 events read in total (82889ms).
[11:34:14.102] <TB1> INFO: 2807050 events read in total (124753ms).
[11:34:55.348] <TB1> INFO: 3729675 events read in total (165999ms).
[11:35:14.834] <TB1> INFO: 4160000 events read in total (185485ms).
[11:35:14.874] <TB1> INFO: Test took 186368ms.
[11:35:43.528] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 189 (-1/-1) hits flags = 528 (plus default)
[11:35:43.536] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:35:43.536] <TB1> INFO: run 1 of 1
[11:35:43.804] <TB1> INFO: Expecting 3952000 events.
[11:36:25.487] <TB1> INFO: 930555 events read in total (41127ms).
[11:37:06.842] <TB1> INFO: 1852580 events read in total (82482ms).
[11:37:47.980] <TB1> INFO: 2764215 events read in total (123620ms).
[11:38:29.044] <TB1> INFO: 3673385 events read in total (164684ms).
[11:38:41.691] <TB1> INFO: 3952000 events read in total (177331ms).
[11:38:41.737] <TB1> INFO: Test took 178201ms.
[11:39:08.388] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[11:39:08.396] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:39:08.396] <TB1> INFO: run 1 of 1
[11:39:08.679] <TB1> INFO: Expecting 3681600 events.
[11:39:51.106] <TB1> INFO: 957560 events read in total (41871ms).
[11:40:32.818] <TB1> INFO: 1904760 events read in total (83583ms).
[11:41:14.702] <TB1> INFO: 2840335 events read in total (125467ms).
[11:41:51.601] <TB1> INFO: 3681600 events read in total (162366ms).
[11:41:51.639] <TB1> INFO: Test took 163243ms.
[11:42:17.467] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[11:42:17.475] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:42:17.475] <TB1> INFO: run 1 of 1
[11:42:17.734] <TB1> INFO: Expecting 3723200 events.
[11:43:00.148] <TB1> INFO: 952400 events read in total (41857ms).
[11:43:41.443] <TB1> INFO: 1894665 events read in total (83152ms).
[11:44:22.528] <TB1> INFO: 2825300 events read in total (124237ms).
[11:45:01.754] <TB1> INFO: 3723200 events read in total (163463ms).
[11:45:01.791] <TB1> INFO: Test took 164316ms.
[11:45:27.500] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[11:45:27.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:45:27.508] <TB1> INFO: run 1 of 1
[11:45:27.767] <TB1> INFO: Expecting 3744000 events.
[11:46:10.516] <TB1> INFO: 949770 events read in total (42193ms).
[11:46:51.991] <TB1> INFO: 1889680 events read in total (83669ms).
[11:47:33.385] <TB1> INFO: 2817750 events read in total (125063ms).
[11:48:14.881] <TB1> INFO: 3744000 events read in total (166558ms).
[11:48:14.928] <TB1> INFO: Test took 167420ms.
[11:48:40.902] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:48:40.903] <TB1> INFO: PixTestTrim::doTest() done, duration: 2988 seconds
[11:48:40.903] <TB1> INFO: Decoding statistics:
[11:48:40.903] <TB1> INFO: General information:
[11:48:40.903] <TB1> INFO: 16bit words read: 0
[11:48:40.903] <TB1> INFO: valid events total: 0
[11:48:40.903] <TB1> INFO: empty events: 0
[11:48:40.903] <TB1> INFO: valid events with pixels: 0
[11:48:40.903] <TB1> INFO: valid pixel hits: 0
[11:48:40.903] <TB1> INFO: Event errors: 0
[11:48:40.903] <TB1> INFO: start marker: 0
[11:48:40.903] <TB1> INFO: stop marker: 0
[11:48:40.903] <TB1> INFO: overflow: 0
[11:48:40.903] <TB1> INFO: invalid 5bit words: 0
[11:48:40.903] <TB1> INFO: invalid XOR eye diagram: 0
[11:48:40.903] <TB1> INFO: frame (failed synchr.): 0
[11:48:40.903] <TB1> INFO: idle data (no TBM trl): 0
[11:48:40.903] <TB1> INFO: no data (only TBM hdr): 0
[11:48:40.903] <TB1> INFO: TBM errors: 0
[11:48:40.903] <TB1> INFO: flawed TBM headers: 0
[11:48:40.903] <TB1> INFO: flawed TBM trailers: 0
[11:48:40.903] <TB1> INFO: event ID mismatches: 0
[11:48:40.903] <TB1> INFO: ROC errors: 0
[11:48:40.903] <TB1> INFO: missing ROC header(s): 0
[11:48:40.903] <TB1> INFO: misplaced readback start: 0
[11:48:40.903] <TB1> INFO: Pixel decoding errors: 0
[11:48:40.903] <TB1> INFO: pixel data incomplete: 0
[11:48:40.903] <TB1> INFO: pixel address: 0
[11:48:40.903] <TB1> INFO: pulse height fill bit: 0
[11:48:40.904] <TB1> INFO: buffer corruption: 0
[11:48:41.573] <TB1> INFO: ######################################################################
[11:48:41.573] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:48:41.573] <TB1> INFO: ######################################################################
[11:48:41.832] <TB1> INFO: Expecting 41600 events.
[11:48:45.597] <TB1> INFO: 41600 events read in total (3208ms).
[11:48:45.598] <TB1> INFO: Test took 4024ms.
[11:48:46.060] <TB1> INFO: Expecting 41600 events.
[11:48:49.815] <TB1> INFO: 41600 events read in total (3199ms).
[11:48:49.816] <TB1> INFO: Test took 4014ms.
[11:48:50.096] <TB1> INFO: Expecting 41600 events.
[11:48:53.880] <TB1> INFO: 41600 events read in total (3228ms).
[11:48:53.881] <TB1> INFO: Test took 4044ms.
[11:48:54.139] <TB1> INFO: Expecting 2560 events.
[11:48:54.993] <TB1> INFO: 2560 events read in total (298ms).
[11:48:54.993] <TB1> INFO: Test took 1096ms.
[11:48:55.298] <TB1> INFO: Expecting 2560 events.
[11:48:56.151] <TB1> INFO: 2560 events read in total (297ms).
[11:48:56.151] <TB1> INFO: Test took 1158ms.
[11:48:56.456] <TB1> INFO: Expecting 2560 events.
[11:48:57.309] <TB1> INFO: 2560 events read in total (297ms).
[11:48:57.309] <TB1> INFO: Test took 1158ms.
[11:48:57.614] <TB1> INFO: Expecting 2560 events.
[11:48:58.467] <TB1> INFO: 2560 events read in total (297ms).
[11:48:58.467] <TB1> INFO: Test took 1157ms.
[11:48:58.772] <TB1> INFO: Expecting 2560 events.
[11:48:59.625] <TB1> INFO: 2560 events read in total (297ms).
[11:48:59.625] <TB1> INFO: Test took 1157ms.
[11:48:59.930] <TB1> INFO: Expecting 2560 events.
[11:49:00.790] <TB1> INFO: 2560 events read in total (304ms).
[11:49:00.790] <TB1> INFO: Test took 1165ms.
[11:49:01.096] <TB1> INFO: Expecting 2560 events.
[11:49:01.950] <TB1> INFO: 2560 events read in total (299ms).
[11:49:01.951] <TB1> INFO: Test took 1160ms.
[11:49:02.255] <TB1> INFO: Expecting 2560 events.
[11:49:03.108] <TB1> INFO: 2560 events read in total (297ms).
[11:49:03.108] <TB1> INFO: Test took 1156ms.
[11:49:03.413] <TB1> INFO: Expecting 2560 events.
[11:49:04.268] <TB1> INFO: 2560 events read in total (299ms).
[11:49:04.268] <TB1> INFO: Test took 1159ms.
[11:49:04.573] <TB1> INFO: Expecting 2560 events.
[11:49:05.426] <TB1> INFO: 2560 events read in total (296ms).
[11:49:05.427] <TB1> INFO: Test took 1158ms.
[11:49:05.732] <TB1> INFO: Expecting 2560 events.
[11:49:06.586] <TB1> INFO: 2560 events read in total (298ms).
[11:49:06.586] <TB1> INFO: Test took 1159ms.
[11:49:06.891] <TB1> INFO: Expecting 2560 events.
[11:49:07.745] <TB1> INFO: 2560 events read in total (298ms).
[11:49:07.745] <TB1> INFO: Test took 1159ms.
[11:49:08.050] <TB1> INFO: Expecting 2560 events.
[11:49:08.907] <TB1> INFO: 2560 events read in total (301ms).
[11:49:08.907] <TB1> INFO: Test took 1162ms.
[11:49:09.212] <TB1> INFO: Expecting 2560 events.
[11:49:10.067] <TB1> INFO: 2560 events read in total (299ms).
[11:49:10.067] <TB1> INFO: Test took 1160ms.
[11:49:10.372] <TB1> INFO: Expecting 2560 events.
[11:49:11.224] <TB1> INFO: 2560 events read in total (296ms).
[11:49:11.224] <TB1> INFO: Test took 1157ms.
[11:49:11.529] <TB1> INFO: Expecting 2560 events.
[11:49:12.382] <TB1> INFO: 2560 events read in total (297ms).
[11:49:12.383] <TB1> INFO: Test took 1158ms.
[11:49:12.386] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:12.688] <TB1> INFO: Expecting 655360 events.
[11:49:29.495] <TB1> INFO: 655360 events read in total (16251ms).
[11:49:29.503] <TB1> INFO: Expecting 655360 events.
[11:49:46.044] <TB1> INFO: 655360 events read in total (16139ms).
[11:49:46.057] <TB1> INFO: Expecting 655360 events.
[11:50:02.626] <TB1> INFO: 655360 events read in total (16167ms).
[11:50:02.641] <TB1> INFO: Expecting 655360 events.
[11:50:19.126] <TB1> INFO: 655360 events read in total (16083ms).
[11:50:19.145] <TB1> INFO: Expecting 655360 events.
[11:50:35.766] <TB1> INFO: 655360 events read in total (16219ms).
[11:50:35.796] <TB1> INFO: Expecting 655360 events.
[11:50:52.378] <TB1> INFO: 655360 events read in total (16181ms).
[11:50:52.403] <TB1> INFO: Expecting 655360 events.
[11:51:09.151] <TB1> INFO: 655360 events read in total (16346ms).
[11:51:09.180] <TB1> INFO: Expecting 655360 events.
[11:51:25.881] <TB1> INFO: 655360 events read in total (16299ms).
[11:51:25.914] <TB1> INFO: Expecting 655360 events.
[11:51:42.566] <TB1> INFO: 655360 events read in total (16251ms).
[11:51:42.604] <TB1> INFO: Expecting 655360 events.
[11:51:59.352] <TB1> INFO: 655360 events read in total (16346ms).
[11:51:59.395] <TB1> INFO: Expecting 655360 events.
[11:52:16.266] <TB1> INFO: 655360 events read in total (16469ms).
[11:52:16.308] <TB1> INFO: Expecting 655360 events.
[11:52:33.078] <TB1> INFO: 655360 events read in total (16369ms).
[11:52:33.125] <TB1> INFO: Expecting 655360 events.
[11:52:49.784] <TB1> INFO: 655360 events read in total (16257ms).
[11:52:49.834] <TB1> INFO: Expecting 655360 events.
[11:53:06.493] <TB1> INFO: 655360 events read in total (16257ms).
[11:53:06.547] <TB1> INFO: Expecting 655360 events.
[11:53:23.313] <TB1> INFO: 655360 events read in total (16364ms).
[11:53:23.415] <TB1> INFO: Expecting 655360 events.
[11:53:40.207] <TB1> INFO: 655360 events read in total (16390ms).
[11:53:40.277] <TB1> INFO: Test took 267891ms.
[11:53:40.356] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:40.586] <TB1> INFO: Expecting 655360 events.
[11:53:57.269] <TB1> INFO: 655360 events read in total (16127ms).
[11:53:57.280] <TB1> INFO: Expecting 655360 events.
[11:54:14.078] <TB1> INFO: 655360 events read in total (16396ms).
[11:54:14.090] <TB1> INFO: Expecting 655360 events.
[11:54:30.750] <TB1> INFO: 655360 events read in total (16258ms).
[11:54:30.768] <TB1> INFO: Expecting 655360 events.
[11:54:47.227] <TB1> INFO: 655360 events read in total (16058ms).
[11:54:47.245] <TB1> INFO: Expecting 655360 events.
[11:55:03.874] <TB1> INFO: 655360 events read in total (16227ms).
[11:55:03.895] <TB1> INFO: Expecting 655360 events.
[11:55:20.672] <TB1> INFO: 655360 events read in total (16375ms).
[11:55:20.700] <TB1> INFO: Expecting 655360 events.
[11:55:37.678] <TB1> INFO: 655360 events read in total (16576ms).
[11:55:37.710] <TB1> INFO: Expecting 655360 events.
[11:55:54.497] <TB1> INFO: 655360 events read in total (16385ms).
[11:55:54.531] <TB1> INFO: Expecting 655360 events.
[11:56:11.190] <TB1> INFO: 655360 events read in total (16258ms).
[11:56:11.235] <TB1> INFO: Expecting 655360 events.
[11:56:27.916] <TB1> INFO: 655360 events read in total (16280ms).
[11:56:27.957] <TB1> INFO: Expecting 655360 events.
[11:56:44.686] <TB1> INFO: 655360 events read in total (16328ms).
[11:56:44.731] <TB1> INFO: Expecting 655360 events.
[11:57:01.576] <TB1> INFO: 655360 events read in total (16443ms).
[11:57:01.623] <TB1> INFO: Expecting 655360 events.
[11:57:18.175] <TB1> INFO: 655360 events read in total (16150ms).
[11:57:18.228] <TB1> INFO: Expecting 655360 events.
[11:57:34.917] <TB1> INFO: 655360 events read in total (16287ms).
[11:57:34.983] <TB1> INFO: Expecting 655360 events.
[11:57:51.648] <TB1> INFO: 655360 events read in total (16263ms).
[11:57:51.711] <TB1> INFO: Expecting 655360 events.
[11:58:08.459] <TB1> INFO: 655360 events read in total (16346ms).
[11:58:08.530] <TB1> INFO: Test took 268174ms.
[11:58:08.714] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.725] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.732] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.738] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.745] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.751] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.758] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.765] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.771] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.778] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.784] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.791] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.797] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.804] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.810] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.817] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:58:08.853] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:58:08.854] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:58:09.131] <TB1> INFO: Expecting 41600 events.
[11:58:12.600] <TB1> INFO: 41600 events read in total (2913ms).
[11:58:12.600] <TB1> INFO: Test took 3743ms.
[11:58:13.069] <TB1> INFO: Expecting 41600 events.
[11:58:16.531] <TB1> INFO: 41600 events read in total (2905ms).
[11:58:16.531] <TB1> INFO: Test took 3751ms.
[11:58:16.986] <TB1> INFO: Expecting 41600 events.
[11:58:20.478] <TB1> INFO: 41600 events read in total (2936ms).
[11:58:20.479] <TB1> INFO: Test took 3770ms.
[11:58:20.661] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:20.749] <TB1> INFO: Expecting 2560 events.
[11:58:21.604] <TB1> INFO: 2560 events read in total (299ms).
[11:58:21.605] <TB1> INFO: Test took 944ms.
[11:58:21.606] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:21.912] <TB1> INFO: Expecting 2560 events.
[11:58:22.768] <TB1> INFO: 2560 events read in total (300ms).
[11:58:22.768] <TB1> INFO: Test took 1162ms.
[11:58:22.770] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:23.074] <TB1> INFO: Expecting 2560 events.
[11:58:23.928] <TB1> INFO: 2560 events read in total (298ms).
[11:58:23.929] <TB1> INFO: Test took 1159ms.
[11:58:23.930] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:24.235] <TB1> INFO: Expecting 2560 events.
[11:58:25.091] <TB1> INFO: 2560 events read in total (299ms).
[11:58:25.091] <TB1> INFO: Test took 1161ms.
[11:58:25.093] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:25.397] <TB1> INFO: Expecting 2560 events.
[11:58:26.249] <TB1> INFO: 2560 events read in total (296ms).
[11:58:26.249] <TB1> INFO: Test took 1156ms.
[11:58:26.251] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:26.555] <TB1> INFO: Expecting 2560 events.
[11:58:27.407] <TB1> INFO: 2560 events read in total (296ms).
[11:58:27.407] <TB1> INFO: Test took 1156ms.
[11:58:27.409] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:27.713] <TB1> INFO: Expecting 2560 events.
[11:58:28.565] <TB1> INFO: 2560 events read in total (296ms).
[11:58:28.565] <TB1> INFO: Test took 1156ms.
[11:58:28.567] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:28.870] <TB1> INFO: Expecting 2560 events.
[11:58:29.725] <TB1> INFO: 2560 events read in total (298ms).
[11:58:29.725] <TB1> INFO: Test took 1158ms.
[11:58:29.727] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:30.031] <TB1> INFO: Expecting 2560 events.
[11:58:30.887] <TB1> INFO: 2560 events read in total (300ms).
[11:58:30.887] <TB1> INFO: Test took 1160ms.
[11:58:30.889] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:31.193] <TB1> INFO: Expecting 2560 events.
[11:58:32.048] <TB1> INFO: 2560 events read in total (299ms).
[11:58:32.048] <TB1> INFO: Test took 1159ms.
[11:58:32.050] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:32.354] <TB1> INFO: Expecting 2560 events.
[11:58:33.206] <TB1> INFO: 2560 events read in total (296ms).
[11:58:33.206] <TB1> INFO: Test took 1156ms.
[11:58:33.208] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:33.511] <TB1> INFO: Expecting 2560 events.
[11:58:34.363] <TB1> INFO: 2560 events read in total (295ms).
[11:58:34.363] <TB1> INFO: Test took 1155ms.
[11:58:34.365] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:34.668] <TB1> INFO: Expecting 2560 events.
[11:58:35.522] <TB1> INFO: 2560 events read in total (297ms).
[11:58:35.522] <TB1> INFO: Test took 1157ms.
[11:58:35.524] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:35.827] <TB1> INFO: Expecting 2560 events.
[11:58:36.681] <TB1> INFO: 2560 events read in total (297ms).
[11:58:36.682] <TB1> INFO: Test took 1158ms.
[11:58:36.684] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:36.987] <TB1> INFO: Expecting 2560 events.
[11:58:37.845] <TB1> INFO: 2560 events read in total (302ms).
[11:58:37.845] <TB1> INFO: Test took 1161ms.
[11:58:37.847] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:38.152] <TB1> INFO: Expecting 2560 events.
[11:58:39.007] <TB1> INFO: 2560 events read in total (299ms).
[11:58:39.007] <TB1> INFO: Test took 1160ms.
[11:58:39.009] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:39.314] <TB1> INFO: Expecting 2560 events.
[11:58:40.167] <TB1> INFO: 2560 events read in total (297ms).
[11:58:40.167] <TB1> INFO: Test took 1158ms.
[11:58:40.169] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:40.473] <TB1> INFO: Expecting 2560 events.
[11:58:41.328] <TB1> INFO: 2560 events read in total (299ms).
[11:58:41.329] <TB1> INFO: Test took 1160ms.
[11:58:41.331] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:41.635] <TB1> INFO: Expecting 2560 events.
[11:58:42.487] <TB1> INFO: 2560 events read in total (296ms).
[11:58:42.487] <TB1> INFO: Test took 1156ms.
[11:58:42.489] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:42.793] <TB1> INFO: Expecting 2560 events.
[11:58:43.647] <TB1> INFO: 2560 events read in total (298ms).
[11:58:43.647] <TB1> INFO: Test took 1158ms.
[11:58:43.649] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:43.952] <TB1> INFO: Expecting 2560 events.
[11:58:44.805] <TB1> INFO: 2560 events read in total (297ms).
[11:58:44.805] <TB1> INFO: Test took 1156ms.
[11:58:44.807] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:45.111] <TB1> INFO: Expecting 2560 events.
[11:58:45.964] <TB1> INFO: 2560 events read in total (297ms).
[11:58:45.964] <TB1> INFO: Test took 1157ms.
[11:58:45.966] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:46.270] <TB1> INFO: Expecting 2560 events.
[11:58:47.122] <TB1> INFO: 2560 events read in total (296ms).
[11:58:47.122] <TB1> INFO: Test took 1156ms.
[11:58:47.124] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:47.428] <TB1> INFO: Expecting 2560 events.
[11:58:48.280] <TB1> INFO: 2560 events read in total (296ms).
[11:58:48.281] <TB1> INFO: Test took 1157ms.
[11:58:48.282] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:48.586] <TB1> INFO: Expecting 2560 events.
[11:58:49.439] <TB1> INFO: 2560 events read in total (297ms).
[11:58:49.439] <TB1> INFO: Test took 1157ms.
[11:58:49.441] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:49.745] <TB1> INFO: Expecting 2560 events.
[11:58:50.598] <TB1> INFO: 2560 events read in total (297ms).
[11:58:50.598] <TB1> INFO: Test took 1157ms.
[11:58:50.600] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:50.904] <TB1> INFO: Expecting 2560 events.
[11:58:51.757] <TB1> INFO: 2560 events read in total (297ms).
[11:58:51.757] <TB1> INFO: Test took 1157ms.
[11:58:51.759] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:52.063] <TB1> INFO: Expecting 2560 events.
[11:58:52.915] <TB1> INFO: 2560 events read in total (296ms).
[11:58:52.915] <TB1> INFO: Test took 1156ms.
[11:58:52.917] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:53.222] <TB1> INFO: Expecting 2560 events.
[11:58:54.078] <TB1> INFO: 2560 events read in total (300ms).
[11:58:54.078] <TB1> INFO: Test took 1161ms.
[11:58:54.081] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:54.383] <TB1> INFO: Expecting 2560 events.
[11:58:55.238] <TB1> INFO: 2560 events read in total (298ms).
[11:58:55.238] <TB1> INFO: Test took 1157ms.
[11:58:55.240] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:55.544] <TB1> INFO: Expecting 2560 events.
[11:58:56.397] <TB1> INFO: 2560 events read in total (296ms).
[11:58:56.397] <TB1> INFO: Test took 1157ms.
[11:58:56.399] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:58:56.702] <TB1> INFO: Expecting 2560 events.
[11:58:57.558] <TB1> INFO: 2560 events read in total (299ms).
[11:58:57.558] <TB1> INFO: Test took 1160ms.
[11:58:58.024] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[11:58:58.024] <TB1> INFO: PH scale (per ROC): 83 80 73 79 73 77 83 79 80 77 83 86 79 74 81 80
[11:58:58.024] <TB1> INFO: PH offset (per ROC): 162 149 173 169 155 174 160 173 172 182 160 160 173 160 172 178
[11:58:58.032] <TB1> INFO: Decoding statistics:
[11:58:58.032] <TB1> INFO: General information:
[11:58:58.032] <TB1> INFO: 16bit words read: 91920
[11:58:58.032] <TB1> INFO: valid events total: 10240
[11:58:58.032] <TB1> INFO: empty events: 7740
[11:58:58.032] <TB1> INFO: valid events with pixels: 2500
[11:58:58.032] <TB1> INFO: valid pixel hits: 2500
[11:58:58.032] <TB1> INFO: Event errors: 0
[11:58:58.032] <TB1> INFO: start marker: 0
[11:58:58.032] <TB1> INFO: stop marker: 0
[11:58:58.032] <TB1> INFO: overflow: 0
[11:58:58.032] <TB1> INFO: invalid 5bit words: 0
[11:58:58.032] <TB1> INFO: invalid XOR eye diagram: 0
[11:58:58.032] <TB1> INFO: frame (failed synchr.): 0
[11:58:58.032] <TB1> INFO: idle data (no TBM trl): 0
[11:58:58.032] <TB1> INFO: no data (only TBM hdr): 0
[11:58:58.032] <TB1> INFO: TBM errors: 0
[11:58:58.032] <TB1> INFO: flawed TBM headers: 0
[11:58:58.032] <TB1> INFO: flawed TBM trailers: 0
[11:58:58.032] <TB1> INFO: event ID mismatches: 0
[11:58:58.032] <TB1> INFO: ROC errors: 0
[11:58:58.032] <TB1> INFO: missing ROC header(s): 0
[11:58:58.032] <TB1> INFO: misplaced readback start: 0
[11:58:58.032] <TB1> INFO: Pixel decoding errors: 0
[11:58:58.032] <TB1> INFO: pixel data incomplete: 0
[11:58:58.032] <TB1> INFO: pixel address: 0
[11:58:58.032] <TB1> INFO: pulse height fill bit: 0
[11:58:58.032] <TB1> INFO: buffer corruption: 0
[11:58:58.199] <TB1> INFO: ######################################################################
[11:58:58.199] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:58:58.199] <TB1> INFO: ######################################################################
[11:58:58.208] <TB1> INFO: scanning low vcal = 10
[11:58:58.470] <TB1> INFO: Expecting 41600 events.
[11:59:02.024] <TB1> INFO: 41600 events read in total (2997ms).
[11:59:02.024] <TB1> INFO: Test took 3815ms.
[11:59:02.027] <TB1> INFO: scanning low vcal = 20
[11:59:02.328] <TB1> INFO: Expecting 41600 events.
[11:59:05.866] <TB1> INFO: 41600 events read in total (2983ms).
[11:59:05.867] <TB1> INFO: Test took 3840ms.
[11:59:05.873] <TB1> INFO: scanning low vcal = 30
[11:59:06.172] <TB1> INFO: Expecting 41600 events.
[11:59:09.713] <TB1> INFO: 41600 events read in total (2985ms).
[11:59:09.714] <TB1> INFO: Test took 3841ms.
[11:59:09.716] <TB1> INFO: scanning low vcal = 40
[11:59:10.014] <TB1> INFO: Expecting 41600 events.
[11:59:14.183] <TB1> INFO: 41600 events read in total (3613ms).
[11:59:14.184] <TB1> INFO: Test took 4468ms.
[11:59:14.187] <TB1> INFO: scanning low vcal = 50
[11:59:14.453] <TB1> INFO: Expecting 41600 events.
[11:59:18.627] <TB1> INFO: 41600 events read in total (3618ms).
[11:59:18.627] <TB1> INFO: Test took 4440ms.
[11:59:18.630] <TB1> INFO: scanning low vcal = 60
[11:59:18.892] <TB1> INFO: Expecting 41600 events.
[11:59:23.103] <TB1> INFO: 41600 events read in total (3655ms).
[11:59:23.104] <TB1> INFO: Test took 4474ms.
[11:59:23.106] <TB1> INFO: scanning low vcal = 70
[11:59:23.370] <TB1> INFO: Expecting 41600 events.
[11:59:27.604] <TB1> INFO: 41600 events read in total (3678ms).
[11:59:27.604] <TB1> INFO: Test took 4497ms.
[11:59:27.607] <TB1> INFO: scanning low vcal = 80
[11:59:27.880] <TB1> INFO: Expecting 41600 events.
[11:59:32.102] <TB1> INFO: 41600 events read in total (3665ms).
[11:59:32.102] <TB1> INFO: Test took 4495ms.
[11:59:32.105] <TB1> INFO: scanning low vcal = 90
[11:59:32.371] <TB1> INFO: Expecting 41600 events.
[11:59:36.598] <TB1> INFO: 41600 events read in total (3670ms).
[11:59:36.598] <TB1> INFO: Test took 4493ms.
[11:59:36.601] <TB1> INFO: scanning low vcal = 100
[11:59:36.911] <TB1> INFO: Expecting 41600 events.
[11:59:41.133] <TB1> INFO: 41600 events read in total (3666ms).
[11:59:41.133] <TB1> INFO: Test took 4532ms.
[11:59:41.136] <TB1> INFO: scanning low vcal = 110
[11:59:41.447] <TB1> INFO: Expecting 41600 events.
[11:59:45.661] <TB1> INFO: 41600 events read in total (3658ms).
[11:59:45.662] <TB1> INFO: Test took 4526ms.
[11:59:45.664] <TB1> INFO: scanning low vcal = 120
[11:59:45.962] <TB1> INFO: Expecting 41600 events.
[11:59:50.160] <TB1> INFO: 41600 events read in total (3642ms).
[11:59:50.161] <TB1> INFO: Test took 4496ms.
[11:59:50.163] <TB1> INFO: scanning low vcal = 130
[11:59:50.441] <TB1> INFO: Expecting 41600 events.
[11:59:54.665] <TB1> INFO: 41600 events read in total (3668ms).
[11:59:54.665] <TB1> INFO: Test took 4502ms.
[11:59:54.667] <TB1> INFO: scanning low vcal = 140
[11:59:54.930] <TB1> INFO: Expecting 41600 events.
[11:59:59.165] <TB1> INFO: 41600 events read in total (3679ms).
[11:59:59.166] <TB1> INFO: Test took 4498ms.
[11:59:59.168] <TB1> INFO: scanning low vcal = 150
[11:59:59.427] <TB1> INFO: Expecting 41600 events.
[12:00:03.629] <TB1> INFO: 41600 events read in total (3646ms).
[12:00:03.629] <TB1> INFO: Test took 4461ms.
[12:00:03.632] <TB1> INFO: scanning low vcal = 160
[12:00:03.893] <TB1> INFO: Expecting 41600 events.
[12:00:08.086] <TB1> INFO: 41600 events read in total (3637ms).
[12:00:08.087] <TB1> INFO: Test took 4455ms.
[12:00:08.089] <TB1> INFO: scanning low vcal = 170
[12:00:08.393] <TB1> INFO: Expecting 41600 events.
[12:00:12.591] <TB1> INFO: 41600 events read in total (3642ms).
[12:00:12.592] <TB1> INFO: Test took 4503ms.
[12:00:12.595] <TB1> INFO: scanning low vcal = 180
[12:00:12.853] <TB1> INFO: Expecting 41600 events.
[12:00:17.109] <TB1> INFO: 41600 events read in total (3700ms).
[12:00:17.109] <TB1> INFO: Test took 4514ms.
[12:00:17.111] <TB1> INFO: scanning low vcal = 190
[12:00:17.378] <TB1> INFO: Expecting 41600 events.
[12:00:21.582] <TB1> INFO: 41600 events read in total (3639ms).
[12:00:21.582] <TB1> INFO: Test took 4471ms.
[12:00:21.584] <TB1> INFO: scanning low vcal = 200
[12:00:21.843] <TB1> INFO: Expecting 41600 events.
[12:00:26.030] <TB1> INFO: 41600 events read in total (3631ms).
[12:00:26.031] <TB1> INFO: Test took 4447ms.
[12:00:26.033] <TB1> INFO: scanning low vcal = 210
[12:00:26.291] <TB1> INFO: Expecting 41600 events.
[12:00:30.487] <TB1> INFO: 41600 events read in total (3639ms).
[12:00:30.487] <TB1> INFO: Test took 4454ms.
[12:00:30.490] <TB1> INFO: scanning low vcal = 220
[12:00:30.745] <TB1> INFO: Expecting 41600 events.
[12:00:34.917] <TB1> INFO: 41600 events read in total (3615ms).
[12:00:34.918] <TB1> INFO: Test took 4428ms.
[12:00:34.920] <TB1> INFO: scanning low vcal = 230
[12:00:35.178] <TB1> INFO: Expecting 41600 events.
[12:00:39.363] <TB1> INFO: 41600 events read in total (3629ms).
[12:00:39.363] <TB1> INFO: Test took 4443ms.
[12:00:39.366] <TB1> INFO: scanning low vcal = 240
[12:00:39.625] <TB1> INFO: Expecting 41600 events.
[12:00:43.872] <TB1> INFO: 41600 events read in total (3690ms).
[12:00:43.873] <TB1> INFO: Test took 4507ms.
[12:00:43.876] <TB1> INFO: scanning low vcal = 250
[12:00:44.145] <TB1> INFO: Expecting 41600 events.
[12:00:48.362] <TB1> INFO: 41600 events read in total (3661ms).
[12:00:48.363] <TB1> INFO: Test took 4487ms.
[12:00:48.367] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[12:00:48.656] <TB1> INFO: Expecting 41600 events.
[12:00:52.866] <TB1> INFO: 41600 events read in total (3654ms).
[12:00:52.866] <TB1> INFO: Test took 4499ms.
[12:00:52.868] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[12:00:53.129] <TB1> INFO: Expecting 41600 events.
[12:00:57.326] <TB1> INFO: 41600 events read in total (3641ms).
[12:00:57.327] <TB1> INFO: Test took 4458ms.
[12:00:57.329] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[12:00:57.595] <TB1> INFO: Expecting 41600 events.
[12:01:01.824] <TB1> INFO: 41600 events read in total (3673ms).
[12:01:01.824] <TB1> INFO: Test took 4495ms.
[12:01:01.827] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[12:01:02.096] <TB1> INFO: Expecting 41600 events.
[12:01:06.285] <TB1> INFO: 41600 events read in total (3633ms).
[12:01:06.286] <TB1> INFO: Test took 4459ms.
[12:01:06.289] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:01:06.567] <TB1> INFO: Expecting 41600 events.
[12:01:10.758] <TB1> INFO: 41600 events read in total (3634ms).
[12:01:10.759] <TB1> INFO: Test took 4470ms.
[12:01:11.262] <TB1> INFO: PixTestGainPedestal::measure() done
[12:01:42.562] <TB1> INFO: PixTestGainPedestal::fit() done
[12:01:42.562] <TB1> INFO: non-linearity mean: 0.952 0.955 0.957 0.956 0.955 0.950 0.953 0.953 0.960 0.956 0.957 0.956 0.952 0.954 0.959 0.964
[12:01:42.562] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.005 0.005 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.008 0.005 0.006 0.005
[12:01:42.562] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[12:01:42.581] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[12:01:42.599] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[12:01:42.617] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[12:01:42.636] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[12:01:42.654] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[12:01:42.673] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[12:01:42.691] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[12:01:42.710] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[12:01:42.728] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[12:01:42.746] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[12:01:42.781] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[12:01:42.811] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[12:01:42.829] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[12:01:42.848] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[12:01:42.866] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[12:01:42.885] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[12:01:42.885] <TB1> INFO: Decoding statistics:
[12:01:42.885] <TB1> INFO: General information:
[12:01:42.885] <TB1> INFO: 16bit words read: 2662400
[12:01:42.885] <TB1> INFO: valid events total: 166400
[12:01:42.885] <TB1> INFO: empty events: 0
[12:01:42.885] <TB1> INFO: valid events with pixels: 166400
[12:01:42.885] <TB1> INFO: valid pixel hits: 665570
[12:01:42.885] <TB1> INFO: Event errors: 0
[12:01:42.885] <TB1> INFO: start marker: 0
[12:01:42.885] <TB1> INFO: stop marker: 0
[12:01:42.885] <TB1> INFO: overflow: 0
[12:01:42.885] <TB1> INFO: invalid 5bit words: 0
[12:01:42.885] <TB1> INFO: invalid XOR eye diagram: 0
[12:01:42.885] <TB1> INFO: frame (failed synchr.): 0
[12:01:42.885] <TB1> INFO: idle data (no TBM trl): 0
[12:01:42.885] <TB1> INFO: no data (only TBM hdr): 0
[12:01:42.885] <TB1> INFO: TBM errors: 0
[12:01:42.885] <TB1> INFO: flawed TBM headers: 0
[12:01:42.885] <TB1> INFO: flawed TBM trailers: 0
[12:01:42.885] <TB1> INFO: event ID mismatches: 0
[12:01:42.885] <TB1> INFO: ROC errors: 0
[12:01:42.885] <TB1> INFO: missing ROC header(s): 0
[12:01:42.885] <TB1> INFO: misplaced readback start: 0
[12:01:42.885] <TB1> INFO: Pixel decoding errors: 0
[12:01:42.885] <TB1> INFO: pixel data incomplete: 0
[12:01:42.885] <TB1> INFO: pixel address: 0
[12:01:42.885] <TB1> INFO: pulse height fill bit: 0
[12:01:42.885] <TB1> INFO: buffer corruption: 0
[12:01:42.892] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C15.dat
[12:01:42.893] <TB1> INFO: ######################################################################
[12:01:42.893] <TB1> INFO: PixTestReadback::doTest()
[12:01:42.893] <TB1> INFO: ######################################################################
[12:01:42.893] <TB1> INFO: ----------------------------------------------------------------------
[12:01:42.893] <TB1> INFO: PixTestReadback::CalibrateVd()
[12:01:42.893] <TB1> INFO: ----------------------------------------------------------------------
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C0.dat
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C1.dat
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C2.dat
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C3.dat
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C4.dat
[12:01:52.356] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C5.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C6.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C7.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C8.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C9.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C10.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C11.dat
[12:01:52.357] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C12.dat
[12:01:52.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C13.dat
[12:01:52.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C14.dat
[12:01:52.358] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C15.dat
[12:01:52.386] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:01:52.386] <TB1> INFO: ----------------------------------------------------------------------
[12:01:52.386] <TB1> INFO: PixTestReadback::CalibrateVa()
[12:01:52.386] <TB1> INFO: ----------------------------------------------------------------------
[12:02:01.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C0.dat
[12:02:01.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C1.dat
[12:02:01.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C2.dat
[12:02:01.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C3.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C4.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C5.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C6.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C7.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C8.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C9.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C10.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C11.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C12.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C13.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C14.dat
[12:02:01.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C15.dat
[12:02:01.915] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:02:01.915] <TB1> INFO: ----------------------------------------------------------------------
[12:02:01.915] <TB1> INFO: PixTestReadback::readbackVbg()
[12:02:01.915] <TB1> INFO: ----------------------------------------------------------------------
[12:02:09.206] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:02:09.206] <TB1> INFO: ----------------------------------------------------------------------
[12:02:09.206] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[12:02:09.206] <TB1> INFO: ----------------------------------------------------------------------
[12:02:09.206] <TB1> INFO: Vbg will be calibrated using Vd calibration
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.6calibrated Vbg = 1.17453 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.3calibrated Vbg = 1.17136 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.9calibrated Vbg = 1.17502 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153calibrated Vbg = 1.18255 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.4calibrated Vbg = 1.1885 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.8calibrated Vbg = 1.18762 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.8calibrated Vbg = 1.18088 :::*/*/*/*/
[12:02:09.206] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.7calibrated Vbg = 1.19304 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.3calibrated Vbg = 1.19243 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 139.9calibrated Vbg = 1.19439 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.2calibrated Vbg = 1.18584 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149calibrated Vbg = 1.18543 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.5calibrated Vbg = 1.17337 :::*/*/*/*/
[12:02:09.207] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.8calibrated Vbg = 1.17872 :::*/*/*/*/
[12:02:09.208] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.5calibrated Vbg = 1.18957 :::*/*/*/*/
[12:02:09.208] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.8calibrated Vbg = 1.17962 :::*/*/*/*/
[12:02:09.209] <TB1> INFO: ----------------------------------------------------------------------
[12:02:09.209] <TB1> INFO: PixTestReadback::CalibrateIa()
[12:02:09.209] <TB1> INFO: ----------------------------------------------------------------------
[12:04:44.502] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C0.dat
[12:04:44.502] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C1.dat
[12:04:44.502] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C2.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C3.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C4.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C5.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C6.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C7.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C8.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C9.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C10.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C11.dat
[12:04:44.503] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C12.dat
[12:04:44.504] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C13.dat
[12:04:44.504] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C14.dat
[12:04:44.504] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//002_FulltestPxar_m20//readbackCal_C15.dat
[12:04:44.532] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:04:44.533] <TB1> INFO: PixTestReadback::doTest() done
[12:04:44.533] <TB1> INFO: Decoding statistics:
[12:04:44.533] <TB1> INFO: General information:
[12:04:44.533] <TB1> INFO: 16bit words read: 1024
[12:04:44.533] <TB1> INFO: valid events total: 128
[12:04:44.533] <TB1> INFO: empty events: 128
[12:04:44.533] <TB1> INFO: valid events with pixels: 0
[12:04:44.533] <TB1> INFO: valid pixel hits: 0
[12:04:44.533] <TB1> INFO: Event errors: 0
[12:04:44.533] <TB1> INFO: start marker: 0
[12:04:44.533] <TB1> INFO: stop marker: 0
[12:04:44.533] <TB1> INFO: overflow: 0
[12:04:44.533] <TB1> INFO: invalid 5bit words: 0
[12:04:44.533] <TB1> INFO: invalid XOR eye diagram: 0
[12:04:44.533] <TB1> INFO: frame (failed synchr.): 0
[12:04:44.533] <TB1> INFO: idle data (no TBM trl): 0
[12:04:44.533] <TB1> INFO: no data (only TBM hdr): 0
[12:04:44.533] <TB1> INFO: TBM errors: 0
[12:04:44.533] <TB1> INFO: flawed TBM headers: 0
[12:04:44.533] <TB1> INFO: flawed TBM trailers: 0
[12:04:44.533] <TB1> INFO: event ID mismatches: 0
[12:04:44.533] <TB1> INFO: ROC errors: 0
[12:04:44.533] <TB1> INFO: missing ROC header(s): 0
[12:04:44.533] <TB1> INFO: misplaced readback start: 0
[12:04:44.533] <TB1> INFO: Pixel decoding errors: 0
[12:04:44.533] <TB1> INFO: pixel data incomplete: 0
[12:04:44.533] <TB1> INFO: pixel address: 0
[12:04:44.533] <TB1> INFO: pulse height fill bit: 0
[12:04:44.533] <TB1> INFO: buffer corruption: 0
[12:04:44.551] <TB1> INFO: Decoding statistics:
[12:04:44.551] <TB1> INFO: General information:
[12:04:44.551] <TB1> INFO: 16bit words read: 2755344
[12:04:44.551] <TB1> INFO: valid events total: 176768
[12:04:44.551] <TB1> INFO: empty events: 7868
[12:04:44.551] <TB1> INFO: valid events with pixels: 168900
[12:04:44.551] <TB1> INFO: valid pixel hits: 668070
[12:04:44.551] <TB1> INFO: Event errors: 0
[12:04:44.551] <TB1> INFO: start marker: 0
[12:04:44.551] <TB1> INFO: stop marker: 0
[12:04:44.551] <TB1> INFO: overflow: 0
[12:04:44.551] <TB1> INFO: invalid 5bit words: 0
[12:04:44.551] <TB1> INFO: invalid XOR eye diagram: 0
[12:04:44.551] <TB1> INFO: frame (failed synchr.): 0
[12:04:44.551] <TB1> INFO: idle data (no TBM trl): 0
[12:04:44.551] <TB1> INFO: no data (only TBM hdr): 0
[12:04:44.551] <TB1> INFO: TBM errors: 0
[12:04:44.551] <TB1> INFO: flawed TBM headers: 0
[12:04:44.551] <TB1> INFO: flawed TBM trailers: 0
[12:04:44.551] <TB1> INFO: event ID mismatches: 0
[12:04:44.551] <TB1> INFO: ROC errors: 0
[12:04:44.551] <TB1> INFO: missing ROC header(s): 0
[12:04:44.551] <TB1> INFO: misplaced readback start: 0
[12:04:44.551] <TB1> INFO: Pixel decoding errors: 0
[12:04:44.551] <TB1> INFO: pixel data incomplete: 0
[12:04:44.551] <TB1> INFO: pixel address: 0
[12:04:44.551] <TB1> INFO: pulse height fill bit: 0
[12:04:44.551] <TB1> INFO: buffer corruption: 0
[12:04:44.551] <TB1> INFO: enter test to run
[12:04:44.551] <TB1> INFO: test: exit no parameter change
[12:04:44.669] <TB1> QUIET: Connection to board 153 closed.
[12:04:44.748] <TB1> INFO: pXar: this is the end, my friend