[08:19:32.351] <TB0> INFO: *** Welcome to pxar ***
[08:19:32.351] <TB0> INFO: *** Today: 2016/01/13
[08:19:33.586] <TB0> INFO: *** Version: 3197
[08:19:33.586] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C15.dat
[08:19:33.586] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//tbmParameters_C0b.dat
[08:19:33.586] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//defaultMaskFile.dat
[08:19:33.586] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters_C15.dat
[08:19:33.654] <TB0> INFO: clk: 4
[08:19:33.654] <TB0> INFO: ctr: 4
[08:19:33.654] <TB0> INFO: sda: 19
[08:19:33.654] <TB0> INFO: tin: 9
[08:19:33.654] <TB0> INFO: level: 15
[08:19:33.654] <TB0> INFO: triggerdelay: 0
[08:19:33.654] <TB0> QUIET: Instanciating API for pxar prod-12
[08:19:33.654] <TB0> INFO: Log level: INFO
[08:19:33.668] <TB0> QUIET: Connection to board DTB_WZ4BYI opened.
[08:19:33.672] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 169
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WZ4BYI
MAC address: 40D8551180A9
Hostname: pixelDTB169
Comment:
------------------------------------------------------
[08:19:33.675] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[08:19:35.187] <TB0> INFO: DUT info:
[08:19:35.187] <TB0> INFO: The DUT currently contains the following objects:
[08:19:35.187] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:19:35.187] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:19:35.187] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:19:35.187] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:19:35.187] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.187] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.188] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.188] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:19:35.588] <TB0> INFO: enter 'restricted' command line mode
[08:19:35.588] <TB0> INFO: enter test to run
[08:19:35.588] <TB0> INFO: test: pretest no parameter change
[08:19:35.588] <TB0> INFO: running: pretest
[08:19:35.593] <TB0> INFO: ######################################################################
[08:19:35.593] <TB0> INFO: PixTestPretest::doTest()
[08:19:35.593] <TB0> INFO: ######################################################################
[08:19:35.594] <TB0> INFO: ----------------------------------------------------------------------
[08:19:35.594] <TB0> INFO: PixTestPretest::programROC()
[08:19:35.594] <TB0> INFO: ----------------------------------------------------------------------
[08:19:53.615] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:19:53.615] <TB0> INFO: IA differences per ROC: 19.3 20.9 17.7 20.9 18.5 18.5 20.1 19.3 17.7 20.1 16.9 18.5 17.7 20.1 20.1 16.9
[08:19:53.701] <TB0> INFO: ----------------------------------------------------------------------
[08:19:53.701] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:19:53.701] <TB0> INFO: ----------------------------------------------------------------------
[08:20:00.112] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[08:20:00.112] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 18.5 19.3 18.5 18.5 18.5 19.3 19.3 19.3 19.3 18.5 18.5 18.5 18.5
[08:20:00.157] <TB0> INFO: ----------------------------------------------------------------------
[08:20:00.157] <TB0> INFO: PixTestPretest::findTiming()
[08:20:00.157] <TB0> INFO: ----------------------------------------------------------------------
[08:20:00.158] <TB0> INFO: PixTestCmd::init()

