SMG-1016M: при передаче с q931 на sip не заменяется вызывающий номер

[askuratov]

Добрый день.
Версия ПО V.3.8.0.2088 M Build: Apr 19 2017 07:14:30
По q931 поступает вызов, который далее должен передаваться по sip. CgPN изначально приходит пустой. Применяется модификатор (-), который меняет CgPN на заданный номер (назовем его N1). Далее, при передаче по sip (в заданную ТГ) применяется еще один модификатор (Х.), который меняет CgPN на другой номер (N2). Проблема в том, что у получателя показывает Anonymous, а не N2 и не N1.
Логи могу предоставить. Подскажите, в какую сторону копать, чтобы решить проблему.

[bravo]

копать в сторону снятия pstn трейса... а в нём всё будет видно.

[askuratov]

Вот лог pbx_pstn.log (все уровни в 1):

Код: Выделить всё

15:42:48.066891  [INFO]  Q931. Line 00/01. Call 9B70. RX 23
DUMP:   08.02.1B.70.05.04.03.80.90.A3.18.03.A9.83.9C.70.
      05.81.31.31.32.31.A1.
      0x1B70 (from orig), MSG=SETUP:
      [bearer capability:
         cap=speech
         std=CCITT
         rate=64 kbit/s
         mode=circuit
         layer1=G.711 A-law]
      [channel id: channel=28 (exclusive)] Coding=CCITT
      [called  party number: 1121 (type=unknown, plan=ISDN)]
      [sending complete]
