[15:48:12.031] <TB3> INFO: *** Welcome to pxar ***
[15:48:12.031] <TB3> INFO: *** Today: 2016/09/27
[15:48:12.100] <TB3> INFO: *** Version: f5d3-dirty
[15:48:12.100] <TB3> INFO: readRocDacs: /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//dacParameters35_C15.dat
[15:48:12.101] <TB3> INFO: readTbmDacs: /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//tbmParameters_C0b.dat
[15:48:12.101] <TB3> INFO: readMaskFile: /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//defaultMaskFile.dat
[15:48:12.101] <TB3> INFO: readTrimFile: /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//trimParameters35_C15.dat
[15:48:12.181] <TB3> INFO: clk: 4
[15:48:12.181] <TB3> INFO: ctr: 4
[15:48:12.181] <TB3> INFO: sda: 19
[15:48:12.181] <TB3> INFO: tin: 9
[15:48:12.181] <TB3> INFO: level: 15
[15:48:12.181] <TB3> INFO: triggerdelay: 0
[15:48:12.181] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[15:48:12.181] <TB3> INFO: Log level: INFO
[15:48:12.194] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:48:12.202] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:48:12.205] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:48:12.207] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[15:48:13.785] <TB3> INFO: DUT info:
[15:48:13.786] <TB3> INFO: The DUT currently contains the following objects:
[15:48:13.786] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:48:13.786] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:48:13.786] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:48:13.786] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:48:13.786] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:13.786] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:14.187] <TB3> INFO: enter 'restricted' command line mode
[15:48:14.187] <TB3> INFO: enter test to run
[15:48:14.187] <TB3> INFO: test: delay setting parameters: ->5<-
[15:48:14.187] <TB3> INFO: delay test by 5 seconds...
[15:48:19.187] <TB3> INFO: enter test to run
[15:48:19.187] <TB3> INFO: test: Xray no parameter change
[15:48:19.187] <TB3> INFO: running: xray
[15:48:19.187] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2347_XrayQualification_2016-09-27_17h30m_1474990249//005_HRData_150//phCalibrationFitErr35_C15.dat
[15:48:19.356] <TB3> INFO: ######################################################################
[15:48:19.356] <TB3> INFO: PixTestXray::doTest()
[15:48:19.356] <TB3> INFO: ######################################################################
[15:48:19.356] <TB3> INFO: ----------------------------------------------------------------------
[15:48:19.356] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:48:19.356] <TB3> INFO: ----------------------------------------------------------------------
[15:48:20.083] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:48:24.664] <TB3> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[15:48:51.401] <TB3> INFO: Resuming triggers.
[15:48:56.000] <TB3> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[15:49:23.120] <TB3> INFO: Resuming triggers.
[15:49:27.721] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:49:54.788] <TB3> INFO: Resuming triggers.
[15:49:59.393] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:50:25.490] <TB3> INFO: Resuming triggers.
[15:50:30.088] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:50:35.614] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a25d 80c0 4030 43 2628 4b 264d 4030 9 228d 58 2265 4c1 204f 4cd 2667 81a 224f 4030 20 2a42 44d 2a4d 6cc 244f 4070 ca 284d 301 226d 41d 2a88 4a0 2049 688 2a45 854 222b e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a257 8040 4030 750 244f 4030 50 264b 55c 264a 4030 6d9 206f 4070 43 2244 2c1 2063 648 2441 e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a258 80b0 4030 1b 2a46 8b 2a6d 14b 2467 659 264a 862 2824 4030 613 226d 4030 68c 2a4d 4031 e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a259 80c0 4030 15 224c 5d 2041 555 284b 4030 4d 2a40 49c 2a4f 644 284d 4030 59 2884 303 2681 412 206b 4031 e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a25a 8000 4030 84d 2628 4030 c0 282a d1 2a6c 21a 2a64 513 2a80 6ca 2841 858 2a42 4030 9d 204d 44a 2686 552 2061 4030 119 2a4f 4071 ed5 2530 647 262a 6e8 2450 2d8 262a e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a25b 8040 4030 103 224a 4030 601 2863 642 244d 4030 345 2661 411 2049 41c 2a64 4031 e000 c000
[15:50:35.614] <TB3> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a25c 80b0 4030 250 2845 60d 262f 4030 4030 4 200a 4b 2464 4b 264c 4d2 2042 4070 849 220d e000 c000
[15:50:57.475] <TB3> INFO: Resuming triggers.
[15:51:02.079] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:51:27.916] <TB3> INFO: Resuming triggers.
[15:51:32.516] <TB3> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[15:51:59.768] <TB3> INFO: Resuming triggers.
[15:52:04.372] <TB3> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:52:29.555] <TB3> INFO: Resuming triggers.
[15:52:34.158] <TB3> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:53:01.110] <TB3> INFO: Resuming triggers.
[15:53:05.715] <TB3> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:53:31.448] <TB3> INFO: Resuming triggers.
[15:53:36.051] <TB3> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:54:02.417] <TB3> INFO: Resuming triggers.
[15:54:07.016] <TB3> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:54:32.760] <TB3> INFO: Resuming triggers.
[15:54:37.359] <TB3> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:55:03.785] <TB3> INFO: Resuming triggers.
[15:55:08.385] <TB3> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:55:33.971] <TB3> INFO: Resuming triggers.
[15:55:38.573] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:56:05.817] <TB3> INFO: Resuming triggers.
[15:56:10.413] <TB3> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:56:37.313] <TB3> INFO: Resuming triggers.
[15:56:41.913] <TB3> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:57:07.300] <TB3> INFO: Resuming triggers.
[15:57:11.895] <TB3> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:57:37.738] <TB3> INFO: Resuming triggers.
[15:57:42.341] <TB3> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:58:08.260] <TB3> INFO: Resuming triggers.
[15:58:12.856] <TB3> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:58:39.147] <TB3> INFO: Resuming triggers.
[15:58:43.745] <TB3> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:59:09.726] <TB3> INFO: Resuming triggers.
[15:59:13.192] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[15:59:32.016] <TB3> INFO: PixTest:: pg_setup set to default.
[15:59:32.017] <TB3> INFO: PixTestXray::doPhRun() done
[15:59:32.017] <TB3> INFO: PixTestXray::doTest() done
[15:59:32.233] <TB3> INFO: enter test to run
[15:59:32.233] <TB3> INFO: test: no parameter change
[15:59:32.463] <TB3> QUIET: Connection to board 121 closed.
[15:59:32.546] <TB3> INFO: pXar: this is the end, my friend