AMI, очередь rrmemory, много Newchannel, Local/70107@from-op

[Pochekutov.Andrey]

Здравствуйте!

Кратко:
asterisk-certified-13.13
CTI приложение, слушает события AMI. Есть очередь, rrmemory.
Проблема: иногда возникают несколько Newchannel
при попытке соединения с оператором, идут "в разнобой", когда нет свободных операторов.

Подробно (как понимаю я):
Телефоны 3CX softfone
1-3 входящих звонок от 89230number, очеред подняла
4-15 очередь пытается соединить с 70107
но почему создается столько каналов? да еще не последовательно
что происходит внутри Астериска?
что посмотреть?


4 Newchannel 1504060853.2431880 SIP/70107-0013aa25
5 Newstate 1504060853.2431880 SIP/70107-0013aa25 Ringing
6 Newchannel 1504060853.2431881 Local/70107@from-operators-00002a78;1
7 Newchannel 1504060853.2431882 Local/70107@from-operators-00002a78;2
8 Newchannel 1504060853.2431883 SIP/70107-0013aa26
9 Hangup 1504060853.2431880 SIP/70107-0013aa25
10 Newstate 1504060853.2431883 SIP/70107-0013aa26 Ringing
11 Newstate 1504060853.2431881 Local/70107@from-operators-00002a78;1 Ringing
12 HangupRequest 1504060853.2431882 Local/70107@from-operators-00002a78;2
13 Hangup 1504060853.2431883 SIP/70107-0013aa26
14 Hangup 1504060853.2431882 Local/70107@from-operators-00002a78;2
15 Hangup 1504060853.2431881 Local/70107@from-operators-00002a78;1
16-18 очередь пытается соединить с 70109, 70109 поднимает трубку
19-20 вх. кладет трубку, оператор кладет трубку

Для большинства звонков лог выглядит как:
1-3
16-21
т.е. без это чехорды 4-15

Ниже полный лог:

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

1 2017-08-30 09:40:39,208 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70100', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Down'}

2 2017-08-30 09:40:39,213 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70100', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Ring'}

3 2017-08-30 09:40:39,216 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'2', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}

4 2017-08-30 09:40:53,334 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Down'}

5 2017-08-30 09:40:53,470 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Ringing'}

6 2017-08-30 09:40:53,473 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Down'}

7 2017-08-30 09:40:53,475 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}

8 2017-08-30 09:40:53,477 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Down'}

9 2017-08-30 09:40:53,478 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Ringing'}

10 2017-08-30 09:40:53,611 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'70107', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Ringing'}

11 2017-08-30 09:40:53,612 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Ringing'}

12 2017-08-30 09:41:03,339 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}

13 2017-08-30 09:41:03,340 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'70107', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Ringing'}

14 2017-08-30 09:41:03,342 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Unknown',
u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}

15 2017-08-30 09:41:03,343 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Unknown', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Ringing'}


16 2017-08-30 09:41:08,347 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Down'}

17 2017-08-30 09:41:08,461 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Ringing'}

18 2017-08-30 09:41:09,668 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Up'}

19 2017-08-30 09:41:53,054 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'6', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}

20 2017-08-30 09:41:53,056 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Up'}

21 2017-08-30 09:41:53,064 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'6', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}

[ded]

Уважаемый Pochekutov.Andrey!
1) Вам не удалось осветить свои вопросы кратко. Кроме того, все они лежат в области непонимания как работает Астериск. Таких вопросов может быть много, и лучше бы Вам искать ответы на них в документации, которой ну ОЧЕНЬ уже много. Тех учёба на форуме "А почему так?" не приветствуется.
2) Если решили выложить лог (нужно подумать трижды - может не стоит?), то прячьте его под спойлер.
3) Когда Вы поймёте, что простой звонок между абонентами АТС

1-3
16-21
т.е. без это чехорды 4-15

