[Aug 20 21:04:23] VERBOSE[3884] logger.c: Asterisk Queue Logger restarted [Aug 20 21:04:31] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for eddf0000-65260c7d@192.168.105.200 - INVITE (No RTP) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Begin: parsing SIP "Supported: timer,100rel,replaces" [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Found SIP option: -timer- [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Matched SIP option: timer [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Found SIP option: -100rel- [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Matched SIP option: 100rel [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Found SIP option: -replaces- [Aug 20 21:04:31] DEBUG[1610] sip/reqresp_parser.c: Matched SIP option: replaces [Aug 20 21:04:31] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:31] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Stopping retransmission on 'eddf0000-65260c7d@192.168.105.200' of Response 483911056: Match Found [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Aug 20 21:04:31] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:31] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Using engine 'asterisk' for RTP instance '0xb73011d0' [Aug 20 21:04:31] DEBUG[1610] res_rtp_asterisk.c: Allocated port 17196 for RTP instance '0xb73011d0' [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: RTP instance '0xb73011d0' is setup and ready to go [Aug 20 21:04:31] DEBUG[1610] res_rtp_asterisk.c: Setup RTCP on RTP instance '0xb73011d0' [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Setting NAT on RTP to Off [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP o=1002 1282338361 1282338360 IN IP4 192.168.105.153... UNSUPPORTED. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP s=SIP Call... UNSUPPORTED. [Aug 20 21:04:31] DEBUG[1610] netsock2.c: Splitting '192.168.105.153' gives... [Aug 20 21:04:31] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '(null)'. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.105.153... OK. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing session-level SDP a=sendrecv... OK. [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Setting payload 18 based on m type on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Setting payload 0 based on m type on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Setting payload 8 based on m type on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Setting payload 101 based on m type on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Incorporating payload 0 on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Incorporating payload 8 on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Incorporating payload 18 on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Incorporating payload 101 on 0x5a03404 [Aug 20 21:04:31] DEBUG[1610] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb73011d0' [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Copying payload 0 from 0x5a03404 to 0xb730137c [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Copying payload 8 from 0x5a03404 to 0xb730137c [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Copying payload 18 from 0x5a03404 to 0xb730137c [Aug 20 21:04:31] DEBUG[1610] rtp_engine.c: Copying payload 101 from 0x5a03404 to 0xb730137c [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Checking SIP call limits for device 1002 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Updating call counter for incoming call [Aug 20 21:04:31] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for SIP - 1002 [Aug 20 21:04:31] DEBUG[1411] chan_sip.c: Checking device state for peer 1002 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: Changing state for SIP/1002 - state 2 (In use) [Aug 20 21:04:31] DEBUG[1411] devicestate.c: device 'SIP/1002' state '2' [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'SIP/1002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: This channel will not be able to handle video. [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: build_route: Contact hop: "1002" [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Incoming INVITE with 'timer' option enabled [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Session-Expires: 1800 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Received Min-SE: 90 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Session timer started: 1352 - eddf0000-65260c7d@192.168.105.200 [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: SIP/1002-00000008: New call is still down.... Trying... [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for SIP - 1002 [Aug 20 21:04:31] DEBUG[1411] chan_sip.c: Checking device state for peer 1002 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: Changing state for SIP/1002 - state 2 (In use) [Aug 20 21:04:31] DEBUG[1411] devicestate.c: device 'SIP/1002' state '2' [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'SIP/1002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'Goto' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [4000@default:1] Goto("SIP/1002-00000008", "page,s,1") in new stack [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Goto (page,s,1) [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'Answer' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:1] Answer("SIP/1002-00000008", "") in new stack [Aug 20 21:04:31] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for SIP - 1002 [Aug 20 21:04:31] DEBUG[1411] chan_sip.c: Checking device state for peer 1002 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: Changing state for SIP/1002 - state 2 (In use) [Aug 20 21:04:31] DEBUG[1411] devicestate.c: device 'SIP/1002' state '2' [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: SIP answering channel: SIP/1002-00000008 [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'SIP/1002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[3885] res_rtp_asterisk.c: Setting the marker bit due to a source update [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: Setting framing from config on incoming call [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True Text flag: True [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: -- Done with adding codecs to SDP [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Aug 20 21:04:31] DEBUG[3885] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:31] DEBUG[3885] pbx.c: Function result is 'test' [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'Set' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:2] Set("SIP/1002-00000008", "CALLERID(name)={Page:test") in new stack [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'Set' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:3] Set("SIP/1002-00000008", "TIMEOUT(digit)=5") in new stack [Aug 20 21:04:31] VERBOSE[3885] func_timeout.c: -- Digit timeout set to 5.000 [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'SIPAddHeader' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:4] SIPAddHeader("SIP/1002-00000008", "Call-Info: Answer-After=0") in new stack [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'SIPAddHeader' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:5] SIPAddHeader("SIP/1002-00000008", "Alert-Info: info=Autoanswer") in new stack [Aug 20 21:04:31] DEBUG[3885] pbx.c: Launching 'Page' [Aug 20 21:04:31] VERBOSE[3885] pbx.c: -- Executing [s@page:6] Page("SIP/1002-00000008", "SIP/1002&MulticastRTP/basic/224.0.1.200:9999") in new stack [Aug 20 21:04:31] DEBUG[3885] netsock2.c: Splitting '224.0.1.200:9999' gives... [Aug 20 21:04:31] DEBUG[3885] netsock2.c: ...host '224.0.1.200' and port '9999'. [Aug 20 21:04:31] DEBUG[3885] rtp_engine.c: Using engine 'multicast' for RTP instance '0xb761ee48' [Aug 20 21:04:31] DEBUG[3885] rtp_engine.c: RTP instance '0xb761ee48' is setup and ready to go [Aug 20 21:04:31] DEBUG[3885] channel.c: Copying hard-transferable variable SIPADDHEADER02. [Aug 20 21:04:31] DEBUG[3885] channel.c: Copying hard-transferable variable SIPADDHEADER01. [Aug 20 21:04:31] DEBUG[3885] channel.c: Not copying variable SIPCALLID. [Aug 20 21:04:31] DEBUG[3885] channel.c: Not copying variable SIPDOMAIN. [Aug 20 21:04:31] DEBUG[3885] channel.c: Not copying variable SIPURI. [Aug 20 21:04:31] VERBOSE[3885] dial.c: -- Called basic/224.0.1.200:9999 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0xb761ee48 [Aug 20 21:04:31] DEBUG[1411] devicestate.c: Changing state for MulticastRTP/0xb761ee48 - state 0 (Unknown) [Aug 20 21:04:31] DEBUG[1411] devicestate.c: device 'MulticastRTP/0xb761ee48' state '0' [Aug 20 21:04:31] VERBOSE[3886] dial.c: -- MulticastRTP/0xb761ee48 answered [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'MulticastRTP/0xb761ee48' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[3886] app_meetme.c: The requested confno is '993944429d'? [Aug 20 21:04:31] DEBUG[3886] app_meetme.c: Building dynamic conference '993944429d' [Aug 20 21:04:31] DEBUG[3885] channel.c: Set channel SIP/1002-00000008 to write format gsm [Aug 20 21:04:31] DEBUG[3886] chan_dahdi.c: Using channel -2 [Aug 20 21:04:31] DEBUG[3886] devicestate.c: device 'DAHDI/pseudo-2098784244' state '2' [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'DAHDI/pseudo-2098784244' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[3885] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Aug 20 21:04:31] DEBUG[3885] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Aug 20 21:04:31] DEBUG[3886] channel.c: Set channel DAHDI/pseudo-2098784244 to read format slin [Aug 20 21:04:31] DEBUG[3885] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Aug 20 21:04:31] VERBOSE[3885] file.c: -- Playing 'beep.gsm' (language 'en') [Aug 20 21:04:31] DEBUG[3886] channel.c: Set channel DAHDI/pseudo-2098784244 to write format slin [Aug 20 21:04:31] VERBOSE[3886] app_meetme.c: -- Created MeetMe conference 1023 for conference '993944429d' [Aug 20 21:04:31] DEBUG[3886] devicestate.c: device 'meetme:993944429d' state '2' [Aug 20 21:04:31] DEBUG[1629] app_queue.c: Device 'meetme:993944429d' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Aug 20 21:04:31] DEBUG[3886] channel.c: Set channel MulticastRTP/0xb761ee48 to write format slin [Aug 20 21:04:31] DEBUG[3886] channel.c: Set channel MulticastRTP/0xb761ee48 to read format slin [Aug 20 21:04:31] DEBUG[3886] app_meetme.c: Placed channel MulticastRTP/0xb761ee48 in DAHDI conf 1023 [Aug 20 21:04:31] DEBUG[3886] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Aug 20 21:04:31] DEBUG[3886] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Aug 20 21:04:31] DEBUG[3885] res_rtp_asterisk.c: Forcing Marker bit, because SSRC has changed [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Aug 20 21:04:31] DEBUG[1610] chan_sip.c: Stopping retransmission on 'eddf0000-65260c7d@192.168.105.200' of Response 483911057: Match Found [Aug 20 21:04:32] DEBUG[3885] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 20 21:04:32] DEBUG[3885] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 20 21:04:32] DEBUG[3885] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 20 21:04:32] DEBUG[3885] channel.c: Set channel SIP/1002-00000008 to write format ulaw [Aug 20 21:04:32] DEBUG[3885] app_meetme.c: The requested confno is '993944429d'? [Aug 20 21:04:32] DEBUG[3885] app_meetme.c: Does conf 993944429d match 993944429d? [Aug 20 21:04:32] DEBUG[3885] channel.c: Set channel SIP/1002-00000008 to write format slin [Aug 20 21:04:32] DEBUG[3885] channel.c: Set channel SIP/1002-00000008 to read format slin [Aug 20 21:04:32] DEBUG[3885] app_meetme.c: Placed channel SIP/1002-00000008 in DAHDI conf 1023 [Aug 20 21:04:32] DEBUG[3885] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Aug 20 21:04:32] DEBUG[3885] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Aug 20 21:04:34] DEBUG[1544] res_pktccops.c: COPS: lost connection to test [Aug 20 21:04:34] DEBUG[1544] res_pktccops.c: COPS: lost connection to teszt [Aug 20 21:04:34] DEBUG[1544] res_pktccops.c: Connecting to cmts: 192.168.0.24:2126 [Aug 20 21:04:34] DEBUG[1544] res_pktccops.c: Connecting to cmts: 192.168.0.24:2126 [Aug 20 21:04:38] DEBUG[1610] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Aug 20 21:04:38] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:38] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:38] DEBUG[1610] chan_sip.c: Setting SIP_ALREADYGONE on dialog eddf0000-65260c7d@192.168.105.200 [Aug 20 21:04:38] DEBUG[1610] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb73011d0' [Aug 20 21:04:38] DEBUG[1610] chan_sip.c: Session timer stopped: -1 - eddf0000-65260c7d@192.168.105.200 [Aug 20 21:04:38] DEBUG[1610] chan_sip.c: Received bye, issuing owner hangup [Aug 20 21:04:38] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:38] NOTICE[3885] chan_sip.c: Unknown option: 5 [Aug 20 21:04:38] NOTICE[3885] chan_sip.c: Unknown option: 6 [Aug 20 21:04:38] DEBUG[3885] channel.c: Soft-Hanging up channel 'MulticastRTP/0xb761ee48' [Aug 20 21:04:38] DEBUG[3886] devicestate.c: device 'meetme:993944429d' state '1' [Aug 20 21:04:38] DEBUG[3886] channel.c: Hanging up channel 'DAHDI/pseudo-2098784244' [Aug 20 21:04:38] DEBUG[3886] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-2098784244) [Aug 20 21:04:38] DEBUG[3886] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 38, callwait = -1, thirdcall = -1 [Aug 20 21:04:38] DEBUG[1629] app_queue.c: Device 'meetme:993944429d' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 20 21:04:38] DEBUG[3886] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-2098784244 [Aug 20 21:04:38] DEBUG[3886] chan_dahdi.c: Updated conferencing on -2, with 0 conference users [Aug 20 21:04:38] VERBOSE[3886] chan_dahdi.c: -- Hungup 'DAHDI/pseudo-2098784244' [Aug 20 21:04:38] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo [Aug 20 21:04:38] DEBUG[1411] devicestate.c: Changing state for DAHDI/pseudo - state 0 (Unknown) [Aug 20 21:04:38] DEBUG[1411] devicestate.c: device 'DAHDI/pseudo' state '0' [Aug 20 21:04:38] DEBUG[1629] app_queue.c: Device 'DAHDI/pseudo' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Aug 20 21:04:38] DEBUG[3885] channel.c: Hanging up channel 'MulticastRTP/0xb761ee48' [Aug 20 21:04:38] DEBUG[3885] rtp_engine.c: Destroyed RTP instance '0xb761ee48' [Aug 20 21:04:38] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0xb761ee48 [Aug 20 21:04:38] DEBUG[3885] pbx.c: Spawn extension (page,s,6) exited non-zero on 'SIP/1002-00000008' [Aug 20 21:04:38] DEBUG[1411] devicestate.c: Changing state for MulticastRTP/0xb761ee48 - state 0 (Unknown) [Aug 20 21:04:38] VERBOSE[3885] pbx.c: == Spawn extension (page, s, 6) exited non-zero on 'SIP/1002-00000008' [Aug 20 21:04:38] DEBUG[1411] devicestate.c: device 'MulticastRTP/0xb761ee48' state '0' [Aug 20 21:04:38] DEBUG[3885] channel.c: Soft-Hanging up channel 'SIP/1002-00000008' [Aug 20 21:04:38] DEBUG[3885] channel.c: Hanging up channel 'SIP/1002-00000008' [Aug 20 21:04:38] DEBUG[3885] chan_sip.c: Hangup call SIP/1002-00000008, SIP callid eddf0000-65260c7d@192.168.105.200 [Aug 20 21:04:38] DEBUG[1629] app_queue.c: Device 'MulticastRTP/0xb761ee48' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Aug 20 21:04:38] DEBUG[3885] chan_sip.c: Updating call counter for incoming call [Aug 20 21:04:38] DEBUG[3885] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb73011d0' [Aug 20 21:04:38] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for SIP - 1002 [Aug 20 21:04:38] DEBUG[1411] chan_sip.c: Checking device state for peer 1002 [Aug 20 21:04:38] DEBUG[1411] devicestate.c: Changing state for SIP/1002 - state 1 (Not in use) [Aug 20 21:04:38] DEBUG[1411] devicestate.c: device 'SIP/1002' state '1' [Aug 20 21:04:38] DEBUG[1629] app_queue.c: Device 'SIP/1002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '2010-08-20 21:04:31' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '"{Page:test" <1002>' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is 'page' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is 'SIP/1002-00000008' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '(null)' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is 'MeetMe' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '993944429d,Atqxd' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '7' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '7' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is 'ANSWERED' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is 'DOCUMENTATION' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '(null)' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '1282356271.52' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '(null)' [Aug 20 21:04:38] DEBUG[3885] pbx.c: Function result is '(null)' [Aug 20 21:04:38] DEBUG[3885] cdr_sqlite3_custom.c: About to log: INSERT INTO cdr (calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test) VALUES ('2010-08-20 21:04:31','"{Page:test" <1002>','page','SIP/1002-00000008','','MeetMe','993944429d,Atqxd','7','7','ANSWERED','DOCUMENTATION','','1282356271.52','','') [Aug 20 21:04:38] DEBUG[1411] devicestate.c: No provider found, checking channel drivers for SIP - 1002 [Aug 20 21:04:38] DEBUG[1411] chan_sip.c: Checking device state for peer 1002 [Aug 20 21:04:38] DEBUG[1411] devicestate.c: Changing state for SIP/1002 - state 1 (Not in use) [Aug 20 21:04:38] DEBUG[1411] devicestate.c: device 'SIP/1002' state '1' [Aug 20 21:04:38] DEBUG[1629] app_queue.c: Device 'SIP/1002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 20 21:04:46] VERBOSE[3884] asterisk.c: -- Remote UNIX connection disconnected [Aug 20 21:04:55] VERBOSE[1383] asterisk.c: -- Remote UNIX connection [Aug 20 21:04:55] DEBUG[1544] res_pktccops.c: COPS: lost connection to test [Aug 20 21:04:55] DEBUG[1544] res_pktccops.c: COPS: lost connection to teszt [Aug 20 21:04:55] DEBUG[1544] res_pktccops.c: Connecting to cmts: 192.168.0.24:2126 [Aug 20 21:04:55] DEBUG[1544] res_pktccops.c: Connecting to cmts: 192.168.0.24:2126 [Aug 20 21:04:57] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edf90000-30cf642c@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:57] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:57] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:57] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:57] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:57] DEBUG[1610] chan_sip.c: Destroying SIP dialog edf90000-30cf642c@192.168.105.153 [Aug 20 21:04:58] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edf90000-186625d3@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Destroying SIP dialog edf90000-186625d3@192.168.105.153 [Aug 20 21:04:58] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edf90000-788c2e06@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Destroying SIP dialog edf90000-788c2e06@192.168.105.153 [Aug 20 21:04:58] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edf90000-bdce8ce@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Destroying SIP dialog edf90000-bdce8ce@192.168.105.153 [Aug 20 21:04:58] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edfa0000-22a5b6c2@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Destroying SIP dialog edfa0000-22a5b6c2@192.168.105.153 [Aug 20 21:04:58] DEBUG[1610] acl.c: For destination '192.168.105.153', our source address is '192.168.105.200'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.105.200:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Allocating new SIP dialog for edfa0000-1952b687@192.168.105.153 - SUBSCRIBE (No RTP) [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Contact hop: ;+sip.instance="" [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Aug 20 21:04:58] DEBUG[1610] netsock2.c: Splitting '192.168.105.153:5060' gives... [Aug 20 21:04:58] DEBUG[1610] netsock2.c: ...host '192.168.105.153' and port '5060'. [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: build_route: Retaining previous route: [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.105.153:5060 [Aug 20 21:04:58] DEBUG[1610] chan_sip.c: Destroying SIP dialog edfa0000-1952b687@192.168.105.153