Bug 2282

Summary: ACE Proactor_Test fails to complete
Product: ACE Reporter: Semih Cemiloglu <Semih.Cemiloglu>
Component: ACE TestsAssignee: DOC Center Support List (internal) <tao-support>
Status: RESOLVED DUPLICATE    
Severity: normal CC: Semih.Cemiloglu
Priority: P3    
Version: 5.4.7   
Hardware: x86   
OS: Solaris   

Description Semih Cemiloglu 2005-10-24 18:52:27 CDT
ACE VERSION: 5.4.7

    HOST MACHINE and OPERATING SYSTEM:
        Open Solaris (Solaris 10) Intel ix86
        SunOS agent99 5.11 snv_22 i86pc i386 i86pc
        build #22

    TARGET MACHINE and OPERATING SYSTEM, if different from HOST:
    COMPILER NAME AND VERSION (AND PATCHLEVEL):
        CC: Sun C++ 5.7 2005/01/07
        Forte Patches 118682-01 and 117846-09 have been applied.
        Note: "Sun Studio 10 software is freely available to participants in 
	the OpenSolaris community for development on both OpenSolaris and 
	Solaris[tm] Operating Systems on SPARC-based systems and 
	x86-based systems, as well as on Linux."

    THE $ACE_ROOT/ace/config.h FILE:
    #include "ace/config-sunos5.10.h"

    THE $ACE_ROOT/include/makeinclude/platform_macros.GNU FILE:
    include $(ACE_ROOT)/include/makeinclude/platform_sunos5_sunc++.GNU


    CONTENTS OF $ACE_ROOT/bin/MakeProjectCreator/config/default.features
    (used by MPC when you generate your own makefiles):
    ssl=0
    qos=1
    cidl=0
    rwho=0
    sctp=0


    AREA/CLASS/EXAMPLE AFFECTED:
    $ACE_ROOT/ace/tests/Proactor_Test fails.

    DOES THE PROBLEM AFFECT:
        COMPILATION? NO
        LINKING? NO
        EXECUTION? YES
        OTHER (please specify)?

    SYNOPSIS:
    One of the ACE regression tests, "Proactor_Test" fails.

    DESCRIPTION:

auto_run_tests_finished: test/Proactor_Scatter_Gather_Test Time:1s Result:0
auto_run_tests: tests/Proactor_Test
ERROR: Proactor_Test timedout
Error: Proactor_Test FAILED (time out)

