[asterisk-ss7] chan_ss7 - T22 timeout (No 'circuit group reset acknowledge' from peer)

Mon Jul 21 03:27:04 CDT 2008

Anton wrote:
> Try adjusting too, but in my case i did not need to modify 
> it. Actually I see that message appearing several times, 
> and after 3-4 timeouts finally receiving GRA
> 
> On Monday 21 July 2008 11:34, Low Yu Siang wrote:
>> I am connecting to a Siemens switch. I waited for hours,
>> T23 timer are also appearing. Currently I see in the
>> chan_ss7 code that the timeout value is set at 30
>> seconds, are you suggesting that I should modify the code
>> so that it is >120 seconds?
>>
>> Thanks.
>> Yu Siang
>>
>> --- On Mon, 21/7/08, Anton <anton.vazir at gmail.com> wrote:
>>> From: Anton <anton.vazir at gmail.com>
>>> Subject: Re: [asterisk-ss7] chan_ss7 - T22 timeout (No
>>> 'circuit group reset acknowledge' from peer) To:
>>> asterisk-ss7 at lists.digium.com, yusiang at yahoo.com Date:
>>> Monday, 21 July, 2008, 2:22 PM
>>> I have similar issue with connecting to ZTE and HUAWEI
>>> switches. GRA comes not imediatelly, but after 1-2
>>> minutes.
>>>
>>> Maybe you have the same issue. Did you wait long
>>> enough?
>>>
>>> On Saturday 19 July 2008 19:39, Low Yu Siang wrote:
>>>> Hi all,
>>>>
>>>> Thanks to the generous help from Mr. Pawel
>>>> Ratajewski,
>>> I
>>>
>>>> have managed to solve the initial alignment
>>> problem(was
>>>
>>>> using the beta version 1.0.93 of chan_ss7 and it is
>>>> solved by downgrading to 1.0.0 following some
>>> reboots).
>>>
>>>> But I still couldn't get the ss7 link ready for
>>> making
>>>
>>>> calls. All CIC are in 'idle reset pending'
>>> status. The
>>>
>>>> below's is the log messages.
>>>>
>>>> [Jul 19 22:26:00] NOTICE[7676]: config.c:549
>>>> load_config_link: Configured link 'l1' on
>>> linkset 'siuc',
>>>
>>>> firstcic=1 [Jul 19 22:26:00] DEBUG[7676]:
>>>> config.c:674 load_config_host: links 'l1:1' [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: config.c:693 load_config_host: linkname
>>>> 'l1', no 1 [Jul 19 22:26:00] DEBUG[7676]:
>>> config.c:711
>>>
>>>> load_config_host: host n_spans 1 [Jul 19 22:26:00]
>>>> WARNING[7676]: config.c:795 load_config_host: Missing
>>>> interface entries for host 'scvideo'. [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: config.c:800 load_config_host: host
>>> scvideo,
>>>
>>>> n_spans 1 [Jul 19 22:26:00] NOTICE[7676]:
>>>> config.c:959 load_config: Configuring DPC 12864 for
>>>> linkset
>>> 'siuc'.
>>>
>>>> [Jul 19 22:26:00] DEBUG[7676]: config.c:975
>>> load_config:
>>>> Setting linkset 0 'siuc' enabled 1 [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: config.c:173 show_config: Host scvideo,
>>>> links 1, ifs 0 [Jul 19 22:26:00] DEBUG[7676]:
>>>> l4isup.c:4302 isup_init: Links 1, host scvideo [Jul
>>>> 19 22:26:00] DEBUG[7676]: l4isup.c:4310 isup_init:
>>>> New
>>> CIC,
>>>
>>>> first_zapid 0, channelmask 0x7fff7fff, connector 1,
>>>> firstcic 1, schannel 16 [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 1 on
>>> zaptel
>>>
>>>> device 1. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 2 on zaptel device 2.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 3 on zaptel device 3.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 4 on zaptel device 4. [Jul 19 22:26:00] DEBUG[7676]:
>>>> transport.c:127 openchannel: Configuring CIC 5 on
>>> zaptel
>>>
>>>> device 5. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 6 on zaptel device 6.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 7 on zaptel device 7.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 8 on zaptel device 8. [Jul 19 22:26:00] DEBUG[7676]:
>>>> transport.c:127 openchannel: Configuring CIC 9 on
>>> zaptel
>>>
>>>> device 9. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 10 on zaptel device 10.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 11 on zaptel device 11.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 12 on zaptel device 12. [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 13 on
>>> zaptel
>>>
>>>> device 13. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 14 on zaptel device 14.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 15 on zaptel device 15.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 17 on zaptel device 17. [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 18 on
>>> zaptel
>>>
>>>> device 18. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 19 on zaptel device 19.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 20 on zaptel device 20.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 21 on zaptel device 21. [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 22 on
>>> zaptel
>>>
>>>> device 22. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 23 on zaptel device 23.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 24 on zaptel device 24.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 25 on zaptel device 25. [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 26 on
>>> zaptel
>>>
>>>> device 26. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 27 on zaptel device 27.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: transport.c:127
>>>> openchannel: Configuring CIC 28 on zaptel device 28.
>>>> [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>> CIC
>>>
>>>> 29 on zaptel device 29. [Jul 19 22:26:00]
>>>> DEBUG[7676]: transport.c:127 openchannel: Configuring
>>>> CIC 30 on
>>> zaptel
>>>
>>>> device 30. [Jul 19 22:26:00] DEBUG[7676]:
>>> transport.c:127
>>>
>>>> openchannel: Configuring CIC 31 on zaptel device 31.
>>> [Jul
>>>
>>>> 19 22:26:00] DEBUG[7676]: cluster.c:780
>>>> build_sender_list: Found no senders to supervise --
>>>> Starting cluster thread, pid=7676.
>>>> [Jul 19 22:26:00] DEBUG[7676]: cluster.c:798
>>>> wait_for_connections: wait 0 0 0 0 -- Starting
>>> continuity
>>>
>>>> check thread, pid=7676. == Registered channel type
>>> 'SS7'
>>>
>>>> (SS7 Protocol Driver) [Jul 19 22:26:00] NOTICE[7676]:
>>>> mtp.c:2190 mtp_init: Initialising 1 signalling links
>>> --
>>>
>>>> Starting MTP thread, pid=7676.
>>>>     -- SS7 channel loaded successfully.
>>>>  Loaded chan_ss7 => (SS7 Protocol Support)
>>>> The 'load' command is deprecated and will be
>>> removed in a
>>>
>>>> future release. Please use 'module load'
>>> instead. *CLI>
>>>
>>>>   -- Starting monitor thread, pid=7676.
>>>> [Jul 19 22:26:00] DEBUG[7711]: mtp.c:2085
>>> mtp_init_link:
>>>> init link l1, linkset siuc, schannel 16. [Jul 19
>>>> 22:26:00] DEBUG[7711]: mtp.c:681
>>> start_initial_alignment:
>>>> Starting initial alignment on link 'l1',
>>> reason: Initial.
>>>
>>>> *CLI> [Jul 19 22:26:00] NOTICE[7711]: mtp.c:1792
>>>> mtp_thread_main: Empty Zaptel output buffer detected,
>>>> outgoing packets may have been lost on link
>>> 'l1'. [Jul 19
>>>
>>>> 22:26:00] DEBUG[7711]: mtp.c:988 mtp2_process_lssu:
>>>> Entering proving state for link 'l1'. [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7711]: mtp.c:499 t4_timeout: Proving successful
>>> on
>>>
>>>> link 'l1'. [Jul 19 22:26:00] NOTICE[7711]:
>>> mtp.c:1130
>>>
>>>> mtp2_good_frame: Sending TRA to peer on link
>>> 'l1'....
>>>
>>>> [Jul 19 22:26:00] DEBUG[7711]: mtp.c:1567
>>>> mtp2_fill_zaptel_buf: Sending buffer to zaptel len=9,
>>> on
>>>
>>>> link 'l1' bsn=127, fsn=0. [Jul 19 22:26:00]
>>> DEBUG[7711]:
>>>> mtp.c:1567 mtp2_fill_zaptel_buf: Sending buffer to
>>> zaptel
>>>
>>>> len=25, on link 'l1' bsn=127, fsn=1. [Jul 19
>>> 22:26:00]
>>>
>>>> DEBUG[7711]: mtp.c:1250 mtp2_good_frame: Got MSU on
>>> link
>>>
>>>> 'l1' sio=1 slc=0 m.sls=0 bsn=1/1, fsn=1/0,
>>> sio=81,
>>>
>>>> len=15: 47 34 90 0c 11 80 f2 54 5c 10 aa ff 55 00
>>>> [Jul
>>> 19
>>>
>>>> 22:26:00] DEBUG[7711]: mtp.c:1323 process_msu: Got
>>> SLTM,
>>>
>>>> sending SLTA 'l1', state=5. [Jul 19 22:26:00]
>>>> DEBUG[7711]: mtp.c:1567 mtp2_fill_zaptel_buf: Sending
>>>> buffer to zaptel len=18, on link 'l1' bsn=0,
>>> fsn=2. [Jul
>>>
>>>> 19 22:26:00] DEBUG[7711]: mtp.c:1250 mtp2_good_frame:
>>> Got
>>>
>>>> MSU on link 'l1' sio=1 slc=0 m.sls=0 bsn=1/1,
>>> fsn=1/1,
>>>
>>>> sio=81, len=22: 47 34 90 0c 21 f0 11 22 33 44 55 66
>>>> 77
>>> 88
>>>
>>>> 99 aa bb cc dd ee ff [Jul 19 22:26:00] DEBUG[7711]:
>>>> mtp.c:1338 process_msu: Got valid SLTA response on
>>> link
>>>
>>>> 'l1', state=5. [Jul 19 22:26:00] DEBUG[7711]:
>>>> l4isup.c:1344 send_init_grs: Sending GROUP RESET
>>> messages
>>>
>>>> on linkset 'siuc'. [Jul 19 22:26:00]
>>> DEBUG[7711]:
>>>> l4isup.c:389 mtp_enqueue_isup_packet: Queue packet
>>> CIC=1,
>>>
>>>> len=10, linkset='siuc', link='l1',
>>> slinkset='siuc',
>>>
>>>> slink='l1' [Jul 19 22:26:00] DEBUG[7711]:
>>> l4isup.c:1377
>>>
>>>> send_init_grs: Group reset first 1, range 14 [Jul 19
>>>> 22:26:00] DEBUG[7711]: l4isup.c:389
>>>> mtp_enqueue_isup_packet: Queue packet CIC=17, len=10,
>>>> linkset='siuc', link='l1',
>>> slinkset='siuc', slink='l1'
>>>
>>>> [Jul 19 22:26:00] DEBUG[7711]: l4isup.c:1377
>>>> send_init_grs: Group reset first 17, range 14 [Jul 19
>>>> 22:26:00] WARNING[7711]: chan_ss7.c:617
>>>> process_event: MTP is now UP on link 'l1'. [Jul 19
>>>> 22:26:00] DEBUG[7711]: mtp.c:1859 mtp_thread_main:
>>>> Queue MSU, lsi=0, last_send_ix=0, linkset=siuc,
>>>> m->link=l1
>>> [Jul 19
>>>
>>>> 22:26:00] DEBUG[7711]: mtp.c:1859 mtp_thread_main:
>>> Queue
>>>
>>>> MSU, lsi=0, last_send_ix=0, linkset=siuc,
>>> m->link=l1 [Jul
>>>
>>>> 19 22:26:00] DEBUG[7711]: mtp.c:1567
>>>> mtp2_fill_zaptel_buf: Sending buffer to zaptel
>>>> len=14,
>>> on
>>>
>>>> link 'l1' bsn=1, fsn=3. [Jul 19 22:26:00]
>>> DEBUG[7711]:
>>>> mtp.c:1567 mtp2_fill_zaptel_buf: Sending buffer to
>>> zaptel
>>>
>>>> len=14, on link 'l1' bsn=1, fsn=4. [Jul 19
>>> 22:26:30]
>>>
>>>> NOTICE[7711]: l4isup.c:1220 t22_timeout: T22 timeout
>>> (No
>>>
>>>> "circuit group reset acknowledge" from peer)
>>> CIC=1. [Jul
>>>
>>>> 19 22:26:30] DEBUG[7711]: l4isup.c:389
>>>> mtp_enqueue_isup_packet: Queue packet CIC=1, len=10,
>>>> linkset='siuc', link='l1',
>>> slinkset='siuc', slink='l1'
>>>
>>>> [Jul 19 22:26:30] NOTICE[7711]: l4isup.c:1220
>>>> t22_timeout: T22 timeout (No "circuit group reset
>>>> acknowledge" from peer) CIC=17. [Jul 19 22:26:30]
>>>> DEBUG[7711]: l4isup.c:389 mtp_enqueue_isup_packet:
>>> Queue
>>>
>>>> packet CIC=17, len=10, linkset='siuc',
>>> link='l1',
>>>
>>>> slinkset='siuc', slink='l1' [Jul 19
>>> 22:26:30]
>>>
>>>> DEBUG[7711]: mtp.c:1859 mtp_thread_main: Queue MSU,
>>>> lsi=0, last_send_ix=0, linkset=siuc, m->link=l1
>>> [Jul 19
>>>
>>>> 22:26:30] DEBUG[7711]: mtp.c:1859 mtp_thread_main:
>>> Queue
>>>
>>>> MSU, lsi=0, last_send_ix=0, linkset=siuc,
>>> m->link=l1 [Jul
>>>
>>>> 19 22:26:30] DEBUG[7711]: mtp.c:1567
>>>> mtp2_fill_zaptel_buf: Sending buffer to zaptel
>>>> len=14,
>>> on
>>>
>>>> link 'l1' bsn=1, fsn=5. [Jul 19 22:26:30]
>>> DEBUG[7711]:
>>>> mtp.c:1567 mtp2_fill_zaptel_buf: Sending buffer to
>>> zaptel
>>>
>>>> len=14, on link 'l1' bsn=1, fsn=6. [Jul 19
>>> 22:27:00]
>>>
>>>> NOTICE[7711]: l4isup.c:1220 t22_timeout: T22 timeout
>>> (No
>>>
>>>> "circuit group reset acknowledge" from peer)
>>> CIC=1. [Jul
>>>
>>>> 19 22:27:00] DEBUG[7711]: l4isup.c:389
>>>> mtp_enqueue_isup_packet: Queue packet CIC=1, len=10,
>>>> linkset='siuc', link='l1',
>>> slinkset='siuc', slink='l1'
>>>
>>>> [Jul 19 22:27:00] NOTICE[7711]: l4isup.c:1220
>>>> t22_timeout: T22 timeout (No "circuit group reset
>>>> acknowledge" from peer) CIC=17. [Jul 19 22:27:00]
>>>> DEBUG[7711]: l4isup.c:389 mtp_enqueue_isup_packet:
>>> Queue
>>>
>>>> packet CIC=17, len=10, linkset='siuc',
>>> link='l1',
>>>
>>>> slinkset='siuc', slink='l1'
>>>>
>>>> Any help would be greatly appreciated. Thank you!
>>>>
>>>> Regards,
>>>> Low Yu Siang
>>> _______________________________________________________
>>> __
>>>
>>>> _ Not happy with your email address?.
>>>> Get the one you really want - millions of new email
>>>> addresses available now at Yahoo!
>>>> http://uk.docs.yahoo.com/ymail/new.html
>>>>
>>>> _______________________________________________
>>>> --Bandwidth and Colocation Provided by
>>>> http://www.api-digital.com--
>>>>
>>>> asterisk-ss7 mailing list
>>>> To UNSUBSCRIBE or update options visit:
>>> http://lists.digium.com/mailman/listinfo/asterisk-ss7
>>      
>> _________________________________________________________
>> _ Not happy with your email address?.
>> Get the one you really want - millions of new email
>> addresses available now at Yahoo!
>> http://uk.docs.yahoo.com/ymail/new.html
> 
> _______________________________________________
> --Bandwidth and Colocation Provided by http://www.api-digital.com--
> 
> asterisk-ss7 mailing list
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-ss7
> 
I have connected to siemens ewsd16 without stress and its on production 
for close to 18months now without much problem.

Thanks

goksie