(кстати - чехАрда, игра такая) и звонок через механизм очередь, это две большие разницы - то вопрос почему создается столько каналов? да еще не последовательно
отпадёт сам собой.

[Pochekutov.Andrey]

Добрый день, ded! Благодарю Вас за ответ, как Вы могли заметить я новичек на этом форуме, и Ваши комметарии по поводу ведения общения на нем - мне полезны.

Отвечу Вам с конца,
3) Строки 16-21, эта соединение в той же очереди. Т.е. очередь при определенных обстоятельствах реализует попытку дозвона как 4-15 (неудачную, оператор занят) и дозвон 16-21 (успешное соединиение). Вопрос по прежнему актуален. Да, правильность письма (и разговора) - не сильная моя сторона, предпочитаю за деревьями видеть лес.

2) Ясно.

1) "они лежат в области непонимания как работает Астериск" Вы верно заметили, область моей компетенции, хоть и в IT, но находится в другой плоскости. Но поиском Google и книгами я умею пользоваться, очень хорошо.

Действительно вопросы больше "а почему?"
а) в одном случае 16-21, в другом 4-15
б) почему на один внутренний номер 70107 идет несколько Newchannel?
в) почему в одном случае в Channel: SIP/70107-0013aa25 а в других используется адресация через Local - Local/70107@from-operators-00002a78;1
г) почему ";1" ";2"

Для ответа на эти "почему", нужны более глубокие зания Астериска, которыми я не обладаю. Их можно получить, прочитав исходнные коды очереди, но решил сперва задать вопрос на форуме.


Может кто нибудь сказать почему очередь для сединения с 70107 создает 4 канала?
SIP/70107-0013aa25
Local/70107@from-operators-00002a78;1
Local/70107@from-operators-00002a78;2
SIP/70107-0013aa26


P.S.
Ded, ты показал кто здесь старослужащий. Делом помочь можешь?
https://drive.google.com/open?id=0ByJxw ... lRZMXhXUHc

[ded]

Новичком считается только что прочитавший «Астериск - будущее телефонии»
http://asterisk.ru/knowledgebase/books и пытающийся сделать большее

лучше бы Вам искать ответы на них в документации, которой ну ОЧЕНЬ уже много. Тех учёба на форуме "А почему так?" не приветствуется..

https://forum.asterisk.ru/viewtopic.php?f=5&t=6691

[Pochekutov.Andrey]

Логи из одного дня, с участием оператора 70107.

Случай 1: абонент позвонил, из очереди сразу к 70107

1 09:11:44,092 Newchannel SIP/AST-750-0013a24d
2 09:11:44,097 Newstate SIP/AST-750-0013a24d Ring
3 09:11:44,101 Newstate SIP/AST-750-0013a24d Up

4 09:11:58,301 Newchannel SIP/70107-0013a256
5 09:11:58,419 Newstate SIP/70107-0013a256 Ringing
6 09:12:01,727 Newstate SIP/70107-0013a256 Up
7 09:12:50,955 HangupRequest SIP/70107-0013a256
8 09:12:50,957 Hangup SIP/70107-0013a256
9 09:12:50,960 Hangup SIP/AST-750-0013a24d


Случай 2: абонент позвонил, очередь с 70107 не соединила, соединяет со следующим.

1 09:37:54,864 Newchannel SIP/AST-750-0013a989
2 09:37:54,874 Newstate SIP/AST-750-0013a989 Ring
3 09:37:54,882 Newstate SIP/AST-750-0013a989 Up

4 09:38:09,116 Newchannel SIP/70107-0013a993
5 09:38:09,232 Newstate SIP/70107-0013a993 Ringing
6 09:38:09,238 Hangup SIP/70107-0013a993

