[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:16] NoOp("SIP/22279-b5208b10", "is_agent=1 ### have_mapping=0 ### agent_away=0") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:17] NoOp("SIP/22279-b5208b10", "source_type= ### source_num= ### source_num_id= ### source_ext= ### source_ext_id= ### source_dpt_id=") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:18] NoOp("SIP/22279-b5208b10", "middle_type= ### middle_num= ### middle_num_id= ### middle_ext= ### middle_ext_id= ### middle_dpt_id=") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:19] NoOp("SIP/22279-b5208b10", "target_type= ### target_num= ### target_num_id= ### target_ext= ### target_ext_id= ### target_dpt_id=") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:20] NoOp("SIP/22279-b5208b10", "parent_channel=#SIP/22279-b5208b10") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:21] Set("SIP/22279-b5208b10", "i=1") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Set
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:22] GotoIf("SIP/22279-b5208b10", "1?23:26") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-dumpvars,s,23)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: GotoIf
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:23] NoOp("SIP/22279-b5208b10", "target[1]=|||||") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '2'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:24] Set("SIP/22279-b5208b10", "i=2") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Set
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Goto'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:25] Goto("SIP/22279-b5208b10", "22") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-dumpvars,s,22)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Goto
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '0'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:22] GotoIf("SIP/22279-b5208b10", "0?23:26") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-dumpvars,s,26)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: GotoIf
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:26] NoOp("SIP/22279-b5208b10", "Finish for-dumpvars-1") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:27] NoOp("SIP/22279-b5208b10", "v_data=") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-dumpvars:28] NoOp("SIP/22279-b5208b10", "-(E)------------------------------------- dumpvars ---------------") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:40] GotoIf("SIP/22279-b5208b10", "1?41:44") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (default-sip,054,41)
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:41] Set("SIP/22279-b5208b10", "__source_type=agent") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:42] Set("SIP/22279-b5208b10", "DB(call_globals/1201800288.604/source_type)=agent") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Goto'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:43] Goto("SIP/22279-b5208b10", "46") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (default-sip,054,46)
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:46] NoOp("SIP/22279-b5208b10", "Finish if-default_from_local-110") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:47] Set("SIP/22279-b5208b10", "__source_num=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:48] Set("SIP/22279-b5208b10", "DB(call_globals/1201800288.604/source_num)=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:49] Set("SIP/22279-b5208b10", "__source_num_id=34417") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:50] Set("SIP/22279-b5208b10", "DB(call_globals/1201800288.604/source_num_id)=34417") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:51] Set("SIP/22279-b5208b10", "__source_dpt_id=1") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:52] Set("SIP/22279-b5208b10", "DB(call_globals/1201800288.604/source_dpt_id)=1") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:53] Set("SIP/22279-b5208b10", "CALLERID(num)=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:54] Set("SIP/22279-b5208b10", "CALLERID(ANI)=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:55] Set("SIP/22279-b5208b10", "__caller=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:56] Set("SIP/22279-b5208b10", "DB(call_globals/1201800288.604/caller)=22279") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Gosub'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@default-sip:57] Gosub("SIP/22279-b5208b10", "static_ivr|054|1") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Macro'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:1] Macro("SIP/22279-b5208b10", "init_system_cmd|1|0|0") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:1] Set("SIP/22279-b5208b10", "answer=1") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Set
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:2] Set("SIP/22279-b5208b10", "cdr=0") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Set
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:3] Set("SIP/22279-b5208b10", "agent=0") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Set
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:4] GotoIf("SIP/22279-b5208b10", "1?5:6") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-init_system_cmd,s,5)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: GotoIf
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Answer'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:5] Answer("SIP/22279-b5208b10", "") in new stack
[Jan 31 09:24:49] DEBUG[1554] chan_sip.c: SIP answering channel: SIP/22279-b5208b10
[Jan 31 09:24:49] DEBUG[1554] chan_sip.c: Setting framing from config on incoming call
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: Answer
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:6] NoOp("SIP/22279-b5208b10", "Finish if-init_system_cmd-32") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:7] GotoIf("SIP/22279-b5208b10", "1?8:9") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-init_system_cmd,s,8)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: GotoIf
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoCDR'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:8] NoCDR("SIP/22279-b5208b10", "") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoCDR
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:9] NoOp("SIP/22279-b5208b10", "Finish if-init_system_cmd-33") in new stack
[Jan 31 09:24:49] DEBUG[29042] chan_sip.c: Stopping retransmission on '12aa1835334a743a274780556f869599@81.198.164.48' of Response 103: Match Not Found
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '0'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:10] GotoIf("SIP/22279-b5208b10", "0?11:15") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (macro-init_system_cmd,s,15)
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: GotoIf
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [s@macro-init_system_cmd:15] NoOp("SIP/22279-b5208b10", "Finish if-init_system_cmd-34") in new stack
[Jan 31 09:24:49] DEBUG[1554] app_macro.c: Executed application: NoOp
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'NoOp'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:2] NoOp("SIP/22279-b5208b10", "-(B)------------------------------------- static_ivr (054)---------------") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:3] MYSQL("SIP/22279-b5208b10", "Connect connid localhost asterisk AstStgPw09 asterisk") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:4] MYSQL("SIP/22279-b5208b10", "Query result1 3 SELECT 1 FROM asterisk.extensions WHERE (extension="22279" and agent="1") AND ts_deleted IS NULL") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:5] MYSQL("SIP/22279-b5208b10", "Fetch foundRow 4 isAgent") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:6] MYSQL("SIP/22279-b5208b10", "Clear 4") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:7] MYSQL("SIP/22279-b5208b10", "Query result1 3 SELECT extension FROM asteriskcdrdb.agent_extension_mapping WHERE agent="22279"") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:8] MYSQL("SIP/22279-b5208b10", "Fetch have_mapping 4 ext_db") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:9] MYSQL("SIP/22279-b5208b10", "Clear 4") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'MYSQL'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:10] MYSQL("SIP/22279-b5208b10", "Disconnect 3") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Expression result is '1'
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'GotoIf'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:11] GotoIf("SIP/22279-b5208b10", "1?12:26") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Goto (static_ivr,054,12)
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Set'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:12] Set("SIP/22279-b5208b10", "login_workgroup_1=") in new stack
[Jan 31 09:24:49] DEBUG[1554] pbx.c: Launching 'Read'
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Executing [054@static_ivr:13] Read("SIP/22279-b5208b10", "login_workgroup_1|_group_login_gsm|1") in new stack
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- Accepting a maximum of 1 digits.
[Jan 31 09:24:49] DEBUG[1554] rtp.c: Ooh, format changed from unknown to gsm
[Jan 31 09:24:49] DEBUG[1554] rtp.c: Created smoother: format: 2 ms: 20 len: 33
[Jan 31 09:24:49] DEBUG[1554] channel.c: Scheduling timer at 160 sample intervals
[Jan 31 09:24:49] VERBOSE[1554] logger.c:     -- <SIP/22279-b5208b10> Playing '_group_login_gsm' (language 'en')
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Everything is fine.
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '22279-b5208b10' AND host = 'dynamic'
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Everything is fine.
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '22279-b5208b10'
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Everything is fine.
[Jan 31 09:24:49] DEBUG[29016] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '22279' AND host = 'dynamic'
[Jan 31 09:24:49] DEBUG[29530] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[1403] app_queue.c: It's not our turn (SIP/112233-b48ca358).
[Jan 31 09:24:49] DEBUG[1242] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[30820] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[781] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[31438] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[1183] rtp.c: Sending dtmf: 52 (4), at 81.198.164.48
[Jan 31 09:24:49] DEBUG[1183] rtp.c: Sending dtmf: 52 (4), at 81.198.164.48
[Jan 31 09:24:49] DEBUG[32140] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[31975] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[32140] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[1489] app_queue.c: It's not our turn (SIP/112233-b47fcce8).
[Jan 31 09:24:49] DEBUG[32520] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[32648] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[1515] app_queue.c: It's not our turn (SIP/112233-b4501280).
[Jan 31 09:24:49] DEBUG[843] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[32719] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:49] DEBUG[32341] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1183] rtp.c: Sending dtmf: 56 (8), at 81.198.164.48
[Jan 31 09:24:50] DEBUG[1319] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1403] app_queue.c: It's not our turn (SIP/112233-b48ca358).
[Jan 31 09:24:50] DEBUG[1515] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1183] rtp.c: Sending dtmf: 56 (8), at 81.198.164.48
[Jan 31 09:24:50] DEBUG[32648] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: It's our turn (SIP/112233-b4825940).
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: SIP/112233-b4825940 is trying to call a queue member.
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22206' with metric 999357
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22208' with metric 1999724
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22234' with metric 5000000
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22232' with metric 5000000
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22241' with metric 5000000
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22233' with metric 5999219
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22238' with metric 5999354
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22231' with metric 5999395
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22259' with metric 5999396
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Agent/22259 paused, can't receive call
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22223' with metric 5999441
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22279' with metric 5999515
[Jan 31 09:24:50] DEBUG[1183] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22218' with metric 5999590
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22240' with metric 5999639
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22249' with metric 5999786
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22272' with metric 5999796
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22237' with metric 5999837
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22278' with metric 5999945
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22220' with metric 5999954
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Trying 'Agent/22252' with metric 5999961
[Jan 31 09:24:50] DEBUG[1319] app_queue.c: Nobody left to try ringing in queue
[Jan 31 09:24:50] NOTICE[1319] app_queue.c: No one is answering queue '22901' (19/18/0)
[Jan 31 09:24:50] DEBUG[1319] res_config_mysql.c: MySQL RealTime: Everything is fine.
[Jan 31 09:24:50] DEBUG[1319] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM queue_member_table WHERE interface LIKE '%' AND queue_name = '22901' ORDER BY interface
[Jan 31 09:24:50] DEBUG[32321] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1489] app_queue.c: It's not our turn (SIP/112233-b47fcce8).
[Jan 31 09:24:50] DEBUG[32547] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[1515] app_queue.c: It's not our turn (SIP/112233-b4501280).
[Jan 31 09:24:50] DEBUG[31056] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:50] DEBUG[30820] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:51] DEBUG[1183] rtp.c: Got RTCP report of 64 bytes
[Jan 31 09:24:51] DEBUG[499] channel.c: Didn't get a frame from channel: SIP/112233-b521b3b8
[Jan 31 09:24:51] DEBUG[499] channel.c: Bridge stops bridging channels SIP/112233-b521b3b8 and Agent/22240
[Jan 31 09:24:51] DEBUG[499] channel.c: Hanging up channel 'Agent/22240'
[Jan 31 09:24:51] DEBUG[499] chan_agent.c: Hangup called for state Up
[Jan 31 09:24:51] DEBUG[499] channel.c: Hanging up channel 'SIP/22240-087e4030'
[Jan 31 09:24:51] DEBUG[499] chan_sip.c: Hangup call SIP/22240-087e4030, SIP callid 6b9c8293175ddb662abc3dea55e17f1e@81.198.164.117)
[Jan 31 09:24:55] VERBOSE[1569] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
[Jan 31 09:24:55] VERBOSE[1569] logger.c: Asterisk Dynamic Loader Starting: