3cx и кол-во активных вызовов.

[veulk1]

Последние два дня система сообщает об ошибках с переполнения кол-ва одноврменных разговоров по лицензии.Лицензия на 32 одновременных разговора.
License limit is reached, active calls: [14331,14332,14333,14334,14335,14336,14337,14339,14340,14341,14342,14343,14344,14345,14346,14347,14348,14350,14351,14352,14353,14354,14355,14356,14357,14358,14359,14360,14361,14362,14363,14364,14365]
Дело в том, что количество текущих совершаемых разговоров у меня мониторится на шлюзе и очень редко когда это количество доходит до отметки 20.А вчера система прислала данное сообщение в 19.38 по МСК, в это время офис и вовсе не работает.
В чем может быть причина ?
Отмечу еще , что на текущую работы телефонии это ошибка никак не влияет.

 

[igorsnezhko]

В 3cx, как вы, наверное, знаете, учитываются не только вызовы через шлюз, а любой вызов, включая ждущие в очереди и внутренние. Кроме того, если вызовы идут с кодеком g. 729, то по лицензии их доступно в 2 раза меньше, т.е. 16. Может быть у вас один из этих случаев?

 

[veulk1]

общее количество вызовов даже с учетом внутренних никак не могло превысить,в системе есть один транк с кодеком g729 и ограничением вызовов 2 линии.Остальное все 711alaw.Самое странное , что вызовов с идентификаторами
[14331,14332,14333,14334,14335,14336,14337,14339,14340,14341,14342,14343,14344,14345,14346,14347,14348,14350,14351,14352,14353,14354,14355,14356,14357,14358,14359,14360,14361,14362,14363,14364,14365] нет в папке C:\ProgramData\3CX\Data\Logs\cdrmulti то, остальные вызовы с порядковым идентификатором между данными значениями есть в данной папке.Отмечу дополнительно, что ситуацию по телефонии я отслеживаю в прямом так сказать эфире и имею общую статистику и опыт прогнозирования загрузки.При самой большой вероятности и ЧНН кол-во одновременных разговоров внутри и наружу не превышает 10 и это бывает очень редко, я бы сказал единожды.

 

[igorsnezhko]

Трудно сказать, что это такое. Посмотрите в отчетах 3CX - что происходит в это время? Кто куда звонит. Еще полезнаяя вещь - 3CX Bin Log Viewer для детального анализа логов. И, конечно, рекомендуется использовать последнюю версию 3CX (12.5), потоому что в предыдущих могут присутствовать баги.

 

[veulk1]

Три раза с интревалом пять минут пришли вот такие сообщения:
License limit is reached, active calls: [947,969,970,971,972,973,974,975,976,977,978,979,980,981,982,983,984,985,986,987,988,989,990,991,992,993,994,995,996,997,998,999,1000]

License limit is reached, active calls: [947,1001,1002,1003,1004,1005,1006,1007,1008,1009,1010,1011,1015,1016,1017,1018,1019,1020,1021,1022,1023,1024,1025,1026,1028,1029,1030,1031,1032,1033,1034,1035,1036]