7 09:38:09,241 Newchannel SIP/70202-0013a994
8 09:38:09,338 Newstate SIP/70202-0013a994 Ringing
9 09:38:14,069 Newstate SIP/70202-0013a994 Up
10 09:39:49,341 HangupRequest SIP/70202-0013a994
11 09:39:49,379 Hangup SIP/70202-0013a994
12 09:39:49,391 Hangup SIP/AST-750-0013a989


Случай 3: абонент позвонил, очередь с 70107 не соединила создает несколько каналов.

1 09:40:39,208 Newchannel SIP/AST-750-0013aa1c
2 09:40:39,213 Newstate SIP/AST-750-0013aa1c Ring
3 09:40:39,216 Newstate SIP/AST-750-0013aa1c Up

4 09:40:53,334 Newchannel SIP/70107-0013aa25
5 09:40:53,470 Newstate SIP/70107-0013aa25 Ringing
6 09:40:53,473 Newchannel Local/70107@from-operators-00002a78;1
7 09:40:53,475 Newchannel Local/70107@from-operators-00002a78;2
8 09:40:53,477 Newchannel SIP/70107-0013aa26
9 09:40:53,478 Hangup SIP/70107-0013aa25
10 09:40:53,611 Newstate SIP/70107-0013aa26 Ringing
11 09:40:53,612 Newstate Local/70107@from-operators-00002a78;1 Ringing
12 09:41:03,339 HangupRequest Local/70107@from-operators-00002a78;2
13 09:41:03,340 Hangup SIP/70107-0013aa26
14 09:41:03,342 Hangup Local/70107@from-operators-00002a78;2
15 09:41:03,343 Hangup Local/70107@from-operators-00002a78;1

16 09:41:08,347 Newchannel SIP/70109-0013aa2c
17 09:41:08,461 Newstate SIP/70109-0013aa2c Ringing
18 09:41:09,668 SIP/70109-0013aa2c Up
19 09:41:53,054 HangupRequest SIP/AST-750-0013aa1c
20 09:41:53,056 Hangup SIP/70109-0013aa2c
21 09:41:53,064 Hangup SIP/AST-750-0013aa1c


Есть ли у кого идеи? Почему в случае 2 попытка соединить с 70107 это три события строки 4-6,
а ТОЖЕ САМОЕ в случае 3 - это строки 4-15?

Было предположение, что случай 3 возникает когда все операторы заняты и у
очереди нет "следующего" оператора которому начать звонить.
Попробовал на тестовом сервере, не подтвердилось,
но на нем условия далеки от реальных.

В реальной системе, поступило входящих звонок
9:11-9:13 - 30шт
9:37-9:40 - 48шт
9:40-9:42 - 24шт
24 оператора обрабатывали звонки в этих временных интервалах
30 операторов в интервале 9:11-9:42


Полный лог событий ниже.

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

Case 1 log:

1 2017-08-30 09:11:44,092 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059104.2428250', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70100', u'CallerIDNum': u'7291', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'7291', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a24d', u'ChannelStateDesc': u'Down'}
2 2017-08-30 09:11:44,097 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059104.2428250', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70100', u'CallerIDNum': u'7291', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'7291', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a24d', u'ChannelStateDesc': u'Ring'}
3 2017-08-30 09:11:44,101 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059104.2428250', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'7291', u'Priority': u'2', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'7291', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a24d', u'ChannelStateDesc': u'Up'}

4 2017-08-30 09:11:58,301 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059118.2428273', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a256', u'ChannelStateDesc': u'Down'}
5 2017-08-30 09:11:58,419 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'7291', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059118.2428273', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7291', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a256', u'ChannelStateDesc': u'Ringing'}
6 2017-08-30 09:12:01,727 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'7291', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059118.2428273', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7291', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a256', u'ChannelStateDesc': u'Up'}
7 2017-08-30 09:12:50,955 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'7291', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059118.2428273', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7291', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a256', u'ChannelStateDesc': u'Up'}
8 2017-08-30 09:12:50,957 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'7291', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059118.2428273', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7291', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70107-0013a256', u'ChannelStateDesc': u'Up'}
9 2017-08-30 09:12:50,960 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504059104.2428250', u'Uniqueid': u'1504059104.2428250', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'7291', u'Priority': u'6', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'7291', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/AST-750-0013a24d', u'ChannelStateDesc': u'Up'}


