[09:29:27.834] <TB1> INFO: *** Welcome to pxar ***
[09:29:27.834] <TB1> INFO: *** Today: 2015/09/07
[09:29:27.834] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C15.dat
[09:29:27.834] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:29:27.834] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//defaultMaskFile.dat
[09:29:27.834] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters_C15.dat
[09:29:27.903] <TB1> INFO: clk: 4
[09:29:27.903] <TB1> INFO: ctr: 4
[09:29:27.903] <TB1> INFO: sda: 19
[09:29:27.903] <TB1> INFO: tin: 9
[09:29:27.903] <TB1> INFO: level: 15
[09:29:27.903] <TB1> INFO: triggerdelay: 0
[09:29:27.903] <TB1> QUIET: Instanciating API for pxar prod-10+14~g355f077
[09:29:27.903] <TB1> INFO: Log level: INFO
[09:29:27.911] <TB1> INFO: Found DTB DTB_WXBYFL
[09:29:27.925] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[09:29:27.928] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[09:29:27.931] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[09:29:29.466] <TB1> INFO: DUT info:
[09:29:29.466] <TB1> INFO: The DUT currently contains the following objects:
[09:29:29.466] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[09:29:29.466] <TB1> INFO: TBM Core alpha (0): 7 registers set
[09:29:29.466] <TB1> INFO: TBM Core beta (1): 7 registers set
[09:29:29.466] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:29:29.466] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.466] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.467] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.467] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.467] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.467] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:29.868] <TB1> INFO: enter 'restricted' command line mode
[09:29:29.868] <TB1> INFO: enter test to run
[09:29:29.868] <TB1> INFO: test: pretest no parameter change
[09:29:29.868] <TB1> INFO: running: pretest
[09:29:29.878] <TB1> INFO: ######################################################################
[09:29:29.878] <TB1> INFO: PixTestPretest::doTest()
[09:29:29.878] <TB1> INFO: ######################################################################
[09:29:29.880] <TB1> INFO: ----------------------------------------------------------------------
[09:29:29.880] <TB1> INFO: PixTestPretest::programROC()
[09:29:29.880] <TB1> INFO: ----------------------------------------------------------------------
[09:29:47.902] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:29:47.902] <TB1> INFO: IA differences per ROC: 18.5 17.7 18.5 16.1 20.1 19.3 17.7 17.7 19.3 18.5 17.7 18.5 18.5 16.9 20.1 19.3
[09:29:47.987] <TB1> INFO: ----------------------------------------------------------------------
[09:29:47.987] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:29:47.987] <TB1> INFO: ----------------------------------------------------------------------
[09:30:07.603] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 395.5 mA = 24.7188 mA/ROC
[09:30:07.606] <TB1> INFO: ----------------------------------------------------------------------
[09:30:07.606] <TB1> INFO: PixTestPretest::findWorkingPixel()
[09:30:07.606] <TB1> INFO: ----------------------------------------------------------------------
[09:30:07.746] <TB1> INFO: Expecting 231680 events.
[09:30:17.132] <TB1> INFO: 231680 events read in total (8665ms).
[09:30:17.195] <TB1> INFO: Test took 9584ms.
[09:30:17.471] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:30:17.519] <TB1> INFO: ----------------------------------------------------------------------
[09:30:17.519] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[09:30:17.519] <TB1> INFO: ----------------------------------------------------------------------
[09:30:17.658] <TB1> INFO: Expecting 231680 events.
[09:30:27.045] <TB1> INFO: 231680 events read in total (8670ms).
[09:30:27.050] <TB1> INFO: Test took 9525ms.
[09:30:27.393] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[09:30:27.393] <TB1> INFO: CalDel: 160 168 121 134 136 145 133 131 128 126 138 137 152 150 140 165
[09:30:27.394] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:30:27.399] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C0.dat
[09:30:27.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C1.dat
[09:30:27.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C2.dat
[09:30:27.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C3.dat
[09:30:27.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C4.dat
[09:30:27.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C5.dat
[09:30:27.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C6.dat
[09:30:27.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C7.dat
[09:30:27.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C8.dat
[09:30:27.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C9.dat
[09:30:27.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C10.dat
[09:30:27.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C11.dat
[09:30:27.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C12.dat
[09:30:27.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C13.dat
[09:30:27.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C14.dat
[09:30:27.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C15.dat
[09:30:27.403] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0a.dat
[09:30:27.403] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:30:27.404] <TB1> INFO: PixTestPretest::doTest() done, duration: 57 seconds
[09:30:27.503] <TB1> INFO: enter test to run
[09:30:27.503] <TB1> INFO: test: fulltest no parameter change
[09:30:27.503] <TB1> INFO: running: fulltest
[09:30:27.503] <TB1> INFO: ######################################################################
[09:30:27.503] <TB1> INFO: PixTestFullTest::doTest()
[09:30:27.503] <TB1> INFO: ######################################################################
[09:30:27.506] <TB1> INFO: ######################################################################
[09:30:27.506] <TB1> INFO: PixTestAlive::doTest()
[09:30:27.506] <TB1> INFO: ######################################################################
[09:30:27.507] <TB1> INFO: ----------------------------------------------------------------------
[09:30:27.507] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:27.508] <TB1> INFO: ----------------------------------------------------------------------
[09:30:27.846] <TB1> INFO: Expecting 41600 events.
[09:30:32.617] <TB1> INFO: 41600 events read in total (4054ms).
[09:30:32.617] <TB1> INFO: Test took 5107ms.
[09:30:32.624] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:32.928] <TB1> INFO: PixTestAlive::aliveTest() done
[09:30:32.928] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[09:30:32.930] <TB1> INFO: ----------------------------------------------------------------------
[09:30:32.930] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:32.930] <TB1> INFO: ----------------------------------------------------------------------
[09:30:33.249] <TB1> INFO: Expecting 41600 events.
[09:30:36.628] <TB1> INFO: 41600 events read in total (2662ms).
[09:30:36.628] <TB1> INFO: Test took 3695ms.
[09:30:36.628] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:36.629] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:30:36.959] <TB1> INFO: PixTestAlive::maskTest() done
[09:30:36.959] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:36.960] <TB1> INFO: ----------------------------------------------------------------------
[09:30:36.960] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:36.960] <TB1> INFO: ----------------------------------------------------------------------
[09:30:37.277] <TB1> INFO: Expecting 41600 events.
[09:30:42.060] <TB1> INFO: 41600 events read in total (4066ms).
[09:30:42.061] <TB1> INFO: Test took 5098ms.
[09:30:42.068] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:42.367] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[09:30:42.367] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:42.367] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[09:30:42.367] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:42.367] <TB1> INFO: Decoding statistics:
[09:30:42.367] <TB1> INFO: General information:
[09:30:42.367] <TB1> INFO: 16bit words read: 0
[09:30:42.367] <TB1> INFO: valid events total: 0
[09:30:42.367] <TB1> INFO: empty events: 0
[09:30:42.367] <TB1> INFO: valid events with pixels: 0
[09:30:42.367] <TB1> INFO: valid pixel hits: 0
[09:30:42.367] <TB1> INFO: Event errors: 0
[09:30:42.367] <TB1> INFO: start marker: 0
[09:30:42.367] <TB1> INFO: stop marker: 0
[09:30:42.367] <TB1> INFO: overflow: 0
[09:30:42.367] <TB1> INFO: invalid 5bit words: 0
[09:30:42.367] <TB1> INFO: invalid XOR eye diagram: 0
[09:30:42.367] <TB1> INFO: TBM errors: 0
[09:30:42.367] <TB1> INFO: flawed TBM headers: 0
[09:30:42.367] <TB1> INFO: flawed TBM trailers: 0
[09:30:42.367] <TB1> INFO: event ID mismatches: 0
[09:30:42.367] <TB1> INFO: ROC errors: 0
[09:30:42.367] <TB1> INFO: missing ROC header(s): 0
[09:30:42.367] <TB1> INFO: misplaced readback start: 0
[09:30:42.367] <TB1> INFO: Pixel decoding errors: 0
[09:30:42.367] <TB1> INFO: pixel data incomplete: 0
[09:30:42.367] <TB1> INFO: pixel address: 0
[09:30:42.367] <TB1> INFO: pulse height fill bit: 0
[09:30:42.367] <TB1> INFO: buffer corruption: 0
[09:30:42.375] <TB1> INFO: ######################################################################
[09:30:42.375] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:30:42.375] <TB1> INFO: ######################################################################
[09:30:42.379] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:30:42.391] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:30:42.391] <TB1> INFO: run 1 of 1
[09:30:42.698] <TB1> INFO: Expecting 3120000 events.
[09:31:23.683] <TB1> INFO: 829185 events read in total (40268ms).
[09:32:03.526] <TB1> INFO: 1653445 events read in total (80111ms).
[09:32:44.189] <TB1> INFO: 2488025 events read in total (120774ms).
[09:33:14.700] <TB1> INFO: 3120000 events read in total (151285ms).
[09:33:14.761] <TB1> INFO: Test took 152371ms.
[09:33:14.894] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:41.341] <TB1> INFO: PixTestBBMap::doTest() done, duration: 178 seconds
[09:33:41.341] <TB1> INFO: number of dead bumps (per ROC): 6 3 0 1 0 1 1 0 9 0 1 4 7 8 2 3
[09:33:41.341] <TB1> INFO: separation cut (per ROC): 88 97 104 80 92 73 97 86 80 72 74 86 72 94 101 78
[09:33:41.341] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:41.341] <TB1> INFO: Decoding statistics:
[09:33:41.341] <TB1> INFO: General information:
[09:33:41.341] <TB1> INFO: 16bit words read: 0
[09:33:41.341] <TB1> INFO: valid events total: 0
[09:33:41.341] <TB1> INFO: empty events: 0
[09:33:41.341] <TB1> INFO: valid events with pixels: 0
[09:33:41.341] <TB1> INFO: valid pixel hits: 0
[09:33:41.341] <TB1> INFO: Event errors: 0
[09:33:41.341] <TB1> INFO: start marker: 0
[09:33:41.341] <TB1> INFO: stop marker: 0
[09:33:41.341] <TB1> INFO: overflow: 0
[09:33:41.341] <TB1> INFO: invalid 5bit words: 0
[09:33:41.341] <TB1> INFO: invalid XOR eye diagram: 0
[09:33:41.341] <TB1> INFO: TBM errors: 0
[09:33:41.341] <TB1> INFO: flawed TBM headers: 0
[09:33:41.341] <TB1> INFO: flawed TBM trailers: 0
[09:33:41.341] <TB1> INFO: event ID mismatches: 0
[09:33:41.342] <TB1> INFO: ROC errors: 0
[09:33:41.342] <TB1> INFO: missing ROC header(s): 0
[09:33:41.342] <TB1> INFO: misplaced readback start: 0
[09:33:41.342] <TB1> INFO: Pixel decoding errors: 0
[09:33:41.342] <TB1> INFO: pixel data incomplete: 0
[09:33:41.342] <TB1> INFO: pixel address: 0
[09:33:41.342] <TB1> INFO: pulse height fill bit: 0
[09:33:41.342] <TB1> INFO: buffer corruption: 0
[09:33:41.432] <TB1> INFO: ######################################################################
[09:33:41.432] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:33:41.432] <TB1> INFO: ######################################################################
[09:33:41.432] <TB1> INFO: ----------------------------------------------------------------------
[09:33:41.432] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:33:41.432] <TB1> INFO: ----------------------------------------------------------------------
[09:33:41.433] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:33:41.442] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:41.442] <TB1> INFO: run 1 of 1
[09:33:41.764] <TB1> INFO: Expecting 26208000 events.
[09:34:11.982] <TB1> INFO: 900550 events read in total (29501ms).
[09:34:41.774] <TB1> INFO: 1786300 events read in total (59293ms).
[09:35:11.353] <TB1> INFO: 2669200 events read in total (88872ms).
[09:35:41.083] <TB1> INFO: 3551550 events read in total (118602ms).
[09:36:10.550] <TB1> INFO: 4431250 events read in total (148069ms).
[09:36:40.343] <TB1> INFO: 5310750 events read in total (177862ms).
[09:37:09.707] <TB1> INFO: 6192300 events read in total (207226ms).
[09:37:39.091] <TB1> INFO: 7069850 events read in total (236610ms).
[09:38:08.293] <TB1> INFO: 7947850 events read in total (265812ms).
[09:38:37.813] <TB1> INFO: 8825400 events read in total (295332ms).
[09:39:07.332] <TB1> INFO: 9705750 events read in total (324851ms).
[09:39:37.018] <TB1> INFO: 10582500 events read in total (354537ms).
[09:40:06.411] <TB1> INFO: 11457450 events read in total (383930ms).
[09:40:35.363] <TB1> INFO: 12333600 events read in total (412882ms).
[09:41:04.903] <TB1> INFO: 13209100 events read in total (442422ms).
[09:41:34.303] <TB1> INFO: 14073900 events read in total (471822ms).
[09:42:03.719] <TB1> INFO: 14938700 events read in total (501238ms).
[09:42:33.199] <TB1> INFO: 15804750 events read in total (530718ms).
[09:43:02.341] <TB1> INFO: 16668550 events read in total (559860ms).
[09:43:31.875] <TB1> INFO: 17530600 events read in total (589394ms).
[09:44:01.284] <TB1> INFO: 18394700 events read in total (618803ms).
[09:44:30.577] <TB1> INFO: 19258150 events read in total (648096ms).
[09:44:59.810] <TB1> INFO: 20120600 events read in total (677329ms).
[09:45:29.260] <TB1> INFO: 20982350 events read in total (706779ms).
[09:45:58.496] <TB1> INFO: 21846450 events read in total (736015ms).
[09:46:27.833] <TB1> INFO: 22708750 events read in total (765352ms).
[09:46:57.246] <TB1> INFO: 23573050 events read in total (794765ms).
[09:47:25.909] <TB1> INFO: 24440150 events read in total (823428ms).
[09:47:54.220] <TB1> INFO: 25307900 events read in total (851739ms).
[09:48:24.018] <TB1> INFO: 26191600 events read in total (881537ms).
[09:48:25.016] <TB1> INFO: 26208000 events read in total (882535ms).
[09:48:25.048] <TB1> INFO: Test took 883606ms.
[09:48:25.129] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:25.349] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:27.068] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:28.686] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:30.312] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:31.830] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:33.570] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:35.183] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:36.773] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:38.340] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:39.931] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:41.364] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:42.965] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:44.567] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:46.212] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:47.839] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:49.327] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:48:50.866] <TB1> INFO: PixTestScurves::scurves() done
[09:48:50.866] <TB1> INFO: Vcal mean: 88.10 106.34 96.65 82.87 87.68 80.11 98.41 99.06 81.72 83.12 85.22 87.89 89.35 94.80 103.74 84.73
[09:48:50.866] <TB1> INFO: Vcal RMS: 5.07 5.90 5.48 4.79 5.63 4.46 5.77 5.56 4.83 4.60 4.71 5.71 5.56 6.49 6.15 4.99
[09:48:50.866] <TB1> INFO: PixTestScurves::fullTest() done, duration: 909 seconds
[09:48:50.866] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:50.866] <TB1> INFO: Decoding statistics:
[09:48:50.866] <TB1> INFO: General information:
[09:48:50.866] <TB1> INFO: 16bit words read: 0
[09:48:50.866] <TB1> INFO: valid events total: 0
[09:48:50.866] <TB1> INFO: empty events: 0
[09:48:50.866] <TB1> INFO: valid events with pixels: 0
[09:48:50.866] <TB1> INFO: valid pixel hits: 0
[09:48:50.866] <TB1> INFO: Event errors: 0
[09:48:50.866] <TB1> INFO: start marker: 0
[09:48:50.866] <TB1> INFO: stop marker: 0
[09:48:50.866] <TB1> INFO: overflow: 0
[09:48:50.866] <TB1> INFO: invalid 5bit words: 0
[09:48:50.866] <TB1> INFO: invalid XOR eye diagram: 0
[09:48:50.866] <TB1> INFO: TBM errors: 0
[09:48:50.866] <TB1> INFO: flawed TBM headers: 0
[09:48:50.866] <TB1> INFO: flawed TBM trailers: 0
[09:48:50.866] <TB1> INFO: event ID mismatches: 0
[09:48:50.866] <TB1> INFO: ROC errors: 0
[09:48:50.866] <TB1> INFO: missing ROC header(s): 0
[09:48:50.866] <TB1> INFO: misplaced readback start: 0
[09:48:50.866] <TB1> INFO: Pixel decoding errors: 0
[09:48:50.866] <TB1> INFO: pixel data incomplete: 0
[09:48:50.866] <TB1> INFO: pixel address: 0
[09:48:50.866] <TB1> INFO: pulse height fill bit: 0
[09:48:50.866] <TB1> INFO: buffer corruption: 0
[09:48:50.938] <TB1> INFO: ######################################################################
[09:48:50.938] <TB1> INFO: PixTestTrim::doTest()
[09:48:50.938] <TB1> INFO: ######################################################################
[09:48:50.940] <TB1> INFO: ----------------------------------------------------------------------
[09:48:50.940] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:48:50.940] <TB1> INFO: ----------------------------------------------------------------------
[09:48:51.024] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:48:51.024] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:48:51.033] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:48:51.033] <TB1> INFO: run 1 of 1
[09:48:51.367] <TB1> INFO: Expecting 6281600 events.
[09:49:30.255] <TB1> INFO: 1065890 events read in total (38171ms).
[09:50:06.847] <TB1> INFO: 2126960 events read in total (74763ms).
[09:50:45.169] <TB1> INFO: 3184490 events read in total (113085ms).
[09:51:23.336] <TB1> INFO: 4248750 events read in total (151252ms).
[09:52:02.044] <TB1> INFO: 5320200 events read in total (189960ms).
[09:52:36.746] <TB1> INFO: 6281600 events read in total (224662ms).
[09:52:36.780] <TB1> INFO: Test took 225747ms.
[09:52:36.834] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:52:56.949] <TB1> INFO: ROC 0 VthrComp = 94
[09:52:56.949] <TB1> INFO: ROC 1 VthrComp = 101
[09:52:56.949] <TB1> INFO: ROC 2 VthrComp = 102
[09:52:56.950] <TB1> INFO: ROC 3 VthrComp = 90
[09:52:56.950] <TB1> INFO: ROC 4 VthrComp = 100
[09:52:56.950] <TB1> INFO: ROC 5 VthrComp = 88
[09:52:56.950] <TB1> INFO: ROC 6 VthrComp = 101
[09:52:56.950] <TB1> INFO: ROC 7 VthrComp = 100
[09:52:56.950] <TB1> INFO: ROC 8 VthrComp = 86
[09:52:56.950] <TB1> INFO: ROC 9 VthrComp = 87
[09:52:56.950] <TB1> INFO: ROC 10 VthrComp = 91
[09:52:56.951] <TB1> INFO: ROC 11 VthrComp = 91
[09:52:56.951] <TB1> INFO: ROC 12 VthrComp = 90
[09:52:56.951] <TB1> INFO: ROC 13 VthrComp = 95
[09:52:56.951] <TB1> INFO: ROC 14 VthrComp = 105
[09:52:56.951] <TB1> INFO: ROC 15 VthrComp = 93
[09:52:56.951] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:52:56.951] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:52:56.960] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:52:56.960] <TB1> INFO: run 1 of 1
[09:52:57.266] <TB1> INFO: Expecting 6281600 events.
[09:53:31.416] <TB1> INFO: 757640 events read in total (33433ms).
[09:54:04.460] <TB1> INFO: 1512850 events read in total (66477ms).
[09:54:38.792] <TB1> INFO: 2268430 events read in total (100809ms).
[09:55:11.576] <TB1> INFO: 3024290 events read in total (133593ms).
[09:55:45.889] <TB1> INFO: 3774600 events read in total (167906ms).
[09:56:20.259] <TB1> INFO: 4521540 events read in total (202276ms).
[09:56:54.377] <TB1> INFO: 5266420 events read in total (236394ms).
[09:57:27.954] <TB1> INFO: 6011290 events read in total (269971ms).
[09:57:40.139] <TB1> INFO: 6281600 events read in total (282156ms).
[09:57:40.179] <TB1> INFO: Test took 283219ms.
[09:57:40.315] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:06.363] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.4862 for pixel 21/1 mean/min/max = 44.7081/31.8633/57.5529
[09:58:06.363] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 62.5037 for pixel 32/5 mean/min/max = 47.4913/32.3508/62.6318
[09:58:06.363] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.1575 for pixel 22/39 mean/min/max = 45.7826/32.2289/59.3362
[09:58:06.364] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.5858 for pixel 5/69 mean/min/max = 45.1177/32.6078/57.6276
[09:58:06.364] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.8556 for pixel 0/72 mean/min/max = 44.4544/30.9865/57.9223
[09:58:06.365] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 58.6278 for pixel 15/63 mean/min/max = 45.6974/32.6371/58.7578
[09:58:06.365] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 59.7811 for pixel 0/77 mean/min/max = 46.1202/32.3486/59.8918
[09:58:06.366] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.6644 for pixel 44/2 mean/min/max = 46.5625/32.3992/60.7258
[09:58:06.366] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.8963 for pixel 11/70 mean/min/max = 45.5526/32.1827/58.9224
[09:58:06.366] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.8922 for pixel 0/45 mean/min/max = 45.3324/32.6259/58.0389
[09:58:06.367] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.0113 for pixel 43/75 mean/min/max = 44.7024/32.3107/57.0941
[09:58:06.367] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.0689 for pixel 45/79 mean/min/max = 45.5961/31.9602/59.2321
[09:58:06.367] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.9106 for pixel 15/79 mean/min/max = 46.013/32.0699/59.956
[09:58:06.368] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 63.1341 for pixel 0/79 mean/min/max = 47.1762/31.0621/63.2903
[09:58:06.368] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 66.0716 for pixel 6/75 mean/min/max = 49.3577/32.3889/66.3265
[09:58:06.368] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.3538 for pixel 1/64 mean/min/max = 45.3943/32.3378/58.4509
[09:58:06.368] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:06.502] <TB1> INFO: Expecting 514560 events.
[09:58:18.858] <TB1> INFO: 514560 events read in total (11639ms).
[09:58:18.863] <TB1> INFO: Expecting 514560 events.
[09:58:31.145] <TB1> INFO: 514560 events read in total (11706ms).
[09:58:31.153] <TB1> INFO: Expecting 514560 events.
[09:58:43.358] <TB1> INFO: 514560 events read in total (11632ms).
[09:58:43.369] <TB1> INFO: Expecting 514560 events.
[09:58:55.435] <TB1> INFO: 514560 events read in total (11509ms).
[09:58:55.445] <TB1> INFO: Expecting 514560 events.
[09:59:07.209] <TB1> INFO: 514560 events read in total (11192ms).
[09:59:07.224] <TB1> INFO: Expecting 514560 events.
[09:59:19.099] <TB1> INFO: 514560 events read in total (11319ms).
[09:59:19.113] <TB1> INFO: Expecting 514560 events.
[09:59:31.270] <TB1> INFO: 514560 events read in total (11591ms).
[09:59:31.287] <TB1> INFO: Expecting 514560 events.
[09:59:43.766] <TB1> INFO: 514560 events read in total (11912ms).
[09:59:43.784] <TB1> INFO: Expecting 514560 events.
[09:59:56.138] <TB1> INFO: 514560 events read in total (11791ms).
[09:59:56.159] <TB1> INFO: Expecting 514560 events.
[10:00:08.286] <TB1> INFO: 514560 events read in total (11567ms).
[10:00:08.307] <TB1> INFO: Expecting 514560 events.
[10:00:20.744] <TB1> INFO: 514560 events read in total (11872ms).
[10:00:20.769] <TB1> INFO: Expecting 514560 events.
[10:00:32.885] <TB1> INFO: 514560 events read in total (11566ms).
[10:00:32.921] <TB1> INFO: Expecting 514560 events.
[10:00:44.613] <TB1> INFO: 514560 events read in total (11158ms).
[10:00:44.641] <TB1> INFO: Expecting 514560 events.
[10:00:56.462] <TB1> INFO: 514560 events read in total (11263ms).
[10:00:56.492] <TB1> INFO: Expecting 514560 events.
[10:01:09.502] <TB1> INFO: 514560 events read in total (12456ms).
[10:01:09.535] <TB1> INFO: Expecting 514560 events.
[10:01:22.389] <TB1> INFO: 514560 events read in total (12316ms).
[10:01:22.424] <TB1> INFO: Test took 196056ms.
[10:01:23.498] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:01:23.507] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:01:23.507] <TB1> INFO: run 1 of 1
[10:01:23.822] <TB1> INFO: Expecting 6281600 events.
[10:02:00.952] <TB1> INFO: 742910 events read in total (36413ms).
[10:02:37.271] <TB1> INFO: 1484500 events read in total (72732ms).
[10:03:12.067] <TB1> INFO: 2225840 events read in total (107528ms).
[10:03:46.902] <TB1> INFO: 2967720 events read in total (142363ms).
[10:04:20.956] <TB1> INFO: 3703590 events read in total (176417ms).
[10:04:55.392] <TB1> INFO: 4435230 events read in total (210853ms).
[10:05:28.479] <TB1> INFO: 5164220 events read in total (243940ms).
[10:06:01.484] <TB1> INFO: 5892990 events read in total (276945ms).
[10:06:20.102] <TB1> INFO: 6281600 events read in total (295563ms).
[10:06:20.148] <TB1> INFO: Test took 296641ms.
[10:06:20.318] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:45.662] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.176520 .. 255.000000
[10:06:45.747] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:06:45.756] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:06:45.756] <TB1> INFO: run 1 of 1
[10:06:46.072] <TB1> INFO: Expecting 10649600 events.
[10:07:19.460] <TB1> INFO: 712310 events read in total (32671ms).
[10:07:50.220] <TB1> INFO: 1424700 events read in total (63431ms).
[10:08:22.752] <TB1> INFO: 2137310 events read in total (95963ms).
[10:08:55.947] <TB1> INFO: 2850220 events read in total (129158ms).
[10:09:28.810] <TB1> INFO: 3562740 events read in total (162021ms).
[10:10:01.558] <TB1> INFO: 4275680 events read in total (194769ms).
[10:10:33.365] <TB1> INFO: 4988390 events read in total (226576ms).
[10:11:05.969] <TB1> INFO: 5701060 events read in total (259180ms).
[10:11:38.887] <TB1> INFO: 6413530 events read in total (292098ms).
[10:12:11.841] <TB1> INFO: 7125030 events read in total (325052ms).
[10:12:45.019] <TB1> INFO: 7836360 events read in total (358230ms).
[10:13:18.155] <TB1> INFO: 8546890 events read in total (391366ms).
[10:13:51.561] <TB1> INFO: 9257730 events read in total (424772ms).
[10:14:25.132] <TB1> INFO: 9968500 events read in total (458343ms).
[10:14:56.562] <TB1> INFO: 10649600 events read in total (489773ms).
[10:14:56.669] <TB1> INFO: Test took 490914ms.
[10:14:56.972] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:25.228] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.306103 .. 47.383901
[10:15:25.303] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 57 (-1/-1) hits flags = 528 (plus default)
[10:15:25.312] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:15:25.312] <TB1> INFO: run 1 of 1
[10:15:25.617] <TB1> INFO: Expecting 2246400 events.
[10:16:03.107] <TB1> INFO: 929080 events read in total (36774ms).
[10:16:37.660] <TB1> INFO: 1856130 events read in total (71326ms).
[10:16:53.633] <TB1> INFO: 2246400 events read in total (87299ms).
[10:16:53.654] <TB1> INFO: Test took 88343ms.
[10:16:53.693] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:08.425] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.371382 .. 45.769202
[10:17:08.520] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:17:08.530] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:17:08.531] <TB1> INFO: run 1 of 1
[10:17:08.874] <TB1> INFO: Expecting 1955200 events.
[10:17:45.042] <TB1> INFO: 918290 events read in total (35451ms).
[10:18:20.686] <TB1> INFO: 1834330 events read in total (71095ms).
[10:18:25.786] <TB1> INFO: 1955200 events read in total (76196ms).
[10:18:25.806] <TB1> INFO: Test took 77276ms.
[10:18:25.842] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:39.908] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 23.750176 .. 44.910628
[10:18:39.983] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 54 (-1/-1) hits flags = 528 (plus default)
[10:18:39.991] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:18:39.991] <TB1> INFO: run 1 of 1
[10:18:40.302] <TB1> INFO: Expecting 1747200 events.
[10:19:16.441] <TB1> INFO: 899700 events read in total (35422ms).
[10:19:48.521] <TB1> INFO: 1747200 events read in total (67502ms).
[10:19:48.533] <TB1> INFO: Test took 68542ms.
[10:19:48.561] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:02.599] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:20:02.599] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:20:02.608] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:20:02.608] <TB1> INFO: run 1 of 1
[10:20:02.937] <TB1> INFO: Expecting 1705600 events.
[10:20:38.854] <TB1> INFO: 879280 events read in total (35200ms).
[10:21:11.485] <TB1> INFO: 1705600 events read in total (67831ms).
[10:21:11.502] <TB1> INFO: Test took 68895ms.
[10:21:11.535] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:21:25.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:21:25.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:21:25.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:21:25.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:21:25.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:21:25.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:21:25.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:21:25.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:21:25.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:21:25.224] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:21:25.231] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:21:25.242] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:21:25.249] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:21:25.258] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:21:25.267] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:21:25.275] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:21:25.285] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:21:25.292] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:21:25.303] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:21:25.310] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:21:25.319] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:21:25.328] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:21:25.336] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:21:25.346] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:21:25.354] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:21:25.363] <TB1> INFO: PixTestTrim::trimTest() done
[10:21:25.363] <TB1> INFO: vtrim: 98 111 106 88 111 97 95 98 114 92 103 99 100 95 131 100
[10:21:25.363] <TB1> INFO: vthrcomp: 94 101 102 90 100 88 101 100 86 87 91 91 90 95 105 93
[10:21:25.363] <TB1> INFO: vcal mean: 34.96 35.02 34.97 34.98 34.98 34.98 34.93 34.97 34.98 34.98 34.96 35.00 34.81 34.99 35.02 35.00
[10:21:25.363] <TB1> INFO: vcal RMS: 0.83 0.99 0.83 0.82 0.80 0.85 0.84 0.89 0.86 0.81 0.84 0.97 0.85 0.94 0.90 0.80
[10:21:25.363] <TB1> INFO: bits mean: 9.86 8.73 9.56 9.41 9.53 9.55 9.14 9.28 9.57 9.30 9.70 8.90 9.06 9.09 9.04 9.34
[10:21:25.363] <TB1> INFO: bits RMS: 2.60 2.79 2.63 2.66 2.91 2.59 2.77 2.66 2.67 2.76 2.64 2.94 2.87 2.92 2.59 2.73
[10:21:25.372] <TB1> INFO: ----------------------------------------------------------------------
[10:21:25.372] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[10:21:25.372] <TB1> INFO: ----------------------------------------------------------------------
[10:21:25.377] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:21:25.387] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:21:25.387] <TB1> INFO: run 1 of 1
[10:21:25.717] <TB1> INFO: Expecting 8320000 events.
[10:22:00.138] <TB1> INFO: 929950 events read in total (33704ms).
[10:22:35.292] <TB1> INFO: 1852520 events read in total (68859ms).
[10:23:08.995] <TB1> INFO: 2773320 events read in total (102561ms).
[10:23:42.929] <TB1> INFO: 3692820 events read in total (136495ms).
[10:24:17.896] <TB1> INFO: 4606650 events read in total (171462ms).
[10:24:52.636] <TB1> INFO: 5516950 events read in total (206202ms).
[10:25:26.990] <TB1> INFO: 6427370 events read in total (240556ms).
[10:26:02.355] <TB1> INFO: 7337440 events read in total (275921ms).
[10:26:36.578] <TB1> INFO: 8253950 events read in total (310144ms).
[10:26:39.380] <TB1> INFO: 8320000 events read in total (312946ms).
[10:26:39.417] <TB1> INFO: Test took 314030ms.
[10:26:39.525] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:07.539] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[10:27:07.548] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:27:07.548] <TB1> INFO: run 1 of 1
[10:27:07.854] <TB1> INFO: Expecting 7280000 events.
[10:27:44.904] <TB1> INFO: 949600 events read in total (36332ms).
[10:28:20.739] <TB1> INFO: 1891280 events read in total (72168ms).
[10:28:57.910] <TB1> INFO: 2831200 events read in total (109338ms).
[10:29:33.993] <TB1> INFO: 3767090 events read in total (145421ms).
[10:30:08.926] <TB1> INFO: 4693690 events read in total (180354ms).
[10:30:44.438] <TB1> INFO: 5620700 events read in total (215866ms).
[10:31:20.257] <TB1> INFO: 6547870 events read in total (251685ms).
[10:31:48.510] <TB1> INFO: 7280000 events read in total (279938ms).
[10:31:48.543] <TB1> INFO: Test took 280995ms.
[10:31:48.629] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:13.249] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[10:32:13.258] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:32:13.258] <TB1> INFO: run 1 of 1
[10:32:13.562] <TB1> INFO: Expecting 6780800 events.
[10:32:49.419] <TB1> INFO: 979560 events read in total (35140ms).
[10:33:27.024] <TB1> INFO: 1950780 events read in total (72745ms).
[10:34:04.809] <TB1> INFO: 2919160 events read in total (110530ms).
[10:34:41.830] <TB1> INFO: 3879320 events read in total (147551ms).
[10:35:16.676] <TB1> INFO: 4834240 events read in total (182397ms).
[10:35:53.738] <TB1> INFO: 5788040 events read in total (219459ms).
[10:36:30.609] <TB1> INFO: 6750430 events read in total (256330ms).
[10:36:32.208] <TB1> INFO: 6780800 events read in total (257929ms).
[10:36:32.238] <TB1> INFO: Test took 258980ms.
[10:36:32.310] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:59.129] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[10:36:59.139] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:36:59.139] <TB1> INFO: run 1 of 1
[10:36:59.475] <TB1> INFO: Expecting 6780800 events.
[10:37:36.817] <TB1> INFO: 979350 events read in total (36625ms).
[10:38:14.988] <TB1> INFO: 1950230 events read in total (74796ms).
[10:38:50.382] <TB1> INFO: 2918360 events read in total (110190ms).
[10:39:27.182] <TB1> INFO: 3877760 events read in total (146990ms).
[10:40:04.535] <TB1> INFO: 4831870 events read in total (184343ms).
[10:40:41.883] <TB1> INFO: 5785320 events read in total (221691ms).
[10:41:20.548] <TB1> INFO: 6747250 events read in total (260356ms).
[10:41:22.241] <TB1> INFO: 6780800 events read in total (262049ms).
[10:41:22.271] <TB1> INFO: Test took 263132ms.
[10:41:22.346] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:46.702] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[10:41:46.711] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:41:46.711] <TB1> INFO: run 1 of 1
[10:41:47.019] <TB1> INFO: Expecting 6739200 events.
[10:42:22.140] <TB1> INFO: 981460 events read in total (34403ms).
[10:43:00.069] <TB1> INFO: 1954950 events read in total (72333ms).
[10:43:37.526] <TB1> INFO: 2924980 events read in total (109790ms).
[10:44:12.958] <TB1> INFO: 3886900 events read in total (145221ms).
[10:44:50.307] <TB1> INFO: 4842160 events read in total (182570ms).
[10:45:27.917] <TB1> INFO: 5797540 events read in total (220180ms).
[10:46:04.808] <TB1> INFO: 6739200 events read in total (257071ms).
[10:46:04.844] <TB1> INFO: Test took 258133ms.
[10:46:04.924] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:27.162] <TB1> INFO: PixTestTrim::trimBitTest() done
[10:46:27.164] <TB1> INFO: PixTestTrim::doTest() done, duration: 3456 seconds
[10:46:27.164] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:27.164] <TB1> INFO: Decoding statistics:
[10:46:27.164] <TB1> INFO: General information:
[10:46:27.164] <TB1> INFO: 16bit words read: 0
[10:46:27.164] <TB1> INFO: valid events total: 0
[10:46:27.164] <TB1> INFO: empty events: 0
[10:46:27.164] <TB1> INFO: valid events with pixels: 0
[10:46:27.164] <TB1> INFO: valid pixel hits: 0
[10:46:27.164] <TB1> INFO: Event errors: 0
[10:46:27.164] <TB1> INFO: start marker: 0
[10:46:27.164] <TB1> INFO: stop marker: 0
[10:46:27.164] <TB1> INFO: overflow: 0
[10:46:27.164] <TB1> INFO: invalid 5bit words: 0
[10:46:27.164] <TB1> INFO: invalid XOR eye diagram: 0
[10:46:27.164] <TB1> INFO: TBM errors: 0
[10:46:27.164] <TB1> INFO: flawed TBM headers: 0
[10:46:27.164] <TB1> INFO: flawed TBM trailers: 0
[10:46:27.164] <TB1> INFO: event ID mismatches: 0
[10:46:27.164] <TB1> INFO: ROC errors: 0
[10:46:27.164] <TB1> INFO: missing ROC header(s): 0
[10:46:27.164] <TB1> INFO: misplaced readback start: 0
[10:46:27.164] <TB1> INFO: Pixel decoding errors: 0
[10:46:27.164] <TB1> INFO: pixel data incomplete: 0
[10:46:27.164] <TB1> INFO: pixel address: 0
[10:46:27.164] <TB1> INFO: pulse height fill bit: 0
[10:46:27.164] <TB1> INFO: buffer corruption: 0
[10:46:27.845] <TB1> INFO: ######################################################################
[10:46:27.845] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:46:27.845] <TB1> INFO: ######################################################################
[10:46:28.152] <TB1> INFO: Expecting 41600 events.
[10:46:32.917] <TB1> INFO: 41600 events read in total (4048ms).
[10:46:32.917] <TB1> INFO: Test took 5070ms.
[10:46:32.925] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:33.520] <TB1> INFO: Expecting 41600 events.
[10:46:38.281] <TB1> INFO: 41600 events read in total (4044ms).
[10:46:38.281] <TB1> INFO: Test took 5098ms.
[10:46:38.288] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:38.641] <TB1> INFO: Expecting 41600 events.
[10:46:43.405] <TB1> INFO: 41600 events read in total (4047ms).
[10:46:43.405] <TB1> INFO: Test took 5101ms.
[10:46:43.411] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:43.766] <TB1> INFO: Expecting 2560 events.
[10:46:44.833] <TB1> INFO: 2560 events read in total (350ms).
[10:46:44.833] <TB1> INFO: Test took 1414ms.
[10:46:45.249] <TB1> INFO: Expecting 2560 events.
[10:46:46.315] <TB1> INFO: 2560 events read in total (349ms).
[10:46:46.315] <TB1> INFO: Test took 1481ms.
[10:46:46.731] <TB1> INFO: Expecting 2560 events.
[10:46:47.797] <TB1> INFO: 2560 events read in total (347ms).
[10:46:47.797] <TB1> INFO: Test took 1482ms.
[10:46:48.213] <TB1> INFO: Expecting 2560 events.
[10:46:49.277] <TB1> INFO: 2560 events read in total (347ms).
[10:46:49.277] <TB1> INFO: Test took 1473ms.
[10:46:49.693] <TB1> INFO: Expecting 2560 events.
[10:46:50.757] <TB1> INFO: 2560 events read in total (347ms).
[10:46:50.757] <TB1> INFO: Test took 1479ms.
[10:46:51.173] <TB1> INFO: Expecting 2560 events.
[10:46:52.236] <TB1> INFO: 2560 events read in total (346ms).
[10:46:52.237] <TB1> INFO: Test took 1480ms.
[10:46:52.652] <TB1> INFO: Expecting 2560 events.
[10:46:53.717] <TB1> INFO: 2560 events read in total (348ms).
[10:46:53.717] <TB1> INFO: Test took 1480ms.
[10:46:54.133] <TB1> INFO: Expecting 2560 events.
[10:46:55.197] <TB1> INFO: 2560 events read in total (347ms).
[10:46:55.197] <TB1> INFO: Test took 1480ms.
[10:46:55.613] <TB1> INFO: Expecting 2560 events.
[10:46:56.677] <TB1> INFO: 2560 events read in total (348ms).
[10:46:56.678] <TB1> INFO: Test took 1481ms.
[10:46:57.094] <TB1> INFO: Expecting 2560 events.
[10:46:58.157] <TB1> INFO: 2560 events read in total (346ms).
[10:46:58.157] <TB1> INFO: Test took 1479ms.
[10:46:58.573] <TB1> INFO: Expecting 2560 events.
[10:46:59.637] <TB1> INFO: 2560 events read in total (347ms).
[10:46:59.638] <TB1> INFO: Test took 1475ms.
[10:47:00.053] <TB1> INFO: Expecting 2560 events.
[10:47:01.122] <TB1> INFO: 2560 events read in total (352ms).
[10:47:01.122] <TB1> INFO: Test took 1484ms.
[10:47:01.537] <TB1> INFO: Expecting 2560 events.
[10:47:02.602] <TB1> INFO: 2560 events read in total (348ms).
[10:47:02.602] <TB1> INFO: Test took 1479ms.
[10:47:03.018] <TB1> INFO: Expecting 2560 events.
[10:47:04.083] <TB1> INFO: 2560 events read in total (348ms).
[10:47:04.083] <TB1> INFO: Test took 1481ms.
[10:47:04.500] <TB1> INFO: Expecting 2560 events.
[10:47:05.570] <TB1> INFO: 2560 events read in total (353ms).
[10:47:05.570] <TB1> INFO: Test took 1486ms.
[10:47:05.986] <TB1> INFO: Expecting 2560 events.
[10:47:07.051] <TB1> INFO: 2560 events read in total (348ms).
[10:47:07.051] <TB1> INFO: Test took 1481ms.
[10:47:07.056] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:47:07.469] <TB1> INFO: Expecting 655360 events.
[10:47:22.916] <TB1> INFO: 655360 events read in total (14730ms).
[10:47:22.925] <TB1> INFO: Expecting 655360 events.
[10:47:38.274] <TB1> INFO: 655360 events read in total (14821ms).
[10:47:38.286] <TB1> INFO: Expecting 655360 events.
[10:47:53.767] <TB1> INFO: 655360 events read in total (14953ms).
[10:47:53.783] <TB1> INFO: Expecting 655360 events.
[10:48:09.232] <TB1> INFO: 655360 events read in total (14921ms).
[10:48:09.255] <TB1> INFO: Expecting 655360 events.
[10:48:24.577] <TB1> INFO: 655360 events read in total (14794ms).
[10:48:24.600] <TB1> INFO: Expecting 655360 events.
[10:48:40.208] <TB1> INFO: 655360 events read in total (15080ms).
[10:48:40.244] <TB1> INFO: Expecting 655360 events.
[10:48:55.510] <TB1> INFO: 655360 events read in total (14738ms).
[10:48:55.543] <TB1> INFO: Expecting 655360 events.
[10:49:10.823] <TB1> INFO: 655360 events read in total (14752ms).
[10:49:10.855] <TB1> INFO: Expecting 655360 events.
[10:49:26.988] <TB1> INFO: 655360 events read in total (15605ms).
[10:49:27.031] <TB1> INFO: Expecting 655360 events.
[10:49:43.148] <TB1> INFO: 655360 events read in total (15589ms).
[10:49:43.195] <TB1> INFO: Expecting 655360 events.
[10:49:59.453] <TB1> INFO: 655360 events read in total (15730ms).
[10:49:59.506] <TB1> INFO: Expecting 655360 events.
[10:50:15.738] <TB1> INFO: 655360 events read in total (15704ms).
[10:50:15.792] <TB1> INFO: Expecting 655360 events.
[10:50:31.650] <TB1> INFO: 655360 events read in total (15330ms).
[10:50:31.701] <TB1> INFO: Expecting 655360 events.
[10:50:48.002] <TB1> INFO: 655360 events read in total (15773ms).
[10:50:48.057] <TB1> INFO: Expecting 655360 events.
[10:51:04.191] <TB1> INFO: 655360 events read in total (15606ms).
[10:51:04.255] <TB1> INFO: Expecting 655360 events.
[10:51:20.351] <TB1> INFO: 655360 events read in total (15568ms).
[10:51:20.410] <TB1> INFO: Test took 253354ms.
[10:51:20.492] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:51:20.800] <TB1> INFO: Expecting 655360 events.
[10:51:36.162] <TB1> INFO: 655360 events read in total (14645ms).
[10:51:36.170] <TB1> INFO: Expecting 655360 events.
[10:51:52.289] <TB1> INFO: 655360 events read in total (15590ms).
[10:51:52.301] <TB1> INFO: Expecting 655360 events.
[10:52:08.525] <TB1> INFO: 655360 events read in total (15696ms).
[10:52:08.540] <TB1> INFO: Expecting 655360 events.
[10:52:24.970] <TB1> INFO: 655360 events read in total (15901ms).
[10:52:24.992] <TB1> INFO: Expecting 655360 events.
[10:52:41.529] <TB1> INFO: 655360 events read in total (16009ms).
[10:52:41.553] <TB1> INFO: Expecting 655360 events.
[10:52:57.820] <TB1> INFO: 655360 events read in total (15739ms).
[10:52:57.851] <TB1> INFO: Expecting 655360 events.
[10:53:14.228] <TB1> INFO: 655360 events read in total (15849ms).
[10:53:14.257] <TB1> INFO: Expecting 655360 events.
[10:53:30.289] <TB1> INFO: 655360 events read in total (15503ms).
[10:53:30.322] <TB1> INFO: Expecting 655360 events.
[10:53:46.667] <TB1> INFO: 655360 events read in total (15816ms).
[10:53:46.708] <TB1> INFO: Expecting 655360 events.
[10:54:02.709] <TB1> INFO: 655360 events read in total (15472ms).
[10:54:02.750] <TB1> INFO: Expecting 655360 events.
[10:54:17.775] <TB1> INFO: 655360 events read in total (14497ms).
[10:54:17.824] <TB1> INFO: Expecting 655360 events.
[10:54:32.895] <TB1> INFO: 655360 events read in total (14543ms).
[10:54:32.941] <TB1> INFO: Expecting 655360 events.
[10:54:49.200] <TB1> INFO: 655360 events read in total (15730ms).
[10:54:49.253] <TB1> INFO: Expecting 655360 events.
[10:55:05.468] <TB1> INFO: 655360 events read in total (15686ms).
[10:55:05.520] <TB1> INFO: Expecting 655360 events.
[10:55:22.197] <TB1> INFO: 655360 events read in total (16148ms).
[10:55:22.260] <TB1> INFO: Expecting 655360 events.
[10:55:38.853] <TB1> INFO: 655360 events read in total (16064ms).
[10:55:38.911] <TB1> INFO: Test took 258419ms.
[10:55:39.107] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.114] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.120] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.127] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.134] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.141] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[10:55:39.148] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[10:55:39.155] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.162] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.169] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.176] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.184] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.192] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.199] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.207] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.214] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.223] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.230] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:55:39.289] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:55:39.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:55:39.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:55:39.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:55:39.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:55:39.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:55:39.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:55:39.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:55:39.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:55:39.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:55:39.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:55:39.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:55:39.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:55:39.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:55:39.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:55:39.293] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:55:39.635] <TB1> INFO: Expecting 41600 events.
[10:55:44.120] <TB1> INFO: 41600 events read in total (3768ms).
[10:55:44.121] <TB1> INFO: Test took 4824ms.
[10:55:44.705] <TB1> INFO: Expecting 41600 events.
[10:55:49.209] <TB1> INFO: 41600 events read in total (3787ms).
[10:55:49.210] <TB1> INFO: Test took 4851ms.
[10:55:49.768] <TB1> INFO: Expecting 41600 events.
[10:55:54.296] <TB1> INFO: 41600 events read in total (3810ms).
[10:55:54.297] <TB1> INFO: Test took 4847ms.
[10:55:54.539] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:54.672] <TB1> INFO: Expecting 2560 events.
[10:55:55.743] <TB1> INFO: 2560 events read in total (353ms).
[10:55:55.744] <TB1> INFO: Test took 1205ms.
[10:55:55.747] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:56.160] <TB1> INFO: Expecting 2560 events.
[10:55:57.233] <TB1> INFO: 2560 events read in total (356ms).
[10:55:57.234] <TB1> INFO: Test took 1487ms.
[10:55:57.237] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:57.650] <TB1> INFO: Expecting 2560 events.
[10:55:58.722] <TB1> INFO: 2560 events read in total (355ms).
[10:55:58.723] <TB1> INFO: Test took 1486ms.
[10:55:58.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:59.139] <TB1> INFO: Expecting 2560 events.
[10:56:00.210] <TB1> INFO: 2560 events read in total (354ms).
[10:56:00.210] <TB1> INFO: Test took 1484ms.
[10:56:00.214] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:00.627] <TB1> INFO: Expecting 2560 events.
[10:56:01.694] <TB1> INFO: 2560 events read in total (350ms).
[10:56:01.694] <TB1> INFO: Test took 1480ms.
[10:56:01.697] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:02.111] <TB1> INFO: Expecting 2560 events.
[10:56:03.184] <TB1> INFO: 2560 events read in total (356ms).
[10:56:03.185] <TB1> INFO: Test took 1488ms.
[10:56:03.188] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:03.601] <TB1> INFO: Expecting 2560 events.
[10:56:04.674] <TB1> INFO: 2560 events read in total (356ms).
[10:56:04.675] <TB1> INFO: Test took 1487ms.
[10:56:04.678] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:05.092] <TB1> INFO: Expecting 2560 events.
[10:56:06.164] <TB1> INFO: 2560 events read in total (355ms).
[10:56:06.164] <TB1> INFO: Test took 1486ms.
[10:56:06.168] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:06.582] <TB1> INFO: Expecting 2560 events.
[10:56:07.654] <TB1> INFO: 2560 events read in total (355ms).
[10:56:07.655] <TB1> INFO: Test took 1487ms.
[10:56:07.658] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:08.072] <TB1> INFO: Expecting 2560 events.
[10:56:09.145] <TB1> INFO: 2560 events read in total (356ms).
[10:56:09.145] <TB1> INFO: Test took 1487ms.
[10:56:09.148] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:09.562] <TB1> INFO: Expecting 2560 events.
[10:56:10.629] <TB1> INFO: 2560 events read in total (350ms).
[10:56:10.629] <TB1> INFO: Test took 1481ms.
[10:56:10.633] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:11.046] <TB1> INFO: Expecting 2560 events.
[10:56:12.131] <TB1> INFO: 2560 events read in total (368ms).
[10:56:12.131] <TB1> INFO: Test took 1498ms.
[10:56:12.134] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:12.548] <TB1> INFO: Expecting 2560 events.
[10:56:13.620] <TB1> INFO: 2560 events read in total (355ms).
[10:56:13.621] <TB1> INFO: Test took 1487ms.
[10:56:13.624] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:14.037] <TB1> INFO: Expecting 2560 events.
[10:56:15.110] <TB1> INFO: 2560 events read in total (356ms).
[10:56:15.110] <TB1> INFO: Test took 1486ms.
[10:56:15.113] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:15.527] <TB1> INFO: Expecting 2560 events.
[10:56:16.597] <TB1> INFO: 2560 events read in total (353ms).
[10:56:16.598] <TB1> INFO: Test took 1485ms.
[10:56:16.601] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:17.014] <TB1> INFO: Expecting 2560 events.
[10:56:18.086] <TB1> INFO: 2560 events read in total (355ms).
[10:56:18.086] <TB1> INFO: Test took 1485ms.
[10:56:18.090] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:18.503] <TB1> INFO: Expecting 2560 events.
[10:56:19.574] <TB1> INFO: 2560 events read in total (354ms).
[10:56:19.575] <TB1> INFO: Test took 1486ms.
[10:56:19.578] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:19.991] <TB1> INFO: Expecting 2560 events.
[10:56:21.062] <TB1> INFO: 2560 events read in total (354ms).
[10:56:21.062] <TB1> INFO: Test took 1484ms.
[10:56:21.065] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:21.479] <TB1> INFO: Expecting 2560 events.
[10:56:22.551] <TB1> INFO: 2560 events read in total (355ms).
[10:56:22.551] <TB1> INFO: Test took 1486ms.
[10:56:22.555] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:22.968] <TB1> INFO: Expecting 2560 events.
[10:56:24.039] <TB1> INFO: 2560 events read in total (354ms).
[10:56:24.039] <TB1> INFO: Test took 1484ms.
[10:56:24.043] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:24.456] <TB1> INFO: Expecting 2560 events.
[10:56:25.528] <TB1> INFO: 2560 events read in total (355ms).
[10:56:25.529] <TB1> INFO: Test took 1486ms.
[10:56:25.532] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:25.947] <TB1> INFO: Expecting 2560 events.
[10:56:27.020] <TB1> INFO: 2560 events read in total (356ms).
[10:56:27.020] <TB1> INFO: Test took 1489ms.
[10:56:27.022] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:27.438] <TB1> INFO: Expecting 2560 events.
[10:56:28.509] <TB1> INFO: 2560 events read in total (354ms).
[10:56:28.510] <TB1> INFO: Test took 1488ms.
[10:56:28.513] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:28.927] <TB1> INFO: Expecting 2560 events.
[10:56:29.999] <TB1> INFO: 2560 events read in total (355ms).
[10:56:29.999] <TB1> INFO: Test took 1486ms.
[10:56:30.002] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:30.416] <TB1> INFO: Expecting 2560 events.
[10:56:31.488] <TB1> INFO: 2560 events read in total (355ms).
[10:56:31.488] <TB1> INFO: Test took 1486ms.
[10:56:31.492] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:31.905] <TB1> INFO: Expecting 2560 events.
[10:56:32.976] <TB1> INFO: 2560 events read in total (354ms).
[10:56:32.976] <TB1> INFO: Test took 1484ms.
[10:56:32.980] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:33.393] <TB1> INFO: Expecting 2560 events.
[10:56:34.465] <TB1> INFO: 2560 events read in total (355ms).
[10:56:34.466] <TB1> INFO: Test took 1486ms.
[10:56:34.469] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:34.883] <TB1> INFO: Expecting 2560 events.
[10:56:35.955] <TB1> INFO: 2560 events read in total (355ms).
[10:56:35.956] <TB1> INFO: Test took 1487ms.
[10:56:35.960] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:36.371] <TB1> INFO: Expecting 2560 events.
[10:56:37.442] <TB1> INFO: 2560 events read in total (354ms).
[10:56:37.442] <TB1> INFO: Test took 1483ms.
[10:56:37.447] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:37.859] <TB1> INFO: Expecting 2560 events.
[10:56:38.932] <TB1> INFO: 2560 events read in total (357ms).
[10:56:38.933] <TB1> INFO: Test took 1487ms.
[10:56:38.936] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:39.350] <TB1> INFO: Expecting 2560 events.
[10:56:40.421] <TB1> INFO: 2560 events read in total (354ms).
[10:56:40.421] <TB1> INFO: Test took 1485ms.
[10:56:40.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:40.838] <TB1> INFO: Expecting 2560 events.
[10:56:41.913] <TB1> INFO: 2560 events read in total (358ms).
[10:56:41.913] <TB1> INFO: Test took 1489ms.
[10:56:42.598] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 614 seconds
[10:56:42.598] <TB1> INFO: PH scale (per ROC): 86 75 84 83 97 82 82 79 82 86 74 80 84 78 81 78
[10:56:42.598] <TB1> INFO: PH offset (per ROC): 161 164 159 129 144 164 143 149 149 151 145 158 153 157 153 161
[10:56:42.604] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:42.604] <TB1> INFO: Decoding statistics:
[10:56:42.604] <TB1> INFO: General information:
[10:56:42.604] <TB1> INFO: 16bit words read: 91916
[10:56:42.604] <TB1> INFO: valid events total: 10240
[10:56:42.604] <TB1> INFO: empty events: 7741
[10:56:42.604] <TB1> INFO: valid events with pixels: 2499
[10:56:42.604] <TB1> INFO: valid pixel hits: 2499
[10:56:42.604] <TB1> INFO: Event errors: 0
[10:56:42.604] <TB1> INFO: start marker: 0
[10:56:42.604] <TB1> INFO: stop marker: 0
[10:56:42.604] <TB1> INFO: overflow: 0
[10:56:42.604] <TB1> INFO: invalid 5bit words: 0
[10:56:42.604] <TB1> INFO: invalid XOR eye diagram: 0
[10:56:42.604] <TB1> INFO: TBM errors: 0
[10:56:42.604] <TB1> INFO: flawed TBM headers: 0
[10:56:42.604] <TB1> INFO: flawed TBM trailers: 0
[10:56:42.605] <TB1> INFO: event ID mismatches: 0
[10:56:42.605] <TB1> INFO: ROC errors: 0
[10:56:42.605] <TB1> INFO: missing ROC header(s): 0
[10:56:42.605] <TB1> INFO: misplaced readback start: 0
[10:56:42.605] <TB1> INFO: Pixel decoding errors: 0
[10:56:42.605] <TB1> INFO: pixel data incomplete: 0
[10:56:42.605] <TB1> INFO: pixel address: 0
[10:56:42.605] <TB1> INFO: pulse height fill bit: 0
[10:56:42.605] <TB1> INFO: buffer corruption: 0
[10:56:42.791] <TB1> INFO: ######################################################################
[10:56:42.791] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:56:42.791] <TB1> INFO: ######################################################################
[10:56:42.802] <TB1> INFO: scanning low vcal = 10
[10:56:43.120] <TB1> INFO: Expecting 41600 events.
[10:56:46.728] <TB1> INFO: 41600 events read in total (2891ms).
[10:56:46.728] <TB1> INFO: Test took 3927ms.
[10:56:46.731] <TB1> INFO: scanning low vcal = 20
[10:56:47.145] <TB1> INFO: Expecting 41600 events.
[10:56:50.769] <TB1> INFO: 41600 events read in total (2907ms).
[10:56:50.769] <TB1> INFO: Test took 4038ms.
[10:56:50.772] <TB1> INFO: scanning low vcal = 30
[10:56:51.185] <TB1> INFO: Expecting 41600 events.
[10:56:54.808] <TB1> INFO: 41600 events read in total (2906ms).
[10:56:54.809] <TB1> INFO: Test took 4037ms.
[10:56:54.812] <TB1> INFO: scanning low vcal = 40
[10:56:55.218] <TB1> INFO: Expecting 41600 events.
[10:56:59.465] <TB1> INFO: 41600 events read in total (3530ms).
[10:56:59.465] <TB1> INFO: Test took 4653ms.
[10:56:59.469] <TB1> INFO: scanning low vcal = 50
[10:56:59.812] <TB1> INFO: Expecting 41600 events.
[10:57:04.071] <TB1> INFO: 41600 events read in total (3542ms).
[10:57:04.072] <TB1> INFO: Test took 4603ms.
[10:57:04.076] <TB1> INFO: scanning low vcal = 60
[10:57:04.417] <TB1> INFO: Expecting 41600 events.
[10:57:08.626] <TB1> INFO: 41600 events read in total (3492ms).
[10:57:08.626] <TB1> INFO: Test took 4550ms.
[10:57:08.629] <TB1> INFO: scanning low vcal = 70
[10:57:08.976] <TB1> INFO: Expecting 41600 events.
[10:57:13.223] <TB1> INFO: 41600 events read in total (3530ms).
[10:57:13.224] <TB1> INFO: Test took 4594ms.
[10:57:13.229] <TB1> INFO: scanning low vcal = 80
[10:57:13.572] <TB1> INFO: Expecting 41600 events.
[10:57:17.780] <TB1> INFO: 41600 events read in total (3491ms).
[10:57:17.781] <TB1> INFO: Test took 4552ms.
[10:57:17.784] <TB1> INFO: scanning low vcal = 90
[10:57:18.122] <TB1> INFO: Expecting 41600 events.
[10:57:22.476] <TB1> INFO: 41600 events read in total (3637ms).
[10:57:22.476] <TB1> INFO: Test took 4692ms.
[10:57:22.480] <TB1> INFO: scanning low vcal = 100
[10:57:22.819] <TB1> INFO: Expecting 41600 events.
[10:57:27.018] <TB1> INFO: 41600 events read in total (3482ms).
[10:57:27.018] <TB1> INFO: Test took 4538ms.
[10:57:27.022] <TB1> INFO: scanning low vcal = 110
[10:57:27.369] <TB1> INFO: Expecting 41600 events.
[10:57:31.594] <TB1> INFO: 41600 events read in total (3508ms).
[10:57:31.595] <TB1> INFO: Test took 4573ms.
[10:57:31.598] <TB1> INFO: scanning low vcal = 120
[10:57:31.948] <TB1> INFO: Expecting 41600 events.
[10:57:36.184] <TB1> INFO: 41600 events read in total (3519ms).
[10:57:36.185] <TB1> INFO: Test took 4587ms.
[10:57:36.189] <TB1> INFO: scanning low vcal = 130
[10:57:36.522] <TB1> INFO: Expecting 41600 events.
[10:57:40.733] <TB1> INFO: 41600 events read in total (3494ms).
[10:57:40.734] <TB1> INFO: Test took 4545ms.
[10:57:40.737] <TB1> INFO: scanning low vcal = 140
[10:57:41.081] <TB1> INFO: Expecting 41600 events.
[10:57:45.306] <TB1> INFO: 41600 events read in total (3508ms).
[10:57:45.307] <TB1> INFO: Test took 4570ms.
[10:57:45.310] <TB1> INFO: scanning low vcal = 150
[10:57:45.656] <TB1> INFO: Expecting 41600 events.
[10:57:49.856] <TB1> INFO: 41600 events read in total (3483ms).
[10:57:49.857] <TB1> INFO: Test took 4547ms.
[10:57:49.860] <TB1> INFO: scanning low vcal = 160
[10:57:50.216] <TB1> INFO: Expecting 41600 events.
[10:57:54.463] <TB1> INFO: 41600 events read in total (3530ms).
[10:57:54.464] <TB1> INFO: Test took 4604ms.
[10:57:54.467] <TB1> INFO: scanning low vcal = 170
[10:57:54.807] <TB1> INFO: Expecting 41600 events.
[10:57:59.033] <TB1> INFO: 41600 events read in total (3509ms).
[10:57:59.033] <TB1> INFO: Test took 4566ms.
[10:57:59.039] <TB1> INFO: scanning low vcal = 180
[10:57:59.381] <TB1> INFO: Expecting 41600 events.
[10:58:03.617] <TB1> INFO: 41600 events read in total (3519ms).
[10:58:03.618] <TB1> INFO: Test took 4579ms.
[10:58:03.621] <TB1> INFO: scanning low vcal = 190
[10:58:03.974] <TB1> INFO: Expecting 41600 events.
[10:58:08.207] <TB1> INFO: 41600 events read in total (3516ms).
[10:58:08.208] <TB1> INFO: Test took 4587ms.
[10:58:08.211] <TB1> INFO: scanning low vcal = 200
[10:58:08.551] <TB1> INFO: Expecting 41600 events.
[10:58:12.720] <TB1> INFO: 41600 events read in total (3452ms).
[10:58:12.721] <TB1> INFO: Test took 4510ms.
[10:58:12.725] <TB1> INFO: scanning low vcal = 210
[10:58:13.069] <TB1> INFO: Expecting 41600 events.
[10:58:17.247] <TB1> INFO: 41600 events read in total (3461ms).
[10:58:17.248] <TB1> INFO: Test took 4523ms.
[10:58:17.252] <TB1> INFO: scanning low vcal = 220
[10:58:17.599] <TB1> INFO: Expecting 41600 events.
[10:58:21.850] <TB1> INFO: 41600 events read in total (3534ms).
[10:58:21.851] <TB1> INFO: Test took 4599ms.
[10:58:21.855] <TB1> INFO: scanning low vcal = 230
[10:58:22.204] <TB1> INFO: Expecting 41600 events.
[10:58:26.410] <TB1> INFO: 41600 events read in total (3488ms).
[10:58:26.411] <TB1> INFO: Test took 4556ms.
[10:58:26.415] <TB1> INFO: scanning low vcal = 240
[10:58:26.758] <TB1> INFO: Expecting 41600 events.
[10:58:30.985] <TB1> INFO: 41600 events read in total (3510ms).
[10:58:30.986] <TB1> INFO: Test took 4571ms.
[10:58:30.989] <TB1> INFO: scanning low vcal = 250
[10:58:31.341] <TB1> INFO: Expecting 41600 events.
[10:58:35.589] <TB1> INFO: 41600 events read in total (3531ms).
[10:58:35.589] <TB1> INFO: Test took 4600ms.
[10:58:35.595] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[10:58:35.944] <TB1> INFO: Expecting 41600 events.
[10:58:40.190] <TB1> INFO: 41600 events read in total (3529ms).
[10:58:40.190] <TB1> INFO: Test took 4595ms.
[10:58:40.194] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[10:58:40.533] <TB1> INFO: Expecting 41600 events.
[10:58:44.773] <TB1> INFO: 41600 events read in total (3523ms).
[10:58:44.774] <TB1> INFO: Test took 4580ms.
[10:58:44.777] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[10:58:45.126] <TB1> INFO: Expecting 41600 events.
[10:58:49.376] <TB1> INFO: 41600 events read in total (3533ms).
[10:58:49.377] <TB1> INFO: Test took 4600ms.
[10:58:49.380] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[10:58:49.719] <TB1> INFO: Expecting 41600 events.
[10:58:54.075] <TB1> INFO: 41600 events read in total (3639ms).
[10:58:54.076] <TB1> INFO: Test took 4695ms.
[10:58:54.079] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:58:54.429] <TB1> INFO: Expecting 41600 events.
[10:58:58.645] <TB1> INFO: 41600 events read in total (3499ms).
[10:58:58.645] <TB1> INFO: Test took 4566ms.
[10:58:59.140] <TB1> INFO: PixTestGainPedestal::measure() done
[10:59:31.151] <TB1> INFO: PixTestGainPedestal::fit() done
[10:59:31.151] <TB1> INFO: non-linearity mean: 0.950 0.958 0.950 0.950 0.961 0.948 0.955 0.956 0.951 0.961 0.955 0.955 0.962 0.950 0.963 0.957
[10:59:31.151] <TB1> INFO: non-linearity RMS: 0.006 0.008 0.005 0.007 0.005 0.006 0.005 0.006 0.007 0.006 0.006 0.006 0.005 0.006 0.005 0.006
[10:59:31.151] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:59:31.169] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:59:31.187] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:59:31.205] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:59:31.223] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:59:31.241] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:59:31.260] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:59:31.278] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:59:31.296] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:59:31.314] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:59:31.332] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:59:31.350] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:59:31.368] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:59:31.386] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:59:31.404] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:59:31.423] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:59:31.441] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 168 seconds
[10:59:31.441] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:31.441] <TB1> INFO: Decoding statistics:
[10:59:31.441] <TB1> INFO: General information:
[10:59:31.441] <TB1> INFO: 16bit words read: 2662400
[10:59:31.441] <TB1> INFO: valid events total: 166400
[10:59:31.441] <TB1> INFO: empty events: 0
[10:59:31.441] <TB1> INFO: valid events with pixels: 166400
[10:59:31.441] <TB1> INFO: valid pixel hits: 665580
[10:59:31.441] <TB1> INFO: Event errors: 0
[10:59:31.441] <TB1> INFO: start marker: 0
[10:59:31.441] <TB1> INFO: stop marker: 0
[10:59:31.441] <TB1> INFO: overflow: 0
[10:59:31.441] <TB1> INFO: invalid 5bit words: 0
[10:59:31.441] <TB1> INFO: invalid XOR eye diagram: 0
[10:59:31.441] <TB1> INFO: TBM errors: 0
[10:59:31.441] <TB1> INFO: flawed TBM headers: 0
[10:59:31.441] <TB1> INFO: flawed TBM trailers: 0
[10:59:31.441] <TB1> INFO: event ID mismatches: 0
[10:59:31.441] <TB1> INFO: ROC errors: 0
[10:59:31.441] <TB1> INFO: missing ROC header(s): 0
[10:59:31.441] <TB1> INFO: misplaced readback start: 0
[10:59:31.441] <TB1> INFO: Pixel decoding errors: 0
[10:59:31.441] <TB1> INFO: pixel data incomplete: 0
[10:59:31.441] <TB1> INFO: pixel address: 0
[10:59:31.441] <TB1> INFO: pulse height fill bit: 0
[10:59:31.441] <TB1> INFO: buffer corruption: 0
[10:59:31.447] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[10:59:31.448] <TB1> INFO: ######################################################################
[10:59:31.448] <TB1> INFO: PixTestTrim::doTest()
[10:59:31.448] <TB1> INFO: ######################################################################
[10:59:31.449] <TB1> INFO: PixTestReadback::RES sent once
[10:59:53.384] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[10:59:53.384] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[10:59:53.384] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[10:59:53.384] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[10:59:53.384] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[10:59:53.385] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[10:59:53.432] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:59:53.432] <TB1> INFO: PixTestReadback::RES sent once
[11:00:15.247] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[11:00:15.247] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[11:00:15.247] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[11:00:15.247] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[11:00:15.248] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:00:15.297] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:00:15.298] <TB1> INFO: PixTestReadback::RES sent once
[11:00:32.239] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:00:32.239] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.3calibrated Vbg = 1.18849 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.1calibrated Vbg = 1.18194 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.4calibrated Vbg = 1.18598 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.8calibrated Vbg = 1.19121 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.5calibrated Vbg = 1.19999 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.3calibrated Vbg = 1.19169 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.4calibrated Vbg = 1.20096 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150calibrated Vbg = 1.19846 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 147.9calibrated Vbg = 1.19099 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.5calibrated Vbg = 1.18039 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160calibrated Vbg = 1.1865 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.8calibrated Vbg = 1.18316 :::*/*/*/*/
[11:00:32.240] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:00:32.245] <TB1> INFO: PixTestReadback::RES sent once
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[11:05:13.540] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[11:05:13.541] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2105_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:05:13.587] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:05:13.588] <TB1> INFO: PixTestReadback::doTest() done
[11:05:13.588] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:13.588] <TB1> INFO: Decoding statistics:
[11:05:13.588] <TB1> INFO: General information:
[11:05:13.588] <TB1> INFO: 16bit words read: 1024
[11:05:13.588] <TB1> INFO: valid events total: 128
[11:05:13.588] <TB1> INFO: empty events: 128
[11:05:13.588] <TB1> INFO: valid events with pixels: 0
[11:05:13.588] <TB1> INFO: valid pixel hits: 0
[11:05:13.588] <TB1> INFO: Event errors: 0
[11:05:13.589] <TB1> INFO: start marker: 0
[11:05:13.589] <TB1> INFO: stop marker: 0
[11:05:13.589] <TB1> INFO: overflow: 0
[11:05:13.589] <TB1> INFO: invalid 5bit words: 0
[11:05:13.589] <TB1> INFO: invalid XOR eye diagram: 0
[11:05:13.589] <TB1> INFO: TBM errors: 0
[11:05:13.589] <TB1> INFO: flawed TBM headers: 0
[11:05:13.589] <TB1> INFO: flawed TBM trailers: 0
[11:05:13.589] <TB1> INFO: event ID mismatches: 0
[11:05:13.589] <TB1> INFO: ROC errors: 0
[11:05:13.589] <TB1> INFO: missing ROC header(s): 0
[11:05:13.589] <TB1> INFO: misplaced readback start: 0
[11:05:13.589] <TB1> INFO: Pixel decoding errors: 0
[11:05:13.589] <TB1> INFO: pixel data incomplete: 0
[11:05:13.589] <TB1> INFO: pixel address: 0
[11:05:13.589] <TB1> INFO: pulse height fill bit: 0
[11:05:13.589] <TB1> INFO: buffer corruption: 0
[11:05:13.605] <TB1> INFO: Decoding statistics:
[11:05:13.605] <TB1> INFO: General information:
[11:05:13.605] <TB1> INFO: 16bit words read: 2755340
[11:05:13.605] <TB1> INFO: valid events total: 176768
[11:05:13.605] <TB1> INFO: empty events: 7869
[11:05:13.605] <TB1> INFO: valid events with pixels: 168899
[11:05:13.605] <TB1> INFO: valid pixel hits: 668079
[11:05:13.605] <TB1> INFO: Event errors: 0
[11:05:13.605] <TB1> INFO: start marker: 0
[11:05:13.605] <TB1> INFO: stop marker: 0
[11:05:13.605] <TB1> INFO: overflow: 0
[11:05:13.605] <TB1> INFO: invalid 5bit words: 0
[11:05:13.605] <TB1> INFO: invalid XOR eye diagram: 0
[11:05:13.605] <TB1> INFO: TBM errors: 0
[11:05:13.605] <TB1> INFO: flawed TBM headers: 0
[11:05:13.605] <TB1> INFO: flawed TBM trailers: 0
[11:05:13.605] <TB1> INFO: event ID mismatches: 0
[11:05:13.605] <TB1> INFO: ROC errors: 0
[11:05:13.605] <TB1> INFO: missing ROC header(s): 0
[11:05:13.605] <TB1> INFO: misplaced readback start: 0
[11:05:13.605] <TB1> INFO: Pixel decoding errors: 0
[11:05:13.605] <TB1> INFO: pixel data incomplete: 0
[11:05:13.605] <TB1> INFO: pixel address: 0
[11:05:13.605] <TB1> INFO: pulse height fill bit: 0
[11:05:13.605] <TB1> INFO: buffer corruption: 0
[11:05:13.605] <TB1> INFO: enter test to run
[11:05:13.605] <TB1> INFO: test: exit no parameter change
[11:05:14.161] <TB1> QUIET: Connection to board 153 closed.
[11:05:14.241] <TB1> INFO: pXar: this is the end, my friend