License limit is reached, active calls: [947,1056,1057,1058,1059,1060,1061,1062,1063,1064,1066,1067,1068,1069,1070,1071,1072,1073,1074,1075,1076,1077,1078,1079,1080,1081,1082,1083,1084,1085,1086,1087,1088]
В логах следующая информация:
29-апр-2015 10:16:08.113 Leg L:1087.3[VMail:9999] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:08.112 [CM503008]: Call(C:1087): Call is terminated
29-апр-2015 10:16:08.111 Leg L:1087.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:08.007 Leg L:1086.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:08.007 [CM503008]: Call(C:1086): Call is terminated
29-апр-2015 10:16:08.007 Leg L:1086.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.902 Leg L:1085.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.902 [CM503008]: Call(C:1085): Call is terminated
29-апр-2015 10:16:07.902 Leg L:1085.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.797 Leg L:1084.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.797 [CM503008]: Call(C:1084): Call is terminated
29-апр-2015 10:16:07.797 Leg L:1084.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.692 Leg L:1083.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.692 [CM503008]: Call(C:1083): Call is terminated
29-апр-2015 10:16:07.692 Leg L:1083.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.587 Leg L:1082.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.587 [CM503008]: Call(C:1082): Call is terminated
29-апр-2015 10:16:07.587 Leg L:1082.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.482 Leg L:1081.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.482 [CM503008]: Call(C:1081): Call is terminated
29-апр-2015 10:16:07.482 Leg L:1081.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.377 Leg L:1080.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.377 [CM503008]: Call(C:1080): Call is terminated
29-апр-2015 10:16:07.377 Leg L:1080.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.273 Leg L:1079.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.272 [CM503008]: Call(C:1079): Call is terminated
29-апр-2015 10:16:07.272 Leg L:1079.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.166 Leg L:1078.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.166 [CM503008]: Call(C:1078): Call is terminated
29-апр-2015 10:16:07.165 Leg L:1078.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:07.061 Leg L:1077.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:07.060 [CM503008]: Call(C:1077): Call is terminated
29-апр-2015 10:16:07.060 Leg L:1077.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.955 Leg L:1076.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.955 [CM503008]: Call(C:1076): Call is terminated
29-апр-2015 10:16:06.954 Leg L:1076.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.848 Leg L:1075.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.848 [CM503008]: Call(C:1075): Call is terminated
29-апр-2015 10:16:06.847 Leg L:1075.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.742 Leg L:1074.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.742 [CM503008]: Call(C:1074): Call is terminated
29-апр-2015 10:16:06.741 Leg L:1074.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.635 Leg L:1073.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.635 [CM503008]: Call(C:1073): Call is terminated
29-апр-2015 10:16:06.634 Leg L:1073.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.525 Leg L:1072.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.525 [CM503008]: Call(C:1072): Call is terminated
29-апр-2015 10:16:06.524 Leg L:1072.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.416 Leg L:1071.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.416 [CM503008]: Call(C:1071): Call is terminated
29-апр-2015 10:16:06.415 Leg L:1071.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.310 Leg L:1070.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.309 [CM503008]: Call(C:1070): Call is terminated
29-апр-2015 10:16:06.309 Leg L:1070.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.204 Leg L:1069.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.203 [CM503008]: Call(C:1069): Call is terminated
29-апр-2015 10:16:06.202 Leg L:1069.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:06.097 Leg L:1068.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:06.097 [CM503008]: Call(C:1068): Call is terminated
29-апр-2015 10:16:06.096 Leg L:1068.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.991 Leg L:1067.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.991 [CM503008]: Call(C:1067): Call is terminated
29-апр-2015 10:16:05.990 Leg L:1067.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.885 Leg L:1066.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.884 [CM503008]: Call(C:1066): Call is terminated
29-апр-2015 10:16:05.884 Leg L:1066.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.779 Leg L:1064.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.779 [CM503008]: Call(C:1064): Call is terminated
29-апр-2015 10:16:05.778 Leg L:1064.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.673 Leg L:1063.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.672 [CM503008]: Call(C:1063): Call is terminated
29-апр-2015 10:16:05.672 Leg L:1063.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.568 Leg L:1062.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.567 [CM503008]: Call(C:1062): Call is terminated
29-апр-2015 10:16:05.567 Leg L:1062.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.463 Leg L:1061.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.462 [CM503008]: Call(C:1061): Call is terminated
29-апр-2015 10:16:05.462 Leg L:1061.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.357 Leg L:1060.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.357 [CM503008]: Call(C:1060): Call is terminated
29-апр-2015 10:16:05.356 Leg L:1060.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.253 Leg L:1059.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.252 [CM503008]: Call(C:1059): Call is terminated
29-апр-2015 10:16:05.252 Leg L:1059.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.147 Leg L:1058.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.147 [CM503008]: Call(C:1058): Call is terminated
29-апр-2015 10:16:05.146 Leg L:1058.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:05.041 Leg L:1057.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX
29-апр-2015 10:16:05.041 [CM503008]: Call(C:1057): Call is terminated
29-апр-2015 10:16:05.040 Leg L:1057.1[Ivr:IVRForward] is terminated: Cause: BYE from 127.0.0.1:40600
29-апр-2015 10:16:04.935 Leg L:1056.2[Ivr:IVRForward] is terminated: Cause: BYE from PBX

