[Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 0 [ 60]: INVITE sip:dialednumber@212.91.252.8:5060;user=phone SIP/2.0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 1 [ 24]: Allow: UPDATE,REFER,INFO [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 2 [ 58]: Call-ID: 08704-FO-0029d15b-4fd540367@sip.sipprovider.de [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 3 [ 34]: Contact: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 4 [ 29]: Content-Type: application/sdp [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 5 [ 20]: CSeq: 1762784 INVITE [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 6 [ 89]: From: ;tag=08704-EL-0029d15c-118d30460 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 7 [ 16]: Max-Forwards: 28 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 8 [ 63]: P-Asserted-Identity: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 9 [ 46]: To: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 10 [ 35]: User-Agent: Cirpack/v4.41e (gw_sip) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 11 [ 63]: Via: SIP/2.0/UDP 212.91.250.104:5060;branch=z9hG4bK-1D6C-37929B [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 12 [ 19]: Content-Length: 266 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 13 [ 0]: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 0 [ 3]: v=0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 1 [ 53]: o=cp10 128086672451 128086672451 IN IP4 212.91.250.74 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 2 [ 10]: s=SIP Call [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 3 [ 22]: c=IN IP4 212.91.250.74 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 4 [ 5]: t=0 0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 5 [ 28]: m=audio 7450 RTP/AVP 8 0 101 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 6 [ 7]: b=AS:64 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 7 [ 22]: a=rtpmap:8 PCMA/8000/1 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 8 [ 22]: a=rtpmap:0 PCMU/8000/1 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 9 [ 33]: a=rtpmap:101 telephone-event/8000 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 10 [ 15]: a=fmtp:101 0-15 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Body 12 [ 10]: a=sendrecv [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: --- (13 headers 13 lines) --- [Aug 3 22:18:05] DEBUG[31544] acl.c: Found IP address for this socket [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 212.91.252.8:5060 [Aug 3 22:18:05] VERBOSE[31544] netsock.c: == Using SIP RTP CoS mark 5 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Setting NAT on RTP to Off [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Allocating new SIP dialog for 08704-FO-0029d15b-4fd540367@sip.sipprovider.de - INVITE (With RTP) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Sending to 212.91.250.104 : 5060 (no NAT) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Initializing initreq for method INVITE - callid 08704-FO-0029d15b-4fd540367@sip.sipprovider.de [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Using INVITE request as basis request - 08704-FO-0029d15b-4fd540367@sip.sipprovider.de [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found peer 'sipprovider-in' for 'customernumber' from 212.91.250.104:5060 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Setting NAT on RTP to Off [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing session-level SDP o=cp10 128086672451 128086672451 IN IP4 212.91.250.74... UNSUPPORTED. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing session-level SDP s=SIP Call... UNSUPPORTED. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing session-level SDP c=IN IP4 212.91.250.74... OK. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found RTP audio format 8 [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found RTP audio format 0 [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found RTP audio format 101 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP b=AS:64... UNSUPPORTED. [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found audio description format PCMA for ID 8 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000/1... OK. [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found audio description format PCMU for ID 0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000/1... OK. [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Found audio description format telephone-event for ID 101 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0xc (ulaw|alaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0xc (ulaw|alaw) [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Peer audio RTP is at port 212.91.250.74:7450 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: We're settling with these formats: 0xc (ulaw|alaw) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Checking SIP call limits for device [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Updating call counter for incoming call [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: Looking for dialednumber in sipprovider-in (domain 212.91.252.8) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: *** Our native formats are 0x8 (alaw) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: *** Joint capabilities are 0xc (ulaw|alaw) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: *** Our capabilities are 0xc (ulaw|alaw) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: This channel will not be able to handle video. [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: build_route: Contact hop: [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: list_route: hop: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: SIP/sipprovider-in-000003f0: New call is still down.... Trying... [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 212.91.250.104:5060 [Aug 3 22:18:05] DEBUG[31513] devicestate.c: No provider found, checking channel drivers for SIP - sipprovider-in [Aug 3 22:18:05] DEBUG[31513] chan_sip.c: Checking device state for peer sipprovider-in [Aug 3 22:18:05] DEBUG[9727] pbx.c: Launching 'Ringing' [Aug 3 22:18:05] DEBUG[31513] devicestate.c: Changing state for SIP/sipprovider-in - state 1 (Not in use) [Aug 3 22:18:05] DEBUG[31513] devicestate.c: device 'SIP/sipprovider-in' state '1' [Aug 3 22:18:05] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:1] Ringing("SIP/sipprovider-in-000003f0", "") in new stack [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: Trying to put 'SIP/2.0 180' onto UDP socket destined for 212.91.250.104:5060 [Aug 3 22:18:05] DEBUG[31543] app_queue.c: Device 'SIP/sipprovider-in' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 3 22:18:05] DEBUG[9727] pbx.c: Launching 'Answer' [Aug 3 22:18:05] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:2] Answer("SIP/sipprovider-in-000003f0", "") in new stack [Aug 3 22:18:05] DEBUG[31513] devicestate.c: No provider found, checking channel drivers for SIP - sipprovider-in [Aug 3 22:18:05] DEBUG[31513] chan_sip.c: Checking device state for peer sipprovider-in [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: SIP answering channel: SIP/sipprovider-in-000003f0 [Aug 3 22:18:05] DEBUG[31513] devicestate.c: Changing state for SIP/sipprovider-in - state 1 (Not in use) [Aug 3 22:18:05] DEBUG[9727] rtp.c: Setting the marker bit due to a source update [Aug 3 22:18:05] DEBUG[31513] devicestate.c: device 'SIP/sipprovider-in' state '1' [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: Setting framing from config on incoming call [Aug 3 22:18:05] DEBUG[31543] app_queue.c: Device 'SIP/sipprovider-in' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: ** Our capability: 0xc (ulaw|alaw) Video flag: True Text flag: True [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: Audio is at 212.91.252.8 port 12624 [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: Adding codec 0x8 (alaw) to SDP [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: -- Done with adding codecs to SDP [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: Done building SDP. Settling with this capability: 0xc (ulaw|alaw) [Aug 3 22:18:05] VERBOSE[9727] chan_sip.c: [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #54770 [Aug 3 22:18:05] DEBUG[9727] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 212.91.250.104:5060 [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 0 [ 41]: ACK sip:dialednumber@212.91.252.8 SIP/2.0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 1 [ 58]: Call-ID: 08704-FO-0029d15b-4fd540367@sip.sipprovider.de [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 2 [ 34]: Contact: [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 3 [ 17]: CSeq: 1762784 ACK [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 4 [ 89]: From: ;tag=08704-EL-0029d15c-118d30460 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 5 [ 16]: Max-Forwards: 28 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 6 [ 61]: To: ;tag=as5baa4fa9 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 7 [ 35]: User-Agent: Cirpack/v4.41e (gw_sip) [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 8 [ 63]: Via: SIP/2.0/UDP 212.91.250.104:5060;branch=z9hG4bK-1B91-37929D [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Header 10 [ 0]: [Aug 3 22:18:05] VERBOSE[31544] chan_sip.c: --- (10 headers 0 lines) --- [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #54770 [Aug 3 22:18:05] DEBUG[31544] chan_sip.c: Stopping retransmission on '08704-FO-0029d15b-4fd540367@sip.sipprovider.de' of Response 1762784: Match Found [Aug 3 22:18:05] DEBUG[9727] pbx.c: Launching 'WaitExten' [Aug 3 22:18:05] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:3] WaitExten("SIP/sipprovider-in-000003f0", "2") in new stack [Aug 3 22:18:05] DEBUG[9728] manager.c: Manager received command 'login' [Aug 3 22:18:05] VERBOSE[9728] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:05] DEBUG[9729] manager.c: Manager received command 'login' [Aug 3 22:18:05] VERBOSE[9729] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:05] DEBUG[9729] manager.c: Manager received command 'Command' [Aug 3 22:18:05] DEBUG[9729] manager.c: Manager received command 'Command' [Aug 3 22:18:05] DEBUG[9728] manager.c: Manager received command 'Command' [Aug 3 22:18:05] DEBUG[9728] manager.c: Manager received command 'Command' [Aug 3 22:18:05] VERBOSE[9729] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:05] VERBOSE[9728] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:07] VERBOSE[9727] pbx.c: -- Timeout on SIP/sipprovider-in-000003f0, continuing... [Aug 3 22:18:07] DEBUG[9727] pbx.c: Launching 'Set' [Aug 3 22:18:07] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:4] Set("SIP/sipprovider-in-000003f0", "CHANNEL(musicclass)=meebl") in new stack [Aug 3 22:18:07] DEBUG[9727] pbx.c: Launching 'Set' [Aug 3 22:18:07] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:5] Set("SIP/sipprovider-in-000003f0", "CHANNEL(language)=de") in new stack [Aug 3 22:18:07] DEBUG[9727] pbx.c: Launching 'MeetMe' [Aug 3 22:18:07] VERBOSE[9727] pbx.c: -- Executing [dialednumber@sipprovider-in:6] MeetMe("SIP/sipprovider-in-000003f0", ",Msp") in new stack [Aug 3 22:18:07] DEBUG[9727] channel.c: Set channel SIP/sipprovider-in-000003f0 to write format slin [Aug 3 22:18:07] DEBUG[9727] rtp.c: Ooh, format changed from unknown to alaw [Aug 3 22:18:07] DEBUG[9727] rtp.c: Created smoother: format: 8 ms: 20 len: 160 [Aug 3 22:18:07] DEBUG[9727] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Aug 3 22:18:07] VERBOSE[9727] file.c: -- Playing 'conf-getconfno.slin' (language 'de') [Aug 3 22:18:09] DEBUG[9730] manager.c: Manager received command 'login' [Aug 3 22:18:09] VERBOSE[9730] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:09] DEBUG[9731] manager.c: Manager received command 'login' [Aug 3 22:18:09] VERBOSE[9731] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:09] DEBUG[9731] manager.c: Manager received command 'Command' [Aug 3 22:18:09] DEBUG[9730] manager.c: Manager received command 'Command' [Aug 3 22:18:09] DEBUG[9731] manager.c: Manager received command 'Command' [Aug 3 22:18:09] DEBUG[9730] manager.c: Manager received command 'Command' [Aug 3 22:18:09] VERBOSE[9731] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:09] DEBUG[9730] manager.c: Manager received command 'Command' [Aug 3 22:18:10] VERBOSE[9730] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:11] DEBUG[9727] channel.c: Scheduling timer at (99 requested / 99 actual) timer ticks per second [Aug 3 22:18:11] DEBUG[9727] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 3 22:18:11] DEBUG[9727] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 3 22:18:11] DEBUG[9727] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Aug 3 22:18:11] DEBUG[9727] channel.c: Set channel SIP/sipprovider-in-000003f0 to write format alaw [Aug 3 22:18:12] DEBUG[9732] manager.c: Manager received command 'login' [Aug 3 22:18:12] VERBOSE[9732] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:12] DEBUG[9732] manager.c: Manager received command 'Command' [Aug 3 22:18:12] DEBUG[9733] manager.c: Manager received command 'login' [Aug 3 22:18:12] VERBOSE[9733] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:12] DEBUG[9733] manager.c: Manager received command 'Command' [Aug 3 22:18:12] DEBUG[9732] manager.c: Manager received command 'Command' [Aug 3 22:18:12] DEBUG[9733] manager.c: Manager received command 'Command' [Aug 3 22:18:12] DEBUG[9732] manager.c: Manager received command 'Command' [Aug 3 22:18:12] VERBOSE[9733] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:12] VERBOSE[9732] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:15] DEBUG[9734] manager.c: Manager received command 'login' [Aug 3 22:18:15] DEBUG[9735] manager.c: Manager received command 'login' [Aug 3 22:18:15] VERBOSE[9734] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:15] VERBOSE[9735] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:15] DEBUG[9735] manager.c: Manager received command 'Command' [Aug 3 22:18:15] DEBUG[9734] manager.c: Manager received command 'Command' [Aug 3 22:18:15] DEBUG[9735] manager.c: Manager received command 'Command' [Aug 3 22:18:15] DEBUG[9734] manager.c: Manager received command 'Command' [Aug 3 22:18:15] VERBOSE[9735] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:15] VERBOSE[9734] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Allocating new SIP dialog for 1f7f694644dc9dd4607b2a9147efbb3c@212.91.252.8 - OPTIONS (No RTP) [Aug 3 22:18:17] DEBUG[31544] acl.c: Found IP address for this socket [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 212.91.252.8:5060 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Initializing initreq for method OPTIONS - callid 033d22337a01d64d25aa744f1d2aa419@212.91.252.8 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 0 [ 41]: OPTIONS sip:sip.sipprovider.de SIP/2.0 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 212.91.252.8:5060;branch=z9hG4bK352d9657;rport [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 3 [ 59]: From: "asterisk" ;tag=as1d1ef65a [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 4 [ 31]: To: [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 5 [ 36]: Contact: [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 6 [ 54]: Call-ID: 033d22337a01d64d25aa744f1d2aa419@212.91.252.8 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 8 [ 21]: User-Agent: sidex pbx [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 9 [ 35]: Date: Tue, 03 Aug 2010 20:18:17 GMT [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Aug 3 22:18:17] VERBOSE[31544] chan_sip.c: Reliably Transmitting (no NAT) to 212.91.250.104:5060: [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #54772 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 212.91.250.104:5060 [Aug 3 22:18:17] VERBOSE[31544] chan_sip.c: [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 0 [ 27]: SIP/2.0 501 Not Implemented [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 1 [ 24]: Allow: UPDATE,REFER,INFO [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 2 [ 54]: Call-ID: 033d22337a01d64d25aa744f1d2aa419@212.91.252.8 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 3 [ 17]: CSeq: 102 OPTIONS [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 4 [ 59]: From: "asterisk" ;tag=as1d1ef65a [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 5 [ 31]: Server: Cirpack/v4.41e (gw_sip) [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 6 [ 63]: To: ;tag=00-08159-0029d1a0-1910feef3 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 7 [ 90]: Via: SIP/2.0/UDP 212.91.252.8:5060;received=212.91.252.8;rport=5060;branch=z9hG4bK352d9657 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 8 [ 17]: Content-Length: 0 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Header 9 [ 0]: [Aug 3 22:18:17] VERBOSE[31544] chan_sip.c: --- (9 headers 0 lines) --- [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #54772 [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Stopping retransmission on '033d22337a01d64d25aa744f1d2aa419@212.91.252.8' of Request 102: Match Found [Aug 3 22:18:17] DEBUG[31544] chan_sip.c: Destroying SIP dialog 033d22337a01d64d25aa744f1d2aa419@212.91.252.8 [Aug 3 22:18:17] VERBOSE[31544] chan_sip.c: Really destroying SIP dialog '033d22337a01d64d25aa744f1d2aa419@212.91.252.8' Method: OPTIONS [Aug 3 22:18:17] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000003 (len = 4) [Aug 3 22:18:17] DEBUG[9727] rtp.c: Sending dtmf: 51 (3), at 212.91.250.74 [Aug 3 22:18:17] DTMF[9727] channel.c: DTMF begin '3' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:17] DTMF[9727] channel.c: DTMF begin ignored '3' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:17] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000003 (len = 4) [Aug 3 22:18:17] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000003 (len = 4) [Aug 3 22:18:17] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000003 (len = 4) [Aug 3 22:18:17] DEBUG[9727] rtp.c: Sending dtmf: 51 (3), at 212.91.250.74 [Aug 3 22:18:17] DTMF[9727] channel.c: DTMF end '3' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:17] DTMF[9727] channel.c: DTMF end passthrough '3' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:18] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:18] DEBUG[9727] rtp.c: Sending dtmf: 50 (2), at 212.91.250.74 [Aug 3 22:18:18] DTMF[9727] channel.c: DTMF begin '2' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:18] DTMF[9727] channel.c: DTMF begin ignored '2' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:18] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:18] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:18] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:18] DEBUG[9727] rtp.c: Sending dtmf: 50 (2), at 212.91.250.74 [Aug 3 22:18:18] DTMF[9727] channel.c: DTMF end '2' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:18] DTMF[9727] channel.c: DTMF end passthrough '2' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:19] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000001 (len = 4) [Aug 3 22:18:19] DEBUG[9727] rtp.c: Sending dtmf: 49 (1), at 212.91.250.74 [Aug 3 22:18:19] DTMF[9727] channel.c: DTMF begin '1' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:19] DTMF[9727] channel.c: DTMF begin ignored '1' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:19] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000001 (len = 4) [Aug 3 22:18:19] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000001 (len = 4) [Aug 3 22:18:19] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000001 (len = 4) [Aug 3 22:18:19] DEBUG[9727] rtp.c: Sending dtmf: 49 (1), at 212.91.250.74 [Aug 3 22:18:19] DTMF[9727] channel.c: DTMF end '1' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:19] DTMF[9727] channel.c: DTMF end passthrough '1' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:19] DEBUG[9736] manager.c: Manager received command 'login' [Aug 3 22:18:19] VERBOSE[9736] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:19] DEBUG[9737] manager.c: Manager received command 'login' [Aug 3 22:18:19] VERBOSE[9737] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:19] DEBUG[9737] manager.c: Manager received command 'Command' [Aug 3 22:18:19] DEBUG[9736] manager.c: Manager received command 'Command' [Aug 3 22:18:19] DEBUG[9737] manager.c: Manager received command 'Command' [Aug 3 22:18:19] DEBUG[9736] manager.c: Manager received command 'Command' [Aug 3 22:18:19] VERBOSE[9737] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:20] DEBUG[9736] manager.c: Manager received command 'Command' [Aug 3 22:18:20] VERBOSE[9736] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: Sending dtmf: 50 (2), at 212.91.250.74 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF begin '2' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF begin ignored '2' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000002 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: Sending dtmf: 50 (2), at 212.91.250.74 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF end '2' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF end passthrough '2' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000004 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: Sending dtmf: 52 (4), at 212.91.250.74 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF begin '4' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF begin ignored '4' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000004 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000004 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000004 (len = 4) [Aug 3 22:18:21] DEBUG[9727] rtp.c: Sending dtmf: 52 (4), at 212.91.250.74 [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF end '4' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:21] DTMF[9727] channel.c: DTMF end passthrough '4' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:22] DEBUG[9738] manager.c: Manager received command 'login' [Aug 3 22:18:22] VERBOSE[9738] manager.c: == Manager 'meebl' logged on from 10.1.10.10 [Aug 3 22:18:22] DEBUG[9738] manager.c: Manager received command 'Command' [Aug 3 22:18:22] DEBUG[9739] manager.c: Manager received command 'login' [Aug 3 22:18:22] VERBOSE[9739] manager.c: == Manager 'meebl' logged on from 10.1.10.20 [Aug 3 22:18:22] DEBUG[9739] manager.c: Manager received command 'Command' [Aug 3 22:18:22] DEBUG[9738] manager.c: Manager received command 'Command' [Aug 3 22:18:22] DEBUG[9739] manager.c: Manager received command 'Command' [Aug 3 22:18:22] DEBUG[9738] manager.c: Manager received command 'Command' [Aug 3 22:18:22] VERBOSE[9739] manager.c: == Manager 'meebl' logged off from 10.1.10.20 [Aug 3 22:18:22] VERBOSE[9738] manager.c: == Manager 'meebl' logged off from 10.1.10.10 [Aug 3 22:18:22] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000005 (len = 4) [Aug 3 22:18:22] DEBUG[9727] rtp.c: Sending dtmf: 53 (5), at 212.91.250.74 [Aug 3 22:18:22] DTMF[9727] channel.c: DTMF begin '5' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:22] DTMF[9727] channel.c: DTMF begin ignored '5' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:22] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000005 (len = 4) [Aug 3 22:18:22] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000005 (len = 4) [Aug 3 22:18:22] DEBUG[9727] rtp.c: - RTP 2833 Event: 00000005 (len = 4) [Aug 3 22:18:22] DEBUG[9727] rtp.c: Sending dtmf: 53 (5), at 212.91.250.74 [Aug 3 22:18:22] DTMF[9727] channel.c: DTMF end '5' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:22] DTMF[9727] channel.c: DTMF end passthrough '5' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:23] DEBUG[9727] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:23] DEBUG[9727] rtp.c: Sending dtmf: 35 (#), at 212.91.250.74 [Aug 3 22:18:23] DTMF[9727] channel.c: DTMF begin '#' received on SIP/sipprovider-in-000003f0 [Aug 3 22:18:23] DTMF[9727] channel.c: DTMF begin ignored '#' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:23] DEBUG[9727] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:23] DEBUG[9727] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:23] DEBUG[9727] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:23] DEBUG[9727] rtp.c: Sending dtmf: 35 (#), at 212.91.250.74 [Aug 3 22:18:23] DTMF[9727] channel.c: DTMF end '#' received on SIP/sipprovider-in-000003f0, duration 200 ms [Aug 3 22:18:23] DTMF[9727] channel.c: DTMF end passthrough '#' on SIP/sipprovider-in-000003f0 [Aug 3 22:18:23] DEBUG[9727] app_meetme.c: The requested confno is '321245'? [Aug 3 22:18:23] DEBUG[9727] app_meetme.c: Does conf 321245 match 321245? [Aug 3 22:18:23] DEBUG[9727] res_config_mysql.c: MySQL RealTime: Connection okay. [Aug 3 22:18:23] DEBUG[9727] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE meetme SET members = '3' WHERE confno = '321245' [Aug 3 22:18:23] DEBUG[9727] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: meetme [Aug 3 22:18:23] DEBUG[9727] channel.c: Set channel SIP/sipprovider-in-000003f0 to write format slin [Aug 3 22:18:23] DEBUG[9727] channel.c: Set channel SIP/sipprovider-in-000003f0 to read format slin [Aug 3 22:18:23] DEBUG[9727] app_meetme.c: Placed channel SIP/sipprovider-in-000003f0 in DAHDI conf 1022 [Aug 3 22:18:23] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[9727] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Aug 3 22:18:24] DEBUG[9727] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Aug 3 22:18:24] DEBUG[9727] rtp.c: Difference is 100960, ms is 12640 [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[9535] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:24] DEBUG[9535] rtp.c: Sending dtmf: 35 (#), at 212.91.250.254 [Aug 3 22:18:24] DTMF[9535] channel.c: DTMF begin '#' received on SIP/sipprovider-in-000003ef [Aug 3 22:18:24] DTMF[9535] channel.c: DTMF begin passthrough '#' on SIP/sipprovider-in-000003ef [Aug 3 22:18:24] DEBUG[9535] app_meetme.c: Got unrecognized frame on channel SIP/sipprovider-in-000003ef, f->frametype=12,f->subclass=35 [Aug 3 22:18:24] DEBUG[9535] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:24] DEBUG[9535] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:24] DEBUG[9535] rtp.c: - RTP 2833 Event: 0000000b (len = 4) [Aug 3 22:18:24] DEBUG[9535] rtp.c: Sending dtmf: 35 (#), at 212.91.250.254 [Aug 3 22:18:24] DTMF[9535] channel.c: DTMF end '#' received on SIP/sipprovider-in-000003ef, duration 200 ms [Aug 3 22:18:24] DTMF[9535] channel.c: DTMF end accepted with begin '#' on SIP/sipprovider-in-000003ef [Aug 3 22:18:24] DTMF[9535] channel.c: DTMF end passthrough '#' on SIP/sipprovider-in-000003ef [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[9535] res_config_mysql.c: MySQL RealTime: Connection okay. [Aug 3 22:18:24] DEBUG[9535] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE meetme SET members = '2' WHERE confno = '321245' [Aug 3 22:18:24] DEBUG[9535] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: meetme [Aug 3 22:18:24] DEBUG[9535] pbx.c: Launching 'Hangup' [Aug 3 22:18:24] VERBOSE[9535] pbx.c: -- Executing [dialednumber@sipprovider-in:7] Hangup("SIP/sipprovider-in-000003ef", "") in new stack [Aug 3 22:18:24] DEBUG[9535] pbx.c: Spawn extension (sipprovider-in,dialednumber,7) exited non-zero on 'SIP/sipprovider-in-000003ef' [Aug 3 22:18:24] VERBOSE[9535] pbx.c: == Spawn extension (sipprovider-in, dialednumber, 7) exited non-zero on 'SIP/sipprovider-in-000003ef' [Aug 3 22:18:24] DEBUG[9535] channel.c: Soft-Hanging up channel 'SIP/sipprovider-in-000003ef' [Aug 3 22:18:24] DEBUG[9535] channel.c: Hanging up channel 'SIP/sipprovider-in-000003ef' [Aug 3 22:18:24] DEBUG[9535] chan_sip.c: Hangup call SIP/sipprovider-in-000003ef, SIP callid 08369-VJ-0029cf63-38972d777@sip.sipprovider.de [Aug 3 22:18:24] VERBOSE[9535] chan_sip.c: Scheduling destruction of SIP dialog '08369-VJ-0029cf63-38972d777@sip.sipprovider.de' in 6400 ms (Method: ACK) [Aug 3 22:18:24] DEBUG[9535] chan_sip.c: Strict routing enforced for session 08369-VJ-0029cf63-38972d777@sip.sipprovider.de [Aug 3 22:18:24] VERBOSE[9535] chan_sip.c: set_destination: Parsing for address/port to send to [Aug 3 22:18:24] VERBOSE[9535] chan_sip.c: set_destination: set destination to 212.91.250.104, port 5060 [Aug 3 22:18:24] VERBOSE[9535] chan_sip.c: Reliably Transmitting (no NAT) to 212.91.250.104:5060: [Aug 3 22:18:24] DEBUG[9535] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #54776 [Aug 3 22:18:24] DEBUG[9535] chan_sip.c: Trying to put 'BYE sip:212' onto UDP socket destined for 212.91.250.104:5060 [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[9535] cdr_addon_mysql.c: Inserting a CDR record. [Aug 3 22:18:24] DEBUG[9535] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (calldate,src,dst,dcontext,channel,lastapp,duration,billsec,disposition,amaflags,uniqueid) VALUES ('2010-08-03 22:16:49','494524900467','dialednumber','sipprovider-in','SIP/sipprovider-in-000003ef','Hangup','95','95','ANSWERED','3','1280866609.2420') [Aug 3 22:18:24] DEBUG[31513] devicestate.c: No provider found, checking channel drivers for SIP - sipprovider-in [Aug 3 22:18:24] DEBUG[31513] chan_sip.c: Checking device state for peer sipprovider-in [Aug 3 22:18:24] DEBUG[31513] devicestate.c: Changing state for SIP/sipprovider-in - state 1 (Not in use) [Aug 3 22:18:24] DEBUG[31513] devicestate.c: device 'SIP/sipprovider-in' state '1' [Aug 3 22:18:24] DEBUG[31543] app_queue.c: Device 'SIP/sipprovider-in' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Aug 3 22:18:24] VERBOSE[31544] chan_sip.c: [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 1 [ 58]: Call-ID: 08369-VJ-0029cf63-38972d777@sip.sipprovider.de [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 2 [ 13]: CSeq: 102 BYE [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 3 [ 63]: From: ;tag=as49eedd3d [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 4 [ 31]: Server: Cirpack/v4.41e (gw_sip) [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 5 [ 87]: To: ;tag=08369-TQ-0029cf64-19f8f6a43 [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 6 [ 90]: Via: SIP/2.0/UDP 212.91.252.8:5060;received=212.91.252.8;rport=5060;branch=z9hG4bK3a9ac4cc [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Header 8 [ 0]: [Aug 3 22:18:24] VERBOSE[31544] chan_sip.c: --- (8 headers 0 lines) --- [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #54776 [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Stopping retransmission on '08369-VJ-0029cf63-38972d777@sip.sipprovider.de' of Request 102: Match Found [Aug 3 22:18:24] VERBOSE[31544] chan_sip.c: SIP Response message for INCOMING dialog BYE arrived [Aug 3 22:18:24] DEBUG[31544] chan_sip.c: Destroying SIP dialog 08369-VJ-0029cf63-38972d777@sip.sipprovider.de [Aug 3 22:18:24] VERBOSE[31544] chan_sip.c: Really destroying SIP dialog '08369-VJ-0029cf63-38972d777@sip.sipprovider.de' Method: ACK [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command' [Aug 3 22:18:24] DEBUG[1889] manager.c: Manager received command 'Command'