Integration with Salesforce doesnt work on Server Side conf

[Mustafabozkurt]

Hello everyone,

We are using Client side Salesforce integration successfully on our company. But we would like to use server sided integration with Salesforce. We followed guide https://www.3cx.com/docs/salesforce-crm-integration/ but on Web interface on a client, when a call received nothing comes from Salesforce side but the caller number shows up.

Everything looks okay on configuration and when we checked the logs it shows something like this;

2018/01/16 10:12:03.715|2284|0027|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:15:02.586|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:16:01.999|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:21:32.496|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:27:40.787|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:28:29.388|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:31:56.865|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:33:30.630|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:34:19.994|2284|0003|Erro|Targeting segment is not found for target 943d9c24df74
2018/01/16 10:46:53.247|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 10:58:28.831|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 11:01:31.427|2284|0016|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/16 11:01:31.510|2284|0003|Erro|Targeting segment is not found for target 8920aa1f2e6a

Can anyone help us to identify the issue?
Client sided configuration works but server side configuration doenst work at all. We would like to uninstall clients and use web client.

Thank you..

 

[edossantos]

Hello @Mustafabozkurt,

Enable Verbose logs for the PBX, restart the services and then call again. That will add more details to the 3cxSystemService.log file.

Kind regards.

 

[Mustafabozkurt]

