search

xtrx-sdr / images

Pre-built XTRX packages and firmware images
https://www.crowdsupply.com/fairwaves/xtrx
44 stars 27 forks source link

Corrupt data issues #40

Open schnet25 opened 5 years ago

schnet25 commented 5 years ago

I am having trouble receiving data from my xtrx. I think it is an issue with my setup; however, I am posting an issue just in case it isn't. When I try to capture using your test_xtrx binary I get bad data. Here is my call:

$ ./test_xtrx --dmarx --o captured.bin --samples=262144 RX_TO_FILE: thread start Master: 32.000 MHz; RX rate: 4.000 MHz; TX rate: 0.000 MHz RX tunned: 900000000.000000 RX bandwidth: 2000000.000000 RX LNA gain: 15.000000 RX PGA gain: 0.000000 RX TIA gain: 9.000000 RX CYCLES=1 SAMPLES=262144 SLICE=16384 (PARTS=16) PROCESSED RX SLICE 0 /0: res 0 TS: 8192 4386 us DELTA 34 us LATE 2372 us 16384 samples PROCESSED RX SLICE 0 /1: res 0 TS: 16384 1923 us DELTA 62 us LATE 2310 us 16384 samples PROCESSED RX SLICE 0 /2: res 0 TS: 24576 2473 us DELTA 45 us LATE 2781 us 16384 samples PROCESSED RX SLICE 0 /3: res 0 TS: 32768 1376 us DELTA 159 us LATE 2269 us 16384 samples PROCESSED RX SLICE 0 /4: res 0 TS: 40960 11452 us DELTA 36 us LATE 11709 us 16384 samples PROCESSED RX SLICE 0 /5: res 0 TS: 49152 364 us DELTA 97 us LATE 10122 us 16384 samples PROCESSED RX SLICE 0 /6: res 0 TS: 57344 380 us DELTA 44 us LATE 8498 us 16384 samples PROCESSED RX SLICE 0 /7: res 0 TS: 65536 389 us DELTA 48 us LATE 6889 us 16384 samples PROCESSED RX SLICE 0 /8: res 0 TS: 73728 358 us DELTA 44 us LATE 5244 us 16384 samples PROCESSED RX SLICE 0 /9: res 0 TS: 81920 344 us DELTA 49 us LATE 3590 us 16384 samples PROCESSED RX SLICE 0 /10: res 0 TS: 90112 882 us DELTA 48 us LATE 2472 us 16384 samples PROCESSED RX SLICE 0 /11: res 0 TS: 98304 2022 us DELTA 72 us LATE 2519 us 16384 samples PROCESSED RX SLICE 0 /12: res 0 TS: 106496 2071 us DELTA 91 us LATE 2633 us 16384 samples PROCESSED RX SLICE 0 /13: res 0 TS: 114688 1935 us DELTA 114 us LATE 2635 us 16384 samples PROCESSED RX SLICE 0 /14: res 0 TS: 122880 1967 us DELTA 131 us LATE 2685 us 16384 samples PROCESSED RX SLICE 0 /15: res 0 TS: 131072 1630 us DELTA 188 us LATE 2455 us 16384 samples RX STAT Overruns:0 RX_TO_FILE: buffer 0 written RX_TO_FILE: thread exit: 1:1 Success! Processed 1 devs, each: RX 2 x 3.711 = 7.422 MSPS (WIRE: 29.689563) TX 2 x 0.000 = 0.000 MSPS (WIRE: 0.000000 MB/s)

All looks good; however, when I inspected the data I see that it is not from the device. Some mem mapping is wrong somewhere and I am getting random kernel buffers back....