По мониторингу активный диалогов на момент проблем ни одного активного разговора в транке с 3cx. в чем проблема?

 

[igorsnezhko]

У меня все же есть подозрение, что кто-то вас ломает.

 

[veulk1]

Cнова проблема повторяется.

License limit is reached, active calls: [3100,3103,3122,3125,3126,3127,3128,3129,3130,3131,3132,3133,3134,3135,3136,3137,3139,3140,3142,3143,3144,3147,3148,3149,3150,3151,3153,3154,3155,3156,3158,3163,3164]

лог

16:37:39.384|MSEndPoint.cpp(2704)|Trace5||??:[MS505002] C:3163.2: Failed to receive incoming stream from 127.0.0.1:40730 WSAError=10054
16:37:39.437|MediaServer.cpp(1587)|Trace5||??:EndPoint [email protected]:5482 removed from call [email protected]:5482
16:37:39.438|MediaServer.cpp(1434)|Trace5||??:EP [email protected]:5482. Stop to record file F:\Recordings\1407\[%D0%A1%D0%B5%D1%80%D0%B3%D0%B5%D0%B9 %D0%A3%D1%81%D0%BA%D0%BE%D0%B2]_1407-84957779990_20150826133730(3163).wav
16:37:39.438|MediaServer.cpp(1598)|Trace5||??:EndPoint [email protected]:5482 removed from call [email protected]:5482
16:37:39.439|MediaServer.cpp(1338)|Trace5||??:references to EndPoint [email protected]:5482 were removed
16:37:39.439|VirtualEndPoint.cpp(12)|Trace5||??:Virtual EndPoint [email protected]:5482 destroyed
16:37:39.440|MediaServer.cpp(1587)|Trace5||??:EndPoint [email protected]:5482 removed from call [email protected]:5482
16:37:39.440|MediaServer.cpp(1338)|Trace5||??:references to EndPoint [email protected]:5482 were removed
16:37:39.460|MediaServer.cpp(1338)|Trace5||??:references to EndPoint [email protected]:5482 were removed
16:37:39.460|MediaServer.cpp(1720)|Trace5||??:references to call [email protected]:5482 were removed
16:37:40.061|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2('2') pressed timestamp=3708881204
16:37:40.062|DtmfRecognizer.cpp(266)|Trace5||??:New tone #2('2') begin on [email protected]:5482
16:37:40.062|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2('2') pressed timestamp=3708881296
16:37:40.080|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2('2') pressed timestamp=3708881388
16:37:40.080|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2('2') pressed timestamp=3708881480
16:37:40.080|DtmfRecognizer.cpp(288)|Trace5||??TMF packet(continue): callStamp=434120 data=b8000a02
16:37:40.101|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2('2') pressed timestamp=3708881572
16:37:40.120|DtmfRecognizer.cpp(153)|Trace5||??TMF packet(end of tone): callStamp=434120 data=cc018a02
16:37:40.120|DtmfRecognizer.cpp(160)|Trace5||??:[MS211000] C:3158.2: DTMF (in-band) from адрес сип провайдера:16468 detected.
16:37:40.132|MSEndPoint.cpp(3621)|Trace5||??:EP:[email protected]:5482 realtime:593997706 timestamp:435350 send DTMF b8000a02
16:37:40.134|MSCallConf.cpp(32)|Trace5||??:Call: [email protected]:5482 destroyed
16:37:40.134|MSEndPoint.cpp(892)|Trace5||??:EndPoint [email protected]:5482 destroyed
16:37:40.134|MSEndPoint.cpp(893)|Trace5||??:EndPoint [email protected]:5482 destroyed statistics:
Begin send:593988236
LastSent:593997004
TotalSent:438
MaxSendInterval:32
MinSendInterval:15
AvgSendInterval:20.0183
SendDeviation:7.03286
Begin recv:593988455
Last recv:593996988
TotalRecv:427
MaxRecvInterval:32
MinRecvInterval:15
AvgRecvInterval:19.9836
ReceiveDeviation:7.01737

