[asterisk-dev] Channel problems

Mon Jul 17 05:19:48 MST 2006

Hi,

again have strange channel problems with sip:

------

Jul 15 16:52:51 VERBOSE[18440] logger.c:     -- Executing
DeadAGI("SIP/mysipid-3d7b", "newalepo.php") in new stack
Jul 15 16:52:51 VERBOSE[18440] logger.c:     -- Launched AGI Script
/var/lib/asterisk/agi-bin/newalepo.php
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_request' =>
'newalepo.php'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_channel' =>
'SIP/mysipid-3d7b'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_language' => 'en'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_type' => 'SIP'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_uniqueid' =>
'1152982371.5285'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_callerid' =>
'mysipid'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_calleridname' =>
'mysipid'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_callingpres' =>
'0'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_callingani2' =>
'0'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_callington' => '0'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_callingtns' => '0'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_dnid' =>
'92215616237'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_rdnis' =>
'unknown'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_context' =>
'from-gigasip-user'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_extension' =>
'92215616237'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_priority' => '1'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_enhanced' => '0.0'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 'agi_accountcode' =>
'mysipid'
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: 
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: string(96) "mysipid
; SIP/mysipid-3d7b ; 1152982371.5285 ; mysipid ; 0092215616237 ;
92215616237"n
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: >> SET AUTOHANGUP 3600
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: >> SET CALLERID 
Jul 15 16:52:51 VERBOSE[18440] logger.c:   newalepo.php: >> EXEC DIAL
sip/70092215616237 at 217.111.25.154
Jul 15 16:52:51 VERBOSE[18440] logger.c:     -- AGI Script Executing
Application: (DIAL) Options: (sip/70092215616237 at 217.111.25.154)
Jul 15 16:52:51 DEBUG[18440] res_config_mysql.c: MySQL RealTime: Retrieve SQL:
SELECT * FROM sipfriends WHERE name = '217.111.25.154'
Jul 15 16:52:51 DEBUG[18440] res_config_mysql.c: MySQL RealTime: Everything is
fine.
Jul 15 16:52:51 DEBUG[18440] chan_sip.c: Outgoing Call for 70092215616237
Jul 15 16:52:51 VERBOSE[18440] logger.c:     -- Called
70092215616237 at 217.111.25.154
Jul 15 16:53:06 VERBOSE[18440] logger.c:     -- SIP/217.111.25.154-bb31 is
making progress passing it to SIP/mysipid-3d7b
Jul 15 16:53:07 DEBUG[18440] chan_sip.c: Oooh, format changed to 2
Jul 15 16:53:09 NOTICE[18440] rtp.c: Comfort noise support incomplete in
Asterisk (RFC 3389). Please turn off on client if possible. Client IP:
217.111.25.154
Jul 15 16:53:17 VERBOSE[18440] logger.c:     -- SIP/217.111.25.154-bb31 is
making progress passing it to SIP/mysipid-3d7b
Jul 15 16:54:02 VERBOSE[18440] logger.c:     -- SIP/217.111.25.154-bb31 answered
SIP/mysipid-3d7b
Jul 15 16:54:02 VERBOSE[18440] logger.c:     -- Attempting native bridge of
SIP/mysipid-3d7b and SIP/217.111.25.154-bb31
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
.
.
.
repeated 2 million times
...
...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 17:52:51 DEBUG[18440] channel.c: Nobody there, continuing...
Jul 15 18:05:45 DEBUG[18440] channel.c: Didn't get a frame from channel:
SIP/217.111.25.154-bb31
Jul 15 18:05:45 DEBUG[18440] channel.c: Bridge stops bridging channels
SIP/mysipid-3d7b and SIP/217.111.25.154-bb31
Jul 15 18:05:45 DEBUG[18440] chan_sip.c: update_call_counter(70092215616237) -
decrement call limit counter
Jul 15 18:05:45 DEBUG[18440] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jul 15 18:05:45 VERBOSE[18440] logger.c:   newalepo.php: >> GET VARIABLE
ANSWEREDTIME
Jul 15 18:05:45 VERBOSE[18440] logger.c:   newalepo.php: >> GET VARIABLE
DIALSTATUS
Jul 15 18:05:45 VERBOSE[18440] logger.c:   newalepo.php: string(6) "ANSWER"n
Jul 15 18:05:45 VERBOSE[18440] logger.c:     -- AGI Script newalepo.php
completed, returning 0
Jul 15 18:05:45 DEBUG[18440] pbx.c: Extension 92215616237, priority 1 returned
normally even though call was hung up
Jul 15 18:05:45 DEBUG[18440] cdr_addon_mysql.c: cdr_mysql: inserting a CDR
record.
Jul 15 18:05:45 DEBUG[18440] cdr_addon_mysql.c: cdr_mysql: SQL command as
follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode)
VALUES ('2006-07-15 16:52:51','\"mysipid\"
<mysipid>','mysipid','92215616237','contextsip',
'SIP/mysipid-3d7b','SIP/217.111.25.154-bb31','Dial','sip/70092215616237 at 217.111.25.154',4374,4303,'ANSWERED',3,'mysipid')

Jul 15 18:05:45 DEBUG[18440] chan_sip.c: update_call_counter(mysipid) -
decrement call limit counter

-----

So why is asterisk keeping channels up when "...Nobody there, continuing...". And what happened there? Won't Asterisk hangup when the user pulls the line out of the wall? Or when the user looses signal from his Accesspoint using a WLAN phone?

Thanks
-- 

"Feel free" – 10 GB Mailbox, 100 FreeSMS/Monat ...
Jetzt GMX TopMail testen: http://www.gmx.net/de/go/topmail