---------------------------------
  15:42:48.067647  [INFO]  Q931. Line 00/01. Create Call, entry 96. Call 9B70 (Incoming)
  15:42:48.068027  [INFO]  Q931. Line 00/01. Call 9B70. Incoming SETUP. Check chan id
  15:42:48.068138  [INFO]  Q931. Line 00/01. Call 9B70. Check IE 'Channel ID': channel id='as indicated'chan=28, mode excl
  15:42:48.068284  [INFO]  Q931. Line 00/01. Search B-chan: chan 28, mask 00010001 - chan ok: mask 00010001
  15:42:48.068387  [INFO]  Q931. Line 00/01. Call 9B70. Check IE 'Bearer capablity': {speech}{64 kbit/s}{G.711 A-law}
  15:42:48.068529  [INFO]  Q931. Line 00/01. Call 9B70. Check IE 'Called party number': {type=0x00, plan=0x01} <1121> (4)
  15:42:48.068621  [INFO]  Q931. Line 00/01. Call 9B70. Incoming SETUP. CgPN <>(0), CdPN <1121>(4) isdn flag=03
  15:42:48.068790  [INFO]  Port PRI:01:96. VChan set (chan 28) - 'line 0, chan 113'
  15:42:48.068967  [INFO]  Port PRI:01:96. Set   cdr-time for: setup
  15:42:48.069117  [INFO]  Port PRI:01:96. Setup   time-stamp: 15:42:48.115 GMT+3 Thu Jun 22 2017
  15:42:48.069218  [INFO]  Port PRI:01:96. Seize (v-chan:28, TG:02). Cld:'<none>'(0). (Trunk) . Clg-len: 0. clg-set: n. COS: -1. RADIUS=-1
  15:42:48.069318  [INFO]  Port PRI:01:96. ModTable in [2:-1], out [-1:-1], out redir:orig [-1:-1]
  15:42:48.069413  [INFO]  Port PRI:01:96. Set calling number: calling not set. Cat [
  15:42:48.069551  [INFO]  Port PRI:01:96. Calling Info (pres not allowed): <none>
  15:42:48.069645  [INFO]  Port PRI:01:96. Search Modifier at 'incoming' for 'calling'. ModTable[2].
  15:42:48.069835  [INFO]  Port PRI:01:96. Search Modifier for num <>(0)
  15:42:48.070024  [INFO]  Port PRI:01:96. Search Modifier at 'incoming' for 'calling'. Result: modifier [3]
  15:42:48.070180  [INFO]  Port PRI:01:96. Modify at 'incoming': use modifier <calling>, modify num <calling>
  15:42:48.070280  [INFO]  Port PRI:01:96. Modify at 'incoming': number mask <(-)>
  15:42:48.070373  [INFO]  Port PRI:01:96. Modify at 'incoming': rule <+4732101112!>, ptr <0>. Num <none>(0), nptr <0>
  15:42:48.070515  [INFO]  Port PRI:01:96. Modify at 'incoming': result-num: <4732101112>.(10). res: 10
  15:42:48.070671  [INFO]  Port PRI:01:96. Modify at 'incoming': calling-params: type <national>, numplan <no-change>, pres <restricted>, screen <no-change>, cat 'N'
  15:42:48.070781  [INFO]  Port PRI:01:96. CgPN: <4732101112>(10) <set> <national> <no-change> <restricted> <net.provided>. cat [
  15:42:48.070963  [INFO]  Port PRI:01:96. Set state 'In-Dial'
  15:42:48.071060  [INFO]  Q931. Line 00/01. Call 9B70. Incoming seize - PLAN 0. COS 0
  15:42:48.071190  [INFO]  Q931. Line 00/01. Call 9B70. Incoming SETUP. proc dial
  15:42:48.071318  [INFO]  Q931. Line 00/01. Call 9B70. State 'Call Present'
  15:42:48.071421  [INFO]  Q931. Line 00/01. Call 9B70. In Dial: 1121{EndOfDial}
  15:42:48.071588  [INFO]  Q931. Line 00/01. Call 9B70. Timer 'T302' (15 sec) start in state 'Call Present'
  15:42:48.071702  [INFO]  Port PRI:01:96. Dial In (in=05, need rm=0): <1><1><2><1>[.]
  15:42:48.071835  [INFO]  Port PRI:01:96. In Digit 5 sym: called buf <1121.>
  15:42:48.071927  [INFO]  Port PRI:01:96. Dial buf <1121>(4)
  15:42:48.072061  [INFO]  Port PRI:01:96. Dial Proc (state In-Dial): PLAN 0, <1121.> (called len 05) Compl.
  15:42:48.072250  [INFO]  Port PRI:01:96. Modify at 'incoming': use modifier <calling>, modify num <called>
  15:42:48.072426  [INFO]  Port PRI:01:96. Modify at 'incoming': number mask <(-)>
  15:42:48.072525  [INFO]  Port PRI:01:96. Modify at 'incoming': rule <$>, ptr <0>. Num <1121.>(5), nptr <0>
  15:42:48.072657  [INFO]  Port PRI:01:96. Modify at 'incoming': result-num: <1121.>.(5). res: 5
  15:42:48.072756  [INFO]  Port PRI:01:96. Modify at 'incoming': called-params:  type <no-change>, numplan <no-change>
  15:42:48.072910  [INFO]  Port PRI:01:96. CdPN: <1121.>(5) <unknown> <isdn/telephony>
  15:42:48.073007  [INFO]  Port PRI:01:96. Search route. Mode: 'calling'. Num: <4732101112>(10).# Complete
  15:42:48.073154  [INFO]  Port PRI:01:96. Search route. Result: route not found. res (-1)
  15:42:48.082914  [INFO]  Port PRI:01:96. Search route. Mode: 'called'. Num: <1121.>(5).# Complete
  15:42:48.085751  [INFO]  Port PRI:01:96. Search route. Result: found route: prefix[36]. res (4)
  15:42:48.085886  [INFO]  Port PRI:01:96. Process route to prefix [36].
  15:42:48.085983  [INFO]  Port PRI:01:96. Send 'End of Dial' to incoming call
  15:42:48.086127  [INFO]  Q931. Line 00/01. Call 9B70. End Dial in state 'Call Present' (condition for call proceeding reached)
  15:42:48.086226  [INFO]  Q931. Line 00/01. Call 9B70. Timer 'T302' stop in state 'Call Present'
  15:42:48.086391  [INFO]  Q931. Line 00/01. Call 9B70. TX 11
DUMP:   08.02.9B.70.02.18.03.A9.83.9C.
      0x1B70 (to orig), MSG=CALL PROCEEDING:
      [channel id: channel=28 (exclusive)] Coding=CCITT
---------------------------------
  15:42:48.086716  [INFO]  Q931. Line 00/01. Call 9B70. State 'Incoming Call Proceeding'
  15:42:48.086923  [INFO]  Port PRI:01:96. Prefix 36: type 'TRUNK', idx=38
  15:42:48.087057  [INFO]  Port PRI:01:96. Accs on
  15:42:48.087155  [INFO]  Port PRI:01:96. Link to TG 38. Out modifiers-table: clg[26], cld[6], redir[-1], orig[-1], generic[-1]
  15:42:48.087314  [INFO]  Port PRI:01:96. Link to TG 38. Setup mode: enblock <ST>
  15:42:48.087505  [INFO]  Port PRI:01:96. Link to TG 38: mode Local. Dir=0, Cld=0 Select Order=undef. In buf: ~1121.
  15:42:48.087609  [INFO]  Port PRI:01:96. TG: Link entry to SIPT[37]
  15:42:48.087747  [INFO]  Port PRI:01:96. Outgoing setup to SIPT:0000.
  15:42:48.087844  [INFO]  SIPT[37]. Seize from port PRI:01:96.
  15:42:48.088031  [INFO]  Port SIPT:05c9. Check out-calling-modify: '4732101112'(10). ModTable[26], ClgMod[-1]
  15:42:48.088209  [INFO]  Port SIPT:05c9. Search Modifier at 'outgoing' for 'calling'. ModTable[26].
  15:42:48.088345  [INFO]  Port SIPT:05c9. Search Modifier for num <4732101112>(10) <national> <no-change> <restricted> <net.provided>. cat 'n'
  15:42:48.088496  [INFO]  Port SIPT:05c9. Search Modifier at 'outgoing' for 'calling'. Result: modifier [27]
  15:42:48.088596  [INFO]  Port SIPT:05c9. Modify at 'outgoing': use modifier <calling>, modify num <calling>
  15:42:48.088730  [INFO]  Port SIPT:05c9. Modify at 'outgoing': number mask <(X.)>
  15:42:48.088879  [INFO]  Port SIPT:05c9. Modify at 'outgoing': rule <+1123602!>, ptr <0>. Num <4732101112>(10), nptr <0>
  15:42:48.088988  [INFO]  Port SIPT:05c9. Modify at 'outgoing': result-num: <1123602>.(7). res: 7
  15:42:48.089144  [INFO]  Port SIPT:05c9. Modify at 'outgoing': calling-params: type <no-change>, numplan <no-change>, pres <no-change>, screen <no-change>, cat 'N'
  15:42:48.089243  [INFO]  Port SIPT:05c9. Check out-calling-modify: ClgMod[-2]
  15:42:48.089376  [INFO]  Port SIPT:05c9. Check out-calling-from-RADIUS-modify: '1123602'(7). ClgMod[-1]
  15:42:48.089508  [INFO]  SIPT[37]. Seize  - interface 37 [ID: 38]: mode 'SIP'
  15:42:48.089627  [INFO]  SIP. Interface[37]. Resolved IP-addr requested. Host '10.1.8.101'. aaa [1], srv-udp [0], srv-tcp [0]. Transport: 1
  15:42:48.089758  [INFO]  SIP. Interface[37]. Host '10.1.8.101'. Records according to transport: aaa [1], srv-udp [0], srv-tcp [0]. All [1]. Next-rec [0]
  15:42:48.089865  [INFO]  SIP. Interface[37]. Result: srv-udp [0] <0.0.0.0:0>
  15:42:48.090051  [INFO]  SIP. Interface[37]. Result: srv-tcp [0] <0.0.0.0:0>
  15:42:48.090224  [INFO]  SIP. Interface[37]. Check : aaa-rec [0] [0:1]
  15:42:48.090379  [INFO]  SIP. Interface[37]. Select: aaa-rec [0] <10.1.8.101:0>[0:1]
  15:42:48.090483  [INFO]  SIP. Interface[37]. Result: aaa-rec [1] <10.1.8.101:0>
  15:42:48.090613  [INFO]  SIPT[37]. Seize  - interface 37 [ID: 38]: set ipaddr:port '10.1.8.101':'5060' for host '10.1.8.101'
  15:42:48.090728  [INFO]  Port SIPT:05c9. Set parent PRI:01:96
  15:42:48.090934  [INFO]  Port SIPT:05c9. Create msp-conn Conn[001e].
  15:42:48.091109  [INFO]  Conn[001e]: Set state 'Idle'->'AllocReq'
  15:42:48.091311  [INFO]  Port SIPT:05c9. Set new IP-conn [001e]
  15:42:48.091421  [INFO]  Conn[001e]: set network interface in state 'AllocReq'. IP <10.1.8.14>. net-dev [2]
  15:42:48.091513  [INFO]  SIPT[37]. Seize  - dst '10.1.8.101:5060' src-port 0
  15:42:48.091661  [INFO]  SIPT[37]. Seize  CgPN: <1123602>(7) <set> <national> <no-change> <restricted> <net.provided>. cat [
  15:42:48.091814  [ERR ]  SIPT[37]. Seize  - calling subscriber category not present
  15:42:48.091931  [INFO]  SIPT[37]. Seize  - enblock mode
  15:42:48.092069  [INFO]  Port SIPT:05c9. Set state 'Wait-Num'
  15:42:48.092191  [INFO]  Port SIPT:05c9. Query Next Digit - peer PRI:01:96 (next.digit=n). Called len 05, proceed 00
  15:42:48.092289  [INFO]  Port SIPT:05c9. Dial Out=1 (ext)
  15:42:48.092384  [INFO]  Port SIPT:05c9. Modify at 'outgoing': use modifier <calling>, modify num <called>
  15:42:48.092474  [INFO]  Port SIPT:05c9. Modify at 'outgoing': number mask <(X.)>
  15:42:48.092562  [INFO]  Port SIPT:05c9. Modify at 'outgoing': rule <$>, ptr <0>. Num <1>(1), nptr <0>
  15:42:48.092654  [INFO]  Port SIPT:05c9. Modify at 'outgoing': result-num: <1>.(1). res: 1
  15:42:48.092744  [INFO]  Port SIPT:05c9. Modify at 'outgoing': called-params:  type <no-change>, numplan <no-change>
  15:42:48.092835  [INFO]  Port SIPT:05c9. Update called params
  15:42:48.092924  [INFO]  Port SIPT:05c9. Check out-called-modify: '1'(1). ModTable[6], CldMod[-1]
  15:42:48.093015  [INFO]  Port SIPT:05c9. Check out-called-modify: number not complete. '1'(1).
  15:42:48.093115  [INFO]  Port SIPT:05c9. Query Next Digit - peer PRI:01:96 (next.digit=n). Called len 05, proceed 01
  15:42:48.101078  [INFO]  Port SIPT:05c9. Dial Out=1 (ext)
  15:42:48.101195  [INFO]  Port SIPT:05c9. Check out-called-modify: '1'(1). ModTable[6], CldMod[-1]
  15:42:48.101292  [INFO]  Port SIPT:05c9. Check out-called-modify: number not complete. '11'(2).
  15:42:48.101396  [INFO]  Port SIPT:05c9. Query Next Digit - peer PRI:01:96 (next.digit=n). Called len 05, proceed 02
  15:42:48.101487  [INFO]  Port SIPT:05c9. Dial Out=2 (ext)
  15:42:48.101580  [INFO]  Port SIPT:05c9. Check out-called-modify: '2'(1). ModTable[6], CldMod[-1]
  15:42:48.101691  [INFO]  Port SIPT:05c9. Check out-called-modify: number not complete. '112'(3).
  15:42:48.101795  [INFO]  Port SIPT:05c9. Query Next Digit - peer PRI:01:96 (next.digit=n). Called len 05, proceed 03
  15:42:48.101888  [INFO]  Port SIPT:05c9. Dial Out=1. (ext)
  15:42:48.101979  [INFO]  Port SIPT:05c9. Check out-called-modify: '1.'(2). ModTable[6], CldMod[-1]
  15:42:48.102074  [INFO]  Port SIPT:05c9. Search Modifier at 'outgoing' for 'called'. ModTable[6].
  15:42:48.102168  [INFO]  Port SIPT:05c9. Search Modifier for num <1121.>(5) <unknown> <no-change>
  15:42:48.102300  [INFO]  Port SIPT:05c9. Search Modifier at 'outgoing' for 'called'. Result: modifier not found
  15:42:48.102394  [INFO]  Port SIPT:05c9. Check out-called-modify: ClgMod[-2:-2], CldMod[-2:-2]
  15:42:48.102485  [INFO]  Port SIPT:05c9. Check out-called-modify: CldMod[-2]
  15:42:48.102575  [INFO]  SIP. Callref 05c9. Dial 1121.
  15:42:48.102683  [INFO]  Port SIPT:05c9. Dial Out: called len 00, append 05 (max digit 40)
  15:42:48.102775  [INFO]  Port SIPT:05c9. Dial Out: 'End_of_Dial' digit/stop dial
  15:42:48.102864  [INFO]  SIP. Callref 05c9. New state 'SIPT_OVL_SENDING'
  15:42:48.102959  [INFO]  Port SIPT:05c9. Setup Ack (peer=PRI:01:96)
  15:42:48.103087  [INFO]  Port SIPT:05c9. Set state 'Out-Dial'
  15:42:48.103367  [INFO]  Port PRI:01:96. Setup Acked in state In-Dial. (peer=SIPT:05c9)
  15:42:48.103483  [INFO]  Port PRI:01:96. LinkUp to SIPT:05c9
  15:42:48.103588  [INFO]  Port SIPT:05c9. Activate IP-conn [001e]
  15:42:48.103705  [INFO]  Conn[001e]: try activate. state 'AllocReq'. (no media)
  15:42:48.103797  [INFO]  SIPT Callref 05c9. Invoke out seize
  15:42:48.103888  [INFO]  SIP. Callref 05c9. Prepare media requested
  15:42:48.103986  [INFO]  SIP. Callref 05c9. Update Media-Info. [core/sipt_proc.c:2172]
  15:42:48.104080  [INFO]  SIP. Callref 05c9. Media-Info (offer): [10.1.8.14]
  15:42:48.104183  [INFO]  Valid
       m[0]: audio 29114 RTP/AVP 8 0
       a[0]:8 G711.A [set]
       a[1]:0 G711.U [set]
       a[2]:ptime:20
       a[3]:sendrecv
  15:42:48.104442  [INFO]  SIP. Callref 05c9. Prepare media finished (should send)
  15:42:48.104568  [INFO]  Port SIPT:05c9. Set   cdr-time for: setup
  15:42:48.104708  [INFO]  Port SIPT:05c9. Setup   time-stamp: 15:42:48.151 GMT+3 Thu Jun 22 2017
  15:42:48.104794  [INFO]  SIP. TX. Callref 05c9. Mode: 'SIP' Destination: [10.1.8.101:5060] localport [0] (with media)
  15:42:48.105048  [INFO]  Port PRI:01:96. Get CallerId: '-none-' (0) no set-flg
  15:42:48.105135  [INFO]  SIP. Callref 05c9. Get display name: ''(0)
  15:42:48.105414  [INFO]  SIP. Callref 05c9. Media-Info prepare
  15:42:48.105508  [INFO]  SIP. Callref 05c9. Media-Info encode. Media-list size: 1
  15:42:48.105683  [INFO]  SIP. TX. Callref 05c9. Mode [0000:38]. [10.1.8.101:5060]
  15:42:48.105884  [INFO]  SIP. TX. Callref 05c9.   IAM- Initial Address Message
      ## Nature of connection: 10
      #    Satellite: no
      #    Continuity Check: not required
      #    Echo control: included
      ## Forward Call Indicators: 28 01
      #    Call to be treated as national call
      #    EndToEnd method: no end-to-end(only link-by-link)
      #    Interworking: encountered
      #    EndToEnd info: not available
      #    ISUP: used all the way
      #    ISUP indicator: preferred all way
      #    Originating access: ISDN
      # Calling party category:  0xff
      # Transmission medium req: 0x00 - speech
      ## Called party number: 82 90 11 12 0F
      #    Nature of address: 0x82 - unknown
      #    INN indicator:     0x08 - not allowed
      #    Numbering plan:    0x01 - ISDN/telephony
      #    Number:            1121 <ST>
      [Optional params]
      ## Calling party number: 83 07 11 32 06 02
      #    Nature of address: 0x83 - national
      #    Numbering plan:    0x00 - spare/not used (0)
      #    Screening:         0x03 - network prov
      #    Presentation:      0x01 - presentation restricted
      #    Number:            1123602
      [end of optional params]
  15:42:48.106930  [INFO]  SIP. TX. Callref 05c9.   MediaInfo
      ## IP: <10.1.8.14>
      ## Offer: <1>
      ## m=audio 29114 RTP/AVP 8 0
      ## a=rtpmap:8 PCMA/8000
      ## a=rtpmap:0 PCMU/8000
      ## a=ptime:20
      ## a=sendrecv
  15:42:48.107566  [INFO]  Port PRI:01:96. Prefix linked. Called len 05, proceed 05.
  15:42:48.107699  [INFO]  Conn[001e]: Set state 'AllocReq'->'Allocated'
  15:42:48.107797  [INFO]  Conn[001e]: Set state 'Allocated'->'DisableReq'
  15:42:48.124547  [INFO]  Conn[001e]: Created (disabled). State 'DisableReq'
  15:42:48.124709  [INFO]  Conn[001e]: Set state 'DisableReq'->'Disabled'
  15:42:48.124814  [INFO]  Port SIPT:05c9. Created IP-conn [001e].
  15:42:48.124906  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:48.199709  [INFO]  SIP. RX. Callref 05c9. Call-ID: 1498-135368-115885
  15:42:48.199900  [INFO]  SIP. RX. Callref 05c9.   ACM-Address Complete
      ## Backward call Indicators: 16 01
      #    Called party's status indicator: Subscriber free
      #    Interworking: encountered
      #    ISUP: not used all the way
      #    Terminating access: non-ISDN
      [No optional params]
  15:42:48.200247  [INFO]  Port SIPT:05c9. set ISDN: BCI=0x80: 'Change' 'Send'
  15:42:48.200346  [INFO]  SIP. Callref 05c9. New state 'SIPT_DELIVERED'
  15:42:48.200443  [INFO]  Port SIPT:05c9. set ISDN: BCI=0x80: 'Send'
  15:42:48.200539  [INFO]  Port SIPT:05c9. Alert (peer=PRI:01:96)
  15:42:48.200669  [INFO]  Port SIPT:05c9. Stop Dial, reason 'alert'
  15:42:48.200806  [INFO]  Port PRI:01:96. LinkUp to SIPT:05c9
  15:42:48.200912  [INFO]  Port SIPT:05c9. Activate IP-conn [001e]
  15:42:48.201001  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:48.201097  [INFO]  Port SIPT:05c9. Set state 'Out-Call'
  15:42:48.201190  [INFO]  Port PRI:01:96. Alerting from peer SIPT:05c9
  15:42:48.201280  [INFO]  Port PRI:01:96. Set state 'In-Call'
  15:42:48.201370  [INFO]  Port SIPT:05c9. Dir[1], obci-req[1]. Call-ind: change[0], obci[0:0], isdn: allway[0], fwd_acs[1]
  15:42:48.201464  [INFO]  Port SIPT:05c9. Dir[1], obci-req[0]. Call-ind: change[0], obci[0:0], isdn: allway[0], fwd_acs[1]
  15:42:48.201558  [INFO]  Q931. Line 00/01. Call 9B70. Call Received. PI [1][0]
  15:42:48.201648  [INFO]  Q931. Line 00/01. Call 9B70. Send 'Alert'
  15:42:48.201766  [INFO]  Q931. Line 00/01. Call 9B70. TX 10
DUMP:   08.02.9B.70.01.1E.02.81.81.
      0x1B70 (to orig), MSG=ALERTING:
      [progress ind: No end-to-end isdn (1)]
---------------------------------
  15:42:48.202040  [INFO]  Q931. Line 00/01. Call 9B70. State 'Call Received'
  15:42:48.202174  [INFO]  Port PRI:01:96. LinkUp to SIPT:05c9
  15:42:48.202277  [INFO]  Port SIPT:05c9. Activate IP-conn [001e]
  15:42:48.202363  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:48.202460  [INFO]  Port SIPT:05c9. TDM-RingBack for IP-conn [001e]. Peer PRI:01:96.
  15:42:48.202553  [INFO]  Port PRI:01:96. Set signal: 'RING_BACK'
  15:42:48.202644  [INFO]  Conn[0000]: Switch tone '21' for port PRI:01:96: 04:00 -> 00:113
  15:42:48.579148  [INFO]  SIP. Access indication. Hostname '10.3.115.66' ID [27], idx [26] accessible. Host 10.3.115.66:5060 (UDP)
  15:42:48.663687  [INFO]  Port PRI:01:96. Signal RING_BACK active
  15:42:48.663837  [INFO]  Conn[0002]: Switch tone '23' for port PRI:01:96: 04:02 -> 00:113
  15:42:50.518294  [INFO]  SIP. Access indication. Hostname '10.3.41.66' ID [9], idx [8] accessible. Host 10.3.41.66:5060 (UDP)
  15:42:51.102823  [INFO]  SIP. Access indication. Hostname '172.31.253.251' ID [36], idx [35] accessible. Host 172.31.253.251:5060 (UDP)
  15:42:51.480217  [INFO]  SIP. Access indication. Hostname '10.3.87.66' ID [20], idx [19] accessible. Host 10.3.87.66:5060 (UDP)
  15:42:51.551619  [INFO]  SIP. Access indication. Hostname '10.3.75.66' ID [17], idx [16] accessible. Host 10.3.75.66:5060 (UDP)
  15:42:51.570773  [INFO]  SIP. Access indication. Hostname '10.3.29.66' ID [6], idx [5] accessible. Host 10.3.29.66:5060 (UDP)
  15:42:52.387741  [INFO]  SIP. Access indication. Hostname '10.3.127.66' ID [30], idx [29] accessible. Host 10.3.127.66:5060 (UDP)
  15:42:52.521640  [INFO]  SIP. RX. Callref 05c9.   MediaConnected
  15:42:52.521795  [INFO]  SIP. Callref 05c9. Media connected. Mode: 'inactive'
  15:42:52.521897  [INFO]  Port SIPT:05c9. Media-connected for IP-conn [001e]
  15:42:52.521986  [INFO]  Conn[001e]: Media connected indication
  15:42:52.522074  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:52.522229  [INFO]  Port SIPT:05c9. Set IP-conn [001e] inactive
  15:42:52.522322  [INFO]  Conn[001e]: SendRecv set 'inactive' in state 'Disabled'
  15:42:52.536166  [INFO]  SIP. RX. Callref 05c9. Call-ID: 1498-135368-115885
  15:42:52.536334  [INFO]  SIP. RX. Callref 05c9.   ANM-Answer
      [No optional params]
  15:42:52.536466  [INFO]  SIP. Callref 05c9. New state 'SIPT_ACTIVE'
  15:42:52.536625  [INFO]  Port SIPT:05c9. Answer
  15:42:52.536741  [INFO]  Port SIPT:05c9. Set   cdr-time for: answer
  15:42:52.536925  [INFO]  Port SIPT:05c9. Setup   time-stamp: 15:42:48.151 GMT+3 Thu Jun 22 2017
  15:42:52.537069  [INFO]  Port SIPT:05c9. Answer  time-monot: 104047.583
  15:42:52.537237  [INFO]  Port SIPT:05c9. Answer  time-stamp: 15:42:52.583 GMT+3 Thu Jun 22 2017, msec-diff: 4432
  15:42:52.537393  [INFO]  Port SIPT:05c9. Set state 'Talk'
  15:42:52.537494  [INFO]  Port PRI:01:96. Answer from peer (SIPT:05c9)
  15:42:52.537625  [INFO]  Port PRI:01:96. Self: ext, Peer: ext.
  15:42:52.537784  [INFO]  Port SIPT:05c9. Self: ext, Peer: ext.
  15:42:52.537923  [INFO]  Port PRI:01:96. Set   cdr-time for: answer
  15:42:52.538146  [INFO]  Port PRI:01:96. Setup   time-stamp: 15:42:48.115 GMT+3 Thu Jun 22 2017
  15:42:52.538288  [INFO]  Port PRI:01:96. Answer  time-monot: 104047.584
  15:42:52.538377  [INFO]  Port PRI:01:96. Answer  time-stamp: 15:42:52.584 GMT+3 Thu Jun 22 2017, msec-diff: 4469
  15:42:52.538507  [INFO]  Port PRI:01:96. Set state 'Talk'
  15:42:52.538616  [INFO]  Q931. Line 00/01. Call 9B70. Answer
  15:42:52.538784  [INFO]  Port SIPT:05c9. Dir[1], obci-req[0]. Call-ind: change[0], obci[0:0], isdn: allway[0], fwd_acs[1]
  15:42:52.538960  [INFO]  Q931. Line 00/01. Call 9B70. Send 'Answer'. PI-sent[2]
  15:42:52.539059  [INFO]  Port PRI:01:96. Set signal: 'NULL'
  15:42:52.539167  [INFO]  Port PRI:01:96. LinkUp to SIPT:05c9
  15:42:52.539323  [INFO]  Port SIPT:05c9. Activate IP-conn [001e]
  15:42:52.539419  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:52.539550  [INFO]  Q931. Line 00/01. Call 9B70. State 'Active'
  15:42:52.539666  [INFO]  Q931. Line 00/01. Call 9B70. TX 6
DUMP:   08.02.9B.70.07.
      0x1B70 (to orig), MSG=CONNECT:
---------------------------------
  15:42:52.540064  [INFO]  Port PRI:01:96. Answer from peer (SIPT:05c9). Do linkup
  15:42:52.540220  [INFO]  Port PRI:01:96. LinkUp to SIPT:05c9
  15:42:52.540323  [INFO]  Port SIPT:05c9. Activate IP-conn [001e]
  15:42:52.540490  [INFO]  Conn[001e]: try activate. state 'Disabled'. (no media)
  15:42:52.540715  [INFO]  SIP. RX. Callref 05c9.   MediaInfo
      ## IP: <10.1.8.101>
      ## Offer: <0>
      ## v=0
      ## o=ATCOM 3161796843 3161796844 IN IP4 10.1.8.101
      ## s=ATCOM Audio Call
      ## c=IN IP4 10.1.8.101
      ## t=0 0
      ## m=audio 16424 RTP/AVP 8
      ## a=sendrecv
      ## a=rtpmap:8 PCMA/8000
      ## a=ptime:20
  15:42:52.541492  [INFO]  SIP. Callref 05c9. Set Media-Info. Count[1]
  15:42:52.541714  [INFO]  SIP. Callref 05c9. Media-Info (answer): [10.1.8.101]
  15:42:52.541862  [INFO]  Valid
       m[0]: audio 16424 RTP/AVP 8
       a[0]:8 G711.A [set]
       a[1]:ptime:20
       a[2]:sendrecv
  15:42:52.542145  [INFO]  SIP. Callref 05c9. Media negotiate. Interface: 37
  15:42:52.542246  [INFO]  SIP. Callref 05c9. Media negotiate <answer>. [10.1.8.101]. List-count: 1
  15:42:52.542371  [INFO]  SIP. Callref 05c9. Media negotiate. Codec found: G711.A. PT 8. PTE 20. m[1], a[0]. sendrecv
  15:42:52.542474  [INFO]  Port SIPT:05c9. Set IP-conn [001e] sendrecv
  15:42:52.542757  [INFO]  Conn[001e]: SendRecv set 'sendrecv' in state 'Disabled'
  15:42:52.542868  [INFO]  Port SIPT:05c9. IP-conn [001e]. Set Tune
  15:42:52.542996  [INFO]  Conn[001e]: Set tune: vad 'off', cng 'off', ecan 'on'
          pte [20], rtcp_period [0], rtcp_ctrl [0], dscp [0]
          dtmf_mode 'inband' dtmf_pt rx [101] dtmf_pt tx [101]
          fax_mode 'T.38' (1) fax_detect 'no detect'(0) t38transit: 0
          use_vbd 'off' vbd_codec [2] vbd_pt [0]
          NAT: N, cc-transit: 0
  15:42:52.543634  [INFO]  SIP. Callref 05c9. Use codec: G711.A sendrecv. PT 8, PTE 20.
  15:42:52.543749  [INFO]  Port SIPT:05c9. IP-conn [001e]. Set Media-Info: [10.1.8.101:16424]
  15:42:52.543849  [INFO]  Conn[001e]: Set media-info in state 'Disabled'. [10.1.8.101:16424]
  15:42:52.543949  [INFO]  Conn[001e]: [10.1.8.14:29114] <-> [10.1.8.101:16424]
  15:42:52.544081  [INFO]  Conn[001e]: Codec: G.711A , payload [8], DTMF-PT: 101
  15:42:52.544323  [INFO]  Conn[001e]: try get remote MAC for <10.1.8.101>
  15:42:52.544535  [INFO]  Conn[001e]: found   remote MAC for <10.1.8.101> - [80:82:87:03:31:ac]. net-dev [2]
  15:42:52.544635  [INFO]  Conn[001e]: Deactivate in state 'Disabled'
  15:42:52.544789  [INFO]  Conn[001e]: try activate. state 'Disabled'.
  15:42:52.544949  [INFO]  Conn[001e]: Activate [10.1.8.14:29114] <-> [10.1.8.101:16424]
          MAC_loc.src [02:31:52:63:74:80], MAC_loc.dst [02:00:04:00:00:02]
          MAC_ext.src [a8:f9:4b:88:78:dd], MAC_ext.dst [80:82:87:03:31:ac]
  15:42:52.545156  [INFO]  Conn[001e]: Start voice. Codec G711A, vad=off, cng=off. Ptime=20 ms. PT [8] SSRC=1893001e
  15:42:52.545410  [INFO]  Conn[001e]: Start voice. codec-index [5] 'G711A'
  15:42:52.545679  [INFO]  Conn[001e]: Set state 'Disabled'->'ActivateReq'
  15:42:52.545786  [INFO]  Conn[001e]: Set codecs-info in state 'ActivateReq'. Count: 1
  15:42:52.545881  [INFO]  Conn[001e]: G711.A PT [8]
  15:42:52.553323  [INFO]  Q931. Line 00/01. Call 9B70. RX 5
DUMP:   08.02.1B.70.0F.
      0x1B70 (from orig), MSG=CONNECT ACKNOWLEDGE:
---------------------------------
  15:42:52.553603  [INFO]  Conn[001e]: Params set. State 'ActivateReq'
  15:42:52.553782  [INFO]  Conn[001e]: Enable connection
  15:42:52.556909  [INFO]  Conn[001e]: Activated
  15:42:52.557034  [INFO]  Conn[001e]: Set state 'ActivateReq'->'Active'
  15:42:52.557151  [INFO]  Conn[001e]: set rtp-cfg.  <10.1.8.14:29114>:[02.31.52.63.74.80] <-> <10.1.8.101:16424>:[80.82.87.03.31.AC]. flg[9]
  15:42:52.557300  [INFO]  Port SIPT:05c9. Changed IP-conn [001e]
  15:42:52.682990  [INFO]  Conn[001e]: Event 'SSRC Change': new [0x15062994], prev [0x00000000]
  15:42:52.893278  [INFO]  SIP. RX. Callref 05c9. SIP reply code: '200'.
  15:42:52.893470  [INFO]  SIP. RX. Callref 05c9.   REL-Release
      # Cause indicators: [User (U)] [cause=Normal call clearing]
      [No optional params]
  15:42:52.893641  [INFO]  SIP. Callref 05c9. 'REL-Release' with cause <Normal call clearing>(16) in state <SIPT_ACTIVE>
  15:42:52.893745  [INFO]  Port SIPT:05c9. Deactivate IP-conn [001e]
  15:42:52.893833  [INFO]  Conn[001e]: Deactivate in state 'Active'
  15:42:52.893974  [INFO]  Conn[001e]: Set state 'Active'->'DisableReq'
  15:42:52.894077  [INFO]  Port SIPT:05c9. Release Complete, cause 'Normal call clearing', dcl '0', loc '0'
  15:42:52.894173  [INFO]  Port SIPT:05c9. Release cause 'Normal call clearing'(16) check for trunk 38. Release-cause list: 255
  15:42:52.894277  [INFO]  Port SIPT:05c9. Set   cdr-time for: release
  15:42:52.894419  [INFO]  Port SIPT:05c9. Setup   time-stamp: 15:42:48.151 GMT+3 Thu Jun 22 2017
  15:42:52.894596  [INFO]  Port SIPT:05c9. Answer  time-monot: 104047.583
  15:42:52.894687  [INFO]  Port SIPT:05c9. Answer  time-stamp: 15:42:52.583 GMT+3 Thu Jun 22 2017, msec-diff: 4432
  15:42:52.894850  [INFO]  Port SIPT:05c9. Release time-monot: 104047.941
  15:42:52.894938  [INFO]  Port SIPT:05c9. Release time-stamp: 15:42:52.941 GMT+3 Thu Jun 22 2017, msec-diff: 358
  15:42:52.895068  [INFO]  Port SIPT:05c9. Duration sec: 0, msec: 358
  15:42:52.895167  [INFO]  Port SIPT:05c9. Save CDR. disabled. [0x0]
  15:42:52.895266  [INFO]  Port SIPT:05c9. Release peer 'PRI:01:96', cause 'Normal call clearing', dcl '1', loc '0'
  15:42:52.895365  [INFO]  Port PRI:01:96. Disconnect from SIPT:05c9, cause 'Normal call clearing', dcl '1', loc '0'
  15:42:52.895459  [INFO]  Port PRI:01:96. Disconnect, cause 'Normal call clearing', dcl '1', loc '0'
  15:42:52.895554  [INFO]  Port PRI:01:96. Set   cdr-time for: release
  15:42:52.895687  [INFO]  Port PRI:01:96. Setup   time-stamp: 15:42:48.115 GMT+3 Thu Jun 22 2017
  15:42:52.895817  [INFO]  Port PRI:01:96. Answer  time-monot: 104047.584
  15:42:52.895926  [INFO]  Port PRI:01:96. Answer  time-stamp: 15:42:52.584 GMT+3 Thu Jun 22 2017, msec-diff: 4469
  15:42:52.896062  [INFO]  Port PRI:01:96. Release time-monot: 104047.942
  15:42:52.896146  [INFO]  Port PRI:01:96. Release time-stamp: 15:42:52.942 GMT+3 Thu Jun 22 2017, msec-diff: 358
  15:42:52.896234  [INFO]  Port PRI:01:96. Duration sec: 0, msec: 358
  15:42:52.896325  [INFO]  Port PRI:01:96. Save CDR. disabled. [0x0]
  15:42:52.896417  [INFO]  Port PRI:01:96. Set signal: 'BUSY'
  15:42:52.896517  [INFO]  Q931. Line 00/01. Call 9B70. Disconnect call in state 'Active'
  15:42:52.896630  [INFO]  Q931. Line 00/01. Call 9B70. State 'Disconnect Request'
  15:42:52.896726  [INFO]  Q931. Line 00/01. Call 9B70. Timer 'T305' (30 sec) start in state 'Disconnect Request'
  15:42:52.896849  [INFO]  Q931. Line 00/01. Call 9B70. TX 10
DUMP:   08.02.9B.70.45.08.02.80.90.
      0x1B70 (to orig), MSG=DISCONNECT:
      [cause: Normal call clearing (location=user, std=CCITT)]
---------------------------------
  15:42:52.897170  [INFO]  Port PRI:01:96. Set state 'Release'
  15:42:52.897273  [INFO]  Port SIPT:05c9. Free IP-conn [001e]
  15:42:52.897369  [INFO]  Conn[001e]: Destroy in state 'DisableReq'. [check] - set destroy-flg
  15:42:52.897492  [INFO]  Port SIPT:05c9. Set state 'Ready'
  15:42:52.897577  [INFO]  SIP. Callref 05c9. New state 'SIPT_IDLE'
  15:42:52.897839  [INFO]  SIP. TX. Callref 05c9.   RLC-Release_Complete
      [No optional params]
  15:42:52.908129  [INFO]  Conn[001e]: Close flag set - destroy.
  15:42:52.908311  [INFO]  Conn[001e]: Destroy in state 'DisableReq'.
  15:42:52.908415  [INFO]  Port SIPT:05c9. Destroy Conn[001e].
  15:42:52.908503  [INFO]  Conn[001e]: Set state 'DisableReq'->'DeallocReq'
  15:42:52.910036  [INFO]  Conn[001e]: Destroy in state 'DeallocReq'.
  15:42:52.910155  [INFO]  Port SIPT:05c9. Destroy Conn[001e].
  15:42:52.912092  [INFO]  Conn[001e]: Destroy response (destroyed)
  15:42:52.912218  [INFO]  Conn[001e]: clr rtp-cfg.  <10.1.8.14:29114>:[02.31.52.63.74.80] <-> <10.1.8.101:16424>:[80.82.87.03.31.AC]. flg[9]
  15:42:52.912319  [INFO]  Conn[001e]: Set state 'DeallocReq'->'Idle'
  15:42:52.954781  [INFO]  Q931. Line 00/01. Call 9B70. RX 5
DUMP:   08.02.1B.70.4D.
      0x1B70 (from orig), MSG=RELEASE:
---------------------------------
  15:42:52.955032  [INFO]  Q931. Line 00/01. Call 9B70. Recv 'Release'
  15:42:52.955205  [INFO]  Q931. Line 00/01. Call 9B70. TX 6
DUMP:   08.02.9B.70.5A.
      0x1B70 (to orig), MSG=RELEASE COMPLETE:
---------------------------------
  15:42:52.955510  [INFO]  Port PRI:01:96. Release Complete, cause 'Normal, unspecified', dcl '0', loc '0'
  15:42:52.955700  [INFO]  Port PRI:01:96. Save CDR. disabled. [0x0]
  15:42:52.955894  [INFO]  Port PRI:01:96. Set state 'Ready'
  15:42:52.956049  [INFO]  Q931. Line 00/01. Call 9B70. State 'Null'


[Bokrenok]

Вот лог pbx_pstn.log (все уровни в 1):

судя по логу в строну SIP уходит нормальный CgPN: "Number: 1123602"
предлагаю еще и pcap с сетевого интерфейса посмотреть сразу, там явно увидим что уходит на встречную сторону

[Bokrenok]

посмотрел еще немного увидел такие строки:

Код: Выделить всё

  15:42:48.091814  [ERR ]  SIPT[37]. Seize  - calling subscriber category not present


и дальше

Код: Выделить всё

 # Calling party category:  0xff
 

предлагаю через входящий модификатор на Q931-транке сразу задать "категорию АОН", скажем 3 или 0

[LAN]

Тут проблема в :

## Calling party number: 83 07 11 32 06 02
# Nature of address: 0x83 - national
# Numbering plan: 0x00 - spare/not used (0)
# Screening: 0x03 - network prov
# Presentation: 0x01 - presentation restricted
# Number: 1123602

[bravo]

полюбому из-за того, что запрещено показывать cgpn.
При таких вещах всегда полезно ещё посмотреть cdr для быстрого анализа. Если вызов передался, значит в CDR номер зафиксировался. По крайней мере маршрутизация должна быть так настроена, что бы не корректные номера (в том числе и пустые) убивались безотлагательно, кто бы там не звонил. На большинство хитрожопых требований заказчиков уже придуманы сигнальные сообщения и параметры.

[askuratov]

Спасибо, получилось настроить. В модификаторах параметры # Nature of address, # Numbering plan, # Screening, # Presentation изначально были заданы "не изменять". Принудительно задал им значения, в частности, # Presentation: allowed, все заработало.