[12:00:43.582] INFO: *** Welcome to pxar ***
[12:00:43.582] INFO: *** Today: 2016/05/17
[12:00:43.624] INFO: *** Version: v1.9.0-796-gef167-dirty
[12:00:43.624] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//dacParameters35_C15.dat
[12:00:43.645] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:00:43.645] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[12:00:43.652] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[12:00:43.737] INFO: clk: 4
[12:00:43.737] INFO: ctr: 4
[12:00:43.737] INFO: sda: 19
[12:00:43.737] INFO: tin: 9
[12:00:43.737] INFO: level: 15
[12:00:43.737] INFO: triggerdelay: 0
[12:00:43.737] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[12:00:43.737] INFO: Log level: INFO
[12:00:43.756] QUIET: Connection to board DTB_WREKRL opened.
[12:00:43.759] 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:
------------------------------------------------------
[12:00:43.762] INFO: RPC call hashes of host and DTB match: 398089610
[12:00:45.289] INFO: DUT info:
[12:00:45.289] INFO: The DUT currently contains the following objects:
[12:00:45.289] INFO: 2 TBM Cores tbm08c (2 ON)
[12:00:45.289] INFO: TBM Core alpha (0): 7 registers set
[12:00:45.290] INFO: TBM Core beta (1): 7 registers set
[12:00:45.290] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:00:45.290] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.290] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:00:45.694] INFO: enter 'restricted' command line mode
[12:00:45.694] INFO: enter test to run
[12:01:06.308] INFO: test: PixelAlive no parameter change
[12:01:06.308] INFO: running: pixelalive
[12:01:06.317] INFO: ----------------------------------------------------------------------
[12:01:06.317] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:01:06.317] INFO: ----------------------------------------------------------------------
[12:01:06.632] INFO: Expecting 41600 events.
[12:01:10.970] INFO: 41600 events read in total (3619ms).
[12:01:11.137] INFO: Test took 4818ms.
[12:01:11.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:11.414] INFO: PixTestAlive::aliveTest() done
[12:01:11.414] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 3 2 15 0 0 0 0 0 0
[12:01:11.445] INFO: enter test to run
[12:01:40.651] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:01:40.651] INFO: running: highrate
[12:01:40.651] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:01:40.859] INFO: ----------------------------------------------------------------------
[12:01:40.860] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:01:40.860] INFO: ----------------------------------------------------------------------
[12:01:40.860] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:01:40.860] INFO: edge/corner pixel THR is adjusted
[12:01:40.860] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:01:41.817] INFO: Collecting data for 5 seconds...
[12:01:46.835] INFO: Done with hot pixel readout
[12:01:47.134] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (109) != TBM ID (111)
[12:01:47.134] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (109) != TBM ID (111)
[12:01:47.134] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:01:47.134] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (112) != TBM ID (110)
[12:01:47.134] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (112) != TBM ID (110)
[12:01:47.134] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 43c8 851 2a48 43c8 43c8 43c8 d 2449 43c9 43c9 43c9 612 284f 43c9 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 4388 4388 4388 4d 2649 698 262e 4388 698 284d 4388 4388 4388 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 80ff 43c8 2da 2444 43c8 43c8 43c8 45a 2465 43c8 43c8 2cd 2843 342 2662 43c8 43c8 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 43c8 43c8 43c8 84b 226f 43c8 8d 2049 43c8 43c8 495 2663 43c8 60d 2a67 809 2246 853 226b 43c8 299 2842 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 43ca 43ca 43ca 43ca 43ca 43ca 43ca 43ca 348 2827 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 43c8 43c8 43c8 43c8 149 2a4d 43c8 85b 2a67 43c8 209 202b 43c8 43c8 e000 c000
[12:01:47.134] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 43c8 43c8 43c8 43c8 43c9 43c9 43c9 43c9 e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 74b 2464 4388 4388 302 2043 4388 d1 2041 4388 4388 4388 4388 e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 4388 4388 4dd 2465 4388 4388 4388 4388 4388 4388 e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 80fe 43c8 43c8 43c8 412 2a4c 750 2449 750 2642 43c8 43c8 43c8 43c8 e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 43c8 402 2a48 512 2665 43c8 43c8 43c8 43c8 43c8 43c8 653 2269 43ca e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 43ca 43ca 43ca d5 224f 2e0 2464 43ca 43ca 855 2a49 43ca 43ca 43c9 e000 c000
[12:01:47.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 43c9 43c9 43c9 43c9 43c9 43c9 43c9 43c9 e000 c000
[12:01:58.458] INFO: PixTest:: pg_setup set to default.
[12:01:58.458] INFO: 9 hot pixels found in step 0
[12:01:59.469] INFO: Collecting data for 5 seconds...
[12:02:04.486] INFO: Done with hot pixel readout
[12:02:15.856] INFO: PixTest:: pg_setup set to default.
[12:02:15.857] INFO: 8 hot pixels found in step 1
[12:02:16.844] INFO: Collecting data for 5 seconds...
[12:02:21.861] INFO: Done with hot pixel readout
[12:02:22.150] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 7 ROCs were found
[12:02:22.170] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (52) != TBM ID (195)
[12:02:22.170] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c6 8000 43c8 43c8 43c8 43c8 68d 244d 43c8 9a 2845 43c8 43c8 720 2040 43c8 e000 c000
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 4388 4388 45b 2249 4388 4388 4389 4d2 284f 4389 4389 2a2 2a4f 4389 e000 c000
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0c2 8000 4388 12 2a48 4388 d8 2825 4389 4389 4388 d1 2842 4388 359 2249 43c9 9a 304f
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a033 80f0 f8 20e0 0 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c3 8040 43c8 151 242f 43c9 43c8 660 264c 43c9 d3 2a4a 14a 284b 821 2268 43c8 da 2445 4cc 2244 848 2248 848 2647 43c9 43c8 43c9 e000 c000
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c4 80b1 43c8 14d 2a2e 554 2242 43c8 43c8 43c8 75d 284f 43c8 43c8 43c8 853 2a6a 43c8 d0 2227 e000 c000
[12:02:22.170] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c5 80c0 43c8 14 2846 11 2246 854 2a81 43c8 43c8 442 284f 43c8 249 222f 54a 2669 43c8 804 2a40 43c8 43c8 808 262d 43c8 e000 c000
[12:02:33.349] INFO: PixTest:: pg_setup set to default.
[12:02:33.350] INFO: 5 hot pixels found in step 2
[12:02:34.339] INFO: Collecting data for 5 seconds...
[12:02:39.356] INFO: Done with hot pixel readout
[12:02:50.433] INFO: PixTest:: pg_setup set to default.
[12:02:50.434] INFO: 6 hot pixels found in step 3
[12:02:51.422] INFO: Collecting data for 5 seconds...
[12:02:56.441] INFO: Done with hot pixel readout
[12:03:07.957] INFO: PixTest:: pg_setup set to default.
[12:03:07.958] INFO: 2 hot pixels found in step 4
[12:03:08.946] INFO: Collecting data for 5 seconds...
[12:03:13.965] INFO: Done with hot pixel readout
[12:03:25.196] INFO: PixTest:: pg_setup set to default.
[12:03:25.197] INFO: 5 hot pixels found in step 5
[12:03:26.185] INFO: Collecting data for 5 seconds...
[12:03:31.203] INFO: Done with hot pixel readout
[12:03:42.758] INFO: PixTest:: pg_setup set to default.
[12:03:42.759] INFO: 5 hot pixels found in step 6
[12:03:43.746] INFO: Collecting data for 5 seconds...
[12:03:48.771] INFO: Done with hot pixel readout
[12:03:49.092] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[12:03:49.092] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04a 8000 43c8 691 202c 43c8 43c8 449 2060 55a 2443 43c8 41b 2849 43c8 43c8 c8 2a26 4d2 2645 43c8 43c8 e000 c000
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a044 80b1 4388 4388 51 2840 14b 2065 4388 710 284d 4388 4388 4388 4388 640 2a2f 4388 e000 c000
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a045 80c0 4388 4388 93 2a42 4388 4388 4388 41d 224c 4388 4388 4388 e000 c000
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a046 8000 4388 4388 4388 4388 4388 4388 b 2042 4388 4388 459 2446 e000 c000
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a047 8040 4388 4388 4388 810 2a46 4388 4388 4388 489 2a4d 4388 24c 282d 43c8 348 2445 15e 2000 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a048 80b1 43c8 21 2062 43c8 43c8 43c8 695 2262 43c8 10 2842 43c8 43c8 221 2662 60b 2a88 43c8 e000 c000
[12:03:49.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a049 80c0 43c8 43c8 43c8 260 284d 43c8 2c8 222a 845 2a49 43c8 43c8 43c8 74c 2a28 43c8 160 2443 e000 c000
[12:03:55.828] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:03:55.828] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:03:55.828] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a077 8040 43c8 43c8 494 242d 43c8 43c8 210 2a28 43c8 43c8 43c8 84c 224f 43c8 853 2682 e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 4388 4388 4388 4388 141 2a45 4389 4389 704 2245 4389 4389 e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 4388 4388 4389 4389 4388 4388 4389 14a 204a 4389 282 244c e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a073 8040 4388 4389 4388 55a 2046 548 2040 4389 14d 2249 4388 4389 4388 4389 e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b0 ff8 204d 80 43c8 43c8 43c8 85a 2a4f 43c8 43c8 43c8 43c8 e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a075 80c0 43c8 43c8 43c8 43c8 43c8 43c8 49c 206b 43c8 43c8 454 2a2a e000 c000
[12:03:55.828] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a076 8000 43c8 43c8 43c8 21 264c 43c8 43c8 43c8 81a 2867 43c8 43c8 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 43c8 43c8 74c 242d 43c8 43c8 518 244f 43c8 151 2063 43c8 43c8 43c8 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 4388 6a1 2249 4388 4388 4388 4389 4389 350 246c 4389 4389 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 4388 4388 4389 a0 2865 4389 4388 4388 4389 819 2465 4389 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 4388 4389 4388 4389 20 286c 4388 708 282b 4389 30c 224a 4388 4388 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a174 80b0 10 2882 2880 424 43c8 43c8 43c8 452 2848 452 244f 43c8 43c8 43c8 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 43c8 553 284a 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:03:55.829] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a176 8000 43c8 43c8 43c8 43c8 43c8 43c8 2cd 2249 43c8 43c8 e000 c000
[12:04:00.409] INFO: PixTest:: pg_setup set to default.
[12:04:00.410] INFO: 4 hot pixels found in step 7
[12:04:01.398] INFO: Collecting data for 5 seconds...
[12:04:06.418] INFO: Done with hot pixel readout
[12:04:07.850] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:04:07.851] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 4388 48a 2844 4388 4388 34a 202d 760 264c 4388 309 284f 4388 4388 4388 4388 28a 260f e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 43c9 50a 2042 43c9 43c9 43c9 43c9 43c9 43c9 43c9 e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 43c8 64d 2460 43c8 43c8 43c8 43c9 43c9 43c9 43c9 55c 2a41 e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 43c8 43c8 43c9 43c9 43c8 89 2045 43c8 4b 2848 43c9 43c9 e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8068 15c 226e 21 4389 4388 4389 455 2840 4388 4389 4388 4388 5c 202f e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 4388 4388 4388 4388 4388 4388 9 2841 4388 4388 e000 c000
[12:04:07.851] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 4388 4388 4388 253 2a4f 641 266e 761 204b 4388 4388 4388 4388 4388 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (4) != Token Chain Length (8)
[12:04:10.227] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:04:10.227] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 4388 4388 4388 81b 264f 4388 4389 4389 4389 4389 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02b 8040 43c8 43c8 43c8 43c8 43c8 308 2221 815 2664 43c8 43c8 43c8 d5 242c 4c9 242a e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02c 80b1 43c8 43c8 159 262d 43c8 43c8 759 2a47 43c8 611 2a6a 43c8 43c8 64a 2a45 43c8 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 403 2a65 43c8 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02e 8000 43c8 154 2629 43c8 4388 4cc 2445 5388 517 2dbe c2a 2981 d 2558 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 438a 438a 438a 615 2269 438a 438a 318 2a45 438a 722 2a4f 438a 4d4 2247 71c 2841 438a 20a 246b e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 4388 4388 4388 4388 4388 2ca 284d 694 2449 4388 20 2043 10d 2242 4388 4388 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 4388 4388 153 284d 4388 4388 4389 304 2228 4389 4389 81b 284f 4389 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12b 8040 43c8 248 2025 6d5 2662 43c8 43c8 43c8 43c8 60a 2641 689 202d 43c8 20a 224a 43c8 43c8 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12c 80b1 43c8 43c8 43c8 43c8 43c8 43c8 30b 2867 43c8 43c8 e000 c000
[12:04:10.227] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c0 43c8 4 280f 43c8 43c8 54c 2840 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:10.228] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12e 8000 43c8 43c8 65b 2447 4388 dc 264a 538a 842 2241 8c0 213e 7c2 2254 4388 438a e000 c000
[12:04:10.228] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 438a 438a 438a 438a 53 282d 438a 315 2669 438a 438a 4389 681 2042 681 2241 e000 c000
[12:04:10.228] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 4389 4389 4389 4389 143 2061 718 2242 4389 853 2245 4389 4389 4389 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[12:04:12.529] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 4388 4388 4388 4388 4388 4388 708 2442 4388 4388 253 246d 543 2260 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b6 8000 43c8 43c8 43c8 43c8 711 264d 43c8 43c8 43c8 43c8 d0 2a21 122 204d e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b7 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b8 80b1 43c8 43c8 293 2247 43c8 43c8 43c8 43c8 548 2a27 43c8 43c8 6c2 264f e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b9 80c0 43c8 43c8 43c8 43c8 142 282c 6c9 282d 819 2a4f 43c8 19 244f 4388 4388 84b 2480 100 2001 a08 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ba 8000 4388 2a0 2245 4388 4388 4388 4388 4388 4388 713 264f 4388 83 2042 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 4388 4388 4388 4388 4388 4388 4388 cc 2a2f 4388 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4388 4388 4388 6c0 282d 4388 4388 4388 4388 4388 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b6 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b7 8040 43c8 35a 2642 43c8 43c8 43c8 43c8 82 2648 43c8 43c8 43c8 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b8 80b1 43c8 43c8 43c8 209 2a42 510 2626 43c8 a2 204f 43c8 43c8 43c8 43c8 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b9 80c0 43c8 35b 2846 418 264e 810 2245 43c8 43c8 43c8 43c8 43c8 4388 4388 e000 d002
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ba 8000 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:04:12.529] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 4388 4388 4388 4388 449 244e 443 2a4f 4388 485 226f 4388 4388 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (5) != Token Chain Length (8)
[12:04:16.261] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[12:04:16.261] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:04:16.261] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 43c8 43c8 43c8 653 204c 43c8 43c9 43c9 90 2a45 43c9 355 2049 43c9 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4388 4388 4388 4388 304 202d 4388 404 2a40 4388 4388 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 4388 4388 285 246d 4388 4388 48a 224d 4388 4388 119 2a49 4388 559 2241 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4388 4388 4388 4388 4388 4388 705 2a2f 4388 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 43c8 43c8 ff9 2ff0 513 24c0 eb4 43c8 43c8 280 2626 6ca 2044 43c8 54a 2646 43c8 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 43ca 53 206d 74b 202f 43ca 43ca 695 284f 752 2a49 43ca 43ca 43ca 219 2848 43ca 43ca e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 43c8 43c8 314 262d 45c 2648 495 2a46 43c8 43c8 43c8 752 2447 43c8 31b 2262 43c8 15a 2041 622 2480 43c8 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 43c8 43c8 109 2243 43c8 150 222e 43c8 43c9 43c9 43c9 43c9 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4388 4388 48c 2828 4388 4388 308 2227 4388 e2 2445 292 244f 4388 4388 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4388 313 2a45 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4388 4388 4388 4388 4388 4388 4388 4388 da 2425 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 43c8 43c8 43c8 10 43c8 43c8 43c8 43c8 43ca e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 43ca 43ca 644 2461 43ca 2c1 2443 43ca 43ca 43ca 43c9 6a1 2848 e000 c000
[12:04:16.261] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 43c9 43c9 15 2445 43c9 121 2a64 43c9 43c9 2c1 2247 43c9 30c 2649 43c9 43c9 e000 c000
[12:04:18.121] INFO: PixTest:: pg_setup set to default.
[12:04:18.122] INFO: 7 hot pixels found in step 8
[12:04:19.109] INFO: Collecting data for 5 seconds...
[12:04:24.127] INFO: Done with hot pixel readout
[12:04:25.588] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:04:25.588] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01c 80b1 43c8 51a 262f 43c8 43c8 43c8 43c8 43c8 43c8 15d 2641 43c8 10 2627 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 4388 4388 4388 4388 453 2082 4388 4388 4388 415 202f 4388 d3 2441 411 2240 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 4388 4388 4388 4388 88 2244 6d3 204a 4388 4388 51a 2440 645 2842 810 2225 4388 4388 58 204d e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 4388 6d8 2a6c 4388 4388 4388 10c 2049 458 2249 4388 649 2444 4388 22 2041 4388 4388 91 2443 6c2 2464 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 4388 4388 45a 2a45 4388 754 2a4c 4388 6db 2861 4388 4388 4388 4388 f000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01a 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 10 282f 6e2 2881 43c8 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01b 8040 43c8 92 244c 43c8 43c8 43c8 43c8 43c8 43c8 30b 2045 43c8 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11c 80b1 43c8 43c8 35d 264f 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 4388 4388 35a 242d 4db 2a2e 4388 24a 244b 4388 4388 4388 4388 4388 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 4388 4388 4388 4388 4388 109 284c 4388 4388 4388 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4388 12 2229 352 244d 712 2241 4388 4388 4a 2249 761 244f 43c8 658 2041 43c8 7f0 2805 43c8 43c8 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:25.588] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11b 8040 43c8 43c8 43c8 24a 2865 43c8 35a 2041 43c8 9a 2445 43c8 43c8 43c8 e000 c000
[12:04:26.272] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:04:26.272] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[12:04:26.272] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:26.272] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a079 80c0 43c8 9c 2846 43c8 43c8 43c8 43c8 43c8 60b 2049 43c8 43c8 51 2044 2e1 226c e000 c000
[12:04:26.272] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a073 8040 4388 4389 4388 458 284f 498 284e 60c 2685 4389 40a 2242 4388 4389 4388 4389 e000 c000
[12:04:26.272] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 4388 18 2065 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:04:26.272] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a075 80c0 4388 4388 4388 4388 318 2449 4388 4388 4388 85c 2644 4388 502 2241 e000 c000
[12:04:26.272] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a076 8000 4388 4388 4388 130c 2241 43c8 200 2f33 ff8 43c8 759 2a40 43c8 145 262d 2da 264f 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a077 8040 43c8 43c8 43c8 43c8 43c8 43c8 51 222d 43c8 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a078 80b1 43c8 43c8 43c8 43c8 43c8 141 2866 609 2249 43c8 43c8 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a179 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 4388 4389 4388 4389 4388 2da 244f 2da 2061 705 2845 4389 4388 4388 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a174 80b1 4388 4388 4388 4388 11a 2a49 4388 5d 2446 4388 4388 4388 6c3 2a49 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 4388 715 2a49 4388 760 262f 760 2a45 4388 4388 689 284f 4388 342 2a26 4388 4388 4388 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a176 8000 4388 14 2429 53c8 43c8 259 2655 82 2070 c13 249e e20 43c8 211 224d 43c8 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 43c8 853 2460 43c8 43c8 43c8 43c8 43c8 43c8 521 2046 43c8 e000 c000
[12:04:26.273] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a178 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4388 4388 4388 4388 4388 4388 81b 2a4e 4388 4388 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 43c8 43c8 dd 2a2f 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e7 8040 43c8 e1 2089 43c8 43c8 43c8 43c8 5c 262f 43c8 43c8 345 224d 43c8 261 284b e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 43c8 43c8 43c8 43c8 213 2046 43c8 60c 2a48 43c8 db 204b 43c8 6dd 2868 43c8 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 43c8 43c8 43c8 43c8 43c8 43c8 849 2866 4388 5388 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 4388 4388 4388 4388 205 2a2f 4388 4388 722 2265 4388 53 244f 10b 282f 4388 e000 c000
[12:04:31.574] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 4388 4388 4388 6e2 2665 4388 4388 820 224d 4388 4388 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (46) != TBM ID (47)
[12:04:35.216] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[12:04:35.216] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[12:04:35.216] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (0) != Token Chain Length (8)
[12:04:35.216] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (46) != TBM ID (47)
[12:04:35.216] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 43c8 43c8 262 284f 43c9 43c9 43c8 43c8 43c9 43c9 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02b 8040 4388 4388 4388 604 2445 4388 4388 4388 4388 358 2a43 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02c 80b1 4388 4388 4388 4388 615 224c 4388 4388 4388 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 4388 611 2260 4388 4388 4388 119 2a4a 4388 4388 4388 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 43ca 43ca 43ca 43ca 151 204c 43ca 14 2245 43ca 43ca 43ca e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 43c8 43c8 43c8 848 2a4f 43c8 43c8 43c8 43c8 dd 2044 2d3 2a65 84a 2669 43c8 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 43c8 43c8 43c8 43c8 43c9 690 2a49 43c9 4 2840 13 2a4f 20b 204c 24b 2849 84c 224f 43c9 345 204a 43c9 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 43c8 6d8 2a2c 43c8 43c9 43c9 43c8 43c8 13 202b 43c9 43c9 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12c 80b1 4388 4388 4388 4388 4388 213 2047 4388 4388 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c0 4388 4388 4388 14b 2429 4388 4388 4388 4a2 2065 4388 4388 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a154 c008
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 43ca 43ca 43ca 43ca d 244f 442 2248 43ca a1 242c a1 2629 449 284d 43ca 43ca 43c9 6a0 204c e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 43c9 43c9 43c9 43c9 43c9 43c9 43c9 43c9 351 2265 e000 c000
[12:04:35.216] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 43c8 43c8 43c8 43c8 43c9 480 2448 43c9 43c9 43c9 e000 c000
[12:04:35.885] INFO: PixTest:: pg_setup set to default.
[12:04:35.886] INFO: 3 hot pixels found in step 9
[12:04:36.874] INFO: Collecting data for 5 seconds...
[12:04:41.891] INFO: Done with hot pixel readout
[12:04:51.536] WARNING: Channel 0 ROC 7: Readback start marker after 2 readouts!
[12:04:51.536] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[12:04:51.536] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a094 80b1 43c8 43c8 43c8 43c8 43c8 43c8 418 264f 43c8 43c8 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08e 8000 4388 4388 4388 4388 694 2245 4388 30a 2045 4388 4388 4388 34c 202d e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08f 8040 438a 438a 438a 40c 222c 438a 438a 8b 2a45 438a 438a 438a e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a090 80b1 4388 660 2249 4388 4388 4388 4388 4388 51a 202c 4388 4388 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a091 80c0 4388 4388 410 2445 4388 64d 2049 4388 4389 4389 45d 244b 53c9 28a 2a63 43ca e 2000 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a092 8000 43c8 43c8 43c9 43c9 43c8 2d9 2045 43c8 43c9 cc 242f 43c9 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a093 8040 43c8 159 2a2e 43c9 43c8 43c9 43c8 43c9 43c8 43c9 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a194 80b1 43c8 695 2640 74b 2a64 43c8 514 244c 514 204d 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18e 8000 4388 6d8 2a4f 4388 4388 4388 4388 21a 2a69 4388 282 2248 4388 438a 4c3 286c e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18f 8040 438a 2e1 204f 438a 438a 438a 451 2047 438a 438a 14b 2665 438a 4389 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a190 80b1 4389 4389 4389 48b 286f 4389 118 2a40 4389 4389 4389 4389 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a191 80c0 4388 4388 10a 2a49 4388 342 2a4d 4388 4389 4389 4389 4389 f000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a192 8000 43c8 10a 206d 43c8 43c9 43c9 81d 262f 43c8 43c8 43c9 85d 244b 43c9 e000 c000
[12:04:51.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a193 8040 43c8 43c9 91 202a 90 2843 43c8 43c9 43c8 209 2440 43c9 43c8 43c8 e000 c000
[12:04:53.480] INFO: PixTest:: pg_setup set to default.
[12:04:53.481] INFO: 3 hot pixels found in step 10
[12:04:54.468] INFO: Collecting data for 5 seconds...
[12:04:59.485] INFO: Done with hot pixel readout
[12:04:59.728] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[12:04:59.728] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[12:04:59.728] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (36) != TBM ID (37)
[12:04:59.728] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a127 8040 43c8 451 244f 43c8 43c8 43c8 44a 2245 43c8 2cd 264f 43c8 43c8 43c8 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a121 80c0 4388 4388 4388 4388 4389 4389 359 2a6f 4389 4389 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a122 8000 4388 4388 4389 245 2a4b 720 286d 4389 4388 4388 351 2269 4389 4389 213 2645 213 2a66 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a123 8040 4388 4389 4388 4389 4388 4389 13 2240 311 284f 4388 4388 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a124 8098 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a125 80c0 43c8 9b 2a49 43c8 43c8 10c 2044 43c8 43c8 298 2445 43c8 43c8 43c8 513 242f e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a126 8000 43c8 852 2246 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a028 80b1 43c8 43c8 43c8 658 2840 43c8 43c8 43c8 43c8 43c8 e2 262e 804 2a2d e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a022 8000 4388 4388 4389 4389 4388 11a 244a 84c 2264 4388 4389 4389 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a023 8040 4388 4389 4388 4389 741 244c 4388 4389 288 2861 4388 4389 24b 2849 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 c00d
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 43c8 43c8 43c8 43c8 43c8 2e0 2845 35d 244d 43c8 30d 2645 30d 2a4e 43c8 43c8 508 222f e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a026 8000 43c8 510 2441 43c8 43c8 653 2042 43c8 43c8 412 224f 862 204f 84a 244a 43c8 65c 2642 43c8 43c8 e000 c000
[12:04:59.729] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a027 8040 43c8 43c8 43c8 43c8 92 264f 43c8 43c8 43c8 43c8 e000 c000
[12:05:10.992] INFO: PixTest:: pg_setup set to default.
[12:05:10.992] INFO: 3 hot pixels found in step 11
[12:05:11.980] INFO: Collecting data for 5 seconds...
[12:05:16.996] INFO: Done with hot pixel readout
[12:05:28.588] INFO: PixTest:: pg_setup set to default.
[12:05:28.589] INFO: 1 hot pixels found in step 12
[12:05:29.576] INFO: Collecting data for 5 seconds...
[12:05:34.593] INFO: Done with hot pixel readout
[12:05:46.134] INFO: PixTest:: pg_setup set to default.
[12:05:46.135] INFO: 2 hot pixels found in step 13
[12:05:47.122] INFO: Collecting data for 5 seconds...
[12:05:52.138] INFO: Done with hot pixel readout
[12:06:03.712] INFO: PixTest:: pg_setup set to default.
[12:06:03.713] INFO: 1 hot pixels found in step 14
[12:06:03.744] INFO: 1 hot pixels could not be trimmed and have been masked.
[12:06:03.747] INFO: PixTest::trimHotPixels() done
[12:06:03.752] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat
[12:06:03.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C1.dat
[12:06:03.763] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C2.dat
[12:06:03.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C3.dat
[12:06:03.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C4.dat
[12:06:03.779] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C5.dat
[12:06:03.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C6.dat
[12:06:03.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C7.dat
[12:06:03.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C8.dat
[12:06:03.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C9.dat
[12:06:03.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C10.dat
[12:06:03.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C11.dat
[12:06:03.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C12.dat
[12:06:03.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C13.dat
[12:06:03.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C14.dat
[12:06:03.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[12:06:03.837] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[12:06:03.854] INFO: enter test to run
[12:06:32.697] INFO: test: timing no parameter change
[12:06:32.697] INFO: running: timing
[12:06:32.711] INFO: ######################################################################
[12:06:32.711] INFO: PixTestTiming::doTest()
[12:06:32.720] INFO: ######################################################################
[12:06:32.720] INFO: ----------------------------------------------------------------------
[12:06:32.720] INFO: PixTestTiming::TBMPhaseScan()
[12:06:32.721] INFO: ----------------------------------------------------------------------
[12:10:42.956] INFO: TBM Phase Settings: 240
[12:10:42.957] INFO: 400MHz Phase: 4
[12:10:42.957] INFO: 160MHz Phase: 7
[12:10:42.957] INFO: Functional Phase Area: 4
[12:10:42.966] INFO: Test took 250246 ms.
[12:10:42.966] INFO: PixTestTiming::TBMPhaseScan() done.
[12:10:42.966] INFO: ----------------------------------------------------------------------
[12:10:42.966] INFO: PixTestTiming::ROCDelayScan()
[12:10:42.966] INFO: ----------------------------------------------------------------------
[12:12:33.981] INFO: ROC Delay Settings: 228
[12:12:33.981] INFO: ROC Header-Trailer/Token Delay: 11
[12:12:33.981] INFO: ROC Port 0 Delay: 4
[12:12:33.981] INFO: ROC Port 1 Delay: 4
[12:12:33.981] INFO: Functional ROC Area: 3
[12:12:33.984] INFO: Test took 111018 ms.
[12:12:33.984] INFO: PixTestTiming::ROCDelayScan() done.
[12:12:33.985] INFO: ----------------------------------------------------------------------
[12:12:33.985] INFO: PixTestTiming::TimingTest()
[12:12:33.985] INFO: ----------------------------------------------------------------------
[12:12:50.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:05.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:20.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:35.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:50.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:05.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:20.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:35.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:50.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:06.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:06.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: Read back bit status: 1
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: Timings are good!
[12:15:06.408] INFO: ----------------------------------------------------------------------
[12:15:06.408] INFO: Test took 152424 ms.
[12:15:06.408] INFO: PixTestTiming::TimingTest() done.
[12:15:06.423] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0a.dat
[12:15:06.423] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:15:06.424] INFO: PixTestTiming::doTest took 513716 ms.
[12:15:06.424] INFO: PixTestTiming::doTest() done
[12:15:06.424] INFO: Write out TBMPhaseScan_0_V0
[12:15:06.424] INFO: Write out TBMPhaseScan_1_V0
[12:15:06.424] INFO: Write out CombinedTBMPhaseScan_V0
[12:15:06.457] INFO: Write out ROCDelayScan3_V0
[12:15:06.457] INFO: enter test to run
[12:15:49.317] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:15:49.317] INFO: running: highrate
[12:15:49.322] INFO: ----------------------------------------------------------------------
[12:15:49.322] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:15:49.322] INFO: ----------------------------------------------------------------------
[12:15:49.322] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:15:49.322] INFO: edge/corner pixel THR is adjusted
[12:15:49.322] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:15:50.280] INFO: Collecting data for 5 seconds...
[12:15:55.300] INFO: Done with hot pixel readout
[12:16:07.010] INFO: PixTest:: pg_setup set to default.
[12:16:07.011] INFO: 0 hot pixels found in step 0
[12:16:07.053] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:16:07.057] INFO: PixTest::trimHotPixels() done
[12:16:07.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat
[12:16:07.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C1.dat
[12:16:07.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C2.dat
[12:16:07.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C3.dat
[12:16:07.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C4.dat
[12:16:07.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C5.dat
[12:16:07.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C6.dat
[12:16:07.097] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C7.dat
[12:16:07.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C8.dat
[12:16:07.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C9.dat
[12:16:07.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C10.dat
[12:16:07.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C11.dat
[12:16:07.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C12.dat
[12:16:07.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C13.dat
[12:16:07.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C14.dat
[12:16:07.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[12:16:07.145] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[12:16:07.161] INFO: enter test to run
[12:16:49.348] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:16:49.348] INFO: running: highrate
[12:16:49.353] INFO: ----------------------------------------------------------------------
[12:16:49.354] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:16:49.354] INFO: ----------------------------------------------------------------------
[12:16:49.354] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:16:49.354] INFO: edge/corner pixel THR is adjusted
[12:16:49.354] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:16:50.312] INFO: Collecting data for 1 seconds...
[12:16:51.316] INFO: Done with hot pixel readout
[12:16:55.262] INFO: PixTest:: pg_setup set to default.
[12:16:55.263] INFO: 0 hot pixels found in step 0
[12:16:55.268] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:16:55.363] INFO: PixTest::trimHotPixels() done
[12:16:55.363] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C0.dat
[12:16:55.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C1.dat
[12:16:55.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C2.dat
[12:16:55.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C3.dat
[12:16:55.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C4.dat
[12:16:55.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C5.dat
[12:16:55.398] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C6.dat
[12:16:55.403] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C7.dat
[12:16:55.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C8.dat
[12:16:55.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C9.dat
[12:16:55.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C10.dat
[12:16:55.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C11.dat
[12:16:55.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C12.dat
[12:16:55.434] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C13.dat
[12:16:55.439] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C14.dat
[12:16:55.444] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//trimParameters35_C15.dat
[12:16:55.450] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-48_FPIXTest-17C-FNAL-160511-0909_2016-05-11_09h09m_1462975769/000_FPIXTest_p17//defaultMaskFile.dat
[12:16:55.466] INFO: enter test to run
[12:17:38.732] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:17:38.732] INFO: running: xray
[12:17:38.733] INFO: ----------------------------------------------------------------------
[12:17:38.733] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:17:38.733] INFO: ----------------------------------------------------------------------
[12:17:39.697] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:17:51.404] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:18:20.980] INFO: Resuming triggers.
[12:18:32.687] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:19:02.327] INFO: Resuming triggers.
[12:19:14.041] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:19:43.734] INFO: Resuming triggers.
[12:19:55.446] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:20:24.662] INFO: Resuming triggers.
[12:20:36.369] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:21:05.954] INFO: Resuming triggers.
[12:21:17.662] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:21:47.313] INFO: Resuming triggers.
[12:21:59.020] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:22:28.660] INFO: Resuming triggers.
[12:22:40.368] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:23:09.947] INFO: Resuming triggers.
[12:23:16.605] INFO: data taking finished, elapsed time: 100 seconds.
[12:23:33.639] INFO: PixTest:: pg_setup set to default.
[12:23:33.642] INFO: PixTestXray::doPhRun() done
[12:23:33.776] INFO: enter test to run
[12:24:11.849] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:24:11.849] INFO: running: xray
[12:24:11.851] INFO: ----------------------------------------------------------------------
[12:24:11.851] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:24:11.851] INFO: ----------------------------------------------------------------------
[12:24:12.814] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:24:19.661] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:24:50.526] INFO: Resuming triggers.
[12:24:57.374] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:25:27.790] INFO: Resuming triggers.
[12:25:34.635] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:26:05.979] INFO: Resuming triggers.
[12:26:12.827] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:26:44.471] INFO: Resuming triggers.
[12:26:51.320] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:27:21.684] INFO: Resuming triggers.
[12:27:28.533] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:27:58.876] INFO: Resuming triggers.
[12:28:05.724] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:28:36.042] INFO: Resuming triggers.
[12:28:42.890] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:29:13.257] INFO: Resuming triggers.
[12:29:20.104] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:29:50.492] INFO: Resuming triggers.
[12:29:57.342] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:30:27.368] INFO: Resuming triggers.
[12:30:34.219] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[12:31:04.348] INFO: Resuming triggers.
[12:31:11.199] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:31:41.526] INFO: Resuming triggers.
[12:31:48.376] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[12:32:18.706] INFO: Resuming triggers.
[12:32:25.555] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:32:55.863] INFO: Resuming triggers.
[12:33:00.327] INFO: data taking finished, elapsed time: 100 seconds.
[12:33:20.443] INFO: PixTest:: pg_setup set to default.
[12:33:20.446] INFO: PixTestXray::doPhRun() done
[12:33:20.594] INFO: enter test to run
[12:33:53.757] INFO: test: HighRate no parameter change
[12:33:53.757] INFO: running: highrate
[12:33:53.758] INFO: ----------------------------------------------------------------------
[12:33:53.758] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:33:53.758] INFO: ----------------------------------------------------------------------
[12:33:53.898] INFO: Expecting 768 events.
[12:33:55.032] INFO: 768 events read in total (418ms).
[12:33:55.032] INFO: Test took 1267ms.
[12:33:55.835] INFO: Expecting 41600 events.
[12:33:58.977] INFO: 41600 events read in total (2615ms).
[12:33:58.977] INFO: Test took 3937ms.
[12:33:59.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:59.741] INFO: Expecting 41600 events.
[12:34:02.939] INFO: 41600 events read in total (2672ms).
[12:34:02.940] INFO: Test took 3915ms.
[12:34:02.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:03.702] INFO: Expecting 41600 events.
[12:34:06.899] INFO: 41600 events read in total (2671ms).
[12:34:06.900] INFO: Test took 3910ms.
[12:34:06.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:07.661] INFO: Expecting 41600 events.
[12:34:10.868] INFO: 41600 events read in total (2680ms).
[12:34:10.869] INFO: Test took 3919ms.
[12:34:10.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:11.630] INFO: Expecting 41600 events.
[12:34:14.853] INFO: 41600 events read in total (2696ms).
[12:34:14.854] INFO: Test took 3935ms.
[12:34:14.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:15.614] INFO: Expecting 41600 events.
[12:34:18.837] INFO: 41600 events read in total (2696ms).
[12:34:18.838] INFO: Test took 3935ms.
[12:34:18.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:19.596] INFO: Expecting 41600 events.
[12:34:22.811] INFO: 41600 events read in total (2688ms).
[12:34:22.812] INFO: Test took 3925ms.
[12:34:22.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:23.573] INFO: Expecting 41600 events.
[12:34:26.804] INFO: 41600 events read in total (2704ms).
[12:34:26.805] INFO: Test took 3944ms.
[12:34:26.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:27.562] INFO: Expecting 41600 events.
[12:34:30.789] INFO: 41600 events read in total (2701ms).
[12:34:30.790] INFO: Test took 3937ms.
[12:34:30.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:31.549] INFO: Expecting 41600 events.
[12:34:34.771] INFO: 41600 events read in total (2695ms).
[12:34:34.772] INFO: Test took 3933ms.
[12:34:34.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:35.530] INFO: Expecting 41600 events.
[12:34:38.746] INFO: 41600 events read in total (2689ms).
[12:34:38.747] INFO: Test took 3925ms.
[12:34:38.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:39.507] INFO: Expecting 41600 events.
[12:34:42.733] INFO: 41600 events read in total (2700ms).
[12:34:42.734] INFO: Test took 3937ms.
[12:34:42.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:43.496] INFO: Expecting 41600 events.
[12:34:46.714] INFO: 41600 events read in total (2691ms).
[12:34:46.715] INFO: Test took 3932ms.
[12:34:46.746] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:47.476] INFO: Expecting 41600 events.
[12:34:50.710] INFO: 41600 events read in total (2707ms).
[12:34:50.711] INFO: Test took 3948ms.
[12:34:50.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:51.470] INFO: Expecting 41600 events.
[12:34:54.658] INFO: 41600 events read in total (2661ms).
[12:34:54.659] INFO: Test took 3898ms.
[12:34:54.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:55.420] INFO: Expecting 41600 events.
[12:34:58.603] INFO: 41600 events read in total (2656ms).
[12:34:58.604] INFO: Test took 3896ms.
[12:34:58.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:59.361] INFO: Expecting 41600 events.
[12:35:02.579] INFO: 41600 events read in total (2691ms).
[12:35:02.580] INFO: Test took 3926ms.
[12:35:02.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:03.341] INFO: Expecting 41600 events.
[12:35:06.575] INFO: 41600 events read in total (2707ms).
[12:35:06.576] INFO: Test took 3947ms.
[12:35:06.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:07.337] INFO: Expecting 41600 events.
[12:35:10.509] INFO: 41600 events read in total (2647ms).
[12:35:10.510] INFO: Test took 3884ms.
[12:35:10.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:11.272] INFO: Expecting 41600 events.
[12:35:14.246] INFO: 41600 events read in total (2447ms).
[12:35:14.246] INFO: Test took 3687ms.
[12:35:14.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:14.648] INFO: enter test to run
[12:35:34.100] INFO: test: HighRate no parameter change
[12:35:34.100] INFO: running: highrate
[12:35:34.101] INFO: ----------------------------------------------------------------------
[12:35:34.102] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:35:34.102] INFO: ----------------------------------------------------------------------
[12:35:34.724] INFO: Expecting 208000 events.
[12:35:46.619] INFO: 208000 events read in total (11368ms).
[12:35:46.621] INFO: Test took 12510ms.
[12:35:46.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:47.007] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 3 2 16 0 0 0 0 0 0
[12:35:47.007] INFO: number of red-efficiency pixels: 72 40 74 107 144 133 129 81 108 123 89 96 124 37 21 19
[12:35:47.007] INFO: number of X-ray hits detected: 63121 39917 63571 99025 108094 107944 112763 73766 70431 88409 88155 74727 79797 46275 20746 22928
[12:35:47.007] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:35:47.007] INFO: number of Vcal hits detected: 207928 207959 207925 207891 207850 207864 207868 207770 207793 207073 207910 207901 207875 207962 207979 207981
[12:35:47.007] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[12:35:47.007] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.6 100.0 100.0 99.9 100.0 100.0 100.0
[12:35:47.007] INFO: X-ray hit rate [MHz/cm2]: 18.5 11.7 18.6 29.0 31.7 31.6 33.1 21.6 20.6 25.9 25.8 21.9 23.4 13.6 6.1 6.7
[12:35:47.007] INFO: PixTestHighRate::doXPixelAlive() done
[12:35:47.057] INFO: PixTest:: pg_setup set to default.
[12:35:47.069] INFO: enter test to run
[12:36:13.412] INFO: test: HighRate no parameter change
[12:36:13.412] INFO: running: highrate
[12:36:13.413] INFO: ----------------------------------------------------------------------
[12:36:13.413] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:36:13.413] INFO: ----------------------------------------------------------------------
[12:36:14.030] INFO: Expecting 208000 events.
[12:36:27.458] INFO: 208000 events read in total (12901ms).
[12:36:27.463] INFO: Test took 14044ms.
[12:36:27.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:28.047] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 3 2 16 0 0 0 0 0 0
[12:36:28.047] INFO: number of red-efficiency pixels: 224 92 239 328 533 520 403 252 237 322 269 282 265 113 52 49
[12:36:28.047] INFO: number of X-ray hits detected: 133506 84780 134486 210591 230172 229204 239530 156934 150743 188317 187646 159035 169970 98892 44719 49111
[12:36:28.047] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:36:28.047] INFO: number of Vcal hits detected: 207760 207903 207741 207638 207423 207435 207570 207583 207660 206858 207716 207704 207722 207883 207948 207950
[12:36:28.047] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:36:28.047] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.5 99.9 99.9 99.9 99.9 100.0 100.0
[12:36:28.047] INFO: X-ray hit rate [MHz/cm2]: 39.1 24.8 39.4 61.7 67.5 67.2 70.2 46.0 44.2 55.2 55.0 46.6 49.8 29.0 13.1 14.4
[12:36:28.047] INFO: PixTestHighRate::doXPixelAlive() done
[12:36:28.093] INFO: PixTest:: pg_setup set to default.
[12:36:28.109] INFO: enter test to run
[12:36:53.860] INFO: test: HighRate no parameter change
[12:36:53.860] INFO: running: highrate
[12:36:53.861] INFO: ----------------------------------------------------------------------
[12:36:53.861] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:36:53.861] INFO: ----------------------------------------------------------------------
[12:36:54.482] INFO: Expecting 208000 events.
[12:37:10.200] INFO: 208000 events read in total (15191ms).
[12:37:10.207] INFO: Test took 16336ms.
[12:37:10.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:10.996] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 3 2 16 0 0 0 0 0 0
[12:37:10.996] INFO: number of red-efficiency pixels: 482 185 595 792 1204 1201 1028 491 505 672 571 606 730 272 93 88
[12:37:10.996] INFO: number of X-ray hits detected: 204895 129705 206087 320660 350204 349312 365281 240479 230015 286949 285529 243275 259130 150848 68263 75164
[12:37:10.996] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:37:10.996] INFO: number of Vcal hits detected: 207441 207810 207264 207054 206444 206433 206717 207305 207351 206433 207360 207312 207170 207708 207906 207910
[12:37:10.996] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.3 99.3 99.4 99.8 99.7 99.7 99.7 99.7 99.6 99.9 100.0 100.0
[12:37:10.996] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.5 99.3 99.2 99.4 99.7 99.7 99.2 99.7 99.7 99.6 99.9 100.0 100.0
[12:37:10.996] INFO: X-ray hit rate [MHz/cm2]: 60.1 38.0 60.4 94.0 102.6 102.4 107.1 70.5 67.4 84.1 83.7 71.3 76.0 44.2 20.0 22.0
[12:37:10.996] INFO: PixTestHighRate::doXPixelAlive() done
[12:37:11.041] INFO: PixTest:: pg_setup set to default.
[12:37:11.051] INFO: enter test to run
[12:37:35.219] INFO: test: exit no parameter change
[12:37:35.572] QUIET: Connection to board 33 closed.
[12:37:35.573] INFO: pXar: this is the end, my friend