[asterisk-users] One PRI card with 2 (or more) Telcos

Tiago Geada tiago.geada at gmail.com
Fri Mar 18 13:15:33 CDT 2011 

Just a follow up with a bit more information

asterisk*CLI> module show like timing
Module                         Description                              Use
Count
res_timing_pthread.so          pthread Timing Interface                 0

*res_timing_dahdi.so            DAHDI Timing Interface
  40*
2 modules loaded
asterisk*CLI>


--

 [root at asterisk ~]# dahdi_test -c 100

Opened pseudo dahdi interface, measuring accuracy...

99.999% 99.999% 99.992% 99.997% 99.998% 99.995% 99.998% 99.996%

99.997% 99.998% 99.997% 99.994% 99.991% 99.999% 99.998% 99.998%

99.995% 99.993% 99.998% 99.999% 99.998% 99.995% 99.992% 99.998%

100.000% 99.998% 99.995% 99.992% 99.999% 99.998% 99.998% 99.999%

99.995% 99.999% 99.999% 99.998% 99.999% 99.997% 99.999% 99.998%

99.998% 99.996% 99.992% 99.998% 99.998% 99.999% 99.996% 99.992%

99.999% 99.998% 99.997% 99.997% 99.997% 99.998% 99.995% 99.994%

99.995% 99.992% 99.999% 99.993% 99.990% 99.995% 99.993% 99.999%

99.997% 99.993% 99.999% 99.996% 99.998% 99.996% 99.993% 99.995%

99.992% 99.998% 99.993% 99.993% 99.999% 99.993% 99.998% 99.996%

99.993% 99.996% 99.996% 99.994% 99.999% 99.996% 99.996% 99.992%

99.999% 99.996% 99.991% 99.996% 99.992% 99.998% 99.997% 99.994%

99.998% 99.995%

--- Results after 98 passes ---

Best: 100.000 -- Worst: 99.990 -- Average: 99.996163, Difference: 99.998235


--

 [root at asterisk ~]# cat
/sys/devices/system/clocksource/clocksource0/current_clocksource
*tsc*
 [root at asterisk ~]# cat
/sys/devices/system/clocksource/clocksource0/available_clocksource
tsc hpet acpi_pm jiffies


On 18 March 2011 17:52, Tiago Geada <tiago.geada at gmail.com> wrote:

> Hi list!
>
> We currently have a PRI gateway composed by a box with two Digium quad-span
> PRI cards (a TE420 and a ).
> One of the cards is filled with TELCO1, while the other has first two slots
> filled with TELCO2, and 3rd slot with TELCO3.
>
> I am currently having (timer ?) issues on TELCO3 (span 7)
>
> D-Chan (202 as determined by dahdi_genconf ) is constantly failing causing
> on-going calls to terminate.
> Problem clears immediately tho. I send a copy of the log with pri debug at
> a time of problems...
>
> Is there a problem having 2 telcos on the same PRI card?
> Would somebody help?
>
> asterisk*CLI> pri show span 7
> Primary D-channel: 202
> Status: Provisioned, Up, Active
> Switchtype: EuroISDN
> Type: CPE
> Overlap Dial: 0
> Logical Channel Mapping: 0
> Timer and counter settings:
>   N200: 3
>   N202: 3
>   K: 7
>   T200: 1000
>   T202: 10000
>   T203: 10000
>   T303: 4000
>   T305: 30000
>   T308: 4000
>   T309: 6000
>   T313: 4000
>   T-HOLD: 4000
>   T-RETRIEVE: 4000
>   T-RESPONSE: 4000
> Overlap Recv: No
>
>
> and
>
> [Mar 18 17:04:05] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (I): T200
> expired N200 times sending RR/RNR in state 8(Timer recovery)
> [Mar 18 17:04:05] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:05] VERBOSE[19844] chan_dahdi.c: Changing from state 8(Timer
> recovery) to 5(Awaiting establishment)
> [Mar 18 17:04:06] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:07] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:08] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (G): *T200
> expired N200 times sending SABME in state 5(Awaiting establishment)*
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Changing from state
> 5(Awaiting establishment) to 4(TEI assigned)
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: TEI=0 DL event:
> Q931_DL_EVENT_DL_RELEASE_IND(3)
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Start T309 for call cref=56
> on channel 2
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Start T309 for call cref=64
> on channel 3
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Start T309 for call cref=58
> on channel 4
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Start T309 for call cref=66
> on channel 6
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c: Changing from state 4(TEI
> assigned) to 5(Awaiting establishment)
> [Mar 18 17:04:09] VERBOSE[19844] chan_dahdi.c:   == Primary D-Channel on
> span 7 down
> [Mar 18 17:04:09] WARNING[19844] chan_dahdi.c: No D-channels available!
>  Using Primary channel 202 as D-channel anyway!
> [Mar 18 17:04:10] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:11] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:12] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:13] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (G): T200
> expired N200 times sending SABME in state 5(Awaiting establishment)
> [Mar 18 17:04:13] VERBOSE[19844] chan_dahdi.c: Changing from state
> 5(Awaiting establishment) to 4(TEI assigned)
> [Mar 18 17:04:13] VERBOSE[19844] chan_dahdi.c: TEI=0 DL event:
> Q931_DL_EVENT_DL_RELEASE_IND(3)
> [Mar 18 17:04:13] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:13] VERBOSE[19844] chan_dahdi.c: Changing from state 4(TEI
> assigned) to 5(Awaiting establishment)
> [Mar 18 17:04:13] WARNING[19844] chan_dahdi.c: No D-channels available!
>  Using Primary channel 202 as D-channel anyway!
> [Mar 18 17:04:14] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: T309 timed out waiting for
> data link re-establishment
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: q931.c:7750
> pri_internal_clear: Call 56 enters state 0 (Null).  Hold state: Idle
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: clearing, alive 1, hangupack
> 0
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c:   == Primary D-Channel on
> span 7 up
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c:     -- Channel 0/2, span 7
> got hangup, cause 27
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: T309 timed out waiting for
> data link re-establishment
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: q931.c:7750
> pri_internal_clear: Call 64 enters state 0 (Null).  Hold state: Idle
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: clearing, alive 1, hangupack
> 0
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c:     -- Channel 0/3, span 7
> got hangup, cause 27
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: T309 timed out waiting for
> data link re-establishment
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: q931.c:7750
> pri_internal_clear: Call 58 enters state 0 (Null).  Hold state: Idle
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: clearing, alive 1, hangupack
> 0
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c:     -- Channel 0/4, span 7
> got hangup, cause 27
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: T309 timed out waiting for
> data link re-establishment
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: q931.c:7750
> pri_internal_clear: Call 66 enters state 0 (Null).  Hold state: Idle
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: clearing, alive 1, hangupack
> 0
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c:     -- Channel 0/6, span 7
> got hangup, cause 27
> [Mar 18 17:04:15] VERBOSE[27353] pbx.c:   == Spawn extension (incoming-pri,
> sw-30-218543080, 11) exited non-zero on 'DAHDI/189-1'
> [Mar 18 17:04:15] VERBOSE[27353] chan_dahdi.c: q931_hangup: other hangup
> [Mar 18 17:04:15] VERBOSE[27354] app_mixmonitor.c:   == MixMonitor close
> filestream
> [Mar 18 17:04:15] VERBOSE[27353] chan_dahdi.c: NEW_HANGUP DEBUG: Calling
> q931_hangup, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27353] chan_dahdi.c: NEW_HANGUP DEBUG: Destroying
> the call, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27353] chan_dahdi.c:     -- Hungup 'DAHDI/189-1'
> [Mar 18 17:04:15] VERBOSE[27354] app_mixmonitor.c:   == Executing
> [/usr/local/bin/mixmon "1300465595.27894_withhold.gsm"
> "/var/log/asterisk/asterisk_rec/218543080/2011-03-18"]
> [Mar 18 17:04:15] VERBOSE[27279] pbx.c:   == Spawn extension (incoming-pri,
> sw-30-218543080, 11) exited non-zero on 'DAHDI/188-1'
> [Mar 18 17:04:15] VERBOSE[27279] chan_dahdi.c: q931_hangup: other hangup
> [Mar 18 17:04:15] VERBOSE[27279] chan_dahdi.c: NEW_HANGUP DEBUG: Calling
> q931_hangup, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27279] chan_dahdi.c: NEW_HANGUP DEBUG: Destroying
> the call, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27280] app_mixmonitor.c:   == MixMonitor close
> filestream
> [Mar 18 17:04:15] VERBOSE[27279] chan_dahdi.c:     -- Hungup 'DAHDI/188-1'
> [Mar 18 17:04:15] VERBOSE[27280] app_mixmonitor.c:   == Executing
> [/usr/local/bin/mixmon "1300465487.27876_219575683.gsm"
> "/var/log/asterisk/asterisk_rec/218543080/2011-03-18"]
> [Mar 18 17:04:15] VERBOSE[27954] pbx.c:   == Spawn extension (incoming-pri,
> sw-30-218543080, 11) exited non-zero on 'DAHDI/192-1'
> [Mar 18 17:04:15] VERBOSE[27955] app_mixmonitor.c:   == MixMonitor close
> filestream
> [Mar 18 17:04:15] VERBOSE[27954] chan_dahdi.c: q931_hangup: other hangup
> [Mar 18 17:04:15] VERBOSE[27954] chan_dahdi.c: NEW_HANGUP DEBUG: Calling
> q931_hangup, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27954] chan_dahdi.c: NEW_HANGUP DEBUG: Destroying
> the call, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27954] chan_dahdi.c:     -- Hungup 'DAHDI/192-1'
> [Mar 18 17:04:15] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:15] VERBOSE[27955] app_mixmonitor.c:   == Executing
> [/usr/local/bin/mixmon "1300467477.28066_229959499.gsm"
> "/var/log/asterisk/asterisk_rec/218543080/2011-03-18"]
> [Mar 18 17:04:15] VERBOSE[27487] pbx.c:   == Spawn extension (incoming-pri,
> sw-30-218543080, 11) exited non-zero on 'DAHDI/190-1'
> [Mar 18 17:04:15] VERBOSE[27487] chan_dahdi.c: q931_hangup: other hangup
> [Mar 18 17:04:15] VERBOSE[27488] app_mixmonitor.c:   == MixMonitor close
> filestream
> [Mar 18 17:04:15] VERBOSE[27487] chan_dahdi.c: NEW_HANGUP DEBUG: Calling
> q931_hangup, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27487] chan_dahdi.c: NEW_HANGUP DEBUG: Destroying
> the call, ourstate Null, peerstate Null, hold-state Idle
> [Mar 18 17:04:15] VERBOSE[27487] chan_dahdi.c:     -- Hungup 'DAHDI/190-1'
> [Mar 18 17:04:15] VERBOSE[27488] app_mixmonitor.c:   == Executing
> [/usr/local/bin/mixmon "1300466049.27932_263949634.gsm"
> "/var/log/asterisk/asterisk_rec/218543080/2011-03-18"]
> [Mar 18 17:04:16] VERBOSE[27280] app_mixmonitor.c:   == End MixMonitor
> Recording DAHDI/188-1
> [Mar 18 17:04:16] VERBOSE[27488] app_mixmonitor.c:   == End MixMonitor
> Recording DAHDI/190-1
> [Mar 18 17:04:16] VERBOSE[27354] app_mixmonitor.c:   == End MixMonitor
> Recording DAHDI/189-1
> [Mar 18 17:04:16] VERBOSE[27955] app_mixmonitor.c:   == End MixMonitor
> Recording DAHDI/192-1
> [Mar 18 17:04:16] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (G): T200
> expired N200 times sending SABME in state 5(Awaiting establishment)
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c: Changing from state
> 5(Awaiting establishment) to 4(TEI assigned)
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c: TEI=0 DL event:
> Q931_DL_EVENT_DL_RELEASE_IND(3)
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c: Changing from state 4(TEI
> assigned) to 5(Awaiting establishment)
> [Mar 18 17:04:17] VERBOSE[19844] chan_dahdi.c:   == Primary D-Channel on
> span 7 down
> [Mar 18 17:04:17] WARNING[19844] chan_dahdi.c: No D-channels available!
>  Using Primary channel 202 as D-channel anyway!
> [Mar 18 17:04:18] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:19] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:20] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:21] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (G): T200
> expired N200 times sending SABME in state 5(Awaiting establishment)
> [Mar 18 17:04:21] VERBOSE[19844] chan_dahdi.c: Changing from state
> 5(Awaiting establishment) to 4(TEI assigned)
> [Mar 18 17:04:21] VERBOSE[19844] chan_dahdi.c: TEI=0 DL event:
> Q931_DL_EVENT_DL_RELEASE_IND(3)
> [Mar 18 17:04:21] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:21] VERBOSE[19844] chan_dahdi.c: Changing from state 4(TEI
> assigned) to 5(Awaiting establishment)
> [Mar 18 17:04:21] WARNING[19844] chan_dahdi.c: No D-channels available!
>  Using Primary channel 202 as D-channel anyway!
> [Mar 18 17:04:22] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:23] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:24] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:25] VERBOSE[19844] chan_dahdi.c: TEI=0 MDL-ERROR (G): T200
> expired N200 times sending SABME in state 5(Awaiting establishment)
> [Mar 18 17:04:25] VERBOSE[19844] chan_dahdi.c: Changing from state
> 5(Awaiting establishment) to 4(TEI assigned)
> [Mar 18 17:04:25] VERBOSE[19844] chan_dahdi.c: TEI=0 DL event:
> Q931_DL_EVENT_DL_RELEASE_IND(3)
> [Mar 18 17:04:25] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:25] VERBOSE[19844] chan_dahdi.c: Changing from state 4(TEI
> assigned) to 5(Awaiting establishment)
> [Mar 18 17:04:25] WARNING[19844] chan_dahdi.c: No D-channels available!
>  Using Primary channel 202 as D-channel anyway!
> [Mar 18 17:04:26] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:27] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
> [Mar 18 17:04:28] VERBOSE[19844] chan_dahdi.c: TEI=0 Sending SABME
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-users/attachments/20110318/78365f38/attachment.htm>

More information about the asterisk-users mailing list