Автор: DimaSpider 2.11.2019, 10:47
Добрый день! Появилась потребность перейти со схемы E1 <-> LDK300 на схему E1 <-> Asterisk <-> SMG-2 (по E1) <-> LDK300.
E1 <-> Asterisk - работает идеально
Asterisk <-> SMG-2 - вроде работает, по логам во всяком случае
SMG-2 <-> LDK300 - работает непонятно как.
Если звонить с LDK300 на Asterisk звонки проходят, а вот наоборот - нет.
В логах SMG-2 вижу, что звонок идет, номера набирающего и набираемого абонента видны, в KTS Logger вижу, что звонок до АТС доходит, помечен как EXT и на этом все. Так понимаю в отличии от маршрутизации E1 от оператора, тут неправильно выбрана маска номера в DID таблице, с которым на поток направляется звонок для АТС, но к сожалению даже не видно с каким номером проходит звонок к АТС.
У кого какие идеи, как проверить работу и разобраться с маршрутизацией?
Лог с SMG-2 неудачного прозвона Asterisk -> SMG-2 (по E1) <-> LDK300:
Код
2019-10-31 22:20:45.745 app.c !Eltex pa_sip 3.1.6.67. Log started
2019-10-31 22:20:45.745 app.c set log_level 5
2019-10-31 22:20:45.745 ipc.c pbx-ipc SIP: TX (005)
2019-10-31 22:20:45.745 ipc.c 15 24 01 00 05 .$...
2019-10-31 22:20:45.745 ipc.c PBXIPC-SIP. TX. trace
2019-10-31 22:20:45.745 ipc.c # trace-level [5]
2019-10-31 22:20:49.610 sip_endpoint.c !Processing incoming message: Request msg OPTIONS/cseq=25323 (rdata0x31406c)
2019-10-31 22:20:49.610 tsx0x322f3c Transaction created for Request msg OPTIONS/cseq=25323 (rdata0x31406c)
2019-10-31 22:20:49.610 tsx0x322f3c Incoming Request msg OPTIONS/cseq=25323 (rdata0x31406c) in state Null
2019-10-31 22:20:49.610 tsx0x322f3c State changed from Null to Trying, event=RX_MSG
2019-10-31 22:20:49.610 endpoint Response msg 200/OPTIONS/cseq=25323 (tdta0x31c220) created
2019-10-31 22:20:49.610 ipc.c pbx-ipc SIP: TX (031)
2019-10-31 22:20:49.610 ipc.c 0F 22 02 00 18 01 03 02 00 01 00 23 0C 00 33 31 .".........#..xxx
2019-10-31 22:20:49.610 ipc.c 2E 34 34 2E 38 32 2E 32 30 38 05 02 00 14 D7 .xxx.xxx.208.....
2019-10-31 22:20:49.610 ipc.c PBXIPC-SIP. TX. keep-alive-req
2019-10-31 22:20:49.610 ipc.c # requestID 0118
2019-10-31 22:20:49.610 ipc.c # trunkID 01
2019-10-31 22:20:49.610 ipc.c # Hostname 'xxx.xxx.xxx.208'
2019-10-31 22:20:49.610 ipc.c # port 55060
2019-10-31 22:20:49.611 ipc.c !pbx-ipc SIP: RX (262)
2019-10-31 22:20:49.611 ipc.c 10 22 02 00 18 01 03 02 00 01 00 0E F3 00 76 3D ."............v=
2019-10-31 22:20:49.611 ipc.c 30 0D 0A 6F 3D 6D 67 61 70 70 20 30 20 30 20 49 0..o=mgapp 0 0 I
2019-10-31 22:20:49.611 ipc.c 4E 20 49 50 34 20 33 31 2E 34 34 2E 38 32 2E 32 N IP4 xxx.xxx.xxx.2
2019-10-31 22:20:49.611 ipc.c 30 37 0D 0A 73 3D 2D 0D 0A 63 3D 49 4E 20 49 50 07..s=-..c=IN IP
2019-10-31 22:20:49.611 ipc.c 34 20 33 31 2E 34 34 2E 38 32 2E 32 30 37 0D 0A 4 xxx.xxx.xxx.207..
2019-10-31 22:20:49.611 ipc.c 74 3D 30 20 30 0D 0A 6D 3D 61 75 64 69 6F 20 30 t=0 0..m=audio 0
2019-10-31 22:20:49.611 ipc.c 20 52 54 50 2F 41 56 50 20 38 20 30 20 31 38 20 RTP/AVP 8 0 18
2019-10-31 22:20:49.611 ipc.c 31 30 31 0D 0A 61 3D 72 74 70 6D 61 70 3A 38 20 101..a=rtpmap:8
2019-10-31 22:20:49.611 ipc.c 50 43 4D 41 2F 38 30 30 30 0D 0A 61 3D 72 74 70 PCMA/8000..a=rtp
2019-10-31 22:20:49.611 ipc.c 6D 61 70 3A 30 20 50 43 4D 55 2F 38 30 30 30 0D map:0 PCMU/8000.
2019-10-31 22:20:49.611 ipc.c 0A 61 3D 72 74 70 6D 61 70 3A 31 38 20 47 37 32 .a=rtpmap:18 G72
2019-10-31 22:20:49.611 ipc.c 39 2F 38 30 30 30 0D 0A 61 3D 66 6D 74 70 3A 31 9/8000..a=fmtp:1
2019-10-31 22:20:49.611 ipc.c 38 20 61 6E 6E 65 78 62 3D 6E 6F 0D 0A 61 3D 72 8 annexb=no..a=r
2019-10-31 22:20:49.611 ipc.c 74 70 6D 61 70 3A 31 30 31 20 74 65 6C 65 70 68 tpmap:101 teleph
2019-10-31 22:20:49.611 ipc.c 6F 6E 65 2D 65 76 65 6E 74 2F 38 30 30 30 0D 0A one-event/8000..
2019-10-31 22:20:49.611 ipc.c 61 3D 66 6D 74 70 3A 31 30 31 20 30 2D 31 35 0D a=fmtp:101 0-15.
2019-10-31 22:20:49.611 ipc.c 0A 10 02 00 C8 00 ......
2019-10-31 22:20:49.611 ipc.c PBXIPC-SIP. RX. keep-alive-rsp
2019-10-31 22:20:49.611 ipc.c # requestID 0118
2019-10-31 22:20:49.611 ipc.c # trunkID 01
2019-10-31 22:20:49.611 ipc.c # SDP len (243)
2019-10-31 22:20:49.611 ipc.c # cause 200
2019-10-31 22:20:49.611 ipc_msg.c !ipc_msg_parse: msg type = keep-alive-rsp [16]
2019-10-31 22:20:49.612 ipc_msg.c get_ie: ie [34][request-id] size [2]
2019-10-31 22:20:49.612 ipc_msg.c get_ie: ie [3][trunk-id] size [2]
2019-10-31 22:20:49.612 ipc_msg.c get_ie: ie [14][sdp] size [243]
2019-10-31 22:20:49.612 ipc_msg.c get_ie: ie [16][cause] size [2]
2019-10-31 22:20:49.612 ipc.c ipc_process: msg ptr = 0x3238e8. type [16]
2019-10-31 22:20:49.612 mod_pa_pjsip.c keep_alive_reply: cause = 200
2019-10-31 22:20:49.612 tsx0x322f3c Sending Response msg 200/OPTIONS/cseq=25323 (tdta0x31c220) in state Trying
2019-10-31 22:20:49.612 tsx0x322f3c State changed from Trying to Completed, event=TX_MSG
2019-10-31 22:20:50.126 tsx0x31d89c !Timeout timer event
2019-10-31 22:20:50.126 tsx0x31d89c State changed from Completed to Terminated, event=TIMER
2019-10-31 22:20:50.126 tsx0x31d89c Timeout timer event
2019-10-31 22:20:50.126 tsx0x31d89c State changed from Terminated to Destroyed, event=TIMER
2019-10-31 22:20:50.126 tdta0x31ff88 Destroying txdata Response msg 200/OPTIONS/cseq=21042 (tdta0x31ff88)
2019-10-31 22:20:50.126 tsx0x31d89c Transaction destroyed!
2019-10-31 22:20:52.067 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=21072 (rdata0x31406c)
2019-10-31 22:20:52.068 call.c acquire_call: 1001@xxx.xxx.xxx.208:55060 --> 210@xxx.xxx.xxx.207:55060 (call 001f)
2019-10-31 22:20:52.068 call.c call 001f. transport proto: UDP
2019-10-31 22:20:52.068 call.c call_check_isup: call 001f. checking for ISUP presence
2019-10-31 22:20:52.068 call.c call_check_isup: call 001f. ISUP message not found
2019-10-31 22:20:52.068 mod_pa_pjsip.c invite_process: call 001f. Encapsulate ISUP: 'NO'
2019-10-31 22:20:52.068 mod_pa_pjsip.c invite_process: call 001f. Set local-contact "<sip:210@xxx.xxx.xxx.207:55060>"
2019-10-31 22:20:52.068 tsx0x31ffec Transaction created for Request msg INVITE/cseq=21072 (rdata0x31406c)
2019-10-31 22:20:52.068 tsx0x31ffec Incoming Request msg INVITE/cseq=21072 (rdata0x31406c) in state Null
2019-10-31 22:20:52.068 tsx0x31ffec State changed from Null to Trying, event=RX_MSG
2019-10-31 22:20:52.068 dlg0x32394c Transaction tsx0x31ffec state changed to Trying
2019-10-31 22:20:52.068 dlg0x32394c UAS dialog created
2019-10-31 22:20:52.068 mod_pa_pjsip.c invite_process: call 001f. Dialog created
2019-10-31 22:20:52.069 dlg0x32394c Module mod-invite added as dialog usage, data=0x320bb4
2019-10-31 22:20:52.069 dlg0x32394c Session count inc to 2 by mod-invite
2019-10-31 22:20:52.069 inv0x32394c UAS invite session created for dialog dlg0x32394c
2019-10-31 22:20:52.069 endpoint Response msg 100/INVITE/cseq=21072 (tdta0x318d68) created
2019-10-31 22:20:52.069 dlg0x32394c Initial answer Response msg 100/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:20:52.069 inv0x32394c Sending Response msg 100/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:20:52.069 dlg0x32394c Sending Response msg 100/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:20:52.069 tsx0x31ffec Sending Response msg 100/INVITE/cseq=21072 (tdta0x318d68) in state Trying
2019-10-31 22:20:52.069 tsx0x31ffec State changed from Trying to Proceeding, event=TX_MSG
2019-10-31 22:20:52.069 dlg0x32394c Transaction tsx0x31ffec state changed to Proceeding
2019-10-31 22:20:52.069 call.c call_on_state_changed: call 001f. changed to INCOMING state with event TSX_STATE
2019-10-31 22:20:52.069 ipc.c pbx-ipc SIP: TX (985)
2019-10-31 22:20:52.069 ipc.c 01 01 02 00 1F 00 25 24 00 64 34 36 63 63 65 33 ......%%$.d46cce3
2019-10-31 22:20:52.069 ipc.c 35 2D 35 38 64 63 2D 34 38 33 36 2D 39 64 66 61 5-58dc-4836-9dfa
2019-10-31 22:20:52.069 ipc.c 2D 63 37 35 39 63 65 63 37 62 30 66 61 03 02 00 -c759cec7b0fa...
2019-10-31 22:20:52.069 ipc.c 01 00 04 04 00 D0 52 2C 1F 23 0C 00 33 31 2E 34 ......R,.#..xxx.xx
2019-10-31 22:20:52.069 ipc.c 34 2E 38 32 2E 32 30 38 12 21 00 D0 A1 D0 BC D0 x.xxx.208.!......
2019-10-31 22:20:52.069 ipc.c BE D0 BB D1 8C D1 81 D0 BA D0 B8 D0 B9 20 D0 94 ............. ..
2019-10-31 22:20:52.069 ipc.c D0 BC D0 B8 D1 82 D1 80 D0 B8 D0 B9 05 02 00 14 ................
2019-10-31 22:20:52.069 ipc.c D7 07 02 00 14 D7 08 2F 00 00 00 00 00 00 00 00 ......./........
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 03 00 00 00 32 31 30 00 00 00 00 00 ........210.....
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 09 2F 00 00 00 00 00 00 ........./......
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 03 00 00 00 32 31 30 00 00 00 ..........210...
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 00 00 0A 2F 00 00 00 00 .........../....
2019-10-31 22:20:52.070 ipc.c 0A 00 00 00 00 00 00 00 04 00 00 00 31 30 30 31 ............1001
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2019-10-31 22:20:52.070 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 0E CA 02 76 ...............v
2019-10-31 22:20:52.070 ipc.c 3D 30 0D 0A 6F 3D 2D 20 31 32 35 30 35 34 37 31 =0..o=- 12505471
2019-10-31 22:20:52.070 ipc.c 32 33 20 31 32 35 30 35 34 37 31 32 33 20 49 4E 23 1250547123 IN
2019-10-31 22:20:52.070 ipc.c 20 49 50 34 20 33 31 2E 34 34 2E 38 32 2E 32 30 IP4 xxx.xxx.xxx.20
2019-10-31 22:20:52.070 ipc.c 38 0D 0A 73 3D 41 73 74 65 72 69 73 6B 0D 0A 63 8..s=Asterisk..c
2019-10-31 22:20:52.070 ipc.c 3D 49 4E 20 49 50 34 20 33 31 2E 34 34 2E 38 32 =IN IP4 xxx.xxx.xxx
2019-10-31 22:20:52.070 ipc.c 2E 32 30 38 0D 0A 74 3D 30 20 30 0D 0A 6D 3D 61 .208..t=0 0..m=a
2019-10-31 22:20:52.070 ipc.c 75 64 69 6F 20 31 33 39 32 36 20 52 54 50 2F 41 udio 13926 RTP/A
2019-10-31 22:20:52.070 ipc.c 56 50 20 30 20 38 20 31 38 20 39 37 20 31 30 31 VP 0 8 18 97 101
2019-10-31 22:20:52.070 ipc.c 0D 0A 61 3D 69 63 65 2D 75 66 72 61 67 3A 32 61 ..a=ice-ufrag:2a
2019-10-31 22:20:52.070 ipc.c 61 32 63 66 64 63 34 37 63 63 37 35 39 63 36 31 a2cfdc47cc759c61
2019-10-31 22:20:52.070 ipc.c 65 36 32 37 62 65 37 32 34 62 66 65 38 35 0D 0A e627be724bfe85..
2019-10-31 22:20:52.070 ipc.c 61 3D 69 63 65 2D 70 77 64 3A 37 30 31 31 61 61 a=ice-pwd:7011aa
2019-10-31 22:20:52.070 ipc.c 65 62 32 65 36 65 61 36 65 31 31 36 37 63 33 32 eb2e6ea6e1167c32
2019-10-31 22:20:52.070 ipc.c 36 63 37 64 65 62 37 33 32 62 0D 0A 61 3D 63 61 6c7deb732b..a=ca
2019-10-31 22:20:52.070 ipc.c 6E 64 69 64 61 74 65 3A 48 63 30 61 38 30 35 30 ndidate:Hc0a8050
2019-10-31 22:20:52.070 ipc.c 66 20 31 20 55 44 50 20 32 31 33 30 37 30 36 34 f 1 UDP 21307064
2019-10-31 22:20:52.070 ipc.c 33 31 20 31 39 32 2E 31 36 38 2E 35 2E 31 35 20 31 192.168.xxx.xxx
2019-10-31 22:20:52.070 ipc.c 31 33 39 32 36 20 74 79 70 20 68 6F 73 74 0D 0A 13926 typ host..
2019-10-31 22:20:52.070 ipc.c 61 3D 63 61 6E 64 69 64 61 74 65 3A 48 31 66 32 a=candidate:H1f2
2019-10-31 22:20:52.070 ipc.c 63 35 32 64 30 20 31 20 55 44 50 20 32 31 33 30 c52d0 1 UDP 2130
2019-10-31 22:20:52.070 ipc.c 37 30 36 34 33 31 20 33 31 2E 34 34 2E 38 32 2E 706431 xxx.xxx.xxx.
2019-10-31 22:20:52.070 ipc.c 32 30 38 20 31 33 39 32 36 20 74 79 70 20 68 6F 208 13926 typ ho
2019-10-31 22:20:52.070 ipc.c 73 74 0D 0A 61 3D 63 61 6E 64 69 64 61 74 65 3A st..a=candidate:
2019-10-31 22:20:52.070 ipc.c 48 63 30 61 38 30 35 30 66 20 32 20 55 44 50 20 Hc0a8050f 2 UDP
2019-10-31 22:20:52.071 ipc.c 32 31 33 30 37 30 36 34 33 30 20 31 39 32 2E 31 2130706430 192.1
2019-10-31 22:20:52.071 ipc.c 36 38 2E 35 2E 31 35 20 31 33 39 32 37 20 74 79 68.5.15 13927 ty
2019-10-31 22:20:52.071 ipc.c 70 20 68 6F 73 74 0D 0A 61 3D 63 61 6E 64 69 64 p host..a=candid
2019-10-31 22:20:52.071 ipc.c 61 74 65 3A 48 31 66 32 63 35 32 64 30 20 32 20 ate:H1f2c52d0 2
2019-10-31 22:20:52.071 ipc.c 55 44 50 20 32 31 33 30 37 30 36 34 33 30 20 33 UDP 2130706430 x
2019-10-31 22:20:52.071 ipc.c 31 2E 34 34 2E 38 32 2E 32 30 38 20 31 33 39 32 xx.xxx.xxx.208 1392
2019-10-31 22:20:52.071 ipc.c 37 20 74 79 70 20 68 6F 73 74 0D 0A 61 3D 72 74 7 typ host..a=rt
2019-10-31 22:20:52.071 ipc.c 70 6D 61 70 3A 30 20 50 43 4D 55 2F 38 30 30 30 pmap:0 PCMU/8000
2019-10-31 22:20:52.071 ipc.c 0D 0A 61 3D 72 74 70 6D 61 70 3A 38 20 50 43 4D ..a=rtpmap:8 PCM
2019-10-31 22:20:52.071 ipc.c 41 2F 38 30 30 30 0D 0A 61 3D 72 74 70 6D 61 70 A/8000..a=rtpmap
2019-10-31 22:20:52.071 ipc.c 3A 31 38 20 47 37 32 39 2F 38 30 30 30 0D 0A 61 :18 G729/8000..a
2019-10-31 22:20:52.071 ipc.c 3D 66 6D 74 70 3A 31 38 20 61 6E 6E 65 78 62 3D =fmtp:18 annexb=
2019-10-31 22:20:52.071 ipc.c 6E 6F 0D 0A 61 3D 72 74 70 6D 61 70 3A 39 37 20 no..a=rtpmap:97
2019-10-31 22:20:52.071 ipc.c 69 4C 42 43 2F 38 30 30 30 0D 0A 61 3D 66 6D 74 iLBC/8000..a=fmt
2019-10-31 22:20:52.071 ipc.c 70 3A 39 37 20 6D 6F 64 65 3D 32 30 0D 0A 61 3D p:97 mode=20..a=
2019-10-31 22:20:52.071 ipc.c 72 74 70 6D 61 70 3A 31 30 31 20 74 65 6C 65 70 rtpmap:101 telep
2019-10-31 22:20:52.071 ipc.c 68 6F 6E 65 2D 65 76 65 6E 74 2F 38 30 30 30 0D hone-event/8000.
2019-10-31 22:20:52.071 ipc.c 0A 61 3D 66 6D 74 70 3A 31 30 31 20 30 2D 31 36 .a=fmtp:101 0-16
2019-10-31 22:20:52.071 ipc.c 0D 0A 61 3D 70 74 69 6D 65 3A 32 30 0D 0A 61 3D ..a=ptime:20..a=
2019-10-31 22:20:52.071 ipc.c 6D 61 78 70 74 69 6D 65 3A 32 30 0D 0A 61 3D 73 maxptime:20..a=s
2019-10-31 22:20:52.071 ipc.c 65 6E 64 72 65 63 76 0D 0A endrecv..
2019-10-31 22:20:52.071 ipc.c PBXIPC-SIP. TX. seize
2019-10-31 22:20:52.071 ipc.c # callref 001f
2019-10-31 22:20:52.071 ipc.c # Call-ID len (36)
2019-10-31 22:20:52.071 ipc.c # trunkID 01
2019-10-31 22:20:52.071 ipc.c # ipaddr xxx.xxx.xxx.208
2019-10-31 22:20:52.071 ipc.c # Hostname 'xxx.xxx.xxx.208'
2019-10-31 22:20:52.071 ipc.c # Display name 'Смольский Дмитрий'
2019-10-31 22:20:52.071 ipc.c # port 55060
2019-10-31 22:20:52.071 ipc.c # local-port 55060
2019-10-31 22:20:52.071 ipc.c # cdpn <210> (3) cat 0x00
2019-10-31 22:20:52.072 ipc.c # to_cdpn <210> (3) cat 0x00
2019-10-31 22:20:52.072 ipc.c # cgpn <1001> (4) cat 0x0a
2019-10-31 22:20:52.072 ipc.c # SDP len (714)
2019-10-31 22:20:52.073 call.c call_on_tsx_state_changed: call 001f. tsx 'tsx0x31ffec', role UAS, method INVITE, event TX_MSG, tsx-state: Proceeding
2019-10-31 22:21:07.043 ipc.c !pbx-ipc SIP: RX (016)
2019-10-31 22:21:07.043 ipc.c 06 01 02 00 1F 00 29 02 00 66 00 10 02 00 F8 01 ......)..f......
2019-10-31 22:21:07.043 ipc.c PBXIPC-SIP. RX. rel-req
2019-10-31 22:21:07.043 ipc.c # callref 001f
2019-10-31 22:21:07.043 ipc.c # q850-cause 102
2019-10-31 22:21:07.043 ipc.c # cause 504
2019-10-31 22:21:07.055 ipc_msg.c !ipc_msg_parse: msg type = rel-req [6]
2019-10-31 22:21:07.055 ipc_msg.c get_ie: ie [1][callref] size [2]
2019-10-31 22:21:07.055 ipc_msg.c get_ie: ie [41][q850-cause] size [2]
2019-10-31 22:21:07.055 ipc_msg.c get_ie: ie [16][cause] size [2]
2019-10-31 22:21:07.055 ipc.c ipc_process: msg ptr = 0x31f170. type [6]
2019-10-31 22:21:07.055 call.c call_release_req: call 001f. release req w/cause 504 q850-cause: 102
2019-10-31 22:21:07.055 call.c call_end_session: call 001f. End session with response 504 (q850-cause: 102)
2019-10-31 22:21:07.055 inv0x32394c End session in state 'incoming' with code 504 (terminate pending)
2019-10-31 22:21:07.055 call.c add_isup_rel_reason_header: call 001f. Adding Reason header: "Q.850;cause=102;text="Recovery of timer expiry""
2019-10-31 22:21:07.055 inv0x32394c Sending Response msg 504/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:21:07.055 dlg0x32394c Sending Response msg 504/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:21:07.055 tsx0x31ffec Sending Response msg 504/INVITE/cseq=21072 (tdta0x318d68) in state Proceeding
2019-10-31 22:21:07.056 tsx0x31ffec State changed from Proceeding to Completed, event=TX_MSG
2019-10-31 22:21:07.056 dlg0x32394c Transaction tsx0x31ffec state changed to Completed
2019-10-31 22:21:07.056 call.c call_on_state_changed: call 001f. changed to DISCONNCTD state with event TSX_STATE
2019-10-31 22:21:07.056 call.c call_state_disconnected: call 001f. Disconnected. Event type TSX_STATE with response 504
2019-10-31 22:21:07.056 ipc.c pbx-ipc SIP: TX (011)
2019-10-31 22:21:07.056 ipc.c 07 01 02 00 1F 00 10 02 00 F8 01 ...........
2019-10-31 22:21:07.056 ipc.c PBXIPC-SIP. TX. rel-cplt
2019-10-31 22:21:07.056 ipc.c # callref 001f
2019-10-31 22:21:07.056 ipc.c # cause 504
2019-10-31 22:21:07.056 dlg0x32394c Session count dec to 2 by mod-invite
2019-10-31 22:21:07.056 call.c call_on_tsx_state_changed: inv <0x320bb4>, mod-data <(nil)>
2019-10-31 22:21:07.067 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=21072 (rdata0x31406c)
2019-10-31 22:21:07.067 tsx0x31ffec Incoming Request msg ACK/cseq=21072 (rdata0x31406c) in state Completed
2019-10-31 22:21:07.067 tsx0x31ffec State changed from Completed to Confirmed, event=RX_MSG
2019-10-31 22:21:07.067 dlg0x32394c Transaction tsx0x31ffec state changed to Confirmed
2019-10-31 22:21:07.067 call.c call_on_tsx_state_changed: inv <0x320bb4>, mod-data <(nil)>
2019-10-31 22:21:07.076 tsx0x31ffec !Timeout timer event
2019-10-31 22:21:07.076 tsx0x31ffec State changed from Confirmed to Terminated, event=TIMER
2019-10-31 22:21:07.076 dlg0x32394c Transaction tsx0x31ffec state changed to Terminated
2019-10-31 22:21:07.076 call.c call_on_tsx_state_changed: inv <0x320bb4>, mod-data <(nil)>
2019-10-31 22:21:07.076 dlg0x32394c Dialog destroyed
2019-10-31 22:21:07.077 tsx0x31ffec Timeout timer event
2019-10-31 22:21:07.077 tsx0x31ffec State changed from Terminated to Destroyed, event=TIMER
2019-10-31 22:21:07.077 tdta0x318d68 Destroying txdata Response msg 504/INVITE/cseq=21072 (tdta0x318d68)
2019-10-31 22:21:07.077 tsx0x31ffec Transaction destroyed!
2019-10-31 22:21:10.017 tsx0x316c3c !Timeout timer event
2019-10-31 22:21:10.017 tsx0x316c3c State changed from Completed to Terminated, event=TIMER
2019-10-31 22:21:10.017 tsx0x316c3c Timeout timer event
2019-10-31 22:21:10.017 tsx0x316c3c State changed from Terminated to Destroyed, event=TIMER
2019-10-31 22:21:10.017 tdta0x31a498 Destroying txdata Response msg 200/OPTIONS/cseq=943 (tdta0x31a498)
2019-10-31 22:21:10.017 tsx0x316c3c Transaction destroyed!
2019-10-31 22:21:17.982 sip_endpoint.c !Processing incoming message: Request msg OPTIONS/cseq=5195 (rdata0x31406c)
2019-10-31 22:21:17.982 tsx0x31b90c Transaction created for Request msg OPTIONS/cseq=5195 (rdata0x31406c)
2019-10-31 22:21:17.982 tsx0x31b90c Incoming Request msg OPTIONS/cseq=5195 (rdata0x31406c) in state Null
2019-10-31 22:21:17.982 tsx0x31b90c State changed from Null to Trying, event=RX_MSG
2019-10-31 22:21:17.982 endpoint Response msg 200/OPTIONS/cseq=5195 (tdta0x31d228) created
2019-10-31 22:21:17.982 ipc.c pbx-ipc SIP: TX (031)
2019-10-31 22:21:17.982 ipc.c 0F 22 02 00 19 01 03 02 00 01 00 23 0C 00 33 31 .".........#..xxx
2019-10-31 22:21:17.982 ipc.c 2E 34 34 2E 38 32 2E 32 30 38 05 02 00 14 D7 .xxx.xxx.208.....
2019-10-31 22:21:17.982 ipc.c PBXIPC-SIP. TX. keep-alive-req
2019-10-31 22:21:17.982 ipc.c # requestID 0119
2019-10-31 22:21:17.982 ipc.c # trunkID 01
2019-10-31 22:21:17.982 ipc.c # Hostname 'xxx.xxx.xxx.208'
2019-10-31 22:21:17.982 ipc.c # port 55060
2019-10-31 22:21:17.983 ipc.c !pbx-ipc SIP: RX (262)
2019-10-31 22:21:17.983 ipc.c 10 22 02 00 19 01 03 02 00 01 00 0E F3 00 76 3D ."............v=
2019-10-31 22:21:17.983 ipc.c 30 0D 0A 6F 3D 6D 67 61 70 70 20 30 20 30 20 49 0..o=mgapp 0 0 I
2019-10-31 22:21:17.983 ipc.c 4E 20 49 50 34 20 33 31 2E 34 34 2E 38 32 2E 32 N IP4 xxx.xxx.xxx.2
2019-10-31 22:21:17.983 ipc.c 30 37 0D 0A 73 3D 2D 0D 0A 63 3D 49 4E 20 49 50 07..s=-..c=IN IP
2019-10-31 22:21:17.983 ipc.c 34 20 33 31 2E 34 34 2E 38 32 2E 32 30 37 0D 0A 4 xxx.xxx.xxx.207..
2019-10-31 22:21:17.983 ipc.c 74 3D 30 20 30 0D 0A 6D 3D 61 75 64 69 6F 20 30 t=0 0..m=audio 0
2019-10-31 22:21:17.983 ipc.c 20 52 54 50 2F 41 56 50 20 38 20 30 20 31 38 20 RTP/AVP 8 0 18
2019-10-31 22:21:17.983 ipc.c 31 30 31 0D 0A 61 3D 72 74 70 6D 61 70 3A 38 20 101..a=rtpmap:8
2019-10-31 22:21:17.983 ipc.c 50 43 4D 41 2F 38 30 30 30 0D 0A 61 3D 72 74 70 PCMA/8000..a=rtp
2019-10-31 22:21:17.983 ipc.c 6D 61 70 3A 30 20 50 43 4D 55 2F 38 30 30 30 0D map:0 PCMU/8000.
2019-10-31 22:21:17.983 ipc.c 0A 61 3D 72 74 70 6D 61 70 3A 31 38 20 47 37 32 .a=rtpmap:18 G72
2019-10-31 22:21:17.983 ipc.c 39 2F 38 30 30 30 0D 0A 61 3D 66 6D 74 70 3A 31 9/8000..a=fmtp:1
2019-10-31 22:21:17.983 ipc.c 38 20 61 6E 6E 65 78 62 3D 6E 6F 0D 0A 61 3D 72 8 annexb=no..a=r
2019-10-31 22:21:17.983 ipc.c 74 70 6D 61 70 3A 31 30 31 20 74 65 6C 65 70 68 tpmap:101 teleph
2019-10-31 22:21:17.983 ipc.c 6F 6E 65 2D 65 76 65 6E 74 2F 38 30 30 30 0D 0A one-event/8000..
2019-10-31 22:21:17.983 ipc.c 61 3D 66 6D 74 70 3A 31 30 31 20 30 2D 31 35 0D a=fmtp:101 0-15.
2019-10-31 22:21:17.983 ipc.c 0A 10 02 00 C8 00 ......
2019-10-31 22:21:17.983 ipc.c PBXIPC-SIP. RX. keep-alive-rsp
2019-10-31 22:21:17.983 ipc.c # requestID 0119
2019-10-31 22:21:17.983 ipc.c # trunkID 01
2019-10-31 22:21:17.983 ipc.c # SDP len (243)
2019-10-31 22:21:17.983 ipc.c # cause 200
2019-10-31 22:21:17.989 ipc_msg.c !ipc_msg_parse: msg type = keep-alive-rsp [16]
2019-10-31 22:21:17.989 ipc_msg.c get_ie: ie [34][request-id] size [2]
2019-10-31 22:21:17.990 ipc_msg.c get_ie: ie [3][trunk-id] size [2]
2019-10-31 22:21:17.990 ipc_msg.c get_ie: ie [14][sdp] size [243]
2019-10-31 22:21:17.990 ipc_msg.c get_ie: ie [16][cause] size [2]
2019-10-31 22:21:17.990 ipc.c ipc_process: msg ptr = 0x3238e8. type [16]
2019-10-31 22:21:17.990 mod_pa_pjsip.c keep_alive_reply: cause = 200
2019-10-31 22:21:17.990 tsx0x31b90c Sending Response msg 200/OPTIONS/cseq=5195 (tdta0x31d228) in state Trying
2019-10-31 22:21:17.990 tsx0x31b90c State changed from Trying to Completed, event=TX_MSG
2019-10-31 22:21:18.250 ipc.c !pbx-ipc SIP: RX (005)
2019-10-31 22:21:18.250 ipc.c 15 24 01 00 00 .$...
2019-10-31 22:21:18.250 ipc.c PBXIPC-SIP. RX. trace
2019-10-31 22:21:18.250 ipc.c # trace-level [0]
2019-10-31 22:21:18.252 ipc_msg.c !ipc_msg_parse: msg type = trace [21]
2019-10-31 22:21:18.253 ipc_msg.c get_ie: ie [36][trace-level] size [1]
2019-10-31 22:21:18.253 ipc.c ipc_process: msg ptr = 0x316bd8. type [21]
2019-10-31 22:21:18.269 app.c set log_level 0
Вот что показывал логгер с АТС:
Код
NO STA CO TIME START DIALED ACT CNT COST ACCOUNT CODE
---- ----- --- -------- -------------- -------- ---------- ----------- ------------
• 1978 210 021 00:00:19 31/10/19 22:22 O1001 ** 0 0
1979 EXT 006 00:00:00 31/10/19 22:27 R1001
1980 EXT 007 00:04:00 31/10/19 22:28 R1001
На станции сделал трассировку при звонке 1001(asterisk) > 210(ldk300)
Код
687846 COL 002:08 02 St:co idle (00) EVT :D5 13 05 04 03 80 90 A3 18 03 A9 83 82 6C 06 01 83 31 30 30 31 (U0)
687847 C>08 02, D6 06 0D 18 03 A9 83 82
687847 COL 002:08 02 St:co idle (00) Ev-I:ring start P1: 0 P2: 0 EVT: 11 <- 2,61
687847 D>08 02, C0 00 61
687847 COL 002:08 02 St:di-dialing(00) Ev-I:dummy acd P1:26F3 P2: 0 EVT: 0 <- 2,61
2325 EXT 002 00:00:00 02/11/19 10:15 R1001
687997 COL 002:08 02 St:di-dialing(00) ABNORMAL ISDN MSG: evt_no=25, p1=FF p2=02
687997 C>08 02, DE 05 45 08 02 80 BF
687997 D>08 02, C1 00 00
687997 D>08 02, C5 00 00
687997 D>08 02, C5 00 00
687997 COL 002:08 02 St:co idle (00) EVT :DF 05 4D 08 02 80 E6 (U0)
687997 C>08 02, E0 05 5A 08 02 80 E6
688000 COL 002:08 02 St:co idle (00) Ev-T:isd rls gd P1: 0 P2: 0 TMR: 9 <- 2,61
688000 D>08 02, C5 00 00
11/02/19 TIME: 10:16:00
Кстати, ABNORMAL ISDN MSG появляется и при подключении потока к станции. Может его не донастроил? Хотя при звонке с ldk на asterisk все ok. С потоком от оператора такого нет, хотя оператор используют такую же игрушку.
Автор: AXEL 2.11.2019, 19:01
LDK отбивает с причиной Cause Value = 63 Service or option not available, unspecified
В трассировке нет набранного номера
Автор: DimaSpider 11.11.2019, 10:05
Добрый день! Проблему решил.
Сбросил на заводские SMG-2, по новой настроил по инструкции awsswa.livejournal.com/28119.html и все заработало. Передача номера на АТС пошла. Ничего нового вообщем-то и не было сделано в настройках, до этого делалось по той же инструкции, кроме как сделал обновление прошивки и сброс после обновления до заводских, но может именно это и помогло.