Case 2 log:

1 2017-08-30 09:37:54,864 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060674.2431606', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70100', u'CallerIDNum': u'7795297', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'7795297', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a989', u'ChannelStateDesc': u'Down'}
2 2017-08-30 09:37:54,874 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060674.2431606', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70100', u'CallerIDNum': u'7795297', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'7795297', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a989', u'ChannelStateDesc': u'Ring'}
3 2017-08-30 09:37:54,882 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060674.2431606', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'7795297', u'Priority': u'2', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'7795297', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013a989', u'ChannelStateDesc': u'Up'}

4 2017-08-30 09:38:09,116 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431623', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a993', u'ChannelStateDesc': u'Down'}
5 2017-08-30 09:38:09,232 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'7795297', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431623', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013a993', u'ChannelStateDesc': u'Ringing'}
6 2017-08-30 09:38:09,238 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'7795297', u'Cause-txt': u'User busy', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431623', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'17', u'Channel': u'SIP/70107-0013a993', u'ChannelStateDesc': u'Ringing'}

7 2017-08-30 09:38:09,241 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431624', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'Operator 70202', u'Privilege': u'call,all', u'Channel': u'SIP/70202-0013a994', u'ChannelStateDesc': u'Down'}
8 2017-08-30 09:38:09,338 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'7795297', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431624', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'Operator 70202', u'Privilege': u'call,all', u'Channel': u'SIP/70202-0013a994', u'ChannelStateDesc': u'Ringing'}
9 2017-08-30 09:38:14,069 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'7795297', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431624', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'Operator 70202', u'Privilege': u'call,all', u'Channel': u'SIP/70202-0013a994', u'ChannelStateDesc': u'Up'}
10 2017-08-30 09:39:49,341 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'7795297', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431624', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'Operator 70202', u'Privilege': u'call,all', u'Channel': u'SIP/70202-0013a994', u'ChannelStateDesc': u'Up'}
11 2017-08-30 09:39:49,379 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'7795297', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060689.2431624', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'7795297', u'Context': u'from-operators', u'CallerIDName': u'Operator 70202', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70202-0013a994', u'ChannelStateDesc': u'Up'}
12 2017-08-30 09:39:49,391 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060674.2431606', u'Uniqueid': u'1504060674.2431606', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'7795297', u'Priority': u'6', u'ConnectedLineName': u'Operator 70202', u'Context': u'cc-cti', u'CallerIDName': u'7795297', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/AST-750-0013a989', u'ChannelStateDesc': u'Up'}


Case 3 log:

1 2017-08-30 09:40:39,208 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70100', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Down'}
2 2017-08-30 09:40:39,213 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70100', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-out', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Ring'}
3 2017-08-30 09:40:39,216 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'2', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}

4 2017-08-30 09:40:53,334 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Down'}
5 2017-08-30 09:40:53,470 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Ringing'}
6 2017-08-30 09:40:53,473 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Down'}
7 2017-08-30 09:40:53,475 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}
8 2017-08-30 09:40:53,477 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Down'}
9 2017-08-30 09:40:53,478 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431880', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70107-0013aa25', u'ChannelStateDesc': u'Ringing'}
10 2017-08-30 09:40:53,611 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'70107', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Ringing'}
11 2017-08-30 09:40:53,612 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Ringing'}

