[Home]

Summary:ASTERISK-16690: Meetme unreliable functionality
Reporter:var (var)Labels:
Date Opened:2010-09-17 00:18:58Date Closed:2011-05-11 13:53:54
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Applications/app_meetme
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:System: Debian 5.0.4
libpri: 1.4.11.4
dahdi-linux: 2.4.0
dahdi-tools: 2.4.0
asterisk: 1.6.2.13

Setup: Fragmented E1, TE220 (4th Gen)

Using the below ael context, MeetMe will choose to randomly do one of four functions instead of the expected function of "Don't play any audio files, drop users into MeetMe inconspicuously". This currently removes the ability to use 1.6.2.x MeetMe in a professional deployment, leaving us to currently stay with 1.6.1.x:

* Work as expected (60% of the time)
* Mute one or both users (shouldn't happen)
* Play audio message conf-onlyperson to user (shouldn't happen with the flags given)
* Prompt user for introduction message (shouldn't happen with the flags given)

Note: This setup works without issue on 1.6.1.x, wanting to migrate to 1.6.2.x but this issue has been prevalent since at least 1.6.2.7

From looking at the logs, it "looks like" the problem has to do with realtime conferencing/Database lookup. A successful call has this output:

The requested confno is '2222'?
Parsing /etc/asterisk/meetme.conf
 == Parsing '/etc/asterisk/meetme.conf':   == Found
 2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <=  ? AND endtime >=  ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 15:06:'
Parameter 3 ('endtime >= ') = '2010-09-17 15:06:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-419464804' state '2'
Set channel DAHDI/pseudo-419464804 to read format slin
Set channel DAHDI/pseudo-419464804 to write format slin

An unsuccessful call has this output:

The requested confno is '2222'?
 == Parsing /etc/asterisk/meetme.conf
Parsing '/etc/asterisk/meetme.conf':   == Found
2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <=  ? AND endtime >=  ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 15:05:'
Parameter 3 ('endtime >= ') = '2010-09-17 15:05:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-178166286' state '2'
Set channel DAHDI/pseudo-178166286 to read format slin
Set channel DAHDI/pseudo-178166286 to write format slin
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Created MeetMe conference 1023 for conference '2222'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
device 'meetme:2222' state '2'

****** ADDITIONAL INFORMATION ******

   MYEXTEN => {
       Set(_MAINCHAN=${CHANNEL});
       Dial(DAHDI/g1/0432530529,,G(${CONTEXT}^conference^begin));
   };

   conference => {
   begin:
       NoOP(MAIN CHAN: ${MAINCHAN});
       NoOP(CURRENT CHAN: ${CHANNEL});

       if ("${CHANNEL}" == "${MAINCHAN}") {
               MeetMe(2222,C1IqL(480000:180000));
       }
       else {
               Wait(2);
               MeetMe(2222,C1Iq);
       }
   };

=============== START LOG FOR conf-onlyperson Playback ==================

Launching 'Set'
   -- Executing [94144702@default:1] Set("DAHDI/7-1", "_MAINCHAN=DAHDI/7-1") in new stack
Launching 'Dial'
   -- Executing [94144702@default:2] Dial("DAHDI/7-1", "DAHDI/g1/0432530529,,G(default^conference^begin)") in new stack
Using channel 1
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
device 'DAHDI/1-1' state '2'
Channel 'DAHDI/1-1' has no RTP, not doing anything
Not copying variable DIALEDTIME.
Not copying variable ANSWEREDTIME.
Not copying variable DIALEDPEERNAME.
Not copying variable DIALEDPEERNUMBER.
Not copying variable DIALSTATUS.
Copying soft-transferable variable MAINCHAN.
Not copying variable CALLEDTON.
Not copying variable PRIREDIRECTREASON.
Not copying variable ANI2.
Not copying variable TRANSFERCAPABILITY.
   -- Requested transfer capability: 0x00 - SPEECH
   -- Called g1/0432530529
No provider found, checking channel drivers for DAHDI - 1
Dropping frame since I'm still dialing on DAHDI/1-1...
Avoiding initial deadlock for channel '0x8647ff0'
Dropping frame since I'm still dialing on DAHDI/1-1...
Avoiding initial deadlock for channel '0x8647ff0'
Dropping frame since I'm still dialing on DAHDI/1-1...
Changing state for DAHDI/1 - state 2 (In use)
device 'DAHDI/1' state '2'
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
Requested indication 15 on channel DAHDI/7-1
Received AST_CONTROL_PROCEEDING on DAHDI/7-1
   -- DAHDI/1-1 is proceeding passing it to DAHDI/7-1
Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1
Requested indication 14 on channel DAHDI/7-1
Received AST_CONTROL_PROGRESS on DAHDI/7-1
   -- DAHDI/1-1 is making progress passing it to DAHDI/7-1
Requested indication 14 on channel DAHDI/7-1
Received AST_CONTROL_PROGRESS on DAHDI/7-1
   -- DAHDI/1-1 is making progress passing it to DAHDI/7-1
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0x8647ff0'
Avoiding initial deadlock for channel '0x8647ff0'
Changing state for DAHDI/1 - state 6 (Ringing)
device 'DAHDI/1' state '6'
Requested indication 3 on channel DAHDI/7-1
   -- DAHDI/1-1 is ringing
No provider found, checking channel drivers for DAHDI - 7
Avoiding initial deadlock for channel '0x8648540'
Changing state for DAHDI/7 - state 6 (Ringing)
device 'DAHDI/7' state '6'
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0x8647ff0'
Avoiding initial deadlock for channel '0x8647ff0'
Changing state for DAHDI/1 - state 2 (In use)
device 'DAHDI/1' state '2'
   -- DAHDI/1-1 answered DAHDI/7-1
Launching 'NoOp'
Launching 'NoOp'
Launching 'NoOp'
   -- Executing [conference@default:1] NoOp("DAHDI/7-1", "MAIN CHAN: DAHDI/7-1") in new stack
Expression result is '0'
   -- Executing [conference@default:2] NoOp("DAHDI/1-1", "CURRENT CHAN: DAHDI/1-1") in new stack
Expression result is '1'
Launching 'GotoIf'
   -- Executing [conference@default:2] NoOp("DAHDI/7-1", "CURRENT CHAN: DAHDI/7-1") in new stack
   -- Executing [conference@default:3] GotoIf("DAHDI/1-1", "0?4:6") in new stack
Launching 'GotoIf'
Launching 'Wait'
   -- Goto (default,conference,6)
   -- Executing [conference@default:3] GotoIf("DAHDI/7-1", "1?4:6") in new stack
   -- Executing [conference@default:6] Wait("DAHDI/1-1", "2") in new stack
Launching 'MeetMe'
   -- Goto (default,conference,4)
   -- Executing [conference@default:4] MeetMe("DAHDI/7-1", "2222,C1IqL(480000:180000)") in new stack
No provider found, checking channel drivers for DAHDI - 7
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Requested indication -1 on channel DAHDI/7-1
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Avoiding initial deadlock for channel '0x8648540'
Changing state for DAHDI/7 - state 2 (In use)
device 'DAHDI/7' state '2'
The requested confno is '2222'?
Parsing /etc/asterisk/meetme.conf
 == Parsing '/etc/asterisk/meetme.conf':   == Found
2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <=  ? AND endtime >=  ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 14:47:'
Parameter 3 ('endtime >= ') = '2010-09-17 14:47:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-1395017849' state '2'
Set channel DAHDI/pseudo-1395017849 to read format slin
Set channel DAHDI/pseudo-1395017849 to write format slin
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Created MeetMe conference 1023 for conference '2222'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
device 'meetme:2222' state '2'
Scheduling timer at (50 requested / 50 actual) timer ticks per second
   -- <DAHDI/7-1> Playing 'conf-onlyperson.alaw' (language 'en')
Launching 'MeetMe'
The requested confno is '2222'?
Does conf 2222 match 2222?
   -- Executing [conference@default:7] MeetMe("DAHDI/1-1", "2222,C1Iq") in new stack
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '2'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Requested indication -1 on channel DAHDI/1-1
Set channel DAHDI/1-1 to write format slin
Set channel DAHDI/1-1 to read format slin
Placed channel DAHDI/1-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
Scheduling timer at (107 requested / 107 actual) timer ticks per second
Scheduling timer at (0 requested / 0 actual) timer ticks per second
Scheduling timer at (0 requested / 0 actual) timer ticks per second
Scheduling timer at (0 requested / 0 actual) timer ticks per second
Requested indication -1 on channel DAHDI/7-1
Set channel DAHDI/7-1 to write format slin
Set channel DAHDI/7-1 to read format slin
Placed channel DAHDI/7-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21

============ START LOG FOR MUTED USERS (BOTH) ================
Launching 'Set'
Launching 'Dial'
   -- Executing [94144702@default:1] Set("DAHDI/2-1", "_MAINCHAN=DAHDI/2-1") in new stack
Using channel 1
   -- Executing [94144702@default:2] Dial("DAHDI/2-1", "DAHDI/g1/0432530529,,G(default^conference^begin)") in new stack
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
device 'DAHDI/1-1' state '2'
Channel 'DAHDI/1-1' has no RTP, not doing anything
Not copying variable DIALEDTIME.
Not copying variable ANSWEREDTIME.
Not copying variable DIALEDPEERNAME.
Not copying variable DIALEDPEERNUMBER.
Not copying variable DIALSTATUS.
Copying soft-transferable variable MAINCHAN.
Not copying variable CALLEDTON.
Not copying variable PRIREDIRECTREASON.
Not copying variable ANI2.
Not copying variable TRANSFERCAPABILITY.
   -- Requested transfer capability: 0x00 - SPEECH
No provider found, checking channel drivers for DAHDI - 1
   -- Called g1/0432530529
Changing state for DAHDI/1 - state 2 (In use)
device 'DAHDI/1' state '2'
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
Requested indication 15 on channel DAHDI/2-1
Received AST_CONTROL_PROCEEDING on DAHDI/2-1
   -- DAHDI/1-1 is proceeding passing it to DAHDI/2-1
Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1
Requested indication 14 on channel DAHDI/2-1
Received AST_CONTROL_PROGRESS on DAHDI/2-1
   -- DAHDI/1-1 is making progress passing it to DAHDI/2-1
Requested indication 14 on channel DAHDI/2-1
   -- Received AST_CONTROL_PROGRESS on DAHDI/2-1
DAHDI/1-1 is making progress passing it to DAHDI/2-1
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0x864ccf8'
Avoiding initial deadlock for channel '0x864ccf8'
Changing state for DAHDI/1 - state 6 (Ringing)
device 'DAHDI/1' state '6'
Requested indication 3 on channel DAHDI/2-1
   -- DAHDI/1-1 is ringing
No provider found, checking channel drivers for DAHDI - 2
Avoiding initial deadlock for channel '0x864a468'
Changing state for DAHDI/2 - state 6 (Ringing)
device 'DAHDI/2' state '6'
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0x864ccf8'
Avoiding initial deadlock for channel '0x864ccf8'
Changing state for DAHDI/1 - state 2 (In use)
device 'DAHDI/1' state '2'
   -- DAHDI/1-1 answered DAHDI/2-1
Launching 'NoOp'
Launching 'NoOp'
Launching 'NoOp'
   -- Executing [conference@default:1] NoOp("DAHDI/2-1", "MAIN CHAN: DAHDI/2-1") in new stack
Expression result is '0'
   -- Executing [conference@default:2] NoOp("DAHDI/1-1", "CURRENT CHAN: DAHDI/1-1") in new stack
Launching 'GotoIf'
Expression result is '1'
Launching 'GotoIf'
Launching 'Wait'
   -- Executing [conference@default:2] NoOp("DAHDI/2-1", "CURRENT CHAN: DAHDI/2-1") in new stack
   -- Executing [conference@default:3] GotoIf("DAHDI/1-1", "0?4:6") in new stack
   -- Goto (default,conference,6)
Launching 'MeetMe'
   -- Executing [conference@default:3] GotoIf("DAHDI/2-1", "1?4:6") in new stack
   -- Executing [conference@default:6] Wait("DAHDI/1-1", "2") in new stack
   -- Goto (default,conference,4)
   -- Executing [conference@default:4] MeetMe("DAHDI/2-1", "2222,C1IqL(480000:180000)") in new stack
No provider found, checking channel drivers for DAHDI - 2
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Requested indication -1 on channel DAHDI/2-1
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Changing state for DAHDI/2 - state 2 (In use)
device 'DAHDI/2' state '2'
The requested confno is '2222'?
Parsing /etc/asterisk/meetme.conf
 == Parsing '/etc/asterisk/meetme.conf':   == Found
2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <=  ? AND endtime >=  ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 14:56:'
Parameter 3 ('endtime >= ') = '2010-09-17 14:56:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-1256468569' state '2'
Set channel DAHDI/pseudo-1256468569 to read format slin
Set channel DAHDI/pseudo-1256468569 to write format slin
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Created MeetMe conference 1023 for conference '2222'
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
device 'meetme:2222' state '2'
Requested indication -1 on channel DAHDI/2-1
Set channel DAHDI/2-1 to write format slin
Set channel DAHDI/2-1 to read format slin
Placed channel DAHDI/2-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
Launching 'MeetMe'
The requested confno is '2222'?
Does conf 2222 match 2222?
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Executing [conference@default:7] MeetMe("DAHDI/1-1", "2222,C1Iq") in new stack
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '2'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Requested indication -1 on channel DAHDI/1-1
Set channel DAHDI/1-1 to write format slin
Set channel DAHDI/1-1 to read format slin
Placed channel DAHDI/1-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <= ? AND endtime >= ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <=') = '2010-09-17 14:57:00'
Parameter 3 ('endtime >=') = '2010-09-17 14:57:00'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <= ? AND endtime >= ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <=') = '2010-09-17 14:57:00'
Parameter 3 ('endtime >=') = '2010-09-17 14:57:00'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Channel 0/2, span 1 got hangup request, cause 16
Setting actual tx gain on DAHDI/2-1 to 0.000000
Setting actual rx gain on DAHDI/2-1 to 0.000000
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Spawn extension (default,conference,4) exited non-zero on 'DAHDI/2-1'

=================== START SUCCESSFUL MEETME CALL ========================
Launching 'Set'
Launching 'Dial'
   -- Executing [94144702@default:1] Set("DAHDI/6-1", "_MAINCHAN=DAHDI/6-1") in new stack
   -- Executing [94144702@default:2] Dial("DAHDI/6-1", "DAHDI/g1/0432530529,,G(default^conference^begin)") in new stack
Using channel 1
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
device 'DAHDI/1-1' state '2'
Channel 'DAHDI/1-1' has no RTP, not doing anything
Not copying variable DIALEDTIME.
Not copying variable ANSWEREDTIME.
Not copying variable DIALEDPEERNAME.
Not copying variable DIALEDPEERNUMBER.
Not copying variable DIALSTATUS.
Copying soft-transferable variable MAINCHAN.
Not copying variable CALLEDTON.
Not copying variable PRIREDIRECTREASON.
Not copying variable ANI2.
Not copying variable TRANSFERCAPABILITY.
No provider found, checking channel drivers for DAHDI - 1
Changing state for DAHDI/1 - state 2 (In use)
Dropping frame since I'm still dialing on DAHDI/1-1...
device 'DAHDI/1' state '2'
   -- Requested transfer capability: 0x00 - SPEECH
Dropping frame since I'm still dialing on DAHDI/1-1...
   -- Called g1/0432530529
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Dropping frame since I'm still dialing on DAHDI/1-1...
Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
Requested indication 15 on channel DAHDI/6-1
Received AST_CONTROL_PROCEEDING on DAHDI/6-1
   -- DAHDI/1-1 is proceeding passing it to DAHDI/6-1
Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1
Requested indication 14 on channel DAHDI/6-1
Received AST_CONTROL_PROGRESS on DAHDI/6-1
   -- DAHDI/1-1 is making progress passing it to DAHDI/6-1
Requested indication 14 on channel DAHDI/6-1
   -- DAHDI/1-1 is making progress passing it to DAHDI/6-1
Received AST_CONTROL_PROGRESS on DAHDI/6-1
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0xb5f01da0'
Avoiding initial deadlock for channel '0xb5f01da0'
Changing state for DAHDI/1 - state 6 (Ringing)
device 'DAHDI/1' state '6'
Requested indication 3 on channel DAHDI/6-1
   -- DAHDI/1-1 is ringing
No provider found, checking channel drivers for DAHDI - 6
Avoiding initial deadlock for channel '0x864a468'
Changing state for DAHDI/6 - state 6 (Ringing)
device 'DAHDI/6' state '6'
No echo cancellation requested
No provider found, checking channel drivers for DAHDI - 1
Avoiding initial deadlock for channel '0xb5f01da0'
Avoiding initial deadlock for channel '0xb5f01da0'
Changing state for DAHDI/1 - state 2 (In use)
device 'DAHDI/1' state '2'
   -- DAHDI/1-1 answered DAHDI/6-1
Launching 'NoOp'
Launching 'NoOp'
Launching 'NoOp'
   -- Executing [conference@default:1] NoOp("DAHDI/6-1", "MAIN CHAN: DAHDI/6-1") in new stack
   -- Executing [conference@default:2] NoOp("DAHDI/1-1", "CURRENT CHAN: DAHDI/1-1") in new stack
Expression result is '0'
Launching 'GotoIf'
   -- Executing [conference@default:2] NoOp("DAHDI/6-1", "CURRENT CHAN: DAHDI/6-1") in new stack
   -- Executing [conference@default:3] GotoIf("DAHDI/1-1", "0?4:6") in new stack
Expression result is '1'
Launching 'GotoIf'
   -- Goto (default,conference,6)
Launching 'Wait'
   -- Executing [conference@default:3] GotoIf("DAHDI/6-1", "1?4:6") in new stack
   -- Goto (default,conference,4)
Launching 'MeetMe'
   -- Executing [conference@default:6] Wait("DAHDI/1-1", "2") in new stack
   -- Executing [conference@default:4] MeetMe("DAHDI/6-1", "2222,C1IqL(480000:180000)") in new stack
No provider found, checking channel drivers for DAHDI - 6
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Requested indication -1 on channel DAHDI/6-1
Avoiding initial deadlock for channel '0x864a468'
Avoiding initial deadlock for channel '0x864a468'
Changing state for DAHDI/6 - state 2 (In use)
device 'DAHDI/6' state '2'
The requested confno is '2222'?
Parsing /etc/asterisk/meetme.conf
 == Parsing '/etc/asterisk/meetme.conf':   == Found
2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <=  ? AND endtime >=  ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 15:01:'
Parameter 3 ('endtime >= ') = '2010-09-17 15:01:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-964970250' state '2'
Set channel DAHDI/pseudo-964970250 to read format slin
Set channel DAHDI/pseudo-964970250 to write format slin
Limit Data for this call:
- timelimit     = 480000
- play_warning  = 180000
   -- Created MeetMe conference 1023 for conference '2222'
- warning_freq  = 0
- warning_sound = /var/lib/asterisk/sounds/en/beep
- end_sound     = UNDEF
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
device 'meetme:2222' state '2'
Requested indication -1 on channel DAHDI/6-1
Set channel DAHDI/6-1 to write format slin
Set channel DAHDI/6-1 to read format slin
Placed channel DAHDI/6-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
Launching 'MeetMe'
The requested confno is '2222'?
Does conf 2222 match 2222?
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
   -- Executing [conference@default:7] MeetMe("DAHDI/1-1", "2222,C1Iq") in new stack
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '2'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Requested indication -1 on channel DAHDI/1-1
Set channel DAHDI/1-1 to write format slin
Set channel DAHDI/1-1 to read format slin
Placed channel DAHDI/1-1 in DAHDI conf 1023
Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
   -- Channel 0/1, span 1 got hangup request, cause 16
Setting actual tx gain on DAHDI/1-1 to 0.000000
Setting actual rx gain on DAHDI/1-1 to 0.000000
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Spawn extension (default,conference,7) exited non-zero on 'DAHDI/1-1'
Comments:By: var (var) 2010-09-17 00:29:14

Can't edit the description and there's an error in it, the successful log in the description is incorrect. It should read:

== Parsing '/etc/asterisk/meetme.conf': == Found
2222 isn't a valid conference
Looking for conference 2222 that starts after
Skip: 0; SQL: SELECT * FROM scheduled_conferences WHERE confno = ? AND starttime <= ? AND endtime >= ?
Parameter 1 ('confno') = '2222'
Parameter 2 ('starttime <= ') = '2010-09-17 15:01:'
Parameter 3 ('endtime >= ') = '2010-09-17 15:01:'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Using channel -2
device 'DAHDI/pseudo-964970250' state '2'
Set channel DAHDI/pseudo-964970250 to read format slin
Set channel DAHDI/pseudo-964970250 to write format slin
Limit Data for this call:
- timelimit = 480000
- play_warning = 180000
   -- Created MeetMe conference 1023 for conference '2222'
- warning_freq = 0
- warning_sound = /var/lib/asterisk/sounds/en/beep
- end_sound = UNDEF
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
Skip: 0; SQL: UPDATE scheduled_conferences SET members=? WHERE confno=?
Parameter 1 ('members') = '1'
odbc_release_obj2(0x837dee0) called (obj->txf = (nil))
device 'meetme:2222' state '2'
By: Paul Belanger (pabelanger) 2010-09-21 17:31:19

To help keep the issue clean, if you could upload a debug log, it would be helpful.

--
We require a complete debug log to help triage the issue.

This document will provide instructions on how to collect debugging logs from an Asterisk machine for the purpose of helping bug marshals troubleshoot an issue:

http://svn.digium.com/svn/asterisk/trunk/doc/HOWTO_collect_debug_information.txt
By: Andrew Thomas (raffles) 2010-09-23 09:32:37

Same 'sort of' issues here.  Realtime MeetMe is very sporadic.  Sometimes it works (tells me I'm the only one in), other times my CLI reports that it is trying to load the 'background' AGI (I have no options set on the MeetMe), most times I simply get no audio at all and there have been occasions when I am asked to record my name (which I do) and then I get no audio (after I press 1).

Static MeetMe works as it should!

* - 1.6.2.13
DAHDI - 2.4.0

Let me know if you need any further info.

EDIT : Something's not quite right (the wierd cahrs are sent to my CLI):

 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-0000000e", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
[Sep 23 15:36:27] WARNING[2414]: app.c:1843 parse_options: Missing closing parenthesis for argument '7' in string '`Á   X%¶f©p·`!·(`Á    `Á     ô·`!· -À    x%¶f©p·`!· -À    -À      8ñå¶|Ðå¶'
[Sep 23 15:36:27] WARNING[2414]: app.c:1843 parse_options: Missing closing parenthesis for argument '7' in string '`Á   X%¶f©p·`!·(`Á    `Á     ô·`!· -À    x%¶f©p·`!· -À    -À      8ñå¶|Ðå¶'
   -- <SIP/201-0000000e> Playing 'conf-onlyperson.gsm' (language 'en')
   -- Hungup 'DAHDI/pseudo-629967612'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-0000000e'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-0000000f", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
   -- Hungup 'DAHDI/pseudo-1836078866'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-0000000f'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000010", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
[Sep 23 15:36:46] WARNING[2416]: app.c:1843 parse_options: Missing closing parenthesis for argument '7' in string '`Á   X%¶f©p·`!·(`Á    `Á     ô·`!· UÁ    x%¶f©p·`!· UÁ    UÁ      8ñå¶|Ðå¶'
[Sep 23 15:36:46] WARNING[2416]: app.c:1843 parse_options: Missing closing parenthesis for argument '7' in string '`Á   X%¶f©p·`!·(`Á    `Á     ô·`!· UÁ    x%¶f©p·`!· UÁ    UÁ      8ñå¶|Ðå¶'
   -- <SIP/201-00000010> Playing 'conf-onlyperson.gsm' (language 'en')
   -- Hungup 'DAHDI/pseudo-718385817'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000010'


By: Andrew Thomas (raffles) 2010-09-23 10:40:06

Just to add:

 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000000", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
   -- <SIP/201-00000000> Playing 'conf-onlyperson.gsm' (language 'en')
   -- Hungup 'DAHDI/pseudo-1892601016'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000000'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000001", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
[Sep 23 16:37:19] WARNING[4405]: file.c:1160 ast_writefile: No such format ''
   -- Hungup 'DAHDI/pseudo-215024260'
   -- Hungup 'DAHDI/pseudo-1863354008'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000001'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000002", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
[Sep 23 16:37:23] WARNING[4407]: file.c:1160 ast_writefile: No such format ''
   -- Hungup 'DAHDI/pseudo-838707320'
   -- Hungup 'DAHDI/pseudo-1341195226'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000002'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000003", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
   -- <SIP/201-00000003> Playing 'conf-onlyperson.gsm' (language 'en')
   -- Hungup 'DAHDI/pseudo-1410158611'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000003'
 == Using SIP RTP TOS bits 184
 == Using SIP RTP CoS mark 5
   -- Executing MeetMe("SIP/201-00000004", "600")
 == Parsing '/etc/asterisk/meetme.conf':   == Found
   -- Created MeetMe conference 1023 for conference '600'
   -- Hungup 'DAHDI/pseudo-1088148724'
 == Spawn extension (dv-ip, *60600, 1) exited non-zero on 'SIP/201-00000004'
DV-IP*CLI>




This was actioned using the redial button on the phone!  As you can see - MeetMe now has a mind of it's own :)
By: Dmitry Andrianov (dimas) 2010-10-22 10:02:50

Check this out - https://issues.asterisk.org/view.php?id=18182 It may be reason for all these strange behaviors.
By: Jason Parker (jparker) 2011-02-04 13:14:41.000-0600

After a bit of code reading, I'm going to go ahead and say that this is the same as ASTERISK-16845, and close this out.  If you're still seeing this behavior after applying the patch from there, feel free to reopen this so we can look at it further.