[asterisk-users] problems in h323 channels

salzh zhongxiang721 at gmail.com
Tue May 5 21:37:32 CDT 2009


Hi, all!
when my h323 phone dial in Asterisk system, i can hear nothing. and
the following is the log slice i picked from /var/log/asterisk/full.
ps: i am using red hat AS5 kernel 2.6.18-53.el5,Asterisk-1.4.24.1,
pwlib_v1_11_0, openh323_v1_19_0_1.

      Best
Regards!

 81948 [May  6 10:07:34] VERBOSE[11579] logger.c:     -- Remote UNIX connection
  81949 [May  6 10:07:51] VERBOSE[29627] logger.c:  == New H.323
Connection created.
  81950 [May  6 10:07:51] VERBOSE[29627] logger.c:  --Received SETUP message
  81951 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting up
incoming call for ip$192.168.1.175:3584/90
  81952 [May  6 10:07:51] VERBOSE[29627] logger.c:     -- Setting up Call
  81953 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Call
token:  [ip$192.168.1.175:3584/90]
  81954 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Calling
party name:  [72002]
  81955 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Calling
party number:  []
  81956 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Called
party name:  []
  81957 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Called
party number:  []
  81958 [May  6 10:07:51] VERBOSE[29627] logger.c:     --   Calling
party IP:  [192.168.1.175]
  81959 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Could not find
user by name 72002 or address 192.168.1.175
  81960 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Sending
72002 at 192.168.1.175 to context [h323-test] extensio        n
  81961 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting
capabilities for connection ip$192.168.1.175:3584/9        0
  81962 [May  6 10:07:51] VERBOSE[29627] logger.c: Setting
capabilities to 0x4 (ulaw)
  81963 [May  6 10:07:51] VERBOSE[29627] logger.c: Capabilities in
preference order is (ulaw)
  81964 [May  6 10:07:51] VERBOSE[29627] logger.c: Allowed Codecs:
  81965 [May  6 10:07:51] VERBOSE[29627] logger.c:   Table:
  81966 [May  6 10:07:51] VERBOSE[29627] logger.c:    G.711-uLaw-64k <1>
  81967 [May  6 10:07:51] VERBOSE[29627] logger.c:    UserInput/hookflash <2>
  81968 [May  6 10:07:51] VERBOSE[29627] logger.c:    UserInput/RFC2833 <3>
  81969 [May  6 10:07:51] VERBOSE[29627] logger.c:    UserInput/dtmf <4>
  81970 [May  6 10:07:51] VERBOSE[29627] logger.c:  Set:
  81971 [May  6 10:07:51] VERBOSE[29627] logger.c:    0:
  81972 [May  6 10:07:51] VERBOSE[29627] logger.c:      0:
  81973 [May  6 10:07:51] VERBOSE[29627] logger.c:        G.711-uLaw-64k <1>
  81974 [May  6 10:07:51] VERBOSE[29627] logger.c:      1:
  81975 [May  6 10:07:51] VERBOSE[29627] logger.c:
UserInput/hookflash <2>
  81976 [May  6 10:07:51] VERBOSE[29627] logger.c:      2:
  81977 [May  6 10:07:51] VERBOSE[29627] logger.c:        UserInput/RFC2833 <3>
  81978 [May  6 10:07:51] VERBOSE[29627] logger.c:        UserInput/dtmf <4>
 81979 [May  6 10:07:51] VERBOSE[29627] logger.c:
  81980 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Capabilities for
connection ip$192.168.1.175:3584/90 is set
  81981 [May  6 10:07:51] VERBOSE[29627] logger.c:  =-= In
OnAnswerCall for call 90
  81982 [May  6 10:07:51] VERBOSE[29627] logger.c:      - Progress Indicator: 0
  81983 [May  6 10:07:51] VERBOSE[29627] logger.c:      - Inserting PI
of 0 into ALERTING message
  81984 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Preparing Asterisk
to answer for ip$192.168.1.175:3584/90
  81985 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Going to extension
s at h323-test because @h323-test isn't exi        sts
  81986 [May  6 10:07:51] VERBOSE[29645] logger.c:     -- Executing
[s at h323-test:1] Answer("H323/ip$192.168.1.1        75:3584/90", "") in
new stack
  81987 [May  6 10:07:51] DEBUG[29645] chan_h323.c: Answering on
H323/ip$192.168.1.175:3584/90
  81988 [May  6 10:07:51] VERBOSE[29645] logger.c:  Answering call
ip$192.168.1.175:3584/90
  81989 [May  6 10:07:51] DEBUG[29645] chan_h323.c: OH323: Indicating
-1 on ip$192.168.1.175:3584/90
  81990 [May  6 10:07:51] DEBUG[29645] chan_h323.c: OH323: Indicated
-1 on ip$192.168.1.175:3584/90, res=-1
  81991 [May  6 10:07:51] VERBOSE[29645] logger.c:     -- Executing
[s at h323-test:2] Playback("H323/ip$192.168.1        .175:3584/90",
"agent-login") in new stack
  81992 [May  6 10:07:51] VERBOSE[29645] logger.c:     --