$ strings captured.bin 1-8-cross.2.0-1ubuntu2~18.04 5.26.1-6ubuntu0.2 0.9.6~r156-6ubuntu0.18.04.1 6.1.1-2ubuntu0.1 reUnitE19UMeasureFormatWidthiR10UErrorCode _ZNK6icu_6013MeasureFormat18getPluralFormatterERKNS_11MeasureUnitE19UMeasureFormatWidthiR10UErrorCode _ZNK6icu_6013MeasureFormat15getPerFormatterE19UMeasureFormatWidthR10UErrorCode _ZNK6icu_6013MeasureFormat20withPerUnitAndAppendERKNS_13UnicodeStringERKNS_11MeasureUnitERS1_R10UErrorCode _ZNK6icu_6015SimpleFormatter15formatAndAppendEPKPKNS_13UnicodeStringEiRS1_PiiR10UErrorCode _ZN6icu_6015SimpleFormatter22getTextWithNoArgumentsEPKDsi _ZN6icu_6013MeasureFormat20createCurrencyFormatERKNS_6LocaleER10UErrorCode _ZN6icu_6014CurrencyFormatC1ERKNS_6LocaleER10UErrorCode _ZN6icu_6014CurrencyFormatD0Ev _ZN6icu_6013MeasureFormat20createCurrencyFormatER10UErrorCode _ZN6icu_6022MeasureFormatCacheDataD2Ev _ZN6icu_6022MeasureFormatCacheDataD1Ev _ZN6icu_6022MeasureFormatCacheDataD0Ev _ZN6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEED2Ev _ZN6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEED1Ev _ZN6icu_6013MeasureFormat17initMeasureFormatERKNS_6LocaleE19UMeasureFormatWidthPNS_12NumberFormatER10UErrorCode _ZN6icu_6011PluralRules20createSharedInstanceERKNS_6LocaleE11UPluralTypeR10UErrorCode _ZN6icu_6013ListFormatter14createInstanceERKNS_6LocaleEPKcR10UErrorCode _ZN6icu_6013MeasureFormatC2ERKNS_6LocaleE19UMeasureFormatWidthR10UErrorCode _ZN6icu_6013MeasureFormatC1ERKNS_6LocaleE19UMeasureFormatWidthR10UErrorCode _ZN6icu_6013MeasureFormatC2ERKNS_6LocaleE19UMeasureFormatWidthPNS_12NumberFormatER10UErrorCode _ZN6icu_6013MeasureFormatC1ERKNS_6LocaleE19UMeasureFormatWidthPNS_12NumberFormatER10UErrorCode _ZN6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEED0Ev _ZN6icu_6013MeasureFormat22setMeasureFormatLocaleERKNS_6LocaleER10UErrorCode _ZNK6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEE12createObjectEPKvR10UErrorCode _ZNK6icu_6013MeasureFormateqERKNS_6FormatE _ZNK6icu_6013MeasureFormat13formatMeasureERKNS_7MeasureERKNS_12NumberFormatERNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZN6icu_6017QuantityFormatter12selectPluralERKNS_11FormattableERKNS_12NumberFormatERKNS_11PluralRulesERNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZN6icu_6017QuantityFormatter6formatERKNS_15SimpleFormatterERKNS_13UnicodeStringERS4_RNS_13FieldPositionER10UErrorCode _ZNK6icu_6013MeasureFormat6formatERKNS_11FormattableERNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZNK6icu_6013MeasureFormat20formatMeasurePerUnitERKNS_7MeasureERKNS_11MeasureUnitERNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZN6icu_6011MeasureUnit18resolveUnitPerUnitERKS0S2 _ZN6icu_607MeasureC1ERKNS_11FormattableEPNS_11MeasureUnitER10UErrorCode _ZN6icu_607MeasureD1Ev _ZNK6icu_6013MeasureFormat23formatMeasuresSlowTrackEPKNS_7MeasureEiRNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZNK6icu_6013ListFormatter6formatEPKNS_13UnicodeStringEiRS1_iRiR10UErrorCode _ZNK6icu_6013MeasureFormat13formatNumericEdRKNS_10DateFormatE16UDateFormatFieldRKNS_11FormattableERNS_13UnicodeStringER10UErrorCode _ZNK6icu_6013MeasureFormat13formatNumericEPKNS_11FormattableEiRNS_13UnicodeStringER10UErrorCode _ZNK6icu_6013MeasureFormat14formatMeasuresEPKNS_7MeasureEiRNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZNK6icu_6013ListFormatter6formatEPKNS_13UnicodeStringEiRS1_R10UErrorCode _ZTSN6icu_6013MeasureFormatE _ZTIN6icu_6013MeasureFormatE _ZTIN6icu_6022MeasureFormatCacheDataE _ZTSN6icu_608CacheKeyINS_22MeasureFormatCacheDataEEE _ZTIN6icu_608CacheKeyINS_22MeasureFormatCacheDataEEE _ZTSN6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEEE _ZTIN6icu_6014LocaleCacheKeyINS_22MeasureFormatCacheDataEEE _ZNK6icu_6014CurrencyFormat6formatERKNS_11FormattableERNS_13UnicodeStringERNS_13FieldPositionER10UErrorCode _ZN6icu_6014CurrencyFormatD2Ev _ZTVN6icu_6014CurrencyFormatE _ZN6icu_6014CurrencyFormatD1Ev _ZNK6icu_6014CurrencyFormat11parseObjectERKNS_13UnicodeStringERNS_11FormattableERNS_13ParsePositionE _ZN6icu_6014CurrencyFormatC2ERKNS_6LocaleER10UErrorCode

Here are my dmesg prints that correspond with that capture:

[ 637.867172] xtrx 0000:01:00.0: assign IRQ: got 302 [ 637.867208] xtrx: Initializing 0000:01:00.0 [ 637.867303] xtrx 0000:01:00.0: enabling bus mastering [ 637.867872] pps pps1: new PPS source xtrx_pps [ 637.867891] xtrx 0000:01:00.0: Failed to enable MSI, falling back to legacy mode. [ 637.867987] xtrx: buf[0]=8c008000 [virt 2bc1d524] => 8c0087ff [ 637.868033] xtrx: buf[1]=8c010000 [virt 2befb0ad] => 8c0107ff [ 637.868081] xtrx: buf[2]=8c018000 [virt 216f9631] => 8c0187ff [ 637.868124] xtrx: buf[3]=8c020000 [virt 021461be] => 8c0207ff [ 637.868167] xtrx: buf[4]=8c028000 [virt d7fb33ac] => 8c0287ff [ 637.868219] xtrx: buf[5]=8c030000 [virt ca0ea717] => 8c0307ff [ 637.868263] xtrx: buf[6]=8c038000 [virt 5606c332] => 8c0387ff [ 637.868302] xtrx: buf[7]=8c040000 [virt da24ada9] => 8c0407ff [ 637.868349] xtrx: buf[8]=8c048000 [virt 5da123a5] => 8c0487ff [ 637.868391] xtrx: buf[9]=8c050000 [virt a39b474c] => 8c0507ff [ 637.868443] xtrx: buf[10]=8c058000 [virt d3f1345b] => 8c0587ff [ 637.868521] xtrx: buf[11]=8c060000 [virt fb7bb041] => 8c0607ff [ 637.868634] xtrx: buf[12]=8c068000 [virt bc3e323c] => 8c0687ff [ 637.868745] xtrx: buf[13]=8c070000 [virt ab71e619] => 8c0707ff [ 637.868878] xtrx: buf[14]=8c078000 [virt 93cc18e7] => 8c0787ff [ 637.869067] xtrx: buf[15]=8c080000 [virt 827480fe] => 8c0807ff [ 637.869151] xtrx: buf[16]=8c088000 [virt 34b819a8] => 8c0887ff [ 637.869220] xtrx: buf[17]=8c090000 [virt 3b2bdae4] => 8c0907ff [ 637.869280] xtrx: buf[18]=8c098000 [virt 944d2701] => 8c0987ff [ 637.869355] xtrx: buf[19]=8c0a0000 [virt d147ad58] => 8c0a07ff [ 637.869409] xtrx: buf[20]=8c0a8000 [virt aad67064] => 8c0a87ff [ 637.869456] xtrx: buf[21]=8c0b0000 [virt c14e74f5] => 8c0b07ff [ 637.869515] xtrx: buf[22]=8c0b8000 [virt 041e3edd] => 8c0b87ff [ 637.869572] xtrx: buf[23]=8c0c0000 [virt 784323af] => 8c0c07ff [ 637.869640] xtrx: buf[24]=8c0c8000 [virt e92993a1] => 8c0c87ff [ 637.869707] xtrx: buf[25]=8c0d0000 [virt 049ef2f5] => 8c0d07ff [ 637.869765] xtrx: buf[26]=8c0d8000 [virt 24911f51] => 8c0d87ff [ 637.869827] xtrx: buf[27]=8c0e0000 [virt 49e1d5f8] => 8c0e07ff [ 637.869887] xtrx: buf[28]=8c0e8000 [virt e785830d] => 8c0e87ff [ 637.869948] xtrx: buf[29]=8c0f0000 [virt 432929d7] => 8c0f07ff [ 637.870011] xtrx: buf[30]=8c0f8000 [virt 71cd9c94] => 8c0f87ff [ 637.870095] xtrx: buf[31]=8c100000 [virt b03cded5] => 8c1007ff [ 637.870202] xtrx: buf[0]=8c108000 [virt c191ba81] => 8c1087ff [ 637.870285] xtrx: buf[1]=8c110000 [virt 68fae8eb] => 8c1107ff [ 637.870361] xtrx: buf[2]=8c118000 [virt 88f5b70f] => 8c1187ff [ 637.870455] xtrx: buf[3]=8c120000 [virt 4535cd11] => 8c1207ff [ 637.870527] xtrx: buf[4]=8c128000 [virt 5f1615c4] => 8c1287ff [ 637.870587] xtrx: buf[5]=8c130000 [virt 8523e379] => 8c1307ff [ 637.870651] xtrx: buf[6]=8c138000 [virt 48d780d0] => 8c1387ff [ 637.870715] xtrx: buf[7]=8c140000 [virt 03b434ff] => 8c1407ff [ 637.870770] xtrx: buf[8]=8c148000 [virt 966a66fa] => 8c1487ff [ 637.870830] xtrx: buf[9]=8c150000 [virt 99aa96f4] => 8c1507ff [ 637.870893] xtrx: buf[10]=8c158000 [virt 755b4e3e] => 8c1587ff [ 637.870949] xtrx: buf[11]=8c160000 [virt 711a5659] => 8c1607ff [ 637.870993] xtrx: buf[12]=8c168000 [virt 90adde47] => 8c1687ff [ 637.871062] xtrx: buf[13]=8c170000 [virt c3982723] => 8c1707ff [ 637.871169] xtrx: buf[14]=8c178000 [virt 986a282d] => 8c1787ff [ 637.871280] xtrx: buf[15]=8c180000 [virt 37d8d239] => 8c1807ff [ 637.871393] xtrx: buf[16]=8c188000 [virt 3b829567] => 8c1887ff [ 637.871487] xtrx: buf[17]=8c190000 [virt a64ed828] => 8c1907ff [ 637.871570] xtrx: buf[18]=8c198000 [virt 6ac45c51] => 8c1987ff [ 637.871623] xtrx: buf[19]=8c1a0000 [virt 0a137b83] => 8c1a07ff [ 637.871682] xtrx: buf[20]=8c1a8000 [virt 8cdf5304] => 8c1a87ff [ 637.871767] xtrx: buf[21]=8c1b0000 [virt fb83b4a1] => 8c1b07ff [ 637.871854] xtrx: buf[22]=8c1b8000 [virt f43a9267] => 8c1b87ff [ 637.871949] xtrx: buf[23]=8c1c0000 [virt 02c8701f] => 8c1c07ff [ 637.872016] xtrx: buf[24]=8c1c8000 [virt bc1b7df3] => 8c1c87ff [ 637.872102] xtrx: buf[25]=8c1d0000 [virt 4260ef4e] => 8c1d07ff [ 637.872178] xtrx: buf[26]=8c1d8000 [virt baed4982] => 8c1d87ff [ 637.872263] xtrx: buf[27]=8c1e0000 [virt 842d878a] => 8c1e07ff [ 637.872361] xtrx: buf[28]=8c1e8000 [virt fee048b0] => 8c1e87ff [ 637.872453] xtrx: buf[29]=8c1f0000 [virt b047a718] => 8c1f07ff [ 637.872542] xtrx: buf[30]=8c1f8000 [virt 11eeb905] => 8c1f87ff [ 637.872618] xtrx: buf[31]=8c200000 [virt e29aa829] => 8c2007ff [ 637.872710] xtrx: Port config: 0 (1) [ 637.872713] xtrx: Port request: 0 [ 637.872724] 0000:01:00.0: ttyXTRX0 at MMIO 0x0 (irq = 0, base_baud = 9600) is a xtrx_uart [ 637.876499] xtrx: Port config: 1 (1) [ 637.876509] xtrx: Port request: 1 [ 637.876524] 0000:01:00.0: ttyXTRX1 at MMIO 0x0 (irq = 0, base_baud = 9600) is a xtrx_uart [ 637.876530] xtrx: SIM mctrl=0 [ 639.334898] xtrx: call: REGION=1 VMA=8cfdadcf vma->vm_pgoff=0 [ 639.334908] xtrx: VMA open, virt 76fc4000, phys 0 [ 639.334921] xtrx: call: REGION=0 VMA=f83550cb vma->vm_pgoff=1024 [ 639.334928] xtrx: VMA open, virt 76fc3000, phys 400000 [ 639.345148] xtrx: ioctl(12345b, 1) [f4ab1000] [ 639.345156] xtrx: 3V3 CTRL:1 [ 639.550996] xtrx: ioctl(12345b, 1) [f4ab1000] [ 639.604083] xtrx: ioctl(12345a, 10000) [f4ab1000] [ 639.604204] xtrx: buf[0]=8c010000 [virt 2befb0ad] => 8c010fff [ 639.604258] xtrx: buf[1]=8c020000 [virt 021461be] => 8c020fff [ 639.604298] xtrx: buf[2]=8c030000 [virt ca0ea717] => 8c030fff [ 639.604338] xtrx: buf[3]=8c040000 [virt da24ada9] => 8c040fff [ 639.604381] xtrx: buf[4]=8c050000 [virt a39b474c] => 8c050fff [ 639.604423] xtrx: buf[5]=8c060000 [virt fb7bb041] => 8c060fff [ 639.604465] xtrx: buf[6]=8c070000 [virt ab71e619] => 8c070fff [ 639.604508] xtrx: buf[7]=8c080000 [virt 827480fe] => 8c080fff [ 639.604553] xtrx: buf[8]=8c090000 [virt 3b2bdae4] => 8c090fff [ 639.604595] xtrx: buf[9]=8c0a0000 [virt d147ad58] => 8c0a0fff [ 639.604642] xtrx: buf[10]=8c0b0000 [virt c14e74f5] => 8c0b0fff [ 639.604685] xtrx: buf[11]=8c0c0000 [virt 784323af] => 8c0c0fff [ 639.604729] xtrx: buf[12]=8c0d0000 [virt 049ef2f5] => 8c0d0fff [ 639.604825] xtrx: buf[13]=8c0e0000 [virt 49e1d5f8] => 8c0e0fff [ 639.604885] xtrx: buf[14]=8c0f0000 [virt 432929d7] => 8c0f0fff [ 639.604951] xtrx: buf[15]=8c210000 [virt f57229e4] => 8c210fff [ 639.605015] xtrx: buf[16]=8c220000 [virt 057d36ca] => 8c220fff [ 639.605087] xtrx: buf[17]=8c230000 [virt 2356bc9e] => 8c230fff [ 639.605152] xtrx: buf[18]=8c240000 [virt dc9aeff7] => 8c240fff [ 639.605206] xtrx: buf[19]=8c250000 [virt 3b635f2c] => 8c250fff [ 639.605267] xtrx: buf[20]=8c260000 [virt 86354546] => 8c260fff [ 639.605331] xtrx: buf[21]=8c270000 [virt 36c26db1] => 8c270fff [ 639.605385] xtrx: buf[22]=8c280000 [virt 5a98b568] => 8c280fff [ 639.605439] xtrx: buf[23]=8c290000 [virt aa8d0502] => 8c290fff [ 639.605487] xtrx: buf[24]=8c2a0000 [virt 885c6964] => 8c2a0fff [ 639.605535] xtrx: buf[25]=8c2b0000 [virt 19abf3a0] => 8c2b0fff [ 639.605580] xtrx: buf[26]=8c2c0000 [virt 58bab1c5] => 8c2c0fff [ 639.605624] xtrx: buf[27]=8c2d0000 [virt 4ef2f16e] => 8c2d0fff [ 639.605693] xtrx: buf[28]=8c2e0000 [virt adee954c] => 8c2e0fff [ 639.605741] xtrx: buf[29]=8c2f0000 [virt 9aab3d14] => 8c2f0fff [ 639.605784] xtrx: buf[30]=8c300000 [virt 04d9b7b8] => 8c300fff [ 639.605834] xtrx: buf[31]=8c310000 [virt 44da79cb] => 8c310fff [ 639.609009] xtrx: call: REGION=3 VMA=40fcb7a2 vma->vm_pgoff=16384 [ 639.609172] xtrx: ioctl(123459, 10000) [f4ab1000] [ 639.660955] xtrx: ioctl(12345b, 0) [f4ab1000] [ 639.660963] xtrx: 3V3 CTRL:0 [ 639.662140] xtrx: VMA close. [ 639.662159] xtrx: VMA close.

Do you guys have any tips/clues/thoughts? In the meantime I am trying to bring up a different board with a mini PCIe to test on that. Thanks in advance!

sergforce commented 5 years ago

Can you provide your system architecture and your kernel version

And --o doesn't look right, single letter command should start with single dash here

$ ./test_xtrx --dmarx --o captured.bin --samples=262144

should be

$ ./test_xtrx --dmarx -o captured.bin --samples=262144
schnet25 commented 5 years ago

Sure, I am running armbian on a quad core imx6.

$ uname -a Linux cubox 4.19.19-cubox #5.74 SMP Thu Jan 31 11:36:26 EST 2019 armv7l armv7l armv7l GNU/Linux

Same data corruption with your command line arguments:

$ ./test_xtrx --dmarx -o captured.bin --samples=262144 RX_TO_FILE: thread start Master: 32.000 MHz; RX rate: 4.000 MHz; TX rate: 0.000 MHz RX tunned: 900000000.000000 RX bandwidth: 2000000.000000 RX LNA gain: 15.000000 RX PGA gain: 0.000000 RX TIA gain: 9.000000 RX CYCLES=1 SAMPLES=262144 SLICE=16384 (PARTS=16) PROCESSED RX SLICE 0 /0: res 0 TS: 8192 4375 us DELTA 42 us LATE 2369 us 16384 samples PROCESSED RX SLICE 0 /1: res 0 TS: 16384 1940 us DELTA 57 us LATE 2320 us 16384 samples PROCESSED RX SLICE 0 /2: res 0 TS: 24576 2009 us DELTA 55 us LATE 2337 us 16384 samples PROCESSED RX SLICE 0 /3: res 0 TS: 32768 1971 us DELTA 59 us LATE 2319 us 16384 samples PROCESSED RX SLICE 0 /4: res 0 TS: 40960 2158 us DELTA 56 us LATE 2486 us 16384 samples PROCESSED RX SLICE 0 /5: res 0 TS: 49152 1948 us DELTA 89 us LATE 2475 us 16384 samples PROCESSED RX SLICE 0 /6: res 0 TS: 57344 1986 us DELTA 91 us LATE 2505 us 16384 samples PROCESSED RX SLICE 0 /7: res 0 TS: 65536 1861 us DELTA 98 us LATE 2416 us 16384 samples PROCESSED RX SLICE 0 /8: res 0 TS: 73728 1893 us DELTA 76 us LATE 2338 us 16384 samples PROCESSED RX SLICE 0 /9: res 0 TS: 81920 1930 us DELTA 72 us LATE 2292 us 16384 samples PROCESSED RX SLICE 0 /10: res 0 TS: 90112 1983 us DELTA 42 us LATE 2270 us 16384 samples PROCESSED RX SLICE 0 /11: res 0 TS: 98304 2043 us DELTA 36 us LATE 2302 us 16384 samples PROCESSED RX SLICE 0 /12: res 0 TS: 106496 1998 us DELTA 44 us LATE 2297 us 16384 samples PROCESSED RX SLICE 0 /13: res 0 TS: 114688 2075 us DELTA 41 us LATE 2366 us 16384 samples PROCESSED RX SLICE 0 /14: res 0 TS: 122880 2226 us DELTA 66 us LATE 2610 us 16384 samples PROCESSED RX SLICE 0 /15: res 0 TS: 131072 1943 us DELTA 117 us LATE 2623 us 16384 samples RX STAT Overruns:0 RX_TO_FILE: buffer 0 written RX_TO_FILE: thread exit: 1:1 Success! Processed 1 devs, each: RX 2 x 3.320 = 6.641 MSPS (WIRE: 26.562143) TX 2 x 0.000 = 0.000 MSPS (WIRE: 0.000000 MB/s)

