[Home]

Summary:ASTERISK-00605: All MGCP phones turn to constant busy - restart required
Reporter:philipp2 (philipp2)Labels:
Date Opened:2003-12-01 07:39:35.000-0600Date Closed:2011-06-07 14:10:18
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Core/General
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I've seen this a couple of times, yet I am not able to reproduce this myself as I wasn't able to identify the cause: At some point all MGCP phones (Swissvoice ip10) go busy, while X-Lite still works fine; receiving CAPI calls to the MGCP phones also seems fine, but they cannot dial.

Since this a production system I could find a quiet moment to run mgcp debug, instead needed to restart immediately each time. Let me know what additional info is needed...

Twice call parking was involved shortly before the problem appeared, but I have no clue if this is linked to the problem or not.

"mgcp show endpoints" shows the MGCP phones as idle


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

FROM THE LOG FILE:

Dec  1 10:40:56 NOTICE[303314]: File sched.c, Line 218 (sched_settime): Request
to schedule in the past?!?!
Dec  1 10:40:59 NOTICE[303314]: File sched.c, Line 218 (sched_settime): Request
to schedule in the past?!?!
Dec  1 10:42:25 WARNING[306388]: File pbx.c, Line 1663 (ast_pbx_run): CAPI[contr
1/xxxxxxxx]/79 already has a call record??
Dec  1 10:42:25 WARNING[306388]: File cdr.c, Line 135 (ast_cdr_start): CDR on ch
annel 'CAPI[contr1/xxxxxxxx]/79' already started
Dec  1 10:42:25 NOTICE[306388]: File app_dial.c, Line 518 (dial_exec): Unable to
create channel of type 'MGCP'
Dec  1 10:42:25 NOTICE[306388]: File app_dial.c, Line 518 (dial_exec): Unable to
create channel of type 'MGCP'
Dec  1 10:42:25 NOTICE[306388]: File app_dial.c, Line 518 (dial_exec): Unable to
create channel of type 'MGCP'
Dec  1 10:43:46 WARNING[307410]: File pbx.c, Line 1663 (ast_pbx_run): CAPI[contr
1/xxxxxxxx]/80 already has a call record??
Dec  1 10:43:46 WARNING[307410]: File cdr.c, Line 135 (ast_cdr_start): CDR on ch
annel 'CAPI[contr1/xxxxxxxx]/80' already started
Dec  1 10:43:46 NOTICE[307410]: File app_dial.c, Line 518 (dial_exec): Unable to
create channel of type 'MGCP'
Dec  1 10:44:58 NOTICE[306388]: File rtp.c, Line 264 (process_rfc3389): RFC3389
support incomplete.  Turn off on client if possible

Dec  1 11:25:24 WARNING[314585]: File chan_mgcp.c, Line 847 (mgcp_indicate): Don
't know how to indicate condition 14

Dec  1 12:29:07 NOTICE[9226]: File chan_iax.c, Line 4026 (socket_read): Peer 'as
tkarlp' is now REACHABLE!
Dec  1 12:33:06 NOTICE[325858]: File sched.c, Line 218 (sched_settime): Request
to schedule in the past?!?!

Dec  1 12:36:58 NOTICE[326883]: File chan_mgcp.c, Line 775 (mgcp_fixup): mgcp_fi
xup(MGCP/aaln/1@192.168.7.18-1, MGCP/aaln/1@192.168.7.18-1<MASQ>)
Dec  1 12:36:58 NOTICE[326883]: File chan_mgcp.c, Line 775 (mgcp_fixup): mgcp_fi
xup(MGCP/aaln/1@192.168.7.17-1<ZOMBIE>, MGCP/aaln/1@192.168.7.18-1)

Dec  1 13:10:30 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:10:34 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:10:47 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:10:51 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:10:54 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:10:55 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:11:12 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:11:17 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:12:41 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call

Dec  1 13:18:40 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:20:10 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call
Dec  1 13:20:10 WARNING[11276]: File chan_mgcp.c, Line 2412 (mgcpsock_read): Sub
channel already has a cxident. sub->cxident: 3 requested 4
Dec  1 13:20:13 WARNING[11276]: File chan_mgcp.c, Line 2114 (handle_hd_hf): Unab
le to create switch thread: Interrupted system call

Dec  1 13:21:19 WARNING[11276]: File chan_mgcp.c, Line 2412 (mgcpsock_read): Sub
channel already has a cxident. sub->cxident: 96 requested 5a

Dec  1 13:29:24 WARNING[11276]: File chan_mgcp.c, Line 2412 (mgcpsock_read): Sub
channel already has a cxident. sub->cxident: 3 requested 5
Dec  1 13:31:28 NOTICE[23576]: File sched.c, Line 218 (sched_settime): Request t
o schedule in the past?!?!
Dec  1 13:31:30 NOTICE[23576]: File sched.c, Line 218 (sched_settime): Request t
o schedule in the past?!?!


THE BUSY SYNDROM - USER TRYING TO GET A DIAL TONE WITHOUT SUCCESS:

   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hu'
   -- MGCP handle_request(aaln/1@192.168.7.18-1) ast_channel already destroyed
   -- MGCP handle_request(aaln/1@192.168.7.18) set vmwi(+)
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hd'
   -- MGCP mgcp_new(MGCP/aaln/1@192.168.7.18-1) created in state: Down
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hu'
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hd'
   -- MGCP mgcp_new(MGCP/aaln/1@192.168.7.18-1) created in state: Down
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hu'
   -- MGCP handle_request(aaln/1@192.168.7.18-1) ast_channel already destroyed
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hd'
   -- MGCP mgcp_new(MGCP/aaln/1@192.168.7.18-1) created in state: Down
   -- Endpoint 'aaln/1@192.168.7.18-1' observed 'hu'
   -- MGCP handle_request(aaln/1@192.168.7.18-1) ast_channel already destroyed
   -- MGCP handle_request(aaln/1@192.168.7.18) set vmwi(+)
Comments:By: philipp2 (philipp2) 2003-12-01 07:45:00.000-0600

Asterisk CVS-11/22/03-22:06:30
By: Brian West (bkw918) 2004-01-06 23:57:41.000-0600

any more info on this?
By: philipp2 (philipp2) 2004-01-07 04:02:18.000-0600

Sorry, not at the moment. I disabled all functions like callwaiting and flash transfers to minimize the cause of trouble (FLASH transfers would 100% screw up things when the consulted phone would refuse the call and put the handset down).

What I don't know if this is a) a pure Swissvoice firmware issue, or b) a pure chan_mgcp issue, or c) both. I suspect c) as otherwise I wouldn't see all three MGCP phones turn unusable at the same time.

I can post the firmware version, but I don't think that'll help. The installation is in a remote place that I don't have physical access to, and its a production system. :-(
By: jrollyson (jrollyson) 2004-01-11 22:33:41.000-0600

Anyone able to confirm?
By: darrenm67 (darrenm67) 2004-01-13 05:15:13.000-0600

Could this be the same problem identified in 598?
By: Olle Johansson (oej) 2004-03-21 08:48:57.000-0600

**** Bugtracker reminder:

Is this still a problem in a recent CVS?

Is it connected to 598 as Darrenm67 asked?

The bug has been sleeping for two months, so either we move forward with additional information or put it to sleep eternally :-)
By: Olle Johansson (oej) 2004-04-15 10:01:52

No feedback.