[Ace-users] [ace-bugs] ACE 5.5.10 tests fail in MEM_Stream_Test; Multicast_Test; Proactor_Test; Process_Semaphore_Test

Hess, Richard T. Jr Richard.Hess at ngc.com
Tue Sep 4 15:04:56 CDT 2007


Guys;

Has anyone seen something like this - advice would be appreciated in
either tracking this down or providing some explanation like don't set
an option to something in config.h. 

Subject: ACE Tests fail = MEM_Stream_Test; Multicast_Test;Proactor_Test;
Process_Semaphore_Test

    ACE VERSION: 5.5.10

----------------------------------

HOST MACHINE and OPERATING SYSTEM: 

Linux dragon 2.6.22.2-42.fc6 #1 SMP
EDT 2007 i686 i686 i386 GNU/Linux

RedHat Linux Version
Fedora Core release 6 (Zod) 

Linux Kernel Version
Linux version 2.6.22.2-42.fc6 (brewbuilder at hs20-bc1-5.build.redhat.com)

(gcc version 4.1.2 20070626 (Red Hat 4.1.2-13)) #1 SMP Wed Aug 15

11:32:12 EDT 2007

 

-----------------------------------
THE $ACE_ROOT/ace/config.h
 
#define ACE_GCC_HAS_TEMPLATE_INSTANTIATION_VISIBILITY_ATTRS 1
#define ACE_NDEBUG 0
#define ACE_NTRACE 1
#include <ace/config-linux.h>
------------------------------------

THE $ACE_ROOT/include/makeinclude/platform_macros.GNU FILE

exceptions=1
inline=1
ami=1
rt_corba=1
smart_proxies=1
interceptors=1
interface_repo=1
corba_messaging=1
probe=0
profile=0
fakesvcconf=0
shared_libs_only=1
debug=1
optimize=0
include $(ACE_ROOT)/include/makeinclude/platform_linux.GNU

 
------------------------------------

CONTENTS OF $ACE_ROOT/bin/MakeProjectCreator/config/default.features

(used by MPC when you generate your own makefiles):

Not Applicable
------------------------------------

Compiler Version 

Using built-in specs.

Target: i386-redhat-linux

Configured with: ../configure --prefix=/usr --mandir=/usr/share/man

--infodir=/usr/share/info --enable-shared --enable-threads=posix

--enable-checking=release --with-system-zlib --enable-__cxa_atexit

--disable-libunwind-exceptions --enable-libgcj-multifile

--enable-languages=c,c++,objc,obj-c++,java,fortran,ada
--enable-java-awt=gtk

--disable-dssi --enable-plugin

--with-java-home=/usr/lib/jvm/java-1.4.2-gcj-1.4.2.0/jre
--with-cpu=generic

--host=i386-redhat-linux

Thread model: posix

gcc version 4.1.2 20070626 (Red Hat 4.1.2-13)

 
------------------------------------------------------------------------
----------

AREA/CLASS/EXAMPLE AFFECTED:

[What example failed?  What module failed to compile?]

 

    DOES THE PROBLEM AFFECT:

        COMPILATION?

            No

        LINKING?

             No - Johnny gave me the fix for the link problem I
experiencing before this. 

            On Unix systems, did you run make realclean first?

            YES!

        EXECUTION?

        OTHER (please specify)?

[Please indicate whether ACE, your application, or both are affected.]
 
Several of the ACE tests failed and I was wondering if anyone knows of a
common causative agent. 


    SYNOPSIS:
According to Johnny's instructions I modified the config.h so that the
1st line was the following for the previous link problem :
#define ACE_GCC_HAS_TEMPLATE_INSTANTIATION_VISIBILITY_ATTRS 1

DESCRIPTION:


auto_run_tests_finished: tests/MEM_Stream_Test Time:121s Result:0
Error: (log/MEM_Stream_Test.log): Reactor::run_event_loop timeout
Error: (log/MEM_Stream_Test.log): Reactor::run_event_loop timeout
======= Begin Log File 
Sep  4 11:06:53.083 2007 at LM_DEBUG@(25714|3086022352) Starting
MEM_Stream_Test test at Tue Sep  4 2007 11:06:53.083238
Sep  4 11:06:53.085 2007 at LM_DEBUG@Testing Reactive MEM_Stream

