[09:29:47.903] <TB3> INFO: *** Welcome to pxar ***
[09:29:47.903] <TB3> INFO: *** Today: 2015/09/07
[09:29:47.903] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C15.dat
[09:29:47.905] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:29:47.905] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//defaultMaskFile.dat
[09:29:47.905] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters_C15.dat
[09:29:47.992] <TB3> INFO: clk: 4
[09:29:47.992] <TB3> INFO: ctr: 4
[09:29:47.992] <TB3> INFO: sda: 19
[09:29:47.992] <TB3> INFO: tin: 9
[09:29:47.992] <TB3> INFO: level: 15
[09:29:47.992] <TB3> INFO: triggerdelay: 0
[09:29:47.992] <TB3> QUIET: Instanciating API for pxar prod-10+14~g355f077
[09:29:47.992] <TB3> INFO: Log level: INFO
[09:29:47.999] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:29:48.010] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:29:48.013] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:29:48.016] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[09:29:49.539] <TB3> INFO: DUT info:
[09:29:49.539] <TB3> INFO: The DUT currently contains the following objects:
[09:29:49.539] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:29:49.539] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:29:49.539] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:29:49.539] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:29:49.539] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.539] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.539] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.539] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.540] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:29:49.941] <TB3> INFO: enter 'restricted' command line mode
[09:29:49.941] <TB3> INFO: enter test to run
[09:29:49.942] <TB3> INFO: test: pretest no parameter change
[09:29:49.942] <TB3> INFO: running: pretest
[09:29:49.949] <TB3> INFO: ######################################################################
[09:29:49.949] <TB3> INFO: PixTestPretest::doTest()
[09:29:49.949] <TB3> INFO: ######################################################################
[09:29:49.950] <TB3> INFO: ----------------------------------------------------------------------
[09:29:49.950] <TB3> INFO: PixTestPretest::programROC()
[09:29:49.950] <TB3> INFO: ----------------------------------------------------------------------
[09:30:07.968] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:30:07.968] <TB3> INFO: IA differences per ROC: 16.9 16.9 20.1 19.3 20.1 19.3 18.5 19.3 16.9 19.3 19.3 16.9 17.7 18.5 20.1 18.5
[09:30:08.035] <TB3> INFO: ----------------------------------------------------------------------
[09:30:08.035] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:30:08.035] <TB3> INFO: ----------------------------------------------------------------------
[09:30:12.714] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 363.4 mA = 22.7125 mA/ROC
[09:30:12.717] <TB3> INFO: ----------------------------------------------------------------------
[09:30:12.717] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:30:12.717] <TB3> INFO: ----------------------------------------------------------------------
[09:30:12.857] <TB3> INFO: Expecting 231680 events.
[09:30:22.085] <TB3> INFO: 231680 events read in total (8509ms).
[09:30:22.147] <TB3> INFO: Test took 9424ms.
[09:30:22.395] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:30:22.428] <TB3> INFO: ----------------------------------------------------------------------
[09:30:22.428] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:30:22.428] <TB3> INFO: ----------------------------------------------------------------------
[09:30:22.565] <TB3> INFO: Expecting 231680 events.
[09:30:31.724] <TB3> INFO: 231680 events read in total (8443ms).
[09:30:31.729] <TB3> INFO: Test took 9297ms.
[09:30:32.052] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:30:32.052] <TB3> INFO: CalDel: 145 143 131 145 154 140 122 153 123 129 156 153 123 138 140 149
[09:30:32.052] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:30:32.054] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C0.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C1.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C2.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C3.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C4.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C5.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C6.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C7.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C8.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C9.dat
[09:30:32.055] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C10.dat
[09:30:32.056] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C11.dat
[09:30:32.056] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C12.dat
[09:30:32.056] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C13.dat
[09:30:32.056] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C14.dat
[09:30:32.056] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters_C15.dat
[09:30:32.056] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0a.dat
[09:30:32.056] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:30:32.056] <TB3> INFO: PixTestPretest::doTest() done, duration: 42 seconds
[09:30:32.145] <TB3> INFO: enter test to run
[09:30:32.145] <TB3> INFO: test: fulltest no parameter change
[09:30:32.145] <TB3> INFO: running: fulltest
[09:30:32.145] <TB3> INFO: ######################################################################
[09:30:32.145] <TB3> INFO: PixTestFullTest::doTest()
[09:30:32.145] <TB3> INFO: ######################################################################
[09:30:32.147] <TB3> INFO: ######################################################################
[09:30:32.147] <TB3> INFO: PixTestAlive::doTest()
[09:30:32.147] <TB3> INFO: ######################################################################
[09:30:32.149] <TB3> INFO: ----------------------------------------------------------------------
[09:30:32.149] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:32.149] <TB3> INFO: ----------------------------------------------------------------------
[09:30:32.458] <TB3> INFO: Expecting 41600 events.
[09:30:37.265] <TB3> INFO: 41600 events read in total (4090ms).
[09:30:37.265] <TB3> INFO: Test took 5114ms.
[09:30:37.271] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:37.560] <TB3> INFO: PixTestAlive::aliveTest() done
[09:30:37.560] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:37.561] <TB3> INFO: ----------------------------------------------------------------------
[09:30:37.561] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:37.561] <TB3> INFO: ----------------------------------------------------------------------
[09:30:37.874] <TB3> INFO: Expecting 41600 events.
[09:30:41.281] <TB3> INFO: 41600 events read in total (2691ms).
[09:30:41.281] <TB3> INFO: Test took 3719ms.
[09:30:41.281] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:41.281] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:30:41.597] <TB3> INFO: PixTestAlive::maskTest() done
[09:30:41.597] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:41.598] <TB3> INFO: ----------------------------------------------------------------------
[09:30:41.598] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:41.598] <TB3> INFO: ----------------------------------------------------------------------
[09:30:41.943] <TB3> INFO: Expecting 41600 events.
[09:30:46.838] <TB3> INFO: 41600 events read in total (4179ms).
[09:30:46.839] <TB3> INFO: Test took 5239ms.
[09:30:46.846] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:47.134] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:30:47.134] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:47.134] <TB3> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[09:30:47.134] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:47.134] <TB3> INFO: Decoding statistics:
[09:30:47.134] <TB3> INFO: General information:
[09:30:47.134] <TB3> INFO: 16bit words read: 0
[09:30:47.134] <TB3> INFO: valid events total: 0
[09:30:47.134] <TB3> INFO: empty events: 0
[09:30:47.134] <TB3> INFO: valid events with pixels: 0
[09:30:47.134] <TB3> INFO: valid pixel hits: 0
[09:30:47.134] <TB3> INFO: Event errors: 0
[09:30:47.134] <TB3> INFO: start marker: 0
[09:30:47.134] <TB3> INFO: stop marker: 0
[09:30:47.134] <TB3> INFO: overflow: 0
[09:30:47.134] <TB3> INFO: invalid 5bit words: 0
[09:30:47.134] <TB3> INFO: invalid XOR eye diagram: 0
[09:30:47.134] <TB3> INFO: TBM errors: 0
[09:30:47.134] <TB3> INFO: flawed TBM headers: 0
[09:30:47.134] <TB3> INFO: flawed TBM trailers: 0
[09:30:47.134] <TB3> INFO: event ID mismatches: 0
[09:30:47.134] <TB3> INFO: ROC errors: 0
[09:30:47.134] <TB3> INFO: missing ROC header(s): 0
[09:30:47.134] <TB3> INFO: misplaced readback start: 0
[09:30:47.134] <TB3> INFO: Pixel decoding errors: 0
[09:30:47.134] <TB3> INFO: pixel data incomplete: 0
[09:30:47.134] <TB3> INFO: pixel address: 0
[09:30:47.134] <TB3> INFO: pulse height fill bit: 0
[09:30:47.134] <TB3> INFO: buffer corruption: 0
[09:30:47.144] <TB3> INFO: ######################################################################
[09:30:47.144] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:30:47.144] <TB3> INFO: ######################################################################
[09:30:47.147] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:30:47.160] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:30:47.160] <TB3> INFO: run 1 of 1
[09:30:47.469] <TB3> INFO: Expecting 3120000 events.
[09:31:28.845] <TB3> INFO: 832370 events read in total (40660ms).
[09:32:09.468] <TB3> INFO: 1656395 events read in total (81284ms).
[09:32:50.721] <TB3> INFO: 2489435 events read in total (122536ms).
[09:33:21.052] <TB3> INFO: 3120000 events read in total (152867ms).
[09:33:21.126] <TB3> INFO: Test took 153966ms.
[09:33:21.247] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:44.944] <TB3> INFO: PixTestBBMap::doTest() done, duration: 177 seconds
[09:33:44.944] <TB3> INFO: number of dead bumps (per ROC): 1 0 15 1 0 0 0 0 0 0 3 0 3 4 1 2
[09:33:44.944] <TB3> INFO: separation cut (per ROC): 75 69 73 73 83 67 67 54 60 69 65 87 83 89 89 73
[09:33:44.944] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:44.944] <TB3> INFO: Decoding statistics:
[09:33:44.944] <TB3> INFO: General information:
[09:33:44.944] <TB3> INFO: 16bit words read: 0
[09:33:44.944] <TB3> INFO: valid events total: 0
[09:33:44.944] <TB3> INFO: empty events: 0
[09:33:44.944] <TB3> INFO: valid events with pixels: 0
[09:33:44.944] <TB3> INFO: valid pixel hits: 0
[09:33:44.944] <TB3> INFO: Event errors: 0
[09:33:44.944] <TB3> INFO: start marker: 0
[09:33:44.945] <TB3> INFO: stop marker: 0
[09:33:44.945] <TB3> INFO: overflow: 0
[09:33:44.945] <TB3> INFO: invalid 5bit words: 0
[09:33:44.945] <TB3> INFO: invalid XOR eye diagram: 0
[09:33:44.945] <TB3> INFO: TBM errors: 0
[09:33:44.945] <TB3> INFO: flawed TBM headers: 0
[09:33:44.945] <TB3> INFO: flawed TBM trailers: 0
[09:33:44.945] <TB3> INFO: event ID mismatches: 0
[09:33:44.945] <TB3> INFO: ROC errors: 0
[09:33:44.945] <TB3> INFO: missing ROC header(s): 0
[09:33:44.945] <TB3> INFO: misplaced readback start: 0
[09:33:44.945] <TB3> INFO: Pixel decoding errors: 0
[09:33:44.945] <TB3> INFO: pixel data incomplete: 0
[09:33:44.945] <TB3> INFO: pixel address: 0
[09:33:44.945] <TB3> INFO: pulse height fill bit: 0
[09:33:44.945] <TB3> INFO: buffer corruption: 0
[09:33:45.018] <TB3> INFO: ######################################################################
[09:33:45.018] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:33:45.018] <TB3> INFO: ######################################################################
[09:33:45.018] <TB3> INFO: ----------------------------------------------------------------------
[09:33:45.018] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:33:45.018] <TB3> INFO: ----------------------------------------------------------------------
[09:33:45.018] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:33:45.027] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:45.027] <TB3> INFO: run 1 of 1
[09:33:45.364] <TB3> INFO: Expecting 26208000 events.
[09:34:15.441] <TB3> INFO: 826800 events read in total (29361ms).
[09:34:44.818] <TB3> INFO: 1643350 events read in total (58738ms).
[09:35:14.160] <TB3> INFO: 2455650 events read in total (88080ms).
[09:35:43.291] <TB3> INFO: 3268100 events read in total (117211ms).
[09:36:12.179] <TB3> INFO: 4079450 events read in total (146099ms).
[09:36:41.524] <TB3> INFO: 4888550 events read in total (175444ms).
[09:37:10.909] <TB3> INFO: 5700700 events read in total (204829ms).
[09:37:39.865] <TB3> INFO: 6511000 events read in total (233785ms).
[09:38:08.886] <TB3> INFO: 7319000 events read in total (262806ms).
[09:38:38.110] <TB3> INFO: 8129650 events read in total (292030ms).
[09:39:07.370] <TB3> INFO: 8936050 events read in total (321290ms).
[09:39:36.753] <TB3> INFO: 9745100 events read in total (350673ms).
[09:40:05.694] <TB3> INFO: 10552000 events read in total (379614ms).
[09:40:34.420] <TB3> INFO: 11358800 events read in total (408340ms).
[09:41:03.746] <TB3> INFO: 12168600 events read in total (437666ms).
[09:41:32.811] <TB3> INFO: 12972100 events read in total (466731ms).
[09:42:02.347] <TB3> INFO: 13771050 events read in total (496267ms).
[09:42:31.623] <TB3> INFO: 14568150 events read in total (525543ms).
[09:43:00.449] <TB3> INFO: 15364300 events read in total (554369ms).
[09:43:29.400] <TB3> INFO: 16161900 events read in total (583320ms).
[09:43:58.485] <TB3> INFO: 16956500 events read in total (612405ms).
[09:44:27.268] <TB3> INFO: 17752200 events read in total (641188ms).
[09:44:56.814] <TB3> INFO: 18546950 events read in total (670734ms).
[09:45:25.869] <TB3> INFO: 19342950 events read in total (699789ms).
[09:45:55.025] <TB3> INFO: 20137100 events read in total (728945ms).
[09:46:24.005] <TB3> INFO: 20928050 events read in total (757925ms).
[09:46:53.333] <TB3> INFO: 21722000 events read in total (787253ms).
[09:47:22.118] <TB3> INFO: 22511950 events read in total (816038ms).
[09:47:50.918] <TB3> INFO: 23307000 events read in total (844838ms).
[09:48:19.476] <TB3> INFO: 24098000 events read in total (873396ms).
[09:48:48.728] <TB3> INFO: 24892950 events read in total (902648ms).
[09:49:18.312] <TB3> INFO: 25687300 events read in total (932232ms).
[09:49:37.426] <TB3> INFO: 26208000 events read in total (951346ms).
[09:49:37.464] <TB3> INFO: Test took 952437ms.
[09:49:37.557] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:37.736] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:39.190] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:40.796] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:42.321] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:43.906] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:45.558] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:47.222] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:48.919] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:50.575] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:52.169] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:53.773] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:55.408] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:56.867] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:49:58.508] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:50:00.240] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:50:01.708] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:50:03.336] <TB3> INFO: PixTestScurves::scurves() done
[09:50:03.336] <TB3> INFO: Vcal mean: 84.71 80.80 79.09 79.59 88.88 65.90 68.42 51.15 61.16 77.83 67.77 96.11 80.36 96.88 90.69 76.07
[09:50:03.336] <TB3> INFO: Vcal RMS: 4.89 4.55 3.63 4.48 5.74 4.67 5.28 4.74 5.20 5.36 4.71 5.66 4.55 6.46 5.86 4.67
[09:50:03.336] <TB3> INFO: PixTestScurves::fullTest() done, duration: 978 seconds
[09:50:03.336] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:03.336] <TB3> INFO: Decoding statistics:
[09:50:03.336] <TB3> INFO: General information:
[09:50:03.336] <TB3> INFO: 16bit words read: 0
[09:50:03.336] <TB3> INFO: valid events total: 0
[09:50:03.336] <TB3> INFO: empty events: 0
[09:50:03.336] <TB3> INFO: valid events with pixels: 0
[09:50:03.336] <TB3> INFO: valid pixel hits: 0
[09:50:03.336] <TB3> INFO: Event errors: 0
[09:50:03.336] <TB3> INFO: start marker: 0
[09:50:03.336] <TB3> INFO: stop marker: 0
[09:50:03.336] <TB3> INFO: overflow: 0
[09:50:03.336] <TB3> INFO: invalid 5bit words: 0
[09:50:03.336] <TB3> INFO: invalid XOR eye diagram: 0
[09:50:03.336] <TB3> INFO: TBM errors: 0
[09:50:03.336] <TB3> INFO: flawed TBM headers: 0
[09:50:03.336] <TB3> INFO: flawed TBM trailers: 0
[09:50:03.336] <TB3> INFO: event ID mismatches: 0
[09:50:03.336] <TB3> INFO: ROC errors: 0
[09:50:03.336] <TB3> INFO: missing ROC header(s): 0
[09:50:03.336] <TB3> INFO: misplaced readback start: 0
[09:50:03.336] <TB3> INFO: Pixel decoding errors: 0
[09:50:03.336] <TB3> INFO: pixel data incomplete: 0
[09:50:03.336] <TB3> INFO: pixel address: 0
[09:50:03.336] <TB3> INFO: pulse height fill bit: 0
[09:50:03.337] <TB3> INFO: buffer corruption: 0
[09:50:03.412] <TB3> INFO: ######################################################################
[09:50:03.412] <TB3> INFO: PixTestTrim::doTest()
[09:50:03.412] <TB3> INFO: ######################################################################
[09:50:03.413] <TB3> INFO: ----------------------------------------------------------------------
[09:50:03.413] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:50:03.413] <TB3> INFO: ----------------------------------------------------------------------
[09:50:03.494] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:50:03.494] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:50:03.503] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:50:03.503] <TB3> INFO: run 1 of 1
[09:50:03.835] <TB3> INFO: Expecting 6281600 events.
[09:50:41.192] <TB3> INFO: 1047800 events read in total (36640ms).
[09:51:19.965] <TB3> INFO: 2086250 events read in total (75413ms).
[09:51:58.088] <TB3> INFO: 3114840 events read in total (113536ms).
[09:52:36.419] <TB3> INFO: 4148380 events read in total (151867ms).
[09:53:15.157] <TB3> INFO: 5185410 events read in total (190605ms).
[09:53:53.809] <TB3> INFO: 6223730 events read in total (229257ms).
[09:53:56.449] <TB3> INFO: 6281600 events read in total (231897ms).
[09:53:56.485] <TB3> INFO: Test took 232982ms.
[09:53:56.540] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:54:15.777] <TB3> INFO: ROC 0 VthrComp = 91
[09:54:15.777] <TB3> INFO: ROC 1 VthrComp = 85
[09:54:15.777] <TB3> INFO: ROC 2 VthrComp = 89
[09:54:15.777] <TB3> INFO: ROC 3 VthrComp = 86
[09:54:15.777] <TB3> INFO: ROC 4 VthrComp = 93
[09:54:15.777] <TB3> INFO: ROC 5 VthrComp = 73
[09:54:15.777] <TB3> INFO: ROC 6 VthrComp = 71
[09:54:15.777] <TB3> INFO: ROC 7 VthrComp = 55
[09:54:15.778] <TB3> INFO: ROC 8 VthrComp = 59
[09:54:15.778] <TB3> INFO: ROC 9 VthrComp = 74
[09:54:15.778] <TB3> INFO: ROC 10 VthrComp = 72
[09:54:15.778] <TB3> INFO: ROC 11 VthrComp = 97
[09:54:15.778] <TB3> INFO: ROC 12 VthrComp = 85
[09:54:15.778] <TB3> INFO: ROC 13 VthrComp = 97
[09:54:15.778] <TB3> INFO: ROC 14 VthrComp = 99
[09:54:15.778] <TB3> INFO: ROC 15 VthrComp = 81
[09:54:15.778] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:54:15.779] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:54:15.788] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:54:15.788] <TB3> INFO: run 1 of 1
[09:54:16.092] <TB3> INFO: Expecting 6281600 events.
[09:54:50.279] <TB3> INFO: 753620 events read in total (33470ms).
[09:55:22.880] <TB3> INFO: 1505530 events read in total (66071ms).
[09:55:56.170] <TB3> INFO: 2258340 events read in total (99361ms).
[09:56:29.290] <TB3> INFO: 3011650 events read in total (132481ms).
[09:57:02.745] <TB3> INFO: 3759640 events read in total (165936ms).
[09:57:36.479] <TB3> INFO: 4505570 events read in total (199670ms).
[09:58:08.008] <TB3> INFO: 5249310 events read in total (231199ms).
[09:58:42.105] <TB3> INFO: 5993040 events read in total (265296ms).
[09:58:55.239] <TB3> INFO: 6281600 events read in total (278430ms).
[09:58:55.280] <TB3> INFO: Test took 279492ms.
[09:58:55.427] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:18.759] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.7416 for pixel 0/12 mean/min/max = 45.3524/32.7654/57.9394
[09:59:18.760] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 58.0137 for pixel 24/73 mean/min/max = 44.9773/31.8809/58.0737
[09:59:18.760] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 55.0436 for pixel 24/37 mean/min/max = 43.9207/32.7762/55.0651
[09:59:18.760] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.0967 for pixel 0/52 mean/min/max = 44.1955/32.0344/56.3567
[09:59:18.761] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.3629 for pixel 0/4 mean/min/max = 45.6686/31.8774/59.4597
[09:59:18.761] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.9171 for pixel 0/21 mean/min/max = 45.3632/33.7071/57.0193
[09:59:18.761] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.6661 for pixel 0/26 mean/min/max = 44.8707/31.0406/58.7008
[09:59:18.761] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.4572 for pixel 6/1 mean/min/max = 45.3138/32.0171/58.6105
[09:59:18.762] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 64.0317 for pixel 25/6 mean/min/max = 50.0603/35.9966/64.1239
[09:59:18.762] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 65.2634 for pixel 1/75 mean/min/max = 50.056/34.837/65.2751
[09:59:18.762] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 57.3933 for pixel 1/12 mean/min/max = 45.5698/33.6947/57.445
[09:59:18.762] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.5994 for pixel 2/15 mean/min/max = 46.03/32.4451/59.6149
[09:59:18.763] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.816 for pixel 14/74 mean/min/max = 44.903/31.9121/57.8939
[09:59:18.763] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 62.0869 for pixel 9/15 mean/min/max = 46.4084/30.4807/62.3361
[09:59:18.763] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.7853 for pixel 18/12 mean/min/max = 45.782/31.7452/59.8189
[09:59:18.763] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 57.7851 for pixel 2/13 mean/min/max = 45.2745/32.5471/58.0018
[09:59:18.764] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:59:18.896] <TB3> INFO: Expecting 514560 events.
[09:59:31.030] <TB3> INFO: 514560 events read in total (11418ms).
[09:59:31.035] <TB3> INFO: Expecting 514560 events.
[09:59:43.388] <TB3> INFO: 514560 events read in total (11771ms).
[09:59:43.396] <TB3> INFO: Expecting 514560 events.
[09:59:55.684] <TB3> INFO: 514560 events read in total (11717ms).
[09:59:55.693] <TB3> INFO: Expecting 514560 events.
[10:00:07.777] <TB3> INFO: 514560 events read in total (11517ms).
[10:00:07.787] <TB3> INFO: Expecting 514560 events.
[10:00:20.045] <TB3> INFO: 514560 events read in total (11690ms).
[10:00:20.059] <TB3> INFO: Expecting 514560 events.
[10:00:32.022] <TB3> INFO: 514560 events read in total (11393ms).
[10:00:32.037] <TB3> INFO: Expecting 514560 events.
[10:00:43.806] <TB3> INFO: 514560 events read in total (11199ms).
[10:00:43.827] <TB3> INFO: Expecting 514560 events.
[10:00:55.536] <TB3> INFO: 514560 events read in total (11153ms).
[10:00:55.552] <TB3> INFO: Expecting 514560 events.
[10:01:08.189] <TB3> INFO: 514560 events read in total (12061ms).
[10:01:08.208] <TB3> INFO: Expecting 514560 events.
[10:01:20.887] <TB3> INFO: 514560 events read in total (12109ms).
[10:01:20.906] <TB3> INFO: Expecting 514560 events.
[10:01:33.656] <TB3> INFO: 514560 events read in total (12184ms).
[10:01:33.678] <TB3> INFO: Expecting 514560 events.
[10:01:46.173] <TB3> INFO: 514560 events read in total (11933ms).
[10:01:46.198] <TB3> INFO: Expecting 514560 events.
[10:01:58.841] <TB3> INFO: 514560 events read in total (12100ms).
[10:01:58.871] <TB3> INFO: Expecting 514560 events.
[10:02:11.664] <TB3> INFO: 514560 events read in total (12243ms).
[10:02:11.694] <TB3> INFO: Expecting 514560 events.
[10:02:24.134] <TB3> INFO: 514560 events read in total (11898ms).
[10:02:24.163] <TB3> INFO: Expecting 514560 events.
[10:02:36.519] <TB3> INFO: 514560 events read in total (11810ms).
[10:02:36.551] <TB3> INFO: Test took 197787ms.
[10:02:37.601] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:02:37.610] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:02:37.610] <TB3> INFO: run 1 of 1
[10:02:37.938] <TB3> INFO: Expecting 6281600 events.
[10:03:13.711] <TB3> INFO: 741900 events read in total (35056ms).
[10:03:48.610] <TB3> INFO: 1481750 events read in total (69955ms).
[10:04:22.931] <TB3> INFO: 2221580 events read in total (104276ms).
[10:04:57.810] <TB3> INFO: 2960980 events read in total (139155ms).
[10:05:30.796] <TB3> INFO: 3695320 events read in total (172141ms).
[10:06:04.060] <TB3> INFO: 4425660 events read in total (205405ms).
[10:06:35.915] <TB3> INFO: 5154380 events read in total (237260ms).
[10:07:09.448] <TB3> INFO: 5882900 events read in total (270793ms).
[10:07:28.742] <TB3> INFO: 6281600 events read in total (290087ms).
[10:07:28.793] <TB3> INFO: Test took 291183ms.
[10:07:28.956] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:53.011] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.092574 .. 255.000000
[10:07:53.090] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:07:53.099] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:07:53.099] <TB3> INFO: run 1 of 1
[10:07:53.406] <TB3> INFO: Expecting 10649600 events.
[10:08:26.968] <TB3> INFO: 712420 events read in total (32846ms).
[10:08:59.868] <TB3> INFO: 1424760 events read in total (65746ms).
[10:09:32.984] <TB3> INFO: 2137170 events read in total (98862ms).
[10:10:05.698] <TB3> INFO: 2850080 events read in total (131576ms).
[10:10:38.375] <TB3> INFO: 3562910 events read in total (164253ms).
[10:11:12.285] <TB3> INFO: 4275770 events read in total (198163ms).
[10:11:46.010] <TB3> INFO: 4988440 events read in total (231888ms).
[10:12:19.470] <TB3> INFO: 5700750 events read in total (265348ms).
[10:12:52.354] <TB3> INFO: 6412730 events read in total (298232ms).
[10:13:25.068] <TB3> INFO: 7124470 events read in total (330946ms).
[10:13:58.396] <TB3> INFO: 7836030 events read in total (364274ms).
[10:14:32.065] <TB3> INFO: 8546930 events read in total (397943ms).
[10:15:04.523] <TB3> INFO: 9257750 events read in total (430401ms).
[10:15:36.797] <TB3> INFO: 9968560 events read in total (462675ms).
[10:16:09.163] <TB3> INFO: 10649600 events read in total (495041ms).
[10:16:09.272] <TB3> INFO: Test took 496173ms.
[10:16:09.550] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:39.463] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.425625 .. 64.306480
[10:16:39.542] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 74 (-1/-1) hits flags = 528 (plus default)
[10:16:39.551] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:16:39.551] <TB3> INFO: run 1 of 1
[10:16:39.855] <TB3> INFO: Expecting 2953600 events.
[10:17:14.672] <TB3> INFO: 847120 events read in total (34101ms).
[10:17:49.297] <TB3> INFO: 1694590 events read in total (68726ms).
[10:18:23.511] <TB3> INFO: 2540300 events read in total (102940ms).
[10:18:40.562] <TB3> INFO: 2953600 events read in total (119991ms).
[10:18:40.578] <TB3> INFO: Test took 121027ms.
[10:18:40.628] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:59.133] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 1.394016 .. 64.306480
[10:18:59.223] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 74 (-1/-1) hits flags = 528 (plus default)
[10:18:59.234] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:18:59.234] <TB3> INFO: run 1 of 1
[10:18:59.563] <TB3> INFO: Expecting 3078400 events.
[10:19:34.386] <TB3> INFO: 861490 events read in total (34107ms).
[10:20:09.537] <TB3> INFO: 1723370 events read in total (69258ms).
[10:20:45.732] <TB3> INFO: 2584360 events read in total (105453ms).
[10:21:05.733] <TB3> INFO: 3078400 events read in total (125454ms).
[10:21:05.751] <TB3> INFO: Test took 126517ms.
[10:21:05.802] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:21:22.446] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 1.394016 .. 40.965029
[10:21:22.522] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 50 (-1/-1) hits flags = 528 (plus default)
[10:21:22.531] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:21:22.531] <TB3> INFO: run 1 of 1
[10:21:22.834] <TB3> INFO: Expecting 2080000 events.
[10:21:59.994] <TB3> INFO: 1005060 events read in total (36444ms).
[10:22:38.164] <TB3> INFO: 2010120 events read in total (74614ms).
[10:22:41.315] <TB3> INFO: 2080000 events read in total (77766ms).
[10:22:41.327] <TB3> INFO: Test took 78796ms.
[10:22:41.352] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:54.703] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:22:54.703] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:22:54.712] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:22:54.712] <TB3> INFO: run 1 of 1
[10:22:55.019] <TB3> INFO: Expecting 1705600 events.
[10:23:31.291] <TB3> INFO: 878450 events read in total (35556ms).
[10:24:05.845] <TB3> INFO: 1705600 events read in total (70111ms).
[10:24:05.871] <TB3> INFO: Test took 71160ms.
[10:24:05.911] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:20.839] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:24:20.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:24:20.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:24:20.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:24:20.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:24:20.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:24:20.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:24:20.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:24:20.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:24:20.842] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:24:20.842] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:24:20.842] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:24:20.842] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:24:20.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:24:20.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:24:20.843] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:24:20.843] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:24:20.851] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:24:20.857] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:24:20.864] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:24:20.870] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:24:20.877] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:24:20.883] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:24:20.890] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:24:20.896] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:24:20.903] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:24:20.909] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:24:20.916] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:24:20.922] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:24:20.929] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:24:20.935] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:24:20.942] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:24:20.948] <TB3> INFO: PixTestTrim::trimTest() done
[10:24:20.948] <TB3> INFO: vtrim: 89 82 95 91 94 87 82 72 83 101 87 108 98 109 120 94
[10:24:20.948] <TB3> INFO: vthrcomp: 91 85 89 86 93 73 71 55 59 74 72 97 85 97 99 81
[10:24:20.948] <TB3> INFO: vcal mean: 35.01 35.01 35.01 35.00 34.97 35.00 34.97 34.96 34.97 35.02 34.96 34.96 34.99 34.95 34.96 34.99
[10:24:20.948] <TB3> INFO: vcal RMS: 0.81 0.81 0.77 0.75 0.96 0.77 0.88 2.31 1.77 1.24 0.81 0.86 0.82 0.93 0.86 0.80
[10:24:20.948] <TB3> INFO: bits mean: 9.01 9.39 10.02 9.44 9.30 8.86 9.73 9.81 8.98 8.59 9.55 9.57 9.42 9.56 9.97 9.36
[10:24:20.948] <TB3> INFO: bits RMS: 2.83 2.86 2.43 2.82 2.78 2.79 2.80 2.59 2.09 2.38 2.48 2.61 2.75 2.79 2.50 2.72
[10:24:20.956] <TB3> INFO: ----------------------------------------------------------------------
[10:24:20.956] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[10:24:20.956] <TB3> INFO: ----------------------------------------------------------------------
[10:24:20.959] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:24:20.969] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:24:20.969] <TB3> INFO: run 1 of 1
[10:24:21.305] <TB3> INFO: Expecting 8320000 events.
[10:24:56.290] <TB3> INFO: 881140 events read in total (34269ms).
[10:25:30.816] <TB3> INFO: 1755720 events read in total (68795ms).
[10:26:05.585] <TB3> INFO: 2628050 events read in total (103564ms).
[10:26:39.710] <TB3> INFO: 3498880 events read in total (137689ms).
[10:27:13.487] <TB3> INFO: 4366420 events read in total (171466ms).
[10:27:48.410] <TB3> INFO: 5228220 events read in total (206389ms).
[10:28:23.878] <TB3> INFO: 6090750 events read in total (241857ms).
[10:28:59.742] <TB3> INFO: 6950680 events read in total (277721ms).
[10:29:34.867] <TB3> INFO: 7810740 events read in total (312846ms).
[10:29:55.817] <TB3> INFO: 8320000 events read in total (333796ms).
[10:29:55.865] <TB3> INFO: Test took 334895ms.
[10:29:55.995] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:22.899] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[10:30:22.910] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:30:22.910] <TB3> INFO: run 1 of 1
[10:30:23.243] <TB3> INFO: Expecting 6822400 events.
[10:31:00.281] <TB3> INFO: 910420 events read in total (36322ms).
[10:31:35.970] <TB3> INFO: 1813330 events read in total (72011ms).
[10:32:09.268] <TB3> INFO: 2713570 events read in total (105309ms).
[10:32:42.812] <TB3> INFO: 3609380 events read in total (138853ms).
[10:33:16.833] <TB3> INFO: 4498020 events read in total (172874ms).
[10:33:52.830] <TB3> INFO: 5385490 events read in total (208871ms).
[10:34:29.110] <TB3> INFO: 6270910 events read in total (245151ms).
[10:34:51.962] <TB3> INFO: 6822400 events read in total (268003ms).
[10:34:52.002] <TB3> INFO: Test took 269092ms.
[10:34:52.099] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:17.607] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:35:17.616] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:35:17.616] <TB3> INFO: run 1 of 1
[10:35:17.917] <TB3> INFO: Expecting 6364800 events.
[10:35:55.646] <TB3> INFO: 934470 events read in total (37012ms).
[10:36:31.987] <TB3> INFO: 1860770 events read in total (73353ms).
[10:37:07.379] <TB3> INFO: 2783460 events read in total (108746ms).
[10:37:41.460] <TB3> INFO: 3698740 events read in total (142826ms).
[10:38:18.178] <TB3> INFO: 4608610 events read in total (179544ms).
[10:38:52.199] <TB3> INFO: 5516380 events read in total (213565ms).
[10:39:25.956] <TB3> INFO: 6364800 events read in total (247322ms).
[10:39:25.995] <TB3> INFO: Test took 248379ms.
[10:39:26.083] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:48.873] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:39:48.883] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:39:48.883] <TB3> INFO: run 1 of 1
[10:39:49.215] <TB3> INFO: Expecting 6364800 events.
[10:40:26.479] <TB3> INFO: 933670 events read in total (36548ms).
[10:41:02.043] <TB3> INFO: 1859560 events read in total (72112ms).
[10:41:36.679] <TB3> INFO: 2781430 events read in total (106749ms).
[10:42:11.846] <TB3> INFO: 3696000 events read in total (141915ms).
[10:42:48.201] <TB3> INFO: 4605270 events read in total (178270ms).
[10:43:23.779] <TB3> INFO: 5512480 events read in total (213848ms).
[10:43:55.383] <TB3> INFO: 6364800 events read in total (245452ms).
[10:43:55.419] <TB3> INFO: Test took 246536ms.
[10:43:55.496] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:19.543] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[10:44:19.551] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:44:19.551] <TB3> INFO: run 1 of 1
[10:44:19.864] <TB3> INFO: Expecting 6323200 events.
[10:44:57.352] <TB3> INFO: 935620 events read in total (36772ms).
[10:45:33.731] <TB3> INFO: 1863070 events read in total (73152ms).
[10:46:09.939] <TB3> INFO: 2786850 events read in total (109360ms).
[10:46:44.644] <TB3> INFO: 3702390 events read in total (144064ms).
[10:47:19.362] <TB3> INFO: 4613760 events read in total (178782ms).
[10:47:53.551] <TB3> INFO: 5522050 events read in total (212971ms).
[10:48:24.307] <TB3> INFO: 6323200 events read in total (243727ms).
[10:48:24.342] <TB3> INFO: Test took 244791ms.
[10:48:24.432] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:48.294] <TB3> INFO: PixTestTrim::trimBitTest() done
[10:48:48.295] <TB3> INFO: PixTestTrim::doTest() done, duration: 3524 seconds
[10:48:48.295] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:48.295] <TB3> INFO: Decoding statistics:
[10:48:48.295] <TB3> INFO: General information:
[10:48:48.295] <TB3> INFO: 16bit words read: 0
[10:48:48.295] <TB3> INFO: valid events total: 0
[10:48:48.295] <TB3> INFO: empty events: 0
[10:48:48.295] <TB3> INFO: valid events with pixels: 0
[10:48:48.295] <TB3> INFO: valid pixel hits: 0
[10:48:48.295] <TB3> INFO: Event errors: 0
[10:48:48.295] <TB3> INFO: start marker: 0
[10:48:48.295] <TB3> INFO: stop marker: 0
[10:48:48.295] <TB3> INFO: overflow: 0
[10:48:48.295] <TB3> INFO: invalid 5bit words: 0
[10:48:48.295] <TB3> INFO: invalid XOR eye diagram: 0
[10:48:48.295] <TB3> INFO: TBM errors: 0
[10:48:48.295] <TB3> INFO: flawed TBM headers: 0
[10:48:48.295] <TB3> INFO: flawed TBM trailers: 0
[10:48:48.295] <TB3> INFO: event ID mismatches: 0
[10:48:48.295] <TB3> INFO: ROC errors: 0
[10:48:48.295] <TB3> INFO: missing ROC header(s): 0
[10:48:48.295] <TB3> INFO: misplaced readback start: 0
[10:48:48.295] <TB3> INFO: Pixel decoding errors: 0
[10:48:48.295] <TB3> INFO: pixel data incomplete: 0
[10:48:48.295] <TB3> INFO: pixel address: 0
[10:48:48.296] <TB3> INFO: pulse height fill bit: 0
[10:48:48.296] <TB3> INFO: buffer corruption: 0
[10:48:49.054] <TB3> INFO: ######################################################################
[10:48:49.054] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:48:49.054] <TB3> INFO: ######################################################################
[10:48:49.362] <TB3> INFO: Expecting 41600 events.
[10:48:54.044] <TB3> INFO: 41600 events read in total (3966ms).
[10:48:54.045] <TB3> INFO: Test took 4990ms.
[10:48:54.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:54.640] <TB3> INFO: Expecting 41600 events.
[10:48:59.290] <TB3> INFO: 41600 events read in total (3934ms).
[10:48:59.291] <TB3> INFO: Test took 4978ms.
[10:48:59.297] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:59.640] <TB3> INFO: Expecting 41600 events.
[10:49:04.457] <TB3> INFO: 41600 events read in total (4101ms).
[10:49:04.458] <TB3> INFO: Test took 5145ms.
[10:49:04.464] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:04.800] <TB3> INFO: Expecting 2560 events.
[10:49:05.863] <TB3> INFO: 2560 events read in total (346ms).
[10:49:05.863] <TB3> INFO: Test took 1391ms.
[10:49:06.277] <TB3> INFO: Expecting 2560 events.
[10:49:07.339] <TB3> INFO: 2560 events read in total (345ms).
[10:49:07.339] <TB3> INFO: Test took 1476ms.
[10:49:07.754] <TB3> INFO: Expecting 2560 events.
[10:49:08.817] <TB3> INFO: 2560 events read in total (347ms).
[10:49:08.817] <TB3> INFO: Test took 1478ms.
[10:49:09.232] <TB3> INFO: Expecting 2560 events.
[10:49:10.296] <TB3> INFO: 2560 events read in total (348ms).
[10:49:10.296] <TB3> INFO: Test took 1479ms.
[10:49:10.711] <TB3> INFO: Expecting 2560 events.
[10:49:11.779] <TB3> INFO: 2560 events read in total (352ms).
[10:49:11.779] <TB3> INFO: Test took 1483ms.
[10:49:12.194] <TB3> INFO: Expecting 2560 events.
[10:49:13.257] <TB3> INFO: 2560 events read in total (347ms).
[10:49:13.257] <TB3> INFO: Test took 1478ms.
[10:49:13.672] <TB3> INFO: Expecting 2560 events.
[10:49:14.736] <TB3> INFO: 2560 events read in total (348ms).
[10:49:14.736] <TB3> INFO: Test took 1479ms.
[10:49:15.150] <TB3> INFO: Expecting 2560 events.
[10:49:16.218] <TB3> INFO: 2560 events read in total (351ms).
[10:49:16.218] <TB3> INFO: Test took 1482ms.
[10:49:16.633] <TB3> INFO: Expecting 2560 events.
[10:49:17.699] <TB3> INFO: 2560 events read in total (349ms).
[10:49:17.699] <TB3> INFO: Test took 1480ms.
[10:49:18.114] <TB3> INFO: Expecting 2560 events.
[10:49:19.180] <TB3> INFO: 2560 events read in total (350ms).
[10:49:19.180] <TB3> INFO: Test took 1481ms.
[10:49:19.595] <TB3> INFO: Expecting 2560 events.
[10:49:20.665] <TB3> INFO: 2560 events read in total (353ms).
[10:49:20.665] <TB3> INFO: Test took 1485ms.
[10:49:21.079] <TB3> INFO: Expecting 2560 events.
[10:49:22.144] <TB3> INFO: 2560 events read in total (349ms).
[10:49:22.144] <TB3> INFO: Test took 1478ms.
[10:49:22.560] <TB3> INFO: Expecting 2560 events.
[10:49:23.624] <TB3> INFO: 2560 events read in total (348ms).
[10:49:23.624] <TB3> INFO: Test took 1479ms.
[10:49:24.039] <TB3> INFO: Expecting 2560 events.
[10:49:25.102] <TB3> INFO: 2560 events read in total (347ms).
[10:49:25.102] <TB3> INFO: Test took 1478ms.
[10:49:25.517] <TB3> INFO: Expecting 2560 events.
[10:49:26.581] <TB3> INFO: 2560 events read in total (348ms).
[10:49:26.581] <TB3> INFO: Test took 1479ms.
[10:49:26.996] <TB3> INFO: Expecting 2560 events.
[10:49:28.064] <TB3> INFO: 2560 events read in total (351ms).
[10:49:28.064] <TB3> INFO: Test took 1482ms.
[10:49:28.067] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:49:28.481] <TB3> INFO: Expecting 655360 events.
[10:49:44.493] <TB3> INFO: 655360 events read in total (15296ms).
[10:49:44.505] <TB3> INFO: Expecting 655360 events.
[10:50:00.742] <TB3> INFO: 655360 events read in total (15709ms).
[10:50:00.754] <TB3> INFO: Expecting 655360 events.
[10:50:16.720] <TB3> INFO: 655360 events read in total (15439ms).
[10:50:16.735] <TB3> INFO: Expecting 655360 events.
[10:50:32.434] <TB3> INFO: 655360 events read in total (15171ms).
[10:50:32.453] <TB3> INFO: Expecting 655360 events.
[10:50:48.447] <TB3> INFO: 655360 events read in total (15467ms).
[10:50:48.469] <TB3> INFO: Expecting 655360 events.
[10:51:04.621] <TB3> INFO: 655360 events read in total (15624ms).
[10:51:04.650] <TB3> INFO: Expecting 655360 events.
[10:51:20.500] <TB3> INFO: 655360 events read in total (15323ms).
[10:51:20.542] <TB3> INFO: Expecting 655360 events.
[10:51:35.854] <TB3> INFO: 655360 events read in total (14784ms).
[10:51:35.901] <TB3> INFO: Expecting 655360 events.
[10:51:51.956] <TB3> INFO: 655360 events read in total (15528ms).
[10:51:51.996] <TB3> INFO: Expecting 655360 events.
[10:52:07.988] <TB3> INFO: 655360 events read in total (15464ms).
[10:52:08.027] <TB3> INFO: Expecting 655360 events.
[10:52:24.299] <TB3> INFO: 655360 events read in total (15744ms).
[10:52:24.351] <TB3> INFO: Expecting 655360 events.
[10:52:40.808] <TB3> INFO: 655360 events read in total (15930ms).
[10:52:40.861] <TB3> INFO: Expecting 655360 events.
[10:52:57.092] <TB3> INFO: 655360 events read in total (15703ms).
[10:52:57.153] <TB3> INFO: Expecting 655360 events.
[10:53:13.491] <TB3> INFO: 655360 events read in total (15810ms).
[10:53:13.545] <TB3> INFO: Expecting 655360 events.
[10:53:29.594] <TB3> INFO: 655360 events read in total (15521ms).
[10:53:29.662] <TB3> INFO: Expecting 655360 events.
[10:53:45.830] <TB3> INFO: 655360 events read in total (15640ms).
[10:53:45.898] <TB3> INFO: Test took 257831ms.
[10:53:45.986] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:46.295] <TB3> INFO: Expecting 655360 events.
[10:54:02.455] <TB3> INFO: 655360 events read in total (15443ms).
[10:54:02.464] <TB3> INFO: Expecting 655360 events.
[10:54:17.695] <TB3> INFO: 655360 events read in total (14703ms).
[10:54:17.709] <TB3> INFO: Expecting 655360 events.
[10:54:32.950] <TB3> INFO: 655360 events read in total (14714ms).
[10:54:32.966] <TB3> INFO: Expecting 655360 events.
[10:54:49.225] <TB3> INFO: 655360 events read in total (15732ms).
[10:54:49.244] <TB3> INFO: Expecting 655360 events.
[10:55:05.707] <TB3> INFO: 655360 events read in total (15935ms).
[10:55:05.729] <TB3> INFO: Expecting 655360 events.
[10:55:22.325] <TB3> INFO: 655360 events read in total (16068ms).
[10:55:22.350] <TB3> INFO: Expecting 655360 events.
[10:55:38.739] <TB3> INFO: 655360 events read in total (15861ms).
[10:55:38.771] <TB3> INFO: Expecting 655360 events.
[10:55:55.050] <TB3> INFO: 655360 events read in total (15751ms).
[10:55:55.089] <TB3> INFO: Expecting 655360 events.
[10:56:11.240] <TB3> INFO: 655360 events read in total (15624ms).
[10:56:11.276] <TB3> INFO: Expecting 655360 events.
[10:56:27.693] <TB3> INFO: 655360 events read in total (15890ms).
[10:56:27.732] <TB3> INFO: Expecting 655360 events.
[10:56:44.150] <TB3> INFO: 655360 events read in total (15891ms).
[10:56:44.195] <TB3> INFO: Expecting 655360 events.
[10:57:00.478] <TB3> INFO: 655360 events read in total (15755ms).
[10:57:00.523] <TB3> INFO: Expecting 655360 events.
[10:57:16.849] <TB3> INFO: 655360 events read in total (15798ms).
[10:57:16.898] <TB3> INFO: Expecting 655360 events.
[10:57:33.322] <TB3> INFO: 655360 events read in total (15897ms).
[10:57:33.374] <TB3> INFO: Expecting 655360 events.
[10:57:49.778] <TB3> INFO: 655360 events read in total (15876ms).
[10:57:49.833] <TB3> INFO: Expecting 655360 events.
[10:58:06.259] <TB3> INFO: 655360 events read in total (15898ms).
[10:58:06.322] <TB3> INFO: Test took 260336ms.
[10:58:06.518] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.525] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.532] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.539] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.546] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.553] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.560] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.567] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.574] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.581] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.588] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.595] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[10:58:06.602] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[10:58:06.609] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[10:58:06.616] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.623] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.630] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.637] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.644] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:58:06.678] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:58:06.679] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:58:06.990] <TB3> INFO: Expecting 41600 events.
[10:58:11.463] <TB3> INFO: 41600 events read in total (3756ms).
[10:58:11.463] <TB3> INFO: Test took 4781ms.
[10:58:12.001] <TB3> INFO: Expecting 41600 events.
[10:58:16.502] <TB3> INFO: 41600 events read in total (3784ms).
[10:58:16.503] <TB3> INFO: Test took 4815ms.
[10:58:17.055] <TB3> INFO: Expecting 41600 events.
[10:58:21.545] <TB3> INFO: 41600 events read in total (3774ms).
[10:58:21.546] <TB3> INFO: Test took 4811ms.
[10:58:21.782] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:21.913] <TB3> INFO: Expecting 2560 events.
[10:58:22.983] <TB3> INFO: 2560 events read in total (354ms).
[10:58:22.984] <TB3> INFO: Test took 1202ms.
[10:58:22.986] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:23.399] <TB3> INFO: Expecting 2560 events.
[10:58:24.467] <TB3> INFO: 2560 events read in total (351ms).
[10:58:24.468] <TB3> INFO: Test took 1482ms.
[10:58:24.470] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:24.883] <TB3> INFO: Expecting 2560 events.
[10:58:25.953] <TB3> INFO: 2560 events read in total (353ms).
[10:58:25.953] <TB3> INFO: Test took 1483ms.
[10:58:25.956] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:26.369] <TB3> INFO: Expecting 2560 events.
[10:58:27.439] <TB3> INFO: 2560 events read in total (354ms).
[10:58:27.439] <TB3> INFO: Test took 1483ms.
[10:58:27.442] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:27.855] <TB3> INFO: Expecting 2560 events.
[10:58:28.923] <TB3> INFO: 2560 events read in total (352ms).
[10:58:28.923] <TB3> INFO: Test took 1481ms.
[10:58:28.926] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:29.340] <TB3> INFO: Expecting 2560 events.
[10:58:30.411] <TB3> INFO: 2560 events read in total (355ms).
[10:58:30.412] <TB3> INFO: Test took 1486ms.
[10:58:30.414] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:30.827] <TB3> INFO: Expecting 2560 events.
[10:58:31.896] <TB3> INFO: 2560 events read in total (352ms).
[10:58:31.896] <TB3> INFO: Test took 1482ms.
[10:58:31.899] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:32.312] <TB3> INFO: Expecting 2560 events.
[10:58:33.381] <TB3> INFO: 2560 events read in total (353ms).
[10:58:33.381] <TB3> INFO: Test took 1482ms.
[10:58:33.384] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:33.797] <TB3> INFO: Expecting 2560 events.
[10:58:34.868] <TB3> INFO: 2560 events read in total (355ms).
[10:58:34.868] <TB3> INFO: Test took 1484ms.
[10:58:34.871] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:35.284] <TB3> INFO: Expecting 2560 events.
[10:58:36.355] <TB3> INFO: 2560 events read in total (355ms).
[10:58:36.355] <TB3> INFO: Test took 1484ms.
[10:58:36.357] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:36.771] <TB3> INFO: Expecting 2560 events.
[10:58:37.839] <TB3> INFO: 2560 events read in total (352ms).
[10:58:37.839] <TB3> INFO: Test took 1482ms.
[10:58:37.842] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:38.255] <TB3> INFO: Expecting 2560 events.
[10:58:39.324] <TB3> INFO: 2560 events read in total (353ms).
[10:58:39.324] <TB3> INFO: Test took 1483ms.
[10:58:39.327] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:39.740] <TB3> INFO: Expecting 2560 events.
[10:58:40.809] <TB3> INFO: 2560 events read in total (353ms).
[10:58:40.810] <TB3> INFO: Test took 1483ms.
[10:58:40.812] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:41.225] <TB3> INFO: Expecting 2560 events.
[10:58:42.295] <TB3> INFO: 2560 events read in total (353ms).
[10:58:42.295] <TB3> INFO: Test took 1483ms.
[10:58:42.297] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:42.711] <TB3> INFO: Expecting 2560 events.
[10:58:43.784] <TB3> INFO: 2560 events read in total (357ms).
[10:58:43.784] <TB3> INFO: Test took 1487ms.
[10:58:43.787] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:44.200] <TB3> INFO: Expecting 2560 events.
[10:58:45.265] <TB3> INFO: 2560 events read in total (349ms).
[10:58:45.265] <TB3> INFO: Test took 1478ms.
[10:58:45.268] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:45.681] <TB3> INFO: Expecting 2560 events.
[10:58:46.749] <TB3> INFO: 2560 events read in total (351ms).
[10:58:46.750] <TB3> INFO: Test took 1482ms.
[10:58:46.753] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:47.166] <TB3> INFO: Expecting 2560 events.
[10:58:48.235] <TB3> INFO: 2560 events read in total (353ms).
[10:58:48.235] <TB3> INFO: Test took 1482ms.
[10:58:48.238] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:48.651] <TB3> INFO: Expecting 2560 events.
[10:58:49.714] <TB3> INFO: 2560 events read in total (347ms).
[10:58:49.714] <TB3> INFO: Test took 1476ms.
[10:58:49.717] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:50.130] <TB3> INFO: Expecting 2560 events.
[10:58:51.199] <TB3> INFO: 2560 events read in total (353ms).
[10:58:51.199] <TB3> INFO: Test took 1483ms.
[10:58:51.202] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:51.615] <TB3> INFO: Expecting 2560 events.
[10:58:52.687] <TB3> INFO: 2560 events read in total (356ms).
[10:58:52.687] <TB3> INFO: Test took 1485ms.
[10:58:52.691] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:53.103] <TB3> INFO: Expecting 2560 events.
[10:58:54.176] <TB3> INFO: 2560 events read in total (357ms).
[10:58:54.176] <TB3> INFO: Test took 1486ms.
[10:58:54.178] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:54.593] <TB3> INFO: Expecting 2560 events.
[10:58:55.662] <TB3> INFO: 2560 events read in total (354ms).
[10:58:55.663] <TB3> INFO: Test took 1485ms.
[10:58:55.666] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:56.079] <TB3> INFO: Expecting 2560 events.
[10:58:57.148] <TB3> INFO: 2560 events read in total (353ms).
[10:58:57.148] <TB3> INFO: Test took 1482ms.
[10:58:57.151] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:57.565] <TB3> INFO: Expecting 2560 events.
[10:58:58.634] <TB3> INFO: 2560 events read in total (353ms).
[10:58:58.634] <TB3> INFO: Test took 1483ms.
[10:58:58.636] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:58:59.050] <TB3> INFO: Expecting 2560 events.
[10:59:00.112] <TB3> INFO: 2560 events read in total (346ms).
[10:59:00.112] <TB3> INFO: Test took 1477ms.
[10:59:00.114] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:00.528] <TB3> INFO: Expecting 2560 events.
[10:59:01.590] <TB3> INFO: 2560 events read in total (346ms).
[10:59:01.591] <TB3> INFO: Test took 1477ms.
[10:59:01.593] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:02.006] <TB3> INFO: Expecting 2560 events.
[10:59:03.068] <TB3> INFO: 2560 events read in total (346ms).
[10:59:03.068] <TB3> INFO: Test took 1475ms.
[10:59:03.070] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:03.484] <TB3> INFO: Expecting 2560 events.
[10:59:04.545] <TB3> INFO: 2560 events read in total (345ms).
[10:59:04.546] <TB3> INFO: Test took 1476ms.
[10:59:04.548] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:04.961] <TB3> INFO: Expecting 2560 events.
[10:59:06.023] <TB3> INFO: 2560 events read in total (346ms).
[10:59:06.024] <TB3> INFO: Test took 1477ms.
[10:59:06.025] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:06.439] <TB3> INFO: Expecting 2560 events.
[10:59:07.501] <TB3> INFO: 2560 events read in total (346ms).
[10:59:07.501] <TB3> INFO: Test took 1476ms.
[10:59:07.503] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:07.916] <TB3> INFO: Expecting 2560 events.
[10:59:08.978] <TB3> INFO: 2560 events read in total (345ms).
[10:59:08.978] <TB3> INFO: Test took 1475ms.
[10:59:09.587] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[10:59:09.587] <TB3> INFO: PH scale (per ROC): 77 81 85 87 80 81 85 77 77 77 80 92 83 75 91 84
[10:59:09.587] <TB3> INFO: PH offset (per ROC): 164 151 154 150 155 148 165 162 155 153 153 165 146 161 156 146
[10:59:09.591] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:09.591] <TB3> INFO: Decoding statistics:
[10:59:09.591] <TB3> INFO: General information:
[10:59:09.591] <TB3> INFO: 16bit words read: 91916
[10:59:09.591] <TB3> INFO: valid events total: 10240
[10:59:09.591] <TB3> INFO: empty events: 7741
[10:59:09.591] <TB3> INFO: valid events with pixels: 2499
[10:59:09.591] <TB3> INFO: valid pixel hits: 2499
[10:59:09.591] <TB3> INFO: Event errors: 0
[10:59:09.591] <TB3> INFO: start marker: 0
[10:59:09.591] <TB3> INFO: stop marker: 0
[10:59:09.591] <TB3> INFO: overflow: 0
[10:59:09.591] <TB3> INFO: invalid 5bit words: 0
[10:59:09.591] <TB3> INFO: invalid XOR eye diagram: 0
[10:59:09.591] <TB3> INFO: TBM errors: 0
[10:59:09.591] <TB3> INFO: flawed TBM headers: 0
[10:59:09.591] <TB3> INFO: flawed TBM trailers: 0
[10:59:09.591] <TB3> INFO: event ID mismatches: 0
[10:59:09.591] <TB3> INFO: ROC errors: 0
[10:59:09.591] <TB3> INFO: missing ROC header(s): 0
[10:59:09.591] <TB3> INFO: misplaced readback start: 0
[10:59:09.591] <TB3> INFO: Pixel decoding errors: 0
[10:59:09.591] <TB3> INFO: pixel data incomplete: 0
[10:59:09.591] <TB3> INFO: pixel address: 0
[10:59:09.591] <TB3> INFO: pulse height fill bit: 0
[10:59:09.591] <TB3> INFO: buffer corruption: 0
[10:59:09.765] <TB3> INFO: ######################################################################
[10:59:09.765] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:59:09.765] <TB3> INFO: ######################################################################
[10:59:09.776] <TB3> INFO: scanning low vcal = 10
[10:59:10.101] <TB3> INFO: Expecting 41600 events.
[10:59:13.685] <TB3> INFO: 41600 events read in total (2868ms).
[10:59:13.685] <TB3> INFO: Test took 3909ms.
[10:59:13.686] <TB3> INFO: scanning low vcal = 20
[10:59:14.100] <TB3> INFO: Expecting 41600 events.
[10:59:17.628] <TB3> INFO: 41600 events read in total (2812ms).
[10:59:17.629] <TB3> INFO: Test took 3943ms.
[10:59:17.630] <TB3> INFO: scanning low vcal = 30
[10:59:18.044] <TB3> INFO: Expecting 41600 events.
[10:59:21.610] <TB3> INFO: 41600 events read in total (2850ms).
[10:59:21.611] <TB3> INFO: Test took 3981ms.
[10:59:21.612] <TB3> INFO: scanning low vcal = 40
[10:59:22.020] <TB3> INFO: Expecting 41600 events.
[10:59:26.022] <TB3> INFO: 41600 events read in total (3286ms).
[10:59:26.023] <TB3> INFO: Test took 4410ms.
[10:59:26.026] <TB3> INFO: scanning low vcal = 50
[10:59:26.380] <TB3> INFO: Expecting 41600 events.
[10:59:30.398] <TB3> INFO: 41600 events read in total (3301ms).
[10:59:30.398] <TB3> INFO: Test took 4372ms.
[10:59:30.401] <TB3> INFO: scanning low vcal = 60
[10:59:30.756] <TB3> INFO: Expecting 41600 events.
[10:59:35.001] <TB3> INFO: 41600 events read in total (3529ms).
[10:59:35.002] <TB3> INFO: Test took 4601ms.
[10:59:35.004] <TB3> INFO: scanning low vcal = 70
[10:59:35.354] <TB3> INFO: Expecting 41600 events.
[10:59:39.534] <TB3> INFO: 41600 events read in total (3464ms).
[10:59:39.535] <TB3> INFO: Test took 4530ms.
[10:59:39.537] <TB3> INFO: scanning low vcal = 80
[10:59:39.888] <TB3> INFO: Expecting 41600 events.
[10:59:44.157] <TB3> INFO: 41600 events read in total (3552ms).
[10:59:44.157] <TB3> INFO: Test took 4620ms.
[10:59:44.160] <TB3> INFO: scanning low vcal = 90
[10:59:44.511] <TB3> INFO: Expecting 41600 events.
[10:59:48.815] <TB3> INFO: 41600 events read in total (3588ms).
[10:59:48.815] <TB3> INFO: Test took 4655ms.
[10:59:48.819] <TB3> INFO: scanning low vcal = 100
[10:59:49.169] <TB3> INFO: Expecting 41600 events.
[10:59:53.390] <TB3> INFO: 41600 events read in total (3505ms).
[10:59:53.390] <TB3> INFO: Test took 4571ms.
[10:59:53.393] <TB3> INFO: scanning low vcal = 110
[10:59:53.739] <TB3> INFO: Expecting 41600 events.
[10:59:57.954] <TB3> INFO: 41600 events read in total (3499ms).
[10:59:57.955] <TB3> INFO: Test took 4562ms.
[10:59:57.957] <TB3> INFO: scanning low vcal = 120
[10:59:58.307] <TB3> INFO: Expecting 41600 events.
[11:00:02.488] <TB3> INFO: 41600 events read in total (3465ms).
[11:00:02.488] <TB3> INFO: Test took 4530ms.
[11:00:02.491] <TB3> INFO: scanning low vcal = 130
[11:00:02.843] <TB3> INFO: Expecting 41600 events.
[11:00:07.043] <TB3> INFO: 41600 events read in total (3483ms).
[11:00:07.043] <TB3> INFO: Test took 4552ms.
[11:00:07.046] <TB3> INFO: scanning low vcal = 140
[11:00:07.391] <TB3> INFO: Expecting 41600 events.
[11:00:11.639] <TB3> INFO: 41600 events read in total (3532ms).
[11:00:11.639] <TB3> INFO: Test took 4593ms.
[11:00:11.642] <TB3> INFO: scanning low vcal = 150
[11:00:11.996] <TB3> INFO: Expecting 41600 events.
[11:00:16.186] <TB3> INFO: 41600 events read in total (3474ms).
[11:00:16.187] <TB3> INFO: Test took 4545ms.
[11:00:16.190] <TB3> INFO: scanning low vcal = 160
[11:00:16.539] <TB3> INFO: Expecting 41600 events.
[11:00:20.764] <TB3> INFO: 41600 events read in total (3508ms).
[11:00:20.764] <TB3> INFO: Test took 4574ms.
[11:00:20.767] <TB3> INFO: scanning low vcal = 170
[11:00:21.115] <TB3> INFO: Expecting 41600 events.
[11:00:25.287] <TB3> INFO: 41600 events read in total (3456ms).
[11:00:25.288] <TB3> INFO: Test took 4521ms.
[11:00:25.293] <TB3> INFO: scanning low vcal = 180
[11:00:25.638] <TB3> INFO: Expecting 41600 events.
[11:00:29.921] <TB3> INFO: 41600 events read in total (3566ms).
[11:00:29.922] <TB3> INFO: Test took 4629ms.
[11:00:29.924] <TB3> INFO: scanning low vcal = 190
[11:00:30.266] <TB3> INFO: Expecting 41600 events.
[11:00:34.502] <TB3> INFO: 41600 events read in total (3520ms).
[11:00:34.503] <TB3> INFO: Test took 4579ms.
[11:00:34.505] <TB3> INFO: scanning low vcal = 200
[11:00:34.852] <TB3> INFO: Expecting 41600 events.
[11:00:39.101] <TB3> INFO: 41600 events read in total (3532ms).
[11:00:39.102] <TB3> INFO: Test took 4597ms.
[11:00:39.105] <TB3> INFO: scanning low vcal = 210
[11:00:39.456] <TB3> INFO: Expecting 41600 events.
[11:00:43.645] <TB3> INFO: 41600 events read in total (3472ms).
[11:00:43.646] <TB3> INFO: Test took 4541ms.
[11:00:43.649] <TB3> INFO: scanning low vcal = 220
[11:00:44.002] <TB3> INFO: Expecting 41600 events.
[11:00:48.212] <TB3> INFO: 41600 events read in total (3494ms).
[11:00:48.212] <TB3> INFO: Test took 4563ms.
[11:00:48.215] <TB3> INFO: scanning low vcal = 230
[11:00:48.570] <TB3> INFO: Expecting 41600 events.
[11:00:52.782] <TB3> INFO: 41600 events read in total (3496ms).
[11:00:52.783] <TB3> INFO: Test took 4568ms.
[11:00:52.786] <TB3> INFO: scanning low vcal = 240
[11:00:53.120] <TB3> INFO: Expecting 41600 events.
[11:00:57.350] <TB3> INFO: 41600 events read in total (3513ms).
[11:00:57.351] <TB3> INFO: Test took 4565ms.
[11:00:57.354] <TB3> INFO: scanning low vcal = 250
[11:00:57.701] <TB3> INFO: Expecting 41600 events.
[11:01:01.937] <TB3> INFO: 41600 events read in total (3520ms).
[11:01:01.938] <TB3> INFO: Test took 4584ms.
[11:01:01.942] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:01:02.286] <TB3> INFO: Expecting 41600 events.
[11:01:06.520] <TB3> INFO: 41600 events read in total (3517ms).
[11:01:06.521] <TB3> INFO: Test took 4579ms.
[11:01:06.523] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:01:06.874] <TB3> INFO: Expecting 41600 events.
[11:01:11.127] <TB3> INFO: 41600 events read in total (3536ms).
[11:01:11.127] <TB3> INFO: Test took 4604ms.
[11:01:11.131] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:01:11.477] <TB3> INFO: Expecting 41600 events.
[11:01:15.680] <TB3> INFO: 41600 events read in total (3486ms).
[11:01:15.680] <TB3> INFO: Test took 4549ms.
[11:01:15.683] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:01:16.028] <TB3> INFO: Expecting 41600 events.
[11:01:20.366] <TB3> INFO: 41600 events read in total (3622ms).
[11:01:20.366] <TB3> INFO: Test took 4683ms.
[11:01:20.369] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:01:20.725] <TB3> INFO: Expecting 41600 events.
[11:01:24.979] <TB3> INFO: 41600 events read in total (3537ms).
[11:01:24.979] <TB3> INFO: Test took 4610ms.
[11:01:25.541] <TB3> INFO: PixTestGainPedestal::measure() done
[11:01:59.665] <TB3> INFO: PixTestGainPedestal::fit() done
[11:01:59.665] <TB3> INFO: non-linearity mean: 0.961 0.960 0.956 0.957 0.961 0.947 0.964 0.947 0.950 0.957 0.959 0.956 0.958 0.955 0.962 0.953
[11:01:59.665] <TB3> INFO: non-linearity RMS: 0.004 0.005 0.005 0.006 0.005 0.006 0.005 0.008 0.009 0.009 0.006 0.007 0.005 0.005 0.005 0.005
[11:01:59.665] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:01:59.697] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:01:59.722] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:01:59.749] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:01:59.769] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:01:59.793] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:01:59.819] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:01:59.838] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:01:59.861] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:01:59.886] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:01:59.909] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:01:59.927] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:01:59.945] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:01:59.963] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:01:59.981] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:02:00.000] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:02:00.018] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 170 seconds
[11:02:00.018] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:00.018] <TB3> INFO: Decoding statistics:
[11:02:00.018] <TB3> INFO: General information:
[11:02:00.018] <TB3> INFO: 16bit words read: 2662400
[11:02:00.018] <TB3> INFO: valid events total: 166400
[11:02:00.018] <TB3> INFO: empty events: 0
[11:02:00.018] <TB3> INFO: valid events with pixels: 166400
[11:02:00.018] <TB3> INFO: valid pixel hits: 665379
[11:02:00.018] <TB3> INFO: Event errors: 0
[11:02:00.018] <TB3> INFO: start marker: 0
[11:02:00.018] <TB3> INFO: stop marker: 0
[11:02:00.018] <TB3> INFO: overflow: 0
[11:02:00.018] <TB3> INFO: invalid 5bit words: 0
[11:02:00.018] <TB3> INFO: invalid XOR eye diagram: 0
[11:02:00.018] <TB3> INFO: TBM errors: 0
[11:02:00.018] <TB3> INFO: flawed TBM headers: 0
[11:02:00.018] <TB3> INFO: flawed TBM trailers: 0
[11:02:00.018] <TB3> INFO: event ID mismatches: 0
[11:02:00.018] <TB3> INFO: ROC errors: 0
[11:02:00.018] <TB3> INFO: missing ROC header(s): 0
[11:02:00.018] <TB3> INFO: misplaced readback start: 0
[11:02:00.018] <TB3> INFO: Pixel decoding errors: 0
[11:02:00.018] <TB3> INFO: pixel data incomplete: 0
[11:02:00.018] <TB3> INFO: pixel address: 0
[11:02:00.018] <TB3> INFO: pulse height fill bit: 0
[11:02:00.018] <TB3> INFO: buffer corruption: 0
[11:02:00.024] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:02:00.031] <TB3> INFO: ######################################################################
[11:02:00.031] <TB3> INFO: PixTestTrim::doTest()
[11:02:00.031] <TB3> INFO: ######################################################################
[11:02:00.032] <TB3> INFO: PixTestReadback::RES sent once
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[11:02:21.829] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[11:02:21.830] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:02:21.864] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:02:21.865] <TB3> INFO: PixTestReadback::RES sent once
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[11:02:43.587] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[11:02:43.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:02:43.625] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:02:43.625] <TB3> INFO: PixTestReadback::RES sent once
[11:03:00.506] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:03:00.506] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.6calibrated Vbg = 1.21569 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.9calibrated Vbg = 1.18917 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 145.5calibrated Vbg = 1.24096 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.1calibrated Vbg = 1.34589 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.2calibrated Vbg = 1.37034 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.5calibrated Vbg = 1.3741 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 201.3calibrated Vbg = 1.26292 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.4calibrated Vbg = 1.35435 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.8calibrated Vbg = 1.33162 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 140.5calibrated Vbg = 1.30144 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150calibrated Vbg = 1.19668 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.1calibrated Vbg = 1.19374 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.8calibrated Vbg = 1.22827 :::*/*/*/*/
[11:03:00.506] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[11:03:00.510] <TB3> INFO: PixTestReadback::RES sent once










































