Re: [Asterisk-java-users] Problem with originateToExtensionAsync() andLocal/ channels

SourceForge Headquarters 225 Broadway Suite 1600 San Diego, CA 92101 +1 (858) 422-6466

Hello Ken,

Could we see a snippet of how you setup your callback and do the
originate? And even more useful than that would be an ngrep of the
conversation between AJ and Asterisk.

Thanks,

Martin Smith, Systems Developer
ma...@be...
Bureau of Economic and Business Research
University of Florida
(352) 392-0171 Ext. 221=20

=20

> -----Original Message-----
> From: ast...@li...=20
> [mailto:ast...@li...] On=20
> Behalf Of Ken N. March
> Sent: Friday, July 06, 2007 3:30 PM
> To: ast...@li...
> Subject: [Asterisk-java-users] Problem with=20
> originateToExtensionAsync() andLocal/ channels
>=20
> Hi there,
>=20
> I'm having a problem with
> DefaultAsteriskServer.originateToExtensionAsync() with=20
> asterisk-java 0.3
> and Asterisk 1.4 (1.4.4 presently) when using Local channels.=20
>  On about
> 50% or so of the calls I originate using this method, the onDialing()
> method on my callback is not called. =20
>=20
> Here's some logging for a call that works, and a call that doesn't
> (sorry for the really long lines!).  In this case, both calls=20
> are being
> sent to Local/7777@longdistance, which is just a simple extension that
> answers and plays back some DTMF digits, simulating a user.
>=20
> 2007-07-06 12:44:32,830 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> response with internalActionId '25769070_6':
> org.asteriskjava.manager.response.ManagerResponse:
> actionId=3D'AJ_ORIGINATE_0'; message=3D'Originate successfully =
queued';
> response=3D'Success'; uniqueId=3D'null'; systemHashcode=3D19072308
> 2007-07-06 12:44:32,831 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewChannelEvent[dateReceived=3DFri Jul =
06
> 12:44:32 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747472.797',state=3D'Down'
> ,timestamp
> =3D'null',channel=3D'Local/7777@longdistance-c4d6,1',systemHashcod
> e=3D2796536]
> 2007-07-06 12:44:32,840 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewChannelEvent[dateReceived=3DFri Jul =
06
> 12:44:32 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747472.798',state=3D'Ring'
> ,timestamp
> =3D'null',channel=3D'Local/7777@longdistance-c4d6,2',systemHashcod
> e=3D20830161
> ]
> 2007-07-06 12:44:32,841 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewCallerIdEvent[dateReceived=3DF
> ri Jul 06
> 12:44:32 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<Unknown>',cidcallingpr
> es=3D'0',cal
> leridname=3D'<Unknown>',callerid=3D'<Unknown>',uniqueid=3D'118374747
> 2.797',cid
> callingprestxt=3D'Presentation Allowed, Not
> Screened',timestamp=3D'null',channel=3D'Local/7777@longdistance-c4
> d6,1',syst
> emHashcode=3D19136225]
> 2007-07-06 12:44:32,865 INFO
> [org.asteriskjava.live.internal.ChannelManager] Adding channel
> Local/7777@longdistance-c4d6,1(1183747472.797)
> 2007-07-06 12:44:32,867 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> response with internalActionId '25769070_7':
> org.asteriskjava.manager.response.ManagerResponse: actionId=3D'null';
> message=3D'null'; response=3D'Success'; uniqueId=3D'null';
> systemHashcode=3D5942570
> 2007-07-06 12:44:32,867 INFO
> [ca.ums.mdc.service.AlarmCalloutOriginateCallback]
> Local/7777@longdistance-c4d6,1: Beginning dial.
>=20
> In this case, onDialing() is called (which is what logs the final
> "Beginning dial" line above), and then eventually onSuccess()=20
> is called
> once the line is answered.  However, the next call, things=20
> don't work so
> well:
>=20
> 2007-07-06 12:44:48,050 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> response with internalActionId '25769070_9':
> org.asteriskjava.manager.response.ManagerResponse:
> actionId=3D'AJ_ORIGINATE_1'; message=3D'Originate successfully =
queued';
> response=3D'Success'; uniqueId=3D'null'; systemHashcode=3D30123815
> 2007-07-06 12:44:48,062 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewChannelEvent[dateReceived=3DFri Jul =
06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747488.799',state=3D'Down'
> ,timestamp
> =3D'null',channel=3D'Local/7777@longdistance-6370,1',systemHashcod
> e=3D21689449
> ]
> 2007-07-06 12:44:48,063 INFO
> [org.asteriskjava.live.internal.ChannelManager] Adding channel
> Local/7777@longdistance-6370,1(1183747488.799)
> 2007-07-06 12:44:48,100 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> response with internalActionId '25769070_10':
> org.asteriskjava.manager.response.ManagerError: actionId=3D'null';
> message=3D'No such channel'; response=3D'Error'; uniqueId=3D'null';
> systemHashcode=3D32790978
> 2007-07-06 12:44:48,190 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewChannelEvent[dateReceived=3DFri Jul =
06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747488.800',state=3D'Ring'
> ,timestamp
> =3D'null',channel=3D'Local/7777@longdistance-6370,2',systemHashcod
> e=3D8043179]
> 2007-07-06 12:44:48,190 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewCallerIdEvent[dateReceived=3DF
> ri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<Unknown>',cidcallingpr
> es=3D'0',cal
> leridname=3D'<Unknown>',callerid=3D'<Unknown>',uniqueid=3D'118374748
> 8.799',cid
> callingprestxt=3D'Presentation Allowed, Not
> Screened',timestamp=3D'null',channel=3D'Local/7777@longdistance-63
> 70,1',syst
> emHashcode=3D24458207]
> 2007-07-06 12:44:48,191 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewExtenEvent[dateReceived=3DFri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',extension=3D'7777',appdata=3D'null',uniq
> ueid=3D'1183
> 747488.800',context=3D'longdistance',timestamp=3D'null',channel=3D'L
> ocal/7777@
> longdistance-6370,2',priority=3D'1',application=3D'Answer',systemH
> ashcode=3D27
> 306108]
> 2007-07-06 12:44:48,192 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewStateEvent[dateReceived=3DFri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747488.799',state=3D'Up',t
> imestamp=3D'
> =
null',channel=3D'Local/7777@longdistance-6370,1',systemHashcode=3D9795370=
]
> 2007-07-06 12:44:48,192 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> =
org.asteriskjava.manager.event.OriginateResponseEvent[dateReceived=3DFri
> Jul 06 12:44:48 MDT
> 2007,privilege=3D'call,all',internalactionid=3D'25769070_9',caller
> idname=3D'<u
> nknown>',exten=3D's',actionid=3D'AJ_ORIGINATE_1',response=3D'Success
> ',callerid
> num=3D'<unknown>',reason=3D'4',context=3D'ExecAGIApp',uniqueid=3D'1183
> 747488.799
> ',timestamp=3D'null',channel=3D'Local/7777@longdistance-6370,1',sy
> stemHashco
> de=3D12741325]
> 2007-07-06 12:44:48,193 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewStateEvent[dateReceived=3DFri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',calleridnum=3D'<unknown>',calleridname
> =3D'<unknown
> >',callerid=3D'<unknown>',uniqueid=3D'1183747488.800',state=3D'Up',t
> imestamp=3D'
> null',channel=3D'Local/7777@longdistance-6370,2',systemHashcode=3D
> 32620358]
> 2007-07-06 12:44:48,193 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewExtenEvent[dateReceived=3DFri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',extension=3D'7777',appdata=3D'3.5',uniqu
> eid=3D'11837
> 47488.800',context=3D'longdistance',timestamp=3D'null',channel=3D'Lo
> cal/7777@l
> ongdistance-6370,2',priority=3D'2',application=3D'Wait',systemHash
> code=3D44987
> 53]
> 2007-07-06 12:44:48,194 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> org.asteriskjava.manager.event.NewExtenEvent[dateReceived=3DFri Jul 06
> 12:44:48 MDT
> 2007,privilege=3D'call,all',extension=3D's',appdata=3D'agi://127.0.0
> .1:4573/ca
> llout.agi?acked=3D0',uniqueid=3D'1183747488.799',context=3D'ExecAGIA
> pp',timest
> amp=3D'null',channel=3D'Local/7777@longdistance-6370,1',priority=3D'
> 1',applica
> tion=3D'AGI',systemHashcode=3D12411662]
> 2007-07-06 12:44:48,195 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> event:
> ca.ums.mdc.voice.asterisk.userevents.AlarmCalloutAnsweredEvent
> [dateRecei
> ved=3DFri Jul 06 12:44:48 MDT
> 2007,privilege=3D'user,all',answerchannel=3D'Local/7777@longdistan
> ce-6370,1'
> ,messageid=3D'1825',uniqueid=3D'null',timestamp=3D'null',userevent=3D'
> AlarmCallo
> utAnswered',channel=3D'null',systemHashcode=3D28460584]
> 2007-07-06 12:44:48,195 INFO
> [ca.ums.mdc.service.AlarmCalloutOriginateCallback] 1825: answered
> (Local/7777@longdistance-6370,1)
> 2007-07-06 12:44:48,200 INFO
> [org.asteriskjava.live.internal.ChannelManager] Adding channel
> Local/7777@longdistance-6370,2(1183747488.800)
> 2007-07-06 12:44:48,210 DEBUG
> [org.asteriskjava.manager.internal.ManagerConnectionImpl] Dispatching
> response with internalActionId '25769070_11':
> org.asteriskjava.manager.response.ManagerResponse: actionId=3D'null';
> message=3D'null'; response=3D'Success'; uniqueId=3D'null';
> systemHashcode=3D18613390
> 2007-07-06 12:44:48,220 INFO
> [ca.ums.mdc.service.AlarmCalloutOriginateCallback]
> Local/7777@longdistance-6370,1: Call Answered.
> 2007-07-06 12:44:48,230 DEBUG
> [ca.ums.mdc.service.AlarmCalloutOriginateCallback]
> Local/7777@longdistance-6370,1: *** No dial event received prior to
> answer ***
>=20
> In this case, onDialing() is never called, even though=20
> onSuccess() does
> get called once the call is answered (last 2 log lines).
>=20
> Any ideas?  About the only thing "odd" about this application=20
> is that it
> runs within JBoss (4.0.5), but other than that it's pretty straight
> forward.
>=20
> The other thing I've noticed is that the very first call I originate
> when the application starts always works, but it's hit or miss whether
> or not the next call that is made works.  Not sure if that helps?
>=20
> --
> Ken March, Senior IT Administrator
> Universal Measurement Solutions (UMS) Ltd. / Kilowatts Design Company
> Inc.
>=20
>=20
> --------------------------------------------------------------
> -----------
> This SF.net email is sponsored by DB2 Express
> Download DB2 Express C - the FREE version of DB2 express and take
> control of your XML. No limits. Just data. Click to get it now.
> http://sourceforge.net/powerbar/db2/
> _______________________________________________
> Asterisk-java-users mailing list
> Ast...@li...
> https://lists.sourceforge.net/lists/listinfo/asterisk-java-users
>=20