[Ace-users] Re: [ace-bugs] ACE 5.6 on Fedora Core 6 fails MEM_Stream_Test; Multicast_Test, Proactor_Test, Process_Semaphore_Test

Douglas C. Schmidt schmidt at dre.vanderbilt.edu
Wed Sep 5 22:07:22 CDT 2007


Hi Rick,

> Has anyone seen something like this - advice would be appreciated in eith=
er tracking this down or providing some
> explanation like didn't set an option to something in config.h.

I recommend you try using the "classic" configuration rather than the
auto-conf configuration.  That might make a difference.  It looks like
the tests are timing out, so there may be an issue with your OS.  Have
you tried using a different OS?

Thanks,

     Doug

> ACE 5.6
>=20
> ----------------------------------
>=20
> HOST MACHINE and OPERATING SYSTEM:
>=20
> Linux dragon 2.6.22.2-42.fc6 #1 SMP
>=20
> EDT 2007 i686 i686 i386 GNU/Linux
>=20
> RedHat Linux Version
>=20
> Fedora Core release 6 (Zod)
>=20
> Linux Kernel Version
>=20
> Linux version 2.6.22.2-42.fc6 (brewbuilder at hs20-bc1-5.build.redhat.com)
>=20
> (gcc version 4.1.2 20070626 (Red Hat 4.1.2-13)) #1 SMP Wed Aug 15
>=20
> 11:32:12 EDT 2007
>=20
> -----------------------------------
>=20
> THE $ACE_ROOT/ace/config.h
>=20
> #define ACE_GCC_HAS_TEMPLATE_INSTANTIATION_VISIBILITY_ATTRS 1
>=20
> #define ACE_NDEBUG 0
>=20
>  #define ACE_NTRACE 1
>=20
>  #include <ace/config-linux.h>
>=20
> ------------------------------------
>=20
> THE $ACE_ROOT/include/makeinclude/platform_macros.GNU FILE
>=20
> exceptions=3D1
>=20
> inline=3D1
>=20
> ami=3D1
>=20
> rt_corba=3D1
>=20
> smart_proxies=3D1
>=20
> interceptors=3D1
>=20
> interface_repo=3D1
>=20
> corba_messaging=3D1
>=20
> probe=3D0
>=20
> profile=3D0
>=20
> fakesvcconf=3D0
>=20
> shared_libs_only=3D1
>=20
> debug=3D1
>=20
> optimize=3D0
>=20
> include $(ACE_ROOT)/include/makeinclude/platform_linux.GNU
>=20
> ------------------------------------
>=20
> CONTENTS OF $ACE_ROOT/bin/MakeProjectCreator/config/default.features
>=20
> (used by MPC when you generate your own makefiles):
>=20
> Not Applicable
>=20
> ------------------------------------
>=20
> Compiler Version
>=20
> Using built-in specs.
>=20
> Target: i386-redhat-linux
>=20
> Configured with: ../configure --prefix=3D/usr --mandir=3D/usr/share/man
>=20
> --infodir=3D/usr/share/info --enable-shared --enable-threads=3Dposix
>=20
> --enable-checking=3Drelease --with-system-zlib --enable-__cxa_atexit
>=20
> --disable-libunwind-exceptions --enable-libgcj-multifile
>=20
> --enable-languages=3Dc,c++,objc,obj-c++,java,fortran,ada --enable-java-aw=
t=3Dgtk
>=20
> --disable-dssi --enable-plugin
>=20
> --with-java-home=3D/usr/lib/jvm/java-1.4.2-gcj-1.4.2.0/jre --with-cpu=3Dg=
eneric
>=20
> --host=3Di386-redhat-linux
>=20
> Thread model: posix
>=20
> gcc version 4.1.2 20070626 (Red Hat 4.1.2-13)
>=20
>  ------------------------------------------------------------------------=
----------
>=20
> AREA/CLASS/EXAMPLE AFFECTED:
>=20
> [What example failed?  What module failed to compile?]
>=20
>     DOES THE PROBLEM AFFECT:
>=20
>         COMPILATION?
>=20
>             No
>=20
>         LINKING?
>=20
>             No
>=20
>            On Unix systems, did you run make realclean first?
>=20
>             YES!
>=20
>         EXECUTION?
>=20
>         The following ACE Tests failed
>=20
> 1.       MEM_Stream_Test;
>=20
> 2.       Multicast_Test;
>=20
> 3.       Proactor_Test;
>=20
> 4.       Process_Semaphore_Test
>=20
> [Please indicate whether ACE, your application, or both are affected.]
>=20
> Just ACE right now =D0 have yet to write the application :-)
>=20
> Several of the ACE tests failed and I was wondering if anyone knows of a =
common causative agent.
>=20
> Outputs of test runs follow:
>=20
> auto_run_tests_finished: tests/MEM_Stream_Test Time:121s Result:0
>=20
> Error: (log/MEM_Stream_Test.log): Reactor::run_event_loop timeout
>=20
> Error: (log/MEM_Stream_Test.log): Reactor::run_event_loop timeout
>=20
>  =3D=3D=3D=3D=3D=3D=3D Begin Log File
>=20
>  Sep  5 15:43:59.606 2007 at LM_DEBUG@(8032|3086649040)
>=20
>  Starting MEM_Stream_Test test at Wed Sep  5 2007 15:43:59.606474
>=20
>  Sep  5 15:43:59.694 2007 at LM_DEBUG@Testing Reactive MEM_Stream
>=20
>  Sep  5 15:44:59.726 2007 at LM_ERROR@Reactor::run_event_loop timeout
>=20
> Sep  5 15:44:59.726 2007 at LM_DEBUG@
>=20
>  *** Platform only supports non-scalable SysV semaphores ***
>=20
> Sep  5 15:44:59.726 2007 at LM_DEBUG@Testing Multithreaded MEM_Stream
>=20
> Sep  5 15:45:59.728 2007 at LM_ERROR@Reactor::run_event_loop timeout
>=20
>  Sep  5 15:45:59.729 2007 at LM_DEBUG@(8032|3086649040)
>=20
>  Ending MEM_Stream_Test test at Wed Sep  5 2007 15:45:59.729120
>=20
>  =3D=3D=3D=3D=3D=3D=3D End Log File
>=20
> auto_run_tests_finished: tests/Multicast_Test Time:3s Result:1
>=20
> Error: (log/Multicast_Test.log): maximum wait time of 2000 msec exceeded
>=20
>  =3D=3D=3D=3D=3D=3D=3D Begin Log File
>=20
> Sep  5 15:48:10.922 2007 at LM_DEBUG@(8540|3086444240)
>=20
>  Starting Multicast_Test test at Wed Sep  5 2007 15:48:10.922432
>=20
>  Sep  5 15:48:10.923 2007 at LM_INFO@Starting consumer...
>=20
> Sep  5 15:48:10.924 2007 at LM_DEBUG@Joined 239.255.0.1/16000
>=20
>  Sep  5 15:48:10.924 2007 at LM_DEBUG@Joined 239.255.0.2/16000
>=20
>  Sep  5 15:48:10.925 2007 at LM_DEBUG@Joined 239.255.0.3/16000
>=20
>  Sep  5 15:48:10.926 2007 at LM_DEBUG@Joined 239.255.0.4/16000
>=20
>  Sep  5 15:48:10.926 2007 at LM_DEBUG@Joined 239.255.0.5/16000
>=20
>  Sep  5 15:48:10.927 2007 at LM_INFO@Starting producer...
>=20
> Sep  5 15:48:10.932 2007 at LM_INFO@start waiting for consumer to finish...
>=20
> Sep  5 15:48:12.934 2007 at LM_ERROR@maximum wait time of 2000 msec exceeded
>=20
>  Sep  5 15:48:12.934 2007 at LM_DEBUG@(8540|3086444240)
>=20
>  Ending Multicast_Test test at Wed Sep  5 2007 15:48:12.934909
>=20
>  =3D=3D=3D=3D=3D=3D=3D End Log File
>=20
> auto_run_tests_finished: tests/Proactor_Test Time:4s Result:0
>=20
> Error: (log/Proactor_Test.log):
>=20
> POSIX_Asynch_IO.cpp:1088:(8600 | 3086736272):
>=20
> ACE_POSIX_Asynch_Accept::handle_input: dequeueing failed:
>=20
> Resource temporarily unavailable
>=20
>  =3D=3D=3D=3D=3D=3D=3D Begin Log File
>=20
> Sep  5 15:48:33.987 2007 at LM_DEBUG@(8600|3086739152)
>=20
> Starting Proactor_Test test at Wed Sep  5 2007 15:48:33.988093
>=20
>  Sep  5 15:48:33.988 2007 at LM_DEBUG@(3086739152) Create Proactor Type =3D =
DEFAULT
>=20
>  Sep  5 15:48:33.989 2007 at LM_DEBUG@(8600 | 3086739152) ACE_POSIX_AIOCB_Pr=
oactor::Max Number of AIOs=3D1024 Sep  5
> 15:48:33.990 2007 at LM_DEBUG@(3065756560) MyTask started
>=20
>  Sep  5 15:48:33.991 2007 at LM_DEBUG@(3055266704) MyTask started
>=20
>  Sep  5 15:48:33.991 2007 at LM_DEBUG@(3044776848) MyTask started
>=20
>  Sep  5 15:48:33.992 2007 at LM_ERROR@POSIX_Asynch_IO.cpp:1088:(8600 | 30867=
36272):ACE_POSIX_Asynch_Accept::handle_input:
>=20
> dequeueing failed: Resource temporarily unavailable
>=20
>  Sep  5 15:48:34.009 2007 at LM_DEBUG@(3065756560) Server 0 up; now 1 up, 0 =
down.
>=20
> Sep  5 15:48:34.009 2007 at LM_DEBUG@(3065756560) Server 0 connection from 1=
27.0.0.1:54505
>=20
>  Sep  5 15:48:34.010 2007 at LM_DEBUG@(3065756560) Client 0 up; now 1 up, 0 =
down.
>=20
> Sep  5 15:48:34.010 2007 at LM_DEBUG@(3065756560) Client 0 connected on 127.=
0.0.1:54505
>=20
>  Sep  5 15:48:34.020 2007 at LM_DEBUG@(3065756560) Server 1 up; now 2 up, 0 =
down.
>=20
> Sep  5 15:48:34.020 2007 at LM_DEBUG@(3065756560) Server 1 connection from 1=
27.0.0.1:54506
>=20
>  Sep  5 15:48:34.021 2007 at LM_DEBUG@(3055266704) Client 1 up; now 2 up, 0 =
down.
>=20
> Sep  5 15:48:34.021 2007 at LM_DEBUG@(3055266704) Client 1 connected on 127.=
0.0.1:54506
>=20
>  Sep  5 15:48:34.021 2007 at LM_DEBUG@(3055266704) Server 2 up; now 3 up, 0 =
down.
>=20
> Sep  5 15:48:34.021 2007 at LM_DEBUG@(3055266704) Server 2 connection from 1=
27.0.0.1:54507
>=20
>  Sep  5 15:48:34.022 2007 at LM_DEBUG@(3055266704) Client 2 up; now 3 up, 0 =
down.
>=20
> Sep  5 15:48:34.022 2007 at LM_DEBUG@(3055266704) Client 2 connected on 127.=
0.0.1:54507
>=20
>  Sep  5 15:48:34.023 2007 at LM_DEBUG@(3055266704) Server 3 up; now 4 up, 0 =
down.
>=20
> Sep  5 15:48:34.023 2007 at LM_DEBUG@(3055266704) Server 3 connection from 1=
27.0.0.1:54508
>=20
>  Sep  5 15:48:34.023 2007 at LM_DEBUG@(3055266704) Client 3 up; now 4 up, 0 =
down.
>=20
> Sep  5 15:48:34.024 2007 at LM_DEBUG@(3055266704) Client 3 connected on 127.=
0.0.1:54508
>=20
>  Sep  5 15:48:34.024 2007 at LM_DEBUG@(3055266704) Server 4 up; now 5 up, 0 =
down.
>=20
> Sep  5 15:48:34.024 2007 at LM_DEBUG@(3055266704) Server 4 connection from 1=
27.0.0.1:54509
>=20
>  Sep  5 15:48:34.041 2007 at LM_DEBUG@(3044776848) Client 4 up; now 5 up, 0 =
down.
>=20
> Sep  5 15:48:34.041 2007 at LM_DEBUG@(3044776848) Client 4 connected on 127.=
0.0.1:54509
>=20
>  Sep  5 15:48:34.054 2007 at LM_DEBUG@(3044776848) Client 5 up; now 6 up, 0 =
down.
>=20
> Sep  5 15:48:34.055 2007 at LM_DEBUG@(3044776848) Client 5 connected on 127.=
0.0.1:54510
>=20
>  Sep  5 15:48:34.055 2007 at LM_DEBUG@(3044776848) Server 5 up; now 6 up, 0 =
down.
>=20
> Sep  5 15:48:34.055 2007 at LM_DEBUG@(3044776848) Server 5 connection from 1=
27.0.0.1:54510
>=20
>  Sep  5 15:48:34.055 2007 at LM_DEBUG@(3065756560) Server 6 up; now 7 up, 0 =
down.
>=20
> Sep  5 15:48:34.056 2007 at LM_DEBUG@(3065756560) Server 6 connection from 1=
27.0.0.1:54511
>=20
>  Sep  5 15:48:34.062 2007 at LM_DEBUG@(3055266704) Client 6 up; now 7 up, 0 =
down.
>=20
> Sep  5 15:48:34.062 2007 at LM_DEBUG@(3055266704) Client 6 connected on 127.=
0.0.1:54511 Sep  5 15:48:34.063 2007 at LM_DEBUG@
> (3044776848) Server 7 up; now 8 up, 0 down.
>=20
> Sep  5 15:48:34.063 2007 at LM_DEBUG@(3044776848) Server 7 connection from 1=
27.0.0.1:54512
>=20
>  Sep  5 15:48:34.063 2007 at LM_DEBUG@(3044776848) Client 7 up; now 8 up, 0 =
down.
>=20
> Sep  5 15:48:34.063 2007 at LM_DEBUG@(3044776848) Client 7 connected on 127.=
0.0.1:54512
>=20
>  Sep  5 15:48:34.065 2007 at LM_DEBUG@(3065756560) Server 8 up; now 9 up, 0 =
down.
>=20
> Sep  5 15:48:34.065 2007 at LM_DEBUG@(3065756560) Server 8 connection from 1=
27.0.0.1:54513
>=20
>  Sep  5 15:48:34.065 2007 at LM_DEBUG@(3065756560) Client 8 up; now 9 up, 0 =
down.
>=20
> Sep  5 15:48:34.065 2007 at LM_DEBUG@(3065756560) Client 8 connected on 127.=
0.0.1:54513
>=20
>  Sep  5 15:48:34.065 2007 at LM_DEBUG@(3065756560) Server 9 up; now 10 up, 0=
 down.
