should it be possible to use ivr apps as di_dialer apps? i tried with
the small ivr announcement app that i posted to this list a couple of
months ago, sip phone was ringing, i answered, and then nothing
happened. i don't see any error messages in debug log. neither i see
that announcement app would have been started, because it prints
debug("Starting announcement ...") at session start.
-- juha
taimen:# Apr 21 17:08:47 localhost sems[25898]: Debug:
(XMLRPC2DI.cpp)(execute)(355): XMLRPC2DI: factory 'di_dial' function 'dial'
Apr 21 17:08:47 localhost sems[25898]: Debug: (DIDial.cpp)(dialout)(153):
dialout application 'announcement', user 'Juha', from 'sip:[EMAIL PROTECTED]',
to 'sip:[EMAIL PROTECTED]'
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmDtmfDetector.cpp)(setInbandDetector)(209): Setting spandsp DTMF detector
Apr 21 17:08:47 localhost sems[25898]: Debug:
(IvrSipDialog.cpp)(IvrSipDialog_new)(38): IvrSipDialog_new
Apr 21 17:08:47 localhost sems[25898]: Debug:
(IvrSipRequest.cpp)(IvrSipRequest_new)(61): IvrSipRequest_new
Apr 21 17:08:47 localhost sems[25898]: Debug:
(IvrDialogBase.cpp)(IvrDialogBase_new)(67): IvrDialogBase_new
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmSipDialog.cpp)(updateStatusFromLocalRequest)(96): dialog callid is empty,
updating from UACRequest
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmSipDialog.cpp)(updateStatusFromLocalRequest)(99): local_tag =
26E9B7F3-480C9FEF0006B469-B75DCB90
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmRtpStream.cpp)(setLocalPort)(162): local rtp port set to 50008
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(set_next_hop)(405): next_hop:next_port is
<192.98.101.10:5080>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(parse_uri.cpp)(parse_sip_uri)(314): Converted URI port () to int (5060)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(send_request)(532): Sending to 192.98.101.10:5080 <INVITE
sip:[EMAIL PROTECTED] SIP/2.0^M Via: SIP/2.0/UDP
192.98.101.10:5090;branch=z9hG4bKH6FjSaow^M From: <sip:[EMAIL
PROTECTED]>;tag=26E9B7F3-480C9FEF0006B469-B75DCB90^M To: <sip:[EMAIL
PROTECTED]>^M CSeq: 10 INVITE^M Call-ID: [EMAIL PROTECTED] Contact: <sip:[EMAIL
PROTECTED]:5090>^M User-Agent: Sip Express Media Server (1.0.0-pre-r895M
(i386/linux))^M Max-Forwards: 70^M Content-Type: application/sdp^M
Content-Length: 426^M ^M v=0^M o=sems 2002831073 86318033 IN IP4
192.98.101.10^M s=session^M c=IN IP4 192.98.101.10^M t=0 0^M m=audio 50008
RTP/AVP 101 102 8 0 3 2 96 97 98 99 100^M a=rtpmap:101 iLBC/8000^M a=rtpmap:102
speex/8000^M a=rtpmap:8 PCMA/8000^M a=rtpmap:0 PCMU/8000^M a=rtpmap:3
GSM/8000^M a=rtpmap:2 G721/8000^M a=rtpmap:96 telephone-event/8000^M
a=rtpmap:97 G726-32/8000^M a=rtpmap:98 G726-24/8000^M a=rtpmap:99
G726-40/8000^M a=rtpmap:100 G726-16/8000^M >
Apr 21 17:08:47 localhost sems[25898]: Debug:
(sip_trans.cpp)(reset_timer)(119): New timer of type 0x1 at time=256205
Apr 21 17:08:47 localhost sems[25898]: Debug:
(sip_trans.cpp)(reset_timer)(119): New timer of type 0x2 at time=257780
Apr 21 17:08:47 localhost /usr/sbin/openser[1084]: LOG: INVITE <sip:[EMAIL
PROTECTED]> by <sip:[EMAIL PROTECTED]> is from Application Server
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(704): parse_sip_msg returned 0
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(788): Reply matched an existing transaction
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(update_uac_trans)(831): reply code = 100
Apr 21 17:08:47 localhost sems[25898]: Debug: (sip_trans.cpp)(reset_timer)(75):
Clearing old timer of type 0x1
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(411): Received reply: 100 Trying
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(412): rep.callid = <[EMAIL PROTECTED]>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(413): rep.local_tag =
<26E9B7F3-480C9FEF0006B469-B75DCB90>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(414): rep.remote_tag = <>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(415): cseq = <10>
Apr 21 17:08:47 localhost sems[25898]: Debug: (sip_parser.cpp)(~sip_msg)(92):
~sip_msg()
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(48): AmEventQueue: trying to post event
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(56): AmEventQueue: event posted
Apr 21 17:08:47 localhost sems[25898]: Debug: (sip_parser.cpp)(~sip_msg)(92):
~sip_msg()
Apr 21 17:08:47 localhost sems[25898]: Debug: (udp_trsp.cpp)(run)(90): before
recvmsg (192.98.101.10:5090)
Apr 21 17:08:47 localhost /usr/sbin/openser[1084]: LOG: Routing initial INVITE
to <sip:[EMAIL PROTECTED]:5074> and <<null>>
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmThread.cpp)(_start)(68):
Thread 3062369168 is starting.
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(69): before processing event
Apr 21 17:08:47 localhost sems[25898]: Debug: (Ivr.cpp)(process)(760):
IvrDialog::process
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(process)(499):
AmSession::process
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(process)(503):
Session received SIP Event
Apr 21 17:08:47 localhost sems[25898]: Debug:
(IvrSipReply.cpp)(IvrSipReply_new)(60): IvrSipReply_new
Apr 21 17:08:47 localhost sems[25898]: Debug:
(Ivr.cpp)(callPyEventHandler)(691): method onSipReply is not implemented,
trying default one
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(163): onSipReply: 100 Trying (fwd=0)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(164): onSipReply: content-type =
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmSipDialog.cpp)(updateStatus)(178): updateStatus(reply): transaction found!
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(onSipReply)(610):
Dialog status stays Pending (stopped=false)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(relayEvent)(185): AmB2BSession::relayEvent: id=
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(71): event processed
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(run)(361): [EMAIL
PROTECTED] dlg.getUACTransPending() = 1
Apr 21 17:08:47 localhost sems[25898]: Debug: (wheeltimer.cpp)(~timer)(19):
timer::~timer(this=0x8287ee0)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(704): parse_sip_msg returned 0
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(788): Reply matched an existing transaction
Apr 21 17:08:47 localhost sems[25898]: Debug:
(trans_layer.cpp)(update_uac_trans)(831): reply code = 180
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(411): Received reply: 180 Ringing
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(412): rep.callid = <[EMAIL PROTECTED]>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(413): rep.local_tag =
<26E9B7F3-480C9FEF0006B469-B75DCB90>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(414): rep.remote_tag = <eycqf>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(415): cseq = <10>
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(48): AmEventQueue: trying to post event
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(56): AmEventQueue: event posted
Apr 21 17:08:47 localhost sems[25898]: Debug: (sip_parser.cpp)(~sip_msg)(92):
~sip_msg()
Apr 21 17:08:47 localhost sems[25898]: Debug: (udp_trsp.cpp)(run)(90): before
recvmsg (192.98.101.10:5090)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(69): before processing event
Apr 21 17:08:47 localhost sems[25898]: Debug: (Ivr.cpp)(process)(760):
IvrDialog::process
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(process)(499):
AmSession::process
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(process)(503):
Session received SIP Event
Apr 21 17:08:47 localhost sems[25898]: Debug:
(IvrSipReply.cpp)(IvrSipReply_new)(60): IvrSipReply_new
Apr 21 17:08:47 localhost sems[25898]: Debug:
(Ivr.cpp)(callPyEventHandler)(691): method onSipReply is not implemented,
trying default one
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(163): onSipReply: 180 Ringing (fwd=0)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(164): onSipReply: content-type =
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmSipDialog.cpp)(updateStatus)(178): updateStatus(reply): transaction found!
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(onSipReply)(610):
Dialog status stays Pending (stopped=false)
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(relayEvent)(185): AmB2BSession::relayEvent: id=
Apr 21 17:08:47 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(71): event processed
Apr 21 17:08:47 localhost sems[25898]: Debug: (AmSession.cpp)(run)(361): [EMAIL
PROTECTED] dlg.getUACTransPending() = 1
Apr 21 17:08:49 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(704): parse_sip_msg returned 0
Apr 21 17:08:49 localhost sems[25898]: Debug:
(trans_layer.cpp)(received_msg)(788): Reply matched an existing transaction
Apr 21 17:08:49 localhost sems[25898]: Debug:
(trans_layer.cpp)(update_uac_trans)(831): reply code = 200
Apr 21 17:08:49 localhost sems[25898]: Debug:
(sip_trans.cpp)(reset_all_timers)(138): remove_timer(0x829b948)
Apr 21 17:08:49 localhost sems[25898]: Debug: (sip_parser.cpp)(~sip_msg)(92):
~sip_msg()
Apr 21 17:08:49 localhost sems[25898]: Debug:
(hash_table.cpp)(remove_trans)(400): ~sip_trans()
Apr 21 17:08:49 localhost sems[25898]: Debug:
(parse_uri.cpp)(parse_sip_uri)(314): Converted URI port () to int (5060)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(trans_layer.cpp)(set_next_hop)(405): next_hop:next_port is
<192.98.101.10:5080>
Apr 21 17:08:49 localhost sems[25898]: Debug:
(trans_layer.cpp)(send_200_ack)(1158): About to send 200 ACK: <ACK sip:[EMAIL
PROTECTED]:5074 SIP/2.0^M Via: SIP/2.0/UDP
192.98.101.10;branch=z9hG4bKP6FjSa8a^M Route:
<sip:192.98.101.10;r2=on;lr>,<sip:192.98.101.10:5080;r2=on;lr>^M From:
<sip:[EMAIL PROTECTED]>;tag=26E9B7F3-480C9FEF0006B469-B75DCB90^M To:
<sip:[EMAIL PROTECTED]>;tag=eycqf^M Call-ID: [EMAIL PROTECTED] Max-Forwards:
10^M CSeq: 10 ACK^M ^M >
Apr 21 17:08:49 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(411): Received reply: 200 OK
Apr 21 17:08:49 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(412): rep.callid = <[EMAIL PROTECTED]>
Apr 21 17:08:49 localhost /usr/sbin/openser[1086]: LOG: Routing in-dialog ACK
from <sip:[EMAIL PROTECTED]> to <sip:[EMAIL PROTECTED]:5074>
Apr 21 17:08:49 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(413): rep.local_tag =
<26E9B7F3-480C9FEF0006B469-B75DCB90>
Apr 21 17:08:49 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(414): rep.remote_tag = <eycqf>
Apr 21 17:08:49 localhost sems[25898]: Debug:
(SipCtrlInterface.cpp)(handleSipMsg)(415): cseq = <10>
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(48): AmEventQueue: trying to post event
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(postEvent)(56): AmEventQueue: event posted
Apr 21 17:08:49 localhost sems[25898]: Debug: (sip_parser.cpp)(~sip_msg)(92):
~sip_msg()
Apr 21 17:08:49 localhost sems[25898]: Debug: (udp_trsp.cpp)(run)(90): before
recvmsg (192.98.101.10:5090)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(69): before processing event
Apr 21 17:08:49 localhost sems[25898]: Debug: (Ivr.cpp)(process)(760):
IvrDialog::process
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(process)(499):
AmSession::process
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(process)(503):
Session received SIP Event
Apr 21 17:08:49 localhost sems[25898]: Debug:
(IvrSipReply.cpp)(IvrSipReply_new)(60): IvrSipReply_new
Apr 21 17:08:49 localhost sems[25898]: Debug:
(Ivr.cpp)(callPyEventHandler)(691): method onSipReply is not implemented,
trying default one
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(163): onSipReply: 200 OK (fwd=0)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(onSipReply)(164): onSipReply: content-type = application/sdp
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSipDialog.cpp)(updateStatus)(178): updateStatus(reply): transaction found!
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(onSipReply)(607):
Dialog status changed Pending -> Connected (stopped=false)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(379): parse_sdp_line_ex: parsing sdp messages
..... v=0^M o=twinkle 1562160541 161659428 IN IP4 192.98.101.10^M s=-^M c=IN
IP4 192.98.101.10^M t=0 0^M m=audio 8034 RTP/AVP 101 96^M a=rtpmap:101
iLBC/8000^M a=rtpmap:96 telephone-event/8000^M a=fmtp:96 0-15^M
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(391): parse_sdp_line_ex: found version
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(398): parse_sdp_line_ex: found origin
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_origin)(923): parse_sdp_line_ex: parse_sdp_origin:
parsing sdp origin
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_origin)(1014): parse_sdp_line_ex: parse_sdp_origin: done
parsing sdp origin
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(406): parse_sdp_line_ex: found session
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(436): parse_sdp_line_ex: found connection
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_connection)(527): parse_sdp_line_ex:
parse_sdp_connection: parsing sdp connection
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_connection)(585): parse_sdp_line_ex:
parse_sdp_connection: done parsing sdp connection
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(421): parse_sdp_line_ex: found unknown line 't'
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(443): parse_sdp_line_ex: found media
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_media)(605): parse_sdp_line_ex: parse_sdp_media: parsing
media description, audio 8034 RTP/AVP 101 96^M a=rtpmap:101 iLBC/8000^M
a=rtpmap:96 telephone-event/8000^M a=fmtp:96 0-15^M
Apr 21 17:08:49 localhost sems[25898]: Debug: (wheeltimer.cpp)(~timer)(19):
timer::~timer(this=0x829b948)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_media)(694): parse_sdp_line_ex: parse_sdp_media: done
parsing media description
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSdp.cpp)(parse_sdp_attr)(799):
found media attr 'rtpmap' type '101'
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSdp.cpp)(parse_sdp_attr)(799):
found media attr 'rtpmap' type '96'
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSdp.cpp)(parse_sdp_attr)(848):
found media attr 'fmtp' type '96'
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(parse_sdp_line_ex)(508): parse_sdp_line_ex: parsing sdp message
done :)
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSdp.cpp)(getCompatiblePayloads)(329): using global address:
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(negotiate)(311):
remote party supports telephone events (pt=96)
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmRtpStream.cpp)(setRAddr)(358):
RTP remote address set to 192.98.101.10:8034
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmRtpAudio.cpp)(init)(144):
AmRtpAudio::init(...)
Apr 21 17:08:49 localhost sems[25898]: Debug: (ilbc.c)(iLBC_create)(161): ilbc
with format parameters : '', mode=30.
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmSession.cpp)(acceptAudio)(737): Sending Rtp data to 192.98.101.10/8034
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(onSipReply)(634):
no audio input and output set. Session will not be attached to MediaProcessor.
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmB2BSession.cpp)(relayEvent)(185): AmB2BSession::relayEvent: id=
Apr 21 17:08:49 localhost sems[25898]: Debug:
(AmEventQueue.cpp)(processEvents)(71): event processed
Apr 21 17:08:49 localhost sems[25898]: Debug: (AmSession.cpp)(run)(361): [EMAIL
PROTECTED] dlg.getUACTransPending() = 0
_______________________________________________
Semsdev mailing list
[email protected]
http://lists.iptel.org/mailman/listinfo/semsdev
