16:47:42.222 dtmfbox.c dtmfbox 0.4.0
16:47:42.224 dtmfbox.c PJSIP 0.8.0
16:47:42.253 pjlib select() I/O Queue created (0x4eccd4)
16:47:42.254 sip_endpoint.c Module "mod-msg-print" registered
16:47:42.255 voip_ctrl.c Init VOIP...
16:47:42.259 voip_ctrl.c Resolve IP from host fritz.box ...
16:47:42.260 voip_ctrl.c Use IP: 192.168.178.1
16:47:42.262 udp0x4fd734 SIP UDP transport started, published address is 192.168.178.1:5061
16:47:42.263 voip_ctrl.c UDP server listening on port 5061
16:47:42.268 sip_endpoint.c Module "mod-tsx-layer" registered
16:47:42.269 sip_endpoint.c Module "mod-stateful-util" registered
16:47:42.273 sip_endpoint.c Module "mod-ua" registered
16:47:42.275 sip_endpoint.c Module "mod-100rel" registered
16:47:42.275 voip_ctrl.c VOIP callbacks initialized
16:47:42.276 sip_endpoint.c Module "mod-invite" registered
16:47:42.277 sip_endpoint.c Module "dtmfbox" registered
16:47:42.291 pjlib select() I/O Queue created (0x523a74)
16:47:42.313 voip_ctrl.c RTP/RTCP: 192.168.178.1:4000-4001
16:47:42.317 voip_ctrl.c RTP/RTCP: 192.168.178.1:4002-4003
16:47:42.319 voip_ctrl.c RTP/RTCP: 192.168.178.1:4004-4005
16:47:42.323 voip_ctrl.c RTP/RTCP: 192.168.178.1:4006-4007
16:47:42.326 voip_ctrl.c RTP/RTCP: 192.168.178.1:4008-4009
16:47:42.329 voip_ctrl.c RTP/RTCP: 192.168.178.1:4010-4011
16:47:42.332 voip_ctrl.c RTP/RTCP: 192.168.178.1:4012-4013
16:47:42.335 voip_ctrl.c RTP/RTCP: 192.168.178.1:4014-4015
16:47:42.336 voip_ctrl.c RTP/RTCP media transport created
16:47:42.340 voip_ctrl.c Start VoIP listen loop...
16:47:42.341 voip_ctrl.c VOIP initialized!
16:47:42.344 capi_ctrl.c Init CAPI...
16:47:42.344 capi_ctrl.c Controllers: 5
16:47:42.345 capi_ctrl.c Using controller #1 - 2 B-channel(s)
16:47:42.346 capi_ctrl.c Using controller #3 - 2 B-channel(s)
16:47:42.350 capi_ctrl.c CAPI registered!
16:47:42.350 alaw_ulaw.c Build a-law/pcm table buffer
16:47:42.496 voip_events.c RX-REQUEST 778 bytes Request msg REGISTER/cseq=8 (rdata0x4fe7b4) from UDP 192.168.178.1:5060
16:47:42.497 voip_events.c RX-SIP:
REGISTER sip:192.168.178.1 SIP/2.0
Via: SIP/2.0/UDP 91.14.112.123:5060;rport;branch=z9hG4bKD62BB15F94A59FBD
From: <sip:[email protected]>;tag=416235641
To: <sip:[email protected]>
Call-ID: [email protected]
CSeq: 8 REGISTER
Authorization: Digest username="58xxxxx", realm="fritz.box", nonce="6633487374b0dc51", uri="sip:192.168.178.1", response="ccfbc08f04574e8d6c019008b45c17ac", algorithm=md5, opaque="19495cff2ae8944a"
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.49 (Dec 14 2007)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer,reg
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0
16:47:42.498 voip_auth.c Found credentials -> user: 58xxxxx, realm: fritz.box
16:47:42.499 voip_auth.c Client 192.168.178.1:5060 authorized for account Sipgate
16:47:42.511 voip_registrar Status: 200 (Authorization OK!)
16:47:42.512 sip_endpoint.c Message Request msg REGISTER/cseq=8 (rdata0x4fe7b4) from 192.168.178.1:5060 was dropped/unhandled by any modules
16:47:42.514 voip_events.c RX-REQUEST 865 bytes Request msg REGISTER/cseq=9 (rdata0x4fe7b4) from UDP 192.168.178.1:5060
16:47:42.515 voip_events.c RX-SIP:
REGISTER sip:192.168.178.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.178.1:5060;rport;branch=z9hG4bK98D0BDDB35BDB5CE
From: <sip:[email protected]>;tag=416235641
To: <sip:[email protected]>
Call-ID: [email protected]
CSeq: 9 REGISTER
Contact: <sip:[email protected];uniq=884BCA6C1208416CBE7C66846F695>
Authorization: Digest username="58xxxxx", realm="fritz.box", nonce="6633487374b0dc51", uri="sip:192.168.178.1", response="ccfbc08f04574e8d6c019008b45c17ac", algorithm=md5, opaque="19495cff2ae8944a"
Expires: 600
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.49 (Dec 14 2007)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer,reg
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0
16:47:42.516 voip_auth.c Found credentials -> user: 58xxxxx, realm: fritz.box
16:47:42.523 voip_registrar Status: 200 (Authorization OK!)
16:47:42.524 sip_endpoint.c Message Request msg REGISTER/cseq=9 (rdata0x4fe7b4) from 192.168.178.1:5060 was dropped/unhandled by any modules
16:47:45.174 capi_ctrl.c CAPI initialized!
16:47:45.188 voip_auth.c SIP (TX-REGISTER):
REGISTER sip:sipgate.de SIP/2.0
Via: SIP/2.0/UDP 192.168.178.1:5061;rport;branch=z9hG4bKPj02000000000367458b6b
Max-Forwards: 70
From: "Sipgate" <sip:[email protected]>;tag=02000000000267458b6b
To: <sip:[email protected]>
Call-ID: 02000000000167458b6b
CSeq: 64678 REGISTER
User-Agent: dtmfbox 0.4.0
Contact: <sip:[email protected]:5061>
Expires: 3600
Content-Length: 0
16:47:45.189 capi_ctrl.c (0x3ff) - Listen to CAPI incoming controller 1...
16:47:45.190 capi_ctrl.c (0x3ff) - Listen to CAPI outgoing controller 1...
16:47:45.192 capi_ctrl.c (0xff) - Listen to CAPI internal controller 3...
16:47:45.196 capi_ctrl.c (CNF, CTRL 1) - CAPI_LISTEN
16:47:45.197 capi_ctrl.c (CNF, CTRL 1) - CAPI_LISTEN
16:47:45.197 capi_ctrl.c (CNF, CTRL 3) - CAPI_LISTEN
16:47:45.936 voip_auth.c SIP (RX-REGISTER):
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.178.1:5061;received=91.14.112.123;rport=5061;branch=z9hG4bKPj02000000000467458b6b
From: "Sipgate" <sip:[email protected]>;tag=02000000000267458b6b
To: <sip:[email protected]>;tag=8367f0f887e3954243ec30fa0f5db288.89ba
Call-ID: 02000000000167458b6b
CSeq: 64679 REGISTER
Contact: <sip:[email protected]:5061>;expires=600;received="sip:91.14.112.123:5061"
Content-Length: 0
16:47:45.936 voip_auth.c SIP registration successful (sipgate.de), status=10
16:47:45.937 voip_auth.c Next registration in 600 sec
16:47:47.093 script_funcs.c [-1] (SCRIPT) - Queue: 0, Pending: 0
16:47:47.232 script_funcs.c [-1] (SCRIPT) - Queue: 1, Pending: 0
16:47:47.352 script_funcs.c [-1] (SCRIPT) - Queue: 2, Pending: 0
16:48:12.446 voip_events.c RX-REQUEST 1097 bytes Request msg INVITE/cseq=10 (rdata0x4fe7b4) from UDP 192.168.178.1:5060
16:48:12.447 voip_events.c RX-SIP:
INVITE sip:[email protected]:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.178.1:5060;rport;branch=z9hG4bK349B45FEB710E0E2
From: <sip:[email protected]>;tag=A83B5B9D87CDB8B0
To: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 10 INVITE
Contact: <sip:[email protected];uniq=884BCA6C1208416CBE7C66846F695>
Max-Forwards: 70
Expires: 120
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.49 (Dec 14 2007)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 386
v=0
o=user 14353232 14353232 IN IP4 192.168.178.1
s=call
c=IN IP4 192.168.178.1
t=0 0
m=audio 7078 RTP/AVP 2 102 100 99 97 105 101
a=sendrecv
a=rtpmap:2 G726-32/8000
a=rtpmap:102 G726-32/8000
a=rtpmap:100 G726-40/8000
a=rtpmap:99 G726-24/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:105 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=rtcp:7079
16:48:12.449 voip_events.c Create UAS dialog...
16:48:12.451 voip_events.c Create media capability...
16:48:12.451 voip_events.c Create invite session...
16:48:12.453 voip_events.c Respond 100...
16:48:12.457 voip_events.c Call state changed to INCOMING [port=0]
16:48:12.457 voip_events.c Respond 180 (*ring*)..
16:48:12.463 voip_events.c Call EARLY [port=0, reason=0 (Default status message)]
16:48:12.464 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "CONNECT" "USER" "OUTGOING" "0" "-1" "58xxxxx" "[email protected]" "1" ""
16:48:12.651 voip_funcs.c [0] RECV DTMF: 9
16:48:12.652 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "DDI" "USER" "OUTGOING" "0" "-1" "58xxxxx" "[email protected]" "1" "9"
16:48:12.670 voip_funcs.c [0] RECV DTMF: 0
16:48:12.671 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "DDI" "USER" "OUTGOING" "0" "-1" "58xxxxx" "[email protected]" "1" "0"
16:48:12.776 voip_funcs.c [0] RECV DTMF: 0
16:48:12.777 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "DDI" "USER" "OUTGOING" "0" "-1" "58xxxxx" "[email protected]" "1" "0"
16:48:12.927 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "EARLY" "USER" "OUTGOING" "0" "-1" "58xxxxx" "[email protected]" "1" ""
16:48:14.123 script_funcs.c [0] (SCRIPT) - Queue: 3, Pending: 0
16:48:14.124 voip_funcs.c Respond stateless with code 200 (answer)
16:48:15.093 voip_registrar Make VoIP call by client ("58xxxxx" <sip:[email protected]:5061> -> <sip:[email protected]>)...
16:48:15.094 voip_funcs.c Initialize outgoing call from '"58xxxxx" <sip:[email protected]:5061>' to '<sip:[email protected]>'
16:48:15.095 voip_funcs.c Create SDP...
16:48:15.096 voip_funcs.c Create invite session...
16:48:15.101 voip_events.c On media update
16:48:15.103 voip_events.c (220049) SDP negotiation has failed
16:48:15.103 voip_funcs.c Cannot create stateless respond with status 200!
16:48:15.104 script_funcs.c [0] (SCRIPT) - hook up!
16:48:15.108 voip_events.c Call state changed to CALLING [port=1]
16:48:15.109 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "CONNECT" "VOIP" "OUTGOING" "1" "0" "58xxxxx" "[email protected]" "1" ""
16:48:15.497 voip_events.c Call state changed to CALLING [port=1]
16:48:15.623 script_funcs.c [0] (SCRIPT) - Queue: 4, Pending: 0
16:48:15.624 script_funcs.c [0] (SCRIPT) - wait for event (2)
16:48:15.803 script_funcs.c [0] (SCRIPT) - Queue: 5, Pending: 0
16:48:15.803 script_funcs.c [0] (SCRIPT) - change scriptfile to "./script/script_internal.sh"
16:48:15.804 script_funcs.c [0] (SCRIPT) - change delimiter to ""
16:48:16.123 script_funcs.c [0] (SCRIPT) - Queue: 6, Pending: 0
16:48:16.283 script_funcs.c [0] (SCRIPT) - Queue: 7, Pending: 0
16:48:16.466 voip_events.c Call EARLY [port=1, reason=0 (Default status message)]
16:48:16.467 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "CONNECT" "VOIP" "OUTGOING" "1" "0" "58xxxxx" "[email protected]" "1" ""
16:48:16.540 voip_events.c On media update
16:48:16.541 voip_events.c stream #0: PCMA (8000/1)
16:48:16.542 voip_events.c stream #0: disable VAD [port=1]
16:48:16.545 strm0x57c11c Encoder stream started
16:48:16.546 strm0x57c11c Decoder stream started
16:48:16.635 voip_events.c Call EARLY [port=1, reason=0 (Default status message)]
16:48:16.636 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "CONNECT" "VOIP" "OUTGOING" "1" "0" "58xxxxx" "[email protected]" "1" ""
16:48:16.820 inv0x57292c SDP negotiation done, message body is ignored
16:48:17.053 script_funcs.c [0] (SCRIPT) - Queue: 8, Pending: 0
16:48:26.534 voip_events.c Call DISCONNECTED [port=1, reason=603 (Decline)]
16:48:26.545 voip_events.c Call DISCONNECTED [port=0, reason=487 (Request Terminated)]
16:48:26.546 voip_events.c Hangup [0]
16:48:26.547 voip_registrar VoIP client disconnected [0]
16:48:26.548 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/script_main.sh" "DISCONNECT" "VOIP" "OUTGOING" "1" "-1" "58xxxxx" "[email protected]" "1" ""
16:48:26.638 voip_events.c Hangup [1]
16:48:27.693 script_funcs.c [-1] (SCRIPT) - Queue: 9, Pending: 0