[10:56:43.536] INFO: *** Welcome to pxar ***
[10:56:43.536] INFO: *** Today: 2016/04/13
[10:56:43.551] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:56:43.551] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//dacParameters35_C15.dat
[10:56:43.552] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:56:43.552] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//defaultMaskFile.dat
[10:56:43.552] INFO: MASKED Roc 0 col/row: 0 1
[10:56:43.552] INFO: MASKED Roc 0 col/row: 1 2
[10:56:43.552] INFO: MASKED Roc 0 col/row: 4 32
[10:56:43.552] INFO: MASKED Roc 0 col/row: 5 34
[10:56:43.552] INFO: MASKED Roc 0 col/row: 11 34
[10:56:43.552] INFO: MASKED Roc 0 col/row: 12 32
[10:56:43.552] INFO: MASKED Roc 0 col/row: 12 36
[10:56:43.552] INFO: MASKED Roc 2 col/row: 26 17
[10:56:43.552] INFO: MASKED Roc 2 col/row: 29 47
[10:56:43.552] INFO: MASKED Roc 2 col/row: 32 51
[10:56:43.552] INFO: MASKED Roc 2 col/row: 38 43
[10:56:43.552] INFO: MASKED Roc 2 col/row: 39 32
[10:56:43.552] INFO: MASKED Roc 2 col/row: 39 49
[10:56:43.552] INFO: MASKED Roc 2 col/row: 39 53
[10:56:43.552] INFO: MASKED Roc 2 col/row: 42 53
[10:56:43.552] INFO: MASKED Roc 3 col/row: 1 51
[10:56:43.552] INFO: MASKED Roc 3 col/row: 12 42
[10:56:43.552] INFO: MASKED Roc 3 col/row: 33 23
[10:56:43.552] INFO: MASKED Roc 4 col/row: 28 42
[10:56:43.552] INFO: MASKED Roc 4 col/row: 49 24
[10:56:43.552] INFO: MASKED Roc 5 col/row: 5 59
[10:56:43.552] INFO: MASKED Roc 5 col/row: 15 57
[10:56:43.552] INFO: MASKED Roc 5 col/row: 19 42
[10:56:43.552] INFO: MASKED Roc 5 col/row: 33 55
[10:56:43.552] INFO: MASKED Roc 5 col/row: 47 42
[10:56:43.553] INFO: MASKED Roc 6 col/row: 20 53
[10:56:43.553] INFO: MASKED Roc 6 col/row: 20 57
[10:56:43.553] INFO: MASKED Roc 6 col/row: 21 51
[10:56:43.553] INFO: MASKED Roc 6 col/row: 25 57
[10:56:43.553] INFO: MASKED Roc 6 col/row: 27 59
[10:56:43.553] INFO: MASKED Roc 6 col/row: 30 55
[10:56:43.553] INFO: MASKED Roc 6 col/row: 31 48
[10:56:43.553] INFO: MASKED Roc 6 col/row: 32 51
[10:56:43.553] INFO: MASKED Roc 6 col/row: 35 47
[10:56:43.553] INFO: MASKED Roc 6 col/row: 45 57
[10:56:43.553] INFO: MASKED Roc 6 col/row: 49 51
[10:56:43.553] INFO: MASKED Roc 8 col/row: 43 53
[10:56:43.553] INFO: MASKED Roc 8 col/row: 43 54
[10:56:43.553] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C15.dat
[10:56:43.553] INFO: masking Roc 0 col/row: 0 1
[10:56:43.553] INFO: masking Roc 0 col/row: 1 2
[10:56:43.553] INFO: masking Roc 0 col/row: 4 32
[10:56:43.553] INFO: masking Roc 0 col/row: 5 34
[10:56:43.553] INFO: masking Roc 0 col/row: 11 34
[10:56:43.553] INFO: masking Roc 0 col/row: 12 32
[10:56:43.553] INFO: masking Roc 0 col/row: 12 36
[10:56:43.569] INFO: masking Roc 2 col/row: 26 17
[10:56:43.569] INFO: masking Roc 2 col/row: 29 47
[10:56:43.569] INFO: masking Roc 2 col/row: 32 51
[10:56:43.569] INFO: masking Roc 2 col/row: 38 43
[10:56:43.569] INFO: masking Roc 2 col/row: 39 32
[10:56:43.569] INFO: masking Roc 2 col/row: 39 49
[10:56:43.569] INFO: masking Roc 2 col/row: 39 53
[10:56:43.569] INFO: masking Roc 2 col/row: 42 53
[10:56:43.573] INFO: masking Roc 3 col/row: 1 51
[10:56:43.573] INFO: masking Roc 3 col/row: 12 42
[10:56:43.573] INFO: masking Roc 3 col/row: 33 23
[10:56:43.578] INFO: masking Roc 4 col/row: 28 42
[10:56:43.578] INFO: masking Roc 4 col/row: 49 24
[10:56:43.582] INFO: masking Roc 5 col/row: 5 59
[10:56:43.582] INFO: masking Roc 5 col/row: 15 57
[10:56:43.582] INFO: masking Roc 5 col/row: 19 42
[10:56:43.582] INFO: masking Roc 5 col/row: 33 55
[10:56:43.582] INFO: masking Roc 5 col/row: 47 42
[10:56:43.587] INFO: masking Roc 6 col/row: 20 53
[10:56:43.587] INFO: masking Roc 6 col/row: 20 57
[10:56:43.587] INFO: masking Roc 6 col/row: 21 51
[10:56:43.587] INFO: masking Roc 6 col/row: 25 57
[10:56:43.587] INFO: masking Roc 6 col/row: 27 59
[10:56:43.587] INFO: masking Roc 6 col/row: 30 55
[10:56:43.587] INFO: masking Roc 6 col/row: 31 48
[10:56:43.587] INFO: masking Roc 6 col/row: 32 51
[10:56:43.587] INFO: masking Roc 6 col/row: 35 47
[10:56:43.587] INFO: masking Roc 6 col/row: 45 57
[10:56:43.587] INFO: masking Roc 6 col/row: 49 51
[10:56:43.595] INFO: masking Roc 8 col/row: 43 53
[10:56:43.595] INFO: masking Roc 8 col/row: 43 54
[10:56:43.629] INFO: clk: 4
[10:56:43.629] INFO: ctr: 4
[10:56:43.629] INFO: sda: 19
[10:56:43.629] INFO: tin: 9
[10:56:43.629] INFO: level: 15
[10:56:43.629] INFO: triggerdelay: 0
[10:56:43.629] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:56:43.629] INFO: Log level: INFO
[10:56:43.646] QUIET: Connection to board DTB_WREKRL opened.
[10:56:43.649] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[10:56:43.652] INFO: RPC call hashes of host and DTB match: 398089610
[10:56:45.183] INFO: DUT info:
[10:56:45.183] INFO: The DUT currently contains the following objects:
[10:56:45.183] INFO: 2 TBM Cores tbm08c (2 ON)
[10:56:45.183] INFO: TBM Core alpha (0): 7 registers set
[10:56:45.183] INFO: TBM Core beta (1): 7 registers set
[10:56:45.183] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:56:45.183] INFO: ROC 0: 19 DACs set, Pixels: 7 masked, 0 active.
[10:56:45.183] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.183] INFO: ROC 2: 19 DACs set, Pixels: 8 masked, 0 active.
[10:56:45.183] INFO: ROC 3: 19 DACs set, Pixels: 3 masked, 0 active.
[10:56:45.184] INFO: ROC 4: 19 DACs set, Pixels: 2 masked, 0 active.
[10:56:45.184] INFO: ROC 5: 19 DACs set, Pixels: 5 masked, 0 active.
[10:56:45.184] INFO: ROC 6: 19 DACs set, Pixels: 11 masked, 0 active.
[10:56:45.184] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 8: 19 DACs set, Pixels: 2 masked, 0 active.
[10:56:45.184] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.184] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:45.587] INFO: enter 'restricted' command line mode
[10:56:45.587] INFO: enter test to run
[10:56:48.207] INFO: test: timing no parameter change
[10:56:48.207] INFO: running: timing
[10:56:48.216] INFO: ######################################################################
[10:56:48.216] INFO: PixTestTiming::doTest()
[10:56:48.216] INFO: ######################################################################
[10:56:48.216] INFO: ----------------------------------------------------------------------
[10:56:48.216] INFO: PixTestTiming::TBMPhaseScan()
[10:56:48.216] INFO: ----------------------------------------------------------------------
[11:02:07.917] INFO: TBM Phase Settings: 236
[11:02:07.917] INFO: 400MHz Phase: 3
[11:02:07.917] INFO: 160MHz Phase: 7
[11:02:07.917] INFO: Functional Phase Area: 4
[11:02:07.920] INFO: Test took 319704 ms.
[11:02:07.920] INFO: PixTestTiming::TBMPhaseScan() done.
[11:02:07.920] INFO: ROC 0 masking pixel 0/1
[11:02:07.920] INFO: ROC 0 masking pixel 1/2
[11:02:07.920] INFO: ROC 0 masking pixel 4/32
[11:02:07.920] INFO: ROC 0 masking pixel 5/34
[11:02:07.920] INFO: ROC 0 masking pixel 11/34
[11:02:07.920] INFO: ROC 0 masking pixel 12/32
[11:02:07.920] INFO: ROC 0 masking pixel 12/36
[11:02:07.920] INFO: ROC 2 masking pixel 26/17
[11:02:07.920] INFO: ROC 2 masking pixel 29/47
[11:02:07.920] INFO: ROC 2 masking pixel 32/51
[11:02:07.920] INFO: ROC 2 masking pixel 38/43
[11:02:07.920] INFO: ROC 2 masking pixel 39/32
[11:02:07.920] INFO: ROC 2 masking pixel 39/49
[11:02:07.920] INFO: ROC 2 masking pixel 39/53
[11:02:07.920] INFO: ROC 2 masking pixel 42/53
[11:02:07.920] INFO: ROC 3 masking pixel 1/51
[11:02:07.920] INFO: ROC 3 masking pixel 12/42
[11:02:07.920] INFO: ROC 3 masking pixel 33/23
[11:02:07.920] INFO: ROC 4 masking pixel 28/42
[11:02:07.920] INFO: ROC 4 masking pixel 49/24
[11:02:07.920] INFO: ROC 5 masking pixel 5/59
[11:02:07.920] INFO: ROC 5 masking pixel 15/57
[11:02:07.920] INFO: ROC 5 masking pixel 19/42
[11:02:07.920] INFO: ROC 5 masking pixel 33/55
[11:02:07.920] INFO: ROC 5 masking pixel 47/42
[11:02:07.920] INFO: ROC 6 masking pixel 20/53
[11:02:07.920] INFO: ROC 6 masking pixel 20/57
[11:02:07.920] INFO: ROC 6 masking pixel 21/51
[11:02:07.920] INFO: ROC 6 masking pixel 25/57
[11:02:07.920] INFO: ROC 6 masking pixel 27/59
[11:02:07.920] INFO: ROC 6 masking pixel 30/55
[11:02:07.920] INFO: ROC 6 masking pixel 31/48
[11:02:07.920] INFO: ROC 6 masking pixel 32/51
[11:02:07.920] INFO: ROC 6 masking pixel 35/47
[11:02:07.920] INFO: ROC 6 masking pixel 45/57
[11:02:07.920] INFO: ROC 6 masking pixel 49/51
[11:02:07.921] INFO: ROC 8 masking pixel 43/53
[11:02:07.921] INFO: ROC 8 masking pixel 43/54
[11:02:07.921] INFO: ----------------------------------------------------------------------
[11:02:07.921] INFO: PixTestTiming::ROCDelayScan()
[11:02:07.921] INFO: ----------------------------------------------------------------------
[11:04:20.336] INFO: ROC Delay Settings: 228
[11:04:20.336] INFO: ROC Header-Trailer/Token Delay: 11
[11:04:20.336] INFO: ROC Port 0 Delay: 4
[11:04:20.336] INFO: ROC Port 1 Delay: 4
[11:04:20.336] INFO: Functional ROC Area: 5
[11:04:20.339] INFO: Test took 132418 ms.
[11:04:20.339] INFO: PixTestTiming::ROCDelayScan() done.
[11:04:20.339] INFO: ROC 0 masking pixel 0/1
[11:04:20.339] INFO: ROC 0 masking pixel 1/2
[11:04:20.339] INFO: ROC 0 masking pixel 4/32
[11:04:20.339] INFO: ROC 0 masking pixel 5/34
[11:04:20.339] INFO: ROC 0 masking pixel 11/34
[11:04:20.339] INFO: ROC 0 masking pixel 12/32
[11:04:20.339] INFO: ROC 0 masking pixel 12/36
[11:04:20.339] INFO: ROC 2 masking pixel 26/17
[11:04:20.339] INFO: ROC 2 masking pixel 29/47
[11:04:20.339] INFO: ROC 2 masking pixel 32/51
[11:04:20.340] INFO: ROC 2 masking pixel 38/43
[11:04:20.340] INFO: ROC 2 masking pixel 39/32
[11:04:20.340] INFO: ROC 2 masking pixel 39/49
[11:04:20.340] INFO: ROC 2 masking pixel 39/53
[11:04:20.340] INFO: ROC 2 masking pixel 42/53
[11:04:20.340] INFO: ROC 3 masking pixel 1/51
[11:04:20.340] INFO: ROC 3 masking pixel 12/42
[11:04:20.340] INFO: ROC 3 masking pixel 33/23
[11:04:20.340] INFO: ROC 4 masking pixel 28/42
[11:04:20.340] INFO: ROC 4 masking pixel 49/24
[11:04:20.340] INFO: ROC 5 masking pixel 5/59
[11:04:20.340] INFO: ROC 5 masking pixel 15/57
[11:04:20.340] INFO: ROC 5 masking pixel 19/42
[11:04:20.340] INFO: ROC 5 masking pixel 33/55
[11:04:20.340] INFO: ROC 5 masking pixel 47/42
[11:04:20.340] INFO: ROC 6 masking pixel 20/53
[11:04:20.340] INFO: ROC 6 masking pixel 20/57
[11:04:20.340] INFO: ROC 6 masking pixel 21/51
[11:04:20.340] INFO: ROC 6 masking pixel 25/57
[11:04:20.340] INFO: ROC 6 masking pixel 27/59
[11:04:20.340] INFO: ROC 6 masking pixel 30/55
[11:04:20.340] INFO: ROC 6 masking pixel 31/48
[11:04:20.340] INFO: ROC 6 masking pixel 32/51
[11:04:20.340] INFO: ROC 6 masking pixel 35/47
[11:04:20.340] INFO: ROC 6 masking pixel 45/57
[11:04:20.340] INFO: ROC 6 masking pixel 49/51
[11:04:20.340] INFO: ROC 8 masking pixel 43/53
[11:04:20.340] INFO: ROC 8 masking pixel 43/54
[11:04:20.340] INFO: ----------------------------------------------------------------------
[11:04:20.340] INFO: PixTestTiming::TimingTest()
[11:04:20.340] INFO: ----------------------------------------------------------------------
[11:04:36.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:51.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:06.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:21.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:35.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:50.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:05.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:20.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:35.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:50.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:50.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: Read back bit status: 1
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: Timings are good!
[11:06:50.704] INFO: ----------------------------------------------------------------------
[11:06:50.704] INFO: Test took 150364 ms.
[11:06:50.704] INFO: PixTestTiming::TimingTest() done.
[11:06:50.704] INFO: ROC 0 masking pixel 0/1
[11:06:50.704] INFO: ROC 0 masking pixel 1/2
[11:06:50.704] INFO: ROC 0 masking pixel 4/32
[11:06:50.704] INFO: ROC 0 masking pixel 5/34
[11:06:50.704] INFO: ROC 0 masking pixel 11/34
[11:06:50.704] INFO: ROC 0 masking pixel 12/32
[11:06:50.704] INFO: ROC 0 masking pixel 12/36
[11:06:50.704] INFO: ROC 2 masking pixel 26/17
[11:06:50.704] INFO: ROC 2 masking pixel 29/47
[11:06:50.704] INFO: ROC 2 masking pixel 32/51
[11:06:50.704] INFO: ROC 2 masking pixel 38/43
[11:06:50.704] INFO: ROC 2 masking pixel 39/32
[11:06:50.704] INFO: ROC 2 masking pixel 39/49
[11:06:50.704] INFO: ROC 2 masking pixel 39/53
[11:06:50.704] INFO: ROC 2 masking pixel 42/53
[11:06:50.704] INFO: ROC 3 masking pixel 1/51
[11:06:50.704] INFO: ROC 3 masking pixel 12/42
[11:06:50.704] INFO: ROC 3 masking pixel 33/23
[11:06:50.704] INFO: ROC 4 masking pixel 28/42
[11:06:50.704] INFO: ROC 4 masking pixel 49/24
[11:06:50.704] INFO: ROC 5 masking pixel 5/59
[11:06:50.704] INFO: ROC 5 masking pixel 15/57
[11:06:50.704] INFO: ROC 5 masking pixel 19/42
[11:06:50.704] INFO: ROC 5 masking pixel 33/55
[11:06:50.704] INFO: ROC 5 masking pixel 47/42
[11:06:50.704] INFO: ROC 6 masking pixel 20/53
[11:06:50.704] INFO: ROC 6 masking pixel 20/57
[11:06:50.704] INFO: ROC 6 masking pixel 21/51
[11:06:50.704] INFO: ROC 6 masking pixel 25/57
[11:06:50.704] INFO: ROC 6 masking pixel 27/59
[11:06:50.704] INFO: ROC 6 masking pixel 30/55
[11:06:50.705] INFO: ROC 6 masking pixel 31/48
[11:06:50.705] INFO: ROC 6 masking pixel 32/51
[11:06:50.705] INFO: ROC 6 masking pixel 35/47
[11:06:50.705] INFO: ROC 6 masking pixel 45/57
[11:06:50.705] INFO: ROC 6 masking pixel 49/51
[11:06:50.705] INFO: ROC 8 masking pixel 43/53
[11:06:50.705] INFO: ROC 8 masking pixel 43/54
[11:06:50.705] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//tbmParameters_C0a.dat
[11:06:50.705] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:06:50.705] INFO: PixTestTiming::doTest took 602494 ms.
[11:06:50.705] INFO: PixTestTiming::doTest() done
[11:06:50.705] INFO: ROC 0 masking pixel 0/1
[11:06:50.705] INFO: ROC 0 masking pixel 1/2
[11:06:50.705] INFO: ROC 0 masking pixel 4/32
[11:06:50.705] INFO: ROC 0 masking pixel 5/34
[11:06:50.705] INFO: ROC 0 masking pixel 11/34
[11:06:50.705] INFO: ROC 0 masking pixel 12/32
[11:06:50.705] INFO: ROC 0 masking pixel 12/36
[11:06:50.705] INFO: ROC 2 masking pixel 26/17
[11:06:50.705] INFO: ROC 2 masking pixel 29/47
[11:06:50.705] INFO: ROC 2 masking pixel 32/51
[11:06:50.705] INFO: ROC 2 masking pixel 38/43
[11:06:50.705] INFO: ROC 2 masking pixel 39/32
[11:06:50.705] INFO: ROC 2 masking pixel 39/49
[11:06:50.705] INFO: ROC 2 masking pixel 39/53
[11:06:50.705] INFO: ROC 2 masking pixel 42/53
[11:06:50.705] INFO: ROC 3 masking pixel 1/51
[11:06:50.705] INFO: ROC 3 masking pixel 12/42
[11:06:50.705] INFO: ROC 3 masking pixel 33/23
[11:06:50.705] INFO: ROC 4 masking pixel 28/42
[11:06:50.705] INFO: ROC 4 masking pixel 49/24
[11:06:50.705] INFO: ROC 5 masking pixel 5/59
[11:06:50.705] INFO: ROC 5 masking pixel 15/57
[11:06:50.705] INFO: ROC 5 masking pixel 19/42
[11:06:50.705] INFO: ROC 5 masking pixel 33/55
[11:06:50.705] INFO: ROC 5 masking pixel 47/42
[11:06:50.705] INFO: ROC 6 masking pixel 20/53
[11:06:50.705] INFO: ROC 6 masking pixel 20/57
[11:06:50.705] INFO: ROC 6 masking pixel 21/51
[11:06:50.705] INFO: ROC 6 masking pixel 25/57
[11:06:50.705] INFO: ROC 6 masking pixel 27/59
[11:06:50.705] INFO: ROC 6 masking pixel 30/55
[11:06:50.705] INFO: ROC 6 masking pixel 31/48
[11:06:50.706] INFO: ROC 6 masking pixel 32/51
[11:06:50.706] INFO: ROC 6 masking pixel 35/47
[11:06:50.706] INFO: ROC 6 masking pixel 45/57
[11:06:50.706] INFO: ROC 6 masking pixel 49/51
[11:06:50.706] INFO: ROC 8 masking pixel 43/53
[11:06:50.706] INFO: ROC 8 masking pixel 43/54
[11:06:50.706] INFO: Write out TBMPhaseScan_0_V0
[11:06:50.734] INFO: Write out TBMPhaseScan_1_V0
[11:06:50.734] INFO: Write out CombinedTBMPhaseScan_V0
[11:06:50.734] INFO: Write out ROCDelayScan3_V0
[11:06:50.735] INFO: enter test to run
[11:07:06.786] INFO: test: PixelAlive no parameter change
[11:07:06.786] INFO: running: pixelalive
[11:07:06.791] INFO: ----------------------------------------------------------------------
[11:07:06.791] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:07:06.791] INFO: ----------------------------------------------------------------------
[11:07:06.795] INFO: ROC 0 masking pixel 0/1
[11:07:06.795] INFO: ROC 0 masking pixel 1/2
[11:07:06.795] INFO: ROC 0 masking pixel 4/32
[11:07:06.795] INFO: ROC 0 masking pixel 5/34
[11:07:06.795] INFO: ROC 0 masking pixel 11/34
[11:07:06.795] INFO: ROC 0 masking pixel 12/32
[11:07:06.795] INFO: ROC 0 masking pixel 12/36
[11:07:06.795] INFO: ROC 2 masking pixel 26/17
[11:07:06.795] INFO: ROC 2 masking pixel 29/47
[11:07:06.795] INFO: ROC 2 masking pixel 32/51
[11:07:06.795] INFO: ROC 2 masking pixel 38/43
[11:07:06.795] INFO: ROC 2 masking pixel 39/32
[11:07:06.795] INFO: ROC 2 masking pixel 39/49
[11:07:06.795] INFO: ROC 2 masking pixel 39/53
[11:07:06.795] INFO: ROC 2 masking pixel 42/53
[11:07:06.795] INFO: ROC 3 masking pixel 1/51
[11:07:06.795] INFO: ROC 3 masking pixel 12/42
[11:07:06.795] INFO: ROC 3 masking pixel 33/23
[11:07:06.795] INFO: ROC 4 masking pixel 28/42
[11:07:06.795] INFO: ROC 4 masking pixel 49/24
[11:07:06.795] INFO: ROC 5 masking pixel 5/59
[11:07:06.795] INFO: ROC 5 masking pixel 15/57
[11:07:06.795] INFO: ROC 5 masking pixel 19/42
[11:07:06.795] INFO: ROC 5 masking pixel 33/55
[11:07:06.795] INFO: ROC 5 masking pixel 47/42
[11:07:06.795] INFO: ROC 6 masking pixel 20/53
[11:07:06.795] INFO: ROC 6 masking pixel 20/57
[11:07:06.795] INFO: ROC 6 masking pixel 21/51
[11:07:06.795] INFO: ROC 6 masking pixel 25/57
[11:07:06.795] INFO: ROC 6 masking pixel 27/59
[11:07:06.795] INFO: ROC 6 masking pixel 30/55
[11:07:06.795] INFO: ROC 6 masking pixel 31/48
[11:07:06.795] INFO: ROC 6 masking pixel 32/51
[11:07:06.795] INFO: ROC 6 masking pixel 35/47
[11:07:06.795] INFO: ROC 6 masking pixel 45/57
[11:07:06.795] INFO: ROC 6 masking pixel 49/51
[11:07:06.795] INFO: ROC 8 masking pixel 43/53
[11:07:06.795] INFO: ROC 8 masking pixel 43/54
[11:07:07.112] INFO: Expecting 41600 events.
[11:07:11.451] INFO: 41600 events read in total (3623ms).
[11:07:11.452] INFO: Test took 4657ms.
[11:07:11.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:11.853] INFO: PixTestAlive::aliveTest() done
[11:07:11.853] INFO: number of dead pixels (per ROC): 7 1 12 5 2 5 11 0 2 0 0 0 0 0 0 0
[11:07:11.853] INFO: ROC 0 masking pixel 0/1
[11:07:11.853] INFO: ROC 0 masking pixel 1/2
[11:07:11.853] INFO: ROC 0 masking pixel 4/32
[11:07:11.853] INFO: ROC 0 masking pixel 5/34
[11:07:11.853] INFO: ROC 0 masking pixel 11/34
[11:07:11.853] INFO: ROC 0 masking pixel 12/32
[11:07:11.853] INFO: ROC 0 masking pixel 12/36
[11:07:11.853] INFO: ROC 2 masking pixel 26/17
[11:07:11.853] INFO: ROC 2 masking pixel 29/47
[11:07:11.853] INFO: ROC 2 masking pixel 32/51
[11:07:11.853] INFO: ROC 2 masking pixel 38/43
[11:07:11.853] INFO: ROC 2 masking pixel 39/32
[11:07:11.853] INFO: ROC 2 masking pixel 39/49
[11:07:11.853] INFO: ROC 2 masking pixel 39/53
[11:07:11.853] INFO: ROC 2 masking pixel 42/53
[11:07:11.853] INFO: ROC 3 masking pixel 1/51
[11:07:11.853] INFO: ROC 3 masking pixel 12/42
[11:07:11.853] INFO: ROC 3 masking pixel 33/23
[11:07:11.853] INFO: ROC 4 masking pixel 28/42
[11:07:11.853] INFO: ROC 4 masking pixel 49/24
[11:07:11.853] INFO: ROC 5 masking pixel 5/59
[11:07:11.853] INFO: ROC 5 masking pixel 15/57
[11:07:11.853] INFO: ROC 5 masking pixel 19/42
[11:07:11.854] INFO: ROC 5 masking pixel 33/55
[11:07:11.854] INFO: ROC 5 masking pixel 47/42
[11:07:11.854] INFO: ROC 6 masking pixel 20/53
[11:07:11.854] INFO: ROC 6 masking pixel 20/57
[11:07:11.854] INFO: ROC 6 masking pixel 21/51
[11:07:11.854] INFO: ROC 6 masking pixel 25/57
[11:07:11.854] INFO: ROC 6 masking pixel 27/59
[11:07:11.854] INFO: ROC 6 masking pixel 30/55
[11:07:11.854] INFO: ROC 6 masking pixel 31/48
[11:07:11.854] INFO: ROC 6 masking pixel 32/51
[11:07:11.854] INFO: ROC 6 masking pixel 35/47
[11:07:11.854] INFO: ROC 6 masking pixel 45/57
[11:07:11.854] INFO: ROC 6 masking pixel 49/51
[11:07:11.854] INFO: ROC 8 masking pixel 43/53
[11:07:11.854] INFO: ROC 8 masking pixel 43/54
[11:07:11.857] INFO: enter test to run
[11:07:31.730] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:07:31.730] INFO: running: highrate
[11:07:31.731] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:07:31.886] INFO: ----------------------------------------------------------------------
[11:07:31.886] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:07:31.886] INFO: ----------------------------------------------------------------------
[11:07:31.886] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:07:31.886] INFO: edge/corner pixel THR is adjusted
[11:07:31.886] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:07:32.845] INFO: Collecting data for 5 seconds...
[11:07:37.861] INFO: Done with hot pixel readout
[11:07:50.396] INFO: PixTest:: pg_setup set to default.