12 2017-08-30 09:41:03,339 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}
13 2017-08-30 09:41:03,340 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431883', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'70107', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70107-0013aa26', u'ChannelStateDesc': u'Ringing'}
14 2017-08-30 09:41:03,342 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Unknown', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431882', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'4', u'Exten': u'70107', u'CallerIDNum': u'89230number', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;2', u'ChannelStateDesc': u'Ring'}
15 2017-08-30 09:41:03,343 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Unknown', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060853.2431881', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'70107', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'0', u'Channel': u'Local/70107@from-operators-00002a78;1', u'ChannelStateDesc': u'Ringing'}


16 2017-08-30 09:41:08,347 [Thread-1 ] [DEBUG] Event : Newchannel -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'en', u'AccountCode': u'', u'ChannelState': u'0', u'Exten': u's', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'<unknown>', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Down'}
17 2017-08-30 09:41:08,461 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'5', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Ringing'}
18 2017-08-30 09:41:09,668 [Thread-1 ] [DEBUG] Event : Newstate -> {u'ConnectedLineNum': u'89230number', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Up'}

19 2017-08-30 09:41:53,054 [Thread-1 ] [DEBUG] Event : HangupRequest -> {u'ConnectedLineNum': u'<unknown>', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'6', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}
20 2017-08-30 09:41:53,056 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'89230number', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060868.2431891', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'<unknown>', u'Priority': u'1', u'ConnectedLineName': u'89230number', u'Context': u'from-operators', u'CallerIDName': u'<unknown>', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/70109-0013aa2c', u'ChannelStateDesc': u'Up'}
21 2017-08-30 09:41:53,064 [Thread-1 ] [DEBUG] Event : Hangup -> {u'ConnectedLineNum': u'<unknown>', u'Cause-txt': u'Normal Clearing', u'Linkedid': u'1504060839.2431857', u'Uniqueid': u'1504060839.2431857', u'Language': u'ru', u'AccountCode': u'', u'ChannelState': u'6', u'Exten': u'cc-cti-on', u'CallerIDNum': u'89230number', u'Priority': u'6', u'ConnectedLineName': u'<unknown>', u'Context': u'cc-cti', u'CallerIDName': u'89230number', u'Privilege': u'call,all', u'Cause': u'16', u'Channel': u'SIP/AST-750-0013aa1c', u'ChannelStateDesc': u'Up'}

[Repz]

Есть ли у кого идеи? Почему в случае 2 попытка соединить с 70107 это три события строки 4-6,
а ТОЖЕ САМОЕ в случае 3 - это строки 4-15?

Потому что земля круглая, а телепаты в отпуске.
У очередей много зависимостей. Для начала откройте cdr вызова и посмотрите почему ответил именно этот оператор.
Какая стратегия, пенал, доступные операторы?!
...только портянки неинтересных ивентов
касательно первого поста - а кто писал диалплан? может стоит сначала диалплан разобрать, а потом уже парсить ивенты?!
в общем - вода мокрая.

[ded]

+++

[Pochekutov.Andrey]

"Для начала откройте cdr вызова и посмотрите почему ответил именно этот оператор."
Описанная проблема происходит на любом операторе, взят первый попавшийся 70107.


"Какая стратегия, пенал, доступные операторы?!
...только портянки неинтересных ивентов
касательно первого поста - а кто писал диалплан? может стоит сначала диалплан разобрать, а потом уже парсить ивенты?!"

Выложил конфиги. Диалплан писал наш системный администратор.

"...только портянки неинтересных ивентов"
Да собственно в них то и дело, связь работает исправно. Написанное CTI приложение обрабатывает "Случай 1 и 2".
Приблизительно 5-10 раз за час (возникает только в реальной системе) - идут ивенты как описано в "Случай 3".

Хочется понять почему возникает "Случай 3", либо устранить в реальной, либо повторить на тестовом сервере.



queues.conf

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

[general]

persistentmembers = yes
monitor-type = MixMonitor
shared_lastcall=yes
autofill = yes;
musicclass = default

[CallCenter-cti]
musicclass = default
autofill = yes;
ringinuse=no;
joinempty = yes
strategy = rrmemory
maxlen = 100
wrapuptime = 5
timeout = 10;