$ strings captured.bin 4c9c20d84937e0293fe598f44d785bce 5bb170970da4a03f1c53b6f0202148ef browser-plugin-freshplayer-libpdf 0.3.5-1ubuntu7 89e57b37bb65a92b203e4b812489d0e7 browser-plugin-freshplayer-nacl 2ff74a7db1bb4fef979f63e63ec15efa browser-plugin-freshplayer-pepperflash efb8cdb83d2a1db04174dfb14143ebd9 bsdgames-nonfree 2.17-7 a0df62b17337e5cbd12de06fb52cd546 bugsx 1.08-12 multiverse/math 633d9d97dfd46cbccb5247af21f63dec cbedic kbedic 56a857f9100ac90cb66b2b50caddba94 b3fc6a06f6988440348dbafee80b3cee chaplin 1.10-0.2ubuntu3 4b291946f818007dc5a07f07b10f6fbb cicero mbrola-fr4 fc49eb778e57e68629157bb32fd19f99 2.48.0-0ubuntu1 7d2f73ec99fe6ac800286af990c46c9d 302-5.3 9281d7f0d597f7492962b9135314d699 cl-sql-oracle e2fa54c009744a1533f5486a15f863e6 cl-umlisp 1:2007ac.2-6 862801106fe3fce0392e1fbafafe4f06 cl-umlisp-orf 56177717a37fb571fe11ae0e6d490b77 981097f297a723afe41afec3b96d5a27 2.1+lgpl-6 a79728f91dcfed21b62f89dea1743319 1.53-1 mapleview smile fdd3e34e11f67c13bdf614925d3877ff conserver-client 8.2.1-1 conserver b9cc2e709d8b33dfd2ec4626d89d6cb5 conserver-server e3162cc0887da73106fabc4b43ea815f 2012.06.27-4 432ddc1a98a461eff7937ebd9dac8fe9 2007.03.22-1 multiverse/tex

