[11:22:47.147] INFO: *** Welcome to pxar ***
[11:22:47.147] INFO: *** Today: 2016/06/02
[11:22:47.170] INFO: *** Version: v1.9.0-805-geb36
[11:22:47.170] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo317/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo317/dacParameters35_C15.dat
[11:22:47.171] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo317/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo317/tbmParameters_C0b.dat
[11:22:47.171] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo317/defaultMaskFile.dat
[11:22:47.171] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo317/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo317/trimParameters35_C15.dat
[11:22:47.324] INFO: clk: 4
[11:22:47.324] INFO: ctr: 4
[11:22:47.324] INFO: sda: 19
[11:22:47.324] INFO: tin: 9
[11:22:47.324] INFO: level: 15
[11:22:47.324] INFO: triggerdelay: 0
[11:22:47.324] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:22:47.324] INFO: Log level: INFO
[11:22:47.335] QUIET: Connection to board DTB_WRPRHI opened.
[11:22:47.338] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:22:47.341] INFO: RPC call hashes of host and DTB match: 398089610
[11:22:48.940] INFO: DUT info:
[11:22:48.940] INFO: The DUT currently contains the following objects:
[11:22:48.940] INFO: 2 TBM Cores tbm08c (2 ON)
[11:22:48.941] INFO: TBM Core alpha (0): 7 registers set
[11:22:48.941] INFO: TBM Core beta (1): 7 registers set
[11:22:48.941] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:22:48.941] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:48.941] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:49.532] INFO: PixTestCmd::init()
[11:22:49.537] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo317/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo317/phCalibrationFitErr35_C15.dat
[11:22:49.833] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo317/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo317/readbackCal_C15.dat
[11:23:00.163] INFO: ######################################################################
[11:23:00.163] INFO: PixTestXray::doTest()
[11:23:00.163] INFO: ######################################################################
[11:23:00.163] INFO: ----------------------------------------------------------------------
[11:23:00.163] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:23:00.163] INFO: ----------------------------------------------------------------------
[11:23:01.136] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:23:13.660] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:23:30.537] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 9 ROCs were found
[11:23:30.537] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04b 8040 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a045 80c0 4fe8 4fe8 4fe8 4fe8 60b 2642 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a046 8000 4fe8 4fe8 51c 2845 4fe8 4fe8 4fe8 4fe8 4fe8 4c2 2648 4fe8 e000 c000
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a047 8040 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a048 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 35b 2041 4fe8 4fe8 4fe8 4fe8 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 fff 2fff fff 2fff fff 2fff fff
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a049 80c0 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 255 244d 4fe8 44b 264f e000 c000
[11:23:30.537] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04a 8000 4fe8 404 284f 4fe8 4fe8 4fe8 280 2621 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 9 ROCs were found
[11:23:30.687] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d9 80c0 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 31a 224d 31a 264c 4fe8 4fe8 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d3 8040 4fe8 4fe9 503 226f 4fe8 4fe9 4fe8 4fe9 4fe8 4fe9 350 2645 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d4 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d5 80c0 4fe8 4fe8 4fe8 4fe8 35d 2069 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0d6 8000 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 412 222d 4fe8 4fe8 2200 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 fff 2fff fff 2fff fff 2fff fff
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d7 8040 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:23:30.687] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0d8 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 548 2240 4fe8 e000 c000
[11:23:44.191] INFO: Resuming triggers.
[11:23:56.711] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[11:24:27.320] INFO: Resuming triggers.
[11:24:39.841] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[11:24:41.618] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 9 ROCs were found
[11:24:41.618] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09c 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 213 224f 4fe8 4fe8 4fe8 e000 c000
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a096 8000 4fe8 85b 284c 4fe8 34b 264d 4fe8 4fe8 4fe8 4fe8 492 2245 4fe8 cb 224d 755 2a85 4fe8 60a 244d e000 c000
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a097 8040 4fe8 4fe8 4fe8 4fe8 4fe8 611 204c 4fe8 4fe8 51a 222d 4fe8 e000 c000
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a098 80b1 4fe8 31d 226c 610 2847 4fe8 160 222d 44c 2049 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a099 80c0 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 319 264f 4fe8 4fe8 4fe8 2600 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 fff 2fff fff 2fff fff 2fff fff
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09a 8000 4fe8 41c 264f 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4cb 266f e000 c000
[11:24:41.618] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09b 8040 4fe8 4fe8 4fe8 4fe8 4fe8 2d2 2843 4fe8 10a 2242 4fe8 4fe8 e000 c000
[11:25:10.350] INFO: Resuming triggers.
[11:25:22.873] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[11:25:31.999] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[11:25:31.999] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01c 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 4fe8 4fe8 4fe8 4cc 2a2b 4fe8 4fe8 4fe8 4 2a41 4fe8 6c3 2249 4fe8 108 282d e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 4fe8 49a 2048 4fe8 4fe8 4fe8 212 2862 4fe8 68d 244e 680 244f 4fe8 4fe8 520 246a 4fe8 e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 4fe8 4fe8 4fe8 9c 222c 4fe8 4fe8 281 2484 4fe8 1f8 4fe8 e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01a 8000 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 da 2449 4fe8 159 2247 e000 c000
[11:25:31.999] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01b 8040 4fe8 4fe8 35a 264b 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 9 ROCs were found
[11:25:40.963] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a009 80c0 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 491 2862 4fe8 4fe8 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 4fe8 4fe9 4fe8 4fe9 549 204f 4fe8 4fe9 4fe8 4fe9 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a004 80b1 4fe8 40b 2045 4fe8 d3 2a68 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a005 80c0 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 249 222f 4fe8 4fe8 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a006 8000 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4fe8 4d1 242d 4fe8 4fe8 2400 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 fff 2fff fff 2fff fff 2fff fff
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a007 8040 4fe8 4fe8 4fe8 6c3 224d 4fe8 511 2a66 4fe8 84a 224f 4fe8 dd 202c 4fe8 4fe8 e000 c000
[11:25:40.963] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a008 80b1 4fe8 4fe8 4fe8 4fe8 94 284d 4fe8 4fe8 4fe8 4fe8 e000 c000
[11:25:53.361] INFO: Resuming triggers.
[11:26:03.351] INFO: data taking finished, elapsed time: 60 seconds.
[11:26:27.691] INFO: PixTest:: pg_setup set to default.
[11:26:27.782] INFO: PixTestXray::doPhRun() done
[11:26:27.782] INFO: PixTestXray::doTest() done
[11:27:01.130] QUIET: Connection to board 58 closed.