15:48:53.272 dtmfbox.c PJSIP 0.7.0-trunk
15:48:53.276 sip_endpoint.c Creating endpoint instance...
15:48:53.310 pjlib select() I/O Queue created (0x4f8644)
15:48:53.311 sip_endpoint.c Module "mod-msg-print" registered
15:48:53.312 sip_transport. Transport manager created.
15:48:53.313 capi_ctrl.c Init CAPI...
15:48:53.323 capi_ctrl.c CAPI initialized!
15:48:53.324 voip_ctrl.c Init VOIP...
15:48:53.325 voip_ctrl.c Resolve IP from stun.schlund.de:3478 ...
15:48:53.588 voip_ctrl.c Use IP: 91.32.156.222
15:48:53.590 udp0x51b9fc SIP UDP transport started, published address is 91.32.156.222:5061
15:48:53.592 voip_ctrl.c UDP server listening on port 5061
15:48:53.597 sip_endpoint.c Module "mod-tsx-layer" registered
15:48:53.598 sip_endpoint.c Module "mod-stateful-util" registered
15:48:53.603 sip_endpoint.c Module "mod-ua" registered
15:48:53.604 voip_ctrl.c VOIP callbacks initialized
15:48:53.605 sip_endpoint.c Module "mod-invite" registered
15:48:53.606 sip_endpoint.c Module "dtmfbox" registered
15:48:53.625 pjlib select() I/O Queue created (0x541d34)
15:48:53.628 conference.c Creating conference bridge with 24 ports
15:48:53.630 conference.c Sound device successfully created for port 0
15:48:58.568 voip_ctrl.c RTP/RTCP: 91.32.156.222:4000-4001
15:49:03.507 voip_ctrl.c RTP/RTCP: 91.32.156.222:4002-4003
15:49:08.448 voip_ctrl.c RTP/RTCP: 91.32.156.222:4004-4005
15:49:13.387 voip_ctrl.c RTP/RTCP: 91.32.156.222:4006-4007
15:49:18.327 voip_ctrl.c RTP/RTCP: 91.32.156.222:4008-4009
15:49:23.267 voip_ctrl.c RTP/RTCP: 91.32.156.222:4010-4011
15:49:28.207 voip_ctrl.c RTP/RTCP: 91.32.156.222:4012-4013
15:49:33.147 voip_ctrl.c RTP/RTCP: 91.32.156.222:4014-4015
15:49:33.148 voip_ctrl.c RTP/RTCP transport running from port 4000 to 4015
15:49:34.804 voip_ctrl.c VOIP initialized!
15:49:34.810 endpoint Request msg REGISTER/cseq=57555 (tdta0x570e14) created.
15:49:34.812 tsx0x571e1c Transaction created for Request msg REGISTER/cseq=57556 (tdta0x570e14)
15:49:34.813 tsx0x571e1c Sending Request msg REGISTER/cseq=57556 (tdta0x570e14) in state Null
15:49:34.813 sip_resolve.c Target '212.227.15.197:0' type=Unspecified resolved to '212.227.15.197:5060' type=UDP
15:49:34.816 tsx0x571e1c State changed from Null to Calling, event=TX_MSG
15:49:34.817 voip_auth.c SIP (TX-REGISTER):
REGISTER sip:212.227.15.197 SIP/2.0
Via: SIP/2.0/UDP 91.32.156.222:5061;rport;branch=z9hG4bKPj0db800000003ce82a16f
Max-Forwards: 70
From: <sip:[email protected]>;tag=0db800000002ce82a16f
To: <sip:[email protected]>
Call-ID: 0db800000001ce82a16f
CSeq: 57556 REGISTER
User-Agent: dtmfbox v0.3.8
Contact: <sip:[email protected]:5061>;expires=1800
Expires: 1800
Content-Length: 0
15:49:34.820 capi_ctrl.c Listen to CAPI incoming controller 1...
15:49:34.821 capi_ctrl.c Listen to CAPI internal controller 3...
15:49:34.823 capi_ctrl.c (CNF, CTRL 1, PLCI: 0) - CAPI_LISTEN
15:49:34.824 capi_ctrl.c (CNF, CTRL 3, PLCI: 0) - CAPI_LISTEN
15:49:34.826 voip_ctrl.c Start VOIP listen loop...
15:49:34.830 sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=57556 (rdata0x51ca74)
15:49:34.831 tsx0x571e1c Incoming Response msg 401/REGISTER/cseq=57556 (rdata0x51ca74) in state Calling
15:49:34.832 tsx0x571e1c State changed from Calling to Completed, event=RX_MSG
15:49:34.834 tsx0x5737d4 Transaction created for Request msg REGISTER/cseq=57557 (tdta0x570e14)
15:49:34.835 tsx0x5737d4 Sending Request msg REGISTER/cseq=57557 (tdta0x570e14) in state Null
15:49:34.836 sip_resolve.c Target '212.227.15.197:0' type=Unspecified resolved to '212.227.15.197:5060' type=UDP
15:49:34.839 tsx0x5737d4 State changed from Null to Calling, event=TX_MSG
15:49:34.868 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=57557 (rdata0x51ca74)
15:49:34.869 tsx0x5737d4 Incoming Response msg 200/REGISTER/cseq=57557 (rdata0x51ca74) in state Calling
15:49:34.870 tsx0x5737d4 State changed from Calling to Completed, event=RX_MSG
15:49:34.871 voip_auth.c SIP (RX-REGISTER):
SIP/2.0 200 OK
Via: SIP/2.0/UDP 91.32.156.222:5061;rport=5061;branch=z9hG4bKPj0db800000004ce82a16f
From: <sip:[email protected]>;tag=0db800000002ce82a16f
To: <sip:[email protected]>;tag=a6a1c5f60faecf035a1ae5b6e96e979a-4a3d
Call-ID: 0db800000001ce82a16f
CSeq: 57557 REGISTER
Contact: <sip:[email protected]:5061>;expires=28466, <sip:[email protected];uniq=93E6B668DE811AD8B43B63D1B2DF3>;expires=28354
Server: UI OpenSer
Content-Length: 0
15:49:34.872 voip_auth.c SIP registration successful (212.227.15.197), status=14
15:49:34.873 voip_auth.c Next registration in 28354 sec
15:49:39.846 tsx0x571e1c Timeout timer event
15:49:39.847 tsx0x571e1c State changed from Completed to Terminated, event=TIMER
15:49:39.856 tsx0x571e1c Timeout timer event
15:49:39.857 tsx0x571e1c State changed from Terminated to Destroyed, event=TIMER
15:49:39.857 tsx0x571e1c Transaction destroyed!
15:49:39.876 tsx0x5737d4 Timeout timer event
15:49:39.877 tsx0x5737d4 State changed from Completed to Terminated, event=TIMER
15:49:39.886 tsx0x5737d4 Timeout timer event
15:49:39.887 tsx0x5737d4 State changed from Terminated to Destroyed, event=TIMER
15:49:39.888 tdta0x570e14 Destroying txdata Request msg REGISTER/cseq=57557 (tdta0x570e14)
15:49:39.889 tsx0x5737d4 Transaction destroyed!
15:49:54.585 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_CONNECT
15:49:54.587 funcs.c ConnectionByFree - Connection: 0
15:49:54.588 capi_events.c Source: unknown - Target: zzzzzz - Account: 1
15:49:54.589 capi_events.c (CTRL 1539, PLCI: 3) - Call from unknown to zzzzzz...
15:49:54.669 script_funcs.c Run script: /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/script/script_main.sh CONNECT ISDN OUTGOING 0 -1 "zzzzzz" "unknown" &
15:49:54.671 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:54.673 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:54.674 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:54.693 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:54.695 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:55.042 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:55.046 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.047 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.048 capi_funcs.c DTMF RECEIVED: 4
15:49:55.049 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.050 capi_funcs.c DTMF RECEIVED: 3
15:49:55.051 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.051 capi_funcs.c DTMF RECEIVED: 0
15:49:55.052 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.053 capi_funcs.c DTMF RECEIVED: 3
15:49:55.054 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.054 capi_funcs.c DTMF RECEIVED: 5
15:49:55.058 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:49:55.059 capi_funcs.c DTMF RECEIVED: 0
15:49:55.060 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:56.073 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:56.075 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:56.173 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=67 (rdata0x51ca74)
15:49:56.174 voip_events.c RX-REQUEST 1373 bytes Request msg INVITE/cseq=67 (rdata0x51ca74) from UDP 212.227.15.197:5060
15:49:56.177 voip_events.c Incoming request (49yyyyzzzzzz:0 -> 49yyyyxxxxxx:0)
15:49:56.178 funcs.c ConnectionByFree - Connection: 1
15:49:56.179 voip_events.c Create UAS dialog
15:49:56.181 tsx0x572a34 Transaction created for Request msg INVITE/cseq=67 (rdata0x51ca74)
15:49:56.183 tsx0x572a34 Incoming Request msg INVITE/cseq=67 (rdata0x51ca74) in state Null
15:49:56.183 tsx0x572a34 State changed from Null to Trying, event=RX_MSG
15:49:56.184 dlg0x571e1c Transaction tsx0x572a34 state changed to Trying
15:49:56.185 dlg0x571e1c UAS dialog created
15:49:56.196 voip_events.c Create media capability
15:49:56.198 voip_events.c Create invite session
15:49:56.200 dlg0x571e1c Module mod-invite added as dialog usage, data=0x5727ec
15:49:56.201 dlg0x571e1c Session count inc to 2 by mod-invite
15:49:56.202 inv0x571e1c UAS invite session created for dialog dlg0x571e1c
15:49:56.204 endpoint Response msg 100/INVITE/cseq=67 (tdta0x574a7c) created
15:49:56.204 dlg0x571e1c Sending Response msg 100/INVITE/cseq=67 (tdta0x574a7c)
15:49:56.225 tsx0x572a34 Sending Response msg 100/INVITE/cseq=67 (tdta0x574a7c) in state Trying
15:49:56.227 sip_resolve.c Target '212.227.15.197:5060' type=UDP resolved to '212.227.15.197:5060' type=UDP
15:49:56.229 tsx0x572a34 State changed from Trying to Proceeding, event=TX_MSG
15:49:56.230 dlg0x571e1c Transaction tsx0x572a34 state changed to Proceeding
15:49:56.231 funcs.c ConnectionByInvite - Connection: 1
15:49:56.232 voip_events.c Call state changed to INCOMING [port=1]
15:49:56.233 voip_events.c Respond stateless with code 180 (*ring*)
15:49:56.234 endpoint Response msg 180/INVITE/cseq=67 (tdta0x576a2c) created
15:49:56.235 inv0x571e1c Sending Response msg 180/INVITE/cseq=67 (tdta0x576a2c)
15:49:56.238 dlg0x571e1c Sending Response msg 180/INVITE/cseq=67 (tdta0x576a2c)
15:49:56.239 tsx0x572a34 Sending Response msg 180/INVITE/cseq=67 (tdta0x576a2c) in state Proceeding
15:49:56.240 tdta0x574a7c Destroying txdata Response msg 100/INVITE/cseq=67 (tdta0x574a7c)
15:49:56.242 tsx0x572a34 State changed from Proceeding to Proceeding, event=TX_MSG
15:49:56.243 dlg0x571e1c Transaction tsx0x572a34 state changed to Proceeding
15:49:56.244 funcs.c ConnectionByInvite - Connection: 1
15:49:56.244 voip_events.c Call EARLY [port=1, reason=0 (Default status message)]
15:49:56.429 script_funcs.c Run script: /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/script/script_main.sh CONNECT VOIP INCOMING 1 -1 "49yyyyxxxxxx" "[email protected]" &
15:49:56.456 sip_endpoint.c Message Request msg INVITE/cseq=67 (rdata0x51ca74) from 212.227.15.197:5060 was dropped/unhandled by any modules
15:49:57.322 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:57.323 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:49:57.325 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_INFO
15:50:13.326 script_funcs.c Hook up!
15:50:13.328 voip_funcs.c Respond stateless with code 200 (answer)
15:50:13.331 inv0x571e1c SDP negotiation done, status=0
15:50:13.332 voip_events.c On media update
15:50:13.333 funcs.c ConnectionByInvite - Connection: 1
15:50:13.335 voip_events.c Disable VAD [port=1]
15:50:13.338 rtp.c pjmedia_rtp_session_init: ses=0x579110, default_pt=8, ssrc=0x31103376
15:50:13.338 rtp.c pjmedia_rtp_session_init: ses=0x579d18, default_pt=8, ssrc=0x31103376
15:50:13.339 stream.c Stream strm0x5780c4 created
15:50:13.340 strm0x5780c4 Encoder stream started
15:50:13.341 strm0x5780c4 Decoder stream started
15:50:13.350 strm0x5780c4 Start talksprut..
15:50:13.441 script_funcs.c Run script: /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/script/script_main.sh CONFIRMED VOIP INCOMING 1 -1 "49yyyyxxxxxx" "[email protected]" &
15:50:13.442 inv0x571e1c Sending Response msg 200/INVITE/cseq=67 (tdta0x576a2c)
15:50:13.443 dlg0x571e1c Sending Response msg 200/INVITE/cseq=67 (tdta0x576a2c)
15:50:13.444 tsx0x572a34 Sending Response msg 200/INVITE/cseq=67 (tdta0x576a2c) in state Proceeding
15:50:13.450 tsx0x572a34 State changed from Proceeding to Completed, event=TX_MSG
15:50:13.451 dlg0x571e1c Transaction tsx0x572a34 state changed to Completed
15:50:13.452 funcs.c ConnectionByInvite - Connection: 1
15:50:13.453 voip_events.c Call CONNECTING [port=1, reason=0 (Default status message)]
15:50:13.512 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=67 (rdata0x51ca74)
15:50:13.527 dlg0x571e1c Received Request msg ACK/cseq=67 (rdata0x51ca74)
15:50:13.528 tsx0x572a34 Request to terminate transaction
15:50:13.529 tsx0x572a34 State changed from Completed to Terminated, event=USER
15:50:13.540 dlg0x571e1c Transaction tsx0x572a34 state changed to Terminated
15:50:13.541 funcs.c ConnectionByInvite - Connection: 1
15:50:13.541 voip_events.c Call CONFIRMED [port=1, reason=0 (Default status message)]
15:50:13.545 tsx0x572a34 Timeout timer event
15:50:13.563 tsx0x572a34 State changed from Terminated to Destroyed, event=TIMER
15:50:13.564 tdta0x576a2c Destroying txdata Response msg 200/INVITE/cseq=67 (tdta0x576a2c)
15:50:13.565 tsx0x572a34 Transaction destroyed!
15:50:13.581 capi_ctrl.c (IND, CTRL 3, PLCI: 1539) - CAPI_DISCONNECT
15:50:13.582 funcs.c ConnectionByPLCI - PLCI: 1539, Connection: 0
15:50:13.722 script_funcs.c Run script: /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/script/script_main.sh DISCONNECT ISDN OUTGOING 0 -1 "zzzzzz" "xxxxxx" &
15:50:14.346 voip_funcs.c Create wave player (./play/ansage.wav)...
15:50:14.348 wav_player.c File player './play/ansage.wav' created: samp.rate=8000, ch=1, bufsize=4KB, filesize=217KB
15:50:14.349 conference.c Port 2 (./play/ansage.wav) transmitting to port 1 (strm0x5780c4)
15:50:14.350 script_funcs.c Playing ./play/ansage.wav...
15:50:27.826 wav_player.c File port ./play/ansage.wav EOF, calling callback
15:50:27.827 wav_player.c File port ./play/ansage.wav EOF, stopping..
15:50:28.690 voip_funcs.c Create wave recorder (/var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/record/49yyyyxxxxxx/07-06-07---49yyyyxxxxxx-49yyyyzzzzzz-1.wav)...
15:50:28.700 wav_writer.c File writer '/var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/record/49yyyyxxxxxx/07-06-07---49yyyyxxxxxx-49yyyyzzzzzz-1.wav' created: samp.rate=8000, bufsize=4KB
15:50:28.705 conference.c Port 1 (strm0x5780c4) transmitting to port 3 (/var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/record/49yyyyxxxxxx/07-06-07---49yyyyxxxxxx-49yyyyzzzzzz-1.wav)
15:50:28.709 script_funcs.c Recording to /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/record/49yyyyxxxxxx/07-06-07---49yyyyxxxxxx-49yyyyzzzzzz-1.wav...
15:50:28.716 strm0x5780c4 Jitter buffer empty (prefetch=15)
15:50:33.578 sip_endpoint.c Processing incoming message: Request msg BYE/cseq=68 (rdata0x51ca74)
15:50:33.579 dlg0x571e1c Received Request msg BYE/cseq=68 (rdata0x51ca74)
15:50:33.580 tsx0x572a34 Transaction created for Request msg BYE/cseq=68 (rdata0x51ca74)
15:50:33.581 tsx0x572a34 Incoming Request msg BYE/cseq=68 (rdata0x51ca74) in state Null
15:50:33.582 tsx0x572a34 State changed from Null to Trying, event=RX_MSG
15:50:33.583 dlg0x571e1c Transaction tsx0x572a34 state changed to Trying
15:50:33.584 endpoint Response msg 200/BYE/cseq=68 (tdta0x5869f4) created
15:50:33.585 dlg0x571e1c Sending Response msg 200/BYE/cseq=68 (tdta0x5869f4)
15:50:33.586 tsx0x572a34 Sending Response msg 200/BYE/cseq=68 (tdta0x5869f4) in state Trying
15:50:33.587 sip_resolve.c Target '212.227.15.197:5060' type=UDP resolved to '212.227.15.197:5060' type=UDP
15:50:33.590 tsx0x572a34 State changed from Trying to Completed, event=TX_MSG
15:50:33.591 dlg0x571e1c Transaction tsx0x572a34 state changed to Completed
15:50:33.592 funcs.c ConnectionByInvite - Connection: 1
15:50:33.593 voip_events.c Call DISCONNECTED [port=1, reason=200 (OK)]
15:50:33.593 voip_events.c Hangup [1]
15:50:33.674 script_funcs.c Run script: /var/media/ftp/Solidstatedisk-Partition-0-1/dtmfbox/script/script_main.sh DISCONNECT VOIP INCOMING 1 -1 "49yyyyxxxxxx" "[email protected]" &
15:50:33.677 voip_funcs.c Destroy wave player...
15:50:33.678 voip_funcs.c Destroy wave recorder...
15:50:34.116 dlg0x571e1c Session count dec to 3 by mod-invite
15:51:05.596 tsx0x572a34 Timeout timer event
15:51:05.597 tsx0x572a34 State changed from Completed to Terminated, event=TIMER
15:51:05.598 dlg0x571e1c Transaction tsx0x572a34 state changed to Terminated
15:51:05.599 dlg0x571e1c Dialog destroyed
15:51:05.606 tsx0x572a34 Timeout timer event
15:51:05.607 tsx0x572a34 State changed from Terminated to Destroyed, event=TIMER
15:51:05.608 tdta0x5869f4 Destroying txdata Response msg 200/BYE/cseq=68 (tdta0x5869f4)
15:51:05.609 tsx0x572a34 Transaction destroyed!