[11:07:50.397] INFO: 32 hot pixels found in step 0
[11:07:51.395] INFO: Collecting data for 5 seconds...
[11:07:56.411] INFO: Done with hot pixel readout
[11:08:08.941] INFO: PixTest:: pg_setup set to default.






[11:08:08.942] INFO: 26 hot pixels found in step 1
[11:08:09.940] INFO: Collecting data for 5 seconds...
[11:08:14.956] INFO: Done with hot pixel readout
[11:08:27.442] INFO: PixTest:: pg_setup set to default.


[11:08:27.443] INFO: 23 hot pixels found in step 2
[11:08:28.442] INFO: Collecting data for 5 seconds...
[11:08:33.458] INFO: Done with hot pixel readout
[11:08:46.004] INFO: PixTest:: pg_setup set to default.


[11:08:46.005] INFO: 33 hot pixels found in step 3
[11:08:47.002] INFO: Collecting data for 5 seconds...
[11:08:52.018] INFO: Done with hot pixel readout
[11:09:04.523] INFO: PixTest:: pg_setup set to default.




[11:09:04.524] INFO: 20 hot pixels found in step 4
[11:09:05.522] INFO: Collecting data for 5 seconds...
[11:09:10.538] INFO: Done with hot pixel readout
[11:09:23.065] INFO: PixTest:: pg_setup set to default.