auto_run_tests_finished: test/Proactor_Test Time:404s Result:-1
Error (log/Proactor_Test.log): no line with 'Ending'
======= Begin Log File 
Oct 21 15:07:27.042 2005@LM_DEBUG@(24511|1) Starting Proactor_Test test at Fri 
Oct 21 2005 15:07:27.043331
Oct 21 15:07:27.045 2005@LM_DEBUG@(1) Create Proactor Type = DEFAULT
Oct 21 15:07:27.047 2005@LM_DEBUG@(24511 | 1) ACE_POSIX_AIOCB_Proactor::Max 
Number of AIOs=1024
Oct 21 15:07:27.084 2005@LM_DEBUG@(15) MyTask started
Oct 21 15:07:27.084 2005@LM_DEBUG@(14) MyTask started
Oct 21 15:07:27.085 2005@LM_DEBUG@(16) MyTask started
Oct 21 15:07:27.097 2005@LM_DEBUG@(15) Client 0 up; now 1 up, 0 down.
Oct 21 15:07:27.097 2005@LM_DEBUG@(15) Client 0 connected on 127.0.0.1:54396
Oct 21 15:07:27.098 2005@LM_DEBUG@(15) Client 1 up; now 2 up, 0 down.
Oct 21 15:07:27.098 2005@LM_DEBUG@(15) Client 1 connected on 127.0.0.1:65187
Oct 21 15:07:27.099 2005@LM_DEBUG@(16) Client 2 up; now 3 up, 0 down.
Oct 21 15:07:27.099 2005@LM_DEBUG@(16) Client 2 connected on 127.0.0.1:41634
Oct 21 15:07:27.100 2005@LM_DEBUG@(16) Client 3 up; now 4 up, 0 down.
Oct 21 15:07:27.100 2005@LM_DEBUG@(16) Client 3 connected on 127.0.0.1:43335
Oct 21 15:07:27.101 2005@LM_DEBUG@(16) Client 4 up; now 5 up, 0 down.
Oct 21 15:07:27.101 2005@LM_DEBUG@(16) Client 4 connected on 127.0.0.1:47203
Oct 21 15:07:27.101 2005@LM_DEBUG@(16) Client 5 up; now 6 up, 0 down.
Oct 21 15:07:27.102 2005@LM_DEBUG@(16) Client 5 connected on 127.0.0.1:61586
Oct 21 15:07:27.102 2005@LM_DEBUG@(16) Client 6 up; now 7 up, 0 down.
Oct 21 15:07:27.102 2005@LM_DEBUG@(16) Client 6 connected on 127.0.0.1:60439
Oct 21 15:07:27.103 2005@LM_DEBUG@(16) Server 0 up; now 1 up, 0 down.
Oct 21 15:07:27.103 2005@LM_DEBUG@(16) Server 0 connection from 127.0.0.1:54396
Oct 21 15:07:27.103 2005@LM_DEBUG@(15) Client 7 up; now 8 up, 0 down.
Oct 21 15:07:27.103 2005@LM_DEBUG@(15) Client 7 connected on 127.0.0.1:43816
Oct 21 15:07:27.104 2005@LM_DEBUG@(15) Server 1 up; now 2 up, 0 down.
Oct 21 15:07:27.104 2005@LM_DEBUG@(15) Server 1 connection from 127.0.0.1:65187
Oct 21 15:07:27.104 2005@LM_DEBUG@(15) Server 2 up; now 3 up, 0 down.
Oct 21 15:07:27.105 2005@LM_DEBUG@(15) Server 2 connection from 127.0.0.1:43816
Oct 21 15:07:27.105 2005@LM_DEBUG@(15) Server 3 up; now 4 up, 0 down.
Oct 21 15:07:27.105 2005@LM_DEBUG@(15) Server 3 connection from 127.0.0.1:41634
Oct 21 15:07:27.106 2005@LM_DEBUG@(15) Server 4 up; now 5 up, 0 down.
Oct 21 15:07:27.106 2005@LM_DEBUG@(15) Server 4 connection from 127.0.0.1:43335
Oct 21 15:07:27.116 2005@LM_DEBUG@(14) Server 5 up; now 6 up, 0 down.
Oct 21 15:07:27.116 2005@LM_DEBUG@(14) Server 5 connection from 127.0.0.1:47203
Oct 21 15:07:27.117 2005@LM_DEBUG@(14) Server 6 up; now 7 up, 0 down.
Oct 21 15:07:27.117 2005@LM_DEBUG@(14) Server 6 connection from 127.0.0.1:61586
Oct 21 15:07:27.117 2005@LM_DEBUG@(14) Server 7 up; now 8 up, 0 down.
Oct 21 15:07:27.117 2005@LM_DEBUG@(14) Server 7 connection from 127.0.0.1:60439
Oct 21 15:07:27.140 2005@LM_DEBUG@(15) Client 2 sent 7850, limit 7850
Oct 21 15:07:27.141 2005@LM_DEBUG@(15) Closing Client 2 writes; 2 I/O 
outstanding
Oct 21 15:07:27.142 2005@LM_DEBUG@(15) Server 3 dtor; 50 sends (7850 bytes); 51 
recvs (7850 bytes)
Oct 21 15:07:27.142 2005@LM_DEBUG@(15) Server 3 gone; now 8 up, 1 down
Oct 21 15:07:27.146 2005@LM_DEBUG@(15) Client 5 sent 7850, limit 7850
Oct 21 15:07:27.146 2005@LM_DEBUG@(15) Closing Client 5 writes; 2 I/O 
outstanding
Oct 21 15:07:27.149 2005@LM_DEBUG@(15) Client 3 sent 7850, limit 7850
Oct 21 15:07:27.149 2005@LM_DEBUG@(15) Closing Client 3 writes; 2 I/O 
outstanding
Oct 21 15:07:27.154 2005@LM_DEBUG@(15) Client 1 sent 7850, limit 7850
Oct 21 15:07:27.154 2005@LM_DEBUG@(15) Closing Client 1 writes; 2 I/O 
outstanding
Oct 21 15:07:27.158 2005@LM_DEBUG@(15) Client 0 sent 7850, limit 7850
Oct 21 15:07:27.158 2005@LM_DEBUG@(15) Closing Client 0 writes; 2 I/O 
outstanding
Oct 21 15:07:27.159 2005@LM_DEBUG@(15) Client 7 sent 7850, limit 7850
Oct 21 15:07:27.160 2005@LM_DEBUG@(15) Closing Client 7 writes; 2 I/O 
outstanding
Oct 21 15:07:27.163 2005@LM_DEBUG@(15) Client 4 sent 7850, limit 7850
Oct 21 15:07:27.163 2005@LM_DEBUG@(15) Closing Client 4 writes; 2 I/O 
outstanding
Oct 21 15:07:27.165 2005@LM_DEBUG@(16) Client 6 sent 7850, limit 7850
Oct 21 15:07:27.165 2005@LM_DEBUG@(16) Closing Client 6 writes; 2 I/O 
outstanding
Oct 21 15:07:30.101 2005@LM_DEBUG@(1) Sleeping til sessions run down.
======= End Log File