>=20
> Sep  5 15:48:34.066 2007 at LM_DEBUG@(3065756560) Server 9 connection from 1=
27.0.0.1:54514
>=20
>  Sep  5 15:48:34.066 2007 at LM_DEBUG@(3065756560) Client 9 up; now 10 up, 0=
 down.
>=20
> Sep  5 15:48:34.066 2007 at LM_DEBUG@(3065756560) Client 9 connected on 127.=
0.0.1:54514
>=20
>  Sep  5 15:48:34.109 2007 at LM_DEBUG@(3044776848) Client 0 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.109 2007 at LM_DEBUG@(3044776848) Closing Client 0 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.115 2007 at LM_DEBUG@(3044776848) Server 0 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.116 2007 at LM_DEBUG@(3044776848) Server 0 gone; now 10 up,=
 1 down
>=20
>  Sep  5 15:48:34.117 2007 at LM_DEBUG@(3065756560) Client 0 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.118 2007 at LM_DEBUG@(3065756560) Client 0 gone; now 10 up,=
 1 down
>=20
>  Sep  5 15:48:34.158 2007 at LM_DEBUG@(3065756560) Client 3 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.158 2007 at LM_DEBUG@(3065756560) Closing Client 3 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.162 2007 at LM_DEBUG@(3055266704) Server 3 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.162 2007 at LM_DEBUG@(3055266704) Server 3 gone; now 10 up,=
 2 down