16:37:40.134|MSEndPoint.cpp(892)|Trace5||??:EndPoint [email protected]:5482 destroyed
16:37:40.134|MSEndPoint.cpp(893)|Trace5||??:EndPoint [email protected]:5482 destroyed statistics:
Begin send:593988236
LastSent:593997004
TotalSent:438
MaxSendInterval:32
MinSendInterval:15
AvgSendInterval:20.0183
SendDeviation:7.03286
Begin recv:593988392
Last recv:593996941
TotalRecv:428
MaxRecvInterval:32
MinRecvInterval:15
AvgRecvInterval:19.9743
ReceiveDeviation:7.01709

16:37:40.172|MSEndPoint.cpp(3621)|Trace5||??:EP:[email protected]:5482 realtime:593997752 timestamp:435350 send DTMF cc018a02
16:37:40.172|MSEndPoint.cpp(3621)|Trace5||??:EP:[email protected]:5482 realtime:593997752 timestamp:435350 send DTMF cc018a02
16:37:40.172|MSEndPoint.cpp(3621)|Trace5||??:EP:[email protected]:5482 realtime:593997752 timestamp:435350 send DTMF cc018a02
16:37:42.057|MediaServer.cpp(1161)|Trace5||??:Set Party EP:C:3126.2SDP:v=0
o=3cxVCE 44096340 322006981 IN IP4 127.0.0.1
s=3cxVCE Audio Call
c=IN IP4 127.0.0.1
t=0 0
m=audio 40618 RTP/AVP 0 8 3 13 9 110 99 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:13 CN/8000
a=rtpmap:9 G722/8000
a=rtpmap:110 iLBC/8000
a=rtpmap:99 SPEEX/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=sendonly

16:37:42.058|MSEndPoint.cpp(3810)|Trace5||??:New [email protected]:5482 SILENCE
16:37:42.058|MSEndPoint.cpp(2217)|Trace5||??artyAddrRTP_ is set to 127.0.0.1:40618
16:37:42.058|MSEndPoint.cpp(2218)|Trace5||??artyAddrRTCP_ is set to 127.0.0.1:40619
16:37:42.058|MSEndPoint.cpp(2542)|Log2||??:[MS210000] C:3126.2:Offer received. RTP connection: 127.0.0.1:40618(40619)
16:37:42.058|MSEndPoint.cpp(1852)|Log2||??:[MS210002] C:3126.1:Offer provided. Connection(transcoding mode): локальный адрес:7272(7273)
16:37:42.058|MediaServer.cpp(1099)|Trace5||??:Get Local SDP. EP:C:3126.1SDP:v=

Непосредственно ведется отдельный лог по обращением ос всех адресов на сервер 3cx.Попыток взлома или некорректных обрашений в указанный период не зафиксировано.Только штатный обмен сип с локальными адресами.
Сегодня пользователи услышали сообщение о том что лимит превышен и пожаловались.На момент жалоб наблюдал в пределе 10 разговоров.Кодек 711 и 722.
В чем может быть причина?

 

[igorsnezhko]

В системе все вызовы логируются. Может быть, у вас много звонящих на голосовое меню? Каждый вызов на голосовое меню считается как вызов в системе.

 

[veulk1]

Входящих вызовов в систему на момент наблюдения проблемы 7-8.Проблема возникает не в часы наибольшей нагрузки.

 

[igorsnezhko]

Завтра выйдет версия 14, рекомендую перейти на нее. Тем не менее, у меня складывается впечатление, что у вас кто-то звонит на голосовое меню. Но я могу и ошибаться.

16:37:40.062|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2(‘2’) pressed timestamp=3708881296
16:37:40.080|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2(‘2’) pressed timestamp=3708881388
16:37:40.080|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2(‘2’) pressed timestamp=3708881480
16:37:40.080|DtmfRecognizer.cpp(288)|Trace5||??TMF packet(continue): callStamp=434120 data=b8000a02
16:37:40.101|DtmfRecognizer.cpp(253)|Trace5||??tmf sound detected key #2(‘2’) pressed timestamp=3708881572
16:37:40.120|DtmfRecognizer.cpp(153)|Trace5||??TMF packet(end of tone): callStamp=434120 data=cc018a02

Попробуйте в тот момент, когда есть "левые" звонки, открыть консоль 3CXPhone (иконка в правом верхнем углу софтфона) и посмотреть визуально, что происходит.