==========
dbx information of the core file taken by gcore while the Prtoactor_Test hangs:
==========
agent99$ dbx Proactor_Test core.10104
For information about new features see `help changes'
To remove this message, put `dbxenv suppress_startup_message 7.4' in your .dbxrc
Reading Proactor_Test
core file header read successfully
Reading ld.so.1
Reading libTest_Output.so.5.4.7
Reading libACE.so.5.4.7
Reading libsocket.so.1
Reading libdl.so.1
Reading libnsl.so.1
Reading libgen.so.1
Reading librt.so.1
Reading libadm.so.1
Reading libCstd.so.1
Reading libCrun.so.1
Reading libm.so.2
Reading libthread.so.1
Reading libc.so.1
Reading libaio.so.1
Reading librt.so.1
Reading libadm.so.1
Reading libCstd.so.1
Reading libCrun.so.1
Reading libm.so.2
Reading libthread.so.1
Reading libc.so.1
Reading libaio.so.1
Reading libmd5.so.1
t@null (l@28) terminated by signal 0 (UNKNOWN SIGNAL)
0xd2952749: __lwp_park+0x0019:  jae      __lwp_park+0x27        [ 
0xd2952757, .+0xe ]
(dbx) where
=>[1] __lwp_park(0x0, 0x0), at 0xd2952749 
  [2] cond_sleep_queue(0x81f2210, 0x81f2220, 0x0), at 0xd294cc6f 
  [3] cond_wait_queue(0x81f2210, 0x81f2220, 0x0, 0x0), at 0xd294cd84 
  [4] _cond_wait(0x81f2210, 0x81f2220), at 0xd294d26d 
  [5] cond_wait(0x81f2210, 0x81f2220), at 0xd294d2af 
  [6] _aio_idle(0x81f21c8, 0x0, 0xd27e6400, 0xd2998000, 0x1, 0x0), at 
0xd28b3152 
  [7] _aio_do_request(0x81f21c8), at 0xd28b2985 
  [8] _thr_setup(0xd27e6400), at 0xd29522ea 
  [9] _lwp_start(), at 0xd29526f0 
(dbx) 


    REPEAT BY:
    run $ACE_ROOT/tests/run_test.pl script

    SAMPLE FIX/WORKAROUND:
    N/A
Comment 1 Johnny Willemsen 2005-10-31 07:08:35 CST
Duplicate

*** This bug has been marked as a duplicate of 1426 ***