[12:54:03.593] <TB0> INFO: *** Welcome to pxar ***
[12:54:03.593] <TB0> INFO: *** Today: 2015/11/23
[12:54:03.984] <TB0> INFO: *** Version: 206d-dirty
[12:54:03.984] <TB0> INFO: readRocDacs: /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C15.dat
[12:54:03.984] <TB0> INFO: readTbmDacs: /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0b.dat
[12:54:03.984] <TB0> INFO: readMaskFile: /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//defaultMaskFile.dat
[12:54:03.984] <TB0> INFO: readTrimFile: /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C15.dat
[12:54:04.052] <TB0> INFO: clk: 4
[12:54:04.052] <TB0> INFO: ctr: 4
[12:54:04.052] <TB0> INFO: sda: 19
[12:54:04.052] <TB0> INFO: tin: 9
[12:54:04.052] <TB0> INFO: level: 15
[12:54:04.052] <TB0> INFO: triggerdelay: 0
[12:54:04.052] <TB0> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:54:04.052] <TB0> INFO: Log level: INFO
[12:54:04.065] <TB0> INFO: Found DTB DTB_WS6UZO
[12:54:04.076] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[12:54:04.079] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[12:54:04.082] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[12:54:05.615] <TB0> INFO: DUT info:
[12:54:05.615] <TB0> INFO: The DUT currently contains the following objects:
[12:54:05.615] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[12:54:05.615] <TB0> INFO: TBM Core alpha (0): 7 registers set
[12:54:05.615] <TB0> INFO: TBM Core beta (1): 7 registers set
[12:54:05.615] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:54:05.615] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:05.615] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:06.016] <TB0> INFO: enter 'restricted' command line mode
[12:54:06.016] <TB0> INFO: enter test to run
[12:54:06.016] <TB0> INFO: test: delay setting parameters: ->5<-
[12:54:06.016] <TB0> INFO: delay test by 5 seconds...
[12:54:11.016] <TB0> INFO: enter test to run
[12:54:11.016] <TB0> INFO: test: Xray no parameter change
[12:54:11.016] <TB0> INFO: running: xray
[12:54:11.018] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2119_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C15.dat
[12:54:11.146] <TB0> INFO: ######################################################################
[12:54:11.146] <TB0> INFO: PixTestXray::doTest()
[12:54:11.146] <TB0> INFO: ######################################################################
[12:54:11.146] <TB0> INFO: ----------------------------------------------------------------------
[12:54:11.146] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:54:11.146] <TB0> INFO: ----------------------------------------------------------------------
[12:54:12.111] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:54:15.732] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[12:54:51.082] <TB0> INFO: Resuming triggers.
[12:54:54.713] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:55:27.748] <TB0> INFO: Resuming triggers.
[12:55:31.377] <TB0> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[12:56:05.006] <TB0> INFO: Resuming triggers.
[12:56:08.634] <TB0> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:56:35.181] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L477> Channel 1 has NoTokenPass but 4 ROCs were found
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a149 80c0 4e38 4e38 5 2a62 6d4 264f 71a 2242 845 2662 4e38 62 246e 205 284f 4e38 298 2649 50b 2645 e000 c000
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a143 8040 4e38 22 2a4f 214 206f 704 264d 4e39 2d4 206f 4e38 10a 2864 854 2a4f 4e39 fff 2fff e000 c000
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a144 80b1 4e38 20 204a 28b 28a5 684 2043 4e38 145 2a65 341 2685 4d8 2882 4e38 3 226e 609 2884 4e38 d5 2466 703 2447 e000 c000
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a145 80c0 4e38 4e38 4e38 159 2a60 422 2464 862 266e 4e38 4c 2666 144 284f 159 226c 159 2669 358 2269 6d9 2461 6d0 248a 6ca 2282 6d2 2a42 e000 c000
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a146 8000 4e38 4e38 340 2669 409 224c 682 286c 84d 2a82 85c 226a 85c 2666 861 2282 4e38 655 2261 4e38 18 2843 283 246f 483 206f e0f7 c0a6
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a147 8040 4e38 98 2846 48d 2048 6c5 2844 4e38 24b 228a 604 262f 4e38 700 2a29 860 226a 4e38 21c 2a68 602 224a e000 c000
[12:56:35.181] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a148 80b1 4e38 248 2068 6dc 2069 4e38 808 2829 4e38 309 206a 34d 2840 4e38 9d 2869 d0 2646 142 2043 200 2463 642 2266 e000 c000
[12:56:42.813] <TB0> INFO: Resuming triggers.
[12:56:46.438] <TB0> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[12:57:19.091] <TB0> INFO: Resuming triggers.
[12:57:22.721] <TB0> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:57:56.734] <TB0> INFO: Resuming triggers.
[12:58:00.364] <TB0> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[12:58:33.349] <TB0> INFO: Resuming triggers.
[12:58:36.974] <TB0> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:59:09.899] <TB0> INFO: Resuming triggers.
[12:59:13.528] <TB0> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[12:59:46.118] <TB0> INFO: Resuming triggers.
[12:59:49.745] <TB0> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:00:22.314] <TB0> INFO: Resuming triggers.
[13:00:25.942] <TB0> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:00:59.619] <TB0> INFO: Resuming triggers.
[13:01:03.244] <TB0> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:01:35.729] <TB0> INFO: Resuming triggers.
[13:01:39.353] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:02:12.241] <TB0> INFO: Resuming triggers.
[13:02:15.869] <TB0> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:02:47.413] <TB0> INFO: Resuming triggers.
[13:02:51.037] <TB0> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:03:22.818] <TB0> INFO: Resuming triggers.
[13:03:26.443] <TB0> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:03:57.603] <TB0> INFO: Resuming triggers.
[13:04:01.225] <TB0> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:04:32.121] <TB0> INFO: Resuming triggers.
[13:04:35.743] <TB0> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:05:05.373] <TB0> INFO: Resuming triggers.
[13:05:08.998] <TB0> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:05:39.522] <TB0> INFO: Resuming triggers.
[13:05:43.145] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:06:13.945] <TB0> INFO: Resuming triggers.
[13:06:17.569] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:06:49.465] <TB0> INFO: Resuming triggers.
[13:06:53.089] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:07:24.988] <TB0> INFO: Resuming triggers.
[13:07:28.611] <TB0> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:07:59.344] <TB0> INFO: Resuming triggers.
[13:08:02.964] <TB0> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[13:08:34.414] <TB0> INFO: Resuming triggers.
[13:08:38.036] <TB0> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:09:09.374] <TB0> INFO: Resuming triggers.
[13:09:12.996] <TB0> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:09:44.990] <TB0> INFO: Resuming triggers.
[13:09:48.611] <TB0> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:10:19.754] <TB0> INFO: Resuming triggers.
[13:10:21.968] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[13:10:39.971] <TB0> INFO: PixTest:: pg_setup set to default.
[13:10:39.973] <TB0> INFO: PixTestXray::doPhRun() done
[13:10:39.973] <TB0> INFO: PixTestXray::doTest() done
[13:10:40.167] <TB0> INFO: enter test to run
[13:10:40.167] <TB0> INFO: test: no parameter change
[13:10:40.300] <TB0> QUIET: Connection to board 83 closed.
[13:10:40.381] <TB0> INFO: pXar: this is the end, my friend