[09:34:33.350] <TB2> INFO: *** Welcome to pxar ***
[09:34:33.350] <TB2> INFO: *** Today: 2016/01/19
[09:34:33.409] <TB2> INFO: *** Version: b2d3
[09:34:33.409] <TB2> INFO: readRocDacs: /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//dacParameters35_C15.dat
[09:34:33.409] <TB2> INFO: readTbmDacs: /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//tbmParameters_C0b.dat
[09:34:33.409] <TB2> INFO: readMaskFile: /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//defaultMaskFile.dat
[09:34:33.409] <TB2> INFO: readTrimFile: /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//trimParameters35_C15.dat
[09:34:33.480] <TB2> INFO: clk: 4
[09:34:33.480] <TB2> INFO: ctr: 4
[09:34:33.480] <TB2> INFO: sda: 19
[09:34:33.480] <TB2> INFO: tin: 9
[09:34:33.480] <TB2> INFO: level: 15
[09:34:33.480] <TB2> INFO: triggerdelay: 0
[09:34:33.480] <TB2> QUIET: Instanciating API for pxar prod-12
[09:34:33.480] <TB2> INFO: Log level: INFO
[09:34:33.493] <TB2> INFO: Found DTB DTB_WXENWR
[09:34:33.502] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[09:34:33.505] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[09:34:33.507] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[09:34:35.093] <TB2> INFO: DUT info:
[09:34:35.093] <TB2> INFO: The DUT currently contains the following objects:
[09:34:35.093] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:34:35.093] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:34:35.093] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:34:35.093] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:34:35.093] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.093] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:35.494] <TB2> INFO: enter 'restricted' command line mode
[09:34:35.494] <TB2> INFO: enter test to run
[09:34:35.494] <TB2> INFO: test: delay setting parameters: ->5<-
[09:34:35.494] <TB2> INFO: delay test by 5 seconds...
[09:34:40.494] <TB2> INFO: enter test to run
[09:34:40.494] <TB2> INFO: test: Xray no parameter change
[09:34:40.494] <TB2> INFO: running: xray
[09:34:40.494] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2137_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//phCalibrationFitErr35_C15.dat
[09:34:40.663] <TB2> INFO: ######################################################################
[09:34:40.663] <TB2> INFO: PixTestXray::doTest()
[09:34:40.663] <TB2> INFO: ######################################################################
[09:34:40.663] <TB2> INFO: ----------------------------------------------------------------------
[09:34:40.663] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:34:40.663] <TB2> INFO: ----------------------------------------------------------------------
[09:34:41.634] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:34:44.851] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[09:35:09.273] <TB2> INFO: Resuming triggers.
[09:35:12.504] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:35:37.308] <TB2> INFO: Resuming triggers.
[09:35:40.537] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[09:36:05.623] <TB2> INFO: Resuming triggers.
[09:36:08.850] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[09:36:32.974] <TB2> INFO: Resuming triggers.
[09:36:36.202] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[09:37:00.924] <TB2> INFO: Resuming triggers.
[09:37:04.154] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[09:37:29.688] <TB2> INFO: Resuming triggers.
[09:37:32.917] <TB2> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:37:57.635] <TB2> INFO: Resuming triggers.
[09:38:00.861] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[09:38:25.470] <TB2> INFO: Resuming triggers.
[09:38:28.694] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[09:38:53.885] <TB2> INFO: Resuming triggers.
[09:38:57.109] <TB2> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[09:39:21.598] <TB2> INFO: Resuming triggers.
[09:39:24.820] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[09:39:50.097] <TB2> INFO: Resuming triggers.
[09:39:53.320] <TB2> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[09:40:18.303] <TB2> INFO: Resuming triggers.
[09:40:21.521] <TB2> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:40:45.478] <TB2> INFO: Resuming triggers.
[09:40:48.696] <TB2> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:41:14.372] <TB2> INFO: Resuming triggers.
[09:41:17.591] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[09:41:42.195] <TB2> INFO: Resuming triggers.
[09:41:45.413] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[09:42:10.180] <TB2> INFO: Resuming triggers.
[09:42:13.396] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[09:42:37.909] <TB2> INFO: Resuming triggers.
[09:42:41.129] <TB2> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[09:43:04.967] <TB2> INFO: Resuming triggers.
[09:43:08.184] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[09:43:33.170] <TB2> INFO: Resuming triggers.
[09:43:36.386] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:44:00.976] <TB2> INFO: Resuming triggers.
[09:44:04.191] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[09:44:29.305] <TB2> INFO: Resuming triggers.
[09:44:32.519] <TB2> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:44:57.422] <TB2> INFO: Resuming triggers.
[09:45:00.639] <TB2> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[09:45:25.483] <TB2> INFO: Resuming triggers.
[09:45:28.698] <TB2> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:45:52.686] <TB2> INFO: Resuming triggers.
[09:45:55.903] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:46:20.362] <TB2> INFO: Resuming triggers.
[09:46:23.581] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[09:46:46.992] <TB2> INFO: Resuming triggers.
[09:46:50.207] <TB2> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[09:47:14.266] <TB2> INFO: Resuming triggers.
[09:47:17.481] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:47:42.067] <TB2> INFO: Resuming triggers.
[09:47:45.282] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:48:09.834] <TB2> INFO: Resuming triggers.
[09:48:13.051] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[09:48:35.530] <TB2> INFO: Resuming triggers.
[09:48:38.745] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:48:54.498] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a220 80b1 4c19 320 2464 4c19 20a 244a 648 2a45 4c19 20b 2847 28b 242d 50c 2022 60c 2245 4c19 843 224f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21a 8000 4c18 353 2467 4c18 a1 284f 245 2046 4c2 2042 519 224a 842 2642 4c18 69b 224c 6dc 204f 854 2464 4c18 92 2640 104 2449 688 262d 803 224f e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21b 8040 4c18 4e0 2444 555 2022 4c18 314 2a4d 4c18 212 2446 4c18 d9 2665 21b 2249 495 2a87 fff 2fff e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21c 80b1 4c18 81 2266 862 2a44 4c18 55d 224d 55d 2665 4c18 4c18 60 226a 691 2862 752 206f 754 2647 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21d 80c0 4c18 353 2048 74a 2623 4c18 744 284f 4c18 201 246b 219 2643 2c2 204a 4c18 4c1b 2040 240 2441 29a 2246 2dd 226e e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21e 8000 4c18 80a 224f 4c18 2c3 2a60 60c 2447 713 284a 755 242e 755 2068 4c18 4c18 54c 242f 619 2245 81d 2845 e000 c000
[09:48:54.498] <TB2> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21f 8040 4c1a 8a 2a66 4c1a d 2a6b 20c 2068 2e2 2a4f 514 2223 6da 2064 4c1a 4c1a 40c 2642 e000 c000
[09:49:02.253] <TB2> INFO: Resuming triggers.
[09:49:02.483] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[09:49:04.581] <TB2> INFO: PixTest:: pg_setup set to default.
[09:49:04.583] <TB2> INFO: PixTestXray::doPhRun() done
[09:49:04.583] <TB2> INFO: PixTestXray::doTest() done
[09:49:04.770] <TB2> INFO: enter test to run
[09:49:04.770] <TB2> INFO: test: no parameter change
[09:49:05.027] <TB2> QUIET: Connection to board 162 closed.
[09:49:05.107] <TB2> INFO: pXar: this is the end, my friend