>=20
>  Sep  5 15:48:34.163 2007 at LM_DEBUG@(3055266704) Client 3 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.163 2007 at LM_DEBUG@(3055266704) Client 3 gone; now 10 up,=
 2 down
>=20
>  Sep  5 15:48:34.194 2007 at LM_DEBUG@(3044776848) Client 1 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.194 2007 at LM_DEBUG@(3044776848) Closing Client 1 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.196 2007 at LM_DEBUG@(3055266704) Server 1 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.196 2007 at LM_DEBUG@(3055266704) Server 1 gone; now 10 up,=
 3 down
>=20
>  Sep  5 15:48:34.197 2007 at LM_DEBUG@(3055266704) Client 1 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.197 2007 at LM_DEBUG@(3055266704) Client 1 gone; now 10 up,=
 3 down
>=20
> Sep  5 15:48:34.212 2007 at LM_DEBUG@(3044776848) Client 2 sent 7850, limit =
7850
>=20
>  Sep  5 15:48:34.213 2007 at LM_DEBUG@(3044776848) Closing Client 2 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.216 2007 at LM_DEBUG@(3065756560) Server 2 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.217 2007 at LM_DEBUG@(3065756560) Server 2 gone; now 10 up,=
 4 down
>=20
>  Sep  5 15:48:34.228 2007 at LM_DEBUG@(3065756560) Client 2 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.228 2007 at LM_DEBUG@(3065756560) Client 2 gone; now 10 up,=
 4 down
