[Feb 23 02:28:39] Asterisk 11.2.1 built by cz @ cz-OpenStation on a x86_64 running Linux on 2013-02-04 16:22:37 UTC [Feb 23 02:28:39] VERBOSE[13710] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Feb 23 02:28:39] VERBOSE[13710] logger.c: Asterisk Queue Logger restarted [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Auto destroying SIP dialog '4da896d13324e1da1e8050a141371683@192.168.0.213:5060' [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Destroying SIP dialog 4da896d13324e1da1e8050a141371683@192.168.0.213:5060 [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: Really destroying SIP dialog '4da896d13324e1da1e8050a141371683@192.168.0.213:5060' Method: OPTIONS [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: <--- SIP read from UDP:192.168.0.213:5060 ---> MESSAGE sip:192.168.0.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.98.1:5060;branch=z9hG4bK2a6f56fb Max-Forwards: 70 From: "10658487" ;tag=as4c4f6054 To: Contact: Call-ID: 2f43503c54b89b2b6e700c331993c697@192.168.0.213 CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 <-------------> [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 0 [ 31]: MESSAGE sip:192.168.0.1 SIP/2.0 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 192.168.98.1:5060;branch=z9hG4bK2a6f56fb [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 3 [ 60]: From: "10658487" ;tag=as4c4f6054 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 4 [ 21]: To: [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 5 [ 42]: Contact: [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 6 [ 55]: Call-ID: 2f43503c54b89b2b6e700c331993c697@192.168.0.213 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 7 [ 17]: CSeq: 102 MESSAGE [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 11.2.1 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 9 [ 38]: Content-Type: text/plain;charset=UTF-8 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 10 [ 19]: Content-Length: 128 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Header 11 [ 0]: [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Body 0 [128]: 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: --- (11 headers 1 lines) --- [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: = Looking for Call ID: 2f43503c54b89b2b6e700c331993c697@192.168.0.213 (Checking From) --From tag as4c4f6054 --To-tag [Feb 23 02:29:02] DEBUG[13714] acl.c: Not an IPv4 nor IPv6 address, cannot get port. [Feb 23 02:29:02] DEBUG[13714] acl.c: For destination '192.168.0.213', our source address is '192.168.0.1'. [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.0.1:5060 [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Allocating new SIP dialog for 2f43503c54b89b2b6e700c331993c697@192.168.0.213 - MESSAGE (No RTP) [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: **** Received MESSAGE (11) - Command in SIP MESSAGE [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: Receiving message! [Feb 23 02:29:02] DEBUG[13714] netsock2.c: Splitting '192.168.0.213' into... [Feb 23 02:29:02] DEBUG[13714] netsock2.c: ...host '192.168.0.213' and port ''. [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: Found peer 'mobile-out' for '10658487' from 192.168.0.213:5060 [Feb 23 02:29:02] DEBUG[13714] netsock2.c: Splitting '192.168.0.1' into... [Feb 23 02:29:02] DEBUG[13714] netsock2.c: ...host '192.168.0.1' and port ''. [Feb 23 02:29:02] DEBUG[13714] netsock2.c: Splitting '192.168.0.213' into... [Feb 23 02:29:02] DEBUG[13714] netsock2.c: ...host '192.168.0.213' and port ''. [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: Looking for s in inmobilesms (domain 192.168.0.1) [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Function result is '"10658487" ' [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Function result is '上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。' [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Launching 'NoOp' [Feb 23 02:29:02] VERBOSE[13700][C-00000015] pbx.c: -- Executing [s@inmobilesms:1] NoOp("Message/ast_msg_queue", "Incoming SMS from "10658487" : 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。") in new stack [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Evaluating 'MESSAGE(from)' (from 'MESSAGE(from)}' len 13) [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Function result is '"10658487" ' [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Function result is '"10658487" ") in new stack [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Allocating new SIP dialog for 0fbab17f578da9ee682c647a5757b32e@(null) - MESSAGE (No RTP) [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Feb 23 02:29:02] DEBUG[13700][C-00000015] acl.c: Not an IPv4 nor IPv6 address, cannot get port. [Feb 23 02:29:02] DEBUG[13700][C-00000015] acl.c: For destination '192.168.0.83', our source address is '192.168.0.1'. [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.0.1:5060 [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Initializing initreq for method MESSAGE - callid 0fbab17f578da9ee682c647a5757b32e@(null) [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 0 [ 46]: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 1 [ 46]: Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 3 [ 58]: From: "10658487" ;tag=as345f27e5 [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 4 [ 36]: To: [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 5 [ 40]: Contact: [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 6 [ 48]: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 7 [ 17]: CSeq: 102 MESSAGE [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 11.2.1 [Feb 23 02:29:02] VERBOSE[13700][C-00000015] chan_sip.c: Reliably Transmitting (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #952 [Feb 23 02:29:02] DEBUG[13700][C-00000015] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:02] VERBOSE[13700][C-00000015] chan_sip.c: Scheduling destruction of SIP dialog '0fbab17f578da9ee682c647a5757b32e@(null)' in 32000 ms (Method: MESSAGE) [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Launching 'Hangup' [Feb 23 02:29:02] VERBOSE[13700][C-00000015] pbx.c: -- Executing [s@inmobilesms:5] Hangup("Message/ast_msg_queue", "") in new stack [Feb 23 02:29:02] DEBUG[13700][C-00000015] channel.c: Soft-Hanging up channel 'Message/ast_msg_queue' [Feb 23 02:29:02] DEBUG[13700][C-00000015] pbx.c: Spawn extension (inmobilesms,s,5) exited non-zero on 'Message/ast_msg_queue' [Feb 23 02:29:02] VERBOSE[13700][C-00000015] pbx.c: == Spawn extension (inmobilesms, s, 5) exited non-zero on 'Message/ast_msg_queue' [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: <--- Transmitting (no NAT) to 192.168.0.213:5060 ---> SIP/2.0 202 Accepted Via: SIP/2.0/UDP 192.168.98.1:5060;branch=z9hG4bK2a6f56fb;received=192.168.0.213 From: "10658487" ;tag=as4c4f6054 To: ;tag=as1d9b858a Call-ID: 2f43503c54b89b2b6e700c331993c697@192.168.0.213 CSeq: 102 MESSAGE Server: Asterisk PBX 11.2.1 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces Content-Length: 0 <------------> [Feb 23 02:29:02] DEBUG[13714] chan_sip.c: Trying to put 'SIP/2.0 202' onto UDP socket destined for 192.168.0.213:5060 [Feb 23 02:29:02] VERBOSE[13714] chan_sip.c: Scheduling destruction of SIP dialog '2f43503c54b89b2b6e700c331993c697@192.168.0.213' in 6400 ms (Method: MESSAGE) [Feb 23 02:29:03] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (1) MESSAGE - 11 [Feb 23 02:29:03] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:03] VERBOSE[13714] chan_sip.c: Retransmitting #1 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:03] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:04] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (2) MESSAGE - 11 [Feb 23 02:29:04] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:04] VERBOSE[13714] chan_sip.c: Retransmitting #2 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:04] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:06] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (3) MESSAGE - 11 [Feb 23 02:29:06] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:06] VERBOSE[13714] chan_sip.c: Retransmitting #3 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:06] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:09] DEBUG[13714] chan_sip.c: Auto destroying SIP dialog '4be058de200307191120@15.192.1.15' [Feb 23 02:29:09] DEBUG[13714] chan_sip.c: Destroying SIP dialog 4be058de200307191120@15.192.1.15 [Feb 23 02:29:09] VERBOSE[13714] chan_sip.c: Really destroying SIP dialog '4be058de200307191120@15.192.1.15' Method: OPTIONS [Feb 23 02:29:09] DEBUG[13714] chan_sip.c: Auto destroying SIP dialog '2f43503c54b89b2b6e700c331993c697@192.168.0.213' [Feb 23 02:29:09] DEBUG[13714] chan_sip.c: Destroying SIP dialog 2f43503c54b89b2b6e700c331993c697@192.168.0.213 [Feb 23 02:29:09] VERBOSE[13714] chan_sip.c: Really destroying SIP dialog '2f43503c54b89b2b6e700c331993c697@192.168.0.213' Method: MESSAGE [Feb 23 02:29:10] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (4) MESSAGE - 11 [Feb 23 02:29:10] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:10] VERBOSE[13714] chan_sip.c: Retransmitting #4 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:10] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:14] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (5) MESSAGE - 11 [Feb 23 02:29:14] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:14] VERBOSE[13714] chan_sip.c: Retransmitting #5 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:14] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695 [Feb 23 02:29:18] DEBUG[13714] chan_sip.c: SIP TIMER: Rescheduling retransmission #952 (6) MESSAGE - 11 [Feb 23 02:29:18] DEBUG[13714] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #952)) [Feb 23 02:29:18] VERBOSE[13714] chan_sip.c: Retransmitting #6 (NAT) to 192.168.0.83:60695: MESSAGE sip:2204@192.168.0.83:60695;ob SIP/2.0 Via: SIP/2.0/UDP (null);branch=z9hG4bK49ec1e1f Max-Forwards: 70 From: "10658487" ;tag=as345f27e5 To: Contact: Call-ID: 0fbab17f578da9ee682c647a5757b32e@(null) CSeq: 102 MESSAGE User-Agent: Asterisk PBX 11.2.1 Content-Type: text/plain;charset=UTF-8 Content-Length: 128 上海移动网上营业厅动态密码：340653，30分钟内有效，请您尽快登录，此密码将在您登录后失效。 --- [Feb 23 02:29:18] DEBUG[13714] chan_sip.c: Trying to put 'MESSAGE sip' onto UDP socket destined for 192.168.0.83:60695