[08:22:32.623] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:22:32.623] <TB0> INFO: (success/tries = 100/100), width = 5
[08:22:32.625] <TB0> INFO: ----------------------------------------------------------------------
[08:22:32.625] <TB0> INFO: PixTestPretest::findWorkingPixel()
[08:22:32.625] <TB0> INFO: ----------------------------------------------------------------------
[08:22:32.764] <TB0> INFO: Expecting 231680 events.
[08:22:40.785] <TB0> INFO: 231680 events read in total (7305ms).
[08:22:40.789] <TB0> INFO: Test took 8162ms.
[08:22:41.098] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:22:41.146] <TB0> INFO: ----------------------------------------------------------------------
[08:22:41.146] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[08:22:41.146] <TB0> INFO: ----------------------------------------------------------------------
[08:22:41.285] <TB0> INFO: Expecting 231680 events.
[08:22:49.629] <TB0> INFO: 231680 events read in total (7628ms).
[08:22:49.632] <TB0> INFO: Test took 8480ms.
[08:22:49.968] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[08:22:49.968] <TB0> INFO: CalDel: 119 121 151 126 133 132 127 126 124 143 133 138 132 138 128 117
[08:22:49.968] <TB0> INFO: VthrComp: 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51 53
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C0.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C1.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C2.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C3.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C4.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C5.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C6.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C7.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C8.dat
[08:22:49.971] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C9.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C10.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C11.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C12.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C13.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C14.dat
[08:22:49.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters_C15.dat
[08:22:49.972] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//tbmParameters_C0a.dat
[08:22:49.972] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//tbmParameters_C0b.dat
[08:22:49.972] <TB0> INFO: PixTestPretest::doTest() done, duration: 194 seconds
[08:22:50.036] <TB0> INFO: enter test to run
[08:22:50.036] <TB0> INFO: test: fulltest no parameter change
[08:22:50.036] <TB0> INFO: running: fulltest
[08:22:50.036] <TB0> INFO: ######################################################################
[08:22:50.036] <TB0> INFO: PixTestFullTest::doTest()
[08:22:50.036] <TB0> INFO: ######################################################################
[08:22:50.037] <TB0> INFO: ######################################################################
[08:22:50.037] <TB0> INFO: PixTestAlive::doTest()
[08:22:50.037] <TB0> INFO: ######################################################################
[08:22:50.039] <TB0> INFO: ----------------------------------------------------------------------
[08:22:50.039] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:22:50.039] <TB0> INFO: ----------------------------------------------------------------------
[08:22:50.341] <TB0> INFO: Expecting 41600 events.
[08:22:54.468] <TB0> INFO: 41600 events read in total (3411ms).
[08:22:54.469] <TB0> INFO: Test took 4428ms.
[08:22:54.475] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:54.783] <TB0> INFO: PixTestAlive::aliveTest() done
[08:22:54.783] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[08:22:54.785] <TB0> INFO: ----------------------------------------------------------------------
[08:22:54.785] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:22:54.785] <TB0> INFO: ----------------------------------------------------------------------
[08:22:55.087] <TB0> INFO: Expecting 41600 events.
[08:22:58.184] <TB0> INFO: 41600 events read in total (2380ms).
[08:22:58.184] <TB0> INFO: Test took 3397ms.
[08:22:58.184] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:58.184] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:22:58.512] <TB0> INFO: PixTestAlive::maskTest() done
[08:22:58.512] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:22:58.514] <TB0> INFO: ----------------------------------------------------------------------
[08:22:58.514] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:22:58.514] <TB0> INFO: ----------------------------------------------------------------------
[08:22:58.814] <TB0> INFO: Expecting 41600 events.
[08:23:02.983] <TB0> INFO: 41600 events read in total (3452ms).
[08:23:02.984] <TB0> INFO: Test took 4468ms.
[08:23:02.990] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:03.299] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[08:23:03.299] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:23:03.299] <TB0> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[08:23:03.299] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:03.299] <TB0> INFO: Decoding statistics:
[08:23:03.299] <TB0> INFO: General information:
[08:23:03.299] <TB0> INFO: 16bit words read: 0
[08:23:03.299] <TB0> INFO: valid events total: 0
[08:23:03.299] <TB0> INFO: empty events: 0
[08:23:03.299] <TB0> INFO: valid events with pixels: 0
[08:23:03.299] <TB0> INFO: valid pixel hits: 0
[08:23:03.299] <TB0> INFO: Event errors: 0
[08:23:03.299] <TB0> INFO: start marker: 0
[08:23:03.299] <TB0> INFO: stop marker: 0
[08:23:03.299] <TB0> INFO: overflow: 0
[08:23:03.299] <TB0> INFO: invalid 5bit words: 0
[08:23:03.299] <TB0> INFO: invalid XOR eye diagram: 0
[08:23:03.299] <TB0> INFO: TBM errors: 0
[08:23:03.299] <TB0> INFO: flawed TBM headers: 0
[08:23:03.299] <TB0> INFO: flawed TBM trailers: 0
[08:23:03.299] <TB0> INFO: event ID mismatches: 0
[08:23:03.299] <TB0> INFO: ROC errors: 0
[08:23:03.299] <TB0> INFO: missing ROC header(s): 0
[08:23:03.299] <TB0> INFO: misplaced readback start: 0
[08:23:03.299] <TB0> INFO: Pixel decoding errors: 0
[08:23:03.299] <TB0> INFO: pixel data incomplete: 0
[08:23:03.299] <TB0> INFO: pixel address: 0
[08:23:03.299] <TB0> INFO: pulse height fill bit: 0
[08:23:03.299] <TB0> INFO: buffer corruption: 0
[08:23:03.306] <TB0> INFO: ######################################################################
[08:23:03.306] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:23:03.306] <TB0> INFO: ######################################################################
[08:23:03.310] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:23:03.320] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:23:03.320] <TB0> INFO: run 1 of 1
[08:23:03.619] <TB0> INFO: Expecting 3120000 events.
[08:23:42.105] <TB0> INFO: 887565 events read in total (37770ms).
[08:24:19.607] <TB0> INFO: 1761430 events read in total (75272ms).
[08:24:57.133] <TB0> INFO: 2645925 events read in total (112798ms).
[08:25:17.345] <TB0> INFO: 3120000 events read in total (133010ms).
[08:25:17.389] <TB0> INFO: Test took 134068ms.
[08:25:17.476] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:38.968] <TB0> INFO: PixTestBBMap::doTest() done, duration: 155 seconds
[08:25:38.968] <TB0> INFO: number of dead bumps (per ROC): 9 6 6 8 4 1 7 12 13 15 1 2 2 0 5 8
[08:25:38.968] <TB0> INFO: separation cut (per ROC): 93 98 80 104 103 94 108 105 82 82 91 93 91 103 96 102
[08:25:38.968] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:38.968] <TB0> INFO: Decoding statistics:
[08:25:38.968] <TB0> INFO: General information:
[08:25:38.968] <TB0> INFO: 16bit words read: 0
[08:25:38.968] <TB0> INFO: valid events total: 0
[08:25:38.968] <TB0> INFO: empty events: 0
[08:25:38.968] <TB0> INFO: valid events with pixels: 0
[08:25:38.968] <TB0> INFO: valid pixel hits: 0
[08:25:38.968] <TB0> INFO: Event errors: 0
[08:25:38.968] <TB0> INFO: start marker: 0
[08:25:38.968] <TB0> INFO: stop marker: 0
[08:25:38.968] <TB0> INFO: overflow: 0
[08:25:38.968] <TB0> INFO: invalid 5bit words: 0
[08:25:38.968] <TB0> INFO: invalid XOR eye diagram: 0
[08:25:38.968] <TB0> INFO: TBM errors: 0
[08:25:38.968] <TB0> INFO: flawed TBM headers: 0
[08:25:38.968] <TB0> INFO: flawed TBM trailers: 0
[08:25:38.968] <TB0> INFO: event ID mismatches: 0
[08:25:38.968] <TB0> INFO: ROC errors: 0
[08:25:38.968] <TB0> INFO: missing ROC header(s): 0
[08:25:38.968] <TB0> INFO: misplaced readback start: 0
[08:25:38.968] <TB0> INFO: Pixel decoding errors: 0
[08:25:38.968] <TB0> INFO: pixel data incomplete: 0
[08:25:38.968] <TB0> INFO: pixel address: 0
[08:25:38.968] <TB0> INFO: pulse height fill bit: 0
[08:25:38.968] <TB0> INFO: buffer corruption: 0
[08:25:39.014] <TB0> INFO: ######################################################################
[08:25:39.014] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:25:39.014] <TB0> INFO: ######################################################################
[08:25:39.014] <TB0> INFO: ----------------------------------------------------------------------
[08:25:39.014] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:25:39.014] <TB0> INFO: ----------------------------------------------------------------------
[08:25:39.014] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:25:39.022] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[08:25:39.022] <TB0> INFO: run 1 of 1
[08:25:39.323] <TB0> INFO: Expecting 26208000 events.
[08:26:07.047] <TB0> INFO: 937400 events read in total (27008ms).
[08:26:33.881] <TB0> INFO: 1858350 events read in total (53842ms).
[08:27:01.012] <TB0> INFO: 2780300 events read in total (80973ms).
[08:27:27.985] <TB0> INFO: 3701600 events read in total (107946ms).
[08:27:55.025] <TB0> INFO: 4620300 events read in total (134986ms).
[08:28:21.945] <TB0> INFO: 5537000 events read in total (161906ms).
[08:28:49.204] <TB0> INFO: 6453000 events read in total (189165ms).
[08:29:16.076] <TB0> INFO: 7368600 events read in total (216037ms).
[08:29:43.199] <TB0> INFO: 8284950 events read in total (243160ms).
[08:30:10.193] <TB0> INFO: 9201550 events read in total (270154ms).
[08:30:37.290] <TB0> INFO: 10115350 events read in total (297251ms).
[08:31:04.861] <TB0> INFO: 11024850 events read in total (324822ms).
[08:31:32.758] <TB0> INFO: 11938100 events read in total (352719ms).
[08:32:01.105] <TB0> INFO: 12850550 events read in total (381066ms).
[08:32:28.215] <TB0> INFO: 13753550 events read in total (408176ms).
[08:32:55.187] <TB0> INFO: 14653700 events read in total (435148ms).
[08:33:22.003] <TB0> INFO: 15552750 events read in total (461964ms).
[08:33:48.879] <TB0> INFO: 16447800 events read in total (488840ms).
[08:34:15.754] <TB0> INFO: 17344600 events read in total (515715ms).
[08:34:42.869] <TB0> INFO: 18242400 events read in total (542830ms).
[08:35:10.079] <TB0> INFO: 19137500 events read in total (570040ms).
[08:35:36.937] <TB0> INFO: 20031600 events read in total (596898ms).
[08:36:03.775] <TB0> INFO: 20926300 events read in total (623736ms).
[08:36:31.157] <TB0> INFO: 21820900 events read in total (651118ms).
[08:36:57.848] <TB0> INFO: 22713400 events read in total (677809ms).
[08:37:24.666] <TB0> INFO: 23610100 events read in total (704627ms).
[08:37:51.363] <TB0> INFO: 24506700 events read in total (731324ms).
[08:38:18.279] <TB0> INFO: 25406250 events read in total (758240ms).
[08:38:41.844] <TB0> INFO: 26208000 events read in total (781805ms).
[08:38:41.876] <TB0> INFO: Test took 782854ms.
[08:38:41.947] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:42.085] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:43.407] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:44.759] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:46.119] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:47.438] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:48.748] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:50.074] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:51.390] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:52.731] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:54.077] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:55.421] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:56.756] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:58.081] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:38:59.426] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:39:00.726] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:39:02.039] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:39:03.387] <TB0> INFO: PixTestScurves::scurves() done
[08:39:03.387] <TB0> INFO: Vcal mean: 104.51 89.65 82.95 104.28 105.55 101.66 105.99 95.16 90.47 94.31 95.03 92.48 82.74 103.92 99.29 106.55
[08:39:03.387] <TB0> INFO: Vcal RMS: 4.79 5.21 4.33 5.62 5.28 5.58 4.91 5.54 5.42 5.69 5.22 5.13 4.65 6.22 5.08 5.26
[08:39:03.388] <TB0> INFO: PixTestScurves::fullTest() done, duration: 804 seconds
[08:39:03.388] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:03.388] <TB0> INFO: Decoding statistics:
[08:39:03.388] <TB0> INFO: General information:
[08:39:03.388] <TB0> INFO: 16bit words read: 0
[08:39:03.388] <TB0> INFO: valid events total: 0
[08:39:03.388] <TB0> INFO: empty events: 0
[08:39:03.388] <TB0> INFO: valid events with pixels: 0
[08:39:03.388] <TB0> INFO: valid pixel hits: 0
[08:39:03.388] <TB0> INFO: Event errors: 0
[08:39:03.388] <TB0> INFO: start marker: 0
[08:39:03.388] <TB0> INFO: stop marker: 0
[08:39:03.388] <TB0> INFO: overflow: 0
[08:39:03.388] <TB0> INFO: invalid 5bit words: 0
[08:39:03.388] <TB0> INFO: invalid XOR eye diagram: 0
[08:39:03.388] <TB0> INFO: TBM errors: 0
[08:39:03.388] <TB0> INFO: flawed TBM headers: 0
[08:39:03.388] <TB0> INFO: flawed TBM trailers: 0
[08:39:03.388] <TB0> INFO: event ID mismatches: 0
[08:39:03.388] <TB0> INFO: ROC errors: 0
[08:39:03.388] <TB0> INFO: missing ROC header(s): 0
[08:39:03.388] <TB0> INFO: misplaced readback start: 0
[08:39:03.388] <TB0> INFO: Pixel decoding errors: 0
[08:39:03.388] <TB0> INFO: pixel data incomplete: 0
[08:39:03.388] <TB0> INFO: pixel address: 0
[08:39:03.388] <TB0> INFO: pulse height fill bit: 0
[08:39:03.388] <TB0> INFO: buffer corruption: 0
[08:39:03.456] <TB0> INFO: ######################################################################
[08:39:03.456] <TB0> INFO: PixTestTrim::doTest()
[08:39:03.456] <TB0> INFO: ######################################################################
[08:39:03.458] <TB0> INFO: ----------------------------------------------------------------------
[08:39:03.458] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[08:39:03.458] <TB0> INFO: ----------------------------------------------------------------------
[08:39:03.536] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:39:03.536] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:39:03.544] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[08:39:03.544] <TB0> INFO: run 1 of 1
[08:39:03.845] <TB0> INFO: Expecting 5025280 events.
[08:39:41.068] <TB0> INFO: 1093392 events read in total (36504ms).
[08:40:17.462] <TB0> INFO: 2177624 events read in total (72898ms).
[08:40:54.299] <TB0> INFO: 3262696 events read in total (109736ms).
[08:41:31.173] <TB0> INFO: 4356232 events read in total (146610ms).
[08:41:54.152] <TB0> INFO: 5025280 events read in total (169588ms).
[08:41:54.184] <TB0> INFO: Test took 170641ms.
[08:41:54.230] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:12.432] <TB0> INFO: ROC 0 VthrComp = 102
[08:42:12.432] <TB0> INFO: ROC 1 VthrComp = 93
[08:42:12.432] <TB0> INFO: ROC 2 VthrComp = 84
[08:42:12.432] <TB0> INFO: ROC 3 VthrComp = 102
[08:42:12.433] <TB0> INFO: ROC 4 VthrComp = 102
[08:42:12.433] <TB0> INFO: ROC 5 VthrComp = 97
[08:42:12.433] <TB0> INFO: ROC 6 VthrComp = 102
[08:42:12.433] <TB0> INFO: ROC 7 VthrComp = 97
[08:42:12.433] <TB0> INFO: ROC 8 VthrComp = 91
[08:42:12.433] <TB0> INFO: ROC 9 VthrComp = 91
[08:42:12.433] <TB0> INFO: ROC 10 VthrComp = 91
[08:42:12.433] <TB0> INFO: ROC 11 VthrComp = 93
[08:42:12.434] <TB0> INFO: ROC 12 VthrComp = 85
[08:42:12.434] <TB0> INFO: ROC 13 VthrComp = 98
[08:42:12.434] <TB0> INFO: ROC 14 VthrComp = 99
[08:42:12.434] <TB0> INFO: ROC 15 VthrComp = 105
[08:42:12.434] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:42:12.434] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:42:12.442] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[08:42:12.442] <TB0> INFO: run 1 of 1
[08:42:12.743] <TB0> INFO: Expecting 5025280 events.
[08:42:45.327] <TB0> INFO: 754824 events read in total (31868ms).
[08:43:16.597] <TB0> INFO: 1508688 events read in total (63138ms).
[08:43:47.911] <TB0> INFO: 2263864 events read in total (94452ms).
[08:44:18.942] <TB0> INFO: 3014544 events read in total (125483ms).
[08:44:50.000] <TB0> INFO: 3759936 events read in total (156541ms).
[08:45:22.430] <TB0> INFO: 4504200 events read in total (188971ms).
[08:45:44.683] <TB0> INFO: 5025280 events read in total (211224ms).
[08:45:44.735] <TB0> INFO: Test took 212294ms.
[08:45:44.871] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:07.475] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 56.7201 for pixel 51/72 mean/min/max = 44.9042/32.9388/56.8697
[08:46:07.475] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.5621 for pixel 15/73 mean/min/max = 45.4525/33.2036/57.7014
[08:46:07.475] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 55.4816 for pixel 23/0 mean/min/max = 44.0739/32.4453/55.7024
[08:46:07.475] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 59.7743 for pixel 13/76 mean/min/max = 45.8795/31.7993/59.9596
[08:46:07.475] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 58.9361 for pixel 0/17 mean/min/max = 45.7487/32.4819/59.0155
[08:46:07.476] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.236 for pixel 14/13 mean/min/max = 46.0688/32.8815/59.2561
[08:46:07.476] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 57.5686 for pixel 12/33 mean/min/max = 45.1725/32.4779/57.867
[08:46:07.476] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.9344 for pixel 8/7 mean/min/max = 44.9048/31.5444/58.2652
[08:46:07.476] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.4246 for pixel 11/17 mean/min/max = 45.794/33.1266/58.4614
[08:46:07.477] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 61.7456 for pixel 9/72 mean/min/max = 47.2614/32.4983/62.0245
[08:46:07.477] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.2986 for pixel 13/7 mean/min/max = 46.9372/34.4945/59.3798
[08:46:07.477] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 57.9236 for pixel 0/41 mean/min/max = 45.3882/32.8104/57.966
[08:46:07.477] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 57.3892 for pixel 0/8 mean/min/max = 44.7482/32.1062/57.3902
[08:46:07.478] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 61.3633 for pixel 14/1 mean/min/max = 46.4468/31.5025/61.3911
[08:46:07.478] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 56.977 for pixel 0/74 mean/min/max = 44.4029/31.7286/57.0772
[08:46:07.478] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 61.6107 for pixel 13/78 mean/min/max = 47.9208/34.1479/61.6936
[08:46:07.478] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:07.611] <TB0> INFO: Expecting 411648 events.
[08:46:16.502] <TB0> INFO: 411648 events read in total (8174ms).
[08:46:16.506] <TB0> INFO: Expecting 411648 events.
[08:46:25.321] <TB0> INFO: 411648 events read in total (8221ms).
[08:46:25.326] <TB0> INFO: Expecting 411648 events.
[08:46:34.212] <TB0> INFO: 411648 events read in total (8296ms).
[08:46:34.220] <TB0> INFO: Expecting 411648 events.
[08:46:43.372] <TB0> INFO: 411648 events read in total (8564ms).
[08:46:43.381] <TB0> INFO: Expecting 411648 events.
[08:46:52.531] <TB0> INFO: 411648 events read in total (8565ms).
[08:46:52.542] <TB0> INFO: Expecting 411648 events.
[08:47:01.465] <TB0> INFO: 411648 events read in total (8337ms).
[08:47:01.477] <TB0> INFO: Expecting 411648 events.
[08:47:10.429] <TB0> INFO: 411648 events read in total (8367ms).
[08:47:10.444] <TB0> INFO: Expecting 411648 events.
[08:47:19.287] <TB0> INFO: 411648 events read in total (8263ms).
[08:47:19.302] <TB0> INFO: Expecting 411648 events.
[08:47:28.207] <TB0> INFO: 411648 events read in total (8325ms).
[08:47:28.225] <TB0> INFO: Expecting 411648 events.
[08:47:37.059] <TB0> INFO: 411648 events read in total (8260ms).
[08:47:37.078] <TB0> INFO: Expecting 411648 events.
[08:47:46.018] <TB0> INFO: 411648 events read in total (8363ms).
[08:47:46.039] <TB0> INFO: Expecting 411648 events.
[08:47:54.937] <TB0> INFO: 411648 events read in total (8326ms).
[08:47:54.959] <TB0> INFO: Expecting 411648 events.
[08:48:03.849] <TB0> INFO: 411648 events read in total (8314ms).
[08:48:03.874] <TB0> INFO: Expecting 411648 events.
[08:48:12.880] <TB0> INFO: 411648 events read in total (8434ms).
[08:48:12.907] <TB0> INFO: Expecting 411648 events.
[08:48:21.686] <TB0> INFO: 411648 events read in total (8212ms).
[08:48:21.713] <TB0> INFO: Expecting 411648 events.
[08:48:30.526] <TB0> INFO: 411648 events read in total (8242ms).
[08:48:30.556] <TB0> INFO: Test took 143078ms.
[08:48:31.440] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:48:31.448] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[08:48:31.448] <TB0> INFO: run 1 of 1
[08:48:31.748] <TB0> INFO: Expecting 5025280 events.
[08:49:03.339] <TB0> INFO: 740072 events read in total (30875ms).
[08:49:33.831] <TB0> INFO: 1479488 events read in total (61368ms).
[08:50:04.563] <TB0> INFO: 2219912 events read in total (92099ms).
[08:50:35.316] <TB0> INFO: 2956264 events read in total (122852ms).
[08:51:06.063] <TB0> INFO: 3686616 events read in total (153600ms).
[08:51:37.387] <TB0> INFO: 4414840 events read in total (184923ms).
[08:52:03.045] <TB0> INFO: 5025280 events read in total (210581ms).
[08:52:03.098] <TB0> INFO: Test took 211650ms.
[08:52:03.234] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:52:25.359] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.214355 .. 255.000000
[08:52:25.432] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:52:25.440] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[08:52:25.440] <TB0> INFO: run 1 of 1
[08:52:25.742] <TB0> INFO: Expecting 8519680 events.
[08:52:56.886] <TB0> INFO: 712736 events read in total (30427ms).
[08:53:27.652] <TB0> INFO: 1425824 events read in total (61193ms).
[08:53:58.681] <TB0> INFO: 2138840 events read in total (92222ms).
[08:54:28.868] <TB0> INFO: 2851840 events read in total (122409ms).
[08:54:59.063] <TB0> INFO: 3564832 events read in total (152604ms).
[08:55:29.351] <TB0> INFO: 4277776 events read in total (182892ms).
[08:55:59.426] <TB0> INFO: 4990144 events read in total (212967ms).
[08:56:29.602] <TB0> INFO: 5701824 events read in total (243143ms).
[08:56:59.937] <TB0> INFO: 6412896 events read in total (273478ms).
[08:57:29.997] <TB0> INFO: 7123800 events read in total (303538ms).
[08:58:00.197] <TB0> INFO: 7834088 events read in total (333738ms).
[08:58:29.173] <TB0> INFO: 8519680 events read in total (362714ms).
[08:58:29.252] <TB0> INFO: Test took 363812ms.
[08:58:29.509] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:56.481] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.825410 .. 47.911770
[08:58:56.556] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 5 .. 57 (-1/-1) hits flags = 528 (plus default)
[08:58:56.564] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[08:58:56.564] <TB0> INFO: run 1 of 1
[08:58:56.867] <TB0> INFO: Expecting 1763840 events.
[08:59:31.451] <TB0> INFO: 924536 events read in total (33863ms).
[09:00:02.398] <TB0> INFO: 1763840 events read in total (64810ms).
[09:00:02.419] <TB0> INFO: Test took 65856ms.
[09:00:02.454] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:15.527] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.019554 .. 47.911770
[09:00:15.601] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 10 .. 57 (-1/-1) hits flags = 528 (plus default)
[09:00:15.609] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[09:00:15.609] <TB0> INFO: run 1 of 1
[09:00:15.911] <TB0> INFO: Expecting 1597440 events.
[09:00:50.349] <TB0> INFO: 898384 events read in total (33722ms).
[09:01:16.877] <TB0> INFO: 1597440 events read in total (60250ms).
[09:01:16.893] <TB0> INFO: Test took 61284ms.
[09:01:16.925] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:30.145] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798883 .. 47.911770
[09:01:30.219] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 2 .. 57 (-1/-1) hits flags = 528 (plus default)
[09:01:30.227] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[09:01:30.227] <TB0> INFO: run 1 of 1
[09:01:30.527] <TB0> INFO: Expecting 1863680 events.
[09:02:05.861] <TB0> INFO: 942080 events read in total (34618ms).
[09:02:40.370] <TB0> INFO: 1863680 events read in total (69127ms).
[09:02:40.389] <TB0> INFO: Test took 70162ms.
[09:02:40.422] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:53.429] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:02:53.429] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:02:53.437] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[09:02:53.437] <TB0> INFO: run 1 of 1
[09:02:53.734] <TB0> INFO: Expecting 1364480 events.
[09:03:28.117] <TB0> INFO: 879856 events read in total (33666ms).
[09:03:46.838] <TB0> INFO: 1364480 events read in total (52387ms).
[09:03:46.853] <TB0> INFO: Test took 53417ms.
[09:03:46.886] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C0.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C1.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C2.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C3.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C4.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C5.dat
[09:03:59.859] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C6.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C7.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C8.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C9.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C10.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C11.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C12.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C13.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C14.dat
[09:03:59.860] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C15.dat
[09:03:59.860] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C0.dat
[09:03:59.866] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C1.dat
[09:03:59.871] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C2.dat
[09:03:59.877] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C3.dat
[09:03:59.882] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C4.dat
[09:03:59.888] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C5.dat
[09:03:59.893] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C6.dat
[09:03:59.899] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C7.dat
[09:03:59.904] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C8.dat
[09:03:59.910] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C9.dat
[09:03:59.915] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C10.dat
[09:03:59.921] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C11.dat
[09:03:59.926] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C12.dat
[09:03:59.932] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C13.dat
[09:03:59.937] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C14.dat
[09:03:59.943] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//trimParameters35_C15.dat
[09:03:59.948] <TB0> INFO: PixTestTrim::trimTest() done
[09:03:59.948] <TB0> INFO: vtrim: 85 94 84 99 94 95 102 99 102 99 114 92 87 108 81 104
[09:03:59.948] <TB0> INFO: vthrcomp: 102 93 84 102 102 97 102 97 91 91 91 93 85 98 99 105
[09:03:59.948] <TB0> INFO: vcal mean: 34.99 35.00 35.00 35.00 34.99 35.00 35.05 34.98 35.03 34.97 35.00 34.98 35.01 34.98 34.95 34.99
[09:03:59.948] <TB0> INFO: vcal RMS: 0.92 0.90 1.14 0.94 0.97 1.01 0.91 0.95 0.93 0.93 1.10 0.92 0.93 1.03 0.94 0.96
[09:03:59.948] <TB0> INFO: bits mean: 9.52 9.38 10.46 9.67 9.55 9.65 9.63 9.94 9.74 9.14 9.43 9.49 9.92 9.50 9.74 8.85
[09:03:59.948] <TB0> INFO: bits RMS: 2.64 2.64 2.32 2.67 2.65 2.55 2.65 2.62 2.45 2.71 2.35 2.67 2.60 2.75 2.74 2.54
[09:03:59.956] <TB0> INFO: ----------------------------------------------------------------------
[09:03:59.956] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:03:59.956] <TB0> INFO: ----------------------------------------------------------------------
[09:03:59.961] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:03:59.969] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:03:59.969] <TB0> INFO: run 1 of 1
[09:04:00.271] <TB0> INFO: Expecting 4160000 events.
[09:04:39.000] <TB0> INFO: 963750 events read in total (39013ms).
[09:05:19.702] <TB0> INFO: 1919105 events read in total (78715ms).
[09:05:58.680] <TB0> INFO: 2863415 events read in total (117693ms).
[09:06:37.279] <TB0> INFO: 3804955 events read in total (156292ms).
[09:06:52.046] <TB0> INFO: 4160000 events read in total (171059ms).
[09:06:52.084] <TB0> INFO: Test took 172115ms.
[09:06:52.173] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:18.782] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[09:07:18.789] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:07:18.789] <TB0> INFO: run 1 of 1
[09:07:19.090] <TB0> INFO: Expecting 3806400 events.
[09:07:59.165] <TB0> INFO: 962465 events read in total (39359ms).
[09:08:38.977] <TB0> INFO: 1916805 events read in total (79171ms).
[09:09:18.379] <TB0> INFO: 2859895 events read in total (118573ms).
[09:09:57.963] <TB0> INFO: 3805305 events read in total (158157ms).
[09:09:58.487] <TB0> INFO: 3806400 events read in total (158681ms).
[09:09:58.522] <TB0> INFO: Test took 159732ms.
[09:09:58.604] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:24.117] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[09:10:24.124] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:10:24.125] <TB0> INFO: run 1 of 1
[09:10:24.428] <TB0> INFO: Expecting 3473600 events.
[09:11:06.041] <TB0> INFO: 1004885 events read in total (40897ms).
[09:11:45.671] <TB0> INFO: 1996765 events read in total (80527ms).
[09:12:26.144] <TB0> INFO: 2978550 events read in total (121000ms).
[09:12:46.704] <TB0> INFO: 3473600 events read in total (141560ms).
[09:12:46.734] <TB0> INFO: Test took 142609ms.
[09:12:46.802] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:10.387] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[09:13:10.395] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:13:10.395] <TB0> INFO: run 1 of 1
[09:13:10.694] <TB0> INFO: Expecting 3452800 events.
[09:13:51.498] <TB0> INFO: 1007030 events read in total (40088ms).
[09:14:31.263] <TB0> INFO: 2001830 events read in total (79853ms).
[09:15:10.830] <TB0> INFO: 2985450 events read in total (119420ms).
[09:15:29.877] <TB0> INFO: 3452800 events read in total (138467ms).
[09:15:29.906] <TB0> INFO: Test took 139511ms.
[09:15:29.969] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:53.407] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[09:15:53.415] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:15:53.415] <TB0> INFO: run 1 of 1
[09:15:53.713] <TB0> INFO: Expecting 3494400 events.
[09:16:34.162] <TB0> INFO: 1000535 events read in total (39732ms).
[09:17:13.579] <TB0> INFO: 1989285 events read in total (79149ms).
[09:17:52.816] <TB0> INFO: 2967495 events read in total (118386ms).
[09:18:14.561] <TB0> INFO: 3494400 events read in total (140131ms).
[09:18:14.596] <TB0> INFO: Test took 141181ms.
[09:18:14.664] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:38.565] <TB0> INFO: PixTestTrim::trimBitTest() done
[09:18:38.566] <TB0> INFO: PixTestTrim::doTest() done, duration: 2375 seconds
[09:18:38.566] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:38.566] <TB0> INFO: Decoding statistics:
[09:18:38.566] <TB0> INFO: General information:
[09:18:38.566] <TB0> INFO: 16bit words read: 0
[09:18:38.566] <TB0> INFO: valid events total: 0
[09:18:38.566] <TB0> INFO: empty events: 0
[09:18:38.566] <TB0> INFO: valid events with pixels: 0
[09:18:38.566] <TB0> INFO: valid pixel hits: 0
[09:18:38.566] <TB0> INFO: Event errors: 0
[09:18:38.566] <TB0> INFO: start marker: 0
[09:18:38.566] <TB0> INFO: stop marker: 0
[09:18:38.566] <TB0> INFO: overflow: 0
[09:18:38.566] <TB0> INFO: invalid 5bit words: 0
[09:18:38.566] <TB0> INFO: invalid XOR eye diagram: 0
[09:18:38.566] <TB0> INFO: TBM errors: 0
[09:18:38.566] <TB0> INFO: flawed TBM headers: 0
[09:18:38.566] <TB0> INFO: flawed TBM trailers: 0
[09:18:38.566] <TB0> INFO: event ID mismatches: 0
[09:18:38.566] <TB0> INFO: ROC errors: 0
[09:18:38.566] <TB0> INFO: missing ROC header(s): 0
[09:18:38.566] <TB0> INFO: misplaced readback start: 0
[09:18:38.566] <TB0> INFO: Pixel decoding errors: 0
[09:18:38.566] <TB0> INFO: pixel data incomplete: 0
[09:18:38.566] <TB0> INFO: pixel address: 0
[09:18:38.566] <TB0> INFO: pulse height fill bit: 0
[09:18:38.566] <TB0> INFO: buffer corruption: 0
[09:18:39.219] <TB0> INFO: ######################################################################
[09:18:39.219] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:18:39.219] <TB0> INFO: ######################################################################
[09:18:39.519] <TB0> INFO: Expecting 41600 events.
[09:18:43.725] <TB0> INFO: 41600 events read in total (3489ms).
[09:18:43.726] <TB0> INFO: Test took 4505ms.
[09:18:43.734] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:44.319] <TB0> INFO: Expecting 41600 events.
[09:18:48.571] <TB0> INFO: 41600 events read in total (3535ms).
[09:18:48.572] <TB0> INFO: Test took 4585ms.
[09:18:48.578] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:48.937] <TB0> INFO: Expecting 41600 events.
[09:18:53.168] <TB0> INFO: 41600 events read in total (3515ms).
[09:18:53.169] <TB0> INFO: Test took 4557ms.
[09:18:53.175] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:53.530] <TB0> INFO: Expecting 2560 events.
[09:18:54.595] <TB0> INFO: 2560 events read in total (348ms).
[09:18:54.595] <TB0> INFO: Test took 1413ms.
[09:18:55.011] <TB0> INFO: Expecting 2560 events.
[09:18:56.074] <TB0> INFO: 2560 events read in total (347ms).
[09:18:56.074] <TB0> INFO: Test took 1479ms.
[09:18:56.490] <TB0> INFO: Expecting 2560 events.
[09:18:57.553] <TB0> INFO: 2560 events read in total (347ms).
[09:18:57.553] <TB0> INFO: Test took 1479ms.
[09:18:57.969] <TB0> INFO: Expecting 2560 events.
[09:18:59.032] <TB0> INFO: 2560 events read in total (347ms).
[09:18:59.032] <TB0> INFO: Test took 1478ms.
[09:18:59.447] <TB0> INFO: Expecting 2560 events.
[09:19:00.510] <TB0> INFO: 2560 events read in total (346ms).
[09:19:00.510] <TB0> INFO: Test took 1478ms.
[09:19:00.925] <TB0> INFO: Expecting 2560 events.
[09:19:01.988] <TB0> INFO: 2560 events read in total (346ms).
[09:19:01.988] <TB0> INFO: Test took 1478ms.
[09:19:02.403] <TB0> INFO: Expecting 2560 events.
[09:19:03.468] <TB0> INFO: 2560 events read in total (349ms).
[09:19:03.468] <TB0> INFO: Test took 1479ms.
[09:19:03.883] <TB0> INFO: Expecting 2560 events.
[09:19:04.948] <TB0> INFO: 2560 events read in total (349ms).
[09:19:04.948] <TB0> INFO: Test took 1480ms.
[09:19:05.363] <TB0> INFO: Expecting 2560 events.
[09:19:06.428] <TB0> INFO: 2560 events read in total (348ms).
[09:19:06.428] <TB0> INFO: Test took 1479ms.
[09:19:06.843] <TB0> INFO: Expecting 2560 events.
[09:19:07.907] <TB0> INFO: 2560 events read in total (348ms).
[09:19:07.907] <TB0> INFO: Test took 1478ms.
[09:19:08.322] <TB0> INFO: Expecting 2560 events.
[09:19:09.386] <TB0> INFO: 2560 events read in total (347ms).
[09:19:09.386] <TB0> INFO: Test took 1479ms.
[09:19:09.801] <TB0> INFO: Expecting 2560 events.
[09:19:10.864] <TB0> INFO: 2560 events read in total (347ms).
[09:19:10.864] <TB0> INFO: Test took 1478ms.
[09:19:11.280] <TB0> INFO: Expecting 2560 events.
[09:19:12.343] <TB0> INFO: 2560 events read in total (347ms).
[09:19:12.344] <TB0> INFO: Test took 1479ms.
[09:19:12.759] <TB0> INFO: Expecting 2560 events.
[09:19:13.822] <TB0> INFO: 2560 events read in total (346ms).
[09:19:13.822] <TB0> INFO: Test took 1478ms.
[09:19:14.238] <TB0> INFO: Expecting 2560 events.
[09:19:15.301] <TB0> INFO: 2560 events read in total (347ms).
[09:19:15.301] <TB0> INFO: Test took 1478ms.
[09:19:15.716] <TB0> INFO: Expecting 2560 events.
[09:19:16.779] <TB0> INFO: 2560 events read in total (346ms).
[09:19:16.779] <TB0> INFO: Test took 1478ms.
[09:19:16.784] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:17.196] <TB0> INFO: Expecting 655360 events.
[09:19:31.158] <TB0> INFO: 655360 events read in total (13245ms).
[09:19:31.167] <TB0> INFO: Expecting 655360 events.
[09:19:45.296] <TB0> INFO: 655360 events read in total (13601ms).
[09:19:45.308] <TB0> INFO: Expecting 655360 events.
[09:19:59.805] <TB0> INFO: 655360 events read in total (13969ms).
[09:19:59.820] <TB0> INFO: Expecting 655360 events.
[09:20:14.129] <TB0> INFO: 655360 events read in total (13781ms).
[09:20:14.147] <TB0> INFO: Expecting 655360 events.
[09:20:28.608] <TB0> INFO: 655360 events read in total (13933ms).
[09:20:28.629] <TB0> INFO: Expecting 655360 events.
[09:20:42.562] <TB0> INFO: 655360 events read in total (13404ms).
[09:20:42.587] <TB0> INFO: Expecting 655360 events.
[09:20:56.875] <TB0> INFO: 655360 events read in total (13760ms).
[09:20:56.904] <TB0> INFO: Expecting 655360 events.
[09:21:10.693] <TB0> INFO: 655360 events read in total (13261ms).
[09:21:10.724] <TB0> INFO: Expecting 655360 events.
[09:21:24.791] <TB0> INFO: 655360 events read in total (13539ms).
[09:21:24.825] <TB0> INFO: Expecting 655360 events.
[09:21:38.698] <TB0> INFO: 655360 events read in total (13345ms).
[09:21:38.736] <TB0> INFO: Expecting 655360 events.
[09:21:52.615] <TB0> INFO: 655360 events read in total (13351ms).
[09:21:52.655] <TB0> INFO: Expecting 655360 events.
[09:22:07.167] <TB0> INFO: 655360 events read in total (13984ms).
[09:22:07.213] <TB0> INFO: Expecting 655360 events.
[09:22:21.087] <TB0> INFO: 655360 events read in total (13346ms).
[09:22:21.136] <TB0> INFO: Expecting 655360 events.
[09:22:35.104] <TB0> INFO: 655360 events read in total (13439ms).
[09:22:35.155] <TB0> INFO: Expecting 655360 events.
[09:22:48.894] <TB0> INFO: 655360 events read in total (13211ms).
[09:22:48.949] <TB0> INFO: Expecting 655360 events.
[09:23:02.686] <TB0> INFO: 655360 events read in total (13209ms).
[09:23:02.746] <TB0> INFO: Test took 225962ms.
[09:23:02.827] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:23:03.134] <TB0> INFO: Expecting 655360 events.
[09:23:17.443] <TB0> INFO: 655360 events read in total (13592ms).
[09:23:17.451] <TB0> INFO: Expecting 655360 events.
[09:23:31.642] <TB0> INFO: 655360 events read in total (13664ms).
[09:23:31.654] <TB0> INFO: Expecting 655360 events.
[09:23:45.888] <TB0> INFO: 655360 events read in total (13705ms).
[09:23:45.903] <TB0> INFO: Expecting 655360 events.
[09:23:59.587] <TB0> INFO: 655360 events read in total (13155ms).
[09:23:59.606] <TB0> INFO: Expecting 655360 events.
[09:24:13.264] <TB0> INFO: 655360 events read in total (13130ms).
[09:24:13.285] <TB0> INFO: Expecting 655360 events.
[09:24:27.074] <TB0> INFO: 655360 events read in total (13260ms).
[09:24:27.098] <TB0> INFO: Expecting 655360 events.
[09:24:40.717] <TB0> INFO: 655360 events read in total (13090ms).
[09:24:40.746] <TB0> INFO: Expecting 655360 events.
[09:24:54.697] <TB0> INFO: 655360 events read in total (13423ms).
[09:24:54.728] <TB0> INFO: Expecting 655360 events.
[09:25:08.566] <TB0> INFO: 655360 events read in total (13310ms).
[09:25:08.600] <TB0> INFO: Expecting 655360 events.
[09:25:22.914] <TB0> INFO: 655360 events read in total (13785ms).
[09:25:22.953] <TB0> INFO: Expecting 655360 events.
[09:25:37.284] <TB0> INFO: 655360 events read in total (13803ms).
[09:25:37.325] <TB0> INFO: Expecting 655360 events.
[09:25:51.223] <TB0> INFO: 655360 events read in total (13369ms).
[09:25:51.267] <TB0> INFO: Expecting 655360 events.
[09:26:05.031] <TB0> INFO: 655360 events read in total (13235ms).
[09:26:05.079] <TB0> INFO: Expecting 655360 events.
[09:26:19.396] <TB0> INFO: 655360 events read in total (13789ms).
[09:26:19.447] <TB0> INFO: Expecting 655360 events.
[09:26:33.479] <TB0> INFO: 655360 events read in total (13503ms).
[09:26:33.533] <TB0> INFO: Expecting 655360 events.
[09:26:47.173] <TB0> INFO: 655360 events read in total (13111ms).
[09:26:47.231] <TB0> INFO: Test took 224404ms.
[09:26:47.414] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.420] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[09:26:47.427] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.433] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[09:26:47.439] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.446] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.452] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.458] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.465] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.471] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.477] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.484] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.490] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.496] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.503] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.509] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.515] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.522] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[09:26:47.528] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[09:26:47.535] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[09:26:47.541] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[09:26:47.547] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[09:26:47.554] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[09:26:47.560] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[09:26:47.567] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:26:47.621] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C0.dat
[09:26:47.621] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C1.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C2.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C3.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C4.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C5.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C6.dat
[09:26:47.622] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C7.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C8.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C9.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C10.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C11.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C12.dat
[09:26:47.623] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C13.dat
[09:26:47.624] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C14.dat
[09:26:47.624] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//dacParameters35_C15.dat
[09:26:47.926] <TB0> INFO: Expecting 41600 events.
[09:26:51.779] <TB0> INFO: 41600 events read in total (3137ms).
[09:26:51.779] <TB0> INFO: Test took 4151ms.
[09:26:52.331] <TB0> INFO: Expecting 41600 events.
[09:26:56.232] <TB0> INFO: 41600 events read in total (3184ms).
[09:26:56.232] <TB0> INFO: Test took 4198ms.
[09:26:56.778] <TB0> INFO: Expecting 41600 events.
[09:27:00.676] <TB0> INFO: 41600 events read in total (3182ms).
[09:27:00.676] <TB0> INFO: Test took 4194ms.
[09:27:00.928] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:01.061] <TB0> INFO: Expecting 2560 events.
[09:27:02.124] <TB0> INFO: 2560 events read in total (347ms).
[09:27:02.124] <TB0> INFO: Test took 1196ms.
[09:27:02.127] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:02.541] <TB0> INFO: Expecting 2560 events.
[09:27:03.605] <TB0> INFO: 2560 events read in total (347ms).
[09:27:03.605] <TB0> INFO: Test took 1478ms.
[09:27:03.607] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:04.021] <TB0> INFO: Expecting 2560 events.
[09:27:05.084] <TB0> INFO: 2560 events read in total (346ms).
[09:27:05.085] <TB0> INFO: Test took 1478ms.
[09:27:05.087] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:05.501] <TB0> INFO: Expecting 2560 events.
[09:27:06.564] <TB0> INFO: 2560 events read in total (347ms).
[09:27:06.565] <TB0> INFO: Test took 1478ms.
[09:27:06.568] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:06.981] <TB0> INFO: Expecting 2560 events.
[09:27:08.045] <TB0> INFO: 2560 events read in total (347ms).
[09:27:08.045] <TB0> INFO: Test took 1477ms.
[09:27:08.048] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:08.461] <TB0> INFO: Expecting 2560 events.
[09:27:09.525] <TB0> INFO: 2560 events read in total (347ms).
[09:27:09.525] <TB0> INFO: Test took 1477ms.
[09:27:09.529] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:09.942] <TB0> INFO: Expecting 2560 events.
[09:27:11.005] <TB0> INFO: 2560 events read in total (347ms).
[09:27:11.006] <TB0> INFO: Test took 1477ms.
[09:27:11.008] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:11.421] <TB0> INFO: Expecting 2560 events.
[09:27:12.485] <TB0> INFO: 2560 events read in total (347ms).
[09:27:12.485] <TB0> INFO: Test took 1477ms.
[09:27:12.487] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:12.901] <TB0> INFO: Expecting 2560 events.
[09:27:13.964] <TB0> INFO: 2560 events read in total (347ms).
[09:27:13.964] <TB0> INFO: Test took 1477ms.
[09:27:13.967] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:14.380] <TB0> INFO: Expecting 2560 events.
[09:27:15.443] <TB0> INFO: 2560 events read in total (347ms).
[09:27:15.444] <TB0> INFO: Test took 1478ms.
[09:27:15.446] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:15.860] <TB0> INFO: Expecting 2560 events.
[09:27:16.923] <TB0> INFO: 2560 events read in total (347ms).
[09:27:16.923] <TB0> INFO: Test took 1477ms.
[09:27:16.925] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:17.339] <TB0> INFO: Expecting 2560 events.
[09:27:18.402] <TB0> INFO: 2560 events read in total (346ms).
[09:27:18.403] <TB0> INFO: Test took 1478ms.
[09:27:18.405] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:18.818] <TB0> INFO: Expecting 2560 events.
[09:27:19.882] <TB0> INFO: 2560 events read in total (347ms).
[09:27:19.882] <TB0> INFO: Test took 1477ms.
[09:27:19.885] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:20.298] <TB0> INFO: Expecting 2560 events.
[09:27:21.361] <TB0> INFO: 2560 events read in total (347ms).
[09:27:21.361] <TB0> INFO: Test took 1476ms.
[09:27:21.363] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:21.777] <TB0> INFO: Expecting 2560 events.
[09:27:22.840] <TB0> INFO: 2560 events read in total (347ms).
[09:27:22.840] <TB0> INFO: Test took 1477ms.
[09:27:22.845] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:23.255] <TB0> INFO: Expecting 2560 events.
[09:27:24.318] <TB0> INFO: 2560 events read in total (346ms).
[09:27:24.318] <TB0> INFO: Test took 1473ms.
[09:27:24.321] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:24.735] <TB0> INFO: Expecting 2560 events.
[09:27:25.813] <TB0> INFO: 2560 events read in total (362ms).
[09:27:25.813] <TB0> INFO: Test took 1492ms.
[09:27:25.816] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:26.230] <TB0> INFO: Expecting 2560 events.
[09:27:27.293] <TB0> INFO: 2560 events read in total (347ms).
[09:27:27.294] <TB0> INFO: Test took 1478ms.
[09:27:27.296] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:27.710] <TB0> INFO: Expecting 2560 events.
[09:27:28.792] <TB0> INFO: 2560 events read in total (365ms).
[09:27:28.793] <TB0> INFO: Test took 1497ms.
[09:27:28.795] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:29.209] <TB0> INFO: Expecting 2560 events.
[09:27:30.275] <TB0> INFO: 2560 events read in total (349ms).
[09:27:30.276] <TB0> INFO: Test took 1481ms.
[09:27:30.278] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:30.692] <TB0> INFO: Expecting 2560 events.
[09:27:31.758] <TB0> INFO: 2560 events read in total (350ms).
[09:27:31.759] <TB0> INFO: Test took 1481ms.
[09:27:31.761] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:32.175] <TB0> INFO: Expecting 2560 events.
[09:27:33.257] <TB0> INFO: 2560 events read in total (366ms).
[09:27:33.257] <TB0> INFO: Test took 1496ms.
[09:27:33.260] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:33.674] <TB0> INFO: Expecting 2560 events.
[09:27:34.756] <TB0> INFO: 2560 events read in total (366ms).
[09:27:34.756] <TB0> INFO: Test took 1496ms.
[09:27:34.759] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:35.173] <TB0> INFO: Expecting 2560 events.
[09:27:36.239] <TB0> INFO: 2560 events read in total (350ms).
[09:27:36.239] <TB0> INFO: Test took 1480ms.
[09:27:36.242] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:36.655] <TB0> INFO: Expecting 2560 events.
[09:27:37.721] <TB0> INFO: 2560 events read in total (349ms).
[09:27:37.722] <TB0> INFO: Test took 1480ms.
[09:27:37.724] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:38.138] <TB0> INFO: Expecting 2560 events.
[09:27:39.204] <TB0> INFO: 2560 events read in total (350ms).
[09:27:39.204] <TB0> INFO: Test took 1480ms.
[09:27:39.207] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:39.620] <TB0> INFO: Expecting 2560 events.
[09:27:40.686] <TB0> INFO: 2560 events read in total (349ms).
[09:27:40.687] <TB0> INFO: Test took 1480ms.
[09:27:40.689] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:41.103] <TB0> INFO: Expecting 2560 events.
[09:27:42.169] <TB0> INFO: 2560 events read in total (350ms).
[09:27:42.169] <TB0> INFO: Test took 1480ms.
[09:27:42.172] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:42.586] <TB0> INFO: Expecting 2560 events.
[09:27:43.652] <TB0> INFO: 2560 events read in total (350ms).
[09:27:43.652] <TB0> INFO: Test took 1480ms.
[09:27:43.655] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:44.068] <TB0> INFO: Expecting 2560 events.
[09:27:45.134] <TB0> INFO: 2560 events read in total (349ms).
[09:27:45.134] <TB0> INFO: Test took 1480ms.
[09:27:45.137] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:45.551] <TB0> INFO: Expecting 2560 events.
[09:27:46.617] <TB0> INFO: 2560 events read in total (350ms).
[09:27:46.617] <TB0> INFO: Test took 1480ms.
[09:27:46.620] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:47.034] <TB0> INFO: Expecting 2560 events.
[09:27:48.097] <TB0> INFO: 2560 events read in total (347ms).
[09:27:48.097] <TB0> INFO: Test took 1477ms.
[09:27:48.737] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 549 seconds
[09:27:48.738] <TB0> INFO: PH scale (per ROC): 73 76 70 72 68 64 69 70 71 63 71 78 76 67 74 66
[09:27:48.738] <TB0> INFO: PH offset (per ROC): 176 175 184 189 190 190 191 179 176 191 186 174 174 186 176 186
[09:27:48.741] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:48.741] <TB0> INFO: Decoding statistics:
[09:27:48.741] <TB0> INFO: General information:
[09:27:48.741] <TB0> INFO: 16bit words read: 91916
[09:27:48.741] <TB0> INFO: valid events total: 10240
[09:27:48.741] <TB0> INFO: empty events: 7741
[09:27:48.741] <TB0> INFO: valid events with pixels: 2499
[09:27:48.741] <TB0> INFO: valid pixel hits: 2499
[09:27:48.741] <TB0> INFO: Event errors: 0
[09:27:48.741] <TB0> INFO: start marker: 0
[09:27:48.741] <TB0> INFO: stop marker: 0
[09:27:48.742] <TB0> INFO: overflow: 0
[09:27:48.742] <TB0> INFO: invalid 5bit words: 0
[09:27:48.742] <TB0> INFO: invalid XOR eye diagram: 0
[09:27:48.742] <TB0> INFO: TBM errors: 0
[09:27:48.742] <TB0> INFO: flawed TBM headers: 0
[09:27:48.742] <TB0> INFO: flawed TBM trailers: 0
[09:27:48.742] <TB0> INFO: event ID mismatches: 0
[09:27:48.742] <TB0> INFO: ROC errors: 0
[09:27:48.742] <TB0> INFO: missing ROC header(s): 0
[09:27:48.742] <TB0> INFO: misplaced readback start: 0
[09:27:48.742] <TB0> INFO: Pixel decoding errors: 0
[09:27:48.742] <TB0> INFO: pixel data incomplete: 0
[09:27:48.742] <TB0> INFO: pixel address: 0
[09:27:48.742] <TB0> INFO: pulse height fill bit: 0
[09:27:48.742] <TB0> INFO: buffer corruption: 0
[09:27:48.901] <TB0> INFO: ######################################################################
[09:27:48.901] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:27:48.901] <TB0> INFO: ######################################################################
[09:27:48.911] <TB0> INFO: scanning low vcal = 10
[09:27:49.206] <TB0> INFO: Expecting 41600 events.
[09:27:52.972] <TB0> INFO: 41600 events read in total (3051ms).
[09:27:52.973] <TB0> INFO: Test took 4062ms.
[09:27:52.976] <TB0> INFO: scanning low vcal = 20
[09:27:53.389] <TB0> INFO: Expecting 41600 events.
[09:27:57.150] <TB0> INFO: 41600 events read in total (3045ms).
[09:27:57.150] <TB0> INFO: Test took 4174ms.
[09:27:57.153] <TB0> INFO: scanning low vcal = 30
[09:27:57.566] <TB0> INFO: Expecting 41600 events.
[09:28:01.336] <TB0> INFO: 41600 events read in total (3053ms).
[09:28:01.337] <TB0> INFO: Test took 4184ms.
[09:28:01.340] <TB0> INFO: scanning low vcal = 40
[09:28:01.747] <TB0> INFO: Expecting 41600 events.
[09:28:05.999] <TB0> INFO: 41600 events read in total (3535ms).
[09:28:06.000] <TB0> INFO: Test took 4660ms.
[09:28:06.003] <TB0> INFO: scanning low vcal = 50
[09:28:06.362] <TB0> INFO: Expecting 41600 events.
[09:28:10.604] <TB0> INFO: 41600 events read in total (3526ms).
[09:28:10.605] <TB0> INFO: Test took 4601ms.
[09:28:10.608] <TB0> INFO: scanning low vcal = 60
[09:28:10.968] <TB0> INFO: Expecting 41600 events.
[09:28:15.242] <TB0> INFO: 41600 events read in total (3557ms).
[09:28:15.242] <TB0> INFO: Test took 4634ms.
[09:28:15.246] <TB0> INFO: scanning low vcal = 70
[09:28:15.604] <TB0> INFO: Expecting 41600 events.
[09:28:19.875] <TB0> INFO: 41600 events read in total (3554ms).
[09:28:19.875] <TB0> INFO: Test took 4629ms.
[09:28:19.879] <TB0> INFO: scanning low vcal = 80
[09:28:20.238] <TB0> INFO: Expecting 41600 events.
[09:28:24.518] <TB0> INFO: 41600 events read in total (3564ms).
[09:28:24.519] <TB0> INFO: Test took 4640ms.
[09:28:24.522] <TB0> INFO: scanning low vcal = 90
[09:28:24.881] <TB0> INFO: Expecting 41600 events.
[09:28:29.135] <TB0> INFO: 41600 events read in total (3537ms).
[09:28:29.136] <TB0> INFO: Test took 4614ms.
[09:28:29.140] <TB0> INFO: scanning low vcal = 100
[09:28:29.498] <TB0> INFO: Expecting 41600 events.
[09:28:33.888] <TB0> INFO: 41600 events read in total (3674ms).
[09:28:33.889] <TB0> INFO: Test took 4749ms.
[09:28:33.894] <TB0> INFO: scanning low vcal = 110
[09:28:34.252] <TB0> INFO: Expecting 41600 events.
[09:28:38.522] <TB0> INFO: 41600 events read in total (3554ms).
[09:28:38.523] <TB0> INFO: Test took 4629ms.
[09:28:38.527] <TB0> INFO: scanning low vcal = 120
[09:28:38.885] <TB0> INFO: Expecting 41600 events.
[09:28:43.125] <TB0> INFO: 41600 events read in total (3523ms).
[09:28:43.126] <TB0> INFO: Test took 4599ms.
[09:28:43.130] <TB0> INFO: scanning low vcal = 130
[09:28:43.488] <TB0> INFO: Expecting 41600 events.
[09:28:47.732] <TB0> INFO: 41600 events read in total (3528ms).
[09:28:47.733] <TB0> INFO: Test took 4603ms.
[09:28:47.736] <TB0> INFO: scanning low vcal = 140
[09:28:48.095] <TB0> INFO: Expecting 41600 events.
[09:28:52.340] <TB0> INFO: 41600 events read in total (3528ms).
[09:28:52.340] <TB0> INFO: Test took 4604ms.
[09:28:52.344] <TB0> INFO: scanning low vcal = 150
[09:28:52.703] <TB0> INFO: Expecting 41600 events.
[09:28:56.977] <TB0> INFO: 41600 events read in total (3558ms).
[09:28:56.977] <TB0> INFO: Test took 4633ms.
[09:28:56.980] <TB0> INFO: scanning low vcal = 160
[09:28:57.340] <TB0> INFO: Expecting 41600 events.
[09:29:01.580] <TB0> INFO: 41600 events read in total (3524ms).
[09:29:01.581] <TB0> INFO: Test took 4601ms.
[09:29:01.590] <TB0> INFO: scanning low vcal = 170
[09:29:01.944] <TB0> INFO: Expecting 41600 events.
[09:29:06.244] <TB0> INFO: 41600 events read in total (3584ms).
[09:29:06.244] <TB0> INFO: Test took 4654ms.
[09:29:06.249] <TB0> INFO: scanning low vcal = 180
[09:29:06.606] <TB0> INFO: Expecting 41600 events.
[09:29:10.910] <TB0> INFO: 41600 events read in total (3587ms).
[09:29:10.910] <TB0> INFO: Test took 4661ms.
[09:29:10.913] <TB0> INFO: scanning low vcal = 190
[09:29:11.273] <TB0> INFO: Expecting 41600 events.
[09:29:15.613] <TB0> INFO: 41600 events read in total (3624ms).
[09:29:15.614] <TB0> INFO: Test took 4701ms.
[09:29:15.617] <TB0> INFO: scanning low vcal = 200
[09:29:15.975] <TB0> INFO: Expecting 41600 events.
[09:29:20.237] <TB0> INFO: 41600 events read in total (3545ms).
[09:29:20.237] <TB0> INFO: Test took 4620ms.
[09:29:20.240] <TB0> INFO: scanning low vcal = 210
[09:29:20.598] <TB0> INFO: Expecting 41600 events.
[09:29:24.843] <TB0> INFO: 41600 events read in total (3528ms).
[09:29:24.844] <TB0> INFO: Test took 4604ms.
[09:29:24.848] <TB0> INFO: scanning low vcal = 220
[09:29:25.206] <TB0> INFO: Expecting 41600 events.
[09:29:29.506] <TB0> INFO: 41600 events read in total (3583ms).
[09:29:29.506] <TB0> INFO: Test took 4658ms.
[09:29:29.510] <TB0> INFO: scanning low vcal = 230
[09:29:29.868] <TB0> INFO: Expecting 41600 events.
[09:29:34.159] <TB0> INFO: 41600 events read in total (3574ms).
[09:29:34.160] <TB0> INFO: Test took 4650ms.
[09:29:34.163] <TB0> INFO: scanning low vcal = 240
[09:29:34.522] <TB0> INFO: Expecting 41600 events.
[09:29:38.797] <TB0> INFO: 41600 events read in total (3559ms).
[09:29:38.797] <TB0> INFO: Test took 4634ms.
[09:29:38.800] <TB0> INFO: scanning low vcal = 250
[09:29:39.157] <TB0> INFO: Expecting 41600 events.
[09:29:43.418] <TB0> INFO: 41600 events read in total (3544ms).
[09:29:43.419] <TB0> INFO: Test took 4619ms.
[09:29:43.424] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[09:29:43.782] <TB0> INFO: Expecting 41600 events.
[09:29:48.057] <TB0> INFO: 41600 events read in total (3559ms).
[09:29:48.058] <TB0> INFO: Test took 4634ms.
[09:29:48.061] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[09:29:48.420] <TB0> INFO: Expecting 41600 events.
[09:29:52.667] <TB0> INFO: 41600 events read in total (3531ms).
[09:29:52.668] <TB0> INFO: Test took 4607ms.
[09:29:52.671] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[09:29:53.028] <TB0> INFO: Expecting 41600 events.
[09:29:57.275] <TB0> INFO: 41600 events read in total (3530ms).
[09:29:57.276] <TB0> INFO: Test took 4605ms.
[09:29:57.279] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[09:29:57.637] <TB0> INFO: Expecting 41600 events.
[09:30:01.936] <TB0> INFO: 41600 events read in total (3582ms).
[09:30:01.937] <TB0> INFO: Test took 4658ms.
[09:30:01.940] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:30:02.299] <TB0> INFO: Expecting 41600 events.
[09:30:06.540] <TB0> INFO: 41600 events read in total (3526ms).
[09:30:06.541] <TB0> INFO: Test took 4601ms.
[09:30:07.084] <TB0> INFO: PixTestGainPedestal::measure() done
[09:30:39.312] <TB0> INFO: PixTestGainPedestal::fit() done
[09:30:39.312] <TB0> INFO: non-linearity mean: 0.961 0.963 0.953 0.959 0.963 0.951 0.963 0.954 0.952 0.951 0.957 0.957 0.959 0.961 0.966 0.964
[09:30:39.312] <TB0> INFO: non-linearity RMS: 0.006 0.004 0.007 0.007 0.007 0.007 0.006 0.007 0.006 0.007 0.006 0.006 0.007 0.005 0.004 0.006
[09:30:39.312] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[09:30:39.337] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[09:30:39.362] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[09:30:39.387] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[09:30:39.412] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[09:30:39.437] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[09:30:39.461] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[09:30:39.486] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[09:30:39.511] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[09:30:39.536] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[09:30:39.560] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[09:30:39.585] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[09:30:39.610] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[09:30:39.635] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[09:30:39.660] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[09:30:39.685] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[09:30:39.709] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 170 seconds
[09:30:39.709] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:39.709] <TB0> INFO: Decoding statistics:
[09:30:39.709] <TB0> INFO: General information:
[09:30:39.709] <TB0> INFO: 16bit words read: 2662400
[09:30:39.709] <TB0> INFO: valid events total: 166400
[09:30:39.709] <TB0> INFO: empty events: 0
[09:30:39.709] <TB0> INFO: valid events with pixels: 166400
[09:30:39.709] <TB0> INFO: valid pixel hits: 665562
[09:30:39.709] <TB0> INFO: Event errors: 0
[09:30:39.709] <TB0> INFO: start marker: 0
[09:30:39.710] <TB0> INFO: stop marker: 0
[09:30:39.710] <TB0> INFO: overflow: 0
[09:30:39.710] <TB0> INFO: invalid 5bit words: 0
[09:30:39.710] <TB0> INFO: invalid XOR eye diagram: 0
[09:30:39.710] <TB0> INFO: TBM errors: 0
[09:30:39.710] <TB0> INFO: flawed TBM headers: 0
[09:30:39.710] <TB0> INFO: flawed TBM trailers: 0
[09:30:39.710] <TB0> INFO: event ID mismatches: 0
[09:30:39.710] <TB0> INFO: ROC errors: 0
[09:30:39.710] <TB0> INFO: missing ROC header(s): 0
[09:30:39.710] <TB0> INFO: misplaced readback start: 0
[09:30:39.710] <TB0> INFO: Pixel decoding errors: 0
[09:30:39.710] <TB0> INFO: pixel data incomplete: 0
[09:30:39.710] <TB0> INFO: pixel address: 0
[09:30:39.710] <TB0> INFO: pulse height fill bit: 0
[09:30:39.710] <TB0> INFO: buffer corruption: 0
[09:30:39.716] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C15.dat
[09:30:39.717] <TB0> INFO: ######################################################################
[09:30:39.717] <TB0> INFO: PixTestReadback::doTest()
[09:30:39.717] <TB0> INFO: ######################################################################
[09:30:39.718] <TB0> INFO: PixTestReadback::RES sent once
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C0.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C1.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C2.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C3.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C4.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C5.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C6.dat
[09:30:51.014] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C7.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C8.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C9.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C10.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C11.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C12.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C13.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C14.dat
[09:30:51.015] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C15.dat
[09:30:51.064] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:30:51.064] <TB0> INFO: PixTestReadback::RES sent once
[09:31:02.313] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C0.dat
[09:31:02.316] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C1.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C2.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C3.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C4.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C5.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C6.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C7.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C8.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C9.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C10.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C11.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C12.dat
[09:31:02.317] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C13.dat
[09:31:02.318] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C14.dat
[09:31:02.318] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C15.dat
[09:31:02.364] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:31:02.365] <TB0> INFO: PixTestReadback::RES sent once
[09:31:11.009] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:31:11.009] <TB0> INFO: Vbg will be calibrated using Vd calibration
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.1calibrated Vbg = 1.21867 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.22486 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 142.9calibrated Vbg = 1.2244 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156calibrated Vbg = 1.23379 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 165.6calibrated Vbg = 1.23848 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.5calibrated Vbg = 1.23886 :::*/*/*/*/
[09:31:11.009] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 153.4calibrated Vbg = 1.24066 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.8calibrated Vbg = 1.24302 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.4calibrated Vbg = 1.23563 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.6calibrated Vbg = 1.23781 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.1calibrated Vbg = 1.22953 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.8calibrated Vbg = 1.23199 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.6calibrated Vbg = 1.22342 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 161.3calibrated Vbg = 1.22451 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.1calibrated Vbg = 1.22943 :::*/*/*/*/
[09:31:11.010] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.5calibrated Vbg = 1.22798 :::*/*/*/*/
[09:31:11.012] <TB0> INFO: PixTestReadback::RES sent once
[09:34:06.042] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C0.dat
[09:34:06.042] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C1.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C2.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C3.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C4.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C5.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C6.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C7.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C8.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C9.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C10.dat
[09:34:06.043] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C11.dat
[09:34:06.044] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C12.dat
[09:34:06.044] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C13.dat
[09:34:06.044] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C14.dat
[09:34:06.044] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_Fulltest17_2016-01-13_09h16m_1452673015//000_FulltestPxar_p17//readbackCal_C15.dat
[09:34:06.089] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:34:06.090] <TB0> INFO: PixTestReadback::doTest() done
[09:34:06.090] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:06.090] <TB0> INFO: Decoding statistics:
[09:34:06.090] <TB0> INFO: General information:
[09:34:06.090] <TB0> INFO: 16bit words read: 1024
[09:34:06.090] <TB0> INFO: valid events total: 128
[09:34:06.090] <TB0> INFO: empty events: 128
[09:34:06.090] <TB0> INFO: valid events with pixels: 0
[09:34:06.090] <TB0> INFO: valid pixel hits: 0
[09:34:06.090] <TB0> INFO: Event errors: 0
[09:34:06.090] <TB0> INFO: start marker: 0
[09:34:06.090] <TB0> INFO: stop marker: 0
[09:34:06.090] <TB0> INFO: overflow: 0
[09:34:06.090] <TB0> INFO: invalid 5bit words: 0
[09:34:06.090] <TB0> INFO: invalid XOR eye diagram: 0
[09:34:06.090] <TB0> INFO: TBM errors: 0
[09:34:06.090] <TB0> INFO: flawed TBM headers: 0
[09:34:06.090] <TB0> INFO: flawed TBM trailers: 0
[09:34:06.090] <TB0> INFO: event ID mismatches: 0
[09:34:06.090] <TB0> INFO: ROC errors: 0
[09:34:06.090] <TB0> INFO: missing ROC header(s): 0
[09:34:06.090] <TB0> INFO: misplaced readback start: 0
[09:34:06.090] <TB0> INFO: Pixel decoding errors: 0
[09:34:06.090] <TB0> INFO: pixel data incomplete: 0
[09:34:06.090] <TB0> INFO: pixel address: 0
[09:34:06.090] <TB0> INFO: pulse height fill bit: 0
[09:34:06.090] <TB0> INFO: buffer corruption: 0
[09:34:06.103] <TB0> INFO: Decoding statistics:
[09:34:06.103] <TB0> INFO: General information:
[09:34:06.103] <TB0> INFO: 16bit words read: 2755340
[09:34:06.103] <TB0> INFO: valid events total: 176768
[09:34:06.103] <TB0> INFO: empty events: 7869
[09:34:06.103] <TB0> INFO: valid events with pixels: 168899
[09:34:06.103] <TB0> INFO: valid pixel hits: 668061
[09:34:06.103] <TB0> INFO: Event errors: 0
[09:34:06.103] <TB0> INFO: start marker: 0
[09:34:06.104] <TB0> INFO: stop marker: 0
[09:34:06.104] <TB0> INFO: overflow: 0
[09:34:06.104] <TB0> INFO: invalid 5bit words: 0
[09:34:06.104] <TB0> INFO: invalid XOR eye diagram: 0
[09:34:06.104] <TB0> INFO: TBM errors: 0
[09:34:06.104] <TB0> INFO: flawed TBM headers: 0
[09:34:06.104] <TB0> INFO: flawed TBM trailers: 0
[09:34:06.104] <TB0> INFO: event ID mismatches: 0
[09:34:06.104] <TB0> INFO: ROC errors: 0
[09:34:06.104] <TB0> INFO: missing ROC header(s): 0
[09:34:06.104] <TB0> INFO: misplaced readback start: 0
[09:34:06.104] <TB0> INFO: Pixel decoding errors: 0
[09:34:06.104] <TB0> INFO: pixel data incomplete: 0
[09:34:06.104] <TB0> INFO: pixel address: 0
[09:34:06.104] <TB0> INFO: pulse height fill bit: 0
[09:34:06.104] <TB0> INFO: buffer corruption: 0
[09:34:06.104] <TB0> INFO: enter test to run
[09:34:06.104] <TB0> INFO: test: exit no parameter change
[09:34:06.326] <TB0> QUIET: Connection to board 169 closed.
[09:34:06.405] <TB0> INFO: pXar: this is the end, my friend