[10:19:07.851] <TB2> INFO: *** Welcome to pxar ***
[10:19:07.851] <TB2> INFO: *** Today: 2016/10/05
[10:19:07.923] <TB2> INFO: *** Version: f5d3-dirty
[10:19:07.923] <TB2> INFO: readRocDacs: /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//dacParameters35_C15.dat
[10:19:07.924] <TB2> INFO: readTbmDacs: /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//tbmParameters_C0b.dat
[10:19:07.924] <TB2> INFO: readMaskFile: /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//defaultMaskFile.dat
[10:19:07.924] <TB2> INFO: readTrimFile: /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//trimParameters35_C15.dat
[10:19:08.009] <TB2> INFO: clk: 4
[10:19:08.009] <TB2> INFO: ctr: 4
[10:19:08.009] <TB2> INFO: sda: 19
[10:19:08.009] <TB2> INFO: tin: 9
[10:19:08.009] <TB2> INFO: level: 15
[10:19:08.009] <TB2> INFO: triggerdelay: 0
[10:19:08.009] <TB2> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[10:19:08.009] <TB2> INFO: Log level: INFO
[10:19:08.023] <TB2> INFO: Found DTB DTB_WXENWR
[10:19:08.034] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:19:08.036] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:19:08.037] <TB2> INFO: RPC call hashes of host and DTB match: 486171790
[10:19:09.571] <TB2> INFO: DUT info:
[10:19:09.571] <TB2> INFO: The DUT currently contains the following objects:
[10:19:09.571] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:19:09.571] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:19:09.571] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:19:09.571] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:19:09.571] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.571] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:19:09.971] <TB2> INFO: enter 'restricted' command line mode
[10:19:09.972] <TB2> INFO: enter test to run
[10:19:09.972] <TB2> INFO: test: delay setting parameters: ->5<-
[10:19:09.972] <TB2> INFO: delay test by 5 seconds...
[10:19:14.972] <TB2> INFO: enter test to run
[10:19:14.972] <TB2> INFO: test: Xray no parameter change
[10:19:14.972] <TB2> INFO: running: xray
[10:19:14.972] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2350_XrayQualification_2016-10-05_12h02m_1475661724//005_HRData_150//phCalibrationFitErr35_C15.dat
[10:19:15.114] <TB2> INFO: ######################################################################
[10:19:15.114] <TB2> INFO: PixTestXray::doTest()
[10:19:15.114] <TB2> INFO: ######################################################################
[10:19:15.114] <TB2> INFO: ----------------------------------------------------------------------
[10:19:15.114] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:19:15.114] <TB2> INFO: ----------------------------------------------------------------------
[10:19:15.837] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:19:19.809] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[10:19:45.535] <TB2> INFO: Resuming triggers.
[10:19:49.520] <TB2> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:20:16.158] <TB2> INFO: Resuming triggers.
[10:20:20.145] <TB2> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:20:46.241] <TB2> INFO: Resuming triggers.
[10:20:50.227] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[10:21:16.125] <TB2> INFO: Resuming triggers.
[10:21:20.111] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:21:45.572] <TB2> INFO: Resuming triggers.
[10:21:49.558] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:22:15.933] <TB2> INFO: Resuming triggers.
[10:22:19.921] <TB2> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:22:45.423] <TB2> INFO: Resuming triggers.
[10:22:49.409] <TB2> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:23:16.884] <TB2> INFO: Resuming triggers.
[10:23:20.872] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:23:46.548] <TB2> INFO: Resuming triggers.
[10:23:50.535] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:24:16.666] <TB2> INFO: Resuming triggers.
[10:24:20.650] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[10:24:47.232] <TB2> INFO: Resuming triggers.
[10:24:51.218] <TB2> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:25:18.395] <TB2> INFO: Resuming triggers.
[10:25:22.377] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:25:50.080] <TB2> INFO: Resuming triggers.
[10:25:54.061] <TB2> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:26:20.811] <TB2> INFO: Resuming triggers.
[10:26:24.796] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:26:52.154] <TB2> INFO: Resuming triggers.
[10:26:56.137] <TB2> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:27:23.963] <TB2> INFO: Resuming triggers.
[10:27:27.946] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:27:53.989] <TB2> INFO: Resuming triggers.
[10:27:57.972] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:28:23.724] <TB2> INFO: Resuming triggers.
[10:28:27.706] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[10:28:53.101] <TB2> INFO: Resuming triggers.
[10:28:57.081] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:29:22.388] <TB2> INFO: Resuming triggers.
[10:29:26.368] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:29:53.708] <TB2> INFO: Resuming triggers.
[10:29:57.685] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[10:30:23.307] <TB2> INFO: Resuming triggers.
[10:30:27.283] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:30:31.180] <TB2> WARNING: Channel 2 ROC 2: Readback start marker after 7 readouts!
[10:30:31.180] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 2 Number of ROCs (5) != Token Chain Length (4)
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d9 80c0 4300 143 2667 84b 2a6c 84c 224f 4300 10a 2446 35d 2862 351 244f 350 2240 6cb 204f 853 284f 4300 4300 14b 266d 2d9 2a65 30d 206d 609 2a6f 61c 2a41 e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d3 8040 4300 348 2a4a 4d3 2269 4301 701 2445 803 2681 4300 58 264a 761 2a46 845 2645 4381 31d 246e 51b 2065 e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d4 80b0 4300 481 264a 48d 2660 605 2a67 684 244a 4300 2c5 2069 35c 2241 84b 204c 4300 dc 266b 512 2649 4700 161 2481 248 2a2e 24d 226f 252 2661 e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d5 80c0 4300 154 2260 35d 246e 542 2041 762 2a6f 85c 2065 4300 55a 2229 4300 210 286a 201 224f 280 2a8a 292 2682 4300 209 226e 4ca 246c 604 244d 69b 244f 6db 2269 e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d6 8000 4300 84 2844 215 202f 4300 4303 dc0 2171 100 2091 140 2294 d45 2240 945 2214 4300 10a 2266 24a 226c 28a 208a 289 286f 2c9 2268 4300 54 2269 59 228d 2c4 2a4f 745 224e 811 226f e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d7 8040 4300 93 2284 4300 81c 222f 848 2647 4300 b 2069 3 2a4f 5c 2848 2c9 2064 2d0 2240 554 2a61 819 2260 4300 59 206e 4c2 2a49 e000 c000
[10:30:31.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a2d8 80b0 4300 35c 2065 49c 204a 4300 61a 2a4d 6c0 2440 4300 81d 204c 4300 462 2464 492 2a81 e000 c000
[10:30:53.116] <TB2> INFO: Resuming triggers.
[10:30:57.093] <TB2> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:31:23.329] <TB2> INFO: Resuming triggers.
[10:31:27.305] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:31:52.137] <TB2> INFO: Resuming triggers.
[10:31:52.666] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[10:31:56.042] <TB2> INFO: PixTest:: pg_setup set to default.
[10:31:56.043] <TB2> INFO: PixTestXray::doPhRun() done
[10:31:56.043] <TB2> INFO: PixTestXray::doTest() done
[10:31:56.219] <TB2> INFO: enter test to run
[10:31:56.219] <TB2> INFO: test: no parameter change
[10:31:56.508] <TB2> QUIET: Connection to board 162 closed.
[10:31:56.588] <TB2> INFO: pXar: this is the end, my friend