>=20
>  Sep  5 15:48:34.232 2007 at LM_DEBUG@(3044776848) Client 8 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.233 2007 at LM_DEBUG@(3044776848) Closing Client 8 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.233 2007 at LM_DEBUG@(3044776848) Client 4 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.233 2007 at LM_DEBUG@(3044776848) Closing Client 4 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.235 2007 at LM_DEBUG@(3055266704) Server 4 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.235 2007 at LM_DEBUG@(3055266704) Server 4 gone; now 10 up,=
 5 down
>=20
>  Sep  5 15:48:34.236 2007 at LM_DEBUG@(3055266704) Server 8 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.236 2007 at LM_DEBUG@(3055266704) Server 8 gone; now 10 up,=
 6 down
>=20
>  Sep  5 15:48:34.237 2007 at LM_DEBUG@(3055266704) Client 4 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
> Sep  5 15:48:34.237 2007 at LM_DEBUG@(3055266704) Client 4 gone; now 10 up, =
5 down
>=20
>  Sep  5 15:48:34.237 2007 at LM_DEBUG@(3055266704) Client 6 sent 7850, limit=
 7850 Sep  5 15:48:34.237 2007 at LM_DEBUG@
> (3055266704) Closing Client 6 writes; 1 I/O outstanding
>=20
>  Sep  5 15:48:34.238 2007 at LM_DEBUG@(3044776848) Client 8 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.238 2007 at LM_DEBUG@(3044776848) Client 8 gone; now 10 up,=
 6 down