sergforce commented 5 years ago

It looks like the problem is here

[ 639.604204] xtrx: buf[0]=8c010000 [virt 2befb0ad] => 8c010fff
[ 639.604258] xtrx: buf[1]=8c020000 [virt 021461be] => 8c020fff
[ 639.604298] xtrx: buf[2]=8c030000 [virt ca0ea717] => 8c030fff

The virtual addresses on DMA buffers are junk. However there's only one place they're assigned

pbufs[i].virt = pci_alloc_consistent(d->pdev, buflen, &pbufs[i].phys);

If you have another kernel it'd be worth trying. Unfortunatly I don't have imx6 to test this kernel

Can you also do lspci -vvv for xtrx and cat /proc/interrupts right after the test.

schnet25 commented 5 years ago

$ sudo lspci -vvv 00:00.0 PCI bridge: Synopsys, Inc. Device abcd (rev 01) (prog-if 00 [Normal decode]) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr+ Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- SERR- <PERR- INTx- Latency: 0, Cache Line Size: 64 bytes Interrupt: pin A routed to IRQ 302 Region 0: Memory at 01000000 (32-bit, non-prefetchable) [size=1M] Bus: primary=00, secondary=01, subordinate=ff, sec-latency=0 I/O behind bridge: 0000f000-00000fff Memory behind bridge: 01100000-011fffff Prefetchable memory behind bridge: fff00000-000fffff Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR- [virtual] Expansion ROM at 01200000 [disabled] [size=64K] BridgeCtl: Parity+ SERR- NoISA- VGA- MAbort- >Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- Capabilities: [40] Power Management version 3 Flags: PMEClk- DSI- D1+ D2- AuxCurrent=375mA PME(D0+,D1+,D2-,D3hot+,D3cold+) Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME- Capabilities: [50] MSI: Enable- Count=1/1 Maskable+ 64bit+ Address: 0000000000000000 Data: 0000 Masking: 00000000 Pending: 00000000 Capabilities: [70] Express (v2) Root Port (Slot-), MSI 00 DevCap: MaxPayload 128 bytes, PhantFunc 0 ExtTag- RBE+ DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported- RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop- MaxPayload 128 bytes, MaxReadReq 512 bytes DevSta: CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr+ TransPend- LnkCap: Port #0, Speed 2.5GT/s, Width x1, ASPM L0s L1, Exit Latency L0s <1us, L1 unlimited ClockPM- Surprise- LLActRep+ BwNot- ASPMOptComp- LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- CommClk+ ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt- LnkSta: Speed 2.5GT/s, Width x1, TrErr- Train- SlotClk+ DLActive+ BWMgmt- ABWMgmt- RootCtl: ErrCorrectable- ErrNon-Fatal- ErrFatal- PMEIntEna+ CRSVisible- RootCap: CRSVisible- RootSta: PME ReqID 0000, PMEStatus- PMEPending- DevCap2: Completion Timeout: Range ABCD, TimeoutDis+, LTR-, OBFF Not Supported ARIFwd- DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-, LTR-, OBFF Disabled ARIFwd- LnkCtl2: Target Link Speed: 5GT/s, EnterCompliance- SpeedDis- Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS- Compliance De-emphasis: -6dB LnkSta2: Current De-emphasis Level: -3.5dB, EqualizationComplete-, EqualizationPhase1- EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest- Capabilities: [100 v1] Advanced Error Reporting UESta: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UEMsk: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UESvrt: DLP+ SDES+ TLP- FCP+ CmpltTO- CmpltAbrt- UnxCmplt- RxOF+ MalfTLP+ ECRC- UnsupReq- ACSViol- CESta: RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr- CEMsk: RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr+ AERCap: First Error Pointer: 00, GenCap+ CGenEn- ChkCap+ ChkEn- Capabilities: [140 v1] Virtual Channel Caps: LPEVC=0 RefClk=100ns PATEntryBits=1 Arb: Fixed- WRR32- WRR64- WRR128- Ctrl: ArbSelect=Fixed Status: InProgress- VC0: Caps: PATOffset=00 MaxTimeSlots=1 RejSnoopTrans- Arb: Fixed- WRR32- WRR64- WRR128- TWRR128- WRR256- Ctrl: Enable+ ID=0 ArbSelect=Fixed TC/VC=ff Status: NegoPending- InProgress- Kernel driver in use: pcieport

