in reply to Re^4: CPAN module install really slow - extra strace info
in thread CPAN module install really slow

strace -f should show child processes, too.
لսႽ† ᥲᥒ⚪⟊Ⴙᘓᖇ Ꮅᘓᖇ⎱ Ⴙᥲ𝇋ƙᘓᖇ

Replies are listed 'Best First'.
Re^6: CPAN module install really slow - extra strace info
by gsee (Acolyte) on Jul 15, 2015 at 14:01 UTC
    Hi choroba thanks for that tip, here is the output of that command after from the "looks good"
    [pid 15031] chdir("/home/admtst/.cpanm/work/1436964574.13928/Devel-Glo +balDestruction-0.13") = 0 [pid 15031] write(1, "Looks good\n", 11Looks good ) = 11 [pid 15031] stat("hints", 0x7651a0) = -1 ENOENT (No such file or d +irectory) [pid 15031] stat("/opt/app/perlModules/Sub/Exporter/Progressive.pm", 0 +x7651a0) = -1 ENOENT (No such file or directory) [pid 15031] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/x86_64-linux/Sub/Exporter/Progressive.pm", 0x7651a0) = -1 ENO +ENT (No such file or directory) [pid 15031] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/Sub/Exporter/Progressive.pm", 0x7651a0) = -1 ENOENT (No such +file or directory) [pid 15031] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/x8 +6_64-linux/Sub/Exporter/Progressive.pm", 0x7651a0) = -1 ENOENT (No su +ch file or directory) [pid 15031] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/Su +b/Exporter/Progressive.pm", 0x7651a0) = -1 ENOENT (No such file or di +rectory) [pid 15031] stat("./Sub/Exporter/Progressive.pm", 0x7651a0) = -1 ENOEN +T (No such file or directory) [pid 15031] write(2, "Warning: prerequisite Sub::Expor"..., 69Warning: + prerequisite Sub::Exporter::Progressive 0.001011 not found. ) = 69 [pid 15031] open("/usr/share/locale/locale.alias", O_RDONLY) = 3 [pid 15031] fstat(3, {st_mode=S_IFREG|0644, st_size=2512, ...}) = 0 [pid 15031] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANO +NYMOUS, -1, 0) = 0x7fbd8d758000 [pid 15031] read(3, "# Locale name alias data base.\n#"..., 4096) = 25 +12 [pid 15031] read(3, "", 4096) = 0 [pid 15031] close(3) = 0 [pid 15031] munmap(0x7fbd8d758000, 4096) = 0 [pid 15031] open("/usr/share/locale/en_GB/LC_MESSAGES/libc.mo", O_RDON +LY) = 3 [pid 15031] fstat(3, {st_mode=S_IFREG|0644, st_size=1474, ...}) = 0 [pid 15031] mmap(NULL, 1474, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fbd8d7 +58000 [pid 15031] close(3) = 0 [pid 15031] open("/usr/share/locale/en/LC_MESSAGES/libc.mo", O_RDONLY) + = -1 ENOENT (No such file or directory) [pid 15031] getuid() = 1000007625 [pid 15031] geteuid() = 1000007625 [pid 15031] getgid() = 1000001421 [pid 15031] getegid() = 1000001421 [pid 15031] stat("../config_h.SH", 0x7651a0) = -1 ENOENT (No such file + or directory) [pid 15031] stat("../../config_h.SH", 0x7651a0) = -1 ENOENT (No such f +ile or directory) [pid 15031] stat("../../../config_h.SH", 0x7651a0) = -1 ENOENT (No suc +h file or directory) [pid 15031] stat("../../../../config_h.SH", 0x7651a0) = -1 ENOENT (No +such file or directory) [pid 15031] stat("../../../../../config_h.SH",
    and again
    [pid 16035] chdir("/home/admtst/.cpanm/work/1436964574.13928/MooX-Type +s-MooseLike-0.29") = 0 [pid 16035] write(1, "Looks good\n", 11Looks good ) = 11 [pid 16035] stat("hints", 0x7651a0) = -1 ENOENT (No such file or d +irectory) [pid 16035] stat("/opt/app/perlModules/Module/Runtime.pm", 0x7651a0) = + -1 ENOENT (No such file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/x86_64-linux/Module/Runtime.pm", 0x7651a0) = -1 ENOENT (No su +ch file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/Module/Runtime.pm", {st_mode=S_IFREG|0444, st_size=16613, ... +}) = 0 [pid 16035] geteuid() = 1000007625 [pid 16035] geteuid() = 1000007625 [pid 16035] open("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/Module/Runtime.pm", O_RDONLY) = 3 [pid 16035] ioctl(3, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fff09fef040) = +-1 ENOTTY (Inappropriate ioctl for device) [pid 16035] lseek(3, 0, SEEK_CUR) = 0 [pid 16035] fstat(3, {st_mode=S_IFREG|0444, st_size=16613, ...}) = 0 [pid 16035] fcntl(3, F_SETFD, FD_CLOEXEC) = 0 [pid 16035] read(3, "=head1 NAME\n\nModule::Runtime - r"..., 8192) = 8 +192 [pid 16035] stat("/opt/app/perlModules/version.pmc", 0x7fff09feed90) = + -1 ENOENT (No such file or directory) [pid 16035] stat("/opt/app/perlModules/version.pm", 0x7fff09feecf0) = +-1 ENOENT (No such file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/x86_64-linux/version.pmc", 0x7fff09feed90) = -1 ENOENT (No su +ch file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/x86_64-linux/version.pm", 0x7fff09feecf0) = -1 ENOENT (No suc +h file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/version.pmc", 0x7fff09feed90) = -1 ENOENT (No such file or di +rectory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/site_perl +/5.18.2/version.pm", 0x7fff09feecf0) = -1 ENOENT (No such file or dir +ectory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/x8 +6_64-linux/version.pmc", 0x7fff09feed90) = -1 ENOENT (No such file or + directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/x8 +6_64-linux/version.pm", 0x7fff09feecf0) = -1 ENOENT (No such file or +directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/ve +rsion.pmc", 0x7fff09feed90) = -1 ENOENT (No such file or directory) [pid 16035] stat("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/ve +rsion.pm", {st_mode=S_IFREG|0444, st_size=5284, ...}) = 0 [pid 16035] open("/opt/app/release/perl/linux/5.18.2-7.1/lib/5.18.2/ve +rsion.pm", O_RDONLY) = 4 [pid 16035] ioctl(4, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fff09feead0) = +-1 ENOTTY (Inappropriate ioctl for device) [pid 16035] lseek(4, 0, SEEK_CUR) = 0 [pid 16035] read(4, "#!perl -w\npackage version;\n\nuse "..., 8192) = +5284 [pid 16035] read(4, "", 8192) = 0 [pid 16035] close(4) = 0 [pid 16035] lseek(3, 5384, SEEK_SET) = 5384 [pid 16035] lseek(3, 0, SEEK_CUR) = 5384 [pid 16035] close(3) = 0 [pid 16035] getuid() = 1000007625 [pid 16035] geteuid() = 1000007625 [pid 16035] getgid() = 1000001421 [pid 16035] getegid() = 1000001421 [pid 16035] stat("../config_h.SH", 0x7651a0) = -1 ENOENT (No such file + or directory) [pid 16035] stat("../../config_h.SH", 0x7651a0) = -1 ENOENT (No such f +ile or directory) [pid 16035] stat("../../../config_h.SH", 0x7651a0) = -1 ENOENT (No suc +h file or directory) [pid 16035] stat("../../../../config_h.SH", 0x7651a0) = -1 ENOENT (No +such file or directory) [pid 16035] stat("../../../../../config_h.SH",

    every time in exactly the same place.

    this location of "../../../../../config_h.SH is the same as /home/config_h.SH - /home/admtst is a bind mount on /opt/home/admtst

    so I presume the problem is here but have no idea why

      Problem solved YAY!!!!!!!

      it was a problem when it got to /home which does not properly exist - I do not know how these bind mounts work on an OS level so cant explain exactly the reason for the issue...

      However, changing cpanm build directory to another location which is done using an env variable:

      export PERL_CPANM_HOME=/opt/app/.cpanm
      So issue closed for me!