>=20
>  Sep  5 15:48:34.240 2007 at LM_DEBUG@(3044776848) Server 6 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.240 2007 at LM_DEBUG@(3044776848) Server 6 gone; now 10 up,=
 7 down
>=20
>  Sep  5 15:48:34.241 2007 at LM_DEBUG@(3044776848) Client 6 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.241 2007 at LM_DEBUG@(3044776848) Client 6 gone; now 10 up,=
 7 down
>=20
>  Sep  5 15:48:34.246 2007 at LM_DEBUG@(3065756560) Client 5 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.247 2007 at LM_DEBUG@(3065756560) Closing Client 5 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.249 2007 at LM_DEBUG@(3044776848) Server 5 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.249 2007 at LM_DEBUG@(3044776848) Server 5 gone; now 10 up,=
 8 down
>=20
>  Sep  5 15:48:34.250 2007 at LM_DEBUG@(3044776848) Client 5 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.250 2007 at LM_DEBUG@(3044776848) Client 5 gone; now 10 up,=
 8 down
>=20
>  Sep  5 15:48:34.251 2007 at LM_DEBUG@(3055266704) Client 7 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.251 2007 at LM_DEBUG@(3055266704) Closing Client 7 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.252 2007 at LM_DEBUG@(3055266704) Server 7 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.252 2007 at LM_DEBUG@(3055266704) Server 7 gone; now 10 up,=
 9 down
>=20
>  Sep  5 15:48:34.253 2007 at LM_DEBUG@(3055266704) Client 9 sent 7850, limit=
 7850