2018/01/17 14:39:33.631|3336|0003|Verb|Enqueue Updated.REGISTRATION.187
2018/01/17 14:39:33.633|3336|0006|Verb|Dequeue Updated.REGISTRATION.187
2018/01/17 14:39:55.137|3336|0003|Verb|Enqueue Updated.REGISTRATION.9
2018/01/17 14:39:55.141|3336|0003|Verb|Enqueue Updated.REGISTRATION.8
2018/01/17 14:39:55.142|3336|0006|Verb|Dequeue Updated.REGISTRATION.9
2018/01/17 14:39:55.142|3336|0006|Verb|Dequeue Updated.REGISTRATION.8
2018/01/17 14:40:10.211|3336|0003|Verb|Enqueue Updated.REGISTRATION.42
2018/01/17 14:40:10.212|3336|0006|Verb|Dequeue Updated.REGISTRATION.42
2018/01/17 14:40:18.993|3336|0004|Verb|WaitingEvent for updates:60000
2018/01/17 14:40:19.665|3336|0003|Verb|Enqueue Updated.PARAMETER.610
2018/01/17 14:40:19.668|3336|0006|Verb|Dequeue Updated.PARAMETER.610
2018/01/17 14:40:36.185|3336|0003|Verb|Enqueue Inserted.CONNECTION.216
2018/01/17 14:40:36.185|3336|0003|Verb|Not processed. Number=05303432353, connection=216, state=Dialing
2018/01/17 14:40:36.185|3336|0003|Verb|Add to processing. Number=05303432353, connection=216, state=Dialing
2018/01/17 14:40:36.185|3336|0003|Info|ProcessActiveConnections(). Attached entries to connection:
2018/01/17 14:40:36.185|3336|0003|Verb|LocalMatches=. Number=05303432353, connection=216, state=Dialing
2018/01/17 14:40:36.185|3336|0003|Info|CRM: Synchronize(...) called
2018/01/17 14:40:36.187|3336|0006|Verb|Dequeue Inserted.CONNECTION.216
2018/01/17 14:40:36.187|3336|0015|Info|CRM: maxConcurrency=2
2018/01/17 14:40:36.187|3336|0015|Info|CRM: making request to CRM. Number=05303432353
2018/01/17 14:40:36.187|3336|0015|Verb|Processing scenario 'Default'.
2018/01/17 14:40:36.187|3336|0015|Verb|Creating new HttpClient for Scenario authentication.
2018/01/17 14:40:36.188|3336|0015|Erro|Exception during number processing: Unknown expression: Url. Part: Url.
2018/01/17 14:40:36.188|3336|0021|Info|SQL: Synchronize(...) called. processed=False
2018/01/17 14:40:36.188|3336|0021|Info|SQL: Looking for number: 05303432353 in SQL
2018/01/17 14:40:36.188|3336|0021|Info|Looking for 3432353 according to Minimal Matching Strategy in 3CX
2018/01/17 14:40:36.320|3336|0003|Verb|Enqueue Inserted.CONNECTION.217
2018/01/17 14:40:36.322|3336|0006|Verb|Dequeue Inserted.CONNECTION.217
2018/01/17 14:40:36.362|3336|0003|Verb|Enqueue Inserted.CONNECTION.218
2018/01/17 14:40:36.363|3336|0006|Verb|Dequeue Inserted.CONNECTION.218
2018/01/17 14:40:36.460|3336|0003|Verb|Enqueue Inserted.CONNECTION.219
2018/01/17 14:40:36.460|3336|0006|Verb|Dequeue Inserted.CONNECTION.219
2018/01/17 14:40:38.603|3336|0003|Verb|Enqueue Updated.REGISTRATION.6
2018/01/17 14:40:38.604|3336|0006|Verb|Dequeue Updated.REGISTRATION.6
2018/01/17 14:40:47.413|3336|0003|Verb|Enqueue Deleted.CONNECTION.216
2018/01/17 14:40:47.416|3336|0003|Verb|Enqueue Deleted.CONNECTION.218
2018/01/17 14:40:47.418|3336|0003|Verb|Enqueue Deleted.CONNECTION.219
2018/01/17 14:40:47.420|3336|0003|Verb|Enqueue Deleted.CONNECTION.217
2018/01/17 14:40:47.422|3336|0006|Verb|Dequeue Deleted.CONNECTION.216
2018/01/17 14:40:47.422|3336|0006|Verb|Dequeue Deleted.CONNECTION.218
2018/01/17 14:40:47.422|3336|0006|Verb|Dequeue Deleted.CONNECTION.219
2018/01/17 14:40:47.422|3336|0006|Verb|Dequeue Deleted.CONNECTION.217
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] Receiver.OnReadyToRead()
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] _sock.Receive == 4
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] Received 1975 out of 1975 bytes
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] callOnPacketReceived()
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] Received 1975 bytes, start parsing
2018/01/17 14:40:47.666|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:36.182|IncomingCall|00000BF834A223D2_47|467862619333/Participant DN.10000/Line dn-name='' epname='05303432353@(Ln.10000@ISNET)'
>467862619333/Participant DN.10000/Line dn-name='' epname='05303432353@(Ln.10000@ISNET)'
disp_name=
number=05303432353
sip.contact=<sip:[email protected]:5060>
sip.from=<sip:[email protected]>;tag=3725178040-999667
sip.rl_uri=sip:[email protected];user=phone
sip.src_addr=213.143.226.101:5060
sip.to="02129874405" <sip:[email protected]:5060;user=phone>
target=4405

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:36.183|TargetAdded|00000BF834A223D2_47|df68c56c5067/Target DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405'
>df68c56c5067/Target DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405'
ep.dialing=
ep.originator.caller_num=
ep.originator.dial=
orig.dest=4405
orig.party.id=467862619333

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.415|RouteFailed|00000BF834A223D2_47|9504ec106c5c/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
>9504ec106c5c/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
ep.contact=sip:[email protected]:5060;transport=udp
ep.disp_name=
ep.to=
target.party.id=df68c56c5067
target.party.type=Target

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.415|RouteFailed|00000BF834A223D2_47|1965e11acb48/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
>1965e11acb48/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
ep.contact=sip:[email protected]:5488;rinstance=8ea55469c19e9ce0
ep.disp_name=
ep.to=
target.party.id=df68c56c5067
target.party.type=Target

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.415|RouteFailed|00000BF834A223D2_47|08330b13957a/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
>08330b13957a/Route DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]
ep.contact=sip:[email protected]:50419;rinstance=0-35b56f9924124c4f8537803f7b120884;ob
ep.disp_name=
ep.to=
target.party.id=df68c56c5067
target.party.type=Target

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.415|PartyRemoved|00000BF834A223D2_47|467862619333/None
>467862619333/None

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.422|PartyRemoved|00000BF834A223D2_47|9504ec106c5c/None
>9504ec106c5c/None

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.422|PartyRemoved|00000BF834A223D2_47|1965e11acb48/None
>1965e11acb48/None

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.422|PartyRemoved|00000BF834A223D2_47|08330b13957a/None
>08330b13957a/None

2018/01/17 14:40:47.667|3336|0009|Trac|[CHR] Adding event: 2018-01-17 14:40:47.665|Disconnected|00000BF834A223D2_47|Party.???
>Party.???
replaced_by.chid=