[11:09:23.066] INFO: 24 hot pixels found in step 5
[11:09:24.064] INFO: Collecting data for 5 seconds...
[11:09:29.082] INFO: Done with hot pixel readout
[11:09:41.604] INFO: PixTest:: pg_setup set to default.









[11:09:41.605] INFO: 30 hot pixels found in step 6
[11:09:42.604] INFO: Collecting data for 5 seconds...
[11:09:47.620] INFO: Done with hot pixel readout
[11:10:00.174] INFO: PixTest:: pg_setup set to default.





[11:10:00.175] INFO: 28 hot pixels found in step 7
[11:10:01.174] INFO: Collecting data for 5 seconds...
[11:10:06.190] INFO: Done with hot pixel readout
[11:10:18.704] INFO: PixTest:: pg_setup set to default.

[11:10:18.705] INFO: 28 hot pixels found in step 8
[11:10:19.703] INFO: Collecting data for 5 seconds...
[11:10:24.719] INFO: Done with hot pixel readout
[11:10:37.271] INFO: PixTest:: pg_setup set to default.



[11:10:37.272] INFO: 25 hot pixels found in step 9
[11:10:38.269] INFO: Collecting data for 5 seconds...
[11:10:43.286] INFO: Done with hot pixel readout
[11:10:55.824] INFO: PixTest:: pg_setup set to default.