<H323/ip$192.168.1.175:3584/90> Playing 'agent-login'
(language 'en')
  81993 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  81994 [May  6 10:07:51] VERBOSE[29627] logger.c: Peer capability is
G.711-uLaw-64k <1>
  81995 [May  6 10:07:51] VERBOSE[29627] logger.c: Found peer
capability G.711-uLaw-64k <1>, Asterisk code is 4        , frame size
(in ms) is 20
  81996 [May  6 10:07:51] VERBOSE[29627] logger.c: Peer capabilities =
0x4 (ulaw), ordered list is (ulaw)
  81997 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Got remote
capabilities from connection ip$192.168.1.175:35        84/90
  81998 [May  6 10:07:51] DEBUG[29627] chan_h323.c: prefs[0]=ulaw:20
  81999 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  82000 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  82001 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  82002 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Created RTP channel
  82003 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting NAT on RTP to 0
  82004 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Sending RTP 'US'
127.0.0.1:17380
  82005 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Started logical
channel: sending G.711-uLaw-64k
 82006 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- channelsOpen = 1
  82007 [May  6 10:07:51] VERBOSE[29627] logger.c:  =-= In
OnConnectionEstablished for call 90
  82008 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- Connection
Established with "72002 [192.168.1.175]"
  82009 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Call
ip$192.168.1.175:3584/90 answered
  82010 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  82011 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Sending RTP 'US'
127.0.0.1:17380
  82012 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Started logical
channel: receiving G.711-uLaw-64k
  82013 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- channelsOpen = 2
  82014 [May  6 10:07:51] VERBOSE[29627] logger.c:      External RTP
Session Starting
  82015 [May  6 10:07:51] VERBOSE[29627] logger.c:      RTP channel id
1 parameters:
  82016 [May  6 10:07:51] VERBOSE[29627] logger.c:      --
remoteIpAddress: 192.168.1.175
  82017 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- remotePort: 40000
  82018 [May  6 10:07:51] VERBOSE[29627] logger.c:      --
ExternalIpAddress: 127.0.0.1
  82019 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- ExternalPort: 17380
  82020 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting up RTP
connection for ip$192.168.1.175:3584/90
  82021 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Native format is
set to 4 from 4 by RTP payload type 0
  82022 [May  6 10:07:51] DEBUG[29627] chan_h323.c: RTP connection
prepared for ip$192.168.1.175:3584/90
  82023 [May  6 10:07:51] WARNING[29645] file.c: Unexpected control
subclass '14'
  82024 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28354 to 192.168.1.175:40000: In        valid argument
  82025 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28355 to 192.168.1.175:40000: In        valid argument
  82026 [May  6 10:07:51] VERBOSE[29627] logger.c:  -- Received
Facility message...
  82027 [May  6 10:07:51] VERBOSE[29627] logger.c:
MyH323_ExternalRTPChannel::OnReceivedAckPDU
  82028 [May  6 10:07:51] VERBOSE[29627] logger.c:      --
remoteIpAddress: 192.168.1.175
  82029 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- remotePort: 40000
  82030 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting up RTP
connection for ip$192.168.1.175:3584/90
  82031 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Native format is
set to 4 from 4 by RTP payload type 0
  82032 [May  6 10:07:51] DEBUG[29627] chan_h323.c: RTP connection
prepared for ip$192.168.1.175:3584/90
  82033 [May  6 10:07:51] VERBOSE[29627] logger.c:      External RTP
Session Starting
  82034 [May  6 10:07:51] VERBOSE[29627] logger.c:      RTP channel id
1 parameters:
  82035 [May  6 10:07:51] VERBOSE[29627] logger.c:      --
remoteIpAddress: 192.168.1.175
  82036 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- remotePort: 40000
 82037 [May  6 10:07:51] VERBOSE[29627] logger.c:      --
ExternalIpAddress: 127.0.0.1
  82038 [May  6 10:07:51] VERBOSE[29627] logger.c:      -- ExternalPort: 17380
  82039 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Setting up RTP
connection for ip$192.168.1.175:3584/90
  82040 [May  6 10:07:51] DEBUG[29627] chan_h323.c: Native format is
set to 4 from 4 by RTP payload type 0
  82041 [May  6 10:07:51] DEBUG[29627] chan_h323.c: RTP connection
prepared for ip$192.168.1.175:3584/90
  82042 [May  6 10:07:51] WARNING[29645] file.c: Unexpected control
subclass '14'
  82043 [May  6 10:07:51] WARNING[29645] file.c: Unexpected control
subclass '14'
  82044 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28356 to 192.168.1.175:40000: In        valid argument
  82045 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28357 to 192.168.1.175:40000: In        valid argument
  82046 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28358 to 192.168.1.175:40000: In        valid argument
  82047 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28359 to 192.168.1.175:40000: In        valid argument
  82048 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28360 to 192.168.1.175:40000: In        valid argument
  82049 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28361 to 192.168.1.175:40000: In        valid argument
  82050 [May  6 10:07:51] DEBUG[29645] rtp.c: RTP Transmission error
of packet 28362 to 192.168.1.175:40000: In        valid argument
....



More information about the asterisk-users mailing list