01:00.0 Memory controller: Xilinx Corporation Device 7012 Subsystem: Xilinx Corporation Device 0007 Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr+ Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- SERR- <PERR- INTx- Latency: 0, Cache Line Size: 64 bytes Interrupt: pin A routed to IRQ 302 Region 0: Memory at 01110000 (32-bit, non-prefetchable) [size=4K] Region 1: Memory at 01100000 (32-bit, non-prefetchable) [size=64K] Capabilities: [40] Power Management version 3 Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0+,D1+,D2+,D3hot+,D3cold-) Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME- Capabilities: [48] MSI: Enable- Count=1/16 Maskable- 64bit+ Address: 0000000000000000 Data: 0000 Capabilities: [60] Express (v2) Endpoint, MSI 01 DevCap: MaxPayload 256 bytes, PhantFunc 0, Latency L0s <64ns, L1 unlimited ExtTag- AttnBtn- AttnInd- PwrInd- RBE+ FLReset- SlotPowerLimit 0.000W DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported- RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop+ MaxPayload 128 bytes, MaxReadReq 4096 bytes DevSta: CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr- TransPend- LnkCap: Port #0, Speed 5GT/s, Width x2, ASPM L0s, Exit Latency L0s unlimited, L1 unlimited ClockPM- Surprise- LLActRep- BwNot- ASPMOptComp- LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- CommClk+ ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt- LnkSta: Speed 2.5GT/s, Width x1, TrErr- Train- SlotClk+ DLActive- BWMgmt- ABWMgmt- DevCap2: Completion Timeout: Range B, TimeoutDis-, LTR-, OBFF Not Supported DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-, LTR-, OBFF Disabled LnkCtl2: Target Link Speed: 5GT/s, EnterCompliance- SpeedDis- Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS- Compliance De-emphasis: -6dB LnkSta2: Current De-emphasis Level: -3.5dB, EqualizationComplete-, EqualizationPhase1- EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest- Capabilities: [100 v1] Device Serial Number 00-00-00-00-12-34-56-78 Kernel driver in use: xtrx Kernel modules: xtrx

