Skip Menu |

This queue is for tickets about the Prima CPAN distribution.

Report information
The Basics
Id: 128421
Status: resolved
Priority: 0/
Queue: Prima

People
Owner: Nobody in particular
Requestors: SREZIC [...] cpan.org
Cc:
AdminCc:

Bug Information
Severity: (no value)
Broken in: 1.54
Fixed in: 1.54_2



Subject: t/Object/Application.t hangs on some FreeBSD systems
On some of my FreeBSD smokers (freebsd 9, freebsd 10) t/Object/Application.t hangs. The last lines of truss output before interrupting the hanging script: ... 72329: 2.301562908 0.000005308 clock_gettime(13,{ 1549348398.000000000 }) = 0 (0x0) 72329: 2.301588610 0.000004191 getpid() = 72329 (0x11a89) 72329: 2.301605092 0.000004190 getpid() = 72329 (0x11a89) 72329: 2.301644762 0.000004749 clock_gettime(13,{ 1549348398.000000000 }) = 0 (0x0) 72329: 2.301662641 0.000004190 getpid() = 72329 (0x11a89) 72329: 2.301779416 0.000069841 write(5,"ok 8 - monitor configuration\n",29) = 29 (0x1d) 72329: 2.301886692 0.000007263 setitimer(0,{ 0.000000, 10.000000 },{ 0.000000, 0.000000 }) = 0 (0x0) 72329: 2.301925245 0.000006705 gettimeofday({ 1549348398.426992 },0x0) = 0 (0x0) 72329: 2.301968826 0.000005867 select(5,{ 4 },{ },{ },{ 0.000000 }) = 0 (0x0) 72329: 2.301998438 0.000005587 poll({ 4/POLLIN|POLLOUT },1,-1) = 1 (0x1) 72329: 2.302048445 0.000030172 writev(0x4,0x7fffffffe480,0x3) = 4 (0x4) 72329: 2.302078336 0.000005307 poll({ 4/POLLIN },1,-1) = 1 (0x1) 72329: 2.302103759 0.000006146 recvmsg(0x4,0x7fffffffe2b0,0x0) = 32 (0x20) 72329: 2.302130578 0.000005587 recvmsg(0x4,0x7fffffffe3d0,0x0) ERR#35 'Resource temporarily unavailable' 72329: 2.302157118 0.000005308 recvmsg(0x4,0x7fffffffe3d0,0x0) ERR#35 'Resource temporarily unavailable' 72329: 2.302297917 0.000007542 gettimeofday({ 1549348398.427364 },0x0) = 0 (0x0) 72329: 2.302357143 0.000005308 clock_gettime(13,{ 1549348398.000000000 }) = 0 (0x0) 72329: 2.302381168 0.000004749 getpid() = 72329 (0x11a89) 72329: 2.302398210 0.000004470 getpid() = 72329 (0x11a89) 72329: 2.302450451 0.000005029 clock_gettime(13,{ 1549348398.000000000 }) = 0 (0x0) 72329: 2.302468610 0.000004191 getpid() = 72329 (0x11a89) 72329: 2.302597397 0.000079060 write(5,"ok 9 - timer triggers yield return\n",35) = 35 (0x23) 72329: 2.302655784 0.000005308 sigprocmask(SIG_BLOCK,{ SIGALRM },{ }) = 0 (0x0) 72329: 2.302696013 0.000005029 sigprocmask(SIG_SETMASK,{ SIGHUP|SIGINT|SIGQUIT|SIGILL|SIGTRAP|SIGABRT|SIGEMT|SIGFPE|SIGKILL|SIGBUS|SIGSEGV|SIGSYS|SIGPIPE|SIGALRM|SIGTERM|SIGURG|SIGSTOP|SIGTSTP|SIGCONT|SIGCHLD|SIGTTIN|SIGTTOU|SIGIO|SIGXCPU|SIGXFSZ|SIGVTALRM|SIGPROF|SIGWINCH|SIGINFO|SIGUSR1|SIGUSR2 },{ SIGALRM }) = 0 (0x0) 72329: 2.302724788 0.000005309 sigaction(SIGALRM,{ 0x8009d20f0 SA_SIGINFO ss_t },{ SIG_DFL 0x0 ss_t }) = 0 (0x0) 72329: 2.302751327 0.000005029 sigprocmask(SIG_SETMASK,{ SIGALRM },0x0) = 0 (0x0) 72329: 2.302778146 0.000004749 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0) 72329: 2.302809714 0.000008101 setitimer(0,{ 0.000000, 1.000000 },{ 0.000000, 9.999077 }) = 0 (0x0) 72329: 2.302849105 0.000030171 clock_gettime(13,{ 1549348398.000000000 }) = 0 (0x0) 72329: 2.302886819 0.000006425 gettimeofday({ 1549348398.427954 },0x0) = 0 (0x0) 72329: 2.302924813 0.000005867 select(5,{ 4 },{ },{ },{ 0.000000 }) = 0 (0x0) 72329: 3.309849157 1.148559664 poll({ 12/POLLIN 15/POLLIN },2,-1) ERR#4 'Interrupted system call' 72329: 3.309849157 1.148559664 SIGNAL 14 (SIGALRM) 72329: 3.309918718 0.000000000 sigprocmask(SIG_SETMASK,{ SIGALRM },0x0) = 0 (0x0) 72329: 3.313489563 0.000534704 sigreturn(0x7fffdfdfc630) ERR#4 'Interrupted system call' 72329: 3.313991024 0.000160356 read(15,0x7fffdfdfce60,16) ERR#35 'Resource temporarily unavailable' 72334: 197.080114092 194.928058454 select(5,{ 4 },0x0,{ 4 },0x0) ERR#4 'Interrupted system call' 72329: 197.080150969 193.766108263 poll({ 12/POLLIN 15/POLLIN },2,-1) ERR#4 'Interrupted system call' 72334: 197.080114092 194.928058454 SIGNAL 2 (SIGINT) 72329: 197.080150969 193.766108263 SIGNAL 2 (SIGINT) ...
Thank you, especially for the truss output! I can't reproduce it but I think I have a good idea what's happening. Could you possibly apply the patch and see if that works? /dk
Subject: patch
Download patch
application/octet-stream 887b

Message body not shown because it is not plain text.

On 2019-02-07 07:51:04, KARASIK wrote: Show quoted text
> Thank you, especially for the truss output! > > I can't reproduce it but I think I have a good idea what's happening. > > Could you possibly apply the patch and see if that works? >
Still hangs. truss now says: ... 55676: 2.598458464 0.000081295 write(5,"ok 9 - timer triggers yield return\n",35) = 35 (0x23) 55676: 2.598512381 0.000007263 setitimer(0,{ 0.000000, 0.000000 },{ 0.000000, 9.999038 }) = 0 (0x0) 55676: 2.598548140 0.000005308 sigprocmask(SIG_BLOCK,{ SIGALRM },{ }) = 0 (0x0) 55676: 2.598587251 0.000005029 sigprocmask(SIG_SETMASK,{ SIGHUP|SIGINT|SIGQUIT|SIGILL|SIGTRAP|SIGABRT|SIGEMT|SIGFPE|SIGKILL|SIGBUS|SIGSEGV|SIGSYS|SIGPIPE|SIGALRM|SIGTERM|SIGURG|SIGSTOP|SIGTSTP|SIGCONT|SIGCHLD|SIGTTIN|SIGTTOU|SIGIO|SIGXCPU|SIGXFSZ|SIGVTALRM|SIGPROF|SIGWINCH|SIGINFO|SIGUSR1|SIGUSR2 },{ SIGALRM }) = 0 (0x0) 55676: 2.598616026 0.000005588 sigaction(SIGALRM,{ 0x800af20f0 SA_SIGINFO ss_t },{ SIG_DFL 0x0 ss_t }) = 0 (0x0) 55676: 2.598642845 0.000005029 sigprocmask(SIG_SETMASK,{ SIGALRM },0x0) = 0 (0x0) 55676: 2.598669943 0.000004749 sigprocmask(SIG_SETMASK,{ },0x0) = 0 (0x0) 55676: 2.598713803 0.000005028 clock_gettime(13,{ 1549657945.000000000 }) = 0 (0x0) 55676: 2.598746769 0.000006985 setitimer(0,{ 0.000000, 1.000000 },{ 0.000000, 0.000000 }) = 0 (0x0) 55676: 2.598779175 0.000006705 gettimeofday({ 1549657945.947493 },0x0) = 0 (0x0) 55676: 2.598822756 0.000011175 select(5,{ 4 },{ },{ },{ 0.000000 }) = 0 (0x0) 55676: 3.605844318 1.156679130 poll({ 12/POLLIN 15/POLLIN },2,-1) ERR#4 'Interrupted system call' 55676: 3.605844318 1.156679130 SIGNAL 14 (SIGALRM) 55676: 3.605987912 0.000008660 sigprocmask(SIG_SETMASK,{ SIGALRM },0x0) = 0 (0x0) 55676: 3.606044065 0.000008661 sigreturn(0x7fffdfdfc630) ERR#4 'Interrupted system call' 55676: 3.606101334 0.000008939 read(15,0x7fffdfdfce60,16) ERR#35 'Resource temporarily unavailable' 55681: 41.174472175 38.733795217 select(5,{ 4 },0x0,{ 4 },0x0) ERR#4 'Interrupted system call' 55676: 41.174520784 37.568373354 poll({ 12/POLLIN 15/POLLIN },2,-1) ERR#4 'Interrupted system call' 55681: 41.174472175 38.733795217 SIGNAL 2 (SIGINT) ...
Wow this is getting interesting! Can I ask you to send the freebsd version and perl -V, together with full truss() output, if it is not obscenely huge? /dk
On 2019-02-09 06:20:46, KARASIK wrote: Show quoted text
> Wow this is getting interesting! Can I ask you to send the freebsd > version and perl -V, together with full truss() output, if it is not > obscenely huge? /dk
This is a 10.3-RELEASE-p24. truss log is attached. Summary of my perl5 (revision 5 version 28 subversion 1) configuration: Platform: osname=freebsd osvers=10.3-release-p24 archname=amd64-freebsd uname='freebsd cvrsnica-freebsd-101.herceg.de 10.3-release-p24 freebsd 10.3-release-p24 #0: wed nov 15 04:57:40 utc 2017 root@amd64-builder.daemonology.net:usrobjusrsrcsysgeneric amd64 ' config_args='-ds -e -Dprefix=/usr/perl5.28.1p -Dcf_email=srezic@cpan.org -Doptimize=-O2 -pipe' hint=recommended useposix=true d_sigaction=define useithreads=undef usemultiplicity=undef use64bitint=define use64bitall=define uselongdouble=undef usemymalloc=n default_inc_excludes_dot=define bincompat5005=undef Compiler: cc='cc' ccflags ='-DHAS_FPSETMASK -DHAS_FLOATINGPOINT_H -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -D_FORTIFY_SOURCE=2' optimize='-O2 -pipe' cppflags='-DHAS_FPSETMASK -DHAS_FLOATINGPOINT_H -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include' ccversion='' gccversion='4.2.1 Compatible FreeBSD Clang 3.4.1 (tags/RELEASE_34/dot1-final 208032)' gccosandvers='' intsize=4 longsize=8 ptrsize=8 doublesize=8 byteorder=12345678 doublekind=3 d_longlong=define longlongsize=8 d_longdbl=define longdblsize=16 longdblkind=3 ivtype='long' ivsize=8 nvtype='double' nvsize=8 Off_t='off_t' lseeksize=8 alignbytes=8 prototype=define Linker and Libraries: ld='cc' ldflags ='-pthread -Wl,-E -fstack-protector -L/usr/local/lib' libpth=/usr/lib /usr/local/lib /usr/include/clang/3.4.1 /usr/lib libs=-lpthread -lgdbm -lm -lcrypt -lutil -lc -lelf perllibs=-lpthread -lm -lcrypt -lutil -lc -lelf libc= so=so useshrplib=false libperl=libperl.a gnulibc_version='' Dynamic Linking: dlsrc=dl_dlopen.xs dlext=so d_dlsymun=undef ccdlflags=' ' cccdlflags='-DPIC -fPIC' lddlflags='-shared -L/usr/local/lib -fstack-protector' Characteristics of this binary (from libperl): Compile-time options: HAS_TIMES PERLIO_LAYERS PERL_COPY_ON_WRITE PERL_DONT_CREATE_GVSV PERL_MALLOC_WRAP PERL_OP_PARENT PERL_PRESERVE_IVUV USE_64_BIT_ALL USE_64_BIT_INT USE_LARGE_FILES USE_LOCALE USE_LOCALE_COLLATE USE_LOCALE_CTYPE USE_LOCALE_NUMERIC USE_LOCALE_TIME USE_PERLIO USE_PERL_ATOF Built under freebsd Compiled at Dec 12 2018 23:03:41 %ENV: PERLDOC="-MPod::Perldoc::ToTextOverstrike" @INC: /usr/perl5.28.1p/lib/site_perl/5.28.1/amd64-freebsd /usr/perl5.28.1p/lib/site_perl/5.28.1 /usr/perl5.28.1p/lib/5.28.1/amd64-freebsd /usr/perl5.28.1p/lib/5.28.1
Subject: truss.log.bz2
Download truss.log.bz2
application/x-bzip 389.7k

Message body not shown because it is not plain text.

Hi Slaven, I still cannot reproduce the problem, however this seems has something to do with gtk-dbus interaction in a separate thread. Could you tell what is the glib/gio/gtk version on your machine? Also, to test that, could you run it with —no-gtk argument and see if it hangs as well? /dk
Hi Slaven I think I found the problem. It only manifests itself where there are 2 or more CPUs in the box. I didn't dig far, but I think that is a joint effect of FreeBSD's libthr.so and perl versions, because depending on combination of these, I got test passed, coredumped, or hanged exactly as you have observed. I fixed it by not relying on SIGALRM in tests doing anything sensible. Thank you again! /dk