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

Sat Jul 19 09:39:44 CDT 2008

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