[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C0.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C1.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C2.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C3.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C4.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C5.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C6.dat
[11:07:42.358] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C7.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C8.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C9.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C10.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C11.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C12.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C13.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C14.dat
[11:07:42.359] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//000_FulltestPxar_m20//readbackCal_C15.dat
[11:07:42.393] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:07:42.395] <TB3> INFO: PixTestReadback::doTest() done
[11:07:42.395] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:42.395] <TB3> INFO: Decoding statistics:
[11:07:42.395] <TB3> INFO: General information:
[11:07:42.395] <TB3> INFO: 16bit words read: 1024
[11:07:42.395] <TB3> INFO: valid events total: 128
[11:07:42.395] <TB3> INFO: empty events: 128
[11:07:42.395] <TB3> INFO: valid events with pixels: 0
[11:07:42.395] <TB3> INFO: valid pixel hits: 0
[11:07:42.395] <TB3> INFO: Event errors: 0
[11:07:42.395] <TB3> INFO: start marker: 0
[11:07:42.395] <TB3> INFO: stop marker: 0
[11:07:42.396] <TB3> INFO: overflow: 0
[11:07:42.396] <TB3> INFO: invalid 5bit words: 0
[11:07:42.396] <TB3> INFO: invalid XOR eye diagram: 0
[11:07:42.396] <TB3> INFO: TBM errors: 0
[11:07:42.396] <TB3> INFO: flawed TBM headers: 0
[11:07:42.396] <TB3> INFO: flawed TBM trailers: 0
[11:07:42.396] <TB3> INFO: event ID mismatches: 0
[11:07:42.396] <TB3> INFO: ROC errors: 0
[11:07:42.396] <TB3> INFO: missing ROC header(s): 0
[11:07:42.396] <TB3> INFO: misplaced readback start: 0
[11:07:42.396] <TB3> INFO: Pixel decoding errors: 0
[11:07:42.396] <TB3> INFO: pixel data incomplete: 0
[11:07:42.396] <TB3> INFO: pixel address: 0
[11:07:42.396] <TB3> INFO: pulse height fill bit: 0
[11:07:42.396] <TB3> INFO: buffer corruption: 0
[11:07:42.411] <TB3> INFO: Decoding statistics:
[11:07:42.411] <TB3> INFO: General information:
[11:07:42.411] <TB3> INFO: 16bit words read: 2755340
[11:07:42.411] <TB3> INFO: valid events total: 176768
[11:07:42.411] <TB3> INFO: empty events: 7869
[11:07:42.411] <TB3> INFO: valid events with pixels: 168899
[11:07:42.411] <TB3> INFO: valid pixel hits: 667878
[11:07:42.411] <TB3> INFO: Event errors: 0
[11:07:42.411] <TB3> INFO: start marker: 0
[11:07:42.411] <TB3> INFO: stop marker: 0
[11:07:42.411] <TB3> INFO: overflow: 0
[11:07:42.411] <TB3> INFO: invalid 5bit words: 0
[11:07:42.412] <TB3> INFO: invalid XOR eye diagram: 0
[11:07:42.412] <TB3> INFO: TBM errors: 0
[11:07:42.412] <TB3> INFO: flawed TBM headers: 0
[11:07:42.412] <TB3> INFO: flawed TBM trailers: 0
[11:07:42.412] <TB3> INFO: event ID mismatches: 0
[11:07:42.412] <TB3> INFO: ROC errors: 0
[11:07:42.412] <TB3> INFO: missing ROC header(s): 0
[11:07:42.412] <TB3> INFO: misplaced readback start: 0
[11:07:42.412] <TB3> INFO: Pixel decoding errors: 0
[11:07:42.412] <TB3> INFO: pixel data incomplete: 0
[11:07:42.412] <TB3> INFO: pixel address: 0
[11:07:42.412] <TB3> INFO: pulse height fill bit: 0
[11:07:42.412] <TB3> INFO: buffer corruption: 0
[11:07:42.412] <TB3> INFO: enter test to run
[11:07:42.412] <TB3> INFO: test: exit no parameter change
[11:07:42.950] <TB3> QUIET: Connection to board 170 closed.
[11:07:43.030] <TB3> INFO: pXar: this is the end, my friend