2018/01/17 14:40:47.667|3336|0009|Trac|Saving joined call:
2018/01/17 14:40:47.667|3336|0009|Trac|Executing SQL: INSERT into cl_calls (id,start_time,end_time,is_answered,ringing_dur)
VALUES (DEFAULT,'2018-01-17 11:40:36','2018-01-17 11:40:47',FALSE,'00:00:11.233779')
RETURNING id
2018/01/17 14:40:47.668|3336|0009|Trac| * Participant 467862619333/Participant DN.10000/Line dn-name='' epname='05303432353'; not answered
2018/01/17 14:40:47.669|3336|0009|Trac|Executing SQL: INSERT into cl_party_info (id,dn_type,dn,caller_number,display_name,dn_class,firstlastname)
VALUES (DEFAULT,1,'10000','05303432353','',0,'')
RETURNING id
2018/01/17 14:40:47.669|3336|0009|Trac|Executing SQL: INSERT into cl_participants (id,call_id,info_id,role,is_inbound,end_status,forward_reason,failure_reason,start_time,end_time,billing_group)
VALUES (DEFAULT,25958,104265,1,TRUE,1,0,1,'2018-01-17 11:40:36','2018-01-17 11:40:47','')
RETURNING id
2018/01/17 14:40:47.670|3336|0009|Trac| * Target df68c56c5067/Target DN.4405/Extension dn-name='MUSTAFA BOZKURT' epname='Ext.4405' [Cancelled]; originated by 467862619333
2018/01/17 14:40:47.670|3336|0009|Trac|Executing SQL: INSERT into cl_party_info (id,dn_type,dn,caller_number,display_name,dn_class,firstlastname)
VALUES (DEFAULT,0,'4405','Ext.4405','MUSTAFA BOZKURT',0,'')
RETURNING id
2018/01/17 14:40:47.670|3336|0009|Trac|Executing SQL: INSERT into cl_participants (id,call_id,info_id,role,is_inbound,end_status,forward_reason,failure_reason,start_time,end_time)
VALUES (DEFAULT,25958,104266,2,FALSE,2,0,19,'2018-01-17 11:40:36','2018-01-17 11:40:47')
RETURNING id
2018/01/17 14:40:47.671|3336|0009|Trac| Saving Pbx call 00000BF834A223D2_47
2018/01/17 14:40:47.671|3336|0009|Info| #1: Seg[1/#1] 467862619333(05303432353)=>df68c56c5067(Ext.4405) (14:40:36.182..14:40:47.415) Ringing Failed_Cancelled
2018/01/17 14:40:47.671|3336|0009|Trac|Executing SQL: INSERT into cl_segments (id,call_id,seq_order,seq_group,src_part_id,dst_part_id,start_time,end_time,type,action_id)
VALUES (DEFAULT,25958,1,1,104265,104266,'2018-01-17 11:40:36','2018-01-17 11:40:47',1,418)
RETURNING id
2018/01/17 14:40:47.672|3336|0009|Trac|Executing SQL: INSERT into myphone_callhistory_v14 (idmpch14,call_id,calltype,dnowner,party_dn,party_dntype,party_name,party_callerid,start_time,end_time,end_status,end_status_hint)
VALUES (DEFAULT,25958,1,'4405','10000',128,'','05303432353','2018-01-17 11:40:36','2018-01-17 11:40:47',5,3)
RETURNING idmpch14
2018/01/17 14:40:47.673|3336|0009|Info|CHReceiver_NewMissedCall called
2018/01/17 14:40:47.676|3336|0009|Info|PUSH active=True
2018/01/17 14:40:47.677|3336|0003|Verb|Enqueue Updated.S_MISSEDCALLSSTAT.66
2018/01/17 14:40:47.677|3336|0031|Info|PUSH type=AndroidGcm, data=
2018/01/17 14:40:47.677|3336|0006|Verb|Dequeue Updated.S_MISSEDCALLSSTAT.66

 

[edossantos]

From the logs, we see that the issue is the authentication. When the integration makes the authentication, Salesforce returns a parameter "instance_url" with the information to perform the following requests. In this case, it seems that the authentication is failing, and then you don't have the Url. Please check your credentials configuration.