[11:10:55.825] INFO: 29 hot pixels found in step 10
[11:10:56.823] INFO: Collecting data for 5 seconds...
[11:11:01.839] INFO: Done with hot pixel readout
[11:11:14.350] INFO: PixTest:: pg_setup set to default.




[11:11:14.351] INFO: 20 hot pixels found in step 11
[11:11:15.349] INFO: Collecting data for 5 seconds...
[11:11:20.366] INFO: Done with hot pixel readout
[11:11:32.913] INFO: PixTest:: pg_setup set to default.



[11:11:32.914] INFO: 17 hot pixels found in step 12
[11:11:33.912] INFO: Collecting data for 5 seconds...
[11:11:38.929] INFO: Done with hot pixel readout
[11:11:51.455] INFO: PixTest:: pg_setup set to default.




[11:11:51.456] INFO: 31 hot pixels found in step 13
[11:11:52.454] INFO: Collecting data for 5 seconds...
[11:11:57.470] INFO: Done with hot pixel readout
[11:12:10.026] INFO: PixTest:: pg_setup set to default.


[11:12:10.027] INFO: 23 hot pixels found in step 14
[11:12:10.069] INFO: 23 hot pixels could not be trimmed and have been masked.
[11:12:10.072] INFO: PixTest::trimHotPixels() done
[11:12:10.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C0.dat
[11:12:10.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C1.dat
[11:12:10.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C2.dat
[11:12:10.090] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C3.dat
[11:12:10.095] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C4.dat
[11:12:10.100] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C5.dat
[11:12:10.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C6.dat
[11:12:10.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C7.dat
[11:12:10.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C8.dat
[11:12:10.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C9.dat
[11:12:10.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C10.dat
[11:12:10.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C11.dat
[11:12:10.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C12.dat
[11:12:10.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C13.dat
[11:12:10.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C14.dat
[11:12:10.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C15.dat
[11:12:10.158] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//defaultMaskFile.dat
[11:12:10.168] INFO: enter test to run
[11:12:46.456] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:12:46.456] INFO: running: highrate
[11:12:46.461] INFO: ----------------------------------------------------------------------
[11:12:46.461] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:12:46.461] INFO: ----------------------------------------------------------------------
[11:12:46.461] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:12:46.461] INFO: edge/corner pixel THR is adjusted
[11:12:46.461] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:12:47.418] INFO: Collecting data for 1 seconds...
[11:12:48.422] INFO: Done with hot pixel readout
[11:12:52.857] INFO: PixTest:: pg_setup set to default.
[11:12:52.858] INFO: 0 hot pixels found in step 0
[11:12:52.863] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:12:52.939] INFO: PixTest::trimHotPixels() done
[11:12:52.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C0.dat
[11:12:52.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C1.dat
[11:12:52.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C2.dat
[11:12:52.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C3.dat
[11:12:52.966] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C4.dat
[11:12:52.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C5.dat
[11:12:52.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C6.dat
[11:12:52.982] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C7.dat
[11:12:52.987] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C8.dat
[11:12:52.992] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C9.dat
[11:12:52.997] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C10.dat
[11:12:53.003] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C11.dat
[11:12:53.008] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C12.dat
[11:12:53.013] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C13.dat
[11:12:53.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C14.dat
[11:12:53.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//trimParameters35_C15.dat
[11:12:53.029] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-28_FPIXTest-17C-FNAL-160412-1225_2016-04-12_12h25m_1460481943/000_FPIXTest_p17//defaultMaskFile.dat
[11:12:53.040] INFO: enter test to run
[11:13:16.328] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:13:16.328] INFO: running: xray
[11:13:16.329] INFO: ----------------------------------------------------------------------
[11:13:16.329] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:13:16.329] INFO: ----------------------------------------------------------------------
[11:13:16.332] INFO: ROC 0 masking pixel 0/1
[11:13:16.332] INFO: ROC 0 masking pixel 1/2
[11:13:16.332] INFO: ROC 0 masking pixel 4/32
[11:13:16.332] INFO: ROC 0 masking pixel 5/34
[11:13:16.332] INFO: ROC 0 masking pixel 11/34
[11:13:16.333] INFO: ROC 0 masking pixel 12/32
[11:13:16.333] INFO: ROC 0 masking pixel 12/36
[11:13:16.333] INFO: ROC 2 masking pixel 26/17
[11:13:16.333] INFO: ROC 2 masking pixel 29/47
[11:13:16.333] INFO: ROC 2 masking pixel 32/51
[11:13:16.333] INFO: ROC 2 masking pixel 38/43
[11:13:16.333] INFO: ROC 2 masking pixel 39/32
[11:13:16.333] INFO: ROC 2 masking pixel 39/49
[11:13:16.333] INFO: ROC 2 masking pixel 39/53
[11:13:16.333] INFO: ROC 2 masking pixel 42/53
[11:13:16.333] INFO: ROC 3 masking pixel 1/51
[11:13:16.333] INFO: ROC 3 masking pixel 12/42
[11:13:16.333] INFO: ROC 3 masking pixel 33/23
[11:13:16.333] INFO: ROC 4 masking pixel 28/42
[11:13:16.333] INFO: ROC 4 masking pixel 49/24
[11:13:16.333] INFO: ROC 5 masking pixel 5/59
[11:13:16.333] INFO: ROC 5 masking pixel 15/57
[11:13:16.333] INFO: ROC 5 masking pixel 19/42
[11:13:16.333] INFO: ROC 5 masking pixel 33/55
[11:13:16.333] INFO: ROC 5 masking pixel 47/42
[11:13:16.333] INFO: ROC 6 masking pixel 20/53
[11:13:16.333] INFO: ROC 6 masking pixel 20/57
[11:13:16.333] INFO: ROC 6 masking pixel 21/51
[11:13:16.333] INFO: ROC 6 masking pixel 25/57
[11:13:16.333] INFO: ROC 6 masking pixel 27/59
[11:13:16.333] INFO: ROC 6 masking pixel 30/55
[11:13:16.333] INFO: ROC 6 masking pixel 31/48
[11:13:16.333] INFO: ROC 6 masking pixel 32/51
[11:13:16.333] INFO: ROC 6 masking pixel 35/47
[11:13:16.333] INFO: ROC 6 masking pixel 45/57
[11:13:16.333] INFO: ROC 6 masking pixel 49/51
[11:13:16.333] INFO: ROC 8 masking pixel 43/53
[11:13:16.333] INFO: ROC 8 masking pixel 43/54
[11:13:17.293] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:13:27.914] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:13:56.917] INFO: Resuming triggers.
[11:14:07.646] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:14:37.137] INFO: Resuming triggers.
[11:14:47.757] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:15:17.277] INFO: Resuming triggers.
[11:15:27.900] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[11:15:51.733] INFO: Resuming triggers.
[11:16:02.359] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:16:31.809] INFO: Resuming triggers.
[11:16:42.433] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[11:17:11.864] INFO: Resuming triggers.
[11:17:22.489] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[11:17:51.976] INFO: Resuming triggers.
[11:18:02.594] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[11:18:32.075] INFO: Resuming triggers.
[11:18:42.696] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:19:12.224] INFO: Resuming triggers.
[11:19:16.954] INFO: data taking finished, elapsed time: 100 seconds.
[11:19:30.340] INFO: PixTest:: pg_setup set to default.
[11:19:30.344] INFO: PixTestXray::doPhRun() done
[11:19:30.481] INFO: enter test to run
[11:20:24.277] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:20:24.277] INFO: running: xray
[11:20:24.278] INFO: ----------------------------------------------------------------------
[11:20:24.278] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:20:24.278] INFO: ----------------------------------------------------------------------
[11:20:24.281] INFO: ROC 0 masking pixel 0/1
[11:20:24.282] INFO: ROC 0 masking pixel 1/2
[11:20:24.282] INFO: ROC 0 masking pixel 4/32
[11:20:24.282] INFO: ROC 0 masking pixel 5/34
[11:20:24.282] INFO: ROC 0 masking pixel 11/34
[11:20:24.282] INFO: ROC 0 masking pixel 12/32
[11:20:24.282] INFO: ROC 0 masking pixel 12/36
[11:20:24.282] INFO: ROC 2 masking pixel 26/17
[11:20:24.282] INFO: ROC 2 masking pixel 29/47
[11:20:24.282] INFO: ROC 2 masking pixel 32/51
[11:20:24.282] INFO: ROC 2 masking pixel 38/43
[11:20:24.282] INFO: ROC 2 masking pixel 39/32
[11:20:24.282] INFO: ROC 2 masking pixel 39/49
[11:20:24.282] INFO: ROC 2 masking pixel 39/53
[11:20:24.282] INFO: ROC 2 masking pixel 42/53
[11:20:24.282] INFO: ROC 3 masking pixel 1/51
[11:20:24.282] INFO: ROC 3 masking pixel 12/42
[11:20:24.282] INFO: ROC 3 masking pixel 33/23
[11:20:24.282] INFO: ROC 4 masking pixel 28/42
[11:20:24.282] INFO: ROC 4 masking pixel 49/24
[11:20:24.282] INFO: ROC 5 masking pixel 5/59
[11:20:24.282] INFO: ROC 5 masking pixel 15/57
[11:20:24.282] INFO: ROC 5 masking pixel 19/42
[11:20:24.282] INFO: ROC 5 masking pixel 33/55
[11:20:24.282] INFO: ROC 5 masking pixel 47/42
[11:20:24.282] INFO: ROC 6 masking pixel 20/53
[11:20:24.282] INFO: ROC 6 masking pixel 20/57
[11:20:24.282] INFO: ROC 6 masking pixel 21/51
[11:20:24.282] INFO: ROC 6 masking pixel 25/57
[11:20:24.282] INFO: ROC 6 masking pixel 27/59
[11:20:24.282] INFO: ROC 6 masking pixel 30/55
[11:20:24.282] INFO: ROC 6 masking pixel 31/48
[11:20:24.282] INFO: ROC 6 masking pixel 32/51
[11:20:24.282] INFO: ROC 6 masking pixel 35/47
[11:20:24.282] INFO: ROC 6 masking pixel 45/57
[11:20:24.282] INFO: ROC 6 masking pixel 49/51
[11:20:24.282] INFO: ROC 8 masking pixel 43/53
[11:20:24.282] INFO: ROC 8 masking pixel 43/54
[11:20:25.249] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:20:31.131] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:21:01.188] INFO: Resuming triggers.
[11:21:07.072] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:21:37.129] INFO: Resuming triggers.
[11:21:43.015] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[11:22:13.098] INFO: Resuming triggers.
[11:22:18.982] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:22:49.045] INFO: Resuming triggers.
[11:22:54.927] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[11:23:24.946] INFO: Resuming triggers.
[11:23:30.828] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:24:00.843] INFO: Resuming triggers.
[11:24:06.727] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:24:36.773] INFO: Resuming triggers.
[11:24:42.657] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:25:12.690] INFO: Resuming triggers.
[11:25:18.578] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:25:47.240] INFO: Resuming triggers.
[11:25:53.123] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:26:23.258] INFO: Resuming triggers.
[11:26:29.143] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[11:26:59.216] INFO: Resuming triggers.
[11:27:05.100] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:27:35.123] INFO: Resuming triggers.
[11:27:41.009] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:28:11.084] INFO: Resuming triggers.
[11:28:16.967] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:28:47.011] INFO: Resuming triggers.
[11:28:52.899] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:29:23.004] INFO: Resuming triggers.
[11:29:28.889] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:29:59.003] INFO: Resuming triggers.
[11:30:04.889] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[11:30:34.987] INFO: Resuming triggers.
[11:30:35.277] INFO: data taking finished, elapsed time: 100 seconds.
[11:30:37.135] INFO: PixTest:: pg_setup set to default.
[11:30:37.138] INFO: PixTestXray::doPhRun() done
[11:30:37.295] INFO: enter test to run
[11:31:05.224] INFO: test: HighRate no parameter change
[11:31:05.224] INFO: running: highrate
[11:31:05.225] INFO: ----------------------------------------------------------------------
[11:31:05.225] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:31:05.225] INFO: ----------------------------------------------------------------------
[11:31:05.367] INFO: Expecting 768 events.
[11:31:06.500] INFO: 768 events read in total (417ms).
[11:31:06.501] INFO: Test took 1268ms.
[11:31:06.504] INFO: ROC 0 masking pixel 0/1
[11:31:06.504] INFO: ROC 0 masking pixel 1/2
[11:31:06.504] INFO: ROC 0 masking pixel 4/32
[11:31:06.504] INFO: ROC 0 masking pixel 5/34
[11:31:06.504] INFO: ROC 0 masking pixel 11/34
[11:31:06.504] INFO: ROC 0 masking pixel 12/32
[11:31:06.504] INFO: ROC 0 masking pixel 12/36
[11:31:06.504] INFO: ROC 2 masking pixel 26/17
[11:31:06.505] INFO: ROC 2 masking pixel 29/47
[11:31:06.505] INFO: ROC 2 masking pixel 32/51
[11:31:06.505] INFO: ROC 2 masking pixel 38/43
[11:31:06.505] INFO: ROC 2 masking pixel 39/32
[11:31:06.505] INFO: ROC 2 masking pixel 39/49
[11:31:06.505] INFO: ROC 2 masking pixel 39/53
[11:31:06.505] INFO: ROC 2 masking pixel 42/53
[11:31:06.505] INFO: ROC 3 masking pixel 1/51
[11:31:06.505] INFO: ROC 3 masking pixel 12/42
[11:31:06.505] INFO: ROC 3 masking pixel 33/23
[11:31:06.505] INFO: ROC 4 masking pixel 28/42
[11:31:06.505] INFO: ROC 4 masking pixel 49/24
[11:31:06.505] INFO: ROC 5 masking pixel 5/59
[11:31:06.505] INFO: ROC 5 masking pixel 15/57
[11:31:06.505] INFO: ROC 5 masking pixel 19/42
[11:31:06.505] INFO: ROC 5 masking pixel 33/55
[11:31:06.505] INFO: ROC 5 masking pixel 47/42
[11:31:06.505] INFO: ROC 6 masking pixel 20/53
[11:31:06.505] INFO: ROC 6 masking pixel 20/57
[11:31:06.505] INFO: ROC 6 masking pixel 21/51
[11:31:06.505] INFO: ROC 6 masking pixel 25/57
[11:31:06.505] INFO: ROC 6 masking pixel 27/59
[11:31:06.505] INFO: ROC 6 masking pixel 30/55
[11:31:06.505] INFO: ROC 6 masking pixel 31/48
[11:31:06.505] INFO: ROC 6 masking pixel 32/51
[11:31:06.505] INFO: ROC 6 masking pixel 35/47
[11:31:06.505] INFO: ROC 6 masking pixel 45/57
[11:31:06.505] INFO: ROC 6 masking pixel 49/51
[11:31:06.505] INFO: ROC 8 masking pixel 43/53
[11:31:06.505] INFO: ROC 8 masking pixel 43/54
[11:31:07.304] INFO: Expecting 41600 events.
[11:31:10.483] INFO: 41600 events read in total (2652ms).
[11:31:10.484] INFO: Test took 3976ms.
[11:31:10.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:11.240] INFO: Expecting 41600 events.
[11:31:14.527] INFO: 41600 events read in total (2760ms).
[11:31:14.528] INFO: Test took 3984ms.
[11:31:14.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:15.278] INFO: Expecting 41600 events.
[11:31:18.574] INFO: 41600 events read in total (2769ms).
[11:31:18.575] INFO: Test took 3987ms.
[11:31:18.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:19.329] INFO: Expecting 41600 events.
[11:31:22.619] INFO: 41600 events read in total (2763ms).
[11:31:22.620] INFO: Test took 3983ms.
[11:31:22.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:23.374] INFO: Expecting 41600 events.
[11:31:26.676] INFO: 41600 events read in total (2776ms).
[11:31:26.677] INFO: Test took 3996ms.
[11:31:26.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:27.430] INFO: Expecting 41600 events.
[11:31:30.743] INFO: 41600 events read in total (2786ms).
[11:31:30.744] INFO: Test took 4006ms.
[11:31:30.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:31.498] INFO: Expecting 41600 events.
[11:31:34.797] INFO: 41600 events read in total (2772ms).
[11:31:34.798] INFO: Test took 3992ms.
[11:31:34.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:35.548] INFO: Expecting 41600 events.
[11:31:38.839] INFO: 41600 events read in total (2764ms).
[11:31:38.840] INFO: Test took 3980ms.
[11:31:38.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:39.590] INFO: Expecting 41600 events.
[11:31:42.900] INFO: 41600 events read in total (2783ms).
[11:31:42.901] INFO: Test took 3999ms.
[11:31:42.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:43.655] INFO: Expecting 41600 events.
[11:31:46.956] INFO: 41600 events read in total (2774ms).
[11:31:46.957] INFO: Test took 3994ms.
[11:31:46.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:47.710] INFO: Expecting 41600 events.
[11:31:51.010] INFO: 41600 events read in total (2773ms).
[11:31:51.011] INFO: Test took 3992ms.
[11:31:51.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:51.764] INFO: Expecting 41600 events.
[11:31:55.068] INFO: 41600 events read in total (2777ms).
[11:31:55.069] INFO: Test took 3998ms.
[11:31:55.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:55.823] INFO: Expecting 41600 events.
[11:31:59.135] INFO: 41600 events read in total (2785ms).
[11:31:59.136] INFO: Test took 4006ms.
[11:31:59.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:59.891] INFO: Expecting 41600 events.
[11:32:03.200] INFO: 41600 events read in total (2782ms).
[11:32:03.201] INFO: Test took 4003ms.
[11:32:03.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:03.952] INFO: Expecting 41600 events.
[11:32:07.269] INFO: 41600 events read in total (2790ms).
[11:32:07.271] INFO: Test took 4009ms.
[11:32:07.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:08.021] INFO: Expecting 41600 events.
[11:32:11.328] INFO: 41600 events read in total (2780ms).
[11:32:11.329] INFO: Test took 3996ms.
[11:32:11.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:12.082] INFO: Expecting 41600 events.
[11:32:15.383] INFO: 41600 events read in total (2774ms).
[11:32:15.384] INFO: Test took 3994ms.
[11:32:15.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:16.138] INFO: Expecting 41600 events.
[11:32:19.450] INFO: 41600 events read in total (2785ms).
[11:32:19.451] INFO: Test took 4005ms.
[11:32:19.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:20.203] INFO: Expecting 41600 events.
[11:32:23.503] INFO: 41600 events read in total (2773ms).
[11:32:23.504] INFO: Test took 3991ms.
[11:32:23.544] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:24.254] INFO: Expecting 41600 events.
[11:32:27.417] INFO: 41600 events read in total (2636ms).
[11:32:27.418] INFO: Test took 3854ms.
[11:32:27.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:27.816] INFO: enter test to run
[11:32:44.215] INFO: test: HighRate no parameter change
[11:32:44.215] INFO: running: highrate
[11:32:44.216] INFO: ----------------------------------------------------------------------
[11:32:44.216] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:32:44.216] INFO: ----------------------------------------------------------------------
[11:32:44.224] INFO: ROC 0 masking pixel 0/1
[11:32:44.224] INFO: ROC 0 masking pixel 1/2
[11:32:44.224] INFO: ROC 0 masking pixel 4/32
[11:32:44.224] INFO: ROC 0 masking pixel 5/34
[11:32:44.224] INFO: ROC 0 masking pixel 11/34
[11:32:44.225] INFO: ROC 0 masking pixel 12/32
[11:32:44.225] INFO: ROC 0 masking pixel 12/36
[11:32:44.225] INFO: ROC 2 masking pixel 26/17
[11:32:44.225] INFO: ROC 2 masking pixel 29/47
[11:32:44.225] INFO: ROC 2 masking pixel 32/51
[11:32:44.225] INFO: ROC 2 masking pixel 38/43
[11:32:44.225] INFO: ROC 2 masking pixel 39/32
[11:32:44.225] INFO: ROC 2 masking pixel 39/49
[11:32:44.225] INFO: ROC 2 masking pixel 39/53
[11:32:44.225] INFO: ROC 2 masking pixel 42/53
[11:32:44.225] INFO: ROC 3 masking pixel 1/51
[11:32:44.225] INFO: ROC 3 masking pixel 12/42
[11:32:44.225] INFO: ROC 3 masking pixel 33/23
[11:32:44.225] INFO: ROC 4 masking pixel 28/42
[11:32:44.225] INFO: ROC 4 masking pixel 49/24
[11:32:44.225] INFO: ROC 5 masking pixel 5/59
[11:32:44.225] INFO: ROC 5 masking pixel 15/57
[11:32:44.225] INFO: ROC 5 masking pixel 19/42
[11:32:44.225] INFO: ROC 5 masking pixel 33/55
[11:32:44.225] INFO: ROC 5 masking pixel 47/42
[11:32:44.225] INFO: ROC 6 masking pixel 20/53
[11:32:44.225] INFO: ROC 6 masking pixel 20/57
[11:32:44.225] INFO: ROC 6 masking pixel 21/51
[11:32:44.225] INFO: ROC 6 masking pixel 25/57
[11:32:44.225] INFO: ROC 6 masking pixel 27/59
[11:32:44.225] INFO: ROC 6 masking pixel 30/55
[11:32:44.225] INFO: ROC 6 masking pixel 31/48
[11:32:44.225] INFO: ROC 6 masking pixel 32/51
[11:32:44.225] INFO: ROC 6 masking pixel 35/47
[11:32:44.225] INFO: ROC 6 masking pixel 45/57
[11:32:44.225] INFO: ROC 6 masking pixel 49/51
[11:32:44.225] INFO: ROC 8 masking pixel 43/53
[11:32:44.226] INFO: ROC 8 masking pixel 43/54
[11:32:44.832] INFO: Expecting 208000 events.
[11:32:57.101] INFO: 208000 events read in total (11742ms).
[11:32:57.105] INFO: Test took 12879ms.
[11:32:57.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:57.547] INFO: number of dead pixels (per ROC): 7 1 12 5 2 5 11 0 2 0 0 0 0 0 0 0
[11:32:57.547] INFO: number of red-efficiency pixels: 109 60 141 165 161 207 266 155 142 142 184 134 104 84 36 52
[11:32:57.547] INFO: number of X-ray hits detected: 79878 53430 86191 133167 138913 142620 148021 102554 101062 125056 120986 102195 107741 64360 28985 30020
[11:32:57.547] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:32:57.547] INFO: number of Vcal hits detected: 207542 207890 207270 207584 207732 207539 207172 207843 207759 207852 207806 207863 207895 207916 207964 207948
[11:32:57.547] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[11:32:57.547] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.8 99.9 99.8 99.6 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[11:32:57.547] INFO: X-ray hit rate [MHz/cm2]: 23.4 15.7 25.3 39.0 40.7 41.8 43.4 30.1 29.6 36.7 35.5 30.0 31.6 18.9 8.5 8.8
[11:32:57.547] INFO: PixTestHighRate::doXPixelAlive() done
[11:32:57.595] INFO: PixTest:: pg_setup set to default.
[11:32:57.609] INFO: enter test to run
[11:33:29.991] INFO: test: HighRate no parameter change
[11:33:29.991] INFO: running: highrate
[11:33:29.992] INFO: ----------------------------------------------------------------------
[11:33:29.992] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:33:29.992] INFO: ----------------------------------------------------------------------
[11:33:29.000] INFO: ROC 0 masking pixel 0/1
[11:33:29.000] INFO: ROC 0 masking pixel 1/2
[11:33:29.000] INFO: ROC 0 masking pixel 4/32
[11:33:29.000] INFO: ROC 0 masking pixel 5/34
[11:33:29.000] INFO: ROC 0 masking pixel 11/34
[11:33:29.000] INFO: ROC 0 masking pixel 12/32
[11:33:29.000] INFO: ROC 0 masking pixel 12/36
[11:33:29.000] INFO: ROC 2 masking pixel 26/17
[11:33:29.000] INFO: ROC 2 masking pixel 29/47
[11:33:29.000] INFO: ROC 2 masking pixel 32/51
[11:33:29.000] INFO: ROC 2 masking pixel 38/43
[11:33:29.000] INFO: ROC 2 masking pixel 39/32
[11:33:29.000] INFO: ROC 2 masking pixel 39/49
[11:33:29.000] INFO: ROC 2 masking pixel 39/53
[11:33:29.000] INFO: ROC 2 masking pixel 42/53
[11:33:29.001] INFO: ROC 3 masking pixel 1/51
[11:33:29.001] INFO: ROC 3 masking pixel 12/42
[11:33:29.001] INFO: ROC 3 masking pixel 33/23
[11:33:29.001] INFO: ROC 4 masking pixel 28/42
[11:33:29.001] INFO: ROC 4 masking pixel 49/24
[11:33:29.001] INFO: ROC 5 masking pixel 5/59
[11:33:29.001] INFO: ROC 5 masking pixel 15/57
[11:33:29.001] INFO: ROC 5 masking pixel 19/42
[11:33:29.001] INFO: ROC 5 masking pixel 33/55
[11:33:29.001] INFO: ROC 5 masking pixel 47/42
[11:33:29.001] INFO: ROC 6 masking pixel 20/53
[11:33:29.001] INFO: ROC 6 masking pixel 20/57
[11:33:29.001] INFO: ROC 6 masking pixel 21/51
[11:33:29.001] INFO: ROC 6 masking pixel 25/57
[11:33:29.001] INFO: ROC 6 masking pixel 27/59
[11:33:29.001] INFO: ROC 6 masking pixel 30/55
[11:33:29.001] INFO: ROC 6 masking pixel 31/48
[11:33:29.001] INFO: ROC 6 masking pixel 32/51
[11:33:29.001] INFO: ROC 6 masking pixel 35/47
[11:33:29.001] INFO: ROC 6 masking pixel 45/57
[11:33:29.001] INFO: ROC 6 masking pixel 49/51
[11:33:29.001] INFO: ROC 8 masking pixel 43/53
[11:33:29.001] INFO: ROC 8 masking pixel 43/54
[11:33:30.611] INFO: Expecting 208000 events.
[11:33:45.144] INFO: 208000 events read in total (14006ms).
[11:33:45.150] INFO: Test took 15148ms.
[11:33:45.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:45.828] INFO: number of dead pixels (per ROC): 7 1 12 5 2 5 11 0 2 0 0 0 0 0 0 0
[11:33:45.828] INFO: number of red-efficiency pixels: 332 177 360 640 595 727 931 517 427 523 600 485 320 288 62 68
[11:33:45.828] INFO: number of X-ray hits detected: 163382 110149 179031 274734 284482 292405 303666 210549 206647 255912 248033 210287 221718 131769 59339 61187
[11:33:45.828] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:33:45.828] INFO: number of Vcal hits detected: 207291 207769 207014 207026 207240 206908 206361 207392 207433 207422 207334 207474 207655 207695 207936 207931
[11:33:45.828] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.5 99.7 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[11:33:45.828] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.5 99.6 99.5 99.2 99.7 99.7 99.7 99.7 99.7 99.8 99.9 100.0 100.0
[11:33:45.828] INFO: X-ray hit rate [MHz/cm2]: 47.9 32.3 52.5 80.5 83.4 85.7 89.0 61.7 60.6 75.0 72.7 61.6 65.0 38.6 17.4 17.9
[11:33:45.828] INFO: PixTestHighRate::doXPixelAlive() done
[11:33:45.878] INFO: PixTest:: pg_setup set to default.
[11:33:45.896] INFO: enter test to run
[11:33:58.695] INFO: test: HighRate no parameter change
[11:33:58.695] INFO: running: highrate
[11:33:58.696] INFO: ----------------------------------------------------------------------
[11:33:58.696] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:33:58.696] INFO: ----------------------------------------------------------------------
[11:33:58.705] INFO: ROC 0 masking pixel 0/1
[11:33:58.705] INFO: ROC 0 masking pixel 1/2
[11:33:58.705] INFO: ROC 0 masking pixel 4/32
[11:33:58.705] INFO: ROC 0 masking pixel 5/34
[11:33:58.705] INFO: ROC 0 masking pixel 11/34
[11:33:58.705] INFO: ROC 0 masking pixel 12/32
[11:33:58.705] INFO: ROC 0 masking pixel 12/36
[11:33:58.705] INFO: ROC 2 masking pixel 26/17
[11:33:58.705] INFO: ROC 2 masking pixel 29/47
[11:33:58.705] INFO: ROC 2 masking pixel 32/51
[11:33:58.705] INFO: ROC 2 masking pixel 38/43
[11:33:58.705] INFO: ROC 2 masking pixel 39/32
[11:33:58.705] INFO: ROC 2 masking pixel 39/49
[11:33:58.705] INFO: ROC 2 masking pixel 39/53
[11:33:58.705] INFO: ROC 2 masking pixel 42/53
[11:33:58.705] INFO: ROC 3 masking pixel 1/51
[11:33:58.705] INFO: ROC 3 masking pixel 12/42
[11:33:58.705] INFO: ROC 3 masking pixel 33/23
[11:33:58.705] INFO: ROC 4 masking pixel 28/42
[11:33:58.705] INFO: ROC 4 masking pixel 49/24
[11:33:58.705] INFO: ROC 5 masking pixel 5/59
[11:33:58.705] INFO: ROC 5 masking pixel 15/57
[11:33:58.705] INFO: ROC 5 masking pixel 19/42
[11:33:58.705] INFO: ROC 5 masking pixel 33/55
[11:33:58.705] INFO: ROC 5 masking pixel 47/42
[11:33:58.705] INFO: ROC 6 masking pixel 20/53
[11:33:58.705] INFO: ROC 6 masking pixel 20/57
[11:33:58.705] INFO: ROC 6 masking pixel 21/51
[11:33:58.705] INFO: ROC 6 masking pixel 25/57
[11:33:58.705] INFO: ROC 6 masking pixel 27/59
[11:33:58.705] INFO: ROC 6 masking pixel 30/55
[11:33:58.705] INFO: ROC 6 masking pixel 31/48
[11:33:58.705] INFO: ROC 6 masking pixel 32/51
[11:33:58.705] INFO: ROC 6 masking pixel 35/47
[11:33:58.705] INFO: ROC 6 masking pixel 45/57
[11:33:58.706] INFO: ROC 6 masking pixel 49/51
[11:33:58.706] INFO: ROC 8 masking pixel 43/53
[11:33:58.706] INFO: ROC 8 masking pixel 43/54
[11:33:59.313] INFO: Expecting 208000 events.
[11:34:16.161] INFO: 208000 events read in total (16321ms).
[11:34:16.169] INFO: Test took 17463ms.
[11:34:16.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:17.072] INFO: number of dead pixels (per ROC): 7 1 12 5 2 5 11 0 2 0 0 0 0 0 0 0
[11:34:17.072] INFO: number of red-efficiency pixels: 681 398 762 1374 1351 1612 2027 1047 945 1119 1351 1028 701 623 120 131
[11:34:17.072] INFO: number of X-ray hits detected: 241081 162540 261554 405646 419569 431930 446761 310562 306551 377460 365906 310154 327170 196511 88012 91633
[11:34:17.072] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:34:17.072] INFO: number of Vcal hits detected: 206801 207484 206378 205907 206092 205424 204298 206500 206784 206637 206223 206698 207203 207193 207874 207862
[11:34:17.072] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.6 99.2 99.2 99.0 98.6 99.4 99.5 99.4 99.2 99.5 99.7 99.6 99.9 99.9
[11:34:17.072] INFO: Vcal hit overall efficiency (%): 99.4 99.8 99.2 99.0 99.1 98.8 98.2 99.3 99.4 99.3 99.1 99.4 99.6 99.6 99.9 99.9
[11:34:17.072] INFO: X-ray hit rate [MHz/cm2]: 70.7 47.6 76.7 118.9 123.0 126.6 130.9 91.0 89.9 110.6 107.2 90.9 95.9 57.6 25.8 26.9
[11:34:17.072] INFO: PixTestHighRate::doXPixelAlive() done
[11:34:17.118] INFO: PixTest:: pg_setup set to default.
[11:34:17.135] INFO: enter test to run
[11:34:30.206] INFO: test: exit no parameter change
[11:34:30.589] QUIET: Connection to board 33 closed.
[11:34:30.590] INFO: pXar: this is the end, my friend