ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a13cf-nu28d30hif0s Their Tag y2imqwplr2 Our tag: as642f0ca2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10986f7-ganode8jc6mb Their Tag hoj9lemooz Our tag: as4effa9a6 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10973ac-l2ebsl6y8tim Their Tag o8ozb5jchv Our tag: as1b336e68 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10960c9-su9xi7tw1yth Their Tag 7zn15lybmh Our tag: as74a1ff5f [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1093dbb-6m30ue70ul3r Their Tag j1hiimsuqh Our tag: as00331630 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1092b45-ea5xgnpcpqnb Their Tag qkrypltzh5 Our tag: as713efd5b [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c109121b-mgu3dn6h4oec Their Tag 8zwgyw7fxi Our tag: as5015c0ba [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108cdec-t65pqk9uzb47 Their Tag fc82o1b5yo Our tag: as38d57733 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108a553-cxhbxya2dk9o Their Tag zyk50zqnct Our tag: as5e942a9e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107bb48-1uf95vyifzhz Their Tag t42yish3cb Our tag: as3d64bc9d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10876b9-psq4kfilthfz Their Tag bh62nhdrnu Our tag: as3f89845c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107d0a4-lvp7bxzx8dib Their Tag asyli23l0s Our tag: as7057b25c [Mar 22 11:13:58] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.194 with 192.168.0.0 [Mar 22 11:13:58] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.194 with 10.0.0.0 [Mar 22 11:13:58] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.194 with 172.16.0.0 [Mar 22 11:13:58] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.194 with 169.254.0.0 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4795 sip_alloc: Allocating new SIP dialog for 961ffa4b-f7580de4-679e9aa1@192.168.30.194 - INVITE (With RTP) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as6bbb70dd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '961ffa4b-f7580de4-679e9aa1@192.168.30.194' of Response 1: Match Found [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as6bbb70dd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP v=0... UNSUPPORTED. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP o=- 1269270835 1269270835 IN IP4 192.168.30.194... UNSUPPORTED. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP s=Polycom IP Phone... UNSUPPORTED. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP c=IN IP4 192.168.30.194... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP a=sendrecv... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5593 process_sdp: T38 state changed to 0 on channel [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:5726 process_sdp: We're settling with these formats: 0x1004 (ulaw|g722) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:15141 handle_request_invite: Checking SIP call limits for device 6010 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:3386 update_call_counter: Updating call counter for incoming call [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:3463 update_call_counter: Call from peer '6010' is 1 out of 8 [Mar 22 11:13:58] DEBUG[12787]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state InUse for Notify User 6002 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4241 sip_new: *** Our native formats are 0x4 (ulaw) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4242 sip_new: *** Joint capabilities are 0x1004 (ulaw|g722) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4243 sip_new: *** Our capabilities are 0x1004 (ulaw|g722) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4244 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4267 sip_new: This channel will not be able to handle video. [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:8917 build_route: build_route: Contact hop: [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:15245 handle_request_invite: SIP/6010-00000017: New call is still down.... Trying... [Mar 22 11:13:58] DEBUG[12787]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [Mar 22 11:13:58] DEBUG[12861]: app_queue.c:706 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state InUse for Notify User 6010 [Mar 22 11:13:58] DEBUG[12815]: manager.c:2244 process_message: Manager received command 'GetVar' [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state InUse for Notify User 6000 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12861]: app_queue.c:706 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [Mar 22 11:13:58] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Answer' -- Executing [*901@default-super:1] Answer("SIP/6010-00000017", "") in new stack [Mar 22 11:13:58] DEBUG[10443]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [Mar 22 11:13:58] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:13:58] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:13:58] DEBUG[12861]: app_queue.c:706 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:3866 sip_answer: SIP answering channel: SIP/6010-00000017 [Mar 22 11:13:58] DEBUG[10443]: rtp.c:2091 ast_rtp_update_source: Setting the marker bit due to a source update [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:7239 transmit_response_with_sdp: Setting framing from config on incoming call [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:6954 add_sdp: ** Our capability: 0x1004 (ulaw|g722) Video flag: True [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:6955 add_sdp: ** Our prefcodec: 0x0 (nothing) [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:7072 add_sdp: -- Done with adding codecs to SDP [Mar 22 11:13:58] DEBUG[10443]: channel.c:2561 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) [Mar 22 11:13:58] DEBUG[10443]: chan_sip.c:7181 add_sdp: Done building SDP. Settling with this capability: 0x1004 (ulaw|g722) [Mar 22 11:13:58] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'AGI' -- Executing [*901@default-super:2] AGI("SIP/6010-00000017", "agi://127.0.0.1:4573/extension?arg1=agent&arg2=default") in new stack [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: extension?arg1=agent&arg2=default [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:13:58] DEBUG[10443]: res_agi.c:249 launch_netscript: Wow, connected! AGI Tx >> agi_request: agi://127.0.0.1:4573/extension?arg1=agent&arg2=default AGI Tx >> agi_channel: SIP/6010-00000017 AGI Tx >> agi_language: en AGI Tx >> agi_type: SIP [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: AGI Tx >> agi_uniqueid: 1269270838.31 = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 AGI Tx >> agi_callerid: 6010 AGI Tx >> agi_calleridname: 6010 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 0 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe AGI Tx >> agi_callingtns: 0 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 125 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on 'f4c13806-8f3d6ab-a8a32d78@192.168.30.190' of Request 125: Match Found AGI Tx >> agi_dnid: *901 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: default-super AGI Tx >> agi_extension: *901 AGI Tx >> agi_priority: 2 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: 6010 AGI Tx >> [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 AGI Rx << SET VARIABLE PHONE_CONTEXT "default-agent" AGI Tx >> 200 result=1 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 125 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on 'f0f92d14-468e1a91-a64a33c2@192.168.30.194' of Request 125: Match Found [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 137 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '3c27c10a1ea0-biobz6rjinmr' of Request 137: Match Found [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 22 11:13:58] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '961ffa4b-f7580de4-679e9aa1@192.168.30.194' of Response 2: Match Found AGI Rx << SET VARIABLE PHONE_EXTEN "6010" AGI Tx >> 200 result=1 AGI Rx << GET VARIABLE PHONE_EXTEN_HD AGI Tx >> 200 result=0 AGI Rx << SET VARIABLE PHONE_CHAN "SIP/6010" AGI Tx >> 200 result=1 AGI Rx << SET VARIABLE PHONE_TENANT "default" AGI Tx >> 200 result=1 -- AGI Script agi://127.0.0.1:4573/extension?arg1=agent&arg2=default completed, returning 0 [Mar 22 11:13:58] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '6010' [Mar 22 11:13:58] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:3] Set("SIP/6010-00000017", "GROUP(OUTGOING)=6010") in new stack [Mar 22 11:13:58] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Wait' -- Executing [*901@default-super:4] Wait("SIP/6010-00000017", "1") in new stack [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a13cf-nu28d30hif0s Their Tag y2imqwplr2 Our tag: as642f0ca2 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10986f7-ganode8jc6mb Their Tag hoj9lemooz Our tag: as4effa9a6 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10973ac-l2ebsl6y8tim Their Tag o8ozb5jchv Our tag: as1b336e68 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10960c9-su9xi7tw1yth Their Tag 7zn15lybmh Our tag: as74a1ff5f [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1093dbb-6m30ue70ul3r Their Tag j1hiimsuqh Our tag: as00331630 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1092b45-ea5xgnpcpqnb Their Tag qkrypltzh5 Our tag: as713efd5b [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c109121b-mgu3dn6h4oec Their Tag 8zwgyw7fxi Our tag: as5015c0ba [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108cdec-t65pqk9uzb47 Their Tag fc82o1b5yo Our tag: as38d57733 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108a553-cxhbxya2dk9o Their Tag zyk50zqnct Our tag: as5e942a9e [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107bb48-1uf95vyifzhz Their Tag t42yish3cb Our tag: as3d64bc9d [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10876b9-psq4kfilthfz Their Tag bh62nhdrnu Our tag: as3f89845c [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107d0a4-lvp7bxzx8dib Their Tag asyli23l0s Our tag: as7057b25c [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 192.168.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 10.0.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 172.16.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 169.254.0.0 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4795 sip_alloc: Allocating new SIP dialog for 32d5c9a15b590f3f122aaae855257125@192.168.30.165 - REGISTER (No RTP) [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4795 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 192.168.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 10.0.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 172.16.0.0 [Mar 22 11:13:59] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.165 with 169.254.0.0 [Mar 22 11:13:59] DEBUG[12787]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/siptrunk [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - siptrunk [Mar 22 11:13:59] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer siptrunk [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/siptrunk - state 1 (Not in use) [Mar 22 11:13:59] DEBUG[12861]: app_queue.c:701 handle_statechange: Device 'SIP/siptrunk' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 70dda1ef19acfe7e184626da7135e1af@192.168.30.254 Their Tag Our tag: as473321d1 [Mar 22 11:13:59] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '70dda1ef19acfe7e184626da7135e1af@192.168.30.254' of Request 102: Match Found [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:5] Set("SIP/6010-00000017", "DBKEY=default/agent/static/Local/6010@default-agent/n") in new stack [Mar 22 11:13:59] DEBUG[10443]: res_odbc.c:503 odbc_obj_disconnect: Disconnected 0 from scopserv [scopserv] [Mar 22 11:13:59] DEBUG[10443]: res_odbc.c:510 odbc_obj_disconnect: Database handle deallocated [Mar 22 11:13:59] NOTICE[10443]: res_odbc.c:535 odbc_obj_connect: Re-connecting scopserv [Mar 22 11:13:59] NOTICE[10443]: res_odbc.c:565 odbc_obj_connect: res_odbc: Connected to scopserv [scopserv] [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'default/agent/static/Local/6010@default-agent/n' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '7' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '10' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:6] GotoIf("SIP/6010-00000017", "0?7:10") in new stack -- Goto (default-super,*901,10) [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'agent/byagentid/6010' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:10] Set("SIP/6010-00000017", "AGENT_ID=1") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:11] GotoIf("SIP/6010-00000017", "0?400") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'agent/channel/1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '13' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '14' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:12] GotoIf("SIP/6010-00000017", "1?13:14") in new stack -- Goto (default-super,*901,13) [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'agent/channel/1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'Local/6010@default-agent/n' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:13] Set("SIP/6010-00000017", "AGENTCHAN=Local/6010@default-agent/n") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:14] GotoIf("SIP/6010-00000017", "0?300") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'AGI' -- Executing [*901@default-super:15] AGI("SIP/6010-00000017", "agi://127.0.0.1:4573/agent?arg1=default&arg2=Local/6010@default-agent/n&arg3=pausecheck") in new stack AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: agent?arg1=default&arg2=Local/6010@default-agent/n&arg3=pausecheck [Mar 22 11:13:59] DEBUG[10443]: res_agi.c:249 launch_netscript: Wow, connected! AGI Tx >> agi_request: agi://127.0.0.1:4573/agent?arg1=default&arg2=Local/6010@default-agent/n&arg3=pausecheck AGI Tx >> agi_channel: SIP/6010-00000017 AGI Tx >> agi_language: en AGI Tx >> agi_type: SIP AGI Tx >> agi_uniqueid: 1269270838.31 AGI Tx >> agi_callerid: 6010 AGI Tx >> agi_calleridname: 6010 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 0 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: *901 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: default-super AGI Tx >> agi_extension: *901 AGI Tx >> agi_priority: 15 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: 6010 AGI Tx >> [Mar 22 11:13:59] DEBUG[10447]: manager.c:2244 process_message: Manager received command 'login' == Parsing '/etc/asterisk/manager.conf': [Mar 22 11:13:59] DEBUG[10447]: config.c:902 config_text_file_load: Parsing /etc/asterisk/manager.conf FoundLI> [Mar 22 11:13:59] DEBUG[10447]: acl.c:355 ast_append_ha: 127.0.0.1/255.255.255.255 appended to acl for peer [Mar 22 11:13:59] DEBUG[10447]: acl.c:370 ast_apply_ha: ##### Testing 127.0.0.1 with 127.0.0.1 [Mar 22 11:13:59] DEBUG[10447]: manager.c:2244 process_message: Manager received command 'Command' AGI Rx << SET VARIABLE AGENTSTATUS "UNPAUSED" AGI Tx >> 200 result=1 -- AGI Script agi://127.0.0.1:4573/agent?arg1=default&arg2=Local/6010@default-agent/n&arg3=pausecheck completed, returning 0 [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '18' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:16] GotoIf("SIP/6010-00000017", "1?18") in new stack -- Goto (default-super,*901,18) [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:18] GotoIf("SIP/6010-00000017", "0?200") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:19] GotoIf("SIP/6010-00000017", "0?400") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:20] GotoIf("SIP/6010-00000017", "0?400") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'PauseQueueMember' -- Executing [*901@default-super:21] PauseQueueMember("SIP/6010-00000017", "|Local/6010@default-agent/n") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:22] Set("SIP/6010-00000017", "SCOPSERV_DBPUT(default/Local/6010@default-agent/n)=PAUSED") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'PAUSED' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'default/Local/6010@default-agent/n' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:23] Set("SIP/6010-00000017", "DEVSTATE(Custom:agent-1)=RINGING") in new stack [Mar 22 11:13:59] DEBUG[10443]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Custom:agent-1 [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:157 ast_device_state: Checking if I can find provider for "Custom" - number: agent-1 [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider SLA with Custom [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider Meetme with Custom [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider Custom with Custom [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for Custom:agent-1 - state 6 (Ringing) [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:24] Set("SIP/6010-00000017", "SCOPSERV_DBPUT(default/6010/Agent/Paused)=YES") in new stack [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:157 ast_device_state: Checking if I can find provider for "Custom" - number: agent-1 [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider SLA with Custom [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider Meetme with Custom [Mar 22 11:13:59] DEBUG[12731]: devicestate.c:230 getproviderstate: Checking provider Custom with Custom [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'YES' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'default/6010/Agent/Paused' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [*901@default-super:25] Set("SIP/6010-00000017", "SCOPSERV_DBPUT(Queue/PersistentAgents/Local/6010@default-agent/n)=PAUSED") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'PAUSED' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'Queue/PersistentAgents/Local/6010@default-agent/n' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'UserEvent' -- Executing [*901@default-super:26] UserEvent("SIP/6010-00000017", "AgentPause|Agent: 1|Channel: Local/6010@default-agent/n") in new stack [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '30' [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [*901@default-super:27] GotoIf("SIP/6010-00000017", "1?30") in new stack -- Goto (default-super,*901,30) [Mar 22 11:13:59] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Playback' -- Executing [*901@default-super:30] Playback("SIP/6010-00000017", "scopserv/agent-paused") in new stack [Mar 22 11:13:59] DEBUG[10443]: rtp.c:2929 ast_rtp_write: Ooh, format changed from unknown to ulaw [Mar 22 11:13:59] DEBUG[10443]: rtp.c:2946 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 -- Playing 'scopserv/agent-paused' (language 'en') [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '0' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1859 pbx_extension_helper: Launching 'Hangup' -- Executing [*901@default-super:31] Hangup("SIP/6010-00000017", "0") in new stack [Mar 22 11:14:00] DEBUG[10443]: pbx.c:2413 __ast_pbx_run: Spawn extension (default-super,*901,31) exited non-zero on 'SIP/6010-00000017' == Spawn extension (default-super, *901, 31) exited non-zero on 'SIP/6010-00000017' [Mar 22 11:14:00] DEBUG[10443]: channel.c:1482 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/6010-00000017' [Mar 22 11:14:00] DEBUG[10443]: channel.c:1585 ast_hangup: Hanging up channel 'SIP/6010-00000017' [Mar 22 11:14:00] DEBUG[10443]: chan_sip.c:3703 sip_hangup: Hangup call SIP/6010-00000017, SIP callid 961ffa4b-f7580de4-679e9aa1@192.168.30.194) [Mar 22 11:14:00] DEBUG[10443]: chan_sip.c:3386 update_call_counter: Updating call counter for incoming call [Mar 22 11:14:00] DEBUG[10443]: chan_sip.c:3437 update_call_counter: Call from peer '6010' removed from call limit 8 [Mar 22 11:14:00] DEBUG[10443]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 1 (Not in use) [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state Idle for Notify User 6002 [Mar 22 11:14:00] DEBUG[12861]: app_queue.c:706 handle_statechange: Device 'SIP/6010' changed to state '1' (Not in use) [Mar 22 11:14:00] DEBUG[10443]: cdr_addon_mysql.c:228 mysql_log: cdr_mysql: inserting a CDR record. [Mar 22 11:14:00] DEBUG[10443]: cdr_addon_mysql.c:245 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr_pre (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2010-03-22 11:13:58','\"6010\" <6010>','6010','*901','default-super', 'SIP/6010-00000017','','Hangup','0',2,2,'ANSWERED',3,'6010','1269270838.31','') [Mar 22 11:14:00] DEBUG[10443]: cdr_radius.c:221 radius_log: Unable to create RADIUS record. CDR not recorded! [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '"6010" <6010>' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '6010' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '*901' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'default-super' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'SIP/6010-00000017' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '(null)' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'Hangup' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '0' [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state Idle for Notify User 6010 [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '2010-03-22 11:13:58' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '2010-03-22 11:13:58' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '2010-03-22 11:14:00' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '2' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '2' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 Extension Changed 6010[default-local] new state Idle for Notify User 6000 [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '6010' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '1269270838.31' [Mar 22 11:14:00] DEBUG[10443]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '(null)' [Mar 22 11:14:00] DEBUG[10443]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 1 (Not in use) [Mar 22 11:14:00] DEBUG[12861]: app_queue.c:706 handle_statechange: Device 'SIP/6010' changed to state '1' (Not in use) [Mar 22 11:14:00] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6010 [Mar 22 11:14:00] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6010 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 126 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on 'f4c13806-8f3d6ab-a8a32d78@192.168.30.190' of Request 126: Match Found [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '961ffa4b-f7580de4-679e9aa1@192.168.30.194' of Request 102: Match Found [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 126 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on 'f0f92d14-468e1a91-a64a33c2@192.168.30.194' of Request 126: Match Found [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 961ffa4b-f7580de4-679e9aa1@192.168.30.194 Their Tag B8604012-767B4807 Our tag: as45c39a19 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 138 [Mar 22 11:14:00] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '3c27c10a1ea0-biobz6rjinmr' of Request 138: Match Found [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a13cf-nu28d30hif0s Their Tag y2imqwplr2 Our tag: as642f0ca2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10986f7-ganode8jc6mb Their Tag hoj9lemooz Our tag: as4effa9a6 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10973ac-l2ebsl6y8tim Their Tag o8ozb5jchv Our tag: as1b336e68 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10960c9-su9xi7tw1yth Their Tag 7zn15lybmh Our tag: as74a1ff5f [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1093dbb-6m30ue70ul3r Their Tag j1hiimsuqh Our tag: as00331630 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1092b45-ea5xgnpcpqnb Their Tag qkrypltzh5 Our tag: as713efd5b [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c109121b-mgu3dn6h4oec Their Tag 8zwgyw7fxi Our tag: as5015c0ba [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108cdec-t65pqk9uzb47 Their Tag fc82o1b5yo Our tag: as38d57733 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108a553-cxhbxya2dk9o Their Tag zyk50zqnct Our tag: as5e942a9e [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107bb48-1uf95vyifzhz Their Tag t42yish3cb Our tag: as3d64bc9d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10876b9-psq4kfilthfz Their Tag bh62nhdrnu Our tag: as3f89845c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107d0a4-lvp7bxzx8dib Their Tag asyli23l0s Our tag: as7057b25c [Mar 22 11:14:02] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 192.168.0.0 [Mar 22 11:14:02] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 10.0.0.0 [Mar 22 11:14:02] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 172.16.0.0 [Mar 22 11:14:02] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 169.254.0.0 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4795 sip_alloc: Allocating new SIP dialog for 626390ad-21b7458a-f679ec4f@192.168.30.190 - INVITE (With RTP) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as0ac24583 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '626390ad-21b7458a-f679ec4f@192.168.30.190' of Response 1: Match Found [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as0ac24583 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2893 do_setnat: Setting NAT on RTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2898 do_setnat: Setting NAT on VRTP to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2903 do_setnat: Setting NAT on UDPTL to Off [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP v=0... UNSUPPORTED. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP o=- 1269270838 1269270838 IN IP4 192.168.30.190... UNSUPPORTED. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP s=Polycom IP Phone... UNSUPPORTED. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP c=IN IP4 192.168.30.190... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5430 process_sdp: Processing session-level SDP a=sendrecv... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5568 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5593 process_sdp: T38 state changed to 0 on channel [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:5726 process_sdp: We're settling with these formats: 0x1004 (ulaw|g722) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:15141 handle_request_invite: Checking SIP call limits for device 6002 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:3386 update_call_counter: Updating call counter for incoming call [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:3463 update_call_counter: Call from peer '6002' is 1 out of 8 [Mar 22 11:14:02] DEBUG[12787]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6002 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4241 sip_new: *** Our native formats are 0x4 (ulaw) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4242 sip_new: *** Joint capabilities are 0x1004 (ulaw|g722) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4243 sip_new: *** Our capabilities are 0x1004 (ulaw|g722) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4244 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4267 sip_new: This channel will not be able to handle video. [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:8917 build_route: build_route: Contact hop: [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:15245 handle_request_invite: SIP/6002-00000018: New call is still down.... Trying... [Mar 22 11:14:02] DEBUG[12787]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6002 [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12815]: manager.c:2244 process_message: Manager received command 'GetVar' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [6500@default-super:1] Set("SIP/6002-00000018", "LOCAL_EXTEN=6500") in new stack [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6002 - state 2 (In use) [Mar 22 11:14:02] DEBUG[12861]: app_queue.c:701 handle_statechange: Device 'SIP/6002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Gosub' -- Executing [6500@default-super:2] Gosub("SIP/6002-00000018", "all-local-extension|s|1") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@all-local-extension:1] Set("SIP/6002-00000018", "__PICKUPMARK=6500") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '4' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@all-local-extension:2] GotoIf("SIP/6002-00000018", "0?4") in new stack [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch Extension Changed 6002[default-local] new state InUse for Notify User 6010 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is '6002' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@all-local-extension:3] Set("SIP/6002-00000018", "GROUP(OUTGOING)=6002") in new stack [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@all-local-extension:4] Set("SIP/6002-00000018", "OUTBOUND_GROUP_ONCE=6500@INCOMING") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '8' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Extension Changed 6002[default-local] new state InUse for Notify User 6000 Launching 'GotoIf' -- Executing [s@all-local-extension:5] GotoIf("SIP/6002-00000018", "1?8") in new stack [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: -- Goto (all-local-extension,s,8) Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6002 - state 2 (In use) [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Return' -- Executing [s@all-local-extension:8] Return("SIP/6002-00000018", "") in new stack [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[12861]: app_queue.c:701 handle_statechange: Device 'SIP/6002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Ringing' -- Executing [6500@default-super:3] Ringing("SIP/6002-00000018", "") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Goto' -- Executing [6500@default-super:4] Goto("SIP/6002-00000018", "default-aa-followme-6500|s|1") in new stack -- Goto (default-aa-followme-6500,s,1) [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as3ea64480 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 113 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '31f6897b-f9e6bdd4-b1887e51@192.168.30.194' of Request 113: Match Found [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-followme-6500:1] Set("SIP/6002-00000018", "GLOBAL(__SCREEN_EXTENSION)=DEFAULT_ONCALL_6500") in new stack == Setting global variable '__SCREEN_EXTENSION' to 'DEFAULT_ONCALL_6500' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-followme-6500:2] Set("SIP/6002-00000018", "QUEUE_PRIO=0") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Gosub' -- Executing [s@default-aa-followme-6500:3] Gosub("SIP/6002-00000018", "default-application-acd-sales|s|1") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:1] Set("SIP/6002-00000018", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack [Mar 22 11:14:02] DEBUG[10483]: func_audiohookinherit.c:146 setup_inheritable_audiohook: Set audiohook MixMonitor to be inheritable [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'NoOp' -- Executing [s@default-application-acd-sales:2] NoOp("SIP/6002-00000018", ""=== START QUEUE (default-sales) ==="") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:3] Set("SIP/6002-00000018", "__QUEUENAME=default-sales") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '7' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@default-application-acd-sales:4] GotoIf("SIP/6002-00000018", "0?7") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:5] Set("SIP/6002-00000018", "MONITOR_FILENAME=1269270842.32") in new stack [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '8' [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Goto' -- Executing [s@default-application-acd-sales:6] Goto("SIP/6002-00000018", "8") in new stack -- Goto (default-application-acd-sales,s,8) [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:8] Set("SIP/6002-00000018", "TIMEOUT(digit)=1") in new stack -- Digit timeout set to 1 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:9] Set("SIP/6002-00000018", "TIMEOUT(response)=1") in new stack -- Response timeout set to 1 [Mar 22 11:14:02] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'BackGround' -- Executing [s@default-application-acd-sales:10] BackGround("SIP/6002-00000018", "custom/0001") in new stack [Mar 22 11:14:02] DEBUG[10483]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6002 [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:287 do_state_change: Changing state for SIP/6002 - state 2 (In use) [Mar 22 11:14:02] DEBUG[12861]: app_queue.c:701 handle_statechange: Device 'SIP/6002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 22 11:14:02] DEBUG[12731]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 6002 [Mar 22 11:14:02] DEBUG[12731]: chan_sip.c:17319 sip_devicestate: Checking device state for peer 6002 [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:3866 sip_answer: SIP answering channel: SIP/6002-00000018 [Mar 22 11:14:02] DEBUG[10483]: rtp.c:2091 ast_rtp_update_source: Setting the marker bit due to a source update [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:7239 transmit_response_with_sdp: Setting framing from config on incoming call [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:6954 add_sdp: ** Our capability: 0x1004 (ulaw|g722) Video flag: True [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:6955 add_sdp: ** Our prefcodec: 0x0 (nothing) [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:7072 add_sdp: -- Done with adding codecs to SDP [Mar 22 11:14:02] DEBUG[10483]: channel.c:2561 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) [Mar 22 11:14:02] DEBUG[10483]: chan_sip.c:7181 add_sdp: Done building SDP. Settling with this capability: 0x1004 (ulaw|g722) [Mar 22 11:14:02] DEBUG[10483]: rtp.c:2929 ast_rtp_write: Ooh, format changed from unknown to ulaw [Mar 22 11:14:02] DEBUG[10483]: rtp.c:2946 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 -- Playing 'custom/0001' (language 'en') [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as3ea64480 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 240a18c421f14fb84063d2f67da281ee@192.168.30.165 Their Tag as4e55db67 Our tag: as105b7232 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2238 __sip_ack: Acked pending invite 125 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '3c27c1106196-9eo0ys9gp1jc' of Request 125: Match Found [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as3ea64480 [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 22 11:14:02] DEBUG[12787]: chan_sip.c:2270 __sip_ack: Stopping retransmission on '626390ad-21b7458a-f679ec4f@192.168.30.190' of Response 2: Match Found [Mar 22 11:14:02] DEBUG[8503]: manager.c:2244 process_message: Manager received command 'Command' [Mar 22 11:14:02] DEBUG[12845]: chan_iax2.c:2038 peercnt_remove: ip callno count decremented to 1 for 99.229.93.248 [Mar 22 11:14:03] DEBUG[12787]: chan_sip.c:2175 __sip_autodestruct: Auto destroying SIP dialog '240a18c421f14fb84063d2f67da281ee@192.168.30.165' [Mar 22 11:14:03] DEBUG[12787]: chan_sip.c:3495 sip_destroy: Destroying SIP dialog 240a18c421f14fb84063d2f67da281ee@192.168.30.165 [Mar 22 11:14:06] DEBUG[10549]: manager.c:2244 process_message: Manager received command 'login' == Parsing '/etc/asterisk/manager.conf': [Mar 22 11:14:06] DEBUG[10549]: config.c:902 config_text_file_load: Parsing /etc/asterisk/manager.conf Found [Mar 22 11:14:06] DEBUG[10549]: acl.c:355 ast_append_ha: 127.0.0.1/255.255.255.255 appended to acl for peer [Mar 22 11:14:06] DEBUG[10549]: acl.c:370 ast_apply_ha: ##### Testing 127.0.0.1 with 127.0.0.1 [Mar 22 11:14:06] DEBUG[10549]: manager.c:2244 process_message: Manager received command 'Command' [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 626390ad-21b7458a-f679ec4f@192.168.30.190 Their Tag 67A6C3C-7221A691 Our tag: as3ea64480 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 32d5c9a15b590f3f122aaae855257125@192.168.30.165 Their Tag as6c9fb16a Our tag: as639a7c0c [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: afe021f1-c3169062-ec2e15c7@192.168.30.85 Their Tag B1E97BB0-F5AAFCED Our tag: as56e7637a [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 7ed51ecc-568c21b9-e571c4ca@192.168.30.85 Their Tag 72A82F4F-6FB604D8 Our tag: as78717c7a [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: fb4304fb-20532174-75b4ee81@192.168.30.85 Their Tag E6AB6A32-6FD7ADD7 Our tag: as1f812c0d [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 36df6c2e-68677703-25b7fd1c@192.168.30.85 Their Tag 4E90A849-DBF8209A Our tag: as017e3335 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 667dd955-3e492816-978f9e0b@192.168.30.85 Their Tag 3F2951C4-D9E96F11 Our tag: as38acbfb4 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5931d9a0-f243ef9d-490f04fe@192.168.30.85 Their Tag 1EF29A13-4310BF6C Our tag: as1317d861 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f795717f-bf5c38c8-240fe2e5@192.168.30.85 Their Tag 1A93A2E6-7AFB8B1B Our tag: as65918d57 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 789f8fa2-10acee07-323640f0@192.168.30.85 Their Tag 31A1D32D-F89DA1CE Our tag: as7b5092fe [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e1d54673-7ad09380-e72ce3f5@192.168.30.190 Their Tag 8B242B12-C936B617 Our tag: as48fd162d [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f4c13806-8f3d6ab-a8a32d78@192.168.30.190 Their Tag 1E0815AD-4362F28A Our tag: as2f80e71d [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f44d2301-5822b67e-c73490e3@192.168.30.190 Their Tag 704F4170-5E5E9165 Our tag: as652c6fe4 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5f490c24-84fe9db9-af290ef6@192.168.30.190 Their Tag 1EB6F51B-A1704F68 Our tag: as55610982 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 123f6445-5ee0dde2-b2f0af67@192.168.30.190 Their Tag 4982F014-B2BF3129 Our tag: as4951a8aa [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 668c4b48-2cfed1fd-46c9c15a@192.168.30.190 Their Tag 8EF6F69F-F3BF90C Our tag: as66ad4015 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f2a6033-af2bb40-bf6689b5@192.168.30.190 Their Tag E0233ED2-32A8A7D7 Our tag: as34b3c10a [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 855903c6-a5cc406b-f932538@192.168.30.190 Their Tag 182E0B6D-D3E0D64A Our tag: as2ce618dd [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a5b610c1-acc3523e-3a0c4aa3@192.168.30.190 Their Tag AC690930-EFACD725 Our tag: as04410421 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e01d6a6c-ec8f49-a4d2455a@192.168.30.194 Their Tag CD11186F-3B541F98 Our tag: as51ef07fb [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: d7e0be4-65c9db79-726e7ab6@192.168.30.190 Their Tag AB09FEDB-760FE728 Our tag: as013e57bd [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 31f6897b-f9e6bdd4-b1887e51@192.168.30.194 Their Tag 54AF682-E1A22C37 Our tag: as52d91a52 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b42ad3ef-96eff0dc-9b0bb031@192.168.30.190 Their Tag EB7DFD2E-8F84DD13 Our tag: as01e7d28e [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 4b46af9e-6a7dbec3-9f4a7e3c@192.168.30.194 Their Tag 65D24E59-C9C9CCAA Our tag: as018d102c [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6b7cd1a2-13068127-d5738fd4@192.168.30.190 Their Tag 68830EE9-67B559A6 Our tag: as343343b2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 55492a45-32e4a946-799b9d0b@192.168.30.194 Their Tag 91964BA4-99861F61 Our tag: as1c71ede3 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: be9b5e20-686078cd-7631d7ee@192.168.30.194 Their Tag E8254453-7BCBC60C Our tag: as168137c5 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 5c57261f-8d5b2808-ab919855@192.168.30.194 Their Tag 8914DB96-1993D49B Our tag: as65da8ab9 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 423e0672-4fc9efe7-2d842ef0@192.168.30.194 Their Tag C816A8DD-1C48543E Our tag: as3a1e4ef1 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 880fa389-d8beda9a-5245f2af@192.168.30.194 Their Tag FA3E12D8-4E6DCA65 Our tag: as569789ce [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: f0f92d14-468e1a91-a64a33c2@192.168.30.194 Their Tag 5AF24E77-73A473C0 Our tag: as118bef3a [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: b58dad03-42ad57c-f50b7299@192.168.30.194 Their Tag 6A30B1EA-5DA233F Our tag: as6f190246 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 977c6a86-ec29534b-8e018ae4@192.168.30.194 Their Tag 9963CBA1-8DD6F512 Our tag: as2dfa673c [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: cb594a62-5c41b797-937f5960@192.168.30.194 Their Tag CE46D10D-6541C12E Our tag: as43bc22ff [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 73e41cb9-f0e29c8a-bfd6b05f@192.168.30.194 Their Tag D23E8B48-8FC2F895 Our tag: as1eb2feb2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 327b6284-c811b9c1-a28633b2@192.168.30.194 Their Tag 90BAC227-5253FA30 Our tag: as00ddc743 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: e6dd9b3-aa93b8ec-2622f7c9@192.168.30.194 Their Tag C18CAFDA-CED10CEF Our tag: as3f59d11d [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 392f6d76-512f15fb-ca26dc54@192.168.30.194 Their Tag 508BF6D1-1852B102 Our tag: as51f95043 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 37a05d3d-62f5221e-1bc7db43@192.168.30.194 Their Tag E8E6CBC-1844D6D9 Our tag: as0459d6ba [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: a3f397b8-f16e0ac5-cf616bc6@192.168.30.194 Their Tag 5F29498B-60580A24 Our tag: as6fb979d2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 669739d7-d94a94a0-2c21694d@192.168.30.194 Their Tag 1146EA6E-EA6880D3 Our tag: as0bb93a97 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: af8b61ca-525c7a9f-24f12e88@192.168.30.194 Their Tag EA7098D5-D5CC3E16 Our tag: as1412ab6b [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 9f93c601-45c7a0f2-6179d467@192.168.30.194 Their Tag BEDB0570-45D5B95D Our tag: as6905f5cb [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 1d18e999903f0a9b Their Tag 5e7c61d4f8 Our tag: as50aa4f8f [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 14b3cb37db1e31c0 Their Tag f9ffe6e0d1 Our tag: as41c9ad6a [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: ddabb2be025c6e29 Their Tag 88e866397c Our tag: as1e411468 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 8d7c1b00dc2a5996 Their Tag 28fec5e0e0 Our tag: as23f8fce9 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 2efb71c7e944792b Their Tag 63a1a6a487 Our tag: as2bedad40 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 6bb787c25e57cee5 Their Tag 486e9309f4 Our tag: as38b95d9b [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8a19-lbpdsdhvlnbr Their Tag 63r5v4bqoz Our tag: as7466520e [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b8229-rlz7lhmnc8vp Their Tag y1kau7ajsc Our tag: as4d2f2fe7 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b7771-3omw6e5w5iym Their Tag mnmc2ydmjk Our tag: as728bb495 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c2b197b6f5f-1u1oddfgrxok Their Tag p8gimy0pfc Our tag: as5989b0d2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c09b029c-qdrs646b4lwn Their Tag zueuts54nc Our tag: as2913c2ec [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c110c4fb-l3co46ixkrh2 Their Tag raw7roq8zc Our tag: as6217c6e3 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c11087d8-hyl205ib8juz Their Tag trmohyug0a Our tag: as7ee557c2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1106196-9eo0ys9gp1jc Their Tag jyilytqvqc Our tag: as7bb79000 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10fe4a7-lmrupz1snh62 Their Tag fjjwsepmkv Our tag: as7069c506 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10eee94-zz6iftik64tm Their Tag gcpkbkaj3o Our tag: as1959f629 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10bb402-sqjxzgpbmy4j Their Tag pkd6c78fo3 Our tag: as576a03c4 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a1ea0-biobz6rjinmr Their Tag 12c2tuyhxt Our tag: as16e09626 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10a13cf-nu28d30hif0s Their Tag y2imqwplr2 Our tag: as642f0ca2 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10986f7-ganode8jc6mb Their Tag hoj9lemooz Our tag: as4effa9a6 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10973ac-l2ebsl6y8tim Their Tag o8ozb5jchv Our tag: as1b336e68 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10960c9-su9xi7tw1yth Their Tag 7zn15lybmh Our tag: as74a1ff5f [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1093dbb-6m30ue70ul3r Their Tag j1hiimsuqh Our tag: as00331630 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c1092b45-ea5xgnpcpqnb Their Tag qkrypltzh5 Our tag: as713efd5b [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c109121b-mgu3dn6h4oec Their Tag 8zwgyw7fxi Our tag: as5015c0ba [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108cdec-t65pqk9uzb47 Their Tag fc82o1b5yo Our tag: as38d57733 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c108a553-cxhbxya2dk9o Their Tag zyk50zqnct Our tag: as5e942a9e [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107bb48-1uf95vyifzhz Their Tag t42yish3cb Our tag: as3d64bc9d [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c10876b9-psq4kfilthfz Their Tag bh62nhdrnu Our tag: as3f89845c [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = No match Their Call ID: 3c27c107d0a4-lvp7bxzx8dib Their Tag asyli23l0s Our tag: as7057b25c [Mar 22 11:14:06] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 192.168.0.0 [Mar 22 11:14:06] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 10.0.0.0 [Mar 22 11:14:06] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 172.16.0.0 [Mar 22 11:14:06] DEBUG[12787]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.190 with 169.254.0.0 [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4795 sip_alloc: Allocating new SIP dialog for 651fbc16-8f66eb3b-de2d688@192.168.30.190 - SUBSCRIBE (No RTP) [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:8917 build_route: build_route: Contact hop: [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:4851 find_call: = Found Their Call ID: 651fbc16-8f66eb3b-de2d688@192.168.30.190 Their Tag 99A8773D-FF95509A Our tag: as730548de [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:16540 handle_request: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Mar 22 11:14:06] DEBUG[12787]: chan_sip.c:8855 build_route: build_route: Retaining previous route: [Mar 22 11:14:07] DEBUG[10483]: rtp.c:922 ast_rtcp_read: Got RTCP report of 104 bytes [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:11] Set("SIP/6002-00000018", "__ACD_TO_OUTGOING=1") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:12] Set("SIP/6002-00000018", "CHANNEL(musicclass)=default") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:13] Set("SIP/6002-00000018", "__ALLOW_TRANSFER=twk") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1707 pbx_substitute_variables_helper_full: Function result is 'Reception 6002' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-application-acd-sales:14] Set("SIP/6002-00000018", "CALLERID(name)=SALES:Reception 6002") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Queue' -- Executing [s@default-application-acd-sales:15] Queue("SIP/6002-00000018", "default-sales|tH|||30|") in new stack [Mar 22 11:14:10] DEBUG[10483]: app_queue.c:4017 queue_exec: SIP/6002-00000018: Got priority 0 from ${QUEUE_PRIO}. [Mar 22 11:14:10] DEBUG[10483]: app_queue.c:4049 queue_exec: queue: default-sales, options: tH, url: , announce: , expires: 1269270880, priority: 0 [Mar 22 11:14:10] DEBUG[10483]: app_queue.c:1372 update_realtime_members: Queue default-sales has no realtime members defined. No need for update [Mar 22 11:14:10] WARNING[10483]: app_queue.c:4227 queue_exec: Unable to join queue 'default-sales' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'NoOp' -- Executing [s@default-application-acd-sales:16] NoOp("SIP/6002-00000018", "JOINUNAVAIL") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@default-application-acd-sales:17] GotoIf("SIP/6002-00000018", "0?1000") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@default-application-acd-sales:18] GotoIf("SIP/6002-00000018", "0?1000") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@default-application-acd-sales:19] GotoIf("SIP/6002-00000018", "0?1000") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:6103 pbx_builtin_gotoif: Not taking any branch [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1775 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@default-application-acd-sales:20] GotoIf("SIP/6002-00000018", "1?1000") in new stack -- Goto (default-application-acd-sales,s,1000) [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Gosub' -- Executing [s@default-application-acd-sales:1000] Gosub("SIP/6002-00000018", "default-aa-servicemainmenubutton1englishsubmenu|s|1") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Answer' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:1] Answer("SIP/6002-00000018", "") in new stack [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'AGI' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:2] AGI("SIP/6002-00000018", "agi://127.0.0.1:4573/ivr?arg1=4894") in new stack AGI Tx >> agi_network: yes AGI Tx >> agi_network_script: ivr?arg1=4894 [Mar 22 11:14:10] DEBUG[10483]: res_agi.c:249 launch_netscript: Wow, connected! AGI Tx >> agi_request: agi://127.0.0.1:4573/ivr?arg1=4894 AGI Tx >> agi_channel: SIP/6002-00000018 AGI Tx >> agi_language: en AGI Tx >> agi_type: SIP AGI Tx >> agi_uniqueid: 1269270842.32 AGI Tx >> agi_callerid: 6002 AGI Tx >> agi_calleridname: SALES:Reception 6002 AGI Tx >> agi_callingpres: 0 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 0 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 6500 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: default-aa-servicemainmenubutton1englishsubmenu AGI Tx >> agi_extension: s AGI Tx >> agi_priority: 2 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: 6002 AGI Tx >> -- AGI Script agi://127.0.0.1:4573/ivr?arg1=4894 completed, returning 0 [Mar 22 11:14:10] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Wait' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:3] Wait("SIP/6002-00000018", "1") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:4] Set("SIP/6002-00000018", "MAXCOUNT_TIMEOUT=3") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:5] Set("SIP/6002-00000018", "COUNT_TIMEOUT=0") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:6] Set("SIP/6002-00000018", "MAXCOUNT_TIMEOUT=3") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:7] Set("SIP/6002-00000018", "COUNT_TIMEOUT=0") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:8] Set("SIP/6002-00000018", "MAXCOUNT_INVALID=3") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:9] Set("SIP/6002-00000018", "COUNT_INVALID=0") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:10] Set("SIP/6002-00000018", "__IVRNAME=default-aa-servicemainmenubutton1englishsubmenu") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:11] Set("SIP/6002-00000018", "TIMEOUT(digit)=3") in new stack -- Digit timeout set to 3 [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:12] Set("SIP/6002-00000018", "TIMEOUT(response)=5") in new stack -- Response timeout set to 5 [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'Set' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:13] Set("SIP/6002-00000018", "CHANNEL(language)=en") in new stack [Mar 22 11:14:11] DEBUG[10483]: pbx.c:1859 pbx_extension_helper: Launching 'BackGround' -- Executing [s@default-aa-servicemainmenubutton1englishsubmenu:14] BackGround("SIP/6002-00000018", "custom/0003") in new stack [Mar 22 11:14:11] DEBUG[10483]: rtp.c:2756 ast_rtp_raw_write: Difference is 8808, ms is 1121 -- Playing 'custom/0003' (language 'en')