17:50:56.736 dtmfbox.c dtmfbox 0.5.0-beta1
17:50:56.737 dtmfbox.c PJSIP 0.9.0-release
17:50:56.756 pjlib select() I/O Queue created (0x2ac47070)
17:50:56.756 sip_endpoint.c Module "mod-msg-print" registered
17:50:56.758 voip_ctrl.c Init VOIP...
17:50:56.764 voip_ctrl.c Resolve IP from 194.221.62.209:3478 ...
17:50:56.781 voip_ctrl.c Use IP: 79.220.200.69
17:50:56.783 udp0x52b3c0 SIP UDP transport started, published address is 79.220.200.69:5061
17:50:56.783 voip_ctrl.c UDP server listening on port 5061
17:50:56.787 sip_endpoint.c Module "mod-tsx-layer" registered
17:50:56.787 sip_endpoint.c Module "mod-stateful-util" registered
17:50:56.790 sip_endpoint.c Module "mod-ua" registered
17:50:56.791 sip_endpoint.c Module "mod-100rel" registered
17:50:56.792 sip_endpoint.c Module "mod-invite" registered
17:50:56.792 sip_endpoint.c Module "dtmfbox" registered
17:50:56.796 pjlib select() I/O Queue created (0x5507bc)
17:50:56.801 voip_ctrl.c Conference bridge created (8000hz/16/1)
17:50:56.805 funcs.c Allocate 10 connections with 4 RTP/RTCP transports
17:50:56.811 icetp00 Creating ICE stream transport with 2 component(s)
17:50:56.816 icetp00 Comp 1: srflx candidate starts Binding discovery
17:50:56.816 icetp00 Comp 1: host candidate 192.168.0.250:2053 added
17:50:56.820 icetp00 Comp 2: srflx candidate starts Binding discovery
17:50:56.821 icetp00 Comp 2: host candidate 192.168.0.250:2054 added
17:50:56.821 icetp00 ICE stream transport created
17:50:56.822 icetp01 Creating ICE stream transport with 2 component(s)
17:50:56.827 icetp01 Comp 1: srflx candidate starts Binding discovery
17:50:56.827 icetp01 Comp 1: host candidate 192.168.0.250:2055 added
17:50:56.830 icetp01 Comp 2: srflx candidate starts Binding discovery
17:50:56.831 icetp01 Comp 2: host candidate 192.168.0.250:2056 added
17:50:56.832 icetp01 ICE stream transport created
17:50:56.832 icetp02 Creating ICE stream transport with 2 component(s)
17:50:56.836 icetp02 Comp 1: srflx candidate starts Binding discovery
17:50:56.837 icetp02 Comp 1: host candidate 192.168.0.250:2057 added
17:50:56.840 icetp02 Comp 2: srflx candidate starts Binding discovery
17:50:56.841 icetp02 Comp 2: host candidate 192.168.0.250:2058 added
17:50:56.841 icetp02 ICE stream transport created
17:50:56.841 icetp03 Creating ICE stream transport with 2 component(s)
17:50:56.845 icetp03 Comp 1: srflx candidate starts Binding discovery
17:50:56.847 icetp03 Comp 1: host candidate 192.168.0.250:2059 added
17:50:56.849 icetp03 Comp 2: srflx candidate starts Binding discovery
17:50:56.851 icetp03 Comp 2: host candidate 192.168.0.250:2060 added
17:50:56.851 icetp03 ICE stream transport created
17:50:56.854 voip_ctrl.c Start VoIP listen loop...
17:50:56.856 stuntp0x5657c8 STUN mapped address found/changed: 79.220.200.69:2053
17:50:56.857 icetp00 Comp 1: Binding discovery complete, srflx address is 79.220.200.69:2053
17:50:56.857 stuntp0x5680d0 STUN mapped address found/changed: 79.220.200.69:2054
17:50:56.859 icetp00 Comp 2: Binding discovery complete, srflx address is 79.220.200.69:2054
17:50:56.860 stuntp0x56afe8 STUN mapped address found/changed: 79.220.200.69:2055
17:50:56.860 icetp01 Comp 1: Binding discovery complete, srflx address is 79.220.200.69:2055
17:50:56.861 stuntp0x56d8f0 STUN mapped address found/changed: 79.220.200.69:2056
17:50:56.862 icetp01 Comp 2: Binding discovery complete, srflx address is 79.220.200.69:2056
17:50:56.863 stuntp0x570808 STUN mapped address found/changed: 79.220.200.69:2057
17:50:56.864 icetp02 Comp 1: Binding discovery complete, srflx address is 79.220.200.69:2057
17:50:56.865 stuntp0x573110 STUN mapped address found/changed: 79.220.200.69:2058
17:50:56.866 icetp02 Comp 2: Binding discovery complete, srflx address is 79.220.200.69:2058
17:50:56.867 stuntp0x576028 STUN mapped address found/changed: 79.220.200.69:2059
17:50:56.868 icetp03 Comp 1: Binding discovery complete, srflx address is 79.220.200.69:2059
17:50:56.869 stuntp0x578930 STUN mapped address found/changed: 79.220.200.69:2060
17:50:56.870 icetp03 Comp 2: Binding discovery complete, srflx address is 79.220.200.69:2060
17:50:56.871 voip_ctrl.c VOIP initialized!
17:50:56.872 capi_ctrl.c Init CAPI...
17:50:56.872 capi_ctrl.c CAPI controller: 5
17:50:56.873 capi_ctrl.c Found controller #1 with 2 B-channel(s)
17:50:56.874 capi_ctrl.c Found controller #2 with 2 B-channel(s)
17:50:56.875 capi_ctrl.c Found controller #3 with 2 B-channel(s)
17:50:56.875 capi_ctrl.c Found controller #4 with 1 B-channel(s)
17:50:56.876 capi_ctrl.c Found controller #5 with 3 B-channel(s)
17:50:56.880 capi_ctrl.c CAPI registered!
17:50:56.881 alaw_ulaw.c Build a-law/pcm table buffer
17:50:57.007 voip_events.c RX-REQUEST 671 bytes Request msg REGISTER/cseq=12 (rdata0x52c4a4) from UDP 192.168.0.250:5060
17:50:57.007 voip_events.c RX-SIP:
REGISTER sip:192.168.0.250 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.250:5060;rport;branch=z9hG4bK6B312972CEB91674
From: <sip:[email protected]>;tag=1418421159
To: <sip:[email protected]>
Call-ID: [email protected]
CSeq: 12 REGISTER
Contact: <sip:[email protected];uniq=D8E8EC2CE727DB9A57919BDB30B1D>
Expires: 1800
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.63 (Oct 6 2008)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer,reg
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0
17:50:57.008 voip_auth.c Request authorization challenge (realm: fritz.box)...
17:50:57.023 voip_events.c RX-REQUEST 868 bytes Request msg REGISTER/cseq=13 (rdata0x52c4a4) from UDP 192.168.0.250:5060
17:50:57.024 voip_events.c RX-SIP:
REGISTER sip:192.168.0.250 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.250:5060;rport;branch=z9hG4bKBF51C9728C61AC92
From: <sip:[email protected]>;tag=1418421159
To: <sip:[email protected]>
Call-ID: [email protected]
CSeq: 13 REGISTER
Contact: <sip:[email protected];uniq=D8E8EC2CE727DB9A57919BDB30B1D>
Authorization: Digest username="fritz", realm="fritz.box", nonce="3006c83e614fd4a1", uri="sip:192.168.0.250", response="93c7fc972c75f14ddefa31b53691d41f", algorithm=md5, opaque="419ac2415577f8e1"
Expires: 1800
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.63 (Oct 6 2008)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer,reg
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 0
17:50:57.024 voip_auth.c Found credentials -> user: fritz, realm: fritz.box
17:50:57.025 voip_auth.c Client 192.168.0.250:5060 authorized for account userz08
17:50:57.038 voip_registrar Status: 200 (OK)
17:50:57.040 voip_events.c RX-REQUEST 539 bytes Request msg SUBSCRIBE/cseq=14 (rdata0x52c4a4) from UDP 192.168.0.250:5060
17:50:57.040 voip_events.c RX-SIP:
SUBSCRIBE sip:[email protected]:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.250:5060;rport;branch=z9hG4bKFF8AB3F6F7B9919C
From: <sip:[email protected]>;tag=4281725644
To: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 14 SUBSCRIBE
Contact: <sip:[email protected];uniq=D8E8EC2CE727DB9A57919BDB30B1D>
Event: message-summary
Expires: 3600
Max-Forwards: 70
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.63 (Oct 6 2008)
Allow: NOTIFY
Accept: application/simple-message-summary
Content-Length: 0
17:50:57.044 voip_events.c Method not allowed (6)!
17:50:59.212 capi_ctrl.c CAPI initialized!
17:50:59.344 plugins.c [plugin.menu]: action:1_ctrl5reject loaded (5 entries)
17:50:59.350 plugins.c [plugin.menu]: action:2_cbct loaded (3 entries)
17:50:59.356 plugins.c [plugin.menu]: action:3_answering_machine loaded (3 entries)
17:50:59.362 plugins.c [plugin.menu]: lib:speak loaded (2 entries)
17:50:59.365 plugins.c [plugin.menu]: menu:am loaded (2 entries)
17:50:59.367 plugins.c [plugin.menu]: menu:am_delete loaded (3 entries)
17:50:59.370 plugins.c [plugin.menu]: menu:am_on_off loaded (2 entries)
17:50:59.373 plugins.c [plugin.menu]: menu:am_record_announcement loaded (4 entries)
17:50:59.376 plugins.c [plugin.menu]: menu:am_setup loaded (5 entries)
17:50:59.380 plugins.c [plugin.menu]: menu:callthrough_account loaded (13 entries)
17:50:59.383 plugins.c [plugin.menu]: menu:callthrough_number loaded (3 entries)
17:50:59.387 plugins.c [plugin.menu]: menu:callthrough_pin loaded (3 entries)
17:50:59.389 plugins.c [plugin.menu]: menu:dtmf_commands loaded (7 entries)
17:50:59.391 plugins.c [plugin.menu]: menu:main loaded (6 entries)
17:50:59.396 plugins.c [plugin.menu]: menu:misc loaded (6 entries)
17:50:59.400 plugins.c [plugin.menu]: menu:misc_checkmaild loaded (5 entries)
17:50:59.404 plugins.c [plugin.menu]: menu:misc_fb loaded (5 entries)
17:50:59.408 plugins.c [plugin.menu]: menu:misc_radio loaded (11 entries)
17:50:59.412 plugins.c [plugin.menu]: menu:misc_weather loaded (5 entries)
17:50:59.419 plugins.c [plugin.menu]: script:action_am loaded (1 entries)
17:50:59.424 plugins.c [plugin.menu]: script:action_cbct loaded (1 entries)
17:50:59.430 plugins.c [plugin.menu]: script:action_ctrl5 loaded (1 entries)
17:50:59.435 plugins.c [plugin.menu]: script:am_admin loaded (1 entries)
17:50:59.439 plugins.c [plugin.menu]: script:am_setup loaded (1 entries)
17:50:59.444 plugins.c [plugin.menu]: script:callthrough loaded (1 entries)
17:50:59.449 plugins.c [plugin.menu]: script:misc_checkmaild loaded (1 entries)
17:50:59.454 plugins.c [plugin.menu]: script:misc_fb loaded (1 entries)
17:50:59.459 plugins.c [plugin.menu]: script:misc_radio loaded (1 entries)
17:50:59.464 plugins.c [plugin.menu]: script:misc_weather loaded (1 entries)
17:50:59.470 plugins.c [plugin.menu]: 29 menues/actions loaded!
17:50:59.471 plugins.c [plugin.menu]: Initialized!
17:50:59.471 plugins.c #1 - Plugin "/var/dtmfbox/menu.plugin.so" loaded!
17:50:59.478 funcs.c Set low priority
17:50:59.479 capi_ctrl.c (0x3ff) - Listen to CAPI controller 1...
17:50:59.480 capi_ctrl.c (0x3ff) - Listen to CAPI controller 3...
17:50:59.481 capi_ctrl.c (0x3ff) - Listen to CAPI controller 4...
17:50:59.482 capi_ctrl.c (0x3ff) - Listen to CAPI controller 5...
17:50:59.484 capi_ctrl.c (CNF, CTRL 1) - CAPI_LISTEN
17:50:59.484 capi_ctrl.c (CNF, CTRL 3) - CAPI_LISTEN
17:50:59.485 capi_ctrl.c (CNF, CTRL 4) - CAPI_LISTEN
17:50:59.486 capi_ctrl.c (CNF, CTRL 5) - CAPI_LISTEN
17:52:54.375 capi_events.c Ignoring connection for unknown account [Called-Party: unknown, Calling-Party: 0#004932223332670]
17:52:54.377 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0x0018) - CHANNEL IDENTIFICATION (0x03)
17:52:54.378 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0xc000) - UNKNOWN INFO
17:52:54.380 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_DISCONNECT
17:53:04.157 capi_events.c Ignoring connection for unknown account [Called-Party: 06968609110, Calling-Party: 0#004932223332670]
17:53:04.158 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0x8003) - PROGRESS
17:53:04.159 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0x0070) - UNKNOWN INFO
17:53:04.159 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0x0018) - CHANNEL IDENTIFICATION (0x03)
17:53:04.160 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_INFO (0xc000) - UNKNOWN INFO
17:53:04.162 voip_events.c RX-REQUEST 1090 bytes Request msg INVITE/cseq=15 (rdata0x52c4a4) from UDP 192.168.0.250:5060
17:53:04.163 voip_events.c RX-SIP:
INVITE sip:[email protected]:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.250:5060;rport;branch=z9hG4bKA1FE70D4716D83C8
From: <sip:[email protected]>;tag=05C7F3E64E162026
To: <sip:[email protected]:5061>
Call-ID: [email protected]
CSeq: 15 INVITE
Contact: <sip:[email protected];uniq=D8E8EC2CE727DB9A57919BDB30B1D>
Max-Forwards: 70
Expires: 120
User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.63 (Oct 6 2008)
Supported: 100rel,replaces
Allow-Events: telephone-event,refer
Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Content-Type: application/sdp
Accept: application/sdp, multipart/mixed
Accept-Encoding: identity
Content-Length: 359
v=0
o=user 6969738 6969738 IN IP4 192.168.0.250
s=call
c=IN IP4 192.168.0.250
t=0 0
m=audio 7078 RTP/AVP 8 0 2 102 100 99 97 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:101 telephone-event/8000
a=fmtp:101 0-11
a=rtcp:7079
17:53:04.165 voip_events.c Create UAS dialog...
17:53:04.166 voip_events.c Create media capability...
17:53:04.169 icetp00 ICE session created, comp_cnt=2, role is Controlled agent
17:53:04.170 icetp00 Candidate 0 added: comp_id=1, type=Server Reflexive, foundation=S, addr=79.220.200.69:2053, base=192.168.0.250:2053, prio=0x1f (31)
17:53:04.170 icetp00 Candidate 1 added: comp_id=1, type=Host, foundation=H, addr=192.168.0.250:2053, base=192.168.0.250:2053, prio=0x17 (23)
17:53:04.170 icetp00 Candidate 2 added: comp_id=2, type=Server Reflexive, foundation=S, addr=79.220.200.69:2054, base=192.168.0.250:2054, prio=0x1e (30)
17:53:04.171 icetp00 Candidate 3 added: comp_id=2, type=Host, foundation=H, addr=192.168.0.250:2054, base=192.168.0.250:2054, prio=0x16 (22)
17:53:04.171 voip_events.c Create invite session...
17:53:04.173 voip_events.c Respond 100...
17:53:04.178 voip_events.c Call state changed to INCOMING [port=0]
17:53:04.178 voip_events.c Respond 180 (*ring*)..
17:53:04.185 voip_events.c Call EARLY [port=0, reason=0 (Default status message)]
17:53:04.185 voip_funcs.c [0] RECV DTMF: 0
17:53:04.186 voip_funcs.c [0] RECV DTMF: 6
17:53:04.186 voip_funcs.c [0] RECV DTMF: 9
17:53:04.187 voip_funcs.c [0] RECV DTMF: 6
17:53:04.187 voip_funcs.c [0] RECV DTMF: 8
17:53:04.188 voip_funcs.c [0] RECV DTMF: 6
17:53:04.188 voip_funcs.c [0] RECV DTMF: 0
17:53:04.188 voip_funcs.c [0] RECV DTMF: 9
17:53:04.189 voip_funcs.c [0] RECV DTMF: 1
17:53:04.189 voip_funcs.c [0] RECV DTMF: 1
17:53:04.190 voip_funcs.c [0] RECV DTMF: 0
17:53:04.194 capi_events.c (IND, CTRL 5, PLCI: 517) - CAPI_DISCONNECT
17:53:04.210 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "CONNECT" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" ""
17:53:04.255 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "0"
17:53:04.279 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "6"
17:53:04.321 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "9"
17:53:04.350 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "6"
17:53:04.410 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "8"
17:53:04.470 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "6"
17:53:04.492 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "0"
17:53:04.590 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "9"
17:53:04.621 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "1"
17:53:04.690 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "1"
17:53:04.731 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "DDI" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" "0"
17:53:04.756 script_funcs.c RUN SCRIPT: "/var/dtmfbox/script/userscript.sh" "EARLY" "USER" "OUTGOING" "0" "-1" "004932223332670" "[email protected]" "1" ""
17:53:04.830 funcs.c Set high priority
17:53:06.205 voip_registrar Make VoIP call by client (<sip:[email protected]:5061;uniq=D8E8EC2CE727DB9A57919BDB30B1D> -> <sip:[email protected]>)...
17:53:06.205 voip_funcs.c Initialize outgoing call from '<sip:[email protected]:5061;uniq=D8E8EC2CE727DB9A57919BDB30B1D>' to '<sip:[email protected]>'
17:53:06.207 voip_funcs.c Create SDP...
17:53:06.210 icetp01 ICE session created, comp_cnt=2, role is Controlled agent
17:53:06.210 icetp01 Candidate 0 added: comp_id=1, type=Server Reflexive, foundation=S, addr=79.220.200.69:2055, base=192.168.0.250:2055, prio=0x1f (31)
17:53:06.211 icetp01 Candidate 1 added: comp_id=1, type=Host, foundation=H, addr=192.168.0.250:2055, base=192.168.0.250:2055, prio=0x17 (23)
17:53:06.211 icetp01 Candidate 2 added: comp_id=2, type=Server Reflexive, foundation=S, addr=79.220.200.69:2056, base=192.168.0.250:2056, prio=0x1e (30)
17:53:06.212 icetp01 Candidate 3 added: comp_id=2, type=Host, foundation=H, addr=192.168.0.250:2056, base=192.168.0.250:2056, prio=0x16 (22)
17:53:06.212 voip_funcs.c Create invite session..