announce-holdtime = no
announce-position = no
announce-frequency = 0
min-announce-frequency = 30

;("You are now first in line.")
queue-youarenext = ;queue-youarenext
queue-thereare = ;queue-thereare ;("There are")
queue-holdtime = ;queue-holdtime ;("The current est. holdtime is")
queue-minute = ;queue-minute ;("minute.")
queue-minutes = ;queue-minutes ;("minutes.")
queue-seconds = ;queue-seconds ;("seconds.")
queue-thankyou = ;queue-thankyou ;("Thank you for your patience.")
queue-reporthold = ;queue-reporthold ;("Hold time")
periodic-announce = /var/lib/asterisk/sounds/path
periodic-announce-frequency = 180


member => SIP/70101, 15, 70101
member => SIP/70102, 10, 70102
member => SIP/70103, 10, 70103
...
member => SIP/70121, 10, 70121
member => SIP/70122, 20, 70122
member => SIP/70123, 20, 70123
...
member => SIP/70201, 15, 70201
member => SIP/70202, 15, 70202
...
member => SIP/70229, 15, 70229
member => SIP/70311, 30, 70311
...
member => SIP/70328, 30, 70328

extensions.conf

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

[general]
static=yes

[globals]
UPLINK=SIP/AST-750


[local-calls]
...
exten => 70100,1,Goto(cc-cti,cc-cti-input-point,1)
...


[from-out]
include => local-calls
...


[cc-cti]

exten => cc-cti-input-point,1,Noop()
; Проверки на рабочее/не рабочее время.
same => n,GotoIfTime(16:00-23:59,*,31,12?cc-cti-off,1) ; Новый год

...

; Обычные рабочие дни:
same => n,GotoIfTime(8:00-20:59,mon-thu,*,*?cc-cti-on,1) ; С понедельника по четверг
same => n,GotoIfTime(8:00-20:59,fri,*,*?cc-cti-on,1) ; в пятницу

...

same => n,Goto(cc-cti-off,1)



exten => cc-cti-off,1,Noop()
; Звонок в НЕРАБОЧЕЕ время.
same => n,QueueLog(CallCenter-cti,${UNIQUEID},NONE,CALLTOCLOSED,${CALLERID(num)})
same => n,Playback(some-path)
same => n,Hangup()


exten => cc-cti-on,1,Noop()
; Звонок в РАБОЧЕЕ время
same => n,Answer()
same => n,Wait(1)
same => n,QueueLog(CallCenter-cti,${UNIQUEID},NONE,LISTEN,${CALLERID(num)})
same => n,Playback(some-path)
same => n,Queue(CallCenter-cti,Tt,,,1180)
same => n,Playback(some-path)
same => n,Hangup()

exten => _701XX,1, Dial(SIP/${EXTEN},120,Tt)

sip.conf

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

[operators-phone]
type=friend
nat=no
host=dynamic
dtmfmode=rfc2833
disallow=all
allow=alaw
allow=ulaw
context=from-operators
callcounter=1
deny=0.0.0.0/0.0.0.0
permit=10.60.0.0/255.255.0.0
allowsubscribe=no


[70101](operators-phone)
defaultuser=70101
...

[Repz]

вызов Local происходит в контексте from-operators, а его в вашем диалплане нет, это настораживает дежурных телепатов.

[Pochekutov.Andrey]

Связь работает. Диалплан простейший, без усложнений. В конфигах где троеточие "..." - удалил то, что посчитал конфиденциальным.

extensions.conf дополнение с from-operators

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

[globals]
UPLINK=SIP/AST-750

[context-external]
include => local-calls
include => local-trunks
...

[from-operators]
include => local-calls
include => local-trunks
...

[local-calls]
...
exten => 70100,1,Goto(cc-cti,cc-cti-input-point,1)
...


[from-out]
include => local-calls
...