OPTIONS sip:6004@192.168.30.178;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK0fa17c05;rport From: "asterisk" ;tag=as2fce937b To: Contact: Call-ID: 2df833d27351e46e3cdae97b6501891d@192.168.30.254 CSeq: 102 OPTIONS User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Date: Tue, 04 Mar 2008 21:22:55 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 4 16:22:55] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 192.168.0.0 [Mar 4 16:22:55] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 10.0.0.0 [Mar 4 16:22:55] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 172.16.0.0 [Mar 4 16:22:55] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 169.254.0.0 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 0: OPTIONS sip:6003@192.168.30.199:1025;line=72k5dhxz SIP/2.0 (58) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK169dd207;rport (65) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 2: From: "asterisk" ;tag=as7be9ad14 (61) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 3: To: (48) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 4: Contact: (38) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 5: Call-ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 (56) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 7: User-Agent: Asterisk PBX (Fireworx) (35) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 9: Date: Tue, 04 Mar 2008 21:22:55 GMT (35) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 11: Supported: replaces (19) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 12: Content-Length: 0 (17) [Mar 4 16:22:55] VERBOSE[2698] logger.c: Reliably Transmitting (no NAT) to 192.168.30.199:1025: OPTIONS sip:6003@192.168.30.199:1025;line=72k5dhxz SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK169dd207;rport From: "asterisk" ;tag=as7be9ad14 To: Contact: Call-ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 CSeq: 102 OPTIONS User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Date: Tue, 04 Mar 2008 21:22:55 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:55] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.178:5060 ---> SIP/2.0 200 OK Call-ID: 2df833d27351e46e3cdae97b6501891d@192.168.30.254 CSeq: 102 OPTIONS From: "asterisk" ;tag=as2fce937b To: ;tag=2f3d45a3cbf477f Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK0fa17c05;rport Content-Length: 0 Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO Contact: Supported: replaces User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 1: Call-ID: 2df833d27351e46e3cdae97b6501891d@192.168.30.254 (56) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 2: CSeq: 102 OPTIONS (17) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 3: From: "asterisk" ;tag=as2fce937b (61) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 4: To: ;tag=2f3d45a3cbf477f (49) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK0fa17c05;rport (65) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 6: Content-Length: 0 (17) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 7: Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO (53) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 8: Contact: (48) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 9: Supported: replaces (19) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 10: User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 (71) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 11: (0) [Mar 4 16:22:55] VERBOSE[2698] logger.c: --- (11 headers 0 lines) --- [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: = No match Their Call ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 Their Tag Our tag: as7be9ad14 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: = Found Their Call ID: 2df833d27351e46e3cdae97b6501891d@192.168.30.254 Their Tag Our tag: as2fce937b [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #94 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Stopping retransmission on '2df833d27351e46e3cdae97b6501891d@192.168.30.254' of Request 102: Match Found [Mar 4 16:22:55] VERBOSE[2698] logger.c: Really destroying SIP dialog '2df833d27351e46e3cdae97b6501891d@192.168.30.254' Method: OPTIONS [Mar 4 16:22:55] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK169dd207;rport=5060 From: "asterisk" ;tag=as7be9ad14 To: Call-ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom370/7.1.30 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, callerid Content-Length: 0 <-------------> [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK169dd207;rport=5060 (70) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 2: From: "asterisk" ;tag=as7be9ad14 (61) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 3: To: (48) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 (56) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 6: Contact: ;flow-id=1 (63) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 7: User-Agent: snom370/7.1.30 (26) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 8: Accept-Language: en (19) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 9: Accept: application/sdp (23) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 11: Allow-Events: talk, hold, refer, call-info (42) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 12: Supported: timer, 100rel, replaces, callerid (44) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 13: Content-Length: 0 (17) [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Header 14: (0) [Mar 4 16:22:55] VERBOSE[2698] logger.c: --- (14 headers 0 lines) --- [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: = Found Their Call ID: 18a6a8ef284261fb29816d806cc31dad@192.168.30.254 Their Tag Our tag: as7be9ad14 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #96 [Mar 4 16:22:55] DEBUG[2698] chan_sip.c: Stopping retransmission on '18a6a8ef284261fb29816d806cc31dad@192.168.30.254' of Request 102: Match Found [Mar 4 16:22:55] VERBOSE[2698] logger.c: Really destroying SIP dialog '18a6a8ef284261fb29816d806cc31dad@192.168.30.254' Method: OPTIONS [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 4 16:22:56] DEBUG[2698] acl.c: ##### Testing 192.168.30.162 with 192.168.0.0 [Mar 4 16:22:56] DEBUG[2698] acl.c: ##### Testing 192.168.30.162 with 10.0.0.0 [Mar 4 16:22:56] DEBUG[2698] acl.c: ##### Testing 192.168.30.162 with 172.16.0.0 [Mar 4 16:22:56] DEBUG[2698] acl.c: ##### Testing 192.168.30.162 with 169.254.0.0 [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 0: OPTIONS sip:6000@192.168.30.162:2051;line=oclln1zw SIP/2.0 (58) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK120e2733;rport (65) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 2: From: "asterisk" ;tag=as09cbcf54 (61) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 3: To: (48) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 4: Contact: (38) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 5: Call-ID: 6ab11d5c55c490074539623c7eef1c34@192.168.30.254 (56) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 7: User-Agent: Asterisk PBX (Fireworx) (35) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 9: Date: Tue, 04 Mar 2008 21:22:56 GMT (35) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 11: Supported: replaces (19) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 12: Content-Length: 0 (17) [Mar 4 16:22:56] VERBOSE[2698] logger.c: Reliably Transmitting (no NAT) to 192.168.30.162:2051: OPTIONS sip:6000@192.168.30.162:2051;line=oclln1zw SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK120e2733;rport From: "asterisk" ;tag=as09cbcf54 To: Contact: Call-ID: 6ab11d5c55c490074539623c7eef1c34@192.168.30.254 CSeq: 102 OPTIONS User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Date: Tue, 04 Mar 2008 21:22:56 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:56] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.162:2051 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK120e2733;rport=5060 From: "asterisk" ;tag=as09cbcf54 To: Call-ID: 6ab11d5c55c490074539623c7eef1c34@192.168.30.254 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/7.1.30 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, callerid Content-Length: 0 <-------------> [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK120e2733;rport=5060 (70) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 2: From: "asterisk" ;tag=as09cbcf54 (61) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 3: To: (48) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 6ab11d5c55c490074539623c7eef1c34@192.168.30.254 (56) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 5: CSeq: 102 OPTIONS (17) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 6: Contact: ;flow-id=1 (63) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 7: User-Agent: snom360/7.1.30 (26) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 8: Accept-Language: en (19) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 9: Accept: application/sdp (23) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 11: Allow-Events: talk, hold, refer, call-info (42) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 12: Supported: timer, 100rel, replaces, callerid (44) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 13: Content-Length: 0 (17) [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Header 14: (0) [Mar 4 16:22:56] VERBOSE[2698] logger.c: --- (14 headers 0 lines) --- [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: = Found Their Call ID: 6ab11d5c55c490074539623c7eef1c34@192.168.30.254 Their Tag Our tag: as09cbcf54 [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #100 [Mar 4 16:22:56] DEBUG[2698] chan_sip.c: Stopping retransmission on '6ab11d5c55c490074539623c7eef1c34@192.168.30.254' of Request 102: Match Found [Mar 4 16:22:56] VERBOSE[2698] logger.c: Really destroying SIP dialog '6ab11d5c55c490074539623c7eef1c34@192.168.30.254' Method: OPTIONS [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-remote_agent_test has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:56] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:56] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:56] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:56] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-remote_agent_test has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:56] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:56] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:56] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:56] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:56] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:56] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> INVITE sip:*830@192.168.30.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-gc0yhrvxwtzm;rport From: "David Brillert" ;tag=wpv7cimitv To: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 1 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom370/7.1.30 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 479 v=0 o=root 2000566784 2000566784 IN IP4 192.168.30.199 s=call c=IN IP4 192.168.30.199 t=0 0 m=audio 11742 RTP/AVP 0 8 9 2 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:u289plND8gNV3dKlVuWHDct4Dqp6AWAeJ3e1wyo8 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=encryption:optional a=sendrecv <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: INVITE sip:*830@192.168.30.254 SIP/2.0 (38) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-gc0yhrvxwtzm;rport (70) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: From: "David Brillert" ;tag=wpv7cimitv (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: To: (29) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu (34) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: CSeq: 1 INVITE (14) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: Contact: ;flow-id=1 (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: P-Key-Flags: resolution="31x13", keys="4" (41) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 9: User-Agent: snom370/7.1.30 (26) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 10: Accept: application/sdp (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 12: Allow-Events: talk, hold, refer, call-info (42) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 15: Min-SE: 90 (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 16: Content-Type: application/sdp (29) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 17: Content-Length: 479 (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 18: (0) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: v=0 (3) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: o=root 2000566784 2000566784 IN IP4 192.168.30.199 (50) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: s=call (6) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: c=IN IP4 192.168.30.199 (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: t=0 0 (5) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: m=audio 11742 RTP/AVP 0 8 9 2 3 18 4 101 (40) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:u289plND8gNV3dKlVuWHDct4Dqp6AWAeJ3e1wyo8 (82) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=ptime:20 (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=encryption:optional (21) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=sendrecv (10) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (18 headers 19 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 3c280ef970a9-wlrlcwr1ma6b Their Tag v5fxhbit5m Our tag: as35f327b4 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 3c280ef973de-7oc963e67pes Their Tag o6ysroiuwp Our tag: as67e20f46 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 3c280ef9831b-4gfrrmmiayr0 Their Tag r2bacspr3k Our tag: as20118e23 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 3c280ef996dc-2co40ei5eygp Their Tag vclwgihle1 Our tag: as0202286f [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 3c280ef9a153-uaa6fpq12y5m Their Tag k351dflqa5 Our tag: as66d49357 [Mar 4 16:22:57] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 192.168.0.0 [Mar 4 16:22:57] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 10.0.0.0 [Mar 4 16:22:57] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 172.16.0.0 [Mar 4 16:22:57] DEBUG[2698] acl.c: ##### Testing 192.168.30.199 with 169.254.0.0 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on RTP to Off [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on VRTP to Off [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Allocating new SIP dialog for 3c2ba58f034d-6mj2z6nzk3yu - INVITE (With RTP) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Begin: parsing SIP "Supported: timer, 100rel, replaces, callerid" [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Found SIP option: -timer- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Matched SIP option: timer [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Found SIP option: -100rel- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Matched SIP option: 100rel [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Found SIP option: -replaces- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Matched SIP option: replaces [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Found SIP option: -callerid- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Found no match for SIP option: callerid (Please file bug report!) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Sending to 192.168.30.199 : 1024 (NAT) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Using INVITE request as basis request - 3c2ba58f034d-6mj2z6nzk3yu [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on RTP to Off [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on VRTP to Off [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- Reliably Transmitting (no NAT) to 192.168.30.199:1025 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-gc0yhrvxwtzm;received=192.168.30.199;rport=1024 From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as73ac64f6 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 1 INVITE User-Agent: Asterisk PBX (Fireworx) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="fireworx", nonce="6d9363f0" Content-Length: 0 <------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Scheduling destruction of SIP dialog '3c2ba58f034d-6mj2z6nzk3yu' in 32000 ms (Method: INVITE) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found user '6003' [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> ACK sip:*830@192.168.30.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-gc0yhrvxwtzm;rport From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as73ac64f6 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 1 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: ACK sip:*830@192.168.30.254 SIP/2.0 (35) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-gc0yhrvxwtzm;rport (70) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: From: "David Brillert" ;tag=wpv7cimitv (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: To: ;tag=as73ac64f6 (44) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu (34) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: CSeq: 1 ACK (11) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: Contact: ;flow-id=1 (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: Content-Length: 0 (17) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 9: (0) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (9 headers 0 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 3c2ba58f034d-6mj2z6nzk3yu Their Tag wpv7cimitv Our tag: as73ac64f6 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #103 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Stopping retransmission on '3c2ba58f034d-6mj2z6nzk3yu' of Response 1: Match Found [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> INVITE sip:*830@192.168.30.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-nwxi6oxyumxd;rport From: "David Brillert" ;tag=wpv7cimitv To: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 2 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom370/7.1.30 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Proxy-Authorization: Digest username="6003",realm="fireworx",nonce="6d9363f0",uri="sip:*830@192.168.30.254",response="2506ed0903f9ba90c5f9c7f0557e13a4",algorithm=MD5 Content-Type: application/sdp Content-Length: 479 v=0 o=root 2000566784 2000566784 IN IP4 192.168.30.199 s=call c=IN IP4 192.168.30.199 t=0 0 m=audio 11742 RTP/AVP 0 8 9 2 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:u289plND8gNV3dKlVuWHDct4Dqp6AWAeJ3e1wyo8 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=encryption:optional a=sendrecv <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: INVITE sip:*830@192.168.30.254 SIP/2.0 (38) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-nwxi6oxyumxd;rport (70) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: From: "David Brillert" ;tag=wpv7cimitv (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: To: (29) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu (34) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: CSeq: 2 INVITE (14) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: Contact: ;flow-id=1 (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: P-Key-Flags: resolution="31x13", keys="4" (41) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 9: User-Agent: snom370/7.1.30 (26) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 10: Accept: application/sdp (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 12: Allow-Events: talk, hold, refer, call-info (42) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 15: Min-SE: 90 (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 16: Proxy-Authorization: Digest username="6003",realm="fireworx",nonce="6d9363f0",uri="sip:*830@192.168.30.254",response="2506ed0903f9ba90c5f9c7f0557e13a4",algorithm=MD5 (165) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 17: Content-Type: application/sdp (29) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 18: Content-Length: 479 (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 19: (0) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: v=0 (3) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: o=root 2000566784 2000566784 IN IP4 192.168.30.199 (50) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: s=call (6) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: c=IN IP4 192.168.30.199 (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: t=0 0 (5) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: m=audio 11742 RTP/AVP 0 8 9 2 3 18 4 101 (40) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:u289plND8gNV3dKlVuWHDct4Dqp6AWAeJ3e1wyo8 (82) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=ptime:20 (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=encryption:optional (21) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=sendrecv (10) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (19 headers 19 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 3c2ba58f034d-6mj2z6nzk3yu Their Tag wpv7cimitv Our tag: as73ac64f6 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Mar 4 16:22:57] VERBOSE[2698] logger.c: Sending to 192.168.30.199 : 1024 (NAT) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Using INVITE request as basis request - 3c2ba58f034d-6mj2z6nzk3yu [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on RTP to Off [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Setting NAT on VRTP to Off [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[2698] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found user '6003' [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 0 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 8 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 9 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 2 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 3 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 18 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 4 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 101 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Peer audio RTP is at port 192.168.30.199:11742 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Got unsupported a:crypto in SDP offer [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format pcmu for ID 0 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format pcma for ID 8 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format g722 for ID 9 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format g726-32 for ID 2 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format gsm for ID 3 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format g729 for ID 18 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format g723 for ID 4 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format telephone-event for ID 101 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Got unsupported a:fmtp in SDP offer [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: T38 state changed to 0 on channel [Mar 4 16:22:57] VERBOSE[2698] logger.c: Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x190f (g723|gsm|ulaw|alaw|g726|g729|g722)/video=0x0 (nothing), combined - 0x104 (ulaw|g729) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Peer audio RTP is at port 192.168.30.199:11742 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: We're settling with these formats: 0x104 (ulaw|g729) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Checking SIP call limits for device 6003 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Updating call counter for incoming call [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Call from peer '6003' is 1 out of 4 [Mar 4 16:22:57] DEBUG[2698] devicestate.c: Notification of state change to be queued on device/channel SIP/6003 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Looking for *830 in default-super (domain 192.168.30.254) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** Joint capabilities are 0x104 (ulaw|g729) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** Our capabilities are 0x104 (ulaw|g729) [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: This channel will not be able to handle video. [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: build_route: Contact hop: ;flow-id=1 [Mar 4 16:22:57] VERBOSE[2698] logger.c: list_route: hop: [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: SIP/6003-097fcee8: New call is still down.... Trying... [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- Transmitting (no NAT) to 192.168.30.199:1025 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-nwxi6oxyumxd;received=192.168.30.199;rport=1024 From: "David Brillert" ;tag=wpv7cimitv To: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 2 INVITE User-Agent: Asterisk PBX (Fireworx) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Mar 4 16:22:57] DEBUG[2698] devicestate.c: Notification of state change to be queued on device/channel SIP/6003-097fcee8 [Mar 4 16:22:57] DEBUG[2698] devicestate.c: Notification of state change to be queued on device/channel SIP/6003 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6003 - state 2 (In use) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6003' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'Answer' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:1] Answer("SIP/6003-097fcee8", "") in new stack [Mar 4 16:22:57] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6003-097fcee8 [Mar 4 16:22:57] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6003 [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: SIP answering channel: SIP/6003-097fcee8 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003-097fcee8 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003-097fcee8 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6003-097fcee8 - state 4 (Invalid) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6003 - state 2 (In use) [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: Setting framing from config on incoming call [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6003-097fcee8' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003-097fcee8 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003-097fcee8 [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6003' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6003-097fcee8 - state 4 (Invalid) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6003 - state 2 (In use) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: ** Our capability: 0x104 (ulaw|g729) Video flag: True [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Audio is at 192.168.30.254 port 10116 [Mar 4 16:22:57] VERBOSE[3764] logger.c: Adding codec 0x4 (ulaw) to SDP [Mar 4 16:22:57] VERBOSE[3764] logger.c: Adding codec 0x100 (g729) to SDP [Mar 4 16:22:57] VERBOSE[3764] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: -- Done with adding codecs to SDP [Mar 4 16:22:57] DEBUG[3764] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=126) [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: Done building SDP. Settling with this capability: 0x104 (ulaw|g729) [Mar 4 16:22:57] VERBOSE[3764] logger.c: <--- Reliably Transmitting (no NAT) to 192.168.30.199:1025 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-nwxi6oxyumxd;received=192.168.30.199;rport=1024 From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as3eb13f89 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 2 INVITE User-Agent: Asterisk PBX (Fireworx) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 289 v=0 o=root 2617 2617 IN IP4 192.168.30.254 s=session c=IN IP4 192.168.30.254 t=0 0 m=audio 10116 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Mar 4 16:22:57] DEBUG[3764] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6003-097fcee8' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6003' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[3764] pbx.c: Function result is '6003' [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'Set' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:2] Set("SIP/6003-097fcee8", "GROUP(OUTGOING)=6003") in new stack [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'Set' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:3] Set("SIP/6003-097fcee8", "__PAGING_RINGTIME=5") in new stack [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'NoCDR' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:4] NoCDR("SIP/6003-097fcee8", "") in new stack [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'Set' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:5] Set("SIP/6003-097fcee8", "TIMEOUT(absolute)=45") in new stack [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Channel will hangup at 2008-03-04 21:23:42 UTC. [Mar 4 16:22:57] DEBUG[3764] pbx.c: Launching 'Page' [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Executing [*830@default-super:6] Page("SIP/6003-097fcee8", "Local/6004@default-local-paging") in new stack [Mar 4 16:22:57] DEBUG[3764] channel.c: Copying hard-transferable variable PAGING_RINGTIME. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable SPYGROUP. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable INBOUND_GROUP. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable SIPCALLID. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable SIPUSERAGENT. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable SIPDOMAIN. [Mar 4 16:22:57] DEBUG[3764] channel.c: Not copying variable SIPURI. [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Called 6004@default-local-paging [Mar 4 16:22:57] DEBUG[3764] channel.c: Set channel SIP/6003-097fcee8 to write format gsm [Mar 4 16:22:57] DEBUG[3766] pbx.c: Launching 'SIPAddHeader' [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Executing [6004@default-local-paging:1] SIPAddHeader("Local/6004@default-local-paging-ff51,2", "Call-Info:;answer-after=0") in new stack [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: SIP Header added "Call-Info:;answer-after=0" as _SIPADDHEADER01 [Mar 4 16:22:57] DEBUG[3766] pbx.c: Function result is '0' [Mar 4 16:22:57] DEBUG[3766] pbx.c: Function result is '0' [Mar 4 16:22:57] DEBUG[3766] pbx.c: Expression result is '0' [Mar 4 16:22:57] DEBUG[3766] pbx.c: Launching 'Set' [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Executing [6004@default-local-paging:2] Set("Local/6004@default-local-paging-ff51,2", "GROUPCOUNT=0") in new stack [Mar 4 16:22:57] DEBUG[3766] pbx.c: Expression result is '0' [Mar 4 16:22:57] DEBUG[3766] pbx.c: Expression result is '6' [Mar 4 16:22:57] DEBUG[3766] pbx.c: Launching 'GotoIf' [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Executing [6004@default-local-paging:3] GotoIf("Local/6004@default-local-paging-ff51,2", "0?6") in new stack [Mar 4 16:22:57] DEBUG[3766] pbx.c: Not taking any branch [Mar 4 16:22:57] DEBUG[3766] pbx.c: Launching 'Set' [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Executing [6004@default-local-paging:4] Set("Local/6004@default-local-paging-ff51,2", "OUTBOUND_GROUP=6004@INCOMING") in new stack [Mar 4 16:22:57] DEBUG[3766] pbx.c: Launching 'Dial' [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Executing [6004@default-local-paging:5] Dial("Local/6004@default-local-paging-ff51,2", "SIP/6004|5") in new stack [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3764] rtp.c: Ooh, format changed from unknown to ulaw [Mar 4 16:22:57] DEBUG[3764] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062665, ts 000160, len 000160) [Mar 4 16:22:57] DEBUG[3764] channel.c: Scheduling timer at 160 sample intervals [Mar 4 16:22:57] VERBOSE[3764] logger.c: -- Playing 'beep' (language 'en') [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062666, ts 000320, len 000160) [Mar 4 16:22:57] DEBUG[3766] sched.c: Attempted to delete nonexistent schedule entry 0! [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Setting NAT on RTP to Off [Mar 4 16:22:57] DEBUG[3766] acl.c: ##### Testing 192.168.30.178 with 192.168.0.0 [Mar 4 16:22:57] DEBUG[3766] acl.c: ##### Testing 192.168.30.178 with 10.0.0.0 [Mar 4 16:22:57] DEBUG[3766] acl.c: ##### Testing 192.168.30.178 with 172.16.0.0 [Mar 4 16:22:57] DEBUG[3766] acl.c: ##### Testing 192.168.30.178 with 169.254.0.0 [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** Joint capabilities are 0x0 (nothing) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** Our capabilities are 0x104 (ulaw|g729) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: This channel will not be able to handle video. [Mar 4 16:22:57] DEBUG[3766] rtp.c: Channel 'Local/6004@default-local-paging-ff51,2' has no RTP, not doing anything [Mar 4 16:22:57] DEBUG[3766] channel.c: Not copying variable OUTBOUND_GROUP. [Mar 4 16:22:57] DEBUG[3766] channel.c: Not copying variable GROUPCOUNT. [Mar 4 16:22:57] DEBUG[3766] channel.c: Copying soft-transferable variable SIPADDHEADER01. [Mar 4 16:22:57] DEBUG[3766] channel.c: Copying hard-transferable variable PAGING_RINGTIME. [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Outgoing Call for 6004 [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Updating call counter for outgoing call [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Call to peer '6004' is 1 out of 4 [Mar 4 16:22:57] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel SIP/6004 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6004 - state 6 (Ringing) [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6004' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Our T38 capability (0), joint T38 capability (0) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Adding SIP Header "Call-Info" with content :;answer-after=0: [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: False [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) [Mar 4 16:22:57] VERBOSE[3766] logger.c: Audio is at 192.168.30.254 port 14176 [Mar 4 16:22:57] VERBOSE[3766] logger.c: Adding codec 0x4 (ulaw) to SDP [Mar 4 16:22:57] VERBOSE[3766] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: -- Done with adding codecs to SDP [Mar 4 16:22:57] DEBUG[3766] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=132) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 0: INVITE sip:6004@192.168.30.178;transport=udp SIP/2.0 (52) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport (65) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 2: From: "David Brillert" ;tag=as47c03d0d (63) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 3: To: (43) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 4: Contact: (34) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 5: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 (56) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 7: User-Agent: Asterisk PBX (Fireworx) (35) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 9: Remote-Party-ID: "David Brillert" ;privacy=off;screen=no (81) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 10: Date: Tue, 04 Mar 2008 21:22:57 GMT (35) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 12: Supported: replaces (19) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 13: Call-Info: ;answer-after=0 (26) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 15: Content-Length: 242 (19) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Header 16: (0) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: v=0 (3) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: o=root 2617 2617 IN IP4 192.168.30.254 (38) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: s=session (9) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: c=IN IP4 192.168.30.254 (23) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: t=0 0 (5) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: m=audio 14176 RTP/AVP 0 101 (27) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=ptime:20 (10) [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: Line: a=sendrecv (10) [Mar 4 16:22:57] VERBOSE[3766] logger.c: Reliably Transmitting (no NAT) to 192.168.30.178:5060: INVITE sip:6004@192.168.30.178;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport From: "David Brillert" ;tag=as47c03d0d To: Contact: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 INVITE User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Remote-Party-ID: "David Brillert" ;privacy=off;screen=no Date: Tue, 04 Mar 2008 21:22:57 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Call-Info: ;answer-after=0 Content-Type: application/sdp Content-Length: 242 v=0 o=root 2617 2617 IN IP4 192.168.30.254 s=session c=IN IP4 192.168.30.254 t=0 0 m=audio 14176 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Mar 4 16:22:57] DEBUG[3766] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062667, ts 000480, len 000160) [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- Called 6004 [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062668, ts 000640, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062669, ts 000800, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062670, ts 000960, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062671, ts 001120, len 000160) [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> ACK sip:*830@192.168.30.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-ibr1pvh1fzir;rport From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as3eb13f89 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 2 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: ACK sip:*830@192.168.30.254 SIP/2.0 (35) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-ibr1pvh1fzir;rport (70) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: From: "David Brillert" ;tag=wpv7cimitv (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: To: ;tag=as3eb13f89 (44) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu (34) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: CSeq: 2 ACK (11) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: Contact: ;flow-id=1 (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: Content-Length: 0 (17) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 9: (0) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (9 headers 0 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = No match Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag Our tag: as47c03d0d [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 3c2ba58f034d-6mj2z6nzk3yu Their Tag wpv7cimitv Our tag: as3eb13f89 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #105 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Stopping retransmission on '3c2ba58f034d-6mj2z6nzk3yu' of Response 2: Match Found [Mar 4 16:22:57] DEBUG[3764] rtp.c: Got RTCP report of 52 bytes [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062672, ts 001280, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062673, ts 001440, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062674, ts 001600, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028191, ts 2731146648, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062675, ts 001760, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028192, ts 2731146808, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062676, ts 001920, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028193, ts 2731146968, len 000160) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: SIP TIMER: Rescheduling retransmission #107 (1) INVITE - 5 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 200 ms (t1 100 ms (Retrans id #107)) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Retransmitting #1 (no NAT) to 192.168.30.178:5060: INVITE sip:6004@192.168.30.178;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport From: "David Brillert" ;tag=as47c03d0d To: Contact: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 INVITE User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Remote-Party-ID: "David Brillert" ;privacy=off;screen=no Date: Tue, 04 Mar 2008 21:22:57 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Call-Info: ;answer-after=0 Content-Type: application/sdp Content-Length: 242 v=0 o=root 2617 2617 IN IP4 192.168.30.254 s=session c=IN IP4 192.168.30.254 t=0 0 m=audio 14176 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062677, ts 002080, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028194, ts 2731147128, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062678, ts 002240, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028195, ts 2731147288, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062679, ts 002400, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028196, ts 2731147448, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062680, ts 002560, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028197, ts 2731147608, len 000160) [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.178:5060 ---> SIP/2.0 100 Trying Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 INVITE From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport Content-Length: 0 User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 (56) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: CSeq: 102 INVITE (16) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062681, ts 002720, len 000160) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: From: "David Brillert" ;tag=as47c03d0d (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: To: ;tag=4a5b0efb6b5bdec (49) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport (65) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Content-Length: 0 (17) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 (71) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: (0) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (8 headers 0 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag Our tag: as47c03d0d [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: *** SIP TIMER: Cancelling retransmission #107 - INVITE (got response) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '48749c7b400d493b5427d02803f663a3@192.168.30.254' Request 102: Found [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: SIP response 100 to standard invite [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028198, ts 2731147768, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062682, ts 002880, len 000160) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028199, ts 2731147928, len 000160) [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.178:5060 ---> SIP/2.0 100 Trying Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 INVITE From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport Content-Length: 0 User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 (56) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: CSeq: 102 INVITE (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: From: "David Brillert" ;tag=as47c03d0d (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: To: ;tag=4a5b0efb6b5bdec (49) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport (65) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Content-Length: 0 (17) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 (71) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: (0) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (8 headers 0 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag 4a5b0efb6b5bdec Our tag: as47c03d0d [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '48749c7b400d493b5427d02803f663a3@192.168.30.254' Request 102: Found [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: SIP response 100 to standard invite [Mar 4 16:22:57] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062683, ts 003040, len 000160) [Mar 4 16:22:57] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.178:5060 ---> SIP/2.0 200 OK Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 INVITE From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport Content-Length: 233 Session-Expires: 30;refresher=uas Call-Info: ;appearance-index=1 Allow-Events: talk,hold,conference Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO Content-Type: application/sdp Supported: replaces Contact: Don Wright User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 v=0 o=MxSIP 0 531398907 IN IP4 192.168.30.178 s=SIP Call c=IN IP4 192.168.30.178 t=0 0 m=audio 3000 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=silenceSupp:off - - - - <-------------> [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 1: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 (56) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 2: CSeq: 102 INVITE (16) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 3: From: "David Brillert" ;tag=as47c03d0d (63) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 4: To: ;tag=4a5b0efb6b5bdec (49) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK3e0fcb1b;rport (65) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 6: Content-Length: 233 (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 7: Session-Expires: 30;refresher=uas (33) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 8: Call-Info: ;appearance-index=1 (50) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 9: Allow-Events: talk,hold,conference (34) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 10: Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO (53) [Mar 4 16:22:57] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028200, ts 2731148088, len 000160) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 11: Content-Type: application/sdp (29) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 12: Supported: replaces (19) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 13: Contact: Don Wright (45) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 14: User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 (71) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Header 15: (0) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: v=0 (3) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: o=MxSIP 0 531398907 IN IP4 192.168.30.178 (41) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: s=SIP Call (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: c=IN IP4 192.168.30.178 (23) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: t=0 0 (5) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: m=audio 3000 RTP/AVP 0 101 (26) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=fmtp:101 0-15 (15) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=ptime:20 (10) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Mar 4 16:22:57] VERBOSE[2698] logger.c: --- (15 headers 11 lines) --- [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: = Found Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag 4a5b0efb6b5bdec Our tag: as47c03d0d [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Acked pending invite 102 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Stopping retransmission on '48749c7b400d493b5427d02803f663a3@192.168.30.254' of Request 102: Match Found [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: SIP response 200 to standard invite [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 0 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found RTP audio format 101 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Peer audio RTP is at port 192.168.30.178:3000 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format PCMU for ID 0 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Found audio description format telephone-event for ID 101 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Got unsupported a:fmtp in SDP offer [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: T38 state changed to 0 on channel SIP/6004-09807780 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Mar 4 16:22:57] VERBOSE[2698] logger.c: Peer audio RTP is at port 192.168.30.178:3000 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: We have an owner, now see if we need to change this call [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Updating call counter for outgoing call [Mar 4 16:22:57] DEBUG[2698] devicestate.c: Notification of state change to be queued on device/channel SIP/6004 [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: build_route: Contact hop: Don Wright [Mar 4 16:22:57] VERBOSE[2698] logger.c: list_route: hop: [Mar 4 16:22:57] DEBUG[2698] chan_sip.c: Strict routing enforced for session 48749c7b400d493b5427d02803f663a3@192.168.30.254 [Mar 4 16:22:57] VERBOSE[2698] logger.c: set_destination: Parsing for address/port to send to [Mar 4 16:22:57] VERBOSE[2698] logger.c: set_destination: set destination to 192.168.30.178, port 5060 [Mar 4 16:22:57] VERBOSE[2698] logger.c: Transmitting (no NAT) to 192.168.30.178:5060: ACK sip:6004@192.168.30.178 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK362c0815;rport From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Contact: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 102 ACK User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Remote-Party-ID: "David Brillert" ;privacy=off;screen=no Content-Length: 0 --- [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: Changing state for SIP/6004 - state 2 (In use) [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:57] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel SIP/6004-09807780 [Mar 4 16:22:57] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel SIP/6004 [Mar 4 16:22:57] VERBOSE[3766] logger.c: -- SIP/6004-09807780 answered Local/6004@default-local-paging-ff51,2 [Mar 4 16:22:57] DEBUG[3766] rtp.c: Channel 'Local/6004@default-local-paging-ff51,2' has no RTP, not doing anything [Mar 4 16:22:57] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging-ff51,2 [Mar 4 16:22:57] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging [Mar 4 16:22:57] DEBUG[2733] app_queue.c: Device 'SIP/6004' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:57] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:57] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging [Mar 4 16:22:57] VERBOSE[3767] logger.c: -- Local/6004@default-local-paging-ff51,1 answered [Mar 4 16:22:57] DEBUG[3767] app_meetme.c: Building dynamic conference '1458848547d' [Mar 4 16:22:57] DEBUG[3767] chan_zap.c: Using channel -2 [Mar 4 16:22:57] DEBUG[3767] channel.c: Set channel Zap/pseudo-1157541001 to read format slin [Mar 4 16:22:57] DEBUG[3767] channel.c: Set channel Zap/pseudo-1157541001 to write format slin [Mar 4 16:22:57] VERBOSE[3767] logger.c: -- Created MeetMe conference 1023 for conference '1458848547d' [Mar 4 16:22:57] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel meetme:1458848547d [Mar 4 16:22:57] DEBUG[3767] channel.c: Set channel Local/6004@default-local-paging-ff51,1 to write format slin [Mar 4 16:22:57] DEBUG[3767] channel.c: Set channel Local/6004@default-local-paging-ff51,1 to read format slin [Mar 4 16:22:57] DEBUG[3767] app_meetme.c: Placed channel Local/6004@default-local-paging-ff51,1 in ZAP conf 1023 [Mar 4 16:22:57] DEBUG[3766] channel.c: Got a FRAME_CONTROL (-1) frame on channel Local/6004@default-local-paging-ff51,2 [Mar 4 16:22:57] DEBUG[3766] channel.c: Bridge stops bridging channels Local/6004@default-local-paging-ff51,2 and SIP/6004-09807780 [Mar 4 16:22:57] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004-09807780 [Mar 4 16:22:57] DEBUG[2689] chan_sip.c: Checking device state for peer 6004-09807780 [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for SIP/6004-09807780 - state 4 (Invalid) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'SIP/6004-09807780' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:58] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for SIP/6004 - state 2 (In use) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'SIP/6004' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:58] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging-ff51,2 [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging-ff51,2 exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging-ff51,2 - state 4 (Invalid) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging-ff51,2' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging - state 2 (In use) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging-ff51,1 exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging-ff51,1 - state 4 (Invalid) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging - state 2 (In use) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Checking if I can find provider for "meetme" - number: 1458848547d [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Checking provider SLA with meetme [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Checking provider Meetme with meetme [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for meetme:1458848547d - state 2 (In use) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062684, ts 003200, len 000160) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging-ff51,1' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028201, ts 2731148248, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062685, ts 003360, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028202, ts 2731148408, len 000160) [Mar 4 16:22:58] DEBUG[3766] rtp.c: Ooh, format changed from unknown to ulaw [Mar 4 16:22:58] DEBUG[3766] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005542, ts 000160, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062686, ts 003520, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028203, ts 2731148568, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005543, ts 000320, len 000160) [Mar 4 16:22:58] DEBUG[3764] channel.c: Scheduling timer at 0 sample intervals [Mar 4 16:22:58] DEBUG[3764] channel.c: Scheduling timer at 0 sample intervals [Mar 4 16:22:58] DEBUG[3764] channel.c: Set channel SIP/6003-097fcee8 to write format ulaw [Mar 4 16:22:58] DEBUG[3764] channel.c: Set channel SIP/6003-097fcee8 to write format slin [Mar 4 16:22:58] DEBUG[3764] channel.c: Set channel SIP/6003-097fcee8 to read format slin [Mar 4 16:22:58] DEBUG[3764] app_meetme.c: Placed channel SIP/6003-097fcee8 in ZAP conf 1023 [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028204, ts 2731148728, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005544, ts 000480, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062687, ts 003680, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028205, ts 2731148888, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005545, ts 000640, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062688, ts 003840, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028206, ts 2731149048, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005546, ts 000800, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062689, ts 004000, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028207, ts 2731149208, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005547, ts 000960, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062690, ts 004160, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028208, ts 2731149368, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005548, ts 001120, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062691, ts 004320, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028209, ts 2731149528, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005549, ts 001280, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062692, ts 004480, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028210, ts 2731149688, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005550, ts 001440, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/5006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/5006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/5006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/5006/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/5006/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/5006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062693, ts 004640, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028211, ts 2731149848, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3766] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005551, ts 001600, len 000160) [Mar 4 16:22:58] VERBOSE[3766] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004623, ts 1040199816, len 000160) [Mar 4 16:22:58] DEBUG[3766] channel.c: Planning to masquerade channel SIP/6004-09807780 into the structure of Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[3766] channel.c: Done planning to masquerade channel SIP/6004-09807780 into the structure of Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[3766] chan_local.c: Not posting to queue since already masked on 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] DEBUG[3767] channel.c: Actually Masquerading SIP/6004-09807780(6) into the structure of Local/6004@default-local-paging-ff51,1(6) [Mar 4 16:22:58] DEBUG[3767] channel.c: Got clone lock for masquerade on 'SIP/6004-09807780' at 0x980be40 [Mar 4 16:22:58] DEBUG[3767] channel.c: Set channel SIP/6004-09807780 to write format slin [Mar 4 16:22:58] DEBUG[3767] channel.c: Set channel SIP/6004-09807780 to read format slin [Mar 4 16:22:58] DEBUG[3767] channel.c: Putting channel SIP/6004-09807780 in 64/64 formats [Mar 4 16:22:58] DEBUG[3767] chan_sip.c: SIP Fixup: New owner for dialogue 48749c7b400d493b5427d02803f663a3@192.168.30.254: SIP/6004-09807780 (Old parent: Local/6004@default-local-paging-ff51,1) [Mar 4 16:22:58] DEBUG[3767] channel.c: Released clone lock on 'Local/6004@default-local-paging-ff51,1' [Mar 4 16:22:58] DEBUG[3767] channel.c: Done Masquerading SIP/6004-09807780 (6) [Mar 4 16:22:58] DEBUG[3767] app_meetme.c: Ooh, something swapped out under us, starting over [Mar 4 16:22:58] DEBUG[3767] app_meetme.c: Placed channel SIP/6004-09807780 in ZAP conf 1023 [Mar 4 16:22:58] DEBUG[3766] channel.c: Didn't get a frame from channel: Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[3766] channel.c: Bridge stops bridging channels Local/6004@default-local-paging-ff51,2 and Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[3766] channel.c: Hanging up zombie 'Local/6004@default-local-paging-ff51,1' [Mar 4 16:22:58] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging [Mar 4 16:22:58] DEBUG[3766] rtp.c: Channel 'Local/6004@default-local-paging-ff51,2' has no RTP, not doing anything [Mar 4 16:22:58] DEBUG[3766] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Mar 4 16:22:58] DEBUG[3766] pbx.c: Spawn extension (default-local-paging,6004,5) exited non-zero on 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] VERBOSE[3766] logger.c: == Spawn extension (default-local-paging, 6004, 5) exited non-zero on 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] DEBUG[3766] channel.c: Soft-Hanging up channel 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] DEBUG[3766] channel.c: Hanging up channel 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging-ff51,2 [Mar 4 16:22:58] DEBUG[3766] devicestate.c: Notification of state change to be queued on device/channel Local/6004@default-local-paging [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '"David Brillert" <6003>' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '6003' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '6004' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'default-local-paging' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'Local/6004@default-local-paging-ff51,2' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'SIP/6004-09807780' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'Dial' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'SIP/6004|5' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '2008-03-04 16:22:57' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '2008-03-04 16:22:57' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '2008-03-04 16:22:58' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '1' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '1' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'ANSWERED' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is 'DOCUMENTATION' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '6003' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '1204665777.2' [Mar 4 16:22:58] DEBUG[3766] pbx.c: Function result is '' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6000' [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging-ff51,1 [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging-ff51,1 exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging-ff51,1 - state 4 (Invalid) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging - state 1 (Not in use) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging-ff51,2 [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging-ff51,2 exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging-ff51,2 - state 4 (Invalid) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Local - 6004@default-local-paging [Mar 4 16:22:58] DEBUG[2689] chan_local.c: Checking if extension 6004@default-local-paging exists (devicestate) [Mar 4 16:22:58] DEBUG[2689] devicestate.c: Changing state for Local/6004@default-local-paging - state 1 (Not in use) [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging-ff51,1' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging-ff51,2' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2733] app_queue.c: Device 'Local/6004@default-local-paging' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6000' [Mar 4 16:22:58] DEBUG[3766] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. [Mar 4 16:22:58] DEBUG[3766] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2008-03-04 16:22:57','\"David Brillert\" <6003>','6003','6004','default-local-paging', 'Local/6004@default-local-paging-ff51,2','SIP/6004-09807780','Dial','SIP/6004|5',1,1,'ANSWERED',3,'6003','1204665777.2','') [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004624, ts 1040199976, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6000' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062694, ts 004800, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028212, ts 2731150008, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6000/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6000/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6000' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005552, ts 001760, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062695, ts 004960, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028213, ts 2731150168, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6001' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6001' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004625, ts 1040200136, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6001' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6001/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6001/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6001' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005553, ts 001920, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6002' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062696, ts 005120, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6002' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028214, ts 2731150328, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6002' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6002/Agent' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004626, ts 1040200296, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6002/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6002' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6003' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6003' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005554, ts 002080, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062697, ts 005280, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028215, ts 2731150488, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6003' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6003/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6003/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6003' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004627, ts 1040200456, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6004' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6004' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6004' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005555, ts 002240, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062698, ts 005440, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6004/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028216, ts 2731150648, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6004/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6004' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004628, ts 1040200616, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6005' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6005' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6005' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6005/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6005/Agent' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005556, ts 002400, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6005' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062699, ts 005600, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028217, ts 2731150808, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6006' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004629, ts 1040200776, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6006/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6006/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6006' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005557, ts 002560, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062700, ts 005760, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028218, ts 2731150968, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6007' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6007' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004630, ts 1040200936, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6007' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6007/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6007/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6007' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6008' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005558, ts 002720, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6008' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062701, ts 005920, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallForward' in family 'default/6008' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028219, ts 2731151128, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallForwardOnBusy' in family 'default/6008' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6008' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6008/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004631, ts 1040201096, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6008/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6008' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6009' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6009' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005559, ts 002880, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062702, ts 006080, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6009' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028220, ts 2731151288, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6009/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6009/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6009' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004632, ts 1040201256, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6010' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6010' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6010' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005560, ts 003040, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062703, ts 006240, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6010' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028221, ts 2731151448, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6011' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6011' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004633, ts 1040201416, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6011' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6011/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6011/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6011' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005561, ts 003200, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062704, ts 006400, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028222, ts 2731151608, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6012' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6012' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6012' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004634, ts 1040201576, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6012/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6012/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6012' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6013' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005562, ts 003360, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6013' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062705, ts 006560, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028223, ts 2731151768, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6013' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6013/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6013/Agent' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004635, ts 1040201736, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6013' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6014' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6014' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005563, ts 003520, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6014' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062706, ts 006720, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6014/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028224, ts 2731151928, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6014/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6014' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004636, ts 1040201896, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6056' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6056' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6056' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6056/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6056/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6056' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005564, ts 003680, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062707, ts 006880, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6057' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028225, ts 2731152088, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6057' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004637, ts 1040202056, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6057' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6057/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6057/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6057' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6363' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6363' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005565, ts 003840, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062708, ts 007040, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028226, ts 2731152248, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6363' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004638, ts 1040202216, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6363/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6363/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6363' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'ChanSpy' in family 'default/6364' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005566, ts 004000, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'DoNotDisturb' in family 'default/6364' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062709, ts 007200, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028227, ts 2731152408, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'CallWaiting' in family 'default/6364' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Login' in family 'default/6364/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'Paused' in family 'default/6364/Agent' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004639, ts 1040202376, len 000160) [Mar 4 16:22:58] DEBUG[2741] db.c: Unable to find key 'HotDesk' in family 'default/6364' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'show uptime' [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-remote_agent_test has no realtime members defined. No need for update [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005567, ts 004160, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062710, ts 007360, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028228, ts 2731152568, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004640, ts 1040202536, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:58] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:58] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005568, ts 004320, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062711, ts 007520, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028229, ts 2731152728, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:58] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:58] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004641, ts 1040202696, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-remote_agent_test has no realtime members defined. No need for update [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005569, ts 004480, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062712, ts 007680, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028230, ts 2731152888, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:58] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:58] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004642, ts 1040202856, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'ExtensionState' [Mar 4 16:22:58] DEBUG[2741] devicestate.c: No provider found, checking channel drivers for SIP - 6010 [Mar 4 16:22:58] DEBUG[2741] chan_sip.c: Checking device state for peer 6010 [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005570, ts 004640, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062713, ts 007840, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028231, ts 2731153048, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004643, ts 1040203016, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005571, ts 004800, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062714, ts 008000, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028232, ts 2731153208, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004644, ts 1040203176, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005572, ts 004960, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062715, ts 008160, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028233, ts 2731153368, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004645, ts 1040203336, len 000160) [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-sales has no realtime members defined. No need for update [Mar 4 16:22:58] DEBUG[2741] manager.c: Manager received command 'Command' [Mar 4 16:22:58] DEBUG[2741] app_queue.c: Queue default-service has no realtime members defined. No need for update [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005573, ts 005120, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062716, ts 008320, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028234, ts 2731153528, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004646, ts 1040203496, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005574, ts 005280, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062717, ts 008480, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028235, ts 2731153688, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004647, ts 1040203656, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005575, ts 005440, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062718, ts 008640, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028236, ts 2731153848, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004648, ts 1040203816, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005576, ts 005600, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062719, ts 008800, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028237, ts 2731154008, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004649, ts 1040203976, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005577, ts 005760, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062720, ts 008960, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028238, ts 2731154168, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004650, ts 1040204136, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005578, ts 005920, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062721, ts 009120, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028239, ts 2731154328, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004651, ts 1040204296, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005579, ts 006080, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062722, ts 009280, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028240, ts 2731154488, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004652, ts 1040204456, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005580, ts 006240, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062723, ts 009440, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028241, ts 2731154648, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004653, ts 1040204616, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005581, ts 006400, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062724, ts 009600, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028242, ts 2731154808, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004654, ts 1040204776, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005582, ts 006560, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062725, ts 009760, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028243, ts 2731154968, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004655, ts 1040204936, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005583, ts 006720, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062726, ts 009920, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028244, ts 2731155128, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004656, ts 1040205096, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005584, ts 006880, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062727, ts 010080, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028245, ts 2731155288, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004657, ts 1040205256, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005585, ts 007040, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062728, ts 010240, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028246, ts 2731155448, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004658, ts 1040205416, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005586, ts 007200, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062729, ts 010400, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028247, ts 2731155608, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004659, ts 1040205576, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005587, ts 007360, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062730, ts 010560, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028248, ts 2731155768, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004660, ts 1040205736, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005588, ts 007520, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062731, ts 010720, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028249, ts 2731155928, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004661, ts 1040205896, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005589, ts 007680, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062732, ts 010880, len 000160) [Mar 4 16:22:58] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028250, ts 2731156088, len 000160) [Mar 4 16:22:58] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004662, ts 1040206056, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005590, ts 007840, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062733, ts 011040, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028251, ts 2731156248, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004663, ts 1040206216, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005591, ts 008000, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062734, ts 011200, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028252, ts 2731156408, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004664, ts 1040206376, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005592, ts 008160, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062735, ts 011360, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028253, ts 2731156568, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004665, ts 1040206536, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005593, ts 008320, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062736, ts 011520, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028254, ts 2731156728, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004666, ts 1040206696, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005594, ts 008480, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062737, ts 011680, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028255, ts 2731156888, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004667, ts 1040206856, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005595, ts 008640, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062738, ts 011840, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028256, ts 2731157048, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004668, ts 1040207016, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005596, ts 008800, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062739, ts 012000, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028257, ts 2731157208, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004669, ts 1040207176, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005597, ts 008960, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062740, ts 012160, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028258, ts 2731157368, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004670, ts 1040207336, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005598, ts 009120, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062741, ts 012320, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028259, ts 2731157528, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004671, ts 1040207496, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005599, ts 009280, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062742, ts 012480, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028260, ts 2731157688, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004672, ts 1040207656, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005600, ts 009440, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062743, ts 012640, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028261, ts 2731157848, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004673, ts 1040207816, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005601, ts 009600, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062744, ts 012800, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028262, ts 2731158008, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004674, ts 1040207976, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005602, ts 009760, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062745, ts 012960, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028263, ts 2731158168, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004675, ts 1040208136, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005603, ts 009920, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062746, ts 013120, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028264, ts 2731158328, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004676, ts 1040208296, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005604, ts 010080, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062747, ts 013280, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028265, ts 2731158488, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004677, ts 1040208456, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005605, ts 010240, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062748, ts 013440, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028266, ts 2731158648, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004678, ts 1040208616, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005606, ts 010400, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062749, ts 013600, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028267, ts 2731158808, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004679, ts 1040208776, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005607, ts 010560, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062750, ts 013760, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028268, ts 2731158968, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004680, ts 1040208936, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005608, ts 010720, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062751, ts 013920, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028269, ts 2731159128, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004681, ts 1040209096, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005609, ts 010880, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062752, ts 014080, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028270, ts 2731159288, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004682, ts 1040209256, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005610, ts 011040, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062753, ts 014240, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028271, ts 2731159448, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004683, ts 1040209416, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005611, ts 011200, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062754, ts 014400, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028272, ts 2731159608, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004684, ts 1040209576, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005612, ts 011360, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062755, ts 014560, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028273, ts 2731159768, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004685, ts 1040209736, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005613, ts 011520, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062756, ts 014720, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028274, ts 2731159928, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004686, ts 1040209896, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005614, ts 011680, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062757, ts 014880, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028275, ts 2731160088, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004687, ts 1040210056, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005615, ts 011840, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062758, ts 015040, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028276, ts 2731160248, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004688, ts 1040210216, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005616, ts 012000, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062759, ts 015200, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028277, ts 2731160408, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004689, ts 1040210376, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005617, ts 012160, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062760, ts 015360, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028278, ts 2731160568, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004690, ts 1040210536, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005618, ts 012320, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062761, ts 015520, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028279, ts 2731160728, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004691, ts 1040210696, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005619, ts 012480, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062762, ts 015680, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004692, ts 1040210856, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028280, ts 2731160888, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005620, ts 012640, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062763, ts 015840, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028281, ts 2731161048, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004693, ts 1040211016, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005621, ts 012800, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062764, ts 016000, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028282, ts 2731161208, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004694, ts 1040211176, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005622, ts 012960, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062765, ts 016160, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028283, ts 2731161368, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004695, ts 1040211336, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005623, ts 013120, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062766, ts 016320, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028284, ts 2731161528, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004696, ts 1040211496, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005624, ts 013280, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062767, ts 016480, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028285, ts 2731161688, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004697, ts 1040211656, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005625, ts 013440, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062768, ts 016640, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028286, ts 2731161848, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004698, ts 1040211816, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005626, ts 013600, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062769, ts 016800, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028287, ts 2731162008, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004699, ts 1040211976, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005627, ts 013760, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062770, ts 016960, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028288, ts 2731162168, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004700, ts 1040212136, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005628, ts 013920, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062771, ts 017120, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028289, ts 2731162328, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004701, ts 1040212296, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005629, ts 014080, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062772, ts 017280, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028290, ts 2731162488, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004702, ts 1040212456, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005630, ts 014240, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062773, ts 017440, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028291, ts 2731162648, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004703, ts 1040212616, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005631, ts 014400, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062774, ts 017600, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028292, ts 2731162808, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004704, ts 1040212776, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005632, ts 014560, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062775, ts 017760, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028293, ts 2731162968, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004705, ts 1040212936, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005633, ts 014720, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062776, ts 017920, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Got RTP packet from 192.168.30.199:11742 (type 00, seq 028294, ts 2731163128, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004706, ts 1040213096, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005634, ts 014880, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062777, ts 018080, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004707, ts 1040213256, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005635, ts 015040, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062778, ts 018240, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Got RTP packet from 192.168.30.178:3000 (type 00, seq 004708, ts 1040213416, len 000160) [Mar 4 16:22:59] VERBOSE[3767] logger.c: Sent RTP packet to 192.168.30.178:3000 (type 00, seq 005636, ts 015200, len 000160) [Mar 4 16:22:59] VERBOSE[3764] logger.c: Sent RTP packet to 192.168.30.199:11742 (type 00, seq 062779, ts 018400, len 000160) [Mar 4 16:22:59] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.199:1024 ---> BYE sip:*830@192.168.30.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-uf7oydi5ugo0;rport From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as3eb13f89 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 3 BYE Max-Forwards: 70 Contact: ;flow-id=1 User-Agent: snom370/7.1.30 RTP-RxStat: Total_Rx_Pkts=97,Rx_Pkts=97,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=89,Tx_Pkts=89,Remote_Tx_Pkts=0 Content-Length: 0 <-------------> [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 0: BYE sip:*830@192.168.30.254 SIP/2.0 (35) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-uf7oydi5ugo0;rport (70) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 2: From: "David Brillert" ;tag=wpv7cimitv (63) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 3: To: ;tag=as3eb13f89 (44) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 4: Call-ID: 3c2ba58f034d-6mj2z6nzk3yu (34) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 5: CSeq: 3 BYE (11) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 6: Max-Forwards: 70 (16) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 7: Contact: ;flow-id=1 (63) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 8: User-Agent: snom370/7.1.30 (26) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 9: RTP-RxStat: Total_Rx_Pkts=97,Rx_Pkts=97,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 (76) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 10: RTP-TxStat: Total_Tx_Pkts=89,Tx_Pkts=89,Remote_Tx_Pkts=0 (56) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 11: Content-Length: 0 (17) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Header 12: (0) [Mar 4 16:22:59] VERBOSE[2698] logger.c: --- (12 headers 0 lines) --- [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: = No match Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag 4a5b0efb6b5bdec Our tag: as47c03d0d [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: = Found Their Call ID: 3c2ba58f034d-6mj2z6nzk3yu Their Tag wpv7cimitv Our tag: as3eb13f89 [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Mar 4 16:22:59] VERBOSE[2698] logger.c: Sending to 192.168.30.199 : 1024 (NAT) [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Setting SIP_ALREADYGONE on dialog 3c2ba58f034d-6mj2z6nzk3yu [Mar 4 16:22:59] DEBUG[2698] chan_sip.c: Received bye, issuing owner hangup [Mar 4 16:22:59] VERBOSE[2698] logger.c: <--- Transmitting (NAT) to 192.168.30.199:1024 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.30.199:1025;branch=z9hG4bK-uf7oydi5ugo0;received=192.168.30.199;rport=1024 From: "David Brillert" ;tag=wpv7cimitv To: ;tag=as3eb13f89 Call-ID: 3c2ba58f034d-6mj2z6nzk3yu CSeq: 3 BYE User-Agent: Asterisk PBX (Fireworx) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Mar 4 16:22:59] DEBUG[3764] channel.c: Soft-Hanging up channel 'SIP/6004-09807780' [Mar 4 16:22:59] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel meetme:1458848547d [Mar 4 16:22:59] DEBUG[3767] channel.c: Hanging up channel 'Zap/pseudo-1157541001' [Mar 4 16:22:59] DEBUG[3767] chan_zap.c: zt_hangup(Zap/pseudo-1157541001) [Mar 4 16:22:59] DEBUG[3767] chan_zap.c: Hangup: channel: -2 index = 0, normal = 134, callwait = -1, thirdcall = -1 [Mar 4 16:22:59] DEBUG[3767] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/pseudo-1157541001 [Mar 4 16:22:59] DEBUG[3767] chan_zap.c: Updated conferencing on -2, with 0 conference users [Mar 4 16:22:59] VERBOSE[3767] logger.c: -- Hungup 'Zap/pseudo-1157541001' [Mar 4 16:22:59] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel Zap/pseudo-1157541001 [Mar 4 16:22:59] DEBUG[3767] devicestate.c: Notification of state change to be queued on device/channel Zap/pseudo [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is 's' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is 'zap-incoming' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is 'Zap/pseudo-1157541001' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '2008-03-04 16:22:57' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '2008-03-04 16:22:59' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '2' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '0' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is 'NO ANSWER' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is 'DOCUMENTATION' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '1204665777.4' [Mar 4 16:22:59] DEBUG[3767] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Checking if I can find provider for "meetme" - number: 1458848547d [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Checking provider SLA with meetme [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Checking provider Meetme with meetme [Mar 4 16:22:59] DEBUG[3767] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. [Mar 4 16:22:59] DEBUG[3767] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2008-03-04 16:22:57','','','s','zap-incoming', 'Zap/pseudo-1157541001','','','',2,0,'NO ANSWER',3,'','1204665777.4','') [Mar 4 16:22:59] DEBUG[3764] channel.c: Hanging up channel 'SIP/6004-09807780' [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Hangup call SIP/6004-09807780, SIP callid 48749c7b400d493b5427d02803f663a3@192.168.30.254) [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: update_call_counter(6004) - decrement call limit counter on hangup [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Updating call counter for outgoing call [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Call to peer '6004' removed from call limit 4 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6004 [Mar 4 16:22:59] VERBOSE[3764] logger.c: Scheduling destruction of SIP dialog '48749c7b400d493b5427d02803f663a3@192.168.30.254' in 6400 ms (Method: INVITE) [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Strict routing enforced for session 48749c7b400d493b5427d02803f663a3@192.168.30.254 [Mar 4 16:22:59] VERBOSE[3764] logger.c: set_destination: Parsing for address/port to send to [Mar 4 16:22:59] VERBOSE[3764] logger.c: set_destination: set destination to 192.168.30.178, port 5060 [Mar 4 16:22:59] VERBOSE[3764] logger.c: Reliably Transmitting (no NAT) to 192.168.30.178:5060: BYE sip:6004@192.168.30.178 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK1c2c3db3;rport From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 103 BYE User-Agent: Asterisk PBX (Fireworx) Max-Forwards: 70 Remote-Party-ID: "David Brillert" ;privacy=off;screen=no Content-Length: 0 --- [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6004-09807780 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6004 [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '6003' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '6003' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is 's' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is 'default-super' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is 'SIP/6004-09807780' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '2008-03-04 16:22:57' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '2008-03-04 16:22:57' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '2008-03-04 16:22:59' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '2' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '2' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is 'ANSWERED' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is 'DOCUMENTATION' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '1204665777.3' [Mar 4 16:22:59] DEBUG[3764] pbx.c: Function result is '' [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for meetme:1458848547d - state 4 (Invalid) [Mar 4 16:22:59] DEBUG[3764] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. [Mar 4 16:22:59] DEBUG[3764] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2008-03-04 16:22:57','6003','6003','s','default-super', 'SIP/6004-09807780','','','',2,2,'ANSWERED',3,'','1204665777.3','') [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Zap - pseudo-1157541001 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for Zap/pseudo-1157541001 - state 0 (Unknown) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for Zap - pseudo [Mar 4 16:22:59] DEBUG[3764] pbx.c: Spawn extension (default-super,*830,6) exited non-zero on 'SIP/6003-097fcee8' [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'Zap/pseudo-1157541001' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] VERBOSE[3764] logger.c: == Spawn extension (default-super, *830, 6) exited non-zero on 'SIP/6003-097fcee8' [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for Zap/pseudo - state 0 (Unknown) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6004 - state 1 (Not in use) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:59] DEBUG[3764] channel.c: Soft-Hanging up channel 'SIP/6003-097fcee8' [Mar 4 16:22:59] DEBUG[3764] channel.c: Hanging up channel 'SIP/6003-097fcee8' [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Hangup call SIP/6003-097fcee8, SIP callid 3c2ba58f034d-6mj2z6nzk3yu) [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: update_call_counter(6003) - decrement call limit counter on hangup [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Updating call counter for incoming call [Mar 4 16:22:59] DEBUG[3764] chan_sip.c: Call from peer '6003' removed from call limit 4 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6003 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6003-097fcee8 [Mar 4 16:22:59] DEBUG[3764] devicestate.c: Notification of state change to be queued on device/channel SIP/6003 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'Zap/pseudo' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6004' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004-09807780 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6004-09807780 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6004-09807780 - state 4 (Invalid) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6004 - state 1 (Not in use) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6004 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6004 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6003 - state 1 (Not in use) [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6004-09807780' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6004' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6003' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003-097fcee8 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6003-097fcee8 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6003-097fcee8 - state 4 (Invalid) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:59] DEBUG[2689] devicestate.c: Changing state for SIP/6003 - state 1 (Not in use) [Mar 4 16:22:59] DEBUG[2689] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Mar 4 16:22:59] DEBUG[2689] chan_sip.c: Checking device state for peer 6003 [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6003-097fcee8' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] DEBUG[2733] app_queue.c: Device 'SIP/6003' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 4 16:22:59] VERBOSE[2698] logger.c: Really destroying SIP dialog '3c2ba58f034d-6mj2z6nzk3yu' Method: BYE [Mar 4 16:23:00] VERBOSE[2698] logger.c: <--- SIP read from 192.168.30.178:5060 ---> SIP/2.0 200 OK Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 CSeq: 103 BYE From: "David Brillert" ;tag=as47c03d0d To: ;tag=4a5b0efb6b5bdec Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK1c2c3db3;rport Content-Length: 0 Supported: replaces User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 <-------------> [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 1: Call-ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 (56) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 2: CSeq: 103 BYE (13) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 3: From: "David Brillert" ;tag=as47c03d0d (63) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 4: To: ;tag=4a5b0efb6b5bdec (49) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.30.254:5060;branch=z9hG4bK1c2c3db3;rport (65) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 6: Content-Length: 0 (17) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 7: Supported: replaces (19) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 8: User-Agent: Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45 (71) [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Header 9: (0) [Mar 4 16:23:00] VERBOSE[2698] logger.c: --- (9 headers 0 lines) --- [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: = Found Their Call ID: 48749c7b400d493b5427d02803f663a3@192.168.30.254 Their Tag 4a5b0efb6b5bdec Our tag: as47c03d0d [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #111 [Mar 4 16:23:00] DEBUG[2698] chan_sip.c: Stopping retransmission on '48749c7b400d493b5427d02803f663a3@192.168.30.254' of Request 103: Match Found [Mar 4 16:23:00] VERBOSE[2698] logger.c: Really destroying SIP dialog '48749c7b400d493b5427d02803f663a3@192.168.30.254' Method: INVITE