$ ./test_xtrx --dmarx -o captured.bin --samples=262144 ; cat /proc/interrupts RX_TO_FILE: thread start Master: 32.000 MHz; RX rate: 4.000 MHz; TX rate: 0.000 MHz RX tunned: 900000000.000000 RX bandwidth: 2000000.000000 RX LNA gain: 15.000000 RX PGA gain: 0.000000 RX TIA gain: 9.000000 RX CYCLES=1 SAMPLES=262144 SLICE=16384 (PARTS=16) PROCESSED RX SLICE 0 /0: res 0 TS: 8192 4400 us DELTA 38 us LATE 2391 us 16384 samples PROCESSED RX SLICE 0 /1: res 0 TS: 16384 1890 us DELTA 57 us LATE 2291 us 16384 samples PROCESSED RX SLICE 0 /2: res 0 TS: 24576 2036 us DELTA 43 us LATE 2322 us 16384 samples PROCESSED RX SLICE 0 /3: res 0 TS: 32768 2058 us DELTA 40 us LATE 2372 us 16384 samples PROCESSED RX SLICE 0 /4: res 0 TS: 40960 2108 us DELTA 68 us LATE 2501 us 16384 samples PROCESSED RX SLICE 0 /5: res 0 TS: 49152 2000 us DELTA 96 us LATE 2550 us 16384 samples PROCESSED RX SLICE 0 /6: res 0 TS: 57344 1893 us DELTA 90 us LATE 2485 us 16384 samples PROCESSED RX SLICE 0 /7: res 0 TS: 65536 2025 us DELTA 101 us LATE 2564 us 16384 samples PROCESSED RX SLICE 0 /8: res 0 TS: 73728 1886 us DELTA 98 us LATE 2501 us 16384 samples PROCESSED RX SLICE 0 /9: res 0 TS: 81920 2000 us DELTA 94 us LATE 2547 us 16384 samples PROCESSED RX SLICE 0 /10: res 0 TS: 90112 1914 us DELTA 99 us LATE 2512 us 16384 samples PROCESSED RX SLICE 0 /11: res 0 TS: 98304 2010 us DELTA 87 us LATE 2562 us 16384 samples PROCESSED RX SLICE 0 /12: res 0 TS: 106496 2039 us DELTA 106 us LATE 2660 us 16384 samples PROCESSED RX SLICE 0 /13: res 0 TS: 114688 1883 us DELTA 109 us LATE 2605 us 16384 samples PROCESSED RX SLICE 0 /14: res 0 TS: 122880 1934 us DELTA 112 us LATE 2603 us 16384 samples PROCESSED RX SLICE 0 /15: res 0 TS: 131072 2078 us DELTA 112 us LATE 2746 us 16384 samples RX STAT Overruns:0 RX_TO_FILE: buffer 0 written RX_TO_FILE: thread exit: 1:1 Success! Processed 1 devs, each: RX 2 x 3.668 = 7.335 MSPS (WIRE: 29.340372) TX 2 x 0.000 = 0.000 MSPS (WIRE: 0.000000 MB/s) CPU0 CPU1 CPU2 CPU3
16: 18098 18968 18399 15815 GIC-0 29 Edge twd 17: 271 254 150 159 GPC 55 Level i.MX Timer Tick 18: 0 0 0 0 GPC 49 Level imx_thermal 19: 0 0 0 0 GPC 94 Level arm-pmu 21: 444 0 0 0 GPC 115 Level 120000.hdmi 22: 0 0 0 0 GPC 9 Level 130000.gpu 23: 0 0 0 0 GPC 10 Level 134000.gpu 26: 0 0 0 0 GPC 32 Level 200c000.ecspi 27: 0 0 0 0 GPC 26 Level 2020000.serial 28: 0 0 0 0 GPC 46 Level 2028000.ssi 29: 0 0 0 0 GPC 50 Level 2034000.asrc 30: 0 0 0 0 GPC 12 Level 2040000.vpu 48: 0 0 0 0 GPC 80 Level 20bc000.wdog 53: 0 0 0 0 GPC 19 Level rtc alarm 59: 37 0 0 0 GPC 2 Level sdma 60: 0 0 0 0 GPC 43 Level 2184000.usb 61: 3104 0 0 0 GPC 40 Level 2184200.usb 62: 78 0 0 2038 GIC-0 150 Level 2188000.ethernet 63: 0 0 0 0 GIC-0 151 Level 2188000.ethernet 64: 151 0 0 0 GPC 22 Level mmc1 65: 6184 14243 0 0 GPC 23 Level mmc0 66: 0 0 0 0 GPC 36 Level 21a0000.i2c 67: 532 0 0 0 GPC 37 Level 21a4000.i2c 68: 0 0 0 0 GPC 38 Level 21a8000.i2c 69: 0 0 0 0 GPC 18 Level vdoa 70: 0 0 0 0 GPC 28 Level 21ec000.serial 71: 0 0 0 0 GPC 29 Level 21f0000.serial 74: 0 0 0 0 GPC 39 Level ahci-imx[2200000.sata] 75: 0 0 0 0 GPC 11 Level 2204000.gpu 82: 0 0 0 0 gpio-mxc 4 Edge 2194000.usdhc cd 279: 0 0 0 0 gpio-mxc 9 Edge gpio-ir-recv-irq 302: 747 0 0 0 GPC 123 Level PCIe PME, xtrx_legacy 303: 787 0 0 0 IPU 23 Edge imx_drm 304: 0 0 0 0 IPU 28 Edge imx_drm 305: 0 0 0 0 IPU 23 Edge imx_drm 306: 0 0 0 0 IPU 28 Edge imx_drm 307: 0 0 0 0 GPC 105 Level 2101000.jr0 308: 0 0 0 0 GPC 106 Level 2102000.jr1 IPI0: 0 0 0 0 CPU wakeup interrupts IPI1: 87 94 63 82 Timer broadcast interrupts IPI2: 14584 19843 20719 17436 Rescheduling interrupts IPI3: 3922 2984 4911 4233 Function call interrupts IPI4: 0 0 0 0 CPU stop interrupts IPI5: 2354 2741 2717 1949 IRQ work interrupts IPI6: 0 0 0 0 completion interrupts Err: 0

sergforce commented 5 years ago

I found the same problem on RK3399 chip. Fix for RK3399 was pushed already.

Can you update the driver repo xtrx_linux_pcie_drv apply this patch and retest?

diff --git a/xtrx.c b/xtrx.c
index c9ac97b..6e2c626 100644
--- a/xtrx.c
+++ b/xtrx.c
@@ -177,9 +177,9 @@ MODULE_VERSION("0.1");
  * mmaped to userspce. Convertion DMA->PA->VA does the trick on that
  * platforms
  */
-#ifdef CONFIG_CPU_RK3399
+//#ifdef CONFIG_CPU_RK3399
 #define VA_DMA_ADDR_FIXUP
-#endif
+//#endif

 static void xtrx_writel(struct xtrx_dev *dev, unsigned int off, unsigned int value)