Тестируем ваше оборудование и при работе с IVR вылез непонятный казус.
IVR простейший: Ring->Info->Dial
И при вызове номера IVR с h323 транка разговор начинается, но в трубке тишина и последующий Dial не проходит. Все это отбивается по таймауту через минуту.
Ниже PBX PSTN трассировка (уровни трассировок стоят в 99 на "Вызовы", H.323 и IVR). При звонке с SIP транка и внутреннего номера такого не происходит.
P.S. Системное инфо:
Код: Выделить всё
Программное обеспечение:
Версия ПО V.3.18.0.3977 200/PBX/H323/VAS/REC/IVR Build: Sep 11 2020 18:41:34
Версия SIP-адаптера 3.18.0.67
IVR модуль 0.0.0.8642.079494
Заводские параметры:
Модель SMG-200
Ревизия 1V3
Серийный номер VI55000470
MAC адрес E0:D9:E3:98:6C:6D
Лицензии:
SMG-PBX (100)
SMG-H323
SMG-VAS
SMG-REC
SMG-IVR
Код: Выделить всё
SMG-200. 1V3 SN: VI55000470 Software Version: V.3.18.0.3977 200/PBX/H323/VAS/REC/IVR Build: Sep 11 2020 18:41:34
Log started at: 11:41:07.158006 27/11/20
Trace levels:
alarm - 01
calls - 99
isup - 01
q931 - 01
sipt - 01
rtp - 01
msp - 00
radius - 01
h323 - 99
ivr - 99
fxs/fxo - 01
11:41:08.864158 [INFO] pbx-ipc H323: RX (170)
11:41:08.864208 [INFO] 01 01 02 00 4E 00 03 02 00 01 00 04 04 00 F6 CA ....N...........
11:41:08.864219 [INFO] A8 C0 05 02 00 B8 06 06 04 00 00 00 00 00 07 02 ................
11:41:08.864229 [INFO] 00 00 00 0A 39 00 01 01 00 00 00 00 00 00 00 00 ....9...........
11:41:08.864238 [INFO] 00 04 00 00 00 33 39 31 31 00 00 00 00 00 00 00 .....3911.......
11:41:08.864246 [INFO] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
11:41:08.864255 [INFO] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0C ................
11:41:08.864263 [INFO] 39 00 01 00 02 31 00 00 00 00 00 00 00 04 00 00 9....1..........
11:41:08.864271 [INFO] 00 33 32 32 35 00 00 00 00 00 00 00 00 00 00 00 .3225...........
11:41:08.864279 [INFO] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
11:41:08.864287 [INFO] 00 00 00 00 00 00 00 00 00 00 00 21 01 00 10 18 ...........!....
11:41:08.864295 [INFO] 08 00 42 61 6C 6B 61 6E 6F 76 ..Balkanov
11:41:08.864306 [INFO] PBXIPC-H323. RX. seize
11:41:08.864314 [INFO] # callref 004e
11:41:08.864323 [INFO] # trunkID 01
11:41:08.864333 [INFO] # ipaddr 192.168.202.246
11:41:08.864341 [INFO] # port 1720
11:41:08.864351 [INFO] # local-ipaddr 0.0.0.0
11:41:08.864359 [INFO] # local-port 0
11:41:08.864368 [INFO] # cdpn <3911> (4) cat 00 <unknown> <unknown>
11:41:08.864377 [INFO] # cgpn <3225> (4) cat 0x31 <national> <unknown>
11:41:08.864387 [INFO] # BearerCapability (16)
11:41:08.864395 [INFO] # Display name 'Balkanov'
11:41:08.864404 [INFO] H323. Got message 'seize' len [170]
11:41:08.864436 [INFO] H323. got msg [1] len 170
11:41:08.864455 [INFO] H323. Process message 'seize'
11:41:08.864487 [INFO] H323: get intarface by IP count [2]. search: [192.168.202.246:0] [0]
11:41:08.864499 [INFO] H323: get intarface by IP intf[0]. ip:port [192.168.202.246:1720] [0]
11:41:08.864509 [INFO] H323: get intarface by IP got [0]
11:41:08.864524 [INFO] [C0000CE] H323. Callref 004e. Incoming seize mode:
11:41:08.864524 [INFO] [C0000CE] # source host <192.168.202.246:1720> Balkanov
11:41:08.864524 [INFO] [C0000CE] # Port H323:004e. Intf: 0.
11:41:08.864524 [INFO] [C0000CE] # Category 0x31 (49).
11:41:08.864524 [INFO] [C0000CE] # Calling <3225> Called <3911>
11:41:08.864524 [INFO] [C0000CE] # cplt=y; pres=allow; nature=not subscriber.
11:41:08.864537 [INFO] [C0000CE] H323. Callref 004e. Interface 0
11:41:08.864548 [INFO] H323. Interface[00]. Max-active[0], curr-active[1]
11:41:08.864564 [INFO] [C0000CE] Port H323:004e. Set cdr-time for: setup
11:41:08.864582 [INFO] [C0000CE] Port H323:004e. Setup time-stamp: 11:41:08.199 IRKT Fri Nov 27 2020
11:41:08.864600 [INFO] [C0000CE] Port H323:004e. Seize (v-chan:-1, TG:00). Cld:'<none>'(0). (Trunk) . Clg-len: 4. clg-set: y. COS: -1. Pwd=00. RADIUS=-1
11:41:08.864614 [INFO] [C0000CE] Port H323:004e. ModTable in [-1:-1], out [-1:-1], out redir:orig [-1:-1]
11:41:08.864628 [INFO] [C0000CE] Port H323:004e. CgPN: <3225>(4) <set> <national> <no-change> <allowed> <not verified>. cat [1]. ss7 [0x00:0x00]
11:41:08.864642 [INFO] [C0000CE] Port H323:004e. Calling number: 3225
11:41:08.864654 [INFO] [C0000CE] Port H323:004e. Calling Info (pres allowed): <3225>. <Category=1>
11:41:08.864665 [INFO] [C0000CE] Port H323:004e. CgPN: <3225>(4) <set> <national> <no-change> <allowed> <not verified>. cat [1]. ss7 [0x00:0x00]
11:41:08.864677 [INFO] [C0000CE] Port H323:004e. Set state 'In-Dial'
11:41:08.864714 [INFO] [C0000CE] Port H323:004e. Active call count [02]
11:41:08.864795 [INFO] [C0000CE] SConn[0059]: allocated with <192.168.111.138:20690> for Port H323:004e
11:41:08.864811 [INFO] [C0000CE] Port H323:004e. Set new IP-conn [0059]
11:41:08.864822 [INFO] [C0000CE] Port H323:004e. Created IP-conn [0059]
11:41:08.864833 [INFO] [C0000CE] SConn[0059]: set network interface in state 'None'. IP <192.168.111.138>. net-dev [0]
11:41:08.864877 [INFO] [C0000CE] SConn[0059]: Get media-info <192.168.111.138:20690>
11:41:08.864891 [INFO] [C0000CE] H323. Callref 004e. IP-conn [0059] created
11:41:08.864903 [INFO] [C0000CE] H323. Callref 004e. Set display name (from coding 'avaya'): 'Balkanov'(8)
11:41:08.864916 [INFO] [C0000CE] Port H323:004e. Set CallerId: 'Balkanov' (8). Peer -. Not linked
11:41:08.864926 [INFO] [C0000CE] H323. Callref 004e. Incoming seize - PLAN 0. COS 0
11:41:08.864941 [INFO] [C0000CE] Port H323:004e. Dial In (in=04, need rm=0): <3><9><1><1>
11:41:08.864959 [INFO] [C0000CE] Port H323:004e. In Digit 4 sym: called buf <3911>
11:41:08.864968 [INFO] [C0000CE] Port H323:004e. Dial buf <3911>(4)
11:41:08.864977 [INFO] [C0000CE] H323. Interface[00]. Callref 004e. Inc active[1].
11:41:08.864986 [INFO] [C0000CE] H323. Interface[00]. Max-active[0], curr-active[2]
11:41:08.864996 [INFO] [C0000CE] H323. Callref 004e. Prepare media with mode 'audio'
11:41:08.865005 [INFO] [C0000CE] H323. Callref 004e. Prepare Audio Media.
11:41:08.865015 [INFO] [C0000CE] H323. Callref 004e. New state 'H323_PRESENTED'
11:41:08.865025 [INFO] [C0000CE] H323. Callref 004e. Send proceeding
11:41:08.865035 [INFO] pbx-ipc H323: TX (163)
11:41:08.865044 [INFO] 02 01 02 00 4E 00 1E 04 00 8A 6F A8 C0 1F 02 00 ....N.....o.....
11:41:08.865053 [INFO] D2 50 20 8E 00 01 00 00 00 8A 6F A8 C0 00 00 00 .P .......o.....
11:41:08.865061 [INFO] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
11:41:08.865069 [INFO] 00 00 00 D2 50 00 00 00 00 00 00 00 00 00 00 00 ....P...........
11:41:08.865077 [INFO] 00 00 00 00 00 00 00 00 00 FF FF FF FF FF FF 00 ................
11:41:08.865084 [INFO] 00 14 FF 00 00 FF 00 00 00 FF FF 03 00 01 00 03 ................
11:41:08.865092 [INFO] 00 01 08 14 02 00 00 00 00 00 00 00 00 00 00 00 ................
11:41:08.865100 [INFO] 00 00 00 00 00 00 00 01 00 14 01 00 00 00 00 00 ................
11:41:08.865107 [INFO] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 12 14 ................
11:41:08.865115 [INFO] 03 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
11:41:08.865122 [INFO] 00 00 00 ...
11:41:08.865131 [INFO] PBXIPC-H323. TX. proceeding
11:41:08.865139 [INFO] # callref 004e
11:41:08.865148 [INFO] # media-ipaddr 192.168.111.138
11:41:08.865156 [INFO] # media-port 20690
11:41:08.865165 [INFO] # codec list size (3)
11:41:08.865173 [INFO] # # G711.A PT 8, ptime 20
11:41:08.865182 [INFO] # # G711.U PT 0, ptime 20
11:41:08.865190 [INFO] # # G729.A PT 18, ptime 20
11:41:08.865218 [INFO] [C0000CE] Port H323:004e. Dial In (in=01, need rm=0): [.]
11:41:08.865230 [INFO] [C0000CE] Port H323:004e. In Digit 1 sym: called buf <3911.>
11:41:08.865240 [INFO] [C0000CE] Port H323:004e. Dial buf <3911>(4)
11:41:08.865251 [INFO] [C0000CE] Port H323:004e. Dial Proc (state In-Dial): PLAN 0, <3911.> (called len 05) Compl.
11:41:08.865263 [INFO] [C0000CE] Port H323:004e. Incoming ModTable: [-1:-1]
11:41:08.865272 [INFO] [C0000CE] Port H323:004e. Incoming modifiers CdPN [-1:-1], CgPN [-1:-1]
11:41:08.865284 [INFO] [C0000CE] Port H323:004e. Search route (+abonent). Mode: 'calling'. Num: <3225>(4).# Complete
11:41:08.865310 [INFO] [C0000CE] Port H323:004e. Search route. Result: route not found. res (-1)
11:41:08.865324 [INFO] [C0000CE] Port H323:004e. Search route (+abonent). Mode: 'called'. Num: <3911.>(5).# Complete
11:41:08.865381 [INFO] [C0000CE] Port H323:004e. Search route. User-service data search
11:41:08.865397 [INFO] [C0000CE] Port H323:004e. Search route. User-service data not found
11:41:08.865408 [INFO] [C0000CE] Port H323:004e. Search route. Result: found route to prefix[54]. res (4)
11:41:08.865417 [INFO] [C0000CE] Port H323:004e. Proc route-search result [4]
11:41:08.865428 [INFO] [C0000CE] Port H323:004e. Process route to prefix [54].
11:41:08.865440 [INFO] [C0000CE] Port H323:004e. Prefix 54: type 'IVR.SCENARIO', idx=0
11:41:08.865455 [INFO] IVR. Callref 0025. State changed: 'null_state' -> 'allocated'
11:41:08.865470 [INFO] [C0000CE] SRV. Callref 0038. out-Seize from port H323:004e.
11:41:08.865484 [INFO] [C0000CE] Port SRVC:0038. Set parent H323:004e
11:41:08.865495 [INFO] [C0000CE] Port SRVC:0038. Set UniqueTag: '1400004e 5fc07554 6bcc04c9 1915976f'
11:41:08.865505 [INFO] [C0000CE] Port SRVC:0038. loc 1, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
11:41:08.865515 [INFO] [C0000CE] Port H323:004e. loc 0, trunk-loc 0. peer-loc 1, peer-tunk-loc: 0
11:41:08.867564 [INFO] [C0000CE] Port SRVC:0038. Set cdr-time for: setup
11:41:08.867580 [INFO] [C0000CE] Port SRVC:0038. Setup time-stamp: 11:41:08.202 IRKT Fri Nov 27 2020
11:41:08.867591 [INFO] [C0000CE] Port SRVC:0038. Set CallerId-Peer: 'Balkanov' (8).
11:41:08.867601 [INFO] [C0000CE] Port H323:004e. Set CallerId-Peer: '-none-' (0).
11:41:08.867611 [INFO] [C0000CE] SRV. Callref 0038. CgPN: <3225>(4) <set> <national> <no-change> <allowed> <not verified>. cat [1]
11:41:08.867623 [INFO] [C0000CE] Port SRVC:0038. Setup Ack (peer=H323:004e)
11:41:08.867633 [INFO] [C0000CE] Port SRVC:0038. Set state 'Out-Dial'
11:41:08.867644 [INFO] [C0000CE] Port H323:004e. Setup Acked in state In-Dial. (peer=SRVC:0038)
11:41:08.867653 [INFO] [C0000CE] Port SRVC:0038. Set state 'Service-Dial'
11:41:08.867663 [INFO] [C0000CE] Port SRVC:0038. Set cdr-time for: setup
11:41:08.867675 [INFO] [C0000CE] Port SRVC:0038. Setup time-stamp: 11:41:08.202 IRKT Fri Nov 27 2020
11:41:08.867685 [INFO] [C0000CE] SRV. Callref 0038. New state 'SRV_PRESENTED'
11:41:08.867695 [INFO] [C0000CE] SRV. Callref 0038. Set IVR ref [0025]
11:41:08.867705 [INFO] [C0000CE] Port SRVC:0038. Set state 'Wait-Num'
11:41:08.867715 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit [core/ivr.c:3330]
11:41:08.867726 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit - peer H323:004e (next.digit=n). Called len 05, proceed 00
11:41:08.867736 [INFO] [C0000CE] Port SRVC:0038. Dial Out=3 (loc)
11:41:08.867746 [INFO] [C0000CE] Port SRVC:0038. Check out-called-modify: '3'(1). ModTable[-1], CldMod[-1:-1]
11:41:08.867776 [INFO] [C0000CE] SRV. Callref 0038. Dial 3
11:41:08.867788 [INFO] [C0000CE] Port SRVC:0038. Dial Out: called len 00, append 01 (max digit 40)
11:41:08.867799 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit [core/service_port.c:8261]
11:41:08.867810 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit - peer H323:004e (next.digit=n). Called len 05, proceed 01
11:41:08.867819 [INFO] [C0000CE] Port SRVC:0038. Dial Out=9 (loc)
11:41:08.867828 [INFO] [C0000CE] SRV. Callref 0038. Dial 9
11:41:08.867837 [INFO] [C0000CE] Port SRVC:0038. Dial Out: called len 01, append 01 (max digit 40)
11:41:08.867848 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit [core/service_port.c:8261]
11:41:08.867858 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit - peer H323:004e (next.digit=n). Called len 05, proceed 02
11:41:08.867867 [INFO] [C0000CE] Port SRVC:0038. Dial Out=1 (loc)
11:41:08.867876 [INFO] [C0000CE] SRV. Callref 0038. Dial 1
11:41:08.867885 [INFO] [C0000CE] Port SRVC:0038. Dial Out: called len 02, append 01 (max digit 40)
11:41:08.867895 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit [core/service_port.c:8261]
11:41:08.867905 [INFO] [C0000CE] Port SRVC:0038. Query Next Digit - peer H323:004e (next.digit=n). Called len 05, proceed 03
11:41:08.867926 [INFO] [C0000CE] Port SRVC:0038. Dial Out=1. (loc)
11:41:08.867937 [INFO] [C0000CE] SRV. Callref 0038. Dial 1.
11:41:08.867946 [INFO] [C0000CE] Port SRVC:0038. Dial Out: called len 03, append 02 (max digit 40)
11:41:08.867956 [INFO] [C0000CE] Port SRVC:0038. Dial Out: 'End_of_Dial' digit/stop dial
11:41:08.867967 [INFO] IVR. Try find IVRScenario1 in /mnt/sdb1/ivr_scenario
11:41:08.868055 [INFO] IVR. Script found. Full path: /mnt/sdb1/ivr_scenario/IVRScenario1
11:41:08.868071 [INFO] IVR. Callref 0025. Mapped on SRVC:0038 (Peer H323:004e) loop 0
11:41:08.868083 [INFO] ZMQ. TX Callref 0025. Try send 'Seize'
11:41:08.868102 [INFO] ASN. Added 'Seize' to message body
11:41:08.868120 [INFO] Basic message params:
11:41:08.868120 [INFO] version: . . . . . . . 1
11:41:08.868120 [INFO] appSessionID . . . . .
11:41:08.868120 [INFO] swSessionID: . . . . . 37
11:41:08.868120 [INFO] body: . . . . . . . . . Seize
11:41:08.868120 [INFO] BASIC params:
11:41:08.868120 [INFO] applicationCfg: . . . . /mnt/sdb1/ivr_scenario/IVRScenario1
11:41:08.868120 [INFO] vatsId: . . . . . . . . no_vats
11:41:08.868120 [INFO] applicationId: . . . . 0
11:41:08.868120 [INFO] timestamp: . . . . . . 1606.448468.0
11:41:08.868120 [INFO] cdpn: . . . . . . . . .
11:41:08.868120 [INFO] nai: . . . . . . . . 2
11:41:08.868120 [INFO] npi: . . . . . . . . 0
11:41:08.868120 [INFO] inn: . . . . . . . . 0
11:41:08.868120 [INFO] address: . . . . . . 3911
11:41:08.868120 [INFO] OPTIONAL params:
11:41:08.868120 [INFO] cgpn: . . . . . . . . .
11:41:08.868120 [INFO] nai: . . . . . . . . 2
11:41:08.868120 [INFO] screening: . . . . . 0
11:41:08.868120 [INFO] apri: . . . . . . . 0
11:41:08.868120 [INFO] npi: . . . . . . . . 0
11:41:08.868120 [INFO] ni: . . . . . . . . 0
11:41:08.868120 [INFO] address: . . . . . . 3225
11:41:08.868120 [INFO] displayName: . . . . Balkanov
11:41:08.868120 [INFO] callRef: . . . . . . . no
11:41:08.868120 [INFO] category: . . . . . . . 10
11:41:08.868120 [INFO] numberPlan: . . . . . . 0
11:41:08.868120 [INFO] tgId: . . . . . . . . . no
11:41:08.868120 [INFO] originalCDPN: . . . . . no
11:41:08.868120 [INFO] genericNumber: . . . . no
11:41:08.868120 [INFO] redirectingNumber: . . no
11:41:08.868120 [INFO] redirectionInformation: no
11:41:08.868120 [INFO] callingAccess: . . . . 255
11:41:08.868120 [INFO] fci . . . . . . . . . . no
11:41:08.868120 [INFO] usi . . . . . . . . . . no
11:41:08.868120 [INFO] uti . . . . . . . . . . no
11:41:08.868120 [INFO] tmr . . . . . . . . . . 3
11:41:08.868120 [INFO] noCDR . . . . . . . . . no
11:41:08.868120 [INFO] detached . . . . . . . no
11:41:08.868120 [INFO] toLog . . . . . . . . . no
11:41:08.868120 [INFO] loop count . . . . . . 1
11:41:08.868120 [INFO] textID . . . . . . . . 1400004e 5fc07554 6bcc04c9 1915976f
11:41:08.868266 [INFO] ZMQ. Callref 0025. State changed: 'disconnected' -> 'wait connection'
11:41:08.868278 [INFO] ZMQ. Callref 0025. New session added
11:41:08.870393 [INFO] IVR. Callref 0025. State changed: 'allocated' -> 'new'
11:41:08.870415 [INFO] [C0000CE] Port H323:004e. Prefix linked. Called len 05, proceed 05. (IVR-call)
11:41:08.870426 [INFO] [C0000CE] Port H323:004e. Proc route-complete result [0]
11:41:08.870436 [INFO] [C0000CE] Port H323:004e. Proc number-analiz result [0]
11:41:08.870447 [INFO] H323. Process message 'seize' result [0]
11:41:08.871359 [INFO] Basic message params:
11:41:08.871359 [INFO] version: . . . . . . . 1
11:41:08.871359 [INFO] appSessionID . . . . . 05b50e6e565c1533
11:41:08.871359 [INFO] swSessionID: . . . . . 37
11:41:08.871359 [INFO] body: . . . . . . . . . Progress
11:41:08.871359 [INFO] BASIC params:
11:41:08.871359 [INFO] event . . . . . . . . . 2
11:41:08.871359 [INFO] presentation . . . . . 0
11:41:08.871359 [INFO] timestamp . . . . . . . 1606.448468.870975
11:41:08.871359 [INFO] OPTIONAL params:
11:41:08.871359 [INFO] state . . . . . . . . . no
11:41:08.871359 [INFO] cause . . . . . . . . . no
11:41:08.871359 [INFO] descript . . . . . . . no
11:41:08.871359 [INFO] obci . . . . . . . . . no
11:41:08.871359 [INFO] gnotification . . . . . no
11:41:08.871359 [INFO] redirectionNumber . . . no
11:41:08.871359 [INFO] redirectionRestInd . . no
11:41:08.871359 [INFO] callDiversion . . . . . no
11:41:08.871359 [INFO] callTransferNumber . . no
11:41:08.871359 [INFO] detect . . . . . . . . no
11:41:08.871359 [INFO] collectedInfo . . . . . no
11:41:08.871359 [INFO] play . . . . . . . . . no
11:41:08.871359 [INFO] record . . . . . . . . no
11:41:08.871359 [INFO] noCDR . . . . . . . . . no
11:41:08.871359 [INFO] bridge . . . . . . . . no
11:41:08.871359 [INFO] detached . . . . . . . no
11:41:08.871359 [INFO] toLog . . . . . . . . . no
11:41:08.871419 [INFO] ZMQ. RX Callref 0025. Message 'Progress'
11:41:08.871435 [INFO] ZMQ. Callref 0025. Mapped to '05b50e6e565c1533'
11:41:08.871444 [INFO] ZMQ. Callref 0025. State changed: 'wait connection' -> 'connected'
11:41:08.873469 [INFO] Basic message params:
11:41:08.873469 [INFO] version: . . . . . . . 1
11:41:08.873469 [INFO] appSessionID . . . . . 05b50e6e565c1533
11:41:08.873469 [INFO] swSessionID: . . . . . 37
11:41:08.873469 [INFO] body: . . . . . . . . . Progress
11:41:08.873469 [INFO] BASIC params:
11:41:08.873469 [INFO] event . . . . . . . . . 2
11:41:08.873469 [INFO] presentation . . . . . 0
11:41:08.873469 [INFO] timestamp . . . . . . . 1606.448468.872639
11:41:08.873469 [INFO] OPTIONAL params:
11:41:08.873469 [INFO] state . . . . . . . . . no
11:41:08.873469 [INFO] cause . . . . . . . . . no
11:41:08.873469 [INFO] descript . . . . . . . no
11:41:08.873469 [INFO] obci . . . . . . . . . 1
11:41:08.873469 [INFO] gnotification . . . . . no
11:41:08.873469 [INFO] redirectionNumber . . . no
11:41:08.873469 [INFO] redirectionRestInd . . no
11:41:08.873469 [INFO] callDiversion . . . . . no
11:41:08.873469 [INFO] callTransferNumber . . no
11:41:08.873469 [INFO] detect . . . . . . . . no
11:41:08.873469 [INFO] collectedInfo . . . . . no
11:41:08.873469 [INFO] play . . . . . . . . .
11:41:08.873469 [INFO] file: . . . . . . . 1-1606379273.wav
11:41:08.873469 [INFO] bargein: . . . . . . 0
11:41:08.873469 [INFO] repeatCount: . . . . 1
11:41:08.873469 [INFO] record . . . . . . . . no
11:41:08.873469 [INFO] noCDR . . . . . . . . . no
11:41:08.873469 [INFO] bridge . . . . . . . . no
11:41:08.873469 [INFO] detached . . . . . . . no
11:41:08.873469 [INFO] toLog . . . . . . . . . no
11:41:08.873511 [INFO] ZMQ. RX Callref 0025. Message 'Progress'
11:41:08.873525 [INFO] [C0000CE] Port SRVC:0038. Set in-band indication flag
11:41:08.873536 [INFO] [C0000CE] Port SRVC:0038. Stop Dial, reason 'complete'
11:41:08.873548 [INFO] [C0000CE] Port H323:004e. Send 'End of Dial' to incoming call
11:41:08.873558 [INFO] [C0000CE] H323. Callref 004e. EndDial from peer (condition for call proceeding reached)
11:41:08.873567 [INFO] [C0000CE] H323. Callref 004e. New state 'H323_IN_PROCEEDING'
11:41:08.873580 [INFO] [C0000CE] Port H323:004e. LinkUp to SRVC:0038
11:41:08.873591 [INFO] [C0000CE] SConn[0059]: link off
11:41:08.873619 [INFO] [C0000CE] SConn[0059]: <192.168.111.138:20690> <-> <0.0.0.0:0> (G711.A), peer[ - ], Port H323:004e (sendrecv)
11:41:08.873633 [INFO] [C0000CE] SConn[0059]: set participant topology mode 'sendrecv' [0]
11:41:08.873646 [INFO] IVR. Callref 0025. Play status: Play '1-1606379273.wav'. Repeat 1 of 1. Play position 1 of 1
11:41:08.873657 [INFO] IVR. Callref 0025. Try find file '1-1606379273.wav' in '/mnt/sdb1/ivr_sound'
11:41:08.873745 [INFO] IVR. Callref 0025. File found. Full path '/mnt/sdb1/ivr_sound/1-1606379273.wav'
11:41:08.873776 [INFO] [C0000CE] Port SRVC:0038. Play file start. <local> <out>.. Peer H323:004e
11:41:08.873789 [INFO] [C0000CE] Port H323:004e. Start play file to <TDM> (unlink)
11:41:08.873799 [INFO] [C0000CE] Port H323:004e. Start play file: /mnt/sdb1/ivr_sound/1-1606379273.wav
11:41:08.873818 [INFO] [C0000CE] SConn[0059]: start play file request '/mnt/sdb1/ivr_sound/1-1606379273.wav'; callback for H323:004e
11:41:08.873831 [INFO] [C0000CE] SConn[0059]: <192.168.111.138:20690> <-> <0.0.0.0:0> (G711.A), peer[ - ], Port H323:004e (sendrecv)
11:41:46.795045 [INFO] Port SIPu:0016 [0054]. Reg-indicate: <3900> [192.168.201.71:53865] '192.168.111.138'. tsID [10013c4]. [300]. ROK
11:41:48.225892 [INFO] Basic message params:
11:41:48.225892 [INFO] version: . . . . . . . 1
11:41:48.225892 [INFO] appSessionID . . . . . 05b50e6d1b453454
11:41:48.225892 [INFO] swSessionID: . . . . . 35
11:41:48.225892 [INFO] body: . . . . . . . . . Release
11:41:48.225892 [INFO] BASIC params:
11:41:48.225892 [INFO] cause . . . . . . . . . 19
11:41:48.225892 [INFO] timestamp . . . . . . . 1606.448508.224710
11:41:48.225892 [INFO] OPTIONAL params:
11:41:48.225892 [INFO] descript . . . . . . . subscriber_not_answer
11:41:48.225892 [INFO] noCDR . . . . . . . . . no
11:41:48.225892 [INFO] detached . . . . . . . no
11:41:48.225892 [INFO] toLog . . . . . . . . . no
11:41:48.225963 [INFO] ZMQ. RX Callref 0023. Message 'Release'
11:41:48.225977 [INFO] ZMQ. Callref 0023. State changed: 'connected' -> 'disconnected'
11:41:48.225990 [INFO] IVR. Callref 0023. Will be disconnected. Cause: 19 (User alerting, no answer)
11:41:48.226005 [INFO] [C0000CC] SRV. Callref 0036. Clr IVR ref
11:41:48.226019 [INFO] [C0000CC] SRV. Callref 0036. Release, cause 'User alerting, no answer', loc '0'
11:41:48.226029 [INFO] [C0000CC] SRV. Callref 0036. Set Idle
11:41:48.226041 [INFO] [C0000CC] Port SRVC:0036. Release Complete, cause 'User alerting, no answer', dcl '0', loc '0'
11:41:48.226054 [INFO] [C0000CC] Port SRVC:0036. Release Complete: Release initiator flag set. Peer H323:004c
11:41:48.226065 [INFO] [C0000CC] Port SRVC:0036. Set cdr-time for: release
11:41:48.226083 [INFO] [C0000CC] Port SRVC:0036. Setup time-stamp: 11:40:48.554 IRKT Fri Nov 27 2020
11:41:48.226096 [INFO] [C0000CC] Port SRVC:0036. Release time-monot: 1030721.561
11:41:48.226106 [INFO] [C0000CC] Port SRVC:0036. Release time-stamp: 11:41:48.561 IRKT Fri Nov 27 2020, msec-diff: 0
11:41:48.226115 [INFO] [C0000CC] Port SRVC:0036. Duration sec: 0, msec: 0
11:41:48.226125 [INFO] [C0000CC] Port SRVC:0036. Accounting-stop for 'answer' is off.
11:41:48.226136 [INFO] [C0000CC] Port H323:004c. Accounting-stop for 'originate' is off.
11:41:48.226147 [INFO] [C0000CC] Port SRVC:0036. Save CDR. disabled. [0x1]
11:41:48.226158 [INFO] [C0000CC] Port SRVC:0036. Release peer 'H323:004c', cause 'User alerting, no answer', dcl '7', loc '0'
11:41:48.226169 [INFO] [C0000CC] Port H323:004c. Disconnect from SRVC:0036, cause 'User alerting, no answer', dcl '7', loc '0'
11:41:48.226180 [INFO] [C0000CC] Port H323:004c. Disconnect, cause 'User alerting, no answer', dcl '7', loc '0'
11:41:48.226190 [INFO] [C0000CC] Port H323:004c. Set cdr-time for: release
11:41:48.226202 [INFO] [C0000CC] Port H323:004c. Setup time-stamp: 11:40:48.553 IRKT Fri Nov 27 2020
11:41:48.226242 [INFO] [C0000CC] Port H323:004c. Release time-monot: 1030721.561
11:41:48.226254 [INFO] [C0000CC] Port H323:004c. Release time-stamp: 11:41:48.561 IRKT Fri Nov 27 2020, msec-diff: 0
11:41:48.226264 [INFO] [C0000CC] Port H323:004c. Duration sec: 0, msec: 0
11:41:48.226274 [INFO] [C0000CC] Port SRVC:0036. Accounting-stop for 'answer' is off.
11:41:48.226284 [INFO] [C0000CC] Port H323:004c. Accounting-stop for 'originate' is off.
11:41:48.226293 [INFO] [C0000CC] Port H323:004c. Save CDR. disabled. [0x1]
11:41:48.226303 [INFO] [C0000CC] H323. Callref 004c. Release from peer
11:41:48.226313 [INFO] [C0000CC] H323. Callref 004c. New state 'H323_RELEASE_REQUEST'
11:41:48.226323 [INFO] [C0000CC] H323. Callref 004c. Release. Cause: 19
11:41:48.226334 [INFO] pbx-ipc H323: TX (011)
11:41:48.226344 [INFO] 06 01 02 00 4C 00 15 02 00 13 00 ....L......
11:41:48.226354 [INFO] PBXIPC-H323. TX. rel-req
11:41:48.226362 [INFO] # callref 004c
11:41:48.226371 [INFO] # cause 19
11:41:48.226400 [INFO] [C0000CC] Port H323:004c. Set state 'Release'
11:41:48.226413 [INFO] [C0000CC] Port SRVC:0036. Set state 'Ready'
11:41:48.226426 [INFO] [C0000CC] SRV. Callref 0036. New state 'SRV_IDLE'
11:41:48.226438 [INFO] IVR. Callref 0023. State changed: 'new' -> 'null_state'
11:41:48.228351 [INFO] pbx-ipc H323: RX (011)
11:41:48.228384 [INFO] 07 01 02 00 4C 00 15 02 00 13 00 ....L......
11:41:48.228396 [INFO] PBXIPC-H323. RX. rel-cplt
11:41:48.228405 [INFO] # callref 004c
11:41:48.228414 [INFO] # cause 19
11:41:48.228424 [INFO] H323. Got message 'rel-cplt' len [11]
11:41:48.228453 [INFO] H323. got msg [7] len 11
11:41:48.228472 [INFO] H323. Process message 'rel-cplt'
11:41:48.228484 [INFO] [C0000CC] H323. Callref 004c. procRelease in state 'H323_RELEASE_REQUEST'
11:41:48.228496 [INFO] [C0000CC] H323. Callref 004c. Release complete
11:41:48.228507 [INFO] [C0000CC] Port H323:004c. Deactivate IP-conn [0057]
11:41:48.228518 [INFO] [C0000CC] Port H323:004c. Release [core/h323_i.c:3265]
11:41:48.228530 [INFO] [C0000CC] Port H323:004c. Release, cause 'User alerting, no answer', dcl '0', loc '0'
11:41:48.228541 [INFO] [C0000CC] Port H323:004c. Accounting-stop for 'originate' is off.
11:41:48.228552 [INFO] [C0000CC] Port H323:004c. Save CDR. disabled. [0x1]
11:41:48.228562 [INFO] [C0000CC] H323. Callref 004c. New state 'H323_IDLE'
11:41:48.228573 [INFO] [C0000CC] Port H323:004c. Deactivate IP-conn [0057]
11:41:48.228582 [INFO] [C0000CC] Port H323:004c. Release Complete, cause 'User alerting, no answer', dcl '0', loc '0'
11:41:48.228593 [INFO] [C0000CC] Port H323:004c. Accounting-stop for 'originate' is off.
11:41:48.228602 [INFO] [C0000CC] Port H323:004c. Save CDR. disabled. [0x1]
11:41:48.228612 [INFO] [C0000CC] Port H323:004c. Free IP-conn [0057]
11:41:48.228621 [INFO] [C0000CC] SConn[0057]: destroy
11:41:48.228637 [INFO] [C0000CC] SConn[0057]: <192.168.111.138:20686> <-> <0.0.0.0:0> (G711.A), peer[ - ], Port H323:004c (sendrecv)
11:41:48.228649 [INFO] [C0000CC] Port H323:004c. Set state 'Ready'
11:41:48.228660 [INFO] [C0000CC] H323. Interface[00]. Callref 004c. Dec active[2].
11:41:48.228670 [INFO] [C0000CC] H323. Interface[00]. Max-active[0], curr-active[1]
11:41:48.228679 [INFO] H323. Process message 'rel-cplt' result [0]
11:42:08.875867 [INFO] Basic message params:
11:42:08.875867 [INFO] version: . . . . . . . 1
11:42:08.875867 [INFO] appSessionID . . . . . 05b50e6e565c1533
11:42:08.875867 [INFO] swSessionID: . . . . . 37
11:42:08.875867 [INFO] body: . . . . . . . . . Release
11:42:08.875867 [INFO] BASIC params:
11:42:08.875867 [INFO] cause . . . . . . . . . 19
11:42:08.875867 [INFO] timestamp . . . . . . . 1606.448528.874693
11:42:08.875867 [INFO] OPTIONAL params:
11:42:08.875867 [INFO] descript . . . . . . . subscriber_not_answer
11:42:08.875867 [INFO] noCDR . . . . . . . . . no
11:42:08.875867 [INFO] detached . . . . . . . no
11:42:08.875867 [INFO] toLog . . . . . . . . . no
11:42:08.875968 [INFO] ZMQ. RX Callref 0025. Message 'Release'
11:42:08.875985 [INFO] ZMQ. Callref 0025. State changed: 'connected' -> 'disconnected'
11:42:08.875999 [INFO] IVR. Callref 0025. Will be disconnected. Cause: 19 (User alerting, no answer)
11:42:08.876015 [INFO] [C0000CE] SRV. Callref 0038. Clr IVR ref
11:42:08.876030 [INFO] [C0000CE] SRV. Callref 0038. Release, cause 'User alerting, no answer', loc '0'
11:42:08.876040 [INFO] [C0000CE] SRV. Callref 0038. Set Idle
11:42:08.876052 [INFO] [C0000CE] Port SRVC:0038. Release Complete, cause 'User alerting, no answer', dcl '0', loc '0'
11:42:08.876065 [INFO] [C0000CE] Port SRVC:0038. Release Complete: Release initiator flag set. Peer H323:004e
11:42:08.876077 [INFO] [C0000CE] Port SRVC:0038. Set cdr-time for: release
11:42:08.876095 [INFO] [C0000CE] Port SRVC:0038. Setup time-stamp: 11:41:08.202 IRKT Fri Nov 27 2020
11:42:08.876108 [INFO] [C0000CE] Port SRVC:0038. Release time-monot: 1030742.211
11:42:08.876118 [INFO] [C0000CE] Port SRVC:0038. Release time-stamp: 11:42:08.211 IRKT Fri Nov 27 2020, msec-diff: 0
11:42:08.876128 [INFO] [C0000CE] Port SRVC:0038. Duration sec: 0, msec: 0
11:42:08.876138 [INFO] [C0000CE] Port SRVC:0038. Accounting-stop for 'answer' is off.
11:42:08.876149 [INFO] [C0000CE] Port H323:004e. Accounting-stop for 'originate' is off.
11:42:08.876159 [INFO] [C0000CE] Port SRVC:0038. Save CDR. disabled. [0x1]
11:42:08.876170 [INFO] [C0000CE] Port SRVC:0038. Release peer 'H323:004e', cause 'User alerting, no answer', dcl '7', loc '0'
11:42:08.876182 [INFO] [C0000CE] Port H323:004e. Disconnect from SRVC:0038, cause 'User alerting, no answer', dcl '7', loc '0'
11:42:08.876192 [INFO] [C0000CE] Port H323:004e. Disconnect, cause 'User alerting, no answer', dcl '7', loc '0'
11:42:08.876203 [INFO] [C0000CE] Port H323:004e. Set cdr-time for: release
11:42:08.876215 [INFO] [C0000CE] Port H323:004e. Setup time-stamp: 11:41:08.199 IRKT Fri Nov 27 2020
11:42:08.876226 [INFO] [C0000CE] Port H323:004e. Release time-monot: 1030742.211
11:42:08.876235 [INFO] [C0000CE] Port H323:004e. Release time-stamp: 11:42:08.211 IRKT Fri Nov 27 2020, msec-diff: 0
11:42:08.876244 [INFO] [C0000CE] Port H323:004e. Duration sec: 0, msec: 0
11:42:08.876254 [INFO] [C0000CE] Port SRVC:0038. Accounting-stop for 'answer' is off.
11:42:08.876263 [INFO] [C0000CE] Port H323:004e. Accounting-stop for 'originate' is off.
11:42:08.876272 [INFO] [C0000CE] Port H323:004e. Save CDR. disabled. [0x1]
11:42:08.876283 [INFO] [C0000CE] H323. Callref 004e. Release from peer
11:42:08.876293 [INFO] [C0000CE] H323. Callref 004e. New state 'H323_RELEASE_REQUEST'
11:42:08.876302 [INFO] [C0000CE] H323. Callref 004e. Release. Cause: 19
11:42:08.876314 [INFO] pbx-ipc H323: TX (011)
11:42:08.876323 [INFO] 06 01 02 00 4E 00 15 02 00 13 00 ....N......
11:42:08.876333 [INFO] PBXIPC-H323. TX. rel-req
11:42:08.876342 [INFO] # callref 004e
11:42:08.876350 [INFO] # cause 19
11:42:08.876384 [INFO] [C0000CE] Port H323:004e. Set state 'Release'
11:42:08.876398 [INFO] [C0000CE] Port SRVC:0038. Set state 'Ready'
11:42:08.876410 [INFO] [C0000CE] SRV. Callref 0038. New state 'SRV_IDLE'
11:42:08.876422 [INFO] IVR. Callref 0025. State changed: 'new' -> 'null_state'
11:42:08.878318 [INFO] pbx-ipc H323: RX (011)
11:42:08.878346 [INFO] 07 01 02 00 4E 00 15 02 00 13 00 ....N......
11:42:08.878358 [INFO] PBXIPC-H323. RX. rel-cplt
11:42:08.878367 [INFO] # callref 004e
11:42:08.878377 [INFO] # cause 19
11:42:08.878386 [INFO] H323. Got message 'rel-cplt' len [11]
11:42:08.878410 [INFO] H323. got msg [7] len 11
11:42:08.878429 [INFO] H323. Process message 'rel-cplt'
11:42:08.878456 [INFO] [C0000CE] H323. Callref 004e. procRelease in state 'H323_RELEASE_REQUEST'
11:42:08.878469 [INFO] [C0000CE] H323. Callref 004e. Release complete
11:42:08.878481 [INFO] [C0000CE] Port H323:004e. Deactivate IP-conn [0059]
11:42:08.878492 [INFO] [C0000CE] Port H323:004e. Release [core/h323_i.c:3265]
11:42:08.878504 [INFO] [C0000CE] Port H323:004e. Release, cause 'User alerting, no answer', dcl '0', loc '0'
11:42:08.878515 [INFO] [C0000CE] Port H323:004e. Accounting-stop for 'originate' is off.
11:42:08.878526 [INFO] [C0000CE] Port H323:004e. Save CDR. disabled. [0x1]
11:42:08.878536 [INFO] [C0000CE] H323. Callref 004e. New state 'H323_IDLE'
11:42:08.878547 [INFO] [C0000CE] Port H323:004e. Deactivate IP-conn [0059]
11:42:08.878556 [INFO] [C0000CE] Port H323:004e. Release Complete, cause 'User alerting, no answer', dcl '0', loc '0'
11:42:08.878567 [INFO] [C0000CE] Port H323:004e. Accounting-stop for 'originate' is off.
11:42:08.878577 [INFO] [C0000CE] Port H323:004e. Save CDR. disabled. [0x1]
11:42:08.878586 [INFO] [C0000CE] Port H323:004e. Free IP-conn [0059]
11:42:08.878596 [INFO] [C0000CE] SConn[0059]: destroy
11:42:08.878611 [INFO] [C0000CE] SConn[0059]: <192.168.111.138:20690> <-> <0.0.0.0:0> (G711.A), peer[ - ], Port H323:004e (sendrecv)
11:42:08.878624 [INFO] [C0000CE] Port H323:004e. Set state 'Ready'
11:42:08.878635 [INFO] [C0000CE] H323. Interface[00]. Callref 004e. Dec active[1].
11:42:08.878646 [INFO] [C0000CE] H323. Interface[00]. Max-active[0], curr-active[0]
11:42:08.878655 [INFO] H323. Process message 'rel-cplt' result [0]
SMG-200. 1V3 SN: VI55000470 Software Version: V.3.18.0.3977 200/PBX/H323/VAS/REC/IVR Build: Sep 11 2020 18:41:34
Log stopped at: 11:42:11.417793 27/11/20