[15:10:23.951] INFO: *** Welcome to pxar ***
[15:10:23.951] INFO: *** Today: 2016/04/05
[15:10:23.967] INFO: *** Version: v1.9.0-793-ge521-dirty
[15:10:23.967] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//dacParameters35_C15.dat
[15:10:24.600] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:10:24.600] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[15:10:24.705] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[15:10:26.152] INFO: clk: 4
[15:10:26.152] INFO: ctr: 4
[15:10:26.153] INFO: sda: 19
[15:10:26.153] INFO: tin: 9
[15:10:26.153] INFO: level: 15
[15:10:26.153] INFO: triggerdelay: 0
[15:10:26.153] QUIET: Instanciating API for pxar v1.9.0+793~ge5211a7
[15:10:26.153] INFO: Log level: INFO
[15:10:26.173] QUIET: Connection to board DTB_WREKRL opened.
[15:10:26.176] 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:
------------------------------------------------------
[15:10:26.179] INFO: RPC call hashes of host and DTB match: 398089610
[15:10:27.712] INFO: DUT info:
[15:10:27.712] INFO: The DUT currently contains the following objects:
[15:10:27.712] INFO: 2 TBM Cores tbm08c (2 ON)
[15:10:27.712] INFO: TBM Core alpha (0): 7 registers set
[15:10:27.712] INFO: TBM Core beta (1): 7 registers set
[15:10:27.712] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:10:27.712] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:27.713] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:28.167] INFO: enter 'restricted' command line mode
[15:10:28.167] INFO: enter test to run
[15:10:36.565] INFO: test: PixelAlive no parameter change
[15:10:36.565] INFO: running: pixelalive
[15:10:36.574] INFO: ----------------------------------------------------------------------
[15:10:36.574] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:10:36.574] INFO: ----------------------------------------------------------------------
[15:10:36.888] INFO: Expecting 41600 events.
[15:10:41.200] INFO: 41600 events read in total (3593ms).
[15:10:41.366] INFO: Test took 4789ms.
[15:10:41.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:41.641] INFO: PixTestAlive::aliveTest() done
[15:10:41.641] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 5 0 1 0 0 0 0 0
[15:10:41.669] INFO: enter test to run
[15:11:16.429] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:11:16.429] INFO: running: highrate
[15:11:16.429] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:11:16.627] INFO: ----------------------------------------------------------------------
[15:11:16.627] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:11:16.627] INFO: ----------------------------------------------------------------------
[15:11:16.627] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:11:16.627] INFO: edge/corner pixel THR is adjusted
[15:11:16.627] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:11:17.586] INFO: Collecting data for 5 seconds...
[15:11:22.603] INFO: Done with hot pixel readout
[15:11:34.431] INFO: PixTest:: pg_setup set to default.
[15:11:34.432] INFO: 4 hot pixels found in step 0
[15:11:35.421] INFO: Collecting data for 5 seconds...
[15:11:40.439] INFO: Done with hot pixel readout
[15:11:52.227] INFO: PixTest:: pg_setup set to default.
[15:11:52.228] INFO: 3 hot pixels found in step 1
[15:11:53.223] INFO: Collecting data for 5 seconds...
[15:11:58.239] INFO: Done with hot pixel readout
[15:12:09.186] INFO: PixTest:: pg_setup set to default.
[15:12:09.187] INFO: 2 hot pixels found in step 2
[15:12:10.178] INFO: Collecting data for 5 seconds...
[15:12:15.196] INFO: Done with hot pixel readout
[15:12:27.037] INFO: PixTest:: pg_setup set to default.
[15:12:27.038] INFO: 2 hot pixels found in step 3
[15:12:28.030] INFO: Collecting data for 5 seconds...
[15:12:33.038] INFO: Done with hot pixel readout
[15:12:42.182] INFO: PixTest:: pg_setup set to default.
[15:12:42.183] INFO: 1 hot pixels found in step 4
[15:12:43.176] INFO: Collecting data for 5 seconds...
[15:12:48.193] INFO: Done with hot pixel readout
[15:12:48.482] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[15:12:48.482] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00f 8040 43ca 410 2a69 43ca 40b 224d 43ca 43ca 43c8 6c8 2840 43c8 43ca 109 2869 43ca e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a009 80c0 4388 4388 4388 4388 6d3 204f 4388 159 2865 4388 4388 4388 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00a 8000 4388 4388 4388 699 2849 4388 248 262c 4389 4388 709 2287 4388 11 2040 612 266d 4388 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00b 8040 4388 4388 4388 64a 2a6c 4388 4388 4388 4388 8 282d 541 2241 4388 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00c 80b1 4388 4388 4388 4388 4388 438a 4388 4388 e000 d000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00d 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 4b 2a40 35d 2263 43c8 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00e 8000 43c8 43c8 458 2640 43c8 43c8 43c8 43c9 43c8 43c8 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10f 8040 43ca 43ca 43ca 498 202f 43ca 355 2a6c 43c9 43ca 43c9 43ca e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a109 80c0 4388 321 246f 4388 4388 20a 2640 4388 14 2445 553 2649 4388 4388 4388 4388 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10a 8000 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10b 8040 4388 4388 4388 4388 4388 4388 220 282f 4388 4388 210 284a 611 2642 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10c 80b1 4388 4388 4388 4388 4388 35b 2684 4388 441 2845 43c8 651 2880 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10d 80c0 43c8 43c8 43c8 19 2027 43c8 43ca dd 2060 dc 2864 318 248c 840 244f 43c8 43c8 43c8 e000 c000
[15:12:48.482] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10e 8000 43c8 d1 206a 43c8 694 206a 43c8 43c8 83 204f 295 2862 43c9 115 2069 43c8 621 2867 43ca 43c8 e000 c000
[15:13:00.016] INFO: PixTest:: pg_setup set to default.
[15:13:00.017] INFO: 0 hot pixels found in step 5
[15:13:00.054] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:13:00.058] INFO: PixTest::trimHotPixels() done
[15:13:00.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat
[15:13:00.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C1.dat
[15:13:00.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C2.dat
[15:13:00.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C3.dat
[15:13:00.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C4.dat
[15:13:00.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C5.dat
[15:13:00.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C6.dat
[15:13:00.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C7.dat
[15:13:00.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C8.dat
[15:13:00.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C9.dat
[15:13:00.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C10.dat
[15:13:00.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C11.dat
[15:13:00.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C12.dat
[15:13:00.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C13.dat
[15:13:00.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C14.dat
[15:13:00.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[15:13:00.143] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[15:13:00.153] INFO: enter test to run
[15:13:59.987] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:13:59.987] INFO: running: highrate
[15:13:59.992] INFO: ----------------------------------------------------------------------
[15:13:59.992] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:13:59.992] INFO: ----------------------------------------------------------------------
[15:13:59.992] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:13:59.992] INFO: edge/corner pixel THR is adjusted
[15:13:59.992] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:14:00.950] INFO: Collecting data for 1 seconds...
[15:14:01.954] INFO: Done with hot pixel readout
[15:14:02.413] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[15:14:02.413] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[15:14:02.414] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cf 8040 43ca 43ca 43ca 31a 2a44 650 2062 85d 2649 43ca 493 2665 69c 264f 43c8 419 2665 452 228a 4d0 266f 688 2647 68d 2a69 6d1 2642 851 264d 43c8 30b 244d 43ca 121 2269 508 2a4f 74d 244c 43ca 3 244c 20d 284f e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c9 80c0 4388 62 2444 859 2a60 4388 92 2a45 4388 51a 224e 55a 2086 4388 4388 154 2a68 4388 64a 2683 4388 13 284c 4388 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ca 8000 4388 413 224d 4388 218 244a 4388 4388 291 246f 415 286f 410 286d 549 2a4c 4389 213 2449 4388 68d 2641 4388 35d 2665 851 206c 4388 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cb 8040 4388 d0 2460 513 226d 4388 11d 264d 15c 2443 159 2a0f 4388 512 264f 4388 45d 286a 691 2a4f 4388 11 2861 605 2680 4388 10 282a 492 2265 4d1 224a 4388 55a 244f 4388 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cc 80b1 4388 52 2642 43c8 7eb 3f08 1f09 2265 60f e01f c0e8
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cd 80c0 43c8 65c 242f 43c8 43c8 315 226a 80b 2a67 43c8 41a 206a 649 2446 65b 2a65 43c8 84a 2449 43c8 85c 2a2f 43c8 43c8 51b 2a49 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ce 8000 43c8 43c8 a 2842 10 2241 254 2a26 43c8 4c8 2648 4d1 2a4f 43c8 cd 2288 118 244d 419 2680 4c2 2241 64a 2847 70b 2889 43c8 742 2829 43c9 cd 2882 cb 226b 43c8 43c8 49b 244c e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cf 8040 43ca 340 246e 43ca 62 226f 70c 2062 43ca 43ca 295 2a64 43c9 43ca 34b 2264 43c9 65c 2843 43ca e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c9 80c0 4388 68d 2666 751 2860 4388 20a 2a4d 4388 4388 543 2022 4388 85b 2a69 4388 14b 224f 35b 2065 4388 81d 2a61 4388 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ca 8000 4388 4388 649 284a 4388 cb 2081 80b 2269 4388 0 282f 4388 4388 4388 4388 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cb 8040 4388 4c 2048 290 2448 4388 19 284a 45b 244e 4c4 244f 4388 843 204d 4388 414 2869 652 2248 4388 601 246d 65a 246d 65a 206f 4388 4388 4388 81b 2049 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cc 80b1 43c8 d5 2048 61a 2a6b 10af 36a6 f 289a 720 2010 5a4 20c4 83a 2c92 993 20d0 992 43c8 53 2462 2dc 2869 61c 228a 43c8 75b 2a6d 43c8 452 2865 43c8 521 2a4c 43c8 85d 2044 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cd 80c0 43c8 40c 206d 604 2445 43c8 619 2040 65a 2a6f 43c8 1b 2040 da 2a63 55a 2a60 859 264f 43c8 43ca d8 264d 43c8 45 282f 43c8 43c8 e000 c000
[15:14:02.414] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ce 8000 43c8 658 2445 43c8 43c8 510 2849 619 266c 661 266e 743 2460 43c8 4d5 2686 43c9 43c8 2a0 2a69 43ca 43c8 e000 c000
[15:14:06.082] INFO: PixTest:: pg_setup set to default.
[15:14:06.083] INFO: 0 hot pixels found in step 0
[15:14:06.088] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:06.173] INFO: PixTest::trimHotPixels() done
[15:14:06.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:06.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:06.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:06.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:06.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:06.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:06.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:06.217] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:06.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:06.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:06.233] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:06.238] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:06.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:06.249] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:06.254] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:06.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:06.264] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-25_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:06.275] INFO: enter test to run
[15:14:56.579] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:14:56.579] INFO: running: xray
[15:14:56.580] INFO: ----------------------------------------------------------------------
[15:14:56.580] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:14:56.580] INFO: ----------------------------------------------------------------------
[15:14:57.543] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:15:09.050] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:15:38.634] INFO: Resuming triggers.
[15:15:50.140] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:16:19.811] INFO: Resuming triggers.
[15:16:31.317] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:16:31.427] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[15:16:31.427] WARNING: Channel 1 ROC 0: Readback start marker after 1 readouts!
[15:16:31.427] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[15:16:31.427] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (112) != TBM ID (113)
[15:16:31.427] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 43c8 43c9 43c8 43c9 43c8 43c9 43c8 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 4388 4388 75d 202f 4388 4388 438a 160 2a8f 4388 4388 4388 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 4388 4388 4388 71d 2645 4388 4389 4388 438a 4388 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 438a 438a 35b 2261 438a 160 264c 6c2 2465 438a 4389 438a 4389 438a 2ca 2a4d e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 43ca 12 2884 2880 2b4 43c9 110 2a65 43c8 43c9 149 2466 43c9 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 43c8 522 244d 43c8 43c8 43c8 404 2685 43c8 14b 206f 851 246c 43c9 43c9 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 43c8 43c8 5d 2a46 43c9 43c9 43c8 43c8 43c8 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 43c8 43c8 43c8 458 2a48 43c8 43c8 43c8 43c8 43c8 4b 204d e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 4388 61c 2044 4388 4388 4388 4388 4389 658 264b 4388 4388 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 438a 438a 438a 438a 4388 4388 438a 438a 260 2247 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 c00b
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 43c8 61c 2044 43c8 43c8 43c8 42 2449 659 244f 43c8 651 244f 43c8 548 2a44 855 262f 858 2240 43c9 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 43c8 155 282e 43c8 43c9 409 2264 43c9 43c8 43c8 43c9 43c9 e000 c000
[15:16:31.427] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a073 8040 43c8 255 2044 41a 224c 43c9 43c8 45d 2645 43c9 43c8 548 2241 43c8 43c8 43c9 e000 c000
[15:17:00.576] INFO: Resuming triggers.
[15:17:12.081] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:17:41.616] INFO: Resuming triggers.
[15:17:53.123] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:18:22.416] INFO: Resuming triggers.
[15:18:33.922] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:19:03.369] INFO: Resuming triggers.
[15:19:14.875] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:19:44.370] INFO: Resuming triggers.
[15:19:55.882] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:20:25.256] INFO: Resuming triggers.
[15:20:33.541] INFO: data taking finished, elapsed time: 100 seconds.
[15:20:55.069] INFO: PixTest:: pg_setup set to default.
[15:20:55.072] INFO: PixTestXray::doPhRun() done
[15:20:55.208] INFO: enter test to run
[15:22:17.592] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:22:17.592] INFO: running: xray
[15:22:17.593] INFO: ----------------------------------------------------------------------
[15:22:17.593] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:22:17.593] INFO: ----------------------------------------------------------------------
[15:22:18.557] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:22:24.870] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:22:51.720] INFO: Resuming triggers.
[15:22:58.037] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:23:28.318] INFO: Resuming triggers.
[15:23:34.632] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:23:34.987] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[15:23:34.987] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[15:23:34.987] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (93) != TBM ID (127)
[15:23:34.987] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[15:23:34.987] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (128) != TBM ID (93)
[15:23:34.987] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15f 8040 43ca 122 286f 43ca 43ca 6a0 2868 43ca 43c9 43ca 99 224c 15b 2065 203 2441 43c9 43ca e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a159 80c0 4388 4388 85 204a 2e1 268a 61d 2045 4388 4388 9c 224d 4388 4d3 286d 4388 409 2a4f 4388 4388 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15a 8000 4388 513 2a68 614 262c 4388 14 2a27 4388 10b 2083 81d 2082 4388 75a 2a49 4388 10a 2068 4388 108 2029 152 2268 4388 4388 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15b 8040 4388 845 224d 4388 4388 4388 20a 2061 4388 35b 2687 4388 254 244e 442 284f 4388 4388 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15c 80b1 43c8 43c8 40d 2669 a0f 2aa0 1 2803 f23 2aa9 aab 2ebf 66a 27ea a7e 21f6 b6a 2010 804 43c8 458 2427 43c8 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15d 80c0 43c8 43c8 849 2244 43c8 43c8 b 2880 493 2449 43ca 43c8 43c8 43c8 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15e 8000 43c8 ca 2242 4da 2862 4c2 2049 43c8 24d 2a49 2ca 266a 612 2026 43c8 110 2a89 350 2043 43c8 458 264f 55b 2a45 655 2849 43c9 31b 2685 60c 2864 43c8 4d9 2a45 43ca 43c8 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a060 80b1 43c8 419 2067 43c8 43c8 43c8 10 2a4f 653 2481 75d 2448 43c8 db 2283 11a 284f 43c9 108 2067 43c8 1d 2862 84d 2a65 43c8 10a 2849 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05b 8040 4388 53 282f 93 2265 4388 4388 360 2246 60a 2a4d 4388 d0 2a82 449 2a86 4388 361 266b 4388 db 2a60 14b 2287 84b 2667 4388 8 2645 4388 12 2040 25a 2263 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05c 80b1 43c8 550 224c 7e7 43ca 955 3ffe 778 3a85 1281 28ab f9e e062 c041
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8087 f80 2054 4a0 25b8 43ca 43c8 43c8 148 2a4f e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05d 80c0 43c8 43c8 48b 2645 43c8 710 2424 813 204f 43c8 310 204d 43c8 405 264c 453 266d 548 226a 43c8 8 2628 648 206f 43c8 208 2046 43c8 1 224c 13 2263 55c 2847 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05e 8000 43c8 89 282e 43c8 1d 282f 43c8 803 2642 43c8 83 204d 219 2448 28a 204f 815 2082 43c8 43c9 359 2469 689 2044 80c 2a46 43c8 8d 286b 43c8 e000 c000
[15:23:34.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05f 8040 43ca 69c 206f 43ca 553 286c 43ca 43ca 1d 2868 15 26a1 43c8 43c8 65d 284e 65d 244f 43ca 44a 2264 48a 2a63 60d 2a8b 43ca 11d 204f e000 c000
[15:24:04.852] INFO: Resuming triggers.
[15:24:11.167] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:24:41.241] INFO: Resuming triggers.
[15:24:47.554] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:25:17.638] INFO: Resuming triggers.
[15:25:23.950] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:25:24.103] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[15:25:24.103] WARNING: Channel 1 ROC 6: Readback start marker after 2 readouts!
[15:25:24.103] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 7 ROCs were found
[15:25:24.106] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 43c8 d0 2a4c 159 2449 43c9 358 282c 43c8 512 288e 508 2049 755 2668 43c9 213 2062 41d 244f 43c8 14 2087 43c8 43c8 711 286e 84c 2067 84b 2868 43c9 618 2664 e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4388 4388 4388 4388 4388 13 2062 12 284f 219 282d 4388 21b 284a 4388 41 2a6a 81 2869 404 224f 418 2261 808 2061 4388 e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4388 4388 21c 204f 4388 40c 2845 4388 85 2843 4388 4389 9d 266f 10d 2082 4388 512 266e 64b 2264 4388 e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 438a 51 222f 438a d1 262e 451 2a4f 438a 438a 4d1 222a 501 284f 718 244c 84b 2a6b 4388 703 2842 4388 149 2848 438a 481 2844 84c 2a4e 852 226c 438a e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 4388 4c 2822 4388 4388 48b 2283 690 284f 4388 294 2a42 693 2462 69d 2269 85b 326f 43c8 43c9 85f 35df d6f 2ff7 5bf 2745 828 2759 269 43c8 89 2a62 cd 2667 e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 43c8 8c 204c 9a 2a49 43c8 43c8 43c8 b 2065 43c8 c9 2a69 24c 246e 254 2262 262 226f 41d 2281 41d 2662 43c8 54d 264d 43c9 253 224f 43c9 e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 43c8 215 2a45 43c8 dd 2245 24b 2645 43c9 654 2069 43c9 43c8 413 244f 43c8 308 204a 844 282d 43c9 4d 2867 251 266c 43c9 51b 2049 69b 2a6c e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a103 8040 43c8 43c9 da 224d 2c9 2881 43c8 89 2440 115 2462 511 2067 43c9 43c8 43c9 319 246c 43c8 699 244a 6dd 224a 43c9 45d 204f e000 c000
[15:25:24.106] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4388 4388 719 224d 4388 108 244c 15d 288b 4e0 2a6c 519 2862 4388 120 2264 2d8 264f 2d8 2a88 438a 412 2066 4388 354 224d 4388 6d5 282d 4388 e000 c000
[15:25:24.107] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4388 208 2a4d 4388 4388 1b 2a2f 1c 2242 4388 4389 15 2065 4388 438a 4388 e000 c000
[15:25:24.107] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 438a 44a 2046 438a 654 2040 438a 8 2240 290 224e 50c 2a43 438a 4389 20 2469 438a 4389 438a e000 c000
[15:25:24.107] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a100 80b1 4389 83 204e 82 2860 4389 4389 4c 284b 51a 2a60 520 2261 4389 519 288c 4388 1109 206d 43c9 43ca 1b67 2009 4 2b80 0 21ff 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
[15:25:24.107] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 43c8 64d 2288 43c8 cb 2469 43c8 43c8 280 2a49 43c8 93 2a4c 214 2663 43c9 43c9 43c9 e000 c000
[15:25:24.107] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 43c8 43c8 d2 204f cd 204b 43c9 43c9 43c8 43c8 11 284d 43c8 43c9 1 2a45 e000 c000
[15:25:53.926] INFO: Resuming triggers.
[15:26:00.239] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:26:30.276] INFO: Resuming triggers.
[15:26:36.589] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:27:06.768] INFO: Resuming triggers.
[15:27:13.081] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:27:43.170] INFO: Resuming triggers.
[15:27:49.484] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:28:19.523] INFO: Resuming triggers.
[15:28:25.837] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:28:55.926] INFO: Resuming triggers.
[15:29:02.238] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[15:29:32.290] INFO: Resuming triggers.
[15:29:38.604] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:29:38.950] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09f 8040 43ca 28c 2060 28c 2260 753 2469 43ca 459 2640 43ca 43ca 462 2068 4db 2a42 748 2242 43c8 d1 244a 44d 2a4f 75a 264f 43c8 12 2a45 43ca d9 2242 253 266c 488 2a4e 43ca 8 2440 19 2a4d 253 204c e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a099 80c0 4388 211 202f 4388 4388 4388 210 2429 6a1 244f 6a1 2a49 4388 d 206c 4a 2863 2d5 2a2f 4388 84a 262d 4388 4388 294 2061 e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09a 8000 4388 519 2240 4388 4388 4388 4389 305 2a82 4388 c9 2445 454 2045 619 2068 4388 19 2847 4388 d1 2865 211 2865 25d 2245 559 2a64 71a 2043 e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09b 8040 4388 4388 4388 4ca 2a86 4388 51c 264d 811 224d 4388 93 2862 694 2a4f 4388 54c 262f 55a 2664 642 2062 4388 4388 e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09c 80b1 4388 21 2a4c 212 264c 758 2a6b 4388 d3 2a49 d4 222f 4388 4388 4c5 2649 75a 2a4f 43c8 1305 2062 31b 257f fd 2447 43ca db 2481 2a0 2a60 609 204f 43c8 143 2a4f 28a 2669 852 2a6b 43c8 e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09d 80c0 43c8 65c 222e 862 264f 43c8 43c8 49d 264c 715 2448 750 204c 43c8 10b 264f 148 2069 31d 226e 448 2482 60d 286f 43c8 28a 2869 453 2a8d 692 2664 43c8 51b 2a6d 68b 2640 43c8 609 2666 43c8 a 2444 31c 2063 55b 248c e000 c000
[15:29:38.950] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 43c8 10 244d 43c8 43c8 68b 2663 843 2245 43c8 48d 2465 485 2a64 4c5 2846 805 2648 43c8 dc 264f 122 2040 74a 2863 43c9 453 284f 43c8 614 264c 43c8 e000 c000
[15:30:08.716] INFO: Resuming triggers.
[15:30:15.030] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[15:30:15.229] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 5 ROCs were found
[15:30:15.229] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 43c9 55d 2a84 43c9 43c9 43c9 140 2880 355 2068 715 2642 43c8 11d 246c 2a1 2284 43c9 2d0 2a2c 30c 2a47 43c9 43c9 e000 c000
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 849 2a48 4388 650 2a2c 753 2a4f 4388 14b 2a69 615 2049 4388 858 224f 4388 2c4 286c 519 2688 4388 4388 493 284e 4388 e000 c000
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4388 2db 204f 31d 224a 40b 2483 4388 521 2a45 4388 4388 1c 2447 51 244f 259 24af 41a 286f 4388 4388 4388 4388 2c8 2041 e000 c000
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4388 4388 2ca 2660 440 2420 6cb 2a47 85b 206f 4388 212 2462 4388 652 244d 64b 224f 64d 2a47 819 2084 854 2a8a 4388 4388 4388 520 286a 4388 18 2a2e e000 c000
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4388 4388 2c1 2664 35d 2866 415 2427 710 2860 4388 4388 84c 2869 841 2884 43ca 2c9 e0a0 d0e8
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 43c8 641 206e 80b 2682 43c8 120 2a4f 31b 284b 43c8 65a 264f 65a 2a44 6c8 2865 43c8 43c9 50 2a88 154 2080 31d 226c 400 2881 43c8 43ca 43c8 6a0 2840 e000 c000
[15:30:15.229] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 43ca 80d 2a45 43ca 43ca 51a 264f 43c9 4d 286f 213 286f 2e0 2283 43ca 43c9 43ca e000 c000
[15:30:45.205] INFO: Resuming triggers.
[15:30:51.522] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:31:21.680] INFO: Resuming triggers.
[15:31:27.303] INFO: data taking finished, elapsed time: 100 seconds.
[15:31:54.577] INFO: PixTest:: pg_setup set to default.
[15:31:54.580] INFO: PixTestXray::doPhRun() done
[15:31:54.730] INFO: enter test to run
[15:32:15.043] INFO: test: HighRate no parameter change
[15:32:15.044] INFO: running: highrate
[15:32:15.045] INFO: ----------------------------------------------------------------------
[15:32:15.045] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:32:15.045] INFO: ----------------------------------------------------------------------
[15:32:15.186] INFO: Expecting 768 events.
[15:32:16.320] INFO: 768 events read in total (418ms).
[15:32:16.321] INFO: Test took 1269ms.
[15:32:17.124] INFO: Expecting 41600 events.
[15:32:20.210] INFO: 41600 events read in total (2559ms).
[15:32:20.211] INFO: Test took 3882ms.
[15:32:20.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:20.976] INFO: Expecting 41600 events.
[15:32:24.180] INFO: 41600 events read in total (2677ms).
[15:32:24.181] INFO: Test took 3919ms.
[15:32:24.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:24.937] INFO: Expecting 41600 events.
[15:32:28.157] INFO: 41600 events read in total (2694ms).
[15:32:28.158] INFO: Test took 3926ms.
[15:32:28.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:28.918] INFO: Expecting 41600 events.
[15:32:32.163] INFO: 41600 events read in total (2718ms).
[15:32:32.164] INFO: Test took 3955ms.
[15:32:32.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:32.923] INFO: Expecting 41600 events.
[15:32:36.158] INFO: 41600 events read in total (2709ms).
[15:32:36.159] INFO: Test took 3944ms.
[15:32:36.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:36.917] INFO: Expecting 41600 events.
[15:32:38.922] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[15:32:38.922] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[15:32:38.922] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f0 80b1 4388 2cd 26a0 4388 2cd 26a2 4388 2cd 2685 4388 95 2462 2cd 26c3 4388 4c 2a40 2cd 2687 4389 40 2662 2cd 268f 4388 2cd 268e 4388 2cd 268f 810 2425 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 43c8 15 282f 2cd 26a0 43c8 2cd 268d 43c8 2cd 2684 43c9 2cd 26c1 43c9 2cd 2689 43c8 2cd 268d 402 2442 493 206b 43c9 2cd 26a5 445 264f 43c9 2cd 268a e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 43c8 2cd 268f 43c8 2cd 26a5 43c8 2cd 2685 43c9 283 264c 2cd 26cd 43c8 2cd 2684 43c9 c9 204c 2cd 268f 43c9 2cd 26a2 43c9 2cd 268d e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 43c9 2cd 26a4 43c8 2cd 268f 43c8 2cd 26a0 41b 2668 43c9 2cd 26c0 490 2862 43c8 2cd 268a 43cb 2cd 2688 43c9 2cd 26a5 43c9 2cd 2688 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 43c8 2cd 268e 43c9 2cd 268f 43c8 2cd 268f 43c8 2cd 26cb 4388 2cd 3689 5388 52 3d55 4a0 2fd6 a95 2440 7a9 22cd 6a0 2714 243 4388 1b 244f 2cd 26a2 414 2047 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4388 4389 2cd 268d 4388 2cd 268c 50b 2445 4389 2cd 26c2 4388 2cd 2684 4389 221 2446 2cd 2686 442 2a45 60d 224f 4389 2d0 2848 2cd 26a5 4388 2cd 268c e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 438a 2cd 26a4 438a 2cd 268e 438a 2cd 2689 501 2444 438a 2cd 26c6 4388 2cd 2689 809 244f 4388 2cd 268e 438b 2cd 26a7 438a 2cd 268f e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f0 80b1 4389 2cd 268e 754 2249 4389 2cd 26a4 4389 2cd 268f 4389 2cd 26c0 4388 2cd 26a4 4389 2cd 2686 30d 204d 4389 2cd 26a4 80a 2a4a 4389 2cd 26a2 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ea 8000 43c8 2cd 268f 43c8 2cd 268f 43c8 2cd 268e 43c8 2cd 26c2 43c8 2cd 26a7 43c8 2cd 2681 43c8 2cd 26a7 43c8 2dd 2849 2cd 26a5 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1eb 8040 43c8 2cd 26a4 43c8 2cd 26a4 610 204f 43c8 2cd 268f 43c9 2cd 26ac 652 2849 43c9 2cd 26a9 43c8 2cd 2685 43c8 2cd 26a1 43c8 2cd 26a2 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ec 80b1 43c9 2cd 268a 43c8 2cd 268f 81d 244f 85a 262f 43c8 2cd 268f 43c8 2cd 26af 43c9 2cd 26a7 43c8 2cd 2686 43c9 2cd 26a5 43c8 2cd 26a6 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 43c8 2cd 268e 43c8 2cd 26a5 712 2464 43c8 2cd 268d 43c8 2cd 26ae 1419 306d 505 3bff e0f 2aa9 56a 2a23 906 2b46 250 2042 4388 2166 b51 4388 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ee 8000 4388 2cd 268e 4389 2cd 26a1 4388 2cd 268c 4388 2cd 26a5 4389 161 2685 2cd 26a1 4389 2cd 2689 438b 2cd 26a4 4388 2cd 26a2 e002 c000
[15:32:38.922] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 438b 2cd 268c 438b 2cd 26a4 438b 2cd 268f 438b 2cd 26a9 4389 2cd 26af 438a 2cd 2684 4389 2cd 26a5 438a 2cd 26a9 e002 c000
[15:32:40.152] INFO: 41600 events read in total (2708ms).
[15:32:40.153] INFO: Test took 3943ms.
[15:32:40.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:40.908] INFO: Expecting 41600 events.
[15:32:43.702] INFO: 41600 events read in total (2267ms).
[15:32:43.703] INFO: Test took 3498ms.
[15:32:43.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:44.466] INFO: Expecting 41600 events.
[15:32:47.488] INFO: 41600 events read in total (2495ms).
[15:32:47.488] INFO: Test took 3734ms.
[15:32:47.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:48.250] INFO: Expecting 41600 events.
[15:32:51.256] INFO: 41600 events read in total (2479ms).
[15:32:51.257] INFO: Test took 3717ms.
[15:32:51.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:52.013] INFO: Expecting 41600 events.
[15:32:54.914] INFO: 41600 events read in total (2375ms).
[15:32:54.915] INFO: Test took 3606ms.
[15:32:54.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:55.677] INFO: Expecting 41600 events.
[15:32:58.912] INFO: 41600 events read in total (2708ms).
[15:32:58.913] INFO: Test took 3946ms.
[15:32:58.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:59.674] INFO: Expecting 41600 events.
[15:33:02.917] INFO: 41600 events read in total (2716ms).
[15:33:02.918] INFO: Test took 3954ms.
[15:33:02.951] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:03.673] INFO: Expecting 41600 events.
[15:33:06.929] INFO: 41600 events read in total (2729ms).
[15:33:06.930] INFO: Test took 3962ms.
[15:33:06.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:07.689] INFO: Expecting 41600 events.
[15:33:10.926] INFO: 41600 events read in total (2710ms).
[15:33:10.927] INFO: Test took 3945ms.
[15:33:10.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:11.687] INFO: Expecting 41600 events.
[15:33:14.162] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[15:33:14.162] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f3 8040 4388 545 288c 4389 545 286c 4388 b 202f a 2829 545 28a2 651 2860 4389 545 2865 4388 545 288f 4389 215 2840 545 28a8 4388 545 288c 4389 545 2887 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 43c9 548 2088 544 2a2c 61d 2041 43c9 4cc 2263 548 206b 43c9 548 208f 43c9 45 204e 89 204c 505 2845 548 2065 43ca 548 20a6 43c9 548 208e 43c8 548 2086 43c8 49b 2a40 548 2087 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ee 8000 43c8 93 2a6e 548 2087 43c9 548 206c 43c9 548 208f 43c8 505 2a64 548 206c 43c9 548 20a8 84a 246c 43c9 548 20a6 43cb 548 2085 43c9 548 2085 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 43ca 482 2446 548 2089 662 2065 43cb 548 2069 43ca 548 208f 43cb 548 2062 43c9 548 20a6 43ca 548 20a2 43c9 548 2086 43cb 548 2080 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f0 80b1 43c9 548 2088 43c9 548 206e 801 284c 43c9 115 284c 548 208f 4389 548 206c 4a8 2146 532 22a4 52 4389 4389 548 2086 4389 548 2088 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 4388 548 2084 761 2a61 4388 548 206a 4388 355 244e 548 208d 4388 a0 2642 548 204f 4388 548 20a4 4389 548 20a1 4389 548 2086 4389 548 2088 e002 c000
[15:33:14.162] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 4388 410 2a2e 483 2a4e 545 288d 4388 545 286f 708 2640 4389 545 288d 4389 545 286a 4388 545 28a5 84c 2448 4388 545 28a8 4388 545 2887 4389 545 2887 e002 c000
[15:33:14.935] INFO: 41600 events read in total (2721ms).
[15:33:14.937] INFO: Test took 3958ms.
[15:33:14.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:15.691] INFO: Expecting 41600 events.
[15:33:17.748] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[15:33:17.751] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08d 80c0 4389 321 268f 4389 321 26a3 4389 321 26a2 752 2a4d 4389 321 26ac 4388 321 268f 4388 321 26a9 4389 321 268f 4389 1b 2045 321 26a4 e002 c000
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 43c8 321 2688 43c8 321 268f 43c8 321 268f 43c8 30d 284e 321 26c5 43c8 11 2865 119 282d 321 26a4 741 224d 43c9 58 2661 321 26a7 43c8 321 268e 44c 2061 43c8 321 26a1 50d 2649 e002 c000
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a088 80b1 43c9 321 268d 43c9 321 26a2 43c9 321 26a1 43c9 321 26a8 819 2441 43cb 4d 2448 321 26a9 4dc 2666 43c8 321 26a2 43c9 321 268e 43c9 321 26a7 354 2647 e002 c000
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a089 80c0 43c8 11 2040 321 2687 43c8 321 26a4 43c8 321 268f 690 2043 43c8 321 26a6 65a 2249 43c8 321 268c 43c9 321 26a9 43c8 321 2689 43c8 321 26a5 e002 c000
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a08a 8000 43c9 321 2688 43c9 321 268f 43c8 321 26a3 43c8 321 26af 43c9 50 2245 321 268f 85b 2669 43c8 321 26a9 43c8 288 2444 321 26a5 850 3464 4388 304 2007 83e 2020 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
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08b 8040 4388 321 268c 4388 11a 2849 321 26a9 4389 321 26a5 4389 321 26c1 48d 2269 4388 321 268a 4389 321 26a4 692 2441 4389 321 268f 553 244c 4389 321 268f e002 c000
[15:33:17.751] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08c 80b1 4388 321 268e 692 244c 722 2661 4388 321 268a 4389 321 268f 4388 14b 2a6c 321 26c2 4388 309 204a 321 268f 438b 321 26a8 4389 321 268f 4388 321 268d e002 c000
[15:33:18.942] INFO: 41600 events read in total (2724ms).
[15:33:18.942] INFO: Test took 3949ms.
[15:33:18.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:19.704] INFO: Expecting 41600 events.
[15:33:22.937] INFO: 41600 events read in total (2706ms).
[15:33:22.938] INFO: Test took 3943ms.
[15:33:22.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:23.698] INFO: Expecting 41600 events.
[15:33:25.674] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[15:33:25.674] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 43c9 2c8 2088 43c9 2c8 2081 2d9 2a49 43c8 2c8 2088 43c9 2c8 20a5 354 222f 554 2664 43c8 18 204c 2c8 2085 43cb 2c8 2089 43c8 2c8 20aa 810 2a29 43c9 2c8 2087 e002 c000
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 4388 250 2646 2c8 2489 4388 2c8 2484 4388 2c8 2485 619 2643 4388 2c8 248e 815 2843 4389 2c8 2481 4388 2c8 2486 4388 2c8 24a5 4388 2c8 2487 e002 c000
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 4388 21 2644 2c8 2489 4388 2c8 2487 4388 2c8 2483 4388 2c8 24a5 705 2247 4389 2c8 246f 4389 2c8 2487 4388 2c8 248f 4388 294 2047 2c8 248a e002 c000
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 4389 2c8 2089 4389 2c8 208d 4389 2c8 208a 4389 2c8 20a8 438b 2c8 2084 84c 2047 4389 8a 2a4d 2c8 208d 4389 2c8 20a4 4389 2c8 2088 e002 c000
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a069 80c0 4388 2c8 208d 4388 2c8 2089 4388 2c8 2081 4388 2c8 20a8 505 284d 4388 d8 2662 288 246d 2c8 206f 4388 2c8 2089 85b 2a4f 4388 2c8 20a1 4d0 2648 43c9 fff 2ff2 3e 2020 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
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 43c9 2c8 2088 43c9 2c8 2087 361 2047 43c9 2c8 2081 43c8 19 2468 2c8 20a6 43c9 2c8 206d 43c8 2c8 2089 43c9 2c8 20a4 43c8 9a 2a4f 2c8 2085 e002 c000
[15:33:25.674] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 43c8 55 282c 2c8 208c 715 2241 43c8 2c8 2088 43c8 2c8 2084 43c9 2c8 20a0 712 2249 43c8 2c8 206f 2c3 2a45 43c8 2c8 2080 683 2848 80b 2a4f 43c8 2c8 20a8 43c9 2c8 2085 e002 c000
[15:33:26.932] INFO: 41600 events read in total (2707ms).
[15:33:26.932] INFO: Test took 3943ms.
[15:33:26.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:27.691] INFO: Expecting 41600 events.
[15:33:29.270] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 4 ROCs were found
[15:33:29.270] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[15:33:29.270] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (27) != TBM ID (132)
[15:33:29.270] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[15:33:29.270] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (133) != TBM ID (27)
[15:33:29.270] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11d 80c0 43c8 89 2aa4 43c8 89 2a69 43c8 89 2a83 43c8 89 2a8c 43cb 89 2a8f 43c8 89 2aa7 43c8 89 2a8d 43c9 89 2a88 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 4388 89 2a8f 4388 89 2a6d 119 2867 4388 85 2828 89 2a8a 6da 286f 701 204f 4388 89 2a89 559 2a2f 4389 89 2a85 e1 2884 4388 89 2aa4 4389 89 2a8b 4388 89 2a83 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 4389 89 2a8d 20b 284f 304 284a 4389 89 2a81 10d 244b 240 2643 313 204d 4389 89 2a8c 4389 89 2a89 4388 89 2a89 4389 89 2aa9 4388 89 2a8d 4389 89 2a85 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4388 89 2a88 4388 4b 2a4f 89 2a82 144 2465 45b 2042 4388 89 2a85 4388 89 2a8a 4388 89 2a8d 2c0 284d 4388 89 2a8f 4389 89 2aa0 4388 89 2a82 41c 2649 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 4389 89 2a8f 4389 89 2a69 4389 89 2a8c 85c 264d 4389 89 2a8c 519 224f 43c8 28 203c 39 43c9 89 2aa4 43c8 89 2aa0 43c9 89 2a83 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11b 8040 43c9 89 2a8e 494 2645 43c8 89 2a6c 43c8 89 2a83 43c9 89 2a88 43c8 89 2a85 43c8 89 2aa1 43c9 89 2a8d 43c8 89 2a86 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11c 80b1 43c8 89 2a8c 6c1 2a43 43c9 89 2a81 43c9 89 2a81 2d4 2041 43c9 89 2a8a 452 246c 43c8 89 2a8d 43c9 89 2aa5 43c9 89 2a8f 43c8 89 2a86 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01e 8000 43c8 89 2a80 55d 2247 43c9 89 2aa1 43c9 89 2a8f 43c8 89 2a84 720 2462 43c8 89 2a8f 43c9 89 2a89 43c9 89 2aac 2d3 2661 43c8 89 2aa3 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 4388 89 2a80 4388 89 2a8f 462 2865 4388 4388 89 2a8e 152 2467 4388 89 2aa5 4388 89 2aa2 4388 89 2aab 28b 2a4f 753 2426 4388 89 2aa9 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a01a 8000 4389 89 2a8c 4389 89 2ac2 91 2645 4389 89 2a8c 6cb 244f 43c9 89 2a6f 43c9 17f
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a084 804d 47a 2054 43c9 2050 44d 43c8 89 2aad e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01b 8040 43c9 89 2a82 43c8 89 2a8f 145 2425 43c8 89 2aa4 43c8 89 2a83 43c8 89 2a8d 43c8 89 2a8d 43c8 89 2aaf 43c9 89 2aa8 351 2a46 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01c 80b1 43c8 89 2a86 43c9 89 2a8d 98 2a2d 43c8 452 2049 43c8 89 2a85 43c8 89 2a8d 85d 2a4f 43ca 89 2a8e 4c4 262b 43c8 89 2aaa 43c9 89 2aa8 e002 c000
[15:33:29.270] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01d 80c0 43c8 89 2a80 43c9 89 2a8f 43c9 89 2a8d 43c8 89 2a8f 43c8 89 2a8f 153 2287 43c8 89 2aa2 493 284f 720 2480 43c9 89 2ac4 43c9 89 2aa9 e002 c000
[15:33:31.079] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[15:33:31.082] INFO: 0 events read in total (2864ms).
[15:33:31.082] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[15:33:31.719] INFO: Expecting 41600 events.
[15:33:33.411] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 2 ROCs were found
[15:33:33.411] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (1) != Token Chain Length (8)
[15:33:33.411] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (52) != TBM ID (98)
[15:33:33.411] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[15:33:33.411] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (99) != TBM ID (52)
[15:33:33.411] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a036 8000 4388 113 268c 6d9 222d 4388 113 26a4 4388 4388 113 26a1 21a 224f 4388 113 2685 309 264f 4388 113 26a8 315 2849 4388 113 26c5 4388 113 26ac e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 43c8 113 268c 43c8 113 26a7 43c8 43c8 113 26a6 45a 2865 43c8 113 268c 853 2a4d 43c9 113 26a7 43c8 113 26c1 43c8 113 26a5 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 43c8 113 268f 43c8 113 26a3 43c8 721 2445 43c8 113 26ac 410 2a4e 43c9 113 2684 413 224f 43c8 113 26ac 43c9 113 26af 753 2627 43c9 113 26a5 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 43c8 113 268c 43c8 113 26a4 43c9 84c 2629 43c9 113 26af 810 202c 43c8 113 2685 2c2 2644 605 2461 43c8 113 26a5 43c9 113 26ad 43c9 113 26a3 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 43c8 113 2687 5389 113 26a1 1440 3099 543 3d7f f9a 2f93 e0bf c022
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 4388 53 2a2f 113 2689 4388 113 26a2 4388 851 242e 4388 113 26a8 4388 113 268c 28a 204d 4388 113 26af 4388 113 26af 4388 113 26a7 308 266a 659 2049 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a035 80c0 4388 113 268f 35a 2a4d 4388 113 26ad 4388 4388 113 26ac 4389 113 26a0 4389 113 26ad 4388 113 26c6 355 204c 4388 113 26a7 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a137 8040 4388 113 26a6 4388 113 2688 4388 113 26ac 4388 113 26c1 4389 113 26c4 60d 286c 4388 113 26a3 4389 113 26a2 4388 113 26a5 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 43c8 113 26c0 43c8 113 268b 43c9 113 26ad 43c9 113 26af 694 222a 43c8 113 26c4 28c 224f 43c8 113 268d 43c8 113 26a1 43c9 113 26a2 850 224f e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a133 8040 43c8 113 26ac 1644 204d 7f2
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b162 8082 12a6 3aa7 a6 288a a05 2488 9b5 4388 1a 2a4c 113 26c5 4389 113 268e 31b 2867 4388 113 26a1 4389 113 26a2 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 4388 113 26a7 4388 113 2685 4388 113 26a9 11d 2661 4388 113 26a7 688 2a46 4388 113 26c1 4388 109 222c 113 26a1 49a 2a62 4388 113 26a4 4388 113 26a3 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a135 80c0 4388 113 26ae 4388 113 268a 4388 113 26c1 4388 113 26a9 4388 113 26ae 4388 113 268e 4388 113 268f 722 226d 4388 113 26a9 e002 c000
[15:33:33.412] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 4388 113 26ae 548 222d 548 2642 4388 112 2644 113 2687 4388 113 26ad 4388 113 26a8 4389 113 26ac 621 2482 4388 113 2689 4388 113 26a1 4388 113 268f e002 c000
[15:33:35.119] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[15:33:35.121] INFO: 0 events read in total (2876ms).
[15:33:35.121] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[15:33:35.725] INFO: Expecting 41600 events.
[15:33:38.333] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[15:33:38.333] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[15:33:38.333] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4388 64d 2680 4389 64d 268d 4389 64d 26a9 4388 64d 26ae 6c5 202f 4388 64d 26a4 4389 253 224d 2d4 2040 64d 26ad 69c 282c 4389 408 222e 64d 26a4 4388 64d 2683 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 43c9 15b 2244 64d 2687 43c9 64d 268c 43c9 dd 2440 64d 26af 43c9 64d 26a5 43ca 348 244a 64d 26ad 43c9 64d 26a7 43c9 64d 26aa 43c9 64d 268d e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 43c8 218 2044 64d 2687 43c8 64d 268c 43c8 64d 26a7 43c8 64d 26a5 43c8 418 2445 64d 26af 43c8 649 2a62 64d 26a5 43c8 61 2245 64d 26a9 43c8 64d 2686 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 43c9 64d 2685 43c9 64d 268c 43c9 94 226f 64d 26af 43c9 64d 26af 43c9 4d9 2264 64d 26a2 43c9 64d 268c 43c9 64d 26a5 43c8 64d 266f e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 43c9 64d 2680 4388 64d 2682 978 264d 6af 4388 412 2844 64d 26ac 4388 64d 26ae 4388 649 2a4f 64d 26a6 4388 61d 2665 64d 26a5 4389 4d2 2845 64d 2686 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4388 64d 2680 4389 64d 268f 4388 9c 282c 64c 244f 64d 26ad 4388 64d 26aa 4388 64d 26ad 438a 4a 2a4e 114 2448 64d 26a9 6c9 2642 4388 64d 26a7 4389 64d 2682 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 4388 64d 2669 4389 64d 268f 4389 4cc 2464 64d 26c1 4388 64d 26a9 4388 64d 268f 4388 64d 268f 4389 64d 26a9 4389 64d 2687 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ee 8000 4388 64d 26ac 4389 518 2241 64d 2689 4389 64d 268c 4388 64d 2684 849 2a4f 4389 65b 286c 64d 26a7 4389 64d 26a5 438b 64d 268d 4389 64d 268f e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e8 80b1 43c9 64d 26a4 43c9 64d 266e 43c9 64d 268e 760 224e 43c9 64d 2686 43c8 64d 26ac 43c9 64d 26a2 43c8 64d 268a 43c9 64d 26a5 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e9 80c0 43c8 64d 26a4 655 2681 43c8 64d 2683 43c8 64d 26a5 43c8 64d 2683 43c8 460 2865 64d 26a4 43c8 64d 26a3 43c9 64d 2688 43c8 64d 26a0 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ea 8000 43c9 64d 268e 43c9 64d 266d 43c9 660 2049 64d 268d 43c9 64d 2689 43c8 64d 26a4 43c9 64d 26a9 43c8 64d 2686 43c9 64d 26a1 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1eb 8040 43c9 64d 26a6 4388 64d 26a8 8bc 2186 922 2b26 b47 2bb0 330 4389 4388 54b 2a8c 64d 26a7 4388 64d 26ad 4389 64d 2689 4388 64d 268f e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ec 80b1 4388 4d1 284c 64d 26a0 4389 64d 2689 4389 35d 204e 64d 26a5 4389 60d 2046 654 2845 64d 2689 4388 64d 26a3 4389 64d 26a4 4389 64d 268b 4388 64d 26a4 e002 c000
[15:33:38.333] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 4388 64d 26a8 4388 ca 2465 da 264f 51d 2a60 64d 2688 4388 64d 26a4 4388 342 264c 64d 2686 438b 30d 2065 64d 26a1 4388 64d 26a7 4388 64d 268c 4389 64d 26a5 e002 c000
[15:33:38.953] INFO: 41600 events read in total (2702ms).
[15:33:38.954] INFO: Test took 3833ms.
[15:33:38.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:39.710] INFO: Expecting 41600 events.
[15:33:42.832] INFO: 41600 events read in total (2595ms).
[15:33:42.833] INFO: Test took 3826ms.
[15:33:42.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:43.229] INFO: enter test to run
[15:37:10.057] INFO: test: HighRate no parameter change
[15:37:10.057] INFO: running: highrate
[15:37:10.058] INFO: ----------------------------------------------------------------------
[15:37:10.058] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:37:10.058] INFO: ----------------------------------------------------------------------
[15:37:10.670] INFO: Expecting 208000 events.
[15:37:22.572] INFO: 208000 events read in total (11375ms).
[15:37:22.575] INFO: Test took 12506ms.
[15:37:22.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:22.967] INFO: number of dead pixels (per ROC): 2 0 0 0 0 1 0 1 5 0 1 0 0 0 0 0
[15:37:22.967] INFO: number of red-efficiency pixels: 69 47 80 130 136 170 136 97 1373 117 106 93 101 58 24 32
[15:37:22.967] INFO: number of X-ray hits detected: 63870 43592 67763 108156 110821 115889 111861 77572 76200 95408 93554 82907 86458 49937 21997 24245
[15:37:22.967] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:37:22.967] INFO: number of Vcal hits detected: 207832 207951 207919 207864 207861 207779 207863 207853 206028 207881 207843 207906 207892 207940 207976 207968
[15:37:22.967] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.2 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:37:22.967] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.1 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[15:37:22.967] INFO: X-ray hit rate [MHz/cm2]: 18.7 12.8 19.9 31.7 32.5 34.0 32.8 22.7 22.3 28.0 27.4 24.3 25.3 14.6 6.4 7.1
[15:37:22.967] INFO: PixTestHighRate::doXPixelAlive() done
[15:37:23.016] INFO: PixTest:: pg_setup set to default.
[15:37:23.027] INFO: enter test to run
[15:37:41.545] INFO: test: HighRate no parameter change
[15:37:41.545] INFO: running: highrate
[15:37:41.546] INFO: ----------------------------------------------------------------------
[15:37:41.546] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:37:41.546] INFO: ----------------------------------------------------------------------
[15:37:42.158] INFO: Expecting 208000 events.
[15:37:56.157] INFO: 208000 events read in total (13472ms).
[15:37:56.162] INFO: Test took 14607ms.
[15:37:56.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:56.795] INFO: number of dead pixels (per ROC): 2 0 0 0 0 1 0 1 19 0 1 0 0 0 0 0
[15:37:56.795] INFO: number of red-efficiency pixels: 278 161 285 592 577 760 506 377 1584 468 381 295 329 228 56 70
[15:37:56.795] INFO: number of X-ray hits detected: 147373 99730 154974 247408 253701 263917 255344 177772 173351 219270 214160 190021 197447 114099 50858 55923
[15:37:56.795] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:37:56.795] INFO: number of Vcal hits detected: 207608 207828 207685 207341 207347 207077 207439 207543 204093 207486 207550 207689 207648 207751 207943 207929
[15:37:56.795] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.6 99.8 99.8 98.6 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[15:37:56.795] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.7 99.8 98.1 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[15:37:56.796] INFO: X-ray hit rate [MHz/cm2]: 43.2 29.2 45.4 72.5 74.4 77.4 74.8 52.1 50.8 64.3 62.8 55.7 57.9 33.4 14.9 16.4
[15:37:56.796] INFO: PixTestHighRate::doXPixelAlive() done
[15:37:56.840] INFO: PixTest:: pg_setup set to default.
[15:37:56.862] INFO: enter test to run
[15:38:18.505] INFO: test: HighRate no parameter change
[15:38:18.505] INFO: running: highrate
[15:38:18.506] INFO: ----------------------------------------------------------------------
[15:38:18.506] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:38:18.506] INFO: ----------------------------------------------------------------------
[15:38:19.119] INFO: Expecting 208000 events.
[15:38:35.113] INFO: 208000 events read in total (15467ms).
[15:38:35.121] INFO: Test took 16605ms.
[15:38:35.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:35.965] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 32 0 1 0 0 0 0 0
[15:38:35.966] INFO: number of red-efficiency pixels: 592 340 580 1340 1441 1620 1118 798 1821 1096 778 651 590 466 125 121
[15:38:35.966] INFO: number of X-ray hits detected: 218194 148802 230886 367417 375021 392544 377750 264701 255690 325974 316586 280527 293917 169902 75816 83343
[15:38:35.966] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:38:35.966] INFO: number of Vcal hits detected: 207195 207602 207250 206226 206055 205603 206625 206930 202436 206611 207066 207235 207328 207437 207870 207877
[15:38:35.966] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.2 99.2 99.0 99.4 99.6 98.1 99.4 99.6 99.7 99.7 99.8 99.9 99.9
[15:38:35.966] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.6 99.1 99.1 98.8 99.3 99.5 97.3 99.3 99.6 99.6 99.7 99.7 99.9 99.9
[15:38:35.966] INFO: X-ray hit rate [MHz/cm2]: 64.0 43.6 67.7 107.7 109.9 115.1 110.7 77.6 74.9 95.5 92.8 82.2 86.1 49.8 22.2 24.4
[15:38:35.966] INFO: PixTestHighRate::doXPixelAlive() done
[15:38:36.013] INFO: PixTest:: pg_setup set to default.
[15:38:36.025] INFO: enter test to run
[15:38:40.208] INFO: test: exit no parameter change
[15:38:40.537] QUIET: Connection to board 33 closed.
[15:38:40.538] INFO: pXar: this is the end, my friend