>=20
>  Sep  5 15:48:34.253 2007 at LM_DEBUG@(3055266704) Closing Client 9 writes; =
1 I/O outstanding
>=20
>  Sep  5 15:48:34.253 2007 at LM_DEBUG@(3065756560) Server 9 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.254 2007 at LM_DEBUG@(3065756560) Server 9 gone; now 10 up,=
 10 down
>=20
>  Sep  5 15:48:34.254 2007 at LM_DEBUG@(3044776848) Client 7 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes)
>=20
>  Sep  5 15:48:34.254 2007 at LM_DEBUG@(3044776848) Client 7 gone; now 10 up,=
 9 down
>=20
>  Sep  5 15:48:34.254 2007 at LM_DEBUG@(3044776848) Client 9 dtor; 50 sends (=
7850 bytes); 51 recvs (7850 bytes) Sep  5
> 15:48:34.254 2007 at LM_DEBUG@(3044776848) Client 9 gone; now 10 up, 10 down
>=20
>  Sep  5 15:48:37.066 2007 at LM_DEBUG@(3086739152) Sleeping til sessions run=
 down.
>=20
> Sep  5 15:48:37.066 2007 at LM_DEBUG@(3086739152) Stop Thread Pool Task
>=20
>  Sep  5 15:48:37.066 2007 at LM_DEBUG@(3086739152) Calling End Proactor even=
t loop
>=20
>  Sep  5 15:48:37.067 2007 at LM_DEBUG@(3044776848) MyTask finished
>=20
>  Sep  5 15:48:37.067 2007 at LM_DEBUG@(3065756560) MyTask finished
>=20
>  Sep  5 15:48:37.067 2007 at LM_DEBUG@(3055266704) MyTask finished
>=20
>  Sep  5 15:48:37.068 2007 at LM_DEBUG@(8600|3086739152)
>=20
>  Ending Proactor_Test test at Wed Sep  5 2007 15:48:37.068315
>=20
>  =3D=3D=3D=3D=3D=3D=3D End Log File
>=20
> Error: (log/Process_Semaphore_Test.log): Child 8941 finished with status =
1124396
>=20
>  =3D=3D=3D=3D=3D=3D=3D Begin Log File
>=20
>  Sep  5 15:49:55.081 2007 at LM_DEBUG@(8940|3086808784)
>=20
>  Starting Process_Semaphore_Test test at Wed Sep  5 2007 15:49:55.081995 =
Sep  5 15:49:55.084 2007 at LM_DEBUG@Parent spawned
> child process with pid =3D 8941.
>=20
> Sep  5 15:49:55.085 2007 at LM_DEBUG@(8940) Begin ping-pong
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
> Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Ping
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8940) Testing timeouts
>=20
>  Sep  5 15:49:58.096 2007 at LM_ERROR@Child 8941 finished with status 1124396
>=20
>  Sep  5 15:49:58.097 2007 at LM_DEBUG@(8940|3086808784)
>=20
>  Ending Process_Semaphore_Test test at Wed Sep  5 2007 15:49:58.097030
>=20
> =3D=3D=3D=3D=3D=3D=3D End Log File =3D=3D=3D=3D=3D=3D=3D
>=20
>  Begin Sublog File Process_Semaphore_Test-child.log
>=20
>  Sep  5 15:49:55.093 2007 at LM_DEBUG@(8941|3086227152)
>=20
>  Starting Process_Semaphore_Test-child test at Wed Sep  5 2007 15:49:55.0=
93588
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8941) Begin ping-pong
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.094 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
> Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Pong
>=20
>  Sep  5 15:49:55.095 2007 at LM_DEBUG@(8941) Testing timeouts
>=20
>  Sep  5 15:49:58.098 2007 at LM_DEBUG@(8941|3086227152)
>=20
>  Ending Process_Semaphore_Test-child test at Wed Sep  5 2007 15:49:58.098=
149
>=20
> =3D=3D=3D=3D=3D=3D=3D End Sublog File
>=20
> _______________________________________________
> ace-bugs mailing list
> ace-bugs at mail.cse.wustl.edu
> http://mail.cse.wustl.edu/mailman/listinfo/ace-bugs



More information about the Ace-users mailing list