Project

General

Profile

Actions

Bug #718

closed

Error trace visualizer misses some sources [capidrv]

Added by Alexey Khoroshilov almost 14 years ago. Updated almost 14 years ago.

Status:
Closed
Priority:
High
Category:
Infrastructure
Start date:
01/26/2011
Due date:
% Done:

0%

Estimated time:
Detected in build:
master
Platform:
Published in build:

Description

The ldv-git command below detects an error but error trace visualizer is almost useless since the only sources loaded are slab.h and proc_fs.h.

ldv-git --force --no-update-meta --keep-work --rule=08_1 --filter=isdn/capi/capidrv check

Any intermediate files can help?


Files

current--X--commands-xml--X--defaultshadows--X--08_1.pax (1.02 MB) current--X--commands-xml--X--defaultshadows--X--08_1.pax Alexey Khoroshilov, 01/26/2011 01:48 PM
report.xml (1004 Bytes) report.xml Evgeny Novikov, 01/26/2011 03:42 PM
current--X--commands-xml--X--defaultshadows--X--08_1.report.xml (15.5 KB) current--X--commands-xml--X--defaultshadows--X--08_1.report.xml XML report Pavel Shved, 01/26/2011 06:22 PM
capidrv.ko.linked.ldv_main0_plain_sorted_withcheck.trace (986 KB) capidrv.ko.linked.ldv_main0_plain_sorted_withcheck.trace trace Pavel Shved, 01/26/2011 06:22 PM
linux-2.6.38-dev20110111.log6.08-capidrv (153 KB) linux-2.6.38-dev20110111.log6.08-capidrv Log file with debug level 20 Alexey Khoroshilov, 01/26/2011 06:41 PM
cmd_after_deg.xml (58.4 KB) cmd_after_deg.xml Command stream after deg Alexey Khoroshilov, 01/26/2011 06:46 PM
linux-2.6.38-dev20110111.log6.08-capidrv.debug100 (591 KB) linux-2.6.38-dev20110111.log6.08-capidrv.debug100 Log file with debug level 100 Alexey Khoroshilov, 01/26/2011 09:15 PM
linux-2.6.38-dev20110111.log6.08-capidrv.debug100 (533 KB) linux-2.6.38-dev20110111.log6.08-capidrv.debug100 Log file with debug level 100 after the file removal Alexey Khoroshilov, 01/27/2011 05:09 PM
Actions #1

Updated by Pavel Shved almost 14 years ago

Could you please attach the pax file the uploader tried to upload? It looks like this: $LDVGIT_BASE/envs/1/work-815c4163b6c8ebf8152f42b0a5fd015cfdcedc78/drivers/acpi/apei/erst.c/finished/current--X--commands-xml--X--defaultshadows--X--32_1--X--77_1--X--08_1--X--29_1--X--37_1--X--43_1.pax.

Version of ldv-tools and the Kernel commit you're checking would be useful as well, of course.

Actions #2

Updated by Alexey Khoroshilov almost 14 years ago

  • File current--X--commands-xml--X--defaultshadows--X--RCU_1.pax added

Upload the requested file. [WRONG FILE]

Actions #3

Updated by Alexey Khoroshilov almost 14 years ago

  • File deleted (current--X--commands-xml--X--defaultshadows--X--RCU_1.pax)
Actions #5

Updated by Evgeny Novikov almost 14 years ago

