| Summary: | ASTERISK-00875: 3way calling using flash fails with Swissvoice ip10 | ||
| Reporter: | philipp2 (philipp2) | Labels: | |
| Date Opened: | 2004-01-19 07:08:13.000-0600 | Date Closed: | 2011-06-07 14:10:31 |
| Priority: | Minor | Regression? | No |
| Status: | Closed/Complete | Components: | Core/General |
| Versions: | Frequency of Occurrence | ||
| Related Issues: | |||
| Environment: | Attachments: | ||
| Description: | === === === Testing three-way-calling with MGCP debug: === === === 1. 8755 (SIP from Internet, Grandstream 101, on * A) calls 302 (MGCP, Swissvoice ip10, on * B) via IAX - all is fine 2. 302 (.18) now presses FLASH and dials 303 (MGCP) 3. 303 (.17; Swissvoice ip10) cannot hear 302, but 302 can hear 303; 8755 hears music-on-hold 4. 302 pesses FLASH again to get back to 8755 who hears silence, while 303 hears music 5. 303 hangs up; 8755 gets connected to 303 and both can talk mgcp.conf: both 302 and 303 have just one line "aaln/1" 302 has callwaiting=yes, threewaycalling=yes, transfer=yes, cancallforward=yes, canreinvite=yes 303 has callwaiting=no, threewaycalling=yes, transfer=yes, cancallforward=yes, canreinvite=no Side note: The "services" options in the ip10 say "no services available", so it appears that the Asterisk MGCP call manager is not as such offering services like "enquiry call, brokering, transfer, conference" (is that only a H323 option?) -- Accepting AUTHENTICATED call from 134.xxx.xxx.xxx, requested format = 2, actual format = 2 -- Executing AbsoluteTimeout("IAX2[serverA@serverAp]/5", "7200") in new stack -- Set Absolute Timeout to 7200 -- Executing NoOp("IAX2[serverA@serverAp]/5", "") in new stack -- Executing Dial("IAX2[serverA@serverAp]/5", "MGCP/aaln/1@192.168.23.18|10|rt") in new stack -- MGCP mgcp_request(aaln/1@192.168.23.18) -- MGCP cw: 0, dnd: 0, so: 0, sno: 0 -- MGCP mgcp_new(MGCP/aaln/1@192.168.23.18-0) created in state: Down -- Called aaln/1@192.168.23.18 -- MGCP/aaln/1@192.168.23.18-0 is ringing -- Endpoint 'aaln/1@192.168.23.18-0' observed 'hd' -- MGCP/aaln/1@192.168.23.18-0 answered IAX2[serverA@serverAp]/5 -- Endpoint 'aaln/1@192.168.23.18-0' observed 'hf' -- Swapping 0 for 1 on aaln/1@192.168.23.18 -- MGCP Muting 0 on aaln/1@192.168.23.18 -- Started music on hold, class 'default', on IAX2[serverA@serverAp]/5 -- MGCP mgcp_new(MGCP/aaln/1@192.168.23.18-1) created in state: Down -- Endpoint 'aaln/1@192.168.23.18-1' observed '3' -- Endpoint 'aaln/1@192.168.23.18-1' observed '0' -- Endpoint 'aaln/1@192.168.23.18-1' observed '3' -- Executing AbsoluteTimeout("MGCP/aaln/1@192.168.23.18-1", "7200") in new stack -- Set Absolute Timeout to 7200 -- Executing NoOp("MGCP/aaln/1@192.168.23.18-1", "") in new stack -- Executing Dial("MGCP/aaln/1@192.168.23.18-1", "MGCP/aaln/1@192.168.23.17|20|rt") in new stack -- MGCP mgcp_request(aaln/1@192.168.23.17) -- MGCP cw: 0, dnd: 0, so: 0, sno: 0 -- MGCP mgcp_new(MGCP/aaln/1@192.168.23.17-1) created in state: Down -- Called aaln/1@192.168.23.17 -- MGCP/aaln/1@192.168.23.17-1 is ringing -- Endpoint 'aaln/1@192.168.23.17-1' observed 'hd' -- MGCP/aaln/1@192.168.23.17-1 answered MGCP/aaln/1@192.168.23.18-1 -- MGCP mgcp_answer(MGCP/aaln/1@192.168.23.18-1) on aaln/1@192.168.23.18-1 -- Attempting native bridge of MGCP/aaln/1@192.168.23.18-1 and MGCP/aaln/1@192.168.23.17-1 serverB*CLI> mgcp debug MGCP Debugging Enabled MGCP read: NTFY 1464 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 20ba0022 O: hf from 192.168.23.18:2427MGCP read: NTFY 1464 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 20ba0022 O: hf from 192.168.23.18:2427Verb: 'NTFY', Identifier: '1464', Endpoint: 'aaln/1@[192.168.23.18]', Version: 'MGCP 1.0' 3 headers, 0 lines Handling request 'NTFY' on aaln/1@192.168.23.18 Transmitting: 200 1464 OK to 192.168.23.18:2427 -- Endpoint 'aaln/1@192.168.23.18-1' observed 'hf' -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-1 in cxmode: sendrecv Posting Request: RQNT 169 aaln/1@192.168.23.18 MGCP 1.0 X: 20ba0022 R: hu(N), hf(N), D/[0-9#*](N) to 192.168.23.18:2427 -- Swapping 1 for 0 on aaln/1@192.168.23.18 -- We didn't make one of the calls FLIPFLOP 1 and 0 on aaln/1@192.168.23.18 -- MGCP Muting 1 on aaln/1@192.168.23.18 -- Modified aaln/1@192.168.23.18-1 with new mode: inactive on callid: 65505fa520ba0022 Posting Request: MDCX 170 aaln/1@192.168.23.18 MGCP 1.0 C: 65505fa520ba0022 M: inactive X: 20ba0022 I: 8 R: L/hu(N),L/hf(N),D/[0-9#*](N) to 192.168.23.18:2427 -- Started music on hold, class 'default', on MGCP/aaln/1@192.168.23.17-1 -- Stopped music on hold on IAX2[serverA@serverAp]/5 -- Modified aaln/1@192.168.23.18-0 with new mode: sendrecv on callid: 01faf05e28f06427 Posting Request: MDCX 171 aaln/1@192.168.23.18 MGCP 1.0 C: 01faf05e28f06427 M: sendrecv X: 28f06427 I: 7 R: L/hu(N),L/hf(N),D/[0-9#*](N) to 192.168.23.18:2427 -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-0 in cxmode: sendrecv Posting Request: RQNT 172 aaln/1@192.168.23.18 MGCP 1.0 X: 28f06427 R: hu(N), hf(N), D/[0-9#*](N) to 192.168.23.18:2427 MGCP read: 200 169 OK from 192.168.23.18:2427MGCP read: 200 169 OK from 192.168.23.18:2427Verb: '200', Identifier: '169', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 171 OK from 192.168.23.18:2427MGCP read: 200 171 OK from 192.168.23.18:2427Verb: '200', Identifier: '171', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 170 OK from 192.168.23.18:2427MGCP read: 200 170 OK from 192.168.23.18:2427Verb: '200', Identifier: '170', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 172 OK from 192.168.23.18:2427MGCP read: 200 172 OK from 192.168.23.18:2427Verb: '200', Identifier: '172', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: NTFY 1465 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 28f06427 O: hf from 192.168.23.18:2427MGCP read: NTFY 1465 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 28f06427 O: hf from 192.168.23.18:2427Verb: 'NTFY', Identifier: '1465', Endpoint: 'aaln/1@[192.168.23.18]', Version: 'MGCP 1.0' 3 headers, 0 lines Handling request 'NTFY' on aaln/1@192.168.23.18 Transmitting: 200 1465 OK to 192.168.23.18:2427 -- Endpoint 'aaln/1@192.168.23.18-0' observed 'hf' -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-0 in cxmode: sendrecv Posting Request: RQNT 173 aaln/1@192.168.23.18 MGCP 1.0 X: 28f06427 R: hu(N), hf(N), D/[0-9#*](N) to 192.168.23.18:2427 -- Swapping 0 for 1 on aaln/1@192.168.23.18 -- We didn't make one of the calls FLIPFLOP 0 and 1 on aaln/1@192.168.23.18 -- MGCP Muting 0 on aaln/1@192.168.23.18 -- Modified aaln/1@192.168.23.18-0 with new mode: inactive on callid: 01faf05e28f06427 Posting Request: MDCX 174 aaln/1@192.168.23.18 MGCP 1.0 C: 01faf05e28f06427 M: inactive X: 28f06427 I: 7 R: L/hu(N),L/hf(N),D/[0-9#*](N) to 192.168.23.18:2427 -- Started music on hold, class 'default', on IAX2[serverA@serverAp]/5 -- Stopped music on hold on MGCP/aaln/1@192.168.23.17-1 Jan 19 13:20:57 WARNING[9226]: chan_mgcp.c:2126 handle_hd_hf: Off hook, but alreaedy have owner on aaln/1@192.168.23.18 -- Modified aaln/1@192.168.23.18-1 with new mode: sendrecv on callid: 65505fa520ba0022 Posting Request: MDCX 175 aaln/1@192.168.23.18 MGCP 1.0 C: 65505fa520ba0022 M: sendrecv X: 20ba0022 I: 8 R: L/hu(N),L/hf(N),D/[0-9#*](N) to 192.168.23.18:2427 -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-1 in cxmode: sendrecv Posting Request: RQNT 176 aaln/1@192.168.23.18 MGCP 1.0 X: 20ba0022 R: hu(N), hf(N), D/[0-9#*](N) to 192.168.23.18:2427 MGCP read: 200 173 OK from 192.168.23.18:2427MGCP read: 200 173 OK from 192.168.23.18:2427Verb: '200', Identifier: '173', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 175 OK from 192.168.23.18:2427MGCP read: 200 175 OK from 192.168.23.18:2427Verb: '200', Identifier: '175', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 174 OK from 192.168.23.18:2427MGCP read: 200 174 OK from 192.168.23.18:2427Verb: '200', Identifier: '174', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 176 OK from 192.168.23.18:2427MGCP read: 200 176 OK from 192.168.23.18:2427Verb: '200', Identifier: '176', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: NTFY 1466 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 20ba0022 O: hu from 192.168.23.18:2427MGCP read: NTFY 1466 aaln/1@[192.168.23.18] MGCP 1.0 NCS 1.0 X: 20ba0022 O: hu from 192.168.23.18:2427Verb: 'NTFY', Identifier: '1466', Endpoint: 'aaln/1@[192.168.23.18]', Version: 'MGCP 1.0' 3 headers, 0 lines Handling request 'NTFY' on aaln/1@192.168.23.18 Transmitting: 200 1466 OK to 192.168.23.18:2427 -- Endpoint 'aaln/1@192.168.23.18-1' observed 'hu' -- Modified aaln/1@192.168.23.18-1 with new mode: recvonly on callid: 65505fa520ba0022 Posting Request: MDCX 177 aaln/1@192.168.23.18 MGCP 1.0 C: 65505fa520ba0022 M: recvonly X: 20ba0022 I: 8 R: L/hd(N) to 192.168.23.18:2427 -- Stopped music on hold on IAX2[serverA@serverAp]/5 -- Delete connection 7 aaln/1@192.168.23.18-0 with new mode: inactive on callid: 01faf05e28f06427 Posting Request: DLCX 178 aaln/1@192.168.23.18 MGCP 1.0 C: 01faf05e28f06427 X: 28f06427 I: 7 to 192.168.23.18:2427 Jan 19 13:21:07 NOTICE[26642]: chan_mgcp.c:775 mgcp_fixup: mgcp_fixup(MGCP/aaln/1@192.168.23.17-1, MGCP/aaln/1@192.168.23.17-1<MASQ>) Jan 19 13:21:07 WARNING[26642]: chan_mgcp.c:777 mgcp_fixup: old channel wasn't 0x8115a08 but was (nil) Jan 19 13:21:07 WARNING[26642]: channel.c:2013 ast_do_masquerade: Fixup failed on channel MGCP/aaln/1@192.168.23.17-1<MASQ>, strange things may happen. -- MGCP mgcp_hangup(MGCP/aaln/1@192.168.23.17-1<MASQ>) on aaln/1@192.168.23.18 -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-0 in cxmode: inactive Posting Request: RQNT 179 aaln/1@192.168.23.18 MGCP 1.0 X: 28f06427 R: hd(N) to 192.168.23.18:2427 == Spawn extension (from-ho, 303, 3) exited non-zero on 'MGCP/aaln/1@192.168.23.18-1' -- MGCP mgcp_hangup(MGCP/aaln/1@192.168.23.18-1) on aaln/1@192.168.23.18 -- Delete connection 8 aaln/1@192.168.23.18-1 with new mode: recvonly on callid: 65505fa520ba0022 Posting Request: DLCX 180 aaln/1@192.168.23.18 MGCP 1.0 C: 65505fa520ba0022 X: 20ba0022 I: 8 to 192.168.23.18:2427 -- MGCP Asked to indicate tone: on aaln/1@192.168.23.18-1 in cxmode: recvonly Posting Request: RQNT 181 aaln/1@192.168.23.18 MGCP 1.0 X: 20ba0022 R: hd(N) to 192.168.23.18:2427 -- MGCP mgcp_hangup(MGCP/aaln/1@192.168.23.18-1) on aaln/1@192.168.23.18 set vmwi(+) -- MGCP Asked to indicate tone: vmwi(+) on aaln/1@192.168.23.18-1 in cxmode: inactive Posting Request: RQNT 182 aaln/1@192.168.23.18 MGCP 1.0 X: 20ba0022 R: hd(N) S: vmwi(+) to 192.168.23.18:2427 Jan 19 13:21:07 NOTICE[26642]: chan_mgcp.c:775 mgcp_fixup: mgcp_fixup(ØâBØâBn/1@192.168.23.18-0<ZOMBIE>, MGCP/aaln/1@192.168.23.17-1) MGCP read: 200 177 OK from 192.168.23.18:2427MGCP read: 200 177 OK from 192.168.23.18:2427Verb: '200', Identifier: '177', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 250 178 OK P: PS=8591,OS=1477652,PR=11316,OR=1946352,PL=855,JI=81466,LA=0 from 192.168.23.18:2427MGCP read: 250 178 OK P: PS=8591,OS=1477652,PR=11316,OR=1946352,PL=855,JI=81466,LA=0 from 192.168.23.18:2427Verb: '250', Identifier: '178', Endpoint: 'OK', Version: '(null)' 2 headers, 0 lines MGCP read: 250 180 OK P: PS=0,OS=0,PR=2629,OR=452188,PL=855,JI=81466,LA=0 from 192.168.23.18:2427MGCP read: 250 180 OK P: PS=0,OS=0,PR=2629,OR=452188,PL=855,JI=81466,LA=0 from 192.168.23.18:2427Verb: '250', Identifier: '180', Endpoint: 'OK', Version: '(null)' 2 headers, 0 lines MGCP read: 200 179 OK from 192.168.23.18:2427MGCP read: 200 179 OK from 192.168.23.18:2427Verb: '200', Identifier: '179', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 181 OK from 192.168.23.18:2427MGCP read: 200 181 OK from 192.168.23.18:2427Verb: '200', Identifier: '181', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: 200 182 OK from 192.168.23.18:2427MGCP read: 200 182 OK from 192.168.23.18:2427Verb: '200', Identifier: '182', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines -- MGCP mgcp_hangup(MGCP/aaln/1@192.168.23.17-1) on aaln/1@192.168.23.17 -- Delete connection 97 aaln/1@192.168.23.17-1 with new mode: sendrecv on callid: 0dcc8c234a35384e Posting Request: DLCX 183 aaln/1@192.168.23.17 MGCP 1.0 C: 0dcc8c234a35384e X: 4a35384e I: 97 to 192.168.23.17:2427 -- MGCP Asked to indicate tone: ro on aaln/1@192.168.23.17-1 in cxmode: sendrecv Posting Request: RQNT 184 aaln/1@192.168.23.17 MGCP 1.0 X: 4a35384e R: hu(N), hf(N), D/[0-9#*](N) S: ro to 192.168.23.17:2427 == Spawn extension (default, 302, 3) exited non-zero on 'IAX2[serverA@serverAp]/5' -- Hungup 'IAX2[serverA@serverAp]/5' MGCP read: 250 183 OK P: PS=5373,OS=924156,PR=3332,OR=573104,PL=0,JI=24,LA=0 from 192.168.23.17:2427MGCP read: 250 183 OK P: PS=5373,OS=924156,PR=3332,OR=573104,PL=0,JI=24,LA=0 from 192.168.23.17:2427Verb: '250', Identifier: '183', Endpoint: 'OK', Version: '(null)' 2 headers, 0 lines MGCP read: 200 184 OK from 192.168.23.17:2427MGCP read: 200 184 OK from 192.168.23.17:2427Verb: '200', Identifier: '184', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines MGCP read: NTFY 2978 aaln/1@[192.168.23.17] MGCP 1.0 NCS 1.0 X: 4a35384e O: hu from 192.168.23.17:2427MGCP read: NTFY 2978 aaln/1@[192.168.23.17] MGCP 1.0 NCS 1.0 X: 4a35384e O: hu from 192.168.23.17:2427Verb: 'NTFY', Identifier: '2978', Endpoint: 'aaln/1@[192.168.23.17]', Version: 'MGCP 1.0' 3 headers, 0 lines Handling request 'NTFY' on aaln/1@192.168.23.17 Transmitting: 200 2978 OK to 192.168.23.17:2427 -- Endpoint 'aaln/1@192.168.23.17-1' observed 'hu' -- MGCP handle_request(aaln/1@192.168.23.17-1) ast_channel already destroyed -- MGCP handle_request(aaln/1@192.168.23.17) set vmwi(-) -- MGCP Asked to indicate tone: vmwi(-) on aaln/1@192.168.23.17-1 in cxmode: recvonly Posting Request: RQNT 185 aaln/1@192.168.23.17 MGCP 1.0 X: 4a35384e R: hd(N) S: vmwi(-) to 192.168.23.17:2427 MGCP read: 200 185 OK from 192.168.23.17:2427MGCP read: 200 185 OK from 192.168.23.17:2427Verb: '200', Identifier: '185', Endpoint: 'OK', Version: '(null)' 1 headers, 0 lines serverB*CLI> mgcp no debug | ||
| Comments: | By: Pavel Litvinenko (pavell) 2004-01-19 08:10:06.000-0600 did you try add callgroup=0 to your endpoint's config ? By: philipp2 (philipp2) 2004-01-19 08:55:53.000-0600 both 302 and 303 have callgroup=0,2 pickupgroup=0,1 but I don't really see why this should matter in this case - isn't that only of relevance for call pick-up? By: florian (florian) 2004-03-10 09:27:28.000-0600 You can program the IP10 to have services (transfer = hookflash) using the TFTP'ed configuration file. After that things will work, except for the garbled voice issue I've entered at bug id 0001155 (http://bugs.digium.com/bug_view_page.php?bug_id=0001155) By: Mark Spencer (markster) 2004-04-02 16:04:01.000-0600 Please try latest CVS head, too. There were lots of MGCP fixes there. By: philipp2 (philipp2) 2004-04-05 14:40:04 I'd love to move to latest CVS and try, but then bug 1284 will bite me, and I didn't yet manage to get hold of chan_capi 0.3.2 ... :-( By: philipp2 (philipp2) 2004-04-05 17:36:45 Bad bad... - tonight I first upgraded the Swissvoice ip10 firmware (see below), and that worked reasonable fine with Asterisk (head) 03/05/04 00:50:56 - then I also updated CVS (see below) and things went South from here: The phones apparently (?) cannot sign in with the call manager anymore "Waiting for call manager..." is all the display reveals - and no calling possible at all, of course. Asterisk CVS-04/05/04-21:08:22 built by root@myast on a i686 running Linux Appli version IP10 M v1.0.0 (Build3) Boot version IP10 Boot v0.3.6 DSP version Rel 9.1.0.4, Build p8 GG version R9.0.0 IPP (Build 5) Protocol MGCP 1.0 myast*CLI> mgcp audit No such command 'mgcp audit' (type 'help' for help) myast*CLI> set verbose 10 -- Accepting AUTHENTICATED call from yyy.yyy.yyy.yyy, requested format = 4, actual format = 2 -- Executing AbsoluteTimeout("IAX2[remote_ast@remote_astp]/3", "7200") in new stack -- Set Absolute Timeout to 7200 -- Executing NoOp("IAX2[remote_ast@remote_astp]/3", "") in new stack -- Executing Dial("IAX2[remote_ast@remote_astp]/3", "MGCP/aaln/1@xxx.xxx.xxx.xxx|20|rt") in new stack -- MGCP mgcp_request(aaln/1@xxx.xxx.xxx.xxx) -- MGCP cw: 0, dnd: 0, so: 0, sno: 0 -- MGCP mgcp_new(MGCP/aaln/1@xxx.xxx.xxx.xxx-1) created in state: Down -- Called aaln/1@xxx.xxx.xxx.xxx -- MGCP/aaln/1@xxx.xxx.xxx.xxx-1 is ringing Apr 5 23:03:47 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 17 on [xxx.xxx.xxx.xxx] Apr 5 23:03:47 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 18 on [xxx.xxx.xxx.xxx] Apr 5 23:03:47 NOTICE[9226]: chan_mgcp.c:2195 handle_response: Transaction 18 timed out Apr 5 23:03:47 NOTICE[9226]: chan_mgcp.c:2217 handle_response: Terminating on result 406 from aaln/1@xxx.xxx.xxx.xxx--1 -- No command found on [xxx.xxx.xxx.xxx] for transaction 17. Ignoring... == No one is available to answer at this time -- Executing NoOp("IAX2[remote_ast@remote_astp]/3", "") in new stack -- Executing NoOp("IAX2[remote_ast@remote_astp]/3", "") in new stack -- Executing VoiceMail2("IAX2[remote_ast@remote_astp]/3", "u5427") in new stack -- Playing 'vm-theperson' (language 'en') -- Playing 'digits/5' (language 'en') -- Playing 'digits/4' (language 'en') -- Playing 'digits/2' (language 'en') -- Playing 'digits/7' (language 'en') == Spawn extension (default, 303, 6) exited non-zero on 'IAX2[remote_ast@remote_astp]/3' -- Hungup 'IAX2[remote_ast@remote_astp]/3' Apr 5 23:03:53 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 19 on [xxx.xxx.xxx.xxx] Apr 5 23:03:53 NOTICE[9226]: chan_mgcp.c:2195 handle_response: Transaction 19 timed out Apr 5 23:03:59 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 20 on [xxx.xxx.xxx.xxx] -- No command found on [xxx.xxx.xxx.xxx] for transaction 20. Ignoring... myast*CLI> myast*CLI> mgcp debug MGCP Debugging Enabled -- Accepting AUTHENTICATED call from yyy.yyy.yyy.yyy, requested format = 4, actual format = 2 -- Executing AbsoluteTimeout("IAX2[remote_ast@remote_astp]/1", "7200") in new stack -- Set Absolute Timeout to 7200 -- Executing NoOp("IAX2[remote_ast@remote_astp]/1", "") in new stack -- Executing Dial("IAX2[remote_ast@remote_astp]/1", "MGCP/aaln/1@xxx.xxx.xxx.xxx|20|rt") in new stack -- MGCP mgcp_request(aaln/1@xxx.xxx.xxx.xxx) -- MGCP cw: 0, dnd: 0, so: 0, sno: 0 -- MGCP mgcp_new(MGCP/aaln/1@xxx.xxx.xxx.xxx-1) created in state: Down -- MGCP mgcp_call(MGCP/aaln/1@xxx.xxx.xxx.xxx-1) -- Creating connection for aaln/1@xxx.xxx.xxx.xxx-1 in cxmode: recvonly callid: 32ee97c9431bd7b7 We're at zzz.zzz.zzz.zzz port 11262 Answering with capability 4 Posting Request: CRCX 21 aaln/1@[xxx.xxx.xxx.xxx] MGCP 1.0 C: 32ee97c9431bd7b7 L: p:20, a:PCMU M: recvonly X: 431bd7b7 v=0 o=root 27628 27628 IN IP4 zzz.zzz.zzz.zzz s=session c=IN IP4 zzz.zzz.zzz.zzz t=0 0 m=audio 11262 RTP/AVP 0 a=rtpmap:0 PCMU/8000 to xxx.xxx.xxx.xxx:2427 -- MGCP Asked to indicate tone: L/rg,L/ci(04/05/23/07,8755,Joe on RemoteAST) on aaln/1@xxx.xxx.xxx.xxx-1 in cxmode: recvonly Posting Request: RQNT 22 aaln/1@[xxx.xxx.xxx.xxx] MGCP 1.0 X: 25e45d32 R: L/hd(N) S: L/rg,L/ci(04/05/23/07,8755,Joe on RemoteAST) to xxx.xxx.xxx.xxx:2427 -- Called aaln/1@xxx.xxx.xxx.xxx -- MGCP/aaln/1@xxx.xxx.xxx.xxx-1 is ringing Retransmitting #1 transaction 21 on [xxx.xxx.xxx.xxx] Retransmitting #1 transaction 22 on [xxx.xxx.xxx.xxx] Retransmitting #2 transaction 21 on [xxx.xxx.xxx.xxx] Retransmitting #2 transaction 22 on [xxx.xxx.xxx.xxx] Retransmitting #3 transaction 21 on [xxx.xxx.xxx.xxx] Retransmitting #3 transaction 22 on [xxx.xxx.xxx.xxx] Retransmitting #4 transaction 21 on [xxx.xxx.xxx.xxx] Retransmitting #4 transaction 22 on [xxx.xxx.xxx.xxx] Retransmitting ASTERISK-1 transaction 21 on [xxx.xxx.xxx.xxx] Retransmitting ASTERISK-1 transaction 22 on [xxx.xxx.xxx.xxx] Apr 5 23:07:46 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 21 on [xxx.xxx.xxx.xxx] Apr 5 23:07:46 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 22 on [xxx.xxx.xxx.xxx] Apr 5 23:07:46 NOTICE[9226]: chan_mgcp.c:2195 handle_response: Transaction 22 timed out Apr 5 23:07:46 NOTICE[9226]: chan_mgcp.c:2217 handle_response: Terminating on result 406 from aaln/1@xxx.xxx.xxx.xxx--1 -- No command found on [xxx.xxx.xxx.xxx] for transaction 21. Ignoring... -- MGCP mgcp_hangup(MGCP/aaln/1@xxx.xxx.xxx.xxx-1) on aaln/1@xxx.xxx.xxx.xxx -- MGCP Asked to indicate tone: on aaln/1@xxx.xxx.xxx.xxx-1 in cxmode: recvonly Posting Request: RQNT 23 aaln/1@[xxx.xxx.xxx.xxx] MGCP 1.0 X: 25e45d32 R: hd(N) to xxx.xxx.xxx.xxx:2427 -- MGCP mgcp_hangup(MGCP/aaln/1@xxx.xxx.xxx.xxx-1) on aaln/1@xxx.xxx.xxx.xxx set vmwi(+) -- MGCP Asked to indicate tone: vmwi(+) on aaln/1@xxx.xxx.xxx.xxx-1 in cxmode: inactive Queueing Request: RQNT 24 aaln/1@[xxx.xxx.xxx.xxx] MGCP 1.0 X: 25e45d32 R: hd(N) S: vmwi(+) to xxx.xxx.xxx.xxx:2427 == No one is available to answer at this time -- Executing NoOp("IAX2[remote_ast@remote_astp]/1", "") in new stack -- Executing NoOp("IAX2[remote_ast@remote_astp]/1", "") in new stack -- Executing VoiceMail2("IAX2[remote_ast@remote_astp]/1", "u5427") in new stack -- Playing 'vm-theperson' (language 'en') Retransmitting #1 transaction 23 on [xxx.xxx.xxx.xxx] -- Playing 'digits/5' (language 'en') Retransmitting #2 transaction 23 on [xxx.xxx.xxx.xxx] -- Playing 'digits/4' (language 'en') -- Playing 'digits/2' (language 'en') Retransmitting #3 transaction 23 on [xxx.xxx.xxx.xxx] -- Playing 'digits/7' (language 'en') -- Playing 'vm-isunavail' (language 'en') Retransmitting #4 transaction 23 on [xxx.xxx.xxx.xxx] Retransmitting ASTERISK-1 transaction 23 on [xxx.xxx.xxx.xxx] -- Playing 'vm-intro' (language 'en') Apr 5 23:07:52 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 23 on [xxx.xxx.xxx.xxx] Posting Queued Request: RQNT 24 aaln/1@[xxx.xxx.xxx.xxx] MGCP 1.0 X: 25e45d32 R: hd(N) S: vmwi(+) to xxx.xxx.xxx.xxx:2427 Apr 5 23:07:52 NOTICE[9226]: chan_mgcp.c:2195 handle_response: Transaction 23 timed out Retransmitting #1 transaction 24 on [xxx.xxx.xxx.xxx] == Spawn extension (default, 303, 6) exited non-zero on 'IAX2[remote_ast@remote_astp]/1' -- Hungup 'IAX2[remote_ast@remote_astp]/1' Retransmitting #2 transaction 24 on [xxx.xxx.xxx.xxx] Retransmitting #3 transaction 24 on [xxx.xxx.xxx.xxx] Retransmitting #4 transaction 24 on [xxx.xxx.xxx.xxx] Retransmitting ASTERISK-1 transaction 24 on [xxx.xxx.xxx.xxx] Apr 5 23:07:58 WARNING[9226]: chan_mgcp.c:551 retrans_pkt: Maximum retries exceeded for transaction 24 on [xxx.xxx.xxx.xxx] -- No command found on [xxx.xxx.xxx.xxx] for transaction 24. Ignoring... Apr 5 23:07:46 WARNING[9226]: Maximum retries exceeded for transaction 21 on [xxx.xxx.xxx.xxx] Apr 5 23:07:46 WARNING[9226]: Maximum retries exceeded for transaction 22 on [xxx.xxx.xxx.xxx] Apr 5 23:07:46 NOTICE[9226]: Transaction 22 timed out Apr 5 23:07:46 NOTICE[9226]: Terminating on result 406 from aaln/1@xxx.xxx.xxx.xxx--1 Apr 5 23:07:52 WARNING[9226]: Maximum retries exceeded for transaction 23 on [xxx.xxx.xxx.xxx] Apr 5 23:07:52 NOTICE[9226]: Transaction 23 timed out Apr 5 23:07:58 WARNING[9226]: Maximum retries exceeded for transaction 24 on [xxx.xxx.xxx.xxx] By: philipp2 (philipp2) 2004-04-05 18:02:15 FYI: Reverted back to Asterisk (head) 03/05/04 00:50:56 (successfully) By: serkan (serkan) 2004-04-06 18:54:14 If you can send the following, I can have a look at the issue with the latest CVS. 0. upgrade to latest chan_mgcp.c. 1. turn on verbose mgcp debugging on asterisk 2. power cycle ip10. I hope to see MGCP RSIP indication from your phone 3. output of cli command "mgcp show endpoints" 4. output of mgcp audit endpoint "endpoint name as shown at step 3" 5. mgcp.conf contents 6. and relevant ip addresses if you are using private net. thanks.. By: juanjo (juanjo) 2004-04-07 16:00:09 Mmmmm... Could you please try my last patch, see bug ASTERISK-687. Maybe your phone is trying to register using a wildcard RSIP. Also MGCP debug isn&ASTERISK-177;t enlighting to much so I prefer to use ngrep. Try "ngrep port 2727" on your * box to see exactly what your IP10 are sending. By: twisted (twisted) 2004-04-29 09:38:13 Any update on this one? Is this still a problem with current CVS? The patch referred to in the above post was inserted into cvs on 4/26. An update is required to continue this bug. By: philipp2 (philipp2) 2004-04-29 19:01:17 The problem is that I don't easily have access to the MGCP phones - I am in Germany, and those phones are in Belgium. I have this bug report on my list (naturally, since any newer CVS completely breaks *all* MGCP functionality for that installation). So I hope to find a moment where I can take this production system off-line and have some local tech person assist me... Yet still I'd like to hear experiences of other ip10 users with latest CVS, I know there's a couple of them out there... By: twisted (twisted) 2004-05-03 05:42:42 We really could use some quick feedback on this bug, as the patch from 693 was applied to cvs since this posting, it would be really helpful to know if it made any difference. By: florian (florian) 2004-05-04 22:08:23 Tonight I tried briefly to follow this scenario with my own IP10 and current CVS (development), and I can confirm that the issue is NOT resolved by these patches. During the three-way conversations some audio-paths seem one-way. It is however kind of hard to test with three phones, one person and bug 1155 bugging me :-P By: Mark Spencer (markster) 2004-05-07 10:04:06 According to Yves Girod at Swissvoice, they are sending two IP10S's for us to use for testing and fixing this issue. I'm pleased to see their interest in Asterisk! By: philipp2 (philipp2) 2004-05-07 10:18:52 Great, this is really *good* news! I guess they've been bugged to often by Asterisk users... :-) By: Mark Spencer (markster) 2004-05-19 09:21:39 As it turns out the 10S cannot conference on the device. From Jean-Francois at Swissvoice: Hi Mark, IP10S have not the capabilities to mix by itself 2 RTP flows, that why it refuses the conference mode. Most often, with the different partner we have, the conference capability is managed by a MCU on the network, then on the phone side, it's transparent. Regards, Jean-Francois If you want you can open a feature request that we be able to use a zap conference to implement three-way calling when unavailable on the end device, but that's a pretty good sized development project. | ||