[Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 0: OPTIONS sip:28@192.168.100.23:5061 SIP/2.0 (42) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK7a0956b2;rport (65) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as1a50d3ec (59) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 3: To: (32) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 4: Contact: (37) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 5: Call-ID: 61fde7713932f24634e96e6f2b09e3a0@192.168.100.15 (56) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 9: Date: Sat, 02 Jun 2007 10:33:25 GMT (35) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 11: Supported: replaces (19) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 12: Content-Length: 0 (17) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #124 [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 1: To: ;tag=531291cca968f101i1 (55) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as1a50d3ec (59) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 3: Call-ID: 61fde7713932f24634e96e6f2b09e3a0@192.168.100.15 (56) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK7a0956b2 (59) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 6: Server: Linksys/SPA942-5.1.5 (28) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 7: Content-Length: 0 (17) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 9: Allow-Events: dialog,conference (31) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 10: Supported: 100rel, replaces (27) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Header 11: (0) [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #124 [Jun 2 06:33:25] DEBUG[29179] chan_sip.c: Stopping retransmission on '61fde7713932f24634e96e6f2b09e3a0@192.168.100.15' of Request 102: Match Not Found [Jun 2 06:33:25] VERBOSE[29179] logger.c: Really destroying SIP dialog '61fde7713932f24634e96e6f2b09e3a0@192.168.100.15' Method: OPTIONS [Jun 2 06:33:49] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 2 06:33:49] DEBUG[29179] chan_sip.c: Stopping retransmission on '6f3336263eb46325255bc04d5ee6c7d7@192.168.100.15' of Request 102: Match Not Found [Jun 2 06:33:49] VERBOSE[29179] logger.c: Really destroying SIP dialog '6f3336263eb46325255bc04d5ee6c7d7@192.168.100.15' Method: OPTIONS [Jun 2 06:34:00] DEBUG[29206] manager.c: Manager received command 'Command' [Jun 2 06:34:00] DEBUG[29206] manager.c: Manager received command 'Command' [Jun 2 06:34:00] DEBUG[29206] manager.c: Manager received command 'Command' [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 0: REGISTER sip:192.168.100.15 SIP/2.0 (35) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.23:5060;branch=z9hG4bK-c97445cf (60) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 2: From: "Spare1" ;tag=ee2f3e0687f4a20co0 (61) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 3: To: "Spare1" (36) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 4: Call-ID: 711d9f01-a417b909@192.168.100.23 (41) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 5: CSeq: 9890 REGISTER (19) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 7: Contact: "Spare1" ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" (114) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 8: User-Agent: Linksys/SPA942-5.1.5 (32) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 9: P-Station-Name: Spare1 ;mac=000e08dceb41 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 10: Content-Length: 0 (17) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 11: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 12: Allow-Events: dialog,conference (31) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 13: Supported: 100rel, replaces (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 14: (0) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for 711d9f01-a417b909@192.168.100.23 - REGISTER (No RTP) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- SIP read from 192.168.100.23:5061 ---> REGISTER sip:192.168.100.15 SIP/2.0 Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-705594d8 From: ;tag=39734e367f2c4f9co1 To: Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58826 REGISTER Max-Forwards: 70 Contact: ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" User-Agent: Linksys/SPA942-5.1.5 P-Station-Name: Spare1 ;mac=000e08dceb41 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE Allow-Events: dialog,conference Supported: 100rel, replaces <-------------> [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 0: REGISTER sip:192.168.100.15 SIP/2.0 (35) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-705594d8 (60) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 2: From: ;tag=39734e367f2c4f9co1 (52) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 3: To: (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 4: Call-ID: 880d5e9-419faf41@192.168.100.23 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 5: CSeq: 58826 REGISTER (20) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 7: Contact: ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" (105) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 8: User-Agent: Linksys/SPA942-5.1.5 (32) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 9: P-Station-Name: Spare1 ;mac=000e08dceb41 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 10: Content-Length: 0 (17) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 11: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 12: Allow-Events: dialog,conference (31) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 13: Supported: 100rel, replaces (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 14: (0) [Jun 2 06:34:12] VERBOSE[29179] logger.c: --- (14 headers 0 lines) --- [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for 880d5e9-419faf41@192.168.100.23 - REGISTER (No RTP) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 2 06:34:12] VERBOSE[29179] logger.c: Using latest REGISTER request as basis request [Jun 2 06:34:12] VERBOSE[29179] logger.c: Sending to 192.168.100.23 : 5061 (no NAT) [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- Transmitting (NAT) to 192.168.100.23:5061 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-705594d8;received=192.168.100.23 From: ;tag=39734e367f2c4f9co1 To: Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58826 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- Transmitting (NAT) to 192.168.100.23:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-705594d8;received=192.168.100.23 From: ;tag=39734e367f2c4f9co1 To: ;tag=as432eb17b Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58826 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="27ca6a9e" Content-Length: 0 <------------> [Jun 2 06:34:12] VERBOSE[29179] logger.c: Scheduling destruction of SIP dialog '880d5e9-419faf41@192.168.100.23' in 32000 ms (Method: REGISTER) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 0: REGISTER sip:192.168.100.15 SIP/2.0 (35) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.23:5060;branch=z9hG4bK-3335e6a8 (60) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 2: From: "Spare1" ;tag=ee2f3e0687f4a20co0 (61) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 3: To: "Spare1" (36) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 4: Call-ID: 711d9f01-a417b909@192.168.100.23 (41) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 5: CSeq: 9891 REGISTER (19) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 7: Authorization: Digest username="18",realm="asterisk",nonce="0fd39018",uri="sip:192.168.100.15",algorithm=MD5,response="c52abb3f76cfbd6bd9882b859939c545" (152) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 8: Contact: "Spare1" ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" (114) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 9: User-Agent: Linksys/SPA942-5.1.5 (32) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 10: P-Station-Name: Spare1 ;mac=000e08dceb41 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 11: Content-Length: 0 (17) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 13: Allow-Events: dialog,conference (31) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 14: Supported: 100rel, replaces (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 15: (0) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 2 06:34:12] DEBUG[29179] devicestate.c: Notification of state change to be queued on device/channel SIP/18 [Jun 2 06:34:12] DEBUG[29175] devicestate.c: No provider found, checking channel drivers for SIP - 18 [Jun 2 06:34:12] DEBUG[29175] chan_sip.c: Checking device state for peer 18 [Jun 2 06:34:12] DEBUG[29175] devicestate.c: Changing state for SIP/18 - state 1 (Not in use) [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- SIP read from 192.168.100.23:5061 ---> REGISTER sip:192.168.100.15 SIP/2.0 Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-84f441d6 From: ;tag=39734e367f2c4f9co1 To: Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58827 REGISTER Max-Forwards: 70 Authorization: Digest username="28",realm="asterisk",nonce="27ca6a9e",uri="sip:192.168.100.15",algorithm=MD5,response="826f00a6ed51a9191085843293fa5b28" Contact: ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" User-Agent: Linksys/SPA942-5.1.5 P-Station-Name: Spare1 ;mac=000e08dceb41 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE Allow-Events: dialog,conference Supported: 100rel, replaces <-------------> [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 0: REGISTER sip:192.168.100.15 SIP/2.0 (35) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-84f441d6 (60) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 2: From: ;tag=39734e367f2c4f9co1 (52) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 3: To: (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 4: Call-ID: 880d5e9-419faf41@192.168.100.23 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 5: CSeq: 58827 REGISTER (20) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 7: Authorization: Digest username="28",realm="asterisk",nonce="27ca6a9e",uri="sip:192.168.100.15",algorithm=MD5,response="826f00a6ed51a9191085843293fa5b28" (152) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 8: Contact: ;expires=3600;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" (105) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 9: User-Agent: Linksys/SPA942-5.1.5 (32) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 10: P-Station-Name: Spare1 ;mac=000e08dceb41 (40) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 11: Content-Length: 0 (17) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 13: Allow-Events: dialog,conference (31) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 14: Supported: 100rel, replaces (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 15: (0) [Jun 2 06:34:12] VERBOSE[29179] logger.c: --- (15 headers 0 lines) --- [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 2 06:34:12] VERBOSE[29179] logger.c: Using latest REGISTER request as basis request [Jun 2 06:34:12] VERBOSE[29179] logger.c: Sending to 192.168.100.23 : 5061 (NAT) [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- Transmitting (NAT) to 192.168.100.23:5061 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-84f441d6;received=192.168.100.23 From: ;tag=39734e367f2c4f9co1 To: Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58827 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Jun 2 06:34:12] DEBUG[29175] devicestate.c: No provider found, checking channel drivers for SIP - 18 [Jun 2 06:34:12] DEBUG[29175] chan_sip.c: Checking device state for peer 18 [Jun 2 06:34:12] DEBUG[29442] app_queue.c: Device 'SIP/18' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- Transmitting (NAT) to 192.168.100.23:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-84f441d6;received=192.168.100.23 From: ;tag=39734e367f2c4f9co1 To: ;tag=as432eb17b Call-ID: 880d5e9-419faf41@192.168.100.23 CSeq: 58827 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 3600 Contact: ;expires=3600 Date: Sat, 02 Jun 2007 10:34:12 GMT Content-Length: 0 <------------> [Jun 2 06:34:12] DEBUG[29179] devicestate.c: Notification of state change to be queued on device/channel SIP/28 [Jun 2 06:34:12] VERBOSE[29179] logger.c: Scheduling destruction of SIP dialog '880d5e9-419faf41@192.168.100.23' in 32000 ms (Method: REGISTER) [Jun 2 06:34:12] DEBUG[29175] devicestate.c: No provider found, checking channel drivers for SIP - 28 [Jun 2 06:34:12] DEBUG[29175] chan_sip.c: Checking device state for peer 28 [Jun 2 06:34:12] DEBUG[29175] devicestate.c: Changing state for SIP/28 - state 1 (Not in use) [Jun 2 06:34:12] DEBUG[29175] devicestate.c: No provider found, checking channel drivers for SIP - 28 [Jun 2 06:34:12] DEBUG[29175] chan_sip.c: Checking device state for peer 28 [Jun 2 06:34:12] DEBUG[29443] app_queue.c: Device 'SIP/28' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- SIP read from 192.168.100.23:5061 ---> NOTIFY sip:sa@192.168.100.15 SIP/2.0 Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-79449736 From: ;tag=39734e367f2c4f9co1 To: Call-ID: 7f2e31c6-9cfc4152@192.168.100.23 CSeq: 61570 NOTIFY Max-Forwards: 70 Contact: ;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" Event: dialog User-Agent: Linksys/SPA942-5.1.5 Content-Length: 151 Content-Type: application/dialog-info+xml <-------------> [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 0: NOTIFY sip:sa@192.168.100.15 SIP/2.0 (36) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-79449736 (60) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 2: From: ;tag=39734e367f2c4f9co1 (52) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 3: To: (27) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 4: Call-ID: 7f2e31c6-9cfc4152@192.168.100.23 (41) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 5: CSeq: 61570 NOTIFY (18) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 7: Contact: ;+sip.instance="<00000000-0000-0000-0000-000E08DCEB41>" (92) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 8: Event: dialog (13) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 9: User-Agent: Linksys/SPA942-5.1.5 (32) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 10: Content-Length: 151 (19) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 11: Content-Type: application/dialog-info+xml (41) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Header 12: (0) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Line: (21) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Line: (112) [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Line: (14) [Jun 2 06:34:12] VERBOSE[29179] logger.c: --- (12 headers 3 lines) --- [Jun 2 06:34:12] VERBOSE[29179] logger.c: <--- Transmitting (no NAT) to 192.168.100.23:5061 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 192.168.100.23:5061;branch=z9hG4bK-79449736;received=192.168.100.23 From: ;tag=39734e367f2c4f9co1 To: ;tag=as3c0828f9 Call-ID: 7f2e31c6-9cfc4152@192.168.100.23 CSeq: 61570 NOTIFY User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jun 2 06:34:12] DEBUG[29179] chan_sip.c: Invalid SIP message - rejected , no callid, len 624 [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 0: OPTIONS sip:18@192.168.100.23:5060 SIP/2.0 (42) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK306da352;rport (65) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as71a5d145 (59) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 3: To: (32) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 4: Contact: (37) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 5: Call-ID: 5777488c7718b3fd7b15f5e25cffd7dd@192.168.100.15 (56) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 9: Date: Sat, 02 Jun 2007 10:34:15 GMT (35) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 11: Supported: replaces (19) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 12: Content-Length: 0 (17) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #136 [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 1: To: ;tag=97e2e10e90fa1aa4i0 (55) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as71a5d145 (59) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 3: Call-ID: 5777488c7718b3fd7b15f5e25cffd7dd@192.168.100.15 (56) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK306da352 (59) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 6: Server: Linksys/SPA942-5.1.5 (28) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 7: Content-Length: 0 (17) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 9: Allow-Events: dialog,conference (31) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 10: Supported: 100rel, replaces (27) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Header 11: (0) [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #136 [Jun 2 06:34:15] DEBUG[29179] chan_sip.c: Stopping retransmission on '5777488c7718b3fd7b15f5e25cffd7dd@192.168.100.15' of Request 102: Match Not Found [Jun 2 06:34:15] VERBOSE[29179] logger.c: Really destroying SIP dialog '5777488c7718b3fd7b15f5e25cffd7dd@192.168.100.15' Method: OPTIONS [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 0: OPTIONS sip:28@192.168.100.23:5061 SIP/2.0 (42) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK095dec69;rport (65) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as60e40e5d (59) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 3: To: (32) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 4: Contact: (37) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 5: Call-ID: 361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15 (56) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 9: Date: Sat, 02 Jun 2007 10:34:25 GMT (35) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 11: Supported: replaces (19) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 12: Content-Length: 0 (17) [Jun 2 06:34:25] VERBOSE[29179] logger.c: Reliably Transmitting (NAT) to 192.168.100.23:5061: OPTIONS sip:28@192.168.100.23:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK095dec69;rport From: "Unknown" ;tag=as60e40e5d To: Contact: Call-ID: 361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sat, 02 Jun 2007 10:34:25 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #139 [Jun 2 06:34:25] VERBOSE[29179] logger.c: <--- SIP read from 192.168.100.23:5061 ---> SIP/2.0 200 OK To: ;tag=ce541dbea2d285b4i1 From: "Unknown" ;tag=as60e40e5d Call-ID: 361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK095dec69 Server: Linksys/SPA942-5.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE Allow-Events: dialog,conference Supported: 100rel, replaces <-------------> [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 1: To: ;tag=ce541dbea2d285b4i1 (55) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as60e40e5d (59) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 3: Call-ID: 361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15 (56) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK095dec69 (59) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 6: Server: Linksys/SPA942-5.1.5 (28) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 7: Content-Length: 0 (17) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 9: Allow-Events: dialog,conference (31) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 10: Supported: 100rel, replaces (27) [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Header 11: (0) [Jun 2 06:34:25] VERBOSE[29179] logger.c: --- (11 headers 0 lines) --- [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #139 [Jun 2 06:34:25] DEBUG[29179] chan_sip.c: Stopping retransmission on '361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15' of Request 102: Match Not Found [Jun 2 06:34:25] VERBOSE[29179] logger.c: Really destroying SIP dialog '361fedf929e94ff6058bd5dd7c8042d4@192.168.100.15' Method: OPTIONS [Jun 2 06:34:44] DEBUG[29179] chan_sip.c: Auto destroying SIP dialog '711d9f01-a417b909@192.168.100.23' [Jun 2 06:34:44] DEBUG[29179] chan_sip.c: Destroying SIP dialog 711d9f01-a417b909@192.168.100.23 [Jun 2 06:34:44] VERBOSE[29179] logger.c: Really destroying SIP dialog '711d9f01-a417b909@192.168.100.23' Method: REGISTER [Jun 2 06:34:44] DEBUG[29179] chan_sip.c: Auto destroying SIP dialog '880d5e9-419faf41@192.168.100.23' [Jun 2 06:34:44] DEBUG[29179] chan_sip.c: Destroying SIP dialog 880d5e9-419faf41@192.168.100.23 [Jun 2 06:34:44] VERBOSE[29179] logger.c: Really destroying SIP dialog '880d5e9-419faf41@192.168.100.23' Method: REGISTER [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 0: OPTIONS sip:27@192.168.100.24:5061 SIP/2.0 (42) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK21a7cadc;rport (65) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 2: From: "Unknown" ;tag=as7b82f946 (59) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 3: To: (32) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 4: Contact: (37) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 5: Call-ID: 7d71af6c23cb4ffd31c3cca6402afeb3@192.168.100.15 (56) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 9: Date: Sat, 02 Jun 2007 10:34:49 GMT (35) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 11: Supported: replaces (19) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 12: Content-Length: 0 (17) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #142 [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 1: t: ;tag=a34cd6037e7c7bci1 (53) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 2: f: "Unknown" ;tag=as7b82f946 (56) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 3: i: 7d71af6c23cb4ffd31c3cca6402afeb3@192.168.100.15 (50) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 5: v: SIP/2.0/UDP 192.168.100.15:5060;branch=z9hG4bK21a7cadc (57) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 6: Server: Linksys/SPA942-5.1.7 (28) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 7: l: 0 (4) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, SUBSCRIBE (79) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 9: Allow-Events: dialog,hold,talk,conference (41) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 10: k: 100rel, replaces (19) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Header 11: (0) [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #142 [Jun 2 06:34:49] DEBUG[29179] chan_sip.c: Stopping retransmission on '7d71af6c23cb4ffd31c3cca6402afeb3@192.168.100.15' of Request 102: Match Not Found [Jun 2 06:34:49] VERBOSE[29179] logger.c: Really destroying SIP dialog '7d71af6c23cb4ffd31c3cca6402afeb3@192.168.100.15' Method: OPTIONS