[2008-01-23 14:37:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5062 ---> INVITE sip:99@sip.serviceplanet.nl SIP/2.0 Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bKb953f74e9d4ef201 From: ;tag=31b30fa3ead5bcd0 To: Contact: Supported: replaces, timer, path Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32073 INVITE User-Agent: Grandstream GXP2020 1.1.5.15 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Type: application/sdp Content-Length: 407 v=0 o=sipsma 8000 8000 IN IP4 172.16.1.202 s=SIP Call c=IN IP4 172.16.1.202 t=0 0 m=audio 5004 RTP/AVP 8 0 4 18 2 97 9 3 101 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=20 a=rtpmap:9 G722/16000 a=rtpmap:3 GSM/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: INVITE sip:99@sip.serviceplanet.nl SIP/2.0 (42) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bKb953f74e9d4ef201 (65) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=31b30fa3ead5bcd0 (60) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: (33) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Contact: (53) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: Supported: replaces, timer, path (32) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: Call-ID: d7082d6d20b65881@172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: CSeq: 32073 INVITE (18) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: Max-Forwards: 70 (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: Content-Type: application/sdp (29) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 407 (19) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 13: (0) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: v=0 (3) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: o=sipsma 8000 8000 IN IP4 172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: s=SIP Call (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: c=IN IP4 172.16.1.202 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: t=0 0 (5) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: m=audio 5004 RTP/AVP 8 0 4 18 2 97 9 3 101 (42) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=sendrecv (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:4 G723/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:97 iLBC/8000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=fmtp:97 mode=20 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:9 G722/16000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:3 GSM/8000 (19) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=ptime:20 (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=fmtp:101 0-11 (15) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (13 headers 19 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on RTP to Off [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on VRTP to Off [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for d7082d6d20b65881@172.16.1.202 - INVITE (With RTP) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Begin: parsing SIP "Supported: replaces, timer, path" [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Found SIP option: -replaces- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Matched SIP option: replaces [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Found SIP option: -timer- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Matched SIP option: timer [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Found SIP option: -path- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Matched SIP option: path [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Sending to 172.16.1.202 : 5062 (no NAT) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Using INVITE request as basis request - d7082d6d20b65881@172.16.1.202 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on RTP to Off [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on VRTP to Off [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- Reliably Transmitting (no NAT) to 172.16.1.202:5062 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bKb953f74e9d4ef201;received=172.16.1.202 From: ;tag=31b30fa3ead5bcd0 To: ;tag=as487203cf Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32073 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="32aa6844" Content-Length: 0 <------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #434 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Scheduling destruction of SIP dialog 'd7082d6d20b65881@172.16.1.202' in 32000 ms (Method: INVITE) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found user 'sipsma' [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5062 ---> ACK sip:99@sip.serviceplanet.nl SIP/2.0 Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bKb953f74e9d4ef201 From: ;tag=31b30fa3ead5bcd0 To: ;tag=as487203cf Contact: Supported: path Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32073 ACK User-Agent: Grandstream GXP2020 1.1.5.15 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Length: 0 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: ACK sip:99@sip.serviceplanet.nl SIP/2.0 (39) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bKb953f74e9d4ef201 (65) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=31b30fa3ead5bcd0 (60) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=as487203cf (48) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Contact: (53) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: Supported: path (15) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: Call-ID: d7082d6d20b65881@172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: CSeq: 32073 ACK (15) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: Max-Forwards: 70 (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: Content-Length: 0 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 12: (0) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (12 headers 0 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = Found Their Call ID: d7082d6d20b65881@172.16.1.202 Their Tag 31b30fa3ead5bcd0 Our tag: as487203cf [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #434 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Stopping retransmission on 'd7082d6d20b65881@172.16.1.202' of Response 32073: Match Not Found [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5062 ---> INVITE sip:99@sip.serviceplanet.nl SIP/2.0 Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932 From: ;tag=31b30fa3ead5bcd0 To: Contact: Supported: replaces, timer, path Proxy-Authorization: Digest username="sipsma", realm="asterisk", algorithm=MD5, uri="sip:99@sip.serviceplanet.nl", nonce="32aa6844", response="2ed222ea80774afd99b00494e84727b9" Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32074 INVITE User-Agent: Grandstream GXP2020 1.1.5.15 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Type: application/sdp Content-Length: 407 v=0 o=sipsma 8000 8001 IN IP4 172.16.1.202 s=SIP Call c=IN IP4 172.16.1.202 t=0 0 m=audio 5004 RTP/AVP 8 0 4 18 2 97 9 3 101 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=20 a=rtpmap:9 G722/16000 a=rtpmap:3 GSM/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: INVITE sip:99@sip.serviceplanet.nl SIP/2.0 (42) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932 (65) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=31b30fa3ead5bcd0 (60) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: (33) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Contact: (53) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: Supported: replaces, timer, path (32) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: Proxy-Authorization: Digest username="sipsma", realm="asterisk", algorithm=MD5, uri="sip:99@sip.serviceplanet.nl", nonce="32aa6844", response="2ed222ea80774afd99b00494e84727b9" (176) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: Call-ID: d7082d6d20b65881@172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: CSeq: 32074 INVITE (18) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Max-Forwards: 70 (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 12: Content-Type: application/sdp (29) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 13: Content-Length: 407 (19) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 14: (0) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: v=0 (3) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: o=sipsma 8000 8001 IN IP4 172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: s=SIP Call (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: c=IN IP4 172.16.1.202 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: t=0 0 (5) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: m=audio 5004 RTP/AVP 8 0 4 18 2 97 9 3 101 (42) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=sendrecv (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:4 G723/8000 (20) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:97 iLBC/8000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=fmtp:97 mode=20 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:9 G722/16000 (21) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:3 GSM/8000 (19) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=ptime:20 (10) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Line: a=fmtp:101 0-11 (15) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (14 headers 19 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = Found Their Call ID: d7082d6d20b65881@172.16.1.202 Their Tag 31b30fa3ead5bcd0 Our tag: as487203cf [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Sending to 172.16.1.202 : 5062 (no NAT) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Using INVITE request as basis request - d7082d6d20b65881@172.16.1.202 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on RTP to Off [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Setting NAT on VRTP to Off [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found user 'sipsma' [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 8 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 0 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 4 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 18 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 2 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 97 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 9 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 3 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found RTP audio format 101 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Peer audio RTP is at port 172.16.1.202:5004 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format PCMA for ID 8 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format PCMU for ID 0 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format G723 for ID 4 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format G729 for ID 18 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format G726-32 for ID 2 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format iLBC for ID 97 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Got unsupported a:fmtp in SDP offer [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format G722 for ID 9 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format GSM for ID 3 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Found audio description format telephone-event for ID 101 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Got unsupported a:fmtp in SDP offer [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: T38 state changed to 0 on channel [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Capabilities: us - 0x8 (alaw), peer - audio=0x1d0f (g723|gsm|ulaw|alaw|g726|g729|ilbc|g722)/video=0x0 (nothing), combined - 0x8 (alaw) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Peer audio RTP is at port 172.16.1.202:5004 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: We're settling with these formats: 0x8 (alaw) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Checking SIP call limits for device sipsma [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Updating call counter for incoming call [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Call from peer 'sipsma' is 1 out of 10 [2008-01-23 14:37:16] DEBUG[25132] devicestate.c: Notification of state change to be queued on device/channel SIP/sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Looking for 99 in phones (domain sip.serviceplanet.nl) [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: Changing state for SIP/sipsma - state 2 (In use) [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: *** Our native formats are 0x8 (alaw) [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: *** Joint capabilities are 0x8 (alaw) [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: *** Our capabilities are 0x8 (alaw) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [2008-01-23 14:37:16] VERBOSE[25114] logger.c: Reliably Transmitting (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: This channel will not be able to handle video. [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #436 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: build_route: Contact hop: [2008-01-23 14:37:16] DEBUG[25128] app_queue.c: Device 'SIP/sipsma' changed to state '2' (In use) but we don't care because they're not a member of any queue. [2008-01-23 14:37:16] VERBOSE[25132] logger.c: list_route: hop: [2008-01-23 14:37:16] VERBOSE[25114] logger.c: Extension Changed 201 new state InUse for Notify User siepkes [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: SIP/sipsma-0823b200: New call is still down.... Trying... [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- Transmitting (no NAT) to 172.16.1.202:5062 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932;received=172.16.1.202 From: ;tag=31b30fa3ead5bcd0 To: Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32074 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [2008-01-23 14:37:16] DEBUG[25132] devicestate.c: Notification of state change to be queued on device/channel SIP/sipsma-0823b200 [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: Changing state for SIP/sipsma - state 2 (In use) [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25172] pbx.c: Launching 'Dial' [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] VERBOSE[25172] logger.c: -- Executing [99@phones:1] Dial("SIP/sipsma-0823b200", "mISDN/g:kpn_isdn2/99") in new stack [2008-01-23 14:37:16] DEBUG[25128] app_queue.c: Device 'SIP/sipsma' changed to state '2' (In use) but we don't care because they're not a member of any queue. [2008-01-23 14:37:16] WARNING[25172] chan_misdn.c: Could not Dial out on group 'kpn_isdn2'. Either the L2 and L1 on all of these ports where DOWN (see 'show application misdn_check_l2l1') Or there was no free channel on none of the ports [2008-01-23 14:37:16] WARNING[25172] app_dial.c: Unable to create channel of type 'mISDN' (cause 0 - Unknown) [2008-01-23 14:37:16] VERBOSE[25172] logger.c: == Everyone is busy/congested at this time (1:0/0/1) [2008-01-23 14:37:16] DEBUG[25172] rtp.c: Channel '' has no RTP, not doing anything [2008-01-23 14:37:16] DEBUG[25172] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL. [2008-01-23 14:37:16] VERBOSE[25172] logger.c: == Auto fallthrough, channel 'SIP/sipsma-0823b200' status is 'CHANUNAVAIL' [2008-01-23 14:37:16] VERBOSE[25172] logger.c: <--- Transmitting (no NAT) to 172.16.1.202:5062 ---> SIP/2.0 503 Service Unavailable Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932;received=172.16.1.202 From: ;tag=31b30fa3ead5bcd0 To: ;tag=as04e232a2 Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32074 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [2008-01-23 14:37:16] DEBUG[25172] chan_sip.c: Setting SIP_ALREADYGONE on dialog d7082d6d20b65881@172.16.1.202 [2008-01-23 14:37:16] DEBUG[25172] channel.c: Soft-Hanging up channel 'SIP/sipsma-0823b200' [2008-01-23 14:37:16] DEBUG[25172] devicestate.c: Notification of state change to be queued on device/channel SIP/sipsma-0823b200 [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25172] channel.c: Soft-Hanging up channel 'SIP/sipsma-0823b200' [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: Changing state for SIP/sipsma - state 2 (In use) [2008-01-23 14:37:16] DEBUG[25172] channel.c: Hanging up channel 'SIP/sipsma-0823b200' [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25172] chan_sip.c: Hangup call SIP/sipsma-0823b200, SIP callid d7082d6d20b65881@172.16.1.202) [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25128] app_queue.c: Device 'SIP/sipsma' changed to state '2' (In use) but we don't care because they're not a member of any queue. [2008-01-23 14:37:16] DEBUG[25172] chan_sip.c: update_call_counter(sipsma) - decrement call limit counter on hangup [2008-01-23 14:37:16] DEBUG[25172] chan_sip.c: Updating call counter for incoming call [2008-01-23 14:37:16] DEBUG[25172] chan_sip.c: Call from peer 'sipsma' removed from call limit 10 [2008-01-23 14:37:16] DEBUG[25172] devicestate.c: Notification of state change to be queued on device/channel SIP/sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25172] devicestate.c: Notification of state change to be queued on device/channel SIP/sipsma-0823b200 [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: Changing state for SIP/sipsma - state 1 (Not in use) [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] VERBOSE[25114] logger.c: Reliably Transmitting (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK4b3c2ad6;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 128 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 212 terminated --- [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #437 [2008-01-23 14:37:16] VERBOSE[25114] logger.c: Extension Changed 201 new state Idle for Notify User siepkes [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: Changing state for SIP/sipsma - state 1 (Not in use) [2008-01-23 14:37:16] DEBUG[25114] devicestate.c: No provider found, checking channel drivers for SIP - sipsma [2008-01-23 14:37:16] DEBUG[25114] chan_sip.c: Checking device state for peer sipsma [2008-01-23 14:37:16] DEBUG[25128] app_queue.c: Device 'SIP/sipsma' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2008-01-23 14:37:16] DEBUG[25128] app_queue.c: Device 'SIP/sipsma' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5062 ---> ACK sip:99@sip.serviceplanet.nl SIP/2.0 Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932 From: ;tag=31b30fa3ead5bcd0 To: ;tag=as04e232a2 Contact: Supported: path Proxy-Authorization: Digest username="sipsma", realm="asterisk", algorithm=MD5, uri="sip:99@sip.serviceplanet.nl", nonce="32aa6844", response="2ed222ea80774afd99b00494e84727b9" Call-ID: d7082d6d20b65881@172.16.1.202 CSeq: 32074 ACK User-Agent: Grandstream GXP2020 1.1.5.15 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Length: 0 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: ACK sip:99@sip.serviceplanet.nl SIP/2.0 (39) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.202:5062;branch=z9hG4bK167be5906d466932 (65) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=31b30fa3ead5bcd0 (60) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=as04e232a2 (48) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Contact: (53) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: Supported: path (15) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: Proxy-Authorization: Digest username="sipsma", realm="asterisk", algorithm=MD5, uri="sip:99@sip.serviceplanet.nl", nonce="32aa6844", response="2ed222ea80774afd99b00494e84727b9" (176) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: Call-ID: d7082d6d20b65881@172.16.1.202 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: CSeq: 32074 ACK (15) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Max-Forwards: 70 (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 13: (0) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (13 headers 0 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = Found Their Call ID: d7082d6d20b65881@172.16.1.202 Their Tag 31b30fa3ead5bcd0 Our tag: as04e232a2 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Stopping retransmission on 'd7082d6d20b65881@172.16.1.202' of Response 32074: Match Found [2008-01-23 14:37:16] VERBOSE[25132] logger.c: Really destroying SIP dialog 'd7082d6d20b65881@172.16.1.202' Method: ACK [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:16] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK4b3c2ad6;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 128 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK4b3c2ad6;rport (62) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 5: CSeq: 128 NOTIFY (16) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:16] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #437 [2008-01-23 14:37:16] DEBUG[25132] chan_sip.c: Stopping retransmission on '335dc55de78f32aa@172.16.1.201' of Request 128: Match Not Found [2008-01-23 14:37:16] VERBOSE[25132] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (1) NOTIFY - 4 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 200 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: Retransmitting #1 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:17] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (2) NOTIFY - 4 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 400 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: Retransmitting #2 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:17] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (3) NOTIFY - 4 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 800 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: Retransmitting #3 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:17] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:17] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:17] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (4) NOTIFY - 4 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 1600 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:18] VERBOSE[25132] logger.c: Retransmitting #4 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:18] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:18] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:18] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (5) NOTIFY - 4 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 3200 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:20] VERBOSE[25132] logger.c: Retransmitting #5 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:20] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:20] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:20] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: SIP TIMER: Rescheduling retransmission #436 (6) NOTIFY - 4 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 100 ms (Retrans id #436)) [2008-01-23 14:37:23] VERBOSE[25132] logger.c: Retransmitting #6 (no NAT) to 172.16.1.201:5060: NOTIFY sip:siepkes@172.16.1.201:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Contact: Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 211 confirmed --- [2008-01-23 14:37:23] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport From: ;tag=as7f3278e3 To: ;tag=bdfdcdca87653950 Call-ID: 335dc55de78f32aa@172.16.1.201 CSeq: 127 NOTIFY User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK75ea6d9a;rport (62) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 2: From: ;tag=as7f3278e3 (51) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=bdfdcdca87653950 (59) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 335dc55de78f32aa@172.16.1.201 (38) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 5: CSeq: 127 NOTIFY (16) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:23] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: 16e312d37f4b2570@172.16.1.202 Their Tag 20db66c2c31e29f2 Our tag: as65f0d193 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: 80d688b189b14e64@172.16.1.202 Their Tag cd4b15709f261022 Our tag: as07f505e7 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: ebf7813a3835572b@172.16.1.202 Their Tag 34922d60c530551b Our tag: as717659c7 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: 485bff56bbab48eb@172.16.1.202 Their Tag 193b8b3b2f6dfb88 Our tag: as56a4087f [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: 90795a5938f02b16@172.16.1.202 Their Tag a1aa088587858838 Our tag: as4421d5c5 [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: 03b5cef06af5180c@172.16.1.202 Their Tag 303c209f33b4bd6a Our tag: as2455227b [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = No match Their Call ID: f6c3b089ea796064@172.16.1.202 Their Tag 2a694f1c79eecbee Our tag: as79c1684a [2008-01-23 14:37:23] DEBUG[25132] chan_sip.c: = Found Their Call ID: 335dc55de78f32aa@172.16.1.201 Their Tag bdfdcdca87653950 Our tag: as7f3278e3 [2008-01-23 14:37:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:27] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog '03b5cef06af5180c@172.16.1.202' [2008-01-23 14:37:27] DEBUG[25132] chan_sip.c: Destroying SIP dialog 03b5cef06af5180c@172.16.1.202 [2008-01-23 14:37:27] VERBOSE[25132] logger.c: Really destroying SIP dialog '03b5cef06af5180c@172.16.1.202' Method: REGISTER [2008-01-23 14:37:27] WARNING[25132] chan_sip.c: Maximum retries exceeded on transmission 335dc55de78f32aa@172.16.1.201 for seqno 127 (Non-critical Request) [2008-01-23 14:37:29] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog '90795a5938f02b16@172.16.1.202' [2008-01-23 14:37:29] DEBUG[25132] chan_sip.c: Destroying SIP dialog 90795a5938f02b16@172.16.1.202 [2008-01-23 14:37:29] VERBOSE[25132] logger.c: Really destroying SIP dialog '90795a5938f02b16@172.16.1.202' Method: REGISTER [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog '485bff56bbab48eb@172.16.1.202' [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Destroying SIP dialog 485bff56bbab48eb@172.16.1.202 [2008-01-23 14:37:31] VERBOSE[25132] logger.c: Really destroying SIP dialog '485bff56bbab48eb@172.16.1.202' Method: REGISTER [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 0: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 (59) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK371a1934;rport (62) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as6f14f8b5 (58) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 3: To: (49) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 4: Contact: (35) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 5: Call-ID: 4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25 (53) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 9: Date: Wed, 23 Jan 2008 13:37:31 GMT (35) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 11: Supported: replaces (19) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:37:31] VERBOSE[25132] logger.c: Reliably Transmitting (no NAT) to 172.16.1.201:5070: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK371a1934;rport From: "asterisk" ;tag=as6f14f8b5 To: Contact: Call-ID: 4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 23 Jan 2008 13:37:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #438 [2008-01-23 14:37:31] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5070 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK371a1934;rport From: "asterisk" ;tag=as6f14f8b5 To: ;tag=de5183a1a2d8f3c3 Call-ID: 4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK371a1934;rport (62) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as6f14f8b5 (58) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=de5183a1a2d8f3c3 (70) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25 (53) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:37:31] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: = Found Their Call ID: 4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25 Their Tag Our tag: as6f14f8b5 [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #438 [2008-01-23 14:37:31] DEBUG[25132] chan_sip.c: Stopping retransmission on '4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25' of Request 102: Match Not Found [2008-01-23 14:37:31] VERBOSE[25132] logger.c: Really destroying SIP dialog '4aab5ba94bc6f37033c3f5d3782e150f@172.16.1.25' Method: OPTIONS [2008-01-23 14:37:33] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog 'ebf7813a3835572b@172.16.1.202' [2008-01-23 14:37:33] DEBUG[25132] chan_sip.c: Destroying SIP dialog ebf7813a3835572b@172.16.1.202 [2008-01-23 14:37:33] VERBOSE[25132] logger.c: Really destroying SIP dialog 'ebf7813a3835572b@172.16.1.202' Method: REGISTER [2008-01-23 14:37:35] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog '80d688b189b14e64@172.16.1.202' [2008-01-23 14:37:35] DEBUG[25132] chan_sip.c: Destroying SIP dialog 80d688b189b14e64@172.16.1.202 [2008-01-23 14:37:35] VERBOSE[25132] logger.c: Really destroying SIP dialog '80d688b189b14e64@172.16.1.202' Method: REGISTER [2008-01-23 14:37:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:37] DEBUG[25132] chan_sip.c: Auto destroying SIP dialog '16e312d37f4b2570@172.16.1.202' [2008-01-23 14:37:37] DEBUG[25132] chan_sip.c: Destroying SIP dialog 16e312d37f4b2570@172.16.1.202 [2008-01-23 14:37:37] VERBOSE[25132] logger.c: Really destroying SIP dialog '16e312d37f4b2570@172.16.1.202' Method: REGISTER [2008-01-23 14:37:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:37:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 0: OPTIONS sip:sipsma@172.16.1.202:5070;transport=udp SIP/2.0 (58) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK040d98de;rport (62) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as7e3bd727 (58) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 3: To: (48) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 4: Contact: (35) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 5: Call-ID: 3541489d4cf097b0390b0f582d16121e@172.16.1.25 (53) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 9: Date: Wed, 23 Jan 2008 13:38:05 GMT (35) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 11: Supported: replaces (19) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:38:05] VERBOSE[25132] logger.c: Reliably Transmitting (no NAT) to 172.16.1.202:5070: OPTIONS sip:sipsma@172.16.1.202:5070;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK040d98de;rport From: "asterisk" ;tag=as7e3bd727 To: Contact: Call-ID: 3541489d4cf097b0390b0f582d16121e@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 23 Jan 2008 13:38:05 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #441 [2008-01-23 14:38:05] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5070 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK040d98de;rport From: "asterisk" ;tag=as7e3bd727 To: ;tag=9f02c412ca441e5f Call-ID: 3541489d4cf097b0390b0f582d16121e@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK040d98de;rport (62) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as7e3bd727 (58) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=9f02c412ca441e5f (69) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 3541489d4cf097b0390b0f582d16121e@172.16.1.25 (53) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 7: Contact: (53) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:38:05] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: = Found Their Call ID: 3541489d4cf097b0390b0f582d16121e@172.16.1.25 Their Tag Our tag: as7e3bd727 [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #441 [2008-01-23 14:38:05] DEBUG[25132] chan_sip.c: Stopping retransmission on '3541489d4cf097b0390b0f582d16121e@172.16.1.25' of Request 102: Match Not Found [2008-01-23 14:38:05] VERBOSE[25132] logger.c: Really destroying SIP dialog '3541489d4cf097b0390b0f582d16121e@172.16.1.25' Method: OPTIONS [2008-01-23 14:38:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 0: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 (59) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK25ff5391;rport (62) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as2b06ad46 (58) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 3: To: (49) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 4: Contact: (35) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 5: Call-ID: 45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25 (53) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 9: Date: Wed, 23 Jan 2008 13:38:31 GMT (35) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 11: Supported: replaces (19) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:38:31] VERBOSE[25132] logger.c: Reliably Transmitting (no NAT) to 172.16.1.201:5070: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK25ff5391;rport From: "asterisk" ;tag=as2b06ad46 To: Contact: Call-ID: 45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 23 Jan 2008 13:38:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #444 [2008-01-23 14:38:31] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5070 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK25ff5391;rport From: "asterisk" ;tag=as2b06ad46 To: ;tag=4f53d3889b3e592a Call-ID: 45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK25ff5391;rport (62) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as2b06ad46 (58) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=4f53d3889b3e592a (70) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25 (53) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:38:31] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: = Found Their Call ID: 45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25 Their Tag Our tag: as2b06ad46 [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #444 [2008-01-23 14:38:31] DEBUG[25132] chan_sip.c: Stopping retransmission on '45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25' of Request 102: Match Not Found [2008-01-23 14:38:31] VERBOSE[25132] logger.c: Really destroying SIP dialog '45019a074b2ccb7e6313a4cd0dfe1fd5@172.16.1.25' Method: OPTIONS [2008-01-23 14:38:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:46] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:38:56] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 0: OPTIONS sip:sipsma@172.16.1.202:5070;transport=udp SIP/2.0 (58) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK62dc05b3;rport (62) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as7c63bcd8 (58) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 3: To: (48) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 4: Contact: (35) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 5: Call-ID: 29d0794a700e5c030543edd00ea828eb@172.16.1.25 (53) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 9: Date: Wed, 23 Jan 2008 13:39:05 GMT (35) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 11: Supported: replaces (19) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:39:05] VERBOSE[25132] logger.c: Reliably Transmitting (no NAT) to 172.16.1.202:5070: OPTIONS sip:sipsma@172.16.1.202:5070;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK62dc05b3;rport From: "asterisk" ;tag=as7c63bcd8 To: Contact: Call-ID: 29d0794a700e5c030543edd00ea828eb@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 23 Jan 2008 13:39:05 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #447 [2008-01-23 14:39:05] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.202:5070 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK62dc05b3;rport From: "asterisk" ;tag=as7c63bcd8 To: ;tag=fe3f638f82c7d4a2 Call-ID: 29d0794a700e5c030543edd00ea828eb@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK62dc05b3;rport (62) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as7c63bcd8 (58) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=fe3f638f82c7d4a2 (69) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 29d0794a700e5c030543edd00ea828eb@172.16.1.25 (53) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 7: Contact: (53) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:39:05] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: = Found Their Call ID: 29d0794a700e5c030543edd00ea828eb@172.16.1.25 Their Tag Our tag: as7c63bcd8 [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #447 [2008-01-23 14:39:05] DEBUG[25132] chan_sip.c: Stopping retransmission on '29d0794a700e5c030543edd00ea828eb@172.16.1.25' of Request 102: Match Not Found [2008-01-23 14:39:05] VERBOSE[25132] logger.c: Really destroying SIP dialog '29d0794a700e5c030543edd00ea828eb@172.16.1.25' Method: OPTIONS [2008-01-23 14:39:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:06] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:16] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:26] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 0: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 (59) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK5d58ed1c;rport (62) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as13417625 (58) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 3: To: (49) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 4: Contact: (35) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 5: Call-ID: 1c3809631d83aba62b45dc111ed7eb64@172.16.1.25 (53) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 9: Date: Wed, 23 Jan 2008 13:39:31 GMT (35) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 11: Supported: replaces (19) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 12: Content-Length: 0 (17) [2008-01-23 14:39:31] VERBOSE[25132] logger.c: Reliably Transmitting (no NAT) to 172.16.1.201:5070: OPTIONS sip:siepkes@172.16.1.201:5070;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK5d58ed1c;rport From: "asterisk" ;tag=as13417625 To: Contact: Call-ID: 1c3809631d83aba62b45dc111ed7eb64@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 23 Jan 2008 13:39:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #450 [2008-01-23 14:39:31] VERBOSE[25132] logger.c: <--- SIP read from 172.16.1.201:5070 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK5d58ed1c;rport From: "asterisk" ;tag=as13417625 To: ;tag=cc14c51be566095b Call-ID: 1c3809631d83aba62b45dc111ed7eb64@172.16.1.25 CSeq: 102 OPTIONS User-Agent: Grandstream GXP2020 1.1.5.15 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Supported: replaces, timer Content-Length: 0 <-------------> [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 1: Via: SIP/2.0/UDP 172.16.1.25:5060;branch=z9hG4bK5d58ed1c;rport (62) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 2: From: "asterisk" ;tag=as13417625 (58) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 3: To: ;tag=cc14c51be566095b (70) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 4: Call-ID: 1c3809631d83aba62b45dc111ed7eb64@172.16.1.25 (53) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 6: User-Agent: Grandstream GXP2020 1.1.5.15 (40) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 7: Contact: (54) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE (85) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 9: Supported: replaces, timer (26) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 10: Content-Length: 0 (17) [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Header 11: (0) [2008-01-23 14:39:31] VERBOSE[25132] logger.c: --- (11 headers 0 lines) --- [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: = Found Their Call ID: 1c3809631d83aba62b45dc111ed7eb64@172.16.1.25 Their Tag Our tag: as13417625 [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #450 [2008-01-23 14:39:31] DEBUG[25132] chan_sip.c: Stopping retransmission on '1c3809631d83aba62b45dc111ed7eb64@172.16.1.25' of Request 102: Match Not Found [2008-01-23 14:39:31] VERBOSE[25132] logger.c: Really destroying SIP dialog '1c3809631d83aba62b45dc111ed7eb64@172.16.1.25' Method: OPTIONS [2008-01-23 14:39:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:36] DEBUG[25150] manager.c: Manager received command 'Command' [2008-01-23 14:39:36] DEBUG[25150] manager.c: Manager received command 'Command'