Sep  4 11:07:53.099 2007 at LM_ERROR@Reactor::run_event_loop timeout
Sep  4 11:07:53.100 2007 at LM_DEBUG@
 *** Platform only supports non-scalable SysV semaphores ***

Sep  4 11:07:53.100 2007 at LM_DEBUG@Testing Multithreaded MEM_Stream

Sep  4 11:08:53.103 2007 at LM_ERROR@Reactor::run_event_loop timeout
Sep  4 11:08:53.104 2007 at LM_DEBUG@(25714|3086022352) Ending
MEM_Stream_Test test at Tue Sep  4 2007 11:08:53.104147
======= End Log File 
------------------------------------------------------------------------
-------------
auto_run_tests: tests/Multicast_Test
Error: Multicast_Test FAILED with exit status 1

auto_run_tests_finished: tests/Multicast_Test Time:3s Result:1
Error: (log/Multicast_Test.log): maximum wait time of 2000 msec exceeded
======= Begin Log File 
Sep  4 11:11:08.281 2007 at LM_DEBUG@(26218|3085895376) Starting
Multicast_Test test at Tue Sep  4 2007 11:11:08.281288
Sep  4 11:11:08.282 2007 at LM_INFO@Starting consumer...
Sep  4 11:11:08.285 2007 at LM_INFO@Starting producer...
Sep  4 11:11:08.290 2007 at LM_INFO@start waiting for consumer to finish...
Sep  4 11:11:10.292 2007 at LM_ERROR@maximum wait time of 2000 msec
exceeded
Sep  4 11:11:10.292 2007 at LM_DEBUG@(26218|3085895376) Ending
Multicast_Test test at Tue Sep  4 2007 11:11:10.292662
======= End Log File 
------------------------------------------------------------------------
-------------------------
Error: (log/Proactor_Test.log): POSIX_Asynch_IO.cpp:1088:(26272 |
3086322576):ACE_POSIX_Asynch_Accept::handle_input: dequeueing failed:
Resource temporarily unavailable
======= Begin Log File 
Sep  4 11:11:31.400 2007 at LM_DEBUG@(26272|3086325456) Starting
Proactor_Test test at Tue Sep  4 2007 11:11:31.400436
Sep  4 11:11:31.401 2007 at LM_DEBUG@(3086325456) Create Proactor Type =
DEFAULT
Sep  4 11:11:31.401 2007 at LM_DEBUG@(26272 | 3086325456)
ACE_POSIX_AIOCB_Proactor::Max Number of AIOs=1024
Sep  4 11:11:31.402 2007 at LM_DEBUG@(3065342864) MyTask started
Sep  4 11:11:31.403 2007 at LM_DEBUG@(3054853008) MyTask started
Sep  4 11:11:31.403 2007 at LM_DEBUG@(3044363152) MyTask started
Sep  4 11:11:31.404 2007 at LM_ERROR@POSIX_Asynch_IO.cpp:1088:(26272 |
3086322576):ACE_POSIX_Asynch_Accept::handle_input: dequeueing failed:
Resource temporarily unavailable
Sep  4 11:11:31.407 2007 at LM_DEBUG@(3065342864) Server 0 up; now 1 up, 0
down.
Sep  4 11:11:31.407 2007 at LM_DEBUG@(3065342864) Server 0 connection from
127.0.0.1:53920
Sep  4 11:11:31.408 2007 at LM_DEBUG@(3065342864) Client 0 up; now 1 up, 0
down.
Sep  4 11:11:31.408 2007 at LM_DEBUG@(3065342864) Client 0 connected on
127.0.0.1:53920
Sep  4 11:11:31.425 2007 at LM_DEBUG@(3054853008) Server 1 up; now 2 up, 0
down.
Sep  4 11:11:31.425 2007 at LM_DEBUG@(3054853008) Server 1 connection from
127.0.0.1:53921
Sep  4 11:11:31.426 2007 at LM_DEBUG@(3054853008) Client 1 up; now 2 up, 0
down.
Sep  4 11:11:31.426 2007 at LM_DEBUG@(3054853008) Client 1 connected on
127.0.0.1:53921
Sep  4 11:11:31.427 2007 at LM_DEBUG@(3054853008) Server 2 up; now 3 up, 0
down.
Sep  4 11:11:31.427 2007 at LM_DEBUG@(3054853008) Server 2 connection from
127.0.0.1:53922
Sep  4 11:11:31.428 2007 at LM_DEBUG@(3054853008) Client 2 up; now 3 up, 0
down.
Sep  4 11:11:31.428 2007 at LM_DEBUG@(3054853008) Client 2 connected on
127.0.0.1:53922
Sep  4 11:11:31.428 2007 at LM_DEBUG@(3054853008) Server 3 up; now 4 up, 0
down.
Sep  4 11:11:31.429 2007 at LM_DEBUG@(3054853008) Server 3 connection from
127.0.0.1:53923
Sep  4 11:11:31.429 2007 at LM_DEBUG@(3054853008) Client 3 up; now 4 up, 0
down.
Sep  4 11:11:31.429 2007 at LM_DEBUG@(3054853008) Client 3 connected on
127.0.0.1:53923
Sep  4 11:11:31.430 2007 at LM_DEBUG@(3054853008) Server 4 up; now 5 up, 0
down.
Sep  4 11:11:31.430 2007 at LM_DEBUG@(3054853008) Server 4 connection from
127.0.0.1:53924
Sep  4 11:11:31.442 2007 at LM_DEBUG@(3044363152) Client 4 up; now 5 up, 0
down.
Sep  4 11:11:31.442 2007 at LM_DEBUG@(3044363152) Client 4 connected on
127.0.0.1:53924
Sep  4 11:11:31.443 2007 at LM_DEBUG@(3044363152) Server 5 up; now 6 up, 0
down.
Sep  4 11:11:31.443 2007 at LM_DEBUG@(3044363152) Server 5 connection from
127.0.0.1:53925
Sep  4 11:11:31.448 2007 at LM_DEBUG@(3044363152) Client 0 sent 7850, limit
7850
Sep  4 11:11:31.448 2007 at LM_DEBUG@(3044363152) Closing Client 0 writes;
1 I/O outstanding
Sep  4 11:11:31.449 2007 at LM_DEBUG@(3044363152) Server 0 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.450 2007 at LM_DEBUG@(3044363152) Server 0 gone; now 6 up,
1 down
Sep  4 11:11:31.450 2007 at LM_DEBUG@(3044363152) Client 0 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.450 2007 at LM_DEBUG@(3044363152) Client 0 gone; now 5 up,
1 down
Sep  4 11:11:31.451 2007 at LM_DEBUG@(3065342864) Client 0 up; now 6 up, 1
down.
Sep  4 11:11:31.452 2007 at LM_DEBUG@(3065342864) Client 0 connected on
127.0.0.1:53925
Sep  4 11:11:31.460 2007 at LM_DEBUG@(3044363152) Client 5 up; now 7 up, 1
down.
Sep  4 11:11:31.460 2007 at LM_DEBUG@(3044363152) Client 5 connected on
127.0.0.1:53926
Sep  4 11:11:31.460 2007 at LM_DEBUG@(3044363152) Server 0 up; now 7 up, 1
down.
Sep  4 11:11:31.460 2007 at LM_DEBUG@(3044363152) Server 0 connection from
127.0.0.1:53926
Sep  4 11:11:31.461 2007 at LM_DEBUG@(3044363152) Server 6 up; now 8 up, 1
down.
Sep  4 11:11:31.461 2007 at LM_DEBUG@(3044363152) Server 6 connection from
127.0.0.1:53927
Sep  4 11:11:31.462 2007 at LM_DEBUG@(3065342864) Client 6 up; now 8 up, 1
down.
Sep  4 11:11:31.462 2007 at LM_DEBUG@(3065342864) Client 6 connected on
127.0.0.1:53927
Sep  4 11:11:31.462 2007 at LM_DEBUG@(3065342864) Server 7 up; now 9 up, 1
down.
Sep  4 11:11:31.462 2007 at LM_DEBUG@(3065342864) Server 7 connection from
127.0.0.1:53928
Sep  4 11:11:31.463 2007 at LM_DEBUG@(3065342864) Client 7 up; now 9 up, 1
down.
Sep  4 11:11:31.463 2007 at LM_DEBUG@(3065342864) Client 7 connected on
127.0.0.1:53928
Sep  4 11:11:31.464 2007 at LM_DEBUG@(3054853008) Server 8 up; now 10 up, 1
down.
Sep  4 11:11:31.464 2007 at LM_DEBUG@(3054853008) Server 8 connection from
127.0.0.1:53929
Sep  4 11:11:31.464 2007 at LM_DEBUG@(3054853008) Client 8 up; now 10 up, 1
down.
Sep  4 11:11:31.464 2007 at LM_DEBUG@(3054853008) Client 8 connected on
127.0.0.1:53929
Sep  4 11:11:31.570 2007 at LM_DEBUG@(3054853008) Client 1 sent 7850, limit
7850
Sep  4 11:11:31.570 2007 at LM_DEBUG@(3054853008) Closing Client 1 writes;
1 I/O outstanding
Sep  4 11:11:31.574 2007 at LM_DEBUG@(3065342864) Server 1 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.574 2007 at LM_DEBUG@(3065342864) Server 1 gone; now 10 up,
2 down
Sep  4 11:11:31.576 2007 at LM_DEBUG@(3044363152) Client 1 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.576 2007 at LM_DEBUG@(3044363152) Client 1 gone; now 10 up,
2 down
Sep  4 11:11:31.585 2007 at LM_DEBUG@(3044363152) Client 2 sent 7850, limit
7850
Sep  4 11:11:31.585 2007 at LM_DEBUG@(3044363152) Closing Client 2 writes;
1 I/O outstanding
Sep  4 11:11:31.589 2007 at LM_DEBUG@(3065342864) Server 2 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.589 2007 at LM_DEBUG@(3065342864) Server 2 gone; now 10 up,
3 down
Sep  4 11:11:31.591 2007 at LM_DEBUG@(3044363152) Client 2 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.591 2007 at LM_DEBUG@(3044363152) Client 2 gone; now 10 up,
3 down
Sep  4 11:11:31.608 2007 at LM_DEBUG@(3065342864) Client 0 sent 7850, limit
7850
Sep  4 11:11:31.608 2007 at LM_DEBUG@(3065342864) Closing Client 0 writes;
1 I/O outstanding
Sep  4 11:11:31.610 2007 at LM_DEBUG@(3054853008) Server 5 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.611 2007 at LM_DEBUG@(3054853008) Server 5 gone; now 10 up,
4 down
Sep  4 11:11:31.612 2007 at LM_DEBUG@(3054853008) Client 5 sent 7850, limit
7850
Sep  4 11:11:31.612 2007 at LM_DEBUG@(3054853008) Closing Client 5 writes;
1 I/O outstanding
Sep  4 11:11:31.612 2007 at LM_DEBUG@(3054853008) Client 0 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.612 2007 at LM_DEBUG@(3054853008) Client 0 gone; now 10 up,
4 down
Sep  4 11:11:31.614 2007 at LM_DEBUG@(3065342864) Server 0 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.614 2007 at LM_DEBUG@(3065342864) Server 0 gone; now 10 up,
5 down
Sep  4 11:11:31.615 2007 at LM_DEBUG@(3054853008) Client 5 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.620 2007 at LM_DEBUG@(3044363152) Client 6 sent 7850, limit
7850
Sep  4 11:11:31.621 2007 at LM_DEBUG@(3044363152) Closing Client 6 writes;
1 I/O outstanding
Sep  4 11:11:31.623 2007 at LM_DEBUG@(3065342864) Server 6 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.623 2007 at LM_DEBUG@(3065342864) Server 6 gone; now 10 up,
6 down
Sep  4 11:11:31.624 2007 at LM_DEBUG@(3065342864) Client 6 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.624 2007 at LM_DEBUG@(3065342864) Client 6 gone; now 10 up,
5 down
Sep  4 11:11:31.625 2007 at LM_DEBUG@(3054853008) Client 5 gone; now 10 up,
6 down
Sep  4 11:11:31.629 2007 at LM_DEBUG@(3044363152) Client 3 sent 7850, limit
7850
Sep  4 11:11:31.629 2007 at LM_DEBUG@(3044363152) Closing Client 3 writes;
1 I/O outstanding
Sep  4 11:11:31.631 2007 at LM_DEBUG@(3065342864) Server 3 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.631 2007 at LM_DEBUG@(3065342864) Server 3 gone; now 10 up,
7 down
Sep  4 11:11:31.632 2007 at LM_DEBUG@(3054853008) Client 3 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.632 2007 at LM_DEBUG@(3054853008) Client 3 gone; now 10 up,
7 down
Sep  4 11:11:31.634 2007 at LM_DEBUG@(3044363152) Client 7 sent 7850, limit
7850
Sep  4 11:11:31.634 2007 at LM_DEBUG@(3044363152) Closing Client 7 writes;
1 I/O outstanding
Sep  4 11:11:31.635 2007 at LM_DEBUG@(3054853008) Server 7 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.635 2007 at LM_DEBUG@(3054853008) Server 7 gone; now 10 up,
8 down
Sep  4 11:11:31.635 2007 at LM_DEBUG@(3054853008) Client 7 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.636 2007 at LM_DEBUG@(3054853008) Client 7 gone; now 10 up,
8 down
Sep  4 11:11:31.638 2007 at LM_DEBUG@(3054853008) Client 4 sent 7850, limit
7850
Sep  4 11:11:31.641 2007 at LM_DEBUG@(3054853008) Closing Client 4 writes;
1 I/O outstanding
Sep  4 11:11:31.641 2007 at LM_DEBUG@(3065342864) Server 4 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.641 2007 at LM_DEBUG@(3065342864) Server 4 gone; now 10 up,
9 down
Sep  4 11:11:31.642 2007 at LM_DEBUG@(3065342864) Client 8 sent 7850, limit
7850
Sep  4 11:11:31.642 2007 at LM_DEBUG@(3065342864) Closing Client 8 writes;
1 I/O outstanding
Sep  4 11:11:31.643 2007 at LM_DEBUG@(3044363152) Server 8 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.643 2007 at LM_DEBUG@(3044363152) Server 8 gone; now 10 up,
10 down
Sep  4 11:11:31.643 2007 at LM_DEBUG@(3044363152) Client 8 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.643 2007 at LM_DEBUG@(3044363152) Client 8 gone; now 10 up,
9 down
Sep  4 11:11:31.644 2007 at LM_DEBUG@(3054853008) Client 4 dtor; 50 sends
(7850 bytes); 51 recvs (7850 bytes)
Sep  4 11:11:31.644 2007 at LM_DEBUG@(3054853008) Client 4 gone; now 10 up,
10 down
Sep  4 11:11:34.466 2007 at LM_DEBUG@(3086325456) Sleeping til sessions run
down.
Sep  4 11:11:34.466 2007 at LM_DEBUG@(3086325456) Stop Thread Pool Task
Sep  4 11:11:34.466 2007 at LM_DEBUG@(3086325456) Calling End Proactor
event loop
Sep  4 11:11:34.466 2007 at LM_DEBUG@(3054853008) MyTask finished
Sep  4 11:11:34.467 2007 at LM_DEBUG@(3044363152) MyTask finished
Sep  4 11:11:34.467 2007 at LM_DEBUG@(3065342864) MyTask finished
Sep  4 11:11:34.467 2007 at LM_DEBUG@(26272|3086325456) Ending
Proactor_Test test at Tue Sep  4 2007 11:11:34.467687
======= End Log File 
------------------------------------------------------------------------
--------
Error: (log/Process_Semaphore_Test.log): Child 26598 finished with
status 1124412
======= Begin Log File 
Sep  4 11:12:50.450 2007 at LM_DEBUG@(26597|3086628560) Starting
Process_Semaphore_Test test at Tue Sep  4 2007 11:12:50.450795
Sep  4 11:12:50.455 2007 at LM_DEBUG@Parent spawned child process with pid
= 26598.
Sep  4 11:12:50.455 2007 at LM_DEBUG@(26597) Begin ping-pong
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597) Ping
Sep  4 11:12:50.466 2007 at LM_ERROR@Child 26598 finished with status
1124412
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26597|3086628560) Ending
Process_Semaphore_Test test at Tue Sep  4 2007 11:12:50.466875
======= End Log File 
Error (Process_Semaphore_Test-child.log): no line with 'Ending'
Error (Process_Semaphore_Test-child.log): Number of 'Starting' does not
match number of 'Ending' (1 != 0)
======= Begin Sublog File Process_Semaphore_Test-child.log
Sep  4 11:12:50.464 2007 at LM_DEBUG@(26598|3086194384) Starting
Process_Semaphore_Test-child test at Tue Sep  4 2007 11:12:50.464790
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26598) Begin ping-pong
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.465 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
Sep  4 11:12:50.466 2007 at LM_DEBUG@(26598) Pong
======= End Sublog File 






More information about the Ace-users mailing list