[asterisk-users] bridging - Didn't get a frame from channel

Tony Gaspar tvgaspar at yahoo.com.au
Tue Nov 25 19:14:31 CST 2008


Hi,
 
 
I am having a difficulty with 
getting two realtime user’s to bridge on answer. I have managed successfully to 
bridge the same two users/channels via the Bridge Manager api command and 
confirm that the two communicate directly bypassing the asterisk server (I 
confirmed this with Wireshark). 
 
 
Does anyone have some ideas? I have 
put some log entries below. 
 
I haven’t attached my dialplan. Some 
behaviours I have discovered are: 1) If user A dials directly to the call number 
of user B, bridging works on answer and the failed log entries below don’t 
occur. 2) If user A dials into a queue and waits for User B (who is registered 
in the queue). Then when User B answers, the bridging failures occur as in the 
log entries below.
 
Thank 
you.
 
Tony 
Gaspar
 
 
 
 
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Internal timing is disabled (option_internal_timing=0 
chan->timingfd=26)
[Nov 25 17:47:38] DEBUG[27462] 
devicestate.c: Notification of state change to be queued on device/channel 
IAX2/asxop
[Nov 25 17:47:38] VERBOSE[27462] 
logger.c:     -- IAX2/asxop-14202 answered 
IAX2/wally-10884
[Nov 25 17:47:38] DEBUG[25220] 
devicestate.c: No provider found, checking channel drivers for IAX2 - 
asxop
[Nov 25 17:47:38] DEBUG[25220] 
chan_iax2.c: Checking device state for device asxop
[Nov 25 17:47:38] DEBUG[25220] 
chan_iax2.c: iax2_devicestate: Found peer. What's device state of asxop? 
addr=984047307, defaddr=0 maxms=5000, lastms=191
[Nov 25 17:47:38] DEBUG[25220] 
devicestate.c: Changing state for IAX2/asxop - state 2 (In 
use)
[Nov 25 17:47:38] DEBUG[25225] 
app_queue.c: Device 'IAX2/asxop' changed to state '2' (In 
use)
[Nov 25 17:47:38] DEBUG[27462] 
app_queue.c: Next is 'IAX2/mark' with metric 1
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Set channel IAX2/wally-10884 to write format 
ilbc
[Nov 25 17:47:38] VERBOSE[27462] 
logger.c:     -- Stopped music on hold on 
IAX2/wally-10884
[Nov 25 17:47:38] DEBUG[27462] 
channel.c: Scheduling timer at 0 sample intervals
[Nov 25 17:47:38] DEBUG[27462] 
app_queue.c: Starting MixMonitor as requested.
[Nov 25 17:47:38] DEBUG[27462] 
app_queue.c: Arguments being passed to MixMonitor: 
1227656841.388.wav,b
[Nov 25 17:47:38] DEBUG[27462] 
app_queue.c: Queue 'assist' Leave, Channel 
'IAX2/wally-10884'
[Nov 25 17:47:38] VERBOSE[27464] 
logger.c:   == Begin MixMonitor Recording 
IAX2/wally-10884
[Nov 25 17:47:38] DEBUG[27462] 
features.c: bridge answer set, chan answer set
[Nov 25 17:47:38] DEBUG[27464] 
audiohook.c: Failed to get 160 samples from write factory 
0x7f5aa4095fa0
[Nov 25 17:47:38] DEBUG[27464] 
audiohook.c: Failed to get 160 samples from write factory 
0x7f5aa4095fa0
[Nov 25 17:47:39] DEBUG[27464] 
audiohook.c: Failed to get 160 samples from write factory 
0x7f5aa4095fa0
[Nov 25 17:47:39] DEBUG[25239] 
chan_iax2.c: Ooh, voice format changed to 1024
[Nov 25 17:47:39] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
 
 
 
 
....... audiohook.c 
messages
.......
.......
 
 
 
 
 
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Failed to get 160 samples from write factory 
0x7f5aa4095fa0
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:04] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Read factory 0x7f5aa4095570 was pretty quick last time, waiting for 
them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Write factory 0x7f5aa4095fa0 was pretty quick last time, waiting 
for them.
[Nov 25 17:49:05] DEBUG[27462] 
audiohook.c: Flushing audiohook 0x7f5aa4095500 
so it remains in sync
[Nov 25 17:49:05] DEBUG[27464] 
audiohook.c: Failed to get 160 samples from write factory 
0x7f5aa4095fa0
[Nov 25 17:49:05] DEBUG[25243] 
chan_iax2.c: Immediately destroying 10884, having received 
hangup
[Nov 25 17:49:05] DEBUG[27462] 
channel.c: Didn't get a frame from channel: 
IAX2/wally-10884
[Nov 25 17:49:05] DEBUG[27462] 
channel.c: Bridge stops bridging channels IAX2/wally-10884 and 
IAX2/asxop-14202
[Nov 25 17:49:05] DEBUG[27462] 
cdr_addon_mysql.c: Inserting a CDR record.
[Nov 25 17:49:05] DEBUG[27462] 
cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr 
(calldate,clid,CIDnam,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield,bookid) 
VALUES ('2008-11-25 17:47:21','"wally" 
<3000>','','3000','s','assistq','IAX2/wally-10884','IAX2/asxop-14202','Queue','assist','104','104','ANSWERED','3','','','')
[Nov 25 17:49:05] DEBUG[27462] 
channel.c: Hanging up channel 'IAX2/asxop-14202'
[Nov 25 17:49:05] DEBUG[27462] 
chan_iax2.c: We're hanging up IAX2/asxop-14202 
now...


      Start your day with Yahoo!7 and win a Sony Bravia TV. Enter now http://au.docs.yahoo.com/homepageset/?p1=other&p2=au&p3=tagline
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20081125/439e49e9/attachment-0001.htm 


More information about the asterisk-users mailing list