[14:00:25.442] <TB1> INFO: *** Welcome to pxar ***
[14:00:25.442] <TB1> INFO: *** Today: 2016/06/15
[14:00:25.512] <TB1> INFO: *** Version: 2385
[14:00:25.512] <TB1> INFO: readRocDacs: /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//dacParameters35_C15.dat
[14:00:25.513] <TB1> INFO: readTbmDacs: /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//tbmParameters_C0b.dat
[14:00:25.513] <TB1> INFO: readMaskFile: /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//defaultMaskFile.dat
[14:00:25.513] <TB1> INFO: readTrimFile: /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//trimParameters35_C15.dat
[14:00:25.589] <TB1> INFO: clk: 4
[14:00:25.589] <TB1> INFO: ctr: 4
[14:00:25.589] <TB1> INFO: sda: 19
[14:00:25.589] <TB1> INFO: tin: 9
[14:00:25.589] <TB1> INFO: level: 15
[14:00:25.589] <TB1> INFO: triggerdelay: 0
[14:00:25.589] <TB1> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[14:00:25.589] <TB1> INFO: Log level: INFO
[14:00:25.601] <TB1> INFO: Found DTB DTB_WWXTQT
[14:00:25.613] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[14:00:25.615] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[14:00:25.617] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[14:00:27.209] <TB1> INFO: DUT info:
[14:00:27.209] <TB1> INFO: The DUT currently contains the following objects:
[14:00:27.209] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:00:27.209] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:00:27.209] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:00:27.209] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:00:27.209] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.209] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.209] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.209] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.210] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:27.611] <TB1> INFO: enter 'restricted' command line mode
[14:00:27.611] <TB1> INFO: enter test to run
[14:00:27.611] <TB1> INFO: test: delay setting parameters: ->5<-
[14:00:27.611] <TB1> INFO: delay test by 5 seconds...
[14:00:32.612] <TB1> INFO: enter test to run
[14:00:32.612] <TB1> INFO: test: Xray no parameter change
[14:00:32.612] <TB1> INFO: running: xray
[14:00:32.612] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2254_XrayQualification_2016-06-15_15h42m_1465998135//005_HRData_150//phCalibrationFitErr35_C15.dat
[14:00:32.883] <TB1> INFO: ######################################################################
[14:00:32.883] <TB1> INFO: PixTestXray::doTest()
[14:00:32.883] <TB1> INFO: ######################################################################
[14:00:32.883] <TB1> INFO: ----------------------------------------------------------------------
[14:00:32.883] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:00:32.883] <TB1> INFO: ----------------------------------------------------------------------
[14:00:33.609] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:00:36.939] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[14:01:06.418] <TB1> INFO: Resuming triggers.
[14:01:09.758] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:01:38.731] <TB1> INFO: Resuming triggers.
[14:01:42.069] <TB1> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:02:11.724] <TB1> INFO: Resuming triggers.
[14:02:15.063] <TB1> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:02:44.487] <TB1> INFO: Resuming triggers.
[14:02:47.827] <TB1> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[14:03:17.551] <TB1> INFO: Resuming triggers.
[14:03:20.887] <TB1> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:03:50.301] <TB1> INFO: Resuming triggers.
[14:03:53.637] <TB1> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:04:23.376] <TB1> INFO: Resuming triggers.
[14:04:26.711] <TB1> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:04:55.819] <TB1> INFO: Resuming triggers.
[14:04:59.152] <TB1> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:05:29.602] <TB1> INFO: Resuming triggers.
[14:05:32.937] <TB1> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:06:02.770] <TB1> INFO: Resuming triggers.
[14:06:06.106] <TB1> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:06:13.219] <TB1> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[14:06:13.219] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (222) != TBM ID (223)
[14:06:13.219] <TB1> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[14:06:13.219] <TB1> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1e2 8000 41c0 284 200f 60d 2081 695 264a 744 2241 41c1 5 2025 3 284c 241 2a4d 280 244f 411 2a6a 821 2a4a 41c0 c8 2a64 610 2a4f 41c1 d1 244f 751 2a63 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1dc 80b0 41c0 311 2866 493 2667 652 2460 854 2a61 41c0 12 2861 5 224f 218 286d 842 2a4d 41c0 5 282f c9 2a69 24b 2049 30a 2a45 859 2685 40c1 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1dd 80c0 41c0 41c0 41c0 20 2444 88 266c 44d 2a43 4c5 286c 6d2 244e 6d2 2063 84b 204f 41c0 52 204d 255 2282 288 2249 4dd 2063 749 2647 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1de 8000 41c0 7f 2ce1 84 2230 88c 2323 a4 2426 129 2126 186 2536 b48 2c21 3a8 2320 426 2416 c2a 2b26 bfd 21a5 316 23fd 6 2421 12c 2212 b49 2d26 3fc 2101 20 2b00 c36 236e 424 2bff 0 207f fff 2fff f2ff d2ff
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1df 8040 41c2 114 266d 154 2262 40e0 308 2841 652 264f 71b 2668 41c0 35b 2883 801 2885 41c2 41b 2445 540 242d 851 224b e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1e0 80b0 41c0 4d4 2a8e 849 2445 41c1 482 2269 718 2868 41c1 849 2463 41c0 14c 2645 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1e1 80c0 41c1 60a 2669 41c0 451 2a6c 552 2269 41c1 41c1 d5 2869 242 2069 408 284a 480 2441 491 2269 e000 c000
[14:06:13.219] <TB1> WARNING: Channel 1 ROC 1: Readback start marker after 31 readouts!
[14:06:13.219] <TB1> WARNING: Channel 1 ROC 2: Readback start marker after 31 readouts!
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1f1 80c0 41c1 41c0 218 262f 488 2088 491 2a60 518 2a49 41e1 645 288d 689 2666 41c1 25c 2461 305 284f 561 2669 808 204f 841 2247 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1eb 8040 41c0 210 2262 34a 224e 4c9 2860 680 2a40 762 2467 41c0 485 2a83 859 2667 41c0 293 2287 29b 2681 41c0 cb 2a4f e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ec 80b0 40c0 5 2480 18 2a6f 2c3 2667 35b 248d 55d 2446 650 2a47 800 244f 822 2660 41c0 204 284a 302 2243 341 2a4d 522 2464 515 2268 69d 200f 41c0 d3 266d 2c8 242d 41c0 3 286d 408 2042 48b 2469 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ed 80c0 41c0 4d 222f 69b 282d 689 242d 41c0 2d1 2065 31b 264d 51d 2445 41c0 354 2065 51b 2685 41c0 51 2a4d 8b 2a69 14d 2280 294 2a26 312 222f 519 2a80 85b 2a8f 861 2663 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ee 8000 41c0 344 284e 480 2665 859 2265 41c2 18 2065 650 244f 41c2 5 2a47 144 266d 31a 286f 518 204d 55c 246e 41c0 8c 2a45 4d9 2665 e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ef 8040 40c2 260 2444 4e2 2a64 50d 224e 849 2063 41c0 34a 222c 4d8 244d 41c0 742 2445 80c 264c 41c2 840 2a43 858 264f e000 c000
[14:06:13.219] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1f0 80b0 40c0 103 2864 41c1 59 2a65 d4 286c 455 2448 445 246e 445 2042 500 2842 41c1 41c0 25a 204f 29b 246f 51a 2065 e000 c000
[14:06:36.127] <TB1> INFO: Resuming triggers.
[14:06:39.463] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[14:07:09.163] <TB1> INFO: Resuming triggers.
[14:07:12.494] <TB1> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:07:42.522] <TB1> INFO: Resuming triggers.
[14:07:45.855] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:08:16.113] <TB1> INFO: Resuming triggers.
[14:08:19.447] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:08:49.418] <TB1> INFO: Resuming triggers.
[14:08:52.752] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[14:09:22.885] <TB1> INFO: Resuming triggers.
[14:09:26.217] <TB1> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:09:56.531] <TB1> INFO: Resuming triggers.
[14:09:59.864] <TB1> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:10:30.385] <TB1> INFO: Resuming triggers.
[14:10:33.718] <TB1> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:11:03.873] <TB1> INFO: Resuming triggers.
[14:11:07.206] <TB1> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:11:37.175] <TB1> INFO: Resuming triggers.
[14:11:40.505] <TB1> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:12:11.009] <TB1> INFO: Resuming triggers.
[14:12:14.339] <TB1> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[14:12:44.812] <TB1> INFO: Resuming triggers.
[14:12:48.143] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:13:18.402] <TB1> INFO: Resuming triggers.
[14:13:21.733] <TB1> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:13:51.226] <TB1> INFO: Resuming triggers.
[14:13:54.556] <TB1> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:14:24.391] <TB1> INFO: Resuming triggers.
[14:14:27.730] <TB1> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:14:56.941] <TB1> INFO: Resuming triggers.
[14:15:00.271] <TB1> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[14:15:32.047] <TB1> INFO: Resuming triggers.
[14:15:35.375] <TB1> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:16:05.147] <TB1> INFO: Resuming triggers.
[14:16:08.476] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:16:36.977] <TB1> INFO: Resuming triggers.
[14:16:40.302] <TB1> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:17:10.617] <TB1> INFO: Resuming triggers.
[14:17:10.702] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[14:17:11.836] <TB1> INFO: PixTest:: pg_setup set to default.
[14:17:11.837] <TB1> INFO: PixTestXray::doPhRun() done
[14:17:11.837] <TB1> INFO: PixTestXray::doTest() done
[14:17:12.025] <TB1> INFO: enter test to run
[14:17:12.025] <TB1> INFO: test: no parameter change
[14:17:12.251] <TB1> QUIET: Connection to board 147 closed.
[14:17:12.331] <TB1> INFO: pXar: this is the end, my friend