I obtained the full list of required source code files (it's attached) from our nice testing tool checkdb:

DBNAME=ldv_db_3 DBUSER=joker checkdb.pl -c report.xml

Note that the trace depend on 2 c.p files placed to different folders. May be this causes the problem.

Updated by Pavel Shved almost 14 years ago

I guess this is the issue of error-trace-visualizer's subcomponent that extracts list of sources from a trace.

The report doesn't contain any <sourcefile> tag. In such cases, uploader just assigns all sources to the file... however, there are only two sources in the PAX:

./current--X--commands-xml--X--defaultshadows--X--08_1.report.xml
./sources/artificial_env_1/home/tester/.ldv/ldv/envs/1/shadows/include/linux/proc_fs.h
./sources/artificial_env_1/home/tester/.ldv/ldv/envs/1/shadows/include/linux/slab.h
./traces/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/cmdfiles/rcv/08_1/reports/capidrv.ko.linked.ldv_main0_plain_sorted_withcheck.trace

while there are a lot of them in the trace!

$ grep 'src=".*"' --only-match <trace_file | sort -u
src="/home/tester/.ldv/ldv/envs/1/shadows/include/linux/module.h" 
src="/home/tester/.ldv/ldv/envs/1/shadows/include/linux/proc_fs.h" 
src="/home/tester/.ldv/ldv/envs/1/shadows/include/linux/slab.h" 
src="/home/tester/.ldv/ldv/envs/1/shadows/include/linux/slub_def.h" 
src="/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/cmdfiles/rcv/08_1/preprocessed/capidrv.ko.linked.cbe.i" 
src="/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/capidrv.ko.linked.cbe.c" 
src="/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/capidrv.c.p" 
src="/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/kernel-rules/files/engine-blast-assert.h" 
src="/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/hardware/avm/c4.c/work/current--X--commands.xml--X--defaultshadows--X--08_1--X--29_1--X--32_7--X--37_1--X--43_1--X--77_1--X--RCU_1/artificial_env_1/csd_deg_dscv/13/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.c.p" 
Actions #9

Updated by Evgeny Novikov almost 14 years ago

Attached log contains following lines:

ldv-task: NORMAL: Calling LDV reporter.
error-trace-visualizer: NORMAL: Process the command-line options.
error-trace-visualizer: NORMAL: Process trace.
error-trace-visualizer: NORMAL: Make all successfully.
ldv-task-reporter: NORMAL: Can't find kernel file.ldv-task-reporter: NORMAL: Report created successfully.
ldv-task: IFNO: Remove all artificial environments.

Rather obscure...
Seems that problem connected with RI caching.

Actions #10

Updated by Pavel Shved almost 14 years ago

Eugene Novikov wrote:

Rather obscure...

Seems that problem connected with RI caching.

"Seems"? :-D
I guess, it's obiovus! :-D

Actions #11

Updated by Evgeny Novikov almost 14 years ago

Can you obtain log with LDV_DEBUG=100? Do you use fresh master?

Actions #12

Updated by Evgeny Novikov almost 14 years ago

I'll try to obtain this by myself since you point the way to do this.

Actions #14

Updated by Evgeny Novikov almost 14 years ago

  • Status changed from Open to Feedback
Thank you a lot. I investigate the detailed log and find out that the problem may be related with the non backward compatible caching. So the old stored cache doesn't contain some required files (in fact it's *.c.p files needed for error trace visualization) that are cached and copied from cached after the RI code review. For object and source code files logs are so:
  • kcapi.o.usual (it's successfully got from cache)
    rule-instrumentor: DEBUG: The target file cache key is '/drivers/isdn/capi/kcapi.o.usual'.
    rule-instrumentor: TRACE: Find in cache '/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.o.usual' with keys '08_1 /drivers/isdn/capi/kcapi.o.usual'.
    rule-instrumentor: TRACE: Cache hit.
    rule-instrumentor: INFO: Got file from cache instead of executing '...'.
    
  • kcapi.c.p (it isn't got from cache)
    rule-instrumentor: DEBUG: The target file cache key is '/drivers/isdn/capi/kcapi.c.p'.
    rule-instrumentor: TRACE: Find in cache '/home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.c.p' with keys '08_1 /drivers/isdn/capi/kcapi.c.p'.
    rule-instrumentor: TRACE: Cache miss.
    

    Then this leads to the another problem that I have partially described above:
    ldv-task-reporter: TRACE: Process trace file: /home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/hardware/avm/c4.c/work/current--X--commands.xml--X--defaultshadows--X--08_1--X--29_1--X--32_7--X--37_1--X--43_1--X--77_1--X--RCU_1/artificial_env_1/csd_deg_dscv/13/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.c.p
    ldv-task-reporter: NORMAL: Can't find kernel file.
    

So we either may to change scripts to support backward compatibility of caching (i.e. see on both object and source code files in cache before using them) or remove old cache.

But I still need some proof of my assumptions. Alexey, can you delete the only file /home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.c.p' with keys '08_1 /drivers/isdn/capi/kcapi.c.p and obtain the detailed log one more time?

Also it seems to me that ldv-task-reporter should be fixed a bit: when it can't copy one of many required files it should try to copy other ones.

Actions #15

Updated by Pavel Shved almost 14 years ago

Eugene Novikov wrote:

But I still need some proof of my assumptions. Alexey, can you delete the only file /home/tester/.ldv/ldv/envs/1/work/work-4162cf64973df51fc885825bc9ca4d055891c49f/drivers/isdn/capi/capidrv.c/work/current--X--commands.xml--X--defaultshadows--X--08_1/artificial_env_1/csd_deg_dscv/22/dscv_tempdir/dscv/ri/08_1/drivers/isdn/capi/kcapi.c.p' with keys '08_1 /drivers/isdn/capi/kcapi.c.p and obtain the detailed log one more time?

I guess, the second file Eugene meant is named

/home/tester/.ldv/ldv/envs/1/cache/cache-4162cf64973df51fc885825bc9ca4d055891c49f/08_1/drivers/isdn/capi/kcapi.c.p

right?

Actions #16

Updated by Evgeny Novikov almost 14 years ago

Ooo! I wrote completely incorrectly!!! Thank you! Please delete the file /home/tester/.ldv/ldv/envs/1/cache/cache-4162cf64973df51fc885825bc9ca4d055891c49f/08_1/drivers/isdn/capi/kcapi.o.usual (it's the object file, there isn't specified previously source code file in cache at all!).

Actions #18

Updated by Pavel Shved almost 14 years ago

Hmm.... my cache is filled with *.c.p files, so it might be another problem, actually.

Could you please write such errorneous URLs down for us to analyze them thoroughly later?

Actions #20

Updated by Evgeny Novikov almost 14 years ago

Yes, after the object file removal it's processed and the required source code file is obtained (not from cache, just from aspectator). Then they both are cached and it's most likely that they'll be successfully got from cache in future.

Unfortunately the verdict becomes unknown due to memory limit (why so?..) and we can't see the progress with help of error trace visualizer...

It would be nice if you set up more memory limit and see on progress with error trace visualizer. Then remove old cache (see issue #724) and try again. After all there must be no problems.

Actions #21

Updated by Evgeny Novikov almost 14 years ago

Eugene Novikov wrote:

Unfortunately the verdict becomes unknown due to memory limit (why so?..) and we can't see the progress with help of error trace visualizer...

It would be nice if you set up more memory limit and see on progress with error trace visualizer.

Oh, I see the memory limit is large enough (3Gb)... Something strange...

Actions #22

Updated by Vadim Mutilin almost 14 years ago

More links:
  1. Without code sources
  2. No some sources
  3. Links to the code on the left hand side disappear after loading

1. Without code sources
http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/13971/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/scsi_dh_hp_sw.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/14249/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/77_1/Module/scsiglue.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/11647/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/interface.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/11016/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/mtd_blkdevs.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/8340/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/mtd_pagetest.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/12568/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/mtd_speedtest.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/15151/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/43_1/Module/cs5535-mfgpt.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/8929/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/43_1/Module/cpufreq_stats.ko/Entry+point/ldv_main0_plain_sorted_withcheck

2. No some sources
is_state_active_or_queued
http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/13677/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/43_1/Module/bttv-vbi.ko/Entry+point/ldv_main0_plain_sorted_withcheck

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/6953/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/43_1/Module/cx23885-vbi.ko/Entry+point/ldv_main0_plain_sorted_withcheck

3. Links on the left hand side disappear

http://shved:8999/stats/errortrace/profilename/git/name/ldv_git/user/pavel/host/localhost/password/no/page/Error+trace/value/18636/Task+name/Kernel-59365d136d205cc20fe666ca7f89b1c5001b0d5a/Environment+version/artificial_env_1/Rule+name/08_1/Module/mtd_stresstest.ko/Entry+point/ldv_main0_plain_sorted_withcheck

Actions #23

Updated by Alexey Khoroshilov almost 14 years ago

Feedback is blocked by bug#749.

Actions #24

Updated by Alexey Khoroshilov almost 14 years ago

  • Status changed from Feedback to Closed

I have checked capidrv with 08_1 and it is safe now.

Since the issue with cache has been fixed, I close the bug.

Actions

Also available in: Atom PDF