[11:21:01.206] <TB0> INFO: *** Welcome to pxar ***
[11:21:01.206] <TB0> INFO: *** Today: 2015/10/31
[11:21:01.222] <TB0> INFO: *** Version: 9da6-dirty
[11:21:01.222] <TB0> INFO: readRocDacs: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//dacParameters35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//dacParameters35_C15.dat
[11:21:01.223] <TB0> INFO: readTbmDacs: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//tbmParameters_C0a.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//tbmParameters_C0b.dat
[11:21:01.224] <TB0> INFO: readMaskFile: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//defaultMaskFile.dat
[11:21:01.224] <TB0> INFO: readTrimFile: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//trimParameters35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//trimParameters35_C15.dat
[11:21:01.392] <TB0> INFO: clk: 4
[11:21:01.392] <TB0> INFO: ctr: 4
[11:21:01.392] <TB0> INFO: sda: 19
[11:21:01.392] <TB0> INFO: tin: 9
[11:21:01.392] <TB0> INFO: level: 15
[11:21:01.392] <TB0> INFO: triggerdelay: 0
[11:21:01.392] <TB0> QUIET: Instanciating API for pxar prod-11
[11:21:01.392] <TB0> INFO: Log level: INFO
[11:21:01.399] <TB0> INFO: Found DTB DTB_WXEOI3
[11:21:01.416] <TB0> QUIET: Connection to board DTB_WXEOI3 opened.
[11:21:01.423] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 163
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXE013
MAC address: 40D8551180A3
Hostname: pixelDTB163
Comment:
------------------------------------------------------
[11:21:01.438] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[11:21:03.066] <TB0> INFO: DUT info:
[11:21:03.066] <TB0> INFO: The DUT currently contains the following objects:
[11:21:03.066] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[11:21:03.071] <TB0> INFO: TBM Core alpha (0): 7 registers set
[11:21:03.071] <TB0> INFO: TBM Core beta (1): 7 registers set
[11:21:03.071] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:21:03.071] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.071] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.071] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.071] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.071] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.071] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.072] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:21:03.475] <TB0> INFO: enter 'restricted' command line mode
[11:21:03.480] <TB0> INFO: enter test to run
[11:21:03.485] <TB0> INFO: test: xray no parameter change
[11:21:03.488] <TB0> INFO: running: xray
[11:21:03.496] <TB0> INFO: readGainPedestalParameters /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//phCalibrationFitErr35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//005_HRData_50//phCalibrationFitErr35_C15.dat
[11:21:03.802] <TB0> INFO: ######################################################################
[11:21:03.808] <TB0> INFO: PixTestXray::doTest()
[11:21:03.813] <TB0> INFO: ######################################################################
[11:21:03.815] <TB0> INFO: ----------------------------------------------------------------------
[11:21:03.815] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:21:03.815] <TB0> INFO: ----------------------------------------------------------------------
[11:21:04.816] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:21:12.931] <TB0> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[11:22:13.022] <TB0> INFO: Resuming triggers.
[11:22:21.153] <TB0> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[11:23:19.334] <TB0> INFO: Resuming triggers.
[11:23:27.459] <TB0> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:24:23.629] <TB0> INFO: Resuming triggers.
[11:24:31.764] <TB0> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:25:24.613] <TB0> INFO: Resuming triggers.
[11:25:32.744] <TB0> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[11:26:25.552] <TB0> INFO: Resuming triggers.
[11:26:33.679] <TB0> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:27:36.144] <TB0> INFO: Resuming triggers.
[11:27:44.279] <TB0> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[11:28:37.602] <TB0> INFO: Resuming triggers.
[11:28:45.741] <TB0> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[11:29:36.106] <TB0> INFO: Resuming triggers.
[11:29:44.246] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:30:35.224] <TB0> INFO: Resuming triggers.
[11:30:43.374] <TB0> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:31:37.719] <TB0> INFO: Resuming triggers.
[11:31:45.871] <TB0> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:32:37.070] <TB0> INFO: Resuming triggers.
[11:32:45.216] <TB0> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:33:38.899] <TB0> INFO: Resuming triggers.
[11:33:41.607] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[11:33:54.133] <TB0> INFO: PixTest:: pg_setup set to default.
[11:33:54.141] <TB0> INFO: PixTestXray::doPhRun() done
[11:33:54.145] <TB0> INFO: PixTestXray::doTest() done
[11:33:54.460] <TB0> INFO: enter test to run
[11:33:54.466] <TB0> INFO: test: no parameter change
[11:33:54.623] <TB0> QUIET: Connection to board 163 closed.
[11:33:54.635] <TB0> INFO: pXar: this is the end, my friend
[11:53:25.401] <TB0> INFO: *** Welcome to pxar ***
[11:53:25.402] <TB0> INFO: *** Today: 2015/10/31
[11:53:25.431] <TB0> INFO: *** Version: 9da6-dirty
[11:53:25.432] <TB0> INFO: readRocDacs: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//dacParameters35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//dacParameters35_C15.dat
[11:53:25.433] <TB0> INFO: readTbmDacs: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//tbmParameters_C0a.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//tbmParameters_C0b.dat
[11:53:25.433] <TB0> INFO: readMaskFile: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//defaultMaskFile.dat
[11:53:25.433] <TB0> INFO: readTrimFile: /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//trimParameters35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//trimParameters35_C15.dat
[11:53:25.627] <TB0> INFO: clk: 4
[11:53:25.631] <TB0> INFO: ctr: 4
[11:53:25.634] <TB0> INFO: sda: 19
[11:53:25.637] <TB0> INFO: tin: 9
[11:53:25.639] <TB0> INFO: level: 15
[11:53:25.641] <TB0> INFO: triggerdelay: 0
[11:53:25.644] <TB0> QUIET: Instanciating API for pxar prod-11
[11:53:25.648] <TB0> INFO: Log level: INFO
[11:53:25.655] <TB0> INFO: Found DTB DTB_WXEOI3
[11:53:25.669] <TB0> QUIET: Connection to board DTB_WXEOI3 opened.
[11:53:25.675] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 163
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXE013
MAC address: 40D8551180A3
Hostname: pixelDTB163
Comment:
------------------------------------------------------
[11:53:25.683] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[11:53:27.383] <TB0> INFO: DUT info:
[11:53:27.383] <TB0> INFO: The DUT currently contains the following objects:
[11:53:27.383] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[11:53:27.388] <TB0> INFO: TBM Core alpha (0): 7 registers set
[11:53:27.388] <TB0> INFO: TBM Core beta (1): 7 registers set
[11:53:27.392] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:53:27.394] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.400] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.401] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.402] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:53:27.804] <TB0> INFO: enter 'restricted' command line mode
[11:53:27.804] <TB0> INFO: enter test to run
[11:53:27.804] <TB0> INFO: test: xray no parameter change
[11:53:27.804] <TB0> INFO: running: xray
[11:53:27.814] <TB0> INFO: readGainPedestalParameters /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//phCalibrationFitErr35_C0.dat .. /home/michelecms/DTB/elComandante/DATA/M3512_XrayHRQualification_2015-10-31_11h13m_1446286414//010_HRData_150//phCalibrationFitErr35_C15.dat
[11:53:28.129] <TB0> INFO: ######################################################################
[11:53:28.130] <TB0> INFO: PixTestXray::doTest()
[11:53:28.130] <TB0> INFO: ######################################################################
[11:53:28.130] <TB0> INFO: ----------------------------------------------------------------------
[11:53:28.130] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:53:28.130] <TB0> INFO: ----------------------------------------------------------------------
[11:53:29.112] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:53:33.027] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[11:55:52.540] <TB0> INFO: Resuming triggers.
[11:55:56.482] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:56:16.728] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L473> Channel 1 has NoTokenPass but 4 ROCs were found
[11:56:16.736] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:56:16.741] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e2 8000 40e8 84d 246c 84d 266d 40e8 109 20af 60d 266d 40e9 3 248f 445 2085 40e9 43 2a84 cd 2281 209 2680 21b 228d 709 2669 85c 2281 40e8 c3 2285 40e8 84d 2285 40e9 40e9 99 2aa7 15b 2069 158 2a4c 693 2aa9 6db 268d e000 c000
[11:56:16.743] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dc 80b1 40e8 40e8 c 2066 40e8 1d 2484 15c 2282 40e8 a 2065 9 288d d1 248c 701 266f 851 2688 851 2a4f 40e8 30b 24c7 409 208f 50a 2869 40e8 54 204f 4d 2885 2e1 2684 40e8 1a 288d 2 246d 6c1 2a85 40e8 44d 2225 608 244c 862 2085 e000 c000
[11:56:16.744] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 40e8 2a2 2a6c 513 2aac 760 2447 40e8 4d1 2a89 682 28a9 40e8 404 288c 442 268f 40e8 401 208f 488 2a65 68b 228d 40e8 602 2889 6e2 2a85 40e8 281 2885 504 286d 40e8 109 2089 548 248f 40e8 200 262d e000 c000
[11:56:16.744] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1de 8000 40e8 29d 2088 75a 284d 84c 2469 40e8 293 28af 293 2482 30d 2080 30b 22c1 651 24a5 643 2a85 6d8 282f 6d8 2a2c 6e1 226e 709 228a 749 20a8 40e8 c 2044 b 286d 4d4 22a3 40e8 4dd 226c 55a 2485 71a 2a6c 40e8 61d 2a8d 620 2a84 40e8 102 2669 6d8 2a4f 753 2ac9 40e8 8 2a61 491 24a8 48a 248f 805 268d 40e8 88 288b 88 2a85 122 268f 122 2a8f e000 c000
[11:56:16.744] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1df 8040 40ea cd 286c c4 204f c4 2225 45a 246f 845 2669 40ea 55b 28ed 552 22c9 40ea 40ea e0f7 c096
[11:56:16.744] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e0 80b1 40e9 208 266e 20a 2aaf 259 2aad 310 22af 51c 2a6f 522 2aa1 40e9 609 20a7 609 226c 753 268c 810 22ad 40e9 203 2aaf 342 20a9 454 20c0 70d 248d 40e9 61b 24c4 842 206a 841 2a65 40e9 85 246c 319 28ae 359 2289 4c8 248f 512 2a85 71a 20a5 40e9 154 2069 154 2a6f 2e2 2487 40e9 645 206f 643 20a5 40e9 41 22c1 58 2a8c e000 c000
[11:56:16.744] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e1 80c0 40e8 343 2a4c 719 2482 40e8 5a 2a82 20c 2247 24c 2085 40e8 253 2281 293 2064 704 2489 40e8 658 2245 6c1 288c 722 2a4f 40e9 160 206c 2e0 226f 51a 2aa5 40e9 685 2a6d 852 208f 40e9 455 2a85 458 226f 562 26ac 40e9 6d3 2a81 6d4 2249 e000 c000
[11:57:24.665] <TB0> INFO: Resuming triggers.
[11:57:28.607] <TB0> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:59:42.049] <TB0> INFO: Resuming triggers.
[11:59:45.995] <TB0> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:01:55.359] <TB0> INFO: Resuming triggers.
[12:01:59.316] <TB0> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[12:03:11.714] <TB0> INFO: Resuming triggers.
[12:03:15.669] <TB0> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:04:28.260] <TB0> INFO: Resuming triggers.
[12:04:32.218] <TB0> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:05:51.095] <TB0> INFO: Resuming triggers.
[12:05:55.057] <TB0> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[12:07:02.380] <TB0> INFO: Resuming triggers.
[12:07:06.342] <TB0> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:08:16.512] <TB0> INFO: Resuming triggers.
[12:08:20.484] <TB0> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[12:09:32.705] <TB0> INFO: Resuming triggers.
[12:09:36.673] <TB0> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[12:10:47.532] <TB0> INFO: Resuming triggers.
[12:10:51.510] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:12:09.845] <TB0> INFO: Resuming triggers.
[12:12:13.821] <TB0> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:13:23.749] <TB0> INFO: Resuming triggers.
[12:13:27.732] <TB0> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:14:46.275] <TB0> INFO: Resuming triggers.
[12:14:50.257] <TB0> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:16:09.178] <TB0> INFO: Resuming triggers.
[12:16:13.176] <TB0> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[12:17:31.140] <TB0> INFO: Resuming triggers.
[12:17:35.129] <TB0> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[12:18:56.903] <TB0> INFO: Resuming triggers.
[12:19:00.896] <TB0> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:20:17.494] <TB0> INFO: Resuming triggers.
[12:20:21.488] <TB0> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[12:21:38.449] <TB0> INFO: Resuming triggers.
[12:21:42.460] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[12:22:59.281] <TB0> INFO: Resuming triggers.
[12:23:03.279] <TB0> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:24:21.836] <TB0> INFO: Resuming triggers.
[12:24:25.838] <TB0> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[12:25:13.875] <TB0> INFO: Resuming triggers.
[12:25:17.883] <TB0> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:26:09.378] <TB0> INFO: Resuming triggers.
[12:26:13.386] <TB0> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:27:12.109] <TB0> INFO: Resuming triggers.
[12:27:16.122] <TB0> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:28:34.436] <TB0> INFO: Resuming triggers.
[12:28:35.378] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[12:28:54.422] <TB0> INFO: PixTest:: pg_setup set to default.
[12:28:54.425] <TB0> INFO: PixTestXray::doPhRun() done
[12:28:54.425] <TB0> INFO: PixTestXray::doTest() done
[12:28:55.096] <TB0> INFO: enter test to run
[12:28:55.113] <TB0> INFO: test: no parameter change
[12:28:55.286] <TB0> QUIET: Connection to board 163 closed.
[12:28:55.293] <TB0> INFO: pXar: this is the end, my friend