[2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 3df2bfcd4ed3fdf818396d9a27c30a35@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 2b19e728439016c302f0dc9a14c6930c@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 6d42bfd467de8c3174f3b02418fc915d@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 006b029c75077ab2547e33792d21afad@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 24ce039100779ee5401ca59b0ac19bb4@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 366339fd6e32641d6832342e1d05f8af@192.168.233.235:5060 [2012-02-02 20:33:46] DEBUG[6222] chan_sip.c: Re-scheduled destruction of SIP call 353ae8142c2277c75f8d89e53fc8ed87@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '7ad5f90147a9140e5721a6285aae96ce@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 7ad5f90147a9140e5721a6285aae96ce@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '657163421de0fae76f3ed9284ecd058e@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 657163421de0fae76f3ed9284ecd058e@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '4a9f181a5d25c9cd2edc57836cb57b80@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 4a9f181a5d25c9cd2edc57836cb57b80@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '600cbc663e541974728ef3cc14c75392@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 600cbc663e541974728ef3cc14c75392@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '7e1ae27d13cd4d617fe9e12d6be3d9fb@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 7e1ae27d13cd4d617fe9e12d6be3d9fb@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '2dc10b770dd2a74969de5eb11f0a3c05@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 2dc10b770dd2a74969de5eb11f0a3c05@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '28ca414e14d7660b297a41995011d8dd@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 28ca414e14d7660b297a41995011d8dd@192.168.233.235:5060 [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '76f37f665870ea0c6ebed88c59071ee7@192.168.233.235:5060' [2012-02-02 20:33:48] DEBUG[6222] chan_sip.c: Destroying SIP dialog 76f37f665870ea0c6ebed88c59071ee7@192.168.233.235:5060 [2012-02-02 20:33:55] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:216.115.69.144:5160 ---> OPTIONS sip:76.169.12.120:5060 SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 216.115.69.144:5160;branch=z9hG4bK04ce.72ba96cbab97cb06f284f281fb48d97b.0 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Route: From: sip:ping@invalid;tag=7b092838 To: sip:76.169.12.120:5060 Call-ID: c9e31922-14539737-bfdce44@216.115.69.131 CSeq: 1 OPTIONS Content-Length: 0 <-------------> [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 0 [ 38]: OPTIONS sip:76.169.12.120:5060 SIP/2.0 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 1 [ 16]: Max-Forwards: 10 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 2 [ 42]: Record-Route: [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 3 [ 90]: Via: SIP/2.0/UDP 216.115.69.144:5160;branch=z9hG4bK04ce.72ba96cbab97cb06f284f281fb48d97b.0 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 4 [ 45]: Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 5 [ 69]: Route: [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 6 [ 35]: From: sip:ping@invalid;tag=7b092838 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 7 [ 26]: To: sip:76.169.12.120:5060 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 8 [ 49]: Call-ID: c9e31922-14539737-bfdce44@216.115.69.131 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 9 [ 15]: CSeq: 1 OPTIONS [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [2012-02-02 20:33:55] VERBOSE[6222] chan_sip.c: --- (11 headers 0 lines) --- [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: = Looking for Call ID: c9e31922-14539737-bfdce44@216.115.69.131 (Checking From) --From tag 7b092838 --To-tag [2012-02-02 20:33:55] DEBUG[6222] acl.c: For destination '216.115.69.144', our source address is '192.168.233.235'. [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Target address 216.115.69.144:5160 is not local, substituting externaddr [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 76.169.12.120:5060 [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Allocating new SIP dialog for c9e31922-14539737-bfdce44@216.115.69.131 - OPTIONS (No RTP) [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [2012-02-02 20:33:55] DEBUG[6222] netsock2.c: Splitting '76.169.12.120:5060' into... [2012-02-02 20:33:55] DEBUG[6222] netsock2.c: ...host '76.169.12.120' and port ''. [2012-02-02 20:33:55] DEBUG[6222] netsock2.c: Splitting 'invalid' into... [2012-02-02 20:33:55] DEBUG[6222] netsock2.c: ...host 'invalid' and port ''. [2012-02-02 20:33:55] VERBOSE[6222] chan_sip.c: Looking for s in mystery_sip (domain 76.169.12.120) [2012-02-02 20:33:55] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 216.115.69.144:5160 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144:5160;branch=z9hG4bK04ce.72ba96cbab97cb06f284f281fb48d97b.0;received=216.115.69.144;rport=5160 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=7b092838 To: sip:76.169.12.120:5060;tag=as078e851c Call-ID: c9e31922-14539737-bfdce44@216.115.69.131 CSeq: 1 OPTIONS Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [2012-02-02 20:33:55] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 216.115.69.144:5160 [2012-02-02 20:33:55] VERBOSE[6222] chan_sip.c: Scheduling destruction of SIP dialog 'c9e31922-14539737-bfdce44@216.115.69.131' in 32000 ms (Method: OPTIONS) [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '6037709743dc348141ee980805ec55c6@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 6037709743dc348141ee980805ec55c6@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '6037709743dc348141ee980805ec55c6@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '1b4e82ac35dc29b72c447bed477d396d@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 1b4e82ac35dc29b72c447bed477d396d@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '1b4e82ac35dc29b72c447bed477d396d@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '6a0f8a420acfd9d738f7eaf42bfc768c@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 6a0f8a420acfd9d738f7eaf42bfc768c@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '6a0f8a420acfd9d738f7eaf42bfc768c@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '19b758f524b92ac80b3f82b54a1d6592@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 19b758f524b92ac80b3f82b54a1d6592@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '19b758f524b92ac80b3f82b54a1d6592@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '3d00e70e2b7796826c7687c45b2e266c@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 3d00e70e2b7796826c7687c45b2e266c@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '3d00e70e2b7796826c7687c45b2e266c@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '287c8b7d423395e569d3daa4632dc379@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 287c8b7d423395e569d3daa4632dc379@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '287c8b7d423395e569d3daa4632dc379@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '3958b7196040afcd04619adf4b01bb20@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 3958b7196040afcd04619adf4b01bb20@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '3958b7196040afcd04619adf4b01bb20@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '3df2bfcd4ed3fdf818396d9a27c30a35@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 3df2bfcd4ed3fdf818396d9a27c30a35@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '3df2bfcd4ed3fdf818396d9a27c30a35@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '2b19e728439016c302f0dc9a14c6930c@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 2b19e728439016c302f0dc9a14c6930c@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '2b19e728439016c302f0dc9a14c6930c@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '6d42bfd467de8c3174f3b02418fc915d@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 6d42bfd467de8c3174f3b02418fc915d@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '6d42bfd467de8c3174f3b02418fc915d@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '006b029c75077ab2547e33792d21afad@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 006b029c75077ab2547e33792d21afad@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '006b029c75077ab2547e33792d21afad@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '24ce039100779ee5401ca59b0ac19bb4@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 24ce039100779ee5401ca59b0ac19bb4@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '24ce039100779ee5401ca59b0ac19bb4@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '366339fd6e32641d6832342e1d05f8af@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 366339fd6e32641d6832342e1d05f8af@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '366339fd6e32641d6832342e1d05f8af@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '353ae8142c2277c75f8d89e53fc8ed87@192.168.233.235:5060' [2012-02-02 20:33:57] DEBUG[6222] chan_sip.c: Destroying SIP dialog 353ae8142c2277c75f8d89e53fc8ed87@192.168.233.235:5060 [2012-02-02 20:33:57] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '353ae8142c2277c75f8d89e53fc8ed87@192.168.233.235:5060' Method: NOTIFY [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INVITE sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK408e40472 Max-Forwards: 70 Content-Length: 567 To: 504 From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347974 INVITE Supported: timer Allow-Events: talk,hold,conference Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO Content-Type: application/sdp Contact: GameRoom Supported: replaces User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 v=0 o=MxSIP 0 164751166 IN IP4 192.168.233.240 s=SIP Call c=IN IP4 192.168.233.240 t=0 0 m=audio 10100 RTP/AVP 0 18 96 102 107 104 105 106 97 98 2 99 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:96 BV16/8000 a=rtpmap:102 BV32/16000 a=rtpmap:107 L16/16000 a=rtpmap:104 PCMU/16000 a=rtpmap:105 PCMA/16000 a=rtpmap:106 L16/8000 a=rtpmap:97 G726-16/8000 a=rtpmap:98 G726-24/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:99 G726-40/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=silenceSupp:on - - - - <-------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 0 [ 43]: INVITE sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK408e40472 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 3 [ 19]: Content-Length: 567 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 4 [ 38]: To: 504 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 7 [ 22]: CSeq: 780347974 INVITE [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 9 [ 34]: Allow-Events: talk,hold,conference [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 10 [ 53]: Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 12 [ 77]: Contact: GameRoom [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 13 [ 19]: Supported: replaces [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 14 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 15 [ 0]: [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 0 [ 3]: v=0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 1 [ 42]: o=MxSIP 0 164751166 IN IP4 192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 2 [ 10]: s=SIP Call [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 3 [ 24]: c=IN IP4 192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 4 [ 5]: t=0 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 5 [ 66]: m=audio 10100 RTP/AVP 0 18 96 102 107 104 105 106 97 98 2 99 8 101 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 7 [ 21]: a=rtpmap:18 G729/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 8 [ 21]: a=rtpmap:96 BV16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 9 [ 23]: a=rtpmap:102 BV32/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 10 [ 22]: a=rtpmap:107 L16/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 11 [ 23]: a=rtpmap:104 PCMU/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 12 [ 23]: a=rtpmap:105 PCMA/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 13 [ 21]: a=rtpmap:106 L16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 14 [ 24]: a=rtpmap:97 G726-16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 15 [ 24]: a=rtpmap:98 G726-24/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 16 [ 23]: a=rtpmap:2 G726-32/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 17 [ 24]: a=rtpmap:99 G726-40/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 18 [ 20]: a=rtpmap:8 PCMA/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 19 [ 33]: a=rtpmap:101 telephone-event/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 20 [ 15]: a=fmtp:101 0-15 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 21 [ 10]: a=ptime:30 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 22 [ 24]: a=silenceSupp:on - - - - [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: --- (15 headers 23 lines) --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag [2012-02-02 20:33:59] DEBUG[6222] acl.c: For destination '192.168.233.240', our source address is '192.168.233.235'. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.233.235:5060 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Allocating new SIP dialog for 969a50694d872653a577c2fdf7f0b352@192.168.233.240 - INVITE (No RTP) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2012-02-02 20:33:59] DEBUG[6222] sip/reqresp_parser.c: Begin: parsing SIP "Supported: timer" [2012-02-02 20:33:59] DEBUG[6222] sip/reqresp_parser.c: Found SIP option: -timer- [2012-02-02 20:33:59] DEBUG[6222] sip/reqresp_parser.c: Matched SIP option: timer [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.240:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.240' and port '5060'. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Sending to 192.168.233.240:5060 (NAT) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Initializing initreq for method INVITE - callid 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Using INVITE request as basis request - 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port ''. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found peer '103546579768__xErT' for '103546579768__xErT' from 192.168.233.240:5060 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- Reliably Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK408e40472;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as7f030d31 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347974 INVITE Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="3a026bb5" Content-Length: 0 <------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #361 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Scheduling destruction of SIP dialog '969a50694d872653a577c2fdf7f0b352@192.168.233.240' in 11200 ms (Method: INVITE) [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> ACK sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK408e40472 Max-Forwards: 70 Content-Length: 0 To: 504 ;tag=as7f030d31 From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347974 ACK User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 0 [ 40]: ACK sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK408e40472 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 3 [ 17]: Content-Length: 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as7f030d31 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 7 [ 19]: CSeq: 780347974 ACK [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 8 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: --- (9 headers 0 lines) --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as7f030d31 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #361 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Stopping retransmission on '969a50694d872653a577c2fdf7f0b352@192.168.233.240' of Response 780347974: Match Found [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INVITE sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK173e69f28 Max-Forwards: 70 Content-Length: 567 To: 504 From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 INVITE Supported: timer Allow-Events: talk,hold,conference Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO Contact: GameRoom Content-Type: application/sdp Supported: replaces Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 v=0 o=MxSIP 0 164751166 IN IP4 192.168.233.240 s=SIP Call c=IN IP4 192.168.233.240 t=0 0 m=audio 10100 RTP/AVP 0 18 96 102 107 104 105 106 97 98 2 99 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:96 BV16/8000 a=rtpmap:102 BV32/16000 a=rtpmap:107 L16/16000 a=rtpmap:104 PCMU/16000 a=rtpmap:105 PCMA/16000 a=rtpmap:106 L16/8000 a=rtpmap:97 G726-16/8000 a=rtpmap:98 G726-24/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:99 G726-40/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=silenceSupp:on - - - - <-------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 0 [ 43]: INVITE sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK173e69f28 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 3 [ 19]: Content-Length: 567 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 4 [ 38]: To: 504 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 7 [ 22]: CSeq: 780347975 INVITE [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 9 [ 34]: Allow-Events: talk,hold,conference [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 10 [ 53]: Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 11 [ 77]: Contact: GameRoom [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 13 [ 19]: Supported: replaces [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 14 [177]: Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 15 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 16 [ 0]: [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 0 [ 3]: v=0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 1 [ 42]: o=MxSIP 0 164751166 IN IP4 192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 2 [ 10]: s=SIP Call [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 3 [ 24]: c=IN IP4 192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 4 [ 5]: t=0 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 5 [ 66]: m=audio 10100 RTP/AVP 0 18 96 102 107 104 105 106 97 98 2 99 8 101 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 7 [ 21]: a=rtpmap:18 G729/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 8 [ 21]: a=rtpmap:96 BV16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 9 [ 23]: a=rtpmap:102 BV32/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 10 [ 22]: a=rtpmap:107 L16/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 11 [ 23]: a=rtpmap:104 PCMU/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 12 [ 23]: a=rtpmap:105 PCMA/16000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 13 [ 21]: a=rtpmap:106 L16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 14 [ 24]: a=rtpmap:97 G726-16/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 15 [ 24]: a=rtpmap:98 G726-24/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 16 [ 23]: a=rtpmap:2 G726-32/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 17 [ 24]: a=rtpmap:99 G726-40/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 18 [ 20]: a=rtpmap:8 PCMA/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 19 [ 33]: a=rtpmap:101 telephone-event/8000 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 20 [ 15]: a=fmtp:101 0-15 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 21 [ 10]: a=ptime:30 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Body 22 [ 24]: a=silenceSupp:on - - - - [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: --- (16 headers 23 lines) --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port '5060'. [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port '5060'. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.240:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.240' and port '5060'. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Sending to 192.168.233.240:5060 (NAT) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Initializing initreq for method INVITE - callid 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Using INVITE request as basis request - 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port ''. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found peer '103546579768__xErT' for '103546579768__xErT' from 192.168.233.240:5060 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Using engine 'asterisk' for RTP instance '0xb750f0d0' [2012-02-02 20:33:59] DEBUG[6222] res_rtp_asterisk.c: Allocated port 16078 for RTP instance '0xb750f0d0' [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: RTP instance '0xb750f0d0' is setup and ready to go [2012-02-02 20:33:59] DEBUG[6222] res_rtp_asterisk.c: Setup RTCP on RTP instance '0xb750f0d0' [2012-02-02 20:33:59] VERBOSE[6222] netsock2.c: == Using SIP RTP CoS mark 5 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Setting NAT on RTP to On [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing session-level SDP o=MxSIP 0 164751166 IN IP4 192.168.233.240... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing session-level SDP s=SIP Call... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.240' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.240' and port ''. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.233.240... OK. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 0 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 0 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 18 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 18 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 96 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 102 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 102 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 107 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 104 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 104 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 105 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 105 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 106 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 106 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 97 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 97 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 98 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 98 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 2 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 99 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 99 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 8 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found RTP audio format 101 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Setting payload 101 based on m type on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format PCMU for ID 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format G729 for ID 18 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 96 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format BV16 for ID 96 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 BV16/8000... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 102 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format BV32 for ID 102 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:102 BV32/16000... UNSUPPORTED. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format L16 for ID 107 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:107 L16/16000... OK. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 104 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format PCMU for ID 104 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:104 PCMU/16000... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 105 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format PCMA for ID 105 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:105 PCMA/16000... UNSUPPORTED. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format L16 for ID 106 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:106 L16/8000... OK. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 97 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format G726-16 for ID 97 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 G726-16/8000... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 98 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format G726-24 for ID 98 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:98 G726-24/8000... UNSUPPORTED. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format G726-32 for ID 2 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:2 G726-32/8000... OK. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Unsetting payload 99 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found unknown media description format G726-40 for ID 99 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:99 G726-40/8000... UNSUPPORTED. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format PCMA for ID 8 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Found audio description format telephone-event for ID 101 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=ptime:30... OK. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Processing media-level (audio) SDP a=silenceSupp:on - - - -... UNSUPPORTED. [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 0 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 2 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 8 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 18 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 101 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 106 on 0xb77f14f8 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Incorporating payload 107 on 0xb77f14f8 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Capabilities: us - (ulaw), peer - audio=(ulaw|alaw|g726|slin|g729|slin16)/video=(nothing)/text=(nothing), combined - (ulaw) [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [2012-02-02 20:33:59] DEBUG[6222] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb750f0d0' [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Peer audio RTP is at port 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 0 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 2 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 8 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 18 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 101 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 106 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] rtp_engine.c: Copying payload 107 from 0xb77f14f8 to 0xb750f27c [2012-02-02 20:33:59] DEBUG[6222] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0xb750f0d0' [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: We're settling with these formats: (ulaw) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Checking SIP call limits for device 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Updating call counter for incoming call [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Call from peer '103546579768__xErT' is 1 out of 19 [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: No provider found, checking channel drivers for SIP - 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port ''. [2012-02-02 20:33:59] DEBUG[6215] chan_sip.c: Checking device state for peer 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: Changing state for SIP/103546579768__xErT - state 2 (In use) [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: device 'SIP/103546579768__xErT' state '2' [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: Splitting '192.168.233.235:5060' into... [2012-02-02 20:33:59] DEBUG[6222] netsock2.c: ...host '192.168.233.235' and port ''. [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Looking for 504 in internal111 (domain 192.168.233.235) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: *** Our native formats are (ulaw) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: *** Joint capabilities are (ulaw) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: *** Our capabilities are (ulaw) [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: This channel will not be able to handle video. [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: build_route: Contact hop: GameRoom [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: list_route: hop: [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Session timer started: 363 - 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: SIP/103546579768__xErT-00000000: New call is still down.... Trying... [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK173e69f28;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 INVITE Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: No provider found, checking channel drivers for SIP - 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6215] chan_sip.c: Checking device state for peer 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: Changing state for SIP/103546579768__xErT - state 2 (In use) [2012-02-02 20:33:59] DEBUG[6233] pbx.c: Result of 'EXTEN' is '504' [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: device 'SIP/103546579768__xErT' state '2' [2012-02-02 20:33:59] DEBUG[6233] pbx.c: Launching 'VoiceMailMain' [2012-02-02 20:33:59] VERBOSE[6233] pbx.c: -- Executing [504@internal111:1] VoiceMailMain("SIP/103546579768__xErT-00000000", "104") in new stack [2012-02-02 20:33:59] DEBUG[6233] app_voicemail.c: Before ast_answer [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: SIP answering channel: SIP/103546579768__xErT-00000000 [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: No provider found, checking channel drivers for SIP - 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: Setting the marker bit due to a source update [2012-02-02 20:33:59] DEBUG[6215] chan_sip.c: Checking device state for peer 103546579768__xErT [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: Changing state for SIP/103546579768__xErT - state 2 (In use) [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: Setting framing from config on incoming call [2012-02-02 20:33:59] DEBUG[6215] devicestate.c: device 'SIP/103546579768__xErT' state '2' [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: ** Our prefcodec: (nothing) [2012-02-02 20:33:59] VERBOSE[6233] chan_sip.c: Audio is at 16078 [2012-02-02 20:33:59] VERBOSE[6233] chan_sip.c: Adding codec 100003 (ulaw) to SDP [2012-02-02 20:33:59] VERBOSE[6233] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: -- Done with adding codecs to SDP [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [2012-02-02 20:33:59] VERBOSE[6233] chan_sip.c: <--- Reliably Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK173e69f28;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 INVITE Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 239 v=0 o=root 194650038 194650038 IN IP4 192.168.233.235 s=Asterisk PBX 10.1.0 c=IN IP4 192.168.233.235 t=0 0 m=audio 16078 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #365 [2012-02-02 20:33:59] DEBUG[6233] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: SIP TIMER: Rescheduling retransmission #365 (1) SIP/2.0 - 1 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 350 ms (t1 175 ms (Retrans id #365)) [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: Retransmitting #1 (NAT) to 192.168.233.240:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK173e69f28;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 INVITE Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 239 v=0 o=root 194650038 194650038 IN IP4 192.168.233.235 s=Asterisk PBX 10.1.0 c=IN IP4 192.168.233.235 t=0 0 m=audio 16078 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> ACK sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK216199474 Max-Forwards: 70 Content-Length: 0 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 ACK Contact: GameRoom Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 0 [ 40]: ACK sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK216199474 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 3 [ 17]: Content-Length: 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 7 [ 19]: CSeq: 780347975 ACK [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 8 [ 77]: Contact: GameRoom [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 9 [177]: Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 10 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: --- (11 headers 0 lines) --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #365 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Stopping retransmission on '969a50694d872653a577c2fdf7f0b352@192.168.233.240' of Response 780347975: Match Found [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> ACK sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK216199474 Max-Forwards: 70 Content-Length: 0 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347975 ACK Contact: GameRoom Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 0 [ 40]: ACK sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK216199474 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 3 [ 17]: Content-Length: 0 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 7 [ 19]: CSeq: 780347975 ACK [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 8 [ 77]: Contact: GameRoom [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 9 [177]: Authorization:Digest response="a78edbea33ebb88b03d5c591253d2d79",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: Header 10 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:33:59] VERBOSE[6222] chan_sip.c: --- (11 headers 0 lines) --- [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:33:59] DEBUG[6222] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- start learning mode pass with addr = 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- probation = 4, seq = 11280 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- Condition for learning hasn't exited, so reject the frame. [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- start learning mode pass with addr = 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- probation = 3, seq = 11281 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- Condition for learning hasn't exited, so reject the frame. [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- start learning mode pass with addr = 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- probation = 2, seq = 11282 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- Condition for learning hasn't exited, so reject the frame. [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- start learning mode pass with addr = 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- probation = 1, seq = 11283 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: 0xb751a5d8 -- Probation Ended. Set strict_rtp_state to STRICT_RTP_CLOSED with address 192.168.233.240:10100 [2012-02-02 20:33:59] DEBUG[6233] app_voicemail.c: Before find user for mailbox 104 [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [2012-02-02 20:33:59] DEBUG[6233] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [2012-02-02 20:33:59] DEBUG[6233] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [2012-02-02 20:33:59] VERBOSE[6233] file.c: -- Playing 'vm-password.ulaw' (language 'en') [2012-02-02 20:34:00] DEBUG[6233] channel.c: Scheduling timer at (100 requested / 100 actual) timer ticks per second [2012-02-02 20:34:00] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:00] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:00] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:00] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK48e4a6080 Max-Forwards: 70 Content-Length: 24 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347976 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=1 Duration=160 <-------------> [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK48e4a6080 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 24 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347976 INFO [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Body 0 [ 8]: Signal=1 [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:00] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:00] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:00] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 1 [2012-02-02 20:34:00] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK48e4a6080;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347976 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:00] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:00] DTMF[6233] channel.c: DTMF end '1' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:00] DTMF[6233] channel.c: DTMF end passthrough '1' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK88f2e31b7 Max-Forwards: 70 Content-Length: 24 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347977 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=2 Duration=160 <-------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK88f2e31b7 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 24 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347977 INFO [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 0 [ 8]: Signal=2 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 2 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK88f2e31b7;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347977 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end '2' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end passthrough '2' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK56fc2c4c2 Max-Forwards: 70 Content-Length: 24 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347978 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=3 Duration=160 <-------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK56fc2c4c2 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 24 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347978 INFO [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 0 [ 8]: Signal=3 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 3 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK56fc2c4c2;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347978 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end '3' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end passthrough '3' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe28665d20 Max-Forwards: 70 Content-Length: 24 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347979 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=4 Duration=160 <-------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe28665d20 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 24 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347979 INFO [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 0 [ 8]: Signal=4 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 4 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe28665d20;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347979 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end '4' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end passthrough '4' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:01] DEBUG[6233] res_rtp_asterisk.c: Got RTCP report of 84 bytes [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKcd43867e4 Max-Forwards: 70 Content-Length: 24 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347980 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=5 Duration=160 <-------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKcd43867e4 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 24 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347980 INFO [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 0 [ 8]: Signal=5 [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 5 [2012-02-02 20:34:01] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKcd43867e4;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347980 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:01] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end '5' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:01] DTMF[6233] channel.c: DTMF end passthrough '5' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:02] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> INFO sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe34461863 Max-Forwards: 70 Content-Length: 25 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347981 INFO Supported: timer Content-Type: application/dtmf-relay Contact: GameRoom Supported: replaces Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 Signal=11 Duration=160 <-------------> [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 0 [ 41]: INFO sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe34461863 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 3 [ 18]: Content-Length: 25 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 7 [ 20]: CSeq: 780347981 INFO [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 9 [ 36]: Content-Type: application/dtmf-relay [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 10 [ 77]: Contact: GameRoom [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 11 [ 19]: Supported: replaces [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 12 [177]: Authorization:Digest response="dc74a68fd18950c27b7039a252457160",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 13 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Header 14 [ 0]: [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Body 0 [ 9]: Signal=11 [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Body 1 [ 12]: Duration=160 [2012-02-02 20:34:02] VERBOSE[6222] chan_sip.c: --- (14 headers 2 lines) --- [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: **** Received INFO (13) - Command in SIP INFO [2012-02-02 20:34:02] VERBOSE[6222] chan_sip.c: Receiving INFO! [2012-02-02 20:34:02] VERBOSE[6222] chan_sip.c: * DTMF-relay event received: 1 [2012-02-02 20:34:02] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bKe34461863;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347981 INFO Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:02] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:02] DTMF[6233] channel.c: DTMF end '1' received on SIP/103546579768__xErT-00000000, duration 160 ms [2012-02-02 20:34:02] DTMF[6233] channel.c: DTMF end passthrough '1' on SIP/103546579768__xErT-00000000 [2012-02-02 20:34:04] VERBOSE[6233] app_voicemail.c: -- Incorrect password '123451' for user '104' (context = default) [2012-02-02 20:34:04] DEBUG[6233] res_rtp_asterisk.c: Difference is 29984, ms is 3768 [2012-02-02 20:34:04] DEBUG[6233] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [2012-02-02 20:34:04] VERBOSE[6233] file.c: -- Playing 'vm-incorrect.ulaw' (language 'en') [2012-02-02 20:34:05] DEBUG[6233] channel.c: Scheduling timer at (66 requested / 66 actual) timer ticks per second [2012-02-02 20:34:05] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:05] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:05] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:05] DEBUG[6233] app_voicemail.c: Before find user for mailbox 104 [2012-02-02 20:34:05] DEBUG[6233] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [2012-02-02 20:34:05] VERBOSE[6233] file.c: -- Playing 'vm-password.ulaw' (language 'en') [2012-02-02 20:34:06] VERBOSE[6222] chan_sip.c: <--- SIP read from UDP:192.168.233.240:5060 ---> BYE sip:504@192.168.233.235:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK23426a5ee Max-Forwards: 70 Content-Length: 0 To: 504 ;tag=as55e5d0ba From: GameRoom ;tag=63f483be3afa655 Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347982 BYE Supported: timer Supported: replaces Authorization:Digest response="05af5faf970eae43225f3192441383d2",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 0 [ 40]: BYE sip:504@192.168.233.235:5060 SIP/2.0 [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK23426a5ee [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 3 [ 17]: Content-Length: 0 [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 4 [ 53]: To: 504 ;tag=as55e5d0ba [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 5 [ 80]: From: GameRoom ;tag=63f483be3afa655 [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 6 [ 57]: Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 7 [ 19]: CSeq: 780347982 BYE [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 8 [ 16]: Supported: timer [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 9 [ 19]: Supported: replaces [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 10 [177]: Authorization:Digest response="05af5faf970eae43225f3192441383d2",username="103546579768__xErT",realm="asterisk",nonce="3a026bb5",algorithm=MD5,uri="sip:504@192.168.233.235:5060" [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Header 11 [ 80]: User-Agent: Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 [2012-02-02 20:34:06] VERBOSE[6222] chan_sip.c: --- (12 headers 0 lines) --- [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: = Looking for Call ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 (Checking From) --From tag 63f483be3afa655 --To-tag as55e5d0ba [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Initializing initreq for method BYE - callid 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:06] DEBUG[6222] netsock2.c: Splitting '192.168.233.240:5060' into... [2012-02-02 20:34:06] DEBUG[6222] netsock2.c: ...host '192.168.233.240' and port '5060'. [2012-02-02 20:34:06] VERBOSE[6222] chan_sip.c: Sending to 192.168.233.240:5060 (NAT) [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Setting SIP_ALREADYGONE on dialog 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:06] DEBUG[6222] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb750f0d0' [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Session timer stopped: 363 - 969a50694d872653a577c2fdf7f0b352@192.168.233.240 [2012-02-02 20:34:06] VERBOSE[6222] chan_sip.c: Scheduling destruction of SIP dialog '969a50694d872653a577c2fdf7f0b352@192.168.233.240' in 11200 ms (Method: BYE) [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Received bye, issuing owner hangup [2012-02-02 20:34:06] VERBOSE[6222] chan_sip.c: <--- Transmitting (NAT) to 192.168.233.240:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.233.240:5060;branch=z9hG4bK23426a5ee;received=192.168.233.240;rport=5060 From: GameRoom ;tag=63f483be3afa655 To: 504 ;tag=as55e5d0ba Call-ID: 969a50694d872653a577c2fdf7f0b352@192.168.233.240 CSeq: 780347982 BYE Server: MyPBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [2012-02-02 20:34:06] DEBUG[6222] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.233.240:5060 [2012-02-02 20:34:06] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:06] DEBUG[6233] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [2012-02-02 20:34:06] WARNING[6233] app_voicemail.c: Unable to read password [2012-02-02 20:34:06] DEBUG[6233] app_voicemail.c: After vm_authenticate [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Extension 504, priority 1 returned normally even though call was hung up [2012-02-02 20:34:06] DEBUG[6233] channel.c: Soft-Hanging up channel 'SIP/103546579768__xErT-00000000' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Launching 'Hangup' [2012-02-02 20:34:06] VERBOSE[6233] pbx.c: -- Executing [h@internal111:1] Hangup("SIP/103546579768__xErT-00000000", "") in new stack [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Spawn extension (internal111,h,1) exited non-zero on 'SIP/103546579768__xErT-00000000' [2012-02-02 20:34:06] VERBOSE[6233] pbx.c: == Spawn extension (internal111, h, 1) exited non-zero on 'SIP/103546579768__xErT-00000000' [2012-02-02 20:34:06] DEBUG[6233] channel.c: Hanging up channel 'SIP/103546579768__xErT-00000000' [2012-02-02 20:34:06] DEBUG[6233] chan_sip.c: Hanging up zombie call. Be scared. [2012-02-02 20:34:06] DEBUG[6233] chan_sip.c: update_call_counter(103546579768__xErT) - decrement call limit counter on hangup [2012-02-02 20:34:06] DEBUG[6233] chan_sip.c: Updating call counter for incoming call [2012-02-02 20:34:06] DEBUG[6233] chan_sip.c: Call from peer '103546579768__xErT' removed from call limit 19 [2012-02-02 20:34:06] DEBUG[6233] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb750f0d0' [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: No provider found, checking channel drivers for SIP - 103546579768__xErT [2012-02-02 20:34:06] DEBUG[6215] chan_sip.c: Checking device state for peer 103546579768__xErT [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(clid)' (from 'CDR(clid)}","${CDR(src)}","${CDR(dst)}","${CDR(dcontext)}","${CDR(channel)}","${CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 9) [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: Changing state for SIP/103546579768__xErT - state 1 (Not in use) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '"GameRoom" <103546579768__xErT>' [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: device 'SIP/103546579768__xErT' state '1' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(src)' (from 'CDR(src)}","${CDR(dst)}","${CDR(dcontext)}","${CDR(channel)}","${CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 8) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '103546579768__xErT' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(dst)' (from 'CDR(dst)}","${CDR(dcontext)}","${CDR(channel)}","${CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 8) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '504' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(dcontext)' (from 'CDR(dcontext)}","${CDR(channel)}","${CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 13) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is 'internal111' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(channel)' (from 'CDR(channel)}","${CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 12) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is 'SIP/103546579768__xErT-00000000' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(dstchannel)' (from 'CDR(dstchannel)}","${CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 15) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '(null)' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(lastapp)' (from 'CDR(lastapp)}","${CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 12) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is 'VoiceMailMain' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(lastdata)' (from 'CDR(lastdata)}","${CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 13) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '104' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(start)' (from 'CDR(start)}","${CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 10) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '2012-02-02 20:33:59' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(answer)' (from 'CDR(answer)}","${CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 11) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '2012-02-02 20:33:59' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(end)' (from 'CDR(end)}","${CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 8) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '2012-02-02 20:34:06' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(duration)' (from 'CDR(duration)}","${CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 13) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '7' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(billsec)' (from 'CDR(billsec)}","${CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 12) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '7' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(disposition)' (from 'CDR(disposition)}","${CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 16) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is 'ANSWERED' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(amaflags)' (from 'CDR(amaflags)}","${CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 13) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is 'DOCUMENTATION' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(accountcode)' (from 'CDR(accountcode)}","${CDR(uniqueid)}","${CDR(userfield)}" ' len 16) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '(null)' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(uniqueid)' (from 'CDR(uniqueid)}","${CDR(userfield)}" ' len 13) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '1328243639.0' [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Evaluating 'CDR(userfield)' (from 'CDR(userfield)}" ' len 14) [2012-02-02 20:34:06] DEBUG[6233] pbx.c: Function result is '(null)' [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: No provider found, checking channel drivers for SIP - 103546579768__xErT [2012-02-02 20:34:06] DEBUG[6215] chan_sip.c: Checking device state for peer 103546579768__xErT [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: Changing state for SIP/103546579768__xErT - state 1 (Not in use) [2012-02-02 20:34:06] DEBUG[6215] devicestate.c: device 'SIP/103546579768__xErT' state '1' [2012-02-02 20:34:07] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '5ee65d213a55c4eb485812d77cbd1293@76.169.12.120:5060' [2012-02-02 20:34:07] DEBUG[6222] chan_sip.c: Destroying SIP dialog 5ee65d213a55c4eb485812d77cbd1293@76.169.12.120:5060 [2012-02-02 20:34:07] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '5ee65d213a55c4eb485812d77cbd1293@76.169.12.120:5060' Method: NOTIFY [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '2f14189b36cb9c802fd23344045f85b7@192.168.233.235' [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Destroying SIP dialog 2f14189b36cb9c802fd23344045f85b7@192.168.233.235 [2012-02-02 20:34:09] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '2f14189b36cb9c802fd23344045f85b7@192.168.233.235' Method: REGISTER [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '0408bfb91dffd7e07f054f8c3e5e5bb5@192.168.233.235' [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Destroying SIP dialog 0408bfb91dffd7e07f054f8c3e5e5bb5@192.168.233.235 [2012-02-02 20:34:09] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '0408bfb91dffd7e07f054f8c3e5e5bb5@192.168.233.235' Method: REGISTER [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '2726bbf9497e5cad34a00f22395150be@192.168.233.235' [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Destroying SIP dialog 2726bbf9497e5cad34a00f22395150be@192.168.233.235 [2012-02-02 20:34:09] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '2726bbf9497e5cad34a00f22395150be@192.168.233.235' Method: REGISTER [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Auto destroying SIP dialog '1cf74f190d389c192a1cf2e41cc9cd00@192.168.233.235' [2012-02-02 20:34:09] DEBUG[6222] chan_sip.c: Destroying SIP dialog 1cf74f190d389c192a1cf2e41cc9cd00@192.168.233.235 [2012-02-02 20:34:09] VERBOSE[6222] chan_sip.c: Really destroying SIP dialog '1cf74f190d389c192a1cf2e41cc9cd00@192.168.233.235' Method: REGISTER [2012-02-02 20:34:26] VERBOSE[6222] chan_sip.c: > Saved useragent "Aastra 480i Cordless/1.4.3.1001 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45" for peer 101767249768__xErT [2012-02-02 20:34:36] VERBOSE[6227] asterisk.c: -- Remote UNIX connection disconnected [2012-02-02 20:35:22] VERBOSE[6222] dnsmgr.c: > doing dnsmgr_lookup for 'sipconnect.ipcomms.net' [2012-02-02 20:35:22] VERBOSE[6222] dnsmgr.c: > doing dnsmgr_lookup for 'sip.flowroute.com' [2012-02-02 20:35:22] VERBOSE[6222] srv.c: > ast_get_srv: SRV lookup for '_sip._udp.sip.flowroute.com' mapped to host sip-nv1.flowroute.com, port 5060 [2012-02-02 20:35:22] VERBOSE[6222] dnsmgr.c: > doing dnsmgr_lookup for 'sipconnect.ipcomms.net' [2012-02-02 20:35:55] VERBOSE[6222] chan_sip.c: > Saved useragent "snom-m3-SIP/02.11 (MAC=00087B07961F; HW=255)" for peer 1075465797_6_8XXyywf [2012-02-02 20:36:01] VERBOSE[6222] chan_sip.c: > Saved useragent "snom-m3-SIP/02.11 (MAC=00087B07961F; HW=255)" for peer 1055465797_6_8XXyywf [2012-02-02 20:36:03] VERBOSE[6211] asterisk.c: -- Remote UNIX connection [2012-02-02 20:36:06] VERBOSE[6724] asterisk.c: Waiting for inactivity to perform restart... [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ADSIProg' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Authenticate' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'NoCDR' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'CELGenUserEvent' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ChanIsAvail' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ChannelRedirect' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ControlPlayback' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DAHDIRAS' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DBdeltree' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DBdel' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Dial' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'RetryDial' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Dictate' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Pickup' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'PickupChan' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Directory' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DumpChan' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Echo' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Exec' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'TryExec' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ExecIf' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ExternalIVR' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Flash' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'FollowMe' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'GetCPEID' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ICES' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'IVRDemo' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MacroIf' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MacroExit' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Macro' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MacroExclusive' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Milliwatt' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmRecord' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmGreet' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmNotify' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmDelete' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmAccMess' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MinivmMWI' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MINIVMACCOUNT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MINIVMCOUNTER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StopMixMonitor' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MixMonitor' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action MixMonitorMute [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'NBScat' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Originate' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ParkAndAnnounce' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Playback' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'PlayTones' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StopPlayTones' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'PrivacyManager' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Read' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ReadExten' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Record' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SayUnixTime' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DateTime' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SendDTMF' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action PlayDTMF [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SendText' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SMS' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SoftHangup' [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'gosub' unregistered [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Return' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StackPop' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'GosubIf' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Gosub' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function LOCAL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function LOCAL_PEEK [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'System' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'TrySystem' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'BackgroundDetect' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'TestClient' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'TestServer' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Transfer' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SendURL' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'UserEvent' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Verbose' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Log' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'VoiceMail' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'VoiceMailMain' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MailboxExists' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'VMAuthenticate' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'VMSayName' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MAILBOX_EXISTS [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action VoicemailUsersList [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'WaitForRing' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'WaitForSilence' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'WaitForNoise' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'WaitUntil' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'While' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'EndWhile' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ExitWhile' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ContinueWhile' [2012-02-02 20:36:06] VERBOSE[6724] bridging.c: == Unregistered bridge technology multiplexed_bridge [2012-02-02 20:36:06] VERBOSE[6724] bridging.c: == Unregistered bridge technology simple_bridge [2012-02-02 20:36:06] VERBOSE[6724] bridging.c: == Unregistered bridge technology softmix [2012-02-02 20:36:06] VERBOSE[6724] cdr.c: == Unregistered 'cdr-custom' CDR backend [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'Agent' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function AGENT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'AgentLogin' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'AgentMonitorOutgoing' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action Agents [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action AgentLogoff [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'Bridge' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIDialOffhook [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIHangup [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDITransfer [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIDNDoff [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIDNDon [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIShowChannels [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action DAHDIRestart [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'DAHDI' [2012-02-02 20:36:06] VERBOSE[6724] chan_dahdi.c: -- Unregistered channel -2 [2012-02-02 20:36:06] VERBOSE[6724] chan_dahdi.c: -- Unregistered channel 1 [2012-02-02 20:36:06] VERBOSE[6724] chan_dahdi.c: -- Unregistered channel 2 [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action LocalOptimizeAway [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'Local' [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'MulticastRTP' [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'Phone' [2012-02-02 20:36:06] VERBOSE[6724] channel.c: == Unregistered channel type 'SIP' [2012-02-02 20:36:06] VERBOSE[6724] message.c: -- Message technology handler 'sip' unregistered. [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SIPCHANINFO [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SIPPEER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SIP_HEADER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CHECKSIPDOMAIN [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SIPDtmfMode' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SIPAddHeader' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SIPRemoveHeader' [2012-02-02 20:36:06] VERBOSE[6724] rtp_engine.c: == Unregistered RTP glue 'SIP' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action SIPpeers [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action SIPshowpeer [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action SIPqualifypeer [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action SIPshowregistry [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action SIPnotify [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'ulawtoalaw' from format ulaw to alaw [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'alawtoulaw' from format alaw to ulaw [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintoadpcm' from format slin to adpcm [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'adpcmtolin' from format adpcm to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintoalaw' from format slin to alaw [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'alawtolin' from format alaw to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'g722tolin' from format g722 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintog722' from format slin to g722 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'g722tolin16' from format g722 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lin16tog722' from format slin16 to g722 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'g726tolin' from format g726 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintog726' from format slin to g726 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'g726aal2tolin' from format g726aal2 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintog726aal2' from format slin to g726aal2 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintogsm' from format slin to gsm [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'gsmtolin' from format gsm to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 12000khz' from format slin to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 16000khz' from format slin to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 24000khz' from format slin to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 32000khz' from format slin to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 44100khz' from format slin to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 48000khz' from format slin to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 96000khz' from format slin to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 8000khz -> 192000khz' from format slin to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 8000khz' from format slin12 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 16000khz' from format slin12 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 24000khz' from format slin12 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 32000khz' from format slin12 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 44100khz' from format slin12 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 48000khz' from format slin12 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 96000khz' from format slin12 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 12000khz -> 192000khz' from format slin12 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 8000khz' from format slin16 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 12000khz' from format slin16 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 24000khz' from format slin16 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 32000khz' from format slin16 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 44100khz' from format slin16 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 48000khz' from format slin16 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 96000khz' from format slin16 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 16000khz -> 192000khz' from format slin16 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 8000khz' from format slin24 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 12000khz' from format slin24 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 16000khz' from format slin24 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 32000khz' from format slin24 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 44100khz' from format slin24 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 48000khz' from format slin24 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 96000khz' from format slin24 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 24000khz -> 192000khz' from format slin24 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 8000khz' from format slin32 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 12000khz' from format slin32 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 16000khz' from format slin32 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 24000khz' from format slin32 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 44100khz' from format slin32 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 48000khz' from format slin32 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 96000khz' from format slin32 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 32000khz -> 192000khz' from format slin32 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 8000khz' from format slin44 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 12000khz' from format slin44 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 16000khz' from format slin44 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 24000khz' from format slin44 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 32000khz' from format slin44 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 48000khz' from format slin44 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 96000khz' from format slin44 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 44100khz -> 192000khz' from format slin44 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 8000khz' from format slin48 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 12000khz' from format slin48 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 16000khz' from format slin48 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 24000khz' from format slin48 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 32000khz' from format slin48 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 44100khz' from format slin48 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 96000khz' from format slin48 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 48000khz -> 192000khz' from format slin48 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 8000khz' from format slin96 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 12000khz' from format slin96 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 16000khz' from format slin96 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 24000khz' from format slin96 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 32000khz' from format slin96 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 44100khz' from format slin96 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 48000khz' from format slin96 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 96000khz -> 192000khz' from format slin96 to slin192 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 8000khz' from format slin192 to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 12000khz' from format slin192 to slin12 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 16000khz' from format slin192 to slin16 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 24000khz' from format slin192 to slin24 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 32000khz' from format slin192 to slin32 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 44100khz' from format slin192 to slin44 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 48000khz' from format slin192 to slin48 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'slin 192000khz -> 96000khz' from format slin192 to slin96 [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintoulaw' from format slin to ulaw [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'ulawtolin' from format ulaw to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'testlawtolin' from format testlaw to slin [2012-02-02 20:36:06] VERBOSE[6724] translate.c: == Unregistered translator 'lintotestlaw' from format slin to testlaw [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g719 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g723sf [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g726-40 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g726-32 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g726-24 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g726-16 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g729 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format gsm [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format h263 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format h264 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format iLBC [2012-02-02 20:36:06] VERBOSE[6724] image.c: == Unregistered format 'jpg' (JPEG (Joint Picture Experts Group)) [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format pcm [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format alaw [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format au [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format g722 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format siren14 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format siren7 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln12 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln16 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln24 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln32 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln44 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln48 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln96 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format sln192 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format vox [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format wav [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format wav16 [2012-02-02 20:36:06] VERBOSE[6724] file.c: == Unregistered format wav49 [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function AES_DECRYPT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function AES_ENCRYPT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function AUDIOHOOK_INHERIT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function BASE64_ENCODE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function BASE64_DECODE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function BLACKLIST [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CALLCOMPLETION [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CALLERPRES [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CALLERID [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CONNECTEDLINE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REDIRECTING [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CDR [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CHANNEL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CHANNELS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MASTER_CHANNEL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function AST_CONFIG [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CUT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SORT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DB [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DB_EXISTS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DB_DELETE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DB_KEYS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DEVICE_STATE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function HINT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DIALGROUP [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DIALPLAN_EXISTS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function VALID_EXTEN [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ENUMRESULT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ENUMQUERY [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ENUMLOOKUP [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function TXTCIDNAME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ENV [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function STAT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FILE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FILE_COUNT_LINE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FILE_FORMAT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function EXTENSION_STATE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FRAME_TRACE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function GLOBAL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SHARED [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function GROUP_COUNT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function GROUP_MATCH_COUNT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function GROUP_LIST [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function GROUP [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ICONV [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function JITTERBUFFER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function LOCK [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function TRYLOCK [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function UNLOCK [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ISNULL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SET [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function EXISTS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function IF [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function IFTIME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function IMPORT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MATH [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function INC [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function DEC [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MD5 [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function IFMODULE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function PITCH_SHIFT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function RAND [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REALTIME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REALTIME_STORE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REALTIME_DESTROY [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REALTIME_FIELD [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REALTIME_HASH [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SHA1 [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SHELL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SPRINTF [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SRVQUERY [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SRVRESULT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FIELDQTY [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FIELDNUM [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FILTER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REPLACE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function STRREPLACE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function LISTFILTER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function REGEX [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function ARRAY [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function QUOTE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function CSV_QUOTE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function LEN [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function STRFTIME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function STRPTIME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function EVAL [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function KEYPADHASH [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function HASHKEYS [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function HASH [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ClearHash' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function TOUPPER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function TOLOWER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SHIFT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function POP [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function PUSH [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function UNSHIFT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function PASSTHRU [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function SYSINFO [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function TIMEOUT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function URIDECODE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function URIENCODE [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function VERSION [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function VMCOUNT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function VOLUME [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove check-blacklist/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove get-cid-name/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/6/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/6/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/4/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/4/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/3/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/3/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/2/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/2/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/1/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/1/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove getline/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/26, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/25, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/24, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/23, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/22, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/21, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/20, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/19, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/18, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/17, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/16, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/15, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/14, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove answer-business/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/5/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/5/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/5/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/5/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/4/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/4/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/4/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/4/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/3/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/3/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/3/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/3/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/2/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/2/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/2/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/2/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/1/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/1/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/1/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/1/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove set-call-forward/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-fish/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/h/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/h/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/50, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/49, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/48, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/47, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/46, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/45, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/44, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/43, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/42, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/41, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/40, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/39, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/38, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/37, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/36, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/35, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/34, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/33, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/32, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/31, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/30, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/29, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/28, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/27, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/26, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/25, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/24, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/23, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/22, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/21, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/20, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/19, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/18, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/17, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/16, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/15, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/14, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-international/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/h/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/h/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/i/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/61, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/60, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/59, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/58, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/57, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/56, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/55, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/54, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/53, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/52, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/51, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/50, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/49, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/48, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/47, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/46, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/45, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/44, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/43, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/42, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/41, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/40, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/39, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/38, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/37, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/36, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/35, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/34, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/33, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/32, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/31, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/30, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/29, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/28, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/27, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/26, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/25, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/24, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/23, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/22, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/21, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/20, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/19, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/18, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/17, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/16, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/15, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/14, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove dial-long/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_101./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_101./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_01187[1-4]./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_01187[1-4]./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]1NXX976XXX./3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]1NXX976XXX./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]1NXX976XXX./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]NXX976XXX./3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]NXX976XXX./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]NXX976XXX./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_NXX976XXXX/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_NXX976XXXX/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_NXX976XXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]976./3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]976./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]976./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_976XXX./3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_976XXX./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_976XXX./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]533./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]533./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_533./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_533./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]700./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]700./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_700./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_700./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]900./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_[1-5]900./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_9[05]0./2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove blocked_numbers/_9[05]0./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_0114989./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_0118526./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_01139./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_01144./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_01149221./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011333./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011334./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011331./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011612./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011613./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011649./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove outbound-international/_011647./1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/777888/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/654321/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_510X/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_66XX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/667/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/667/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/667/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/667/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/667/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/666/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/666/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/666/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/12346/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/12346/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/745/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/745/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/745/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/22, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/21, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/20, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/19, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/18, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/17, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/16, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/15, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/14, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/555/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_50[12346]/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/500/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/999/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/999/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/999/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/999/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1104/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1104/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1103/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1103/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1102/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1102/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1101/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/1101/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/36723561/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_[346]11/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/_[346]11/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal/911/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/_91[0-9][0-9]/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/108/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/108/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/107/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/107/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/106/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/106/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/105/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/105/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/104/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/104/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/103/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/103/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/102/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/102/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/101/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internalonly/101/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_1[12]/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_[0-468]1NXXNXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_[0-468]NXXNXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_[0-468]NXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_NXXNXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove normal_calls/_NXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_[1-4]18[0876][0876]NXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 800_numbers/_8[0678][0678]NXXXXXX/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/h/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/107/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/106/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/105/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/104/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/103/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/102/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove internal111/101/-1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/i/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/i/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/1/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/1/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/t/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove callrobyn/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/i/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove bethhell/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove mystery_sip/_011106229323/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove mystery_sip/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove mystery_sip/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/e/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/1/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/9/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove robyn_calling/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/e/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/9/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/6/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/5/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove ira_calling/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove IdeaSIP/11012012013/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove fishtune/3104990989/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove iBid/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/199/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/100/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/13109190999/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/13106945051/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/6314098389/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove camaro/13108074628/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 199/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 199/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 100/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 100/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 6314098389/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove 6314098389/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove zaino/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove zaino/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/i/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/9/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/6/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/6/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/6/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/6/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/5/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/5/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/5/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/5/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/7/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/7/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/7/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/2/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/2/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/1/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/1/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/1/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/28, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/27, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/26, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/25, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/24, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/23, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/22, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/21, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/20, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/19, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/18, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/17, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/16, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/15, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/14, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/13, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/12, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/11, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/10, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/9, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/8, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/7, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/6, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/5, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/4, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/3, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove home/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove parkedcalls/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove default/s/2, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: -- Remove default/s/1, registrar=pbx_config; con=((nil)); con->root=(nil) [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'answer' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'asyncagi break' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'channel status' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'database del' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'database deltree' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'database get' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'database put' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'exec' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'get data' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'get full variable' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'get option' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'get variable' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'hangup' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'noop' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'receive char' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'receive text' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'record file' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say alpha' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say digits' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say number' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say phonetic' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say date' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say time' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'say datetime' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'send image' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'send text' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set autohangup' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set callerid' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set context' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set extension' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set music' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set priority' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'set variable' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'stream file' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'control stream file' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'tdd mode' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'verbose' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'wait for digit' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech create' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech set' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech destroy' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech load grammar' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech unload grammar' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech activate grammar' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech deactivate grammar' unregistered [2012-02-02 20:36:06] VERBOSE[6724] res_agi.c: == AGI Command 'speech recognize' unregistered [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'EAGI' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'DeadAGI' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action AGI [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'AGI' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function FAXOPT [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SendFAX' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ReceiveFAX' [2012-02-02 20:36:06] VERBOSE[6724] config.c: == Parsing '/etc/asterisk/codecs.conf': [2012-02-02 20:36:06] VERBOSE[6724] config.c: == Found [2012-02-02 20:36:06] VERBOSE[6724] config.c: == Parsing '/etc/asterisk/codecs.conf': [2012-02-02 20:36:06] VERBOSE[6724] config.c: == Found [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'Monitor' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StopMonitor' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'ChangeMonitor' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'PauseMonitor' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'UnpauseMonitor' [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action Monitor [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action StopMonitor [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action ChangeMonitor [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action PauseMonitor [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action UnpauseMonitor [2012-02-02 20:36:06] VERBOSE[6724] res_musiconhold.c: == Destroying musiconhold processes [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'MusicOnHold' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'WaitMusicOnHold' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'SetMusicOnHold' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StartMusicOnHold' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered application 'StopMusicOnHold' [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function MUTEAUDIO [2012-02-02 20:36:06] VERBOSE[6724] manager.c: == Manager unregistered action MuteAudio [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function PP_EACH_USER [2012-02-02 20:36:06] VERBOSE[6724] pbx.c: == Unregistered custom function PP_EACH_EXTENSION [2012-02-02 20:36:06] VERBOSE[6724] rtp_engine.c: == Unregistered RTP engine 'asterisk' [2012-02-02 20:36:06] VERBOSE[6724] rtp_engine.c: == Unregistered RTP engine 'multicast' [2012-02-02 20:36:06] VERBOSE[6724] res_security_log.c: -- Security Logging Disabled [2012-02-02 20:36:06] VERBOSE[6724] asterisk.c: Executing last minute cleanups [2012-02-02 20:36:06] VERBOSE[6724] asterisk.c: Asterisk cleanly ending (0). [2012-02-02 20:36:06] VERBOSE[6724] asterisk.c: Preparing for Asterisk restart... [2012-02-02 20:36:06] VERBOSE[6724] asterisk.c: Asterisk is now restarting...