version: 2.23.0
I have a stand alone java app that uses camel to subscribe to
salesforce platform event.
I see frequent disconnects from salesforce causing the subscription
client to fail at some point.
attached the debug logs (1.txt).
I am hitting the same issue with both AuthenticationType.USERNAME_PASSWORD and
AuthenticationType.JWT.
doesn't seem like the camel-salesforce component retries on below
message ( when advice is reconnect=none).
[CHANNEL:META_CONNECT]: {clientId=4ax18g474mdxneef13qtpln3p55cw,
advice=(reconnect=none{reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client,
successful=false}
Found https://developer.salesforce.com/forums/?id=906F0000000ApCYIA0.
Can we follow the suggestion " whenever 403 error occurs you will be
getting connection broken. SO do one thing add listener
metaUnSucessfulListener.
Once you recieve the error, the method will get called and in this
method first unsubsicribe the existing insance of cometd, create fresh
instance."
Note that I am hitting this issue even after disabling salesforce
lightening mobile alerts.
few other relevant links:
https://developer.salesforce.com/docs/atlas.en-us.api_streaming.meta/api_streaming/streaming_handling_errors.htm
Thanks,
Sowjanya.
Starting Camel. Use ctrl + c to terminate the JVM.
2018-10-03 19:16:41 INFO DefaultCamelContext:3138 - Apache Camel
2.23.0-SNAPSHOT (CamelContext: camel-1) is starting
2018-10-03 19:16:41 INFO DefaultCamelContext:3138 - Apache Camel
2.23.0-SNAPSHOT (CamelContext: camel-1) is starting
2018-10-03 19:16:41 INFO ManagedManagementStrategy:205 - JMX is enabled
2018-10-03 19:16:41 INFO ManagedManagementStrategy:205 - JMX is enabled
2018-10-03 19:16:41 INFO DefaultTypeConverter:66 - Type converters loaded
(core: 195, classpath: 0)
2018-10-03 19:16:41 INFO DefaultTypeConverter:66 - Type converters loaded
(core: 195, classpath: 0)
2018-10-03 19:16:41 DEBUG SalesforceComponent:324 - Using shared login
configuration: SalesforceLoginConfig[instanceUrl= 'null',
loginUrl='https://test.salesforce.com/',clientId='3MVG9bx.kiqxiA6Z5rGR7K.sg6HKj09ngHXgLcd2.99r3diB3Mnz6gHEAqxyy4mAH25Y8WAhB2nEtRVsRTA4L',
clientSecret='********', refreshToken='null',
userName='smudim...@tableau.com.dev', password=********'null',
keystore=********'KeyStoreParameters[type=jks, password=********,
provider=null, resource=./keystore.jks]', lazyLogin=false]
2018-10-03 19:16:41 DEBUG SalesforceComponent:324 - Using shared login
configuration: SalesforceLoginConfig[instanceUrl= 'null',
loginUrl='https://test.salesforce.com/',clientId='3MVG9bx.kiqxiA6Z5rGR7K.sg6HKj09ngHXgLcd2.99r3diB3Mnz6gHEAqxyy4mAH25Y8WAhB2nEtRVsRTA4L',
clientSecret='********', refreshToken='null',
userName='smudim...@tableau.com.dev', password=********'null',
keystore=********'KeyStoreParameters[type=jks, password=********,
provider=null, resource=./keystore.jks]', lazyLogin=false]
2018-10-03 19:16:41 DEBUG SalesforceComponent:324 - Using shared login
configuration: SalesforceLoginConfig[instanceUrl= 'null',
loginUrl='https://test.salesforce.com/',clientId='3MVG9bx.kiqxiA6Z5rGR7K.sg6HKj09ngHXgLcd2.99r3diB3Mnz6gHEAqxyy4mAH25Y8WAhB2nEtRVsRTA4L',
clientSecret='********', refreshToken='null',
userName='smudim...@tableau.com.dev', password=********'null',
keystore=********'KeyStoreParameters[type=jks, password=********,
provider=null, resource=./keystore.jks]', lazyLogin=false]
2018-10-03 19:16:41 INFO log:193 - Logging initialized @2358ms to
org.eclipse.jetty.util.log.Slf4jLog
2018-10-03 19:16:41 INFO SSLContextParameters:294 - Available providers: SUN
version 1.8.
2018-10-03 19:16:41 INFO SSLContextParameters:294 - Available providers: SUN
version 1.8.
2018-10-03 19:16:42 INFO SalesforceSession:152 - Login at Salesforce loginUrl:
https://test.salesforce.com/services/oauth2/token
2018-10-03 19:16:42 INFO SalesforceSession:152 - Login at Salesforce loginUrl:
https://test.salesforce.com/services/oauth2/token
2018-10-03 19:16:42 INFO SalesforceSession:152 - Login at Salesforce loginUrl:
https://test.salesforce.com/services/oauth2/token
2018-10-03 19:16:43 INFO SalesforceSession:249 - Login successful
2018-10-03 19:16:43 INFO SalesforceSession:249 - Login successful
2018-10-03 19:16:43 INFO SalesforceSession:249 - Login successful
2018-10-03 19:16:43 WARN SalesforceComponent:367 - Missing property packages,
getSObject* operations will NOT work without property rawPayload=true
2018-10-03 19:16:43 WARN SalesforceComponent:367 - Missing property packages,
getSObject* operations will NOT work without property rawPayload=true
2018-10-03 19:16:43 WARN SalesforceComponent:367 - Missing property packages,
getSObject* operations will NOT work without property rawPayload=true
2018-10-03 19:16:43 DEBUG SalesforceComponent:243 - Creating endpoint for:
event/Netsuite_Customer_Event__e
2018-10-03 19:16:43 DEBUG SalesforceComponent:243 - Creating endpoint for:
event/Netsuite_Customer_Event__e
2018-10-03 19:16:43 DEBUG SalesforceComponent:243 - Creating endpoint for:
event/Netsuite_Customer_Event__e
2018-10-03 19:16:43 INFO DefaultCamelContext:3465 - StreamCaching is not in
use. If using streams then its recommended to enable stream caching. See more
details at http://camel.apache.org/stream-caching.html
2018-10-03 19:16:43 INFO DefaultCamelContext:3465 - StreamCaching is not in
use. If using streams then its recommended to enable stream caching. See more
details at http://camel.apache.org/stream-caching.html
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}
2018-10-03 19:16:44 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}
2018-10-03 19:16:44 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=41s1fw66ww7z3tx2d2vchetepflz,
supportedConnectionTypes=[Ljava.lang.Object;@50d70356, channel=/meta/handshake,
id=1, version=1.0, successful=true}
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}]
2018-10-03 19:16:44 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}
2018-10-03 19:16:44 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}
2018-10-03 19:16:44 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=2, successful=true}
2018-10-03 19:16:44 WARN SalesforceConsumer:112 - Property sObjectName or
sObjectClass NOT set, messages will be of type java.lang.Map
2018-10-03 19:16:44 WARN SalesforceConsumer:112 - Property sObjectName or
sObjectClass NOT set, messages will be of type java.lang.Map
2018-10-03 19:16:44 WARN SalesforceConsumer:112 - Property sObjectName or
sObjectClass NOT set, messages will be of type java.lang.Map
2018-10-03 19:16:44 DEBUG SalesforceConsumer:144 - Starting consumer:
Consumer[sfdc://event/Netsuite_Customer_Event__e?defaultReplayId=-1]
2018-10-03 19:16:44 DEBUG SalesforceConsumer:144 - Starting consumer:
Consumer[sfdc://event/Netsuite_Customer_Event__e?defaultReplayId=-1]
2018-10-03 19:16:44 DEBUG SalesforceConsumer:144 - Starting consumer:
Consumer[sfdc://event/Netsuite_Customer_Event__e?defaultReplayId=-1]
2018-10-03 19:16:44 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:16:44 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:16:44 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:16:44 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:16:44 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:16:44 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:16:44 INFO DefaultCamelContext:4034 - Route: route1 started and
consuming from: sfdc://event/Netsuite_Customer_Event__e?defaultReplayId=-1
2018-10-03 19:16:44 INFO DefaultCamelContext:4034 - Route: route1 started and
consuming from: sfdc://event/Netsuite_Customer_Event__e?defaultReplayId=-1
2018-10-03 19:16:44 INFO DefaultCamelContext:3186 - Total 1 routes, of which 1
are started
2018-10-03 19:16:44 INFO DefaultCamelContext:3186 - Total 1 routes, of which 1
are started
2018-10-03 19:16:44 INFO DefaultCamelContext:3197 - Apache Camel
2.23.0-SNAPSHOT (CamelContext: camel-1) started in 3.603 seconds
2018-10-03 19:16:44 INFO DefaultCamelContext:3197 - Apache Camel
2.23.0-SNAPSHOT (CamelContext: camel-1) started in 3.603 seconds
MainExample with Camel is now started!
2018-10-03 19:16:45 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:16:45 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:16:45 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:16:45 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:16:45 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:16:45 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/subscribe, id=4,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:16:45 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:16:45 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:16:45 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e},
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}]
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e},
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}]
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e},
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}]
2018-10-03 19:17:00 DEBUG SubscriptionHelper:382 - Received Message:
{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:382 - Received Message:
{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:382 - Received Message:
{data={schema=1dFADUdhATgLCIwji6bQMg, payload={CreatedById=00532000004OeYqAAK,
Path__c=/tbo/v1/customers/, Netsuite_Customer_Id__c=a2P60000000hJ4IEAU,
CreatedDate=2018-10-03T19:17:00.218Z, Event_Type__c=Updated},
event={replayId=390676}}, channel=/event/Netsuite_Customer_Event__e}
2018-10-03 19:17:00 DEBUG SalesforceConsumer:130 - Received event
/event/Netsuite_Customer_Event__e on channel /event/Netsuite_Customer_Event__e
2018-10-03 19:17:00 DEBUG SalesforceConsumer:130 - Received event
/event/Netsuite_Customer_Event__e on channel /event/Netsuite_Customer_Event__e
2018-10-03 19:17:00 DEBUG SalesforceConsumer:130 - Received event
/event/Netsuite_Customer_Event__e on channel /event/Netsuite_Customer_Event__e
****************Received Event**********************
2018-10-03 19:17:00 INFO route1:159 - Successfully sent the event to FIFO
queue.
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, channel=/meta/connect, id=3,
successful=true}
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=41s1fw66ww7z3tx2d2vchetepflz,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=5,
error=403::Unknown client, successful=false}]
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=41s1fw66ww7z3tx2d2vchetepflz,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=5,
error=403::Unknown client, successful=false}]
2018-10-03 19:17:00 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=41s1fw66ww7z3tx2d2vchetepflz,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=5,
error=403::Unknown client, successful=false}]
2018-10-03 19:17:00 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:17:00 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:17:00 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:17:00 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:17:00 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:17:00 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:00 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:00 WARN SubscriptionHelper:169 - Connect failure:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:00 WARN SubscriptionHelper:169 - Connect failure:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:00 WARN SubscriptionHelper:169 - Connect failure:
{clientId=41s1fw66ww7z3tx2d2vchetepflz, advice={reconnect=none, interval=0},
channel=/meta/connect, id=5, error=403::Unknown client, successful=false}
2018-10-03 19:17:05 DEBUG SubscriptionHelper:262 - Pausing for 0 msecs before
restart attempt
2018-10-03 19:17:05 DEBUG SubscriptionHelper:262 - Pausing for 0 msecs before
restart attempt
2018-10-03 19:17:05 DEBUG SubscriptionHelper:262 - Pausing for 0 msecs before
restart attempt
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=3q5942ic3yz9k0imlsvq9y09jd5,
supportedConnectionTypes=[Ljava.lang.Object;@1fdedb9d, channel=/meta/handshake,
id=6, version=1.0, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=retry, interval=0,
timeout=110000}, channel=/meta/connect, id=7, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:177 - Refreshing subscriptions to
1 channels on reconnect
2018-10-03 19:17:06 DEBUG SubscriptionHelper:177 - Refreshing subscriptions to
1 channels on reconnect
2018-10-03 19:17:06 DEBUG SubscriptionHelper:177 - Refreshing subscriptions to
1 channels on reconnect
2018-10-03 19:17:06 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:17:06 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:17:06 INFO SubscriptionHelper:433 - Set Replay extension to
replay from `-1` for channel `/event/Netsuite_Customer_Event__e`
2018-10-03 19:17:06 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:17:06 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:17:06 INFO SubscriptionHelper:377 - Subscribing to channel
/event/Netsuite_Customer_Event__e...
2018-10-03 19:17:06 INFO SubscriptionHelper:286 - Successfully restarted!
2018-10-03 19:17:06 INFO SubscriptionHelper:286 - Successfully restarted!
2018-10-03 19:17:06 INFO SubscriptionHelper:286 - Successfully restarted!
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}]
2018-10-03 19:17:06 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:17:06 DEBUG SubscriptionHelper:394 - [CHANNEL:META_SUBSCRIBE]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/subscribe, id=8,
subscription=/event/Netsuite_Customer_Event__e, successful=true}
2018-10-03 19:17:06 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:17:06 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:17:06 INFO SubscriptionHelper:409 - Subscribed to channel
/event/Netsuite_Customer_Event__e
2018-10-03 19:18:56 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}]
2018-10-03 19:18:56 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}]
2018-10-03 19:18:56 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}]
2018-10-03 19:18:56 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}
2018-10-03 19:18:56 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}
2018-10-03 19:18:56 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=9,
successful=true}
2018-10-03 19:20:47 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}]
2018-10-03 19:20:47 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}]
2018-10-03 19:20:47 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}]
2018-10-03 19:20:47 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}
2018-10-03 19:20:47 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}
2018-10-03 19:20:47 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=10,
successful=true}
2018-10-03 19:22:37 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}]
2018-10-03 19:22:37 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}]
2018-10-03 19:22:37 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}]
2018-10-03 19:22:37 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}
2018-10-03 19:22:37 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}
2018-10-03 19:22:37 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=11,
successful=true}
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}]
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}]
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}]
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, channel=/meta/connect, id=12,
successful=true}
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=3q5942ic3yz9k0imlsvq9y09jd5,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=13,
error=403::Unknown client, successful=false}]
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=3q5942ic3yz9k0imlsvq9y09jd5,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=13,
error=403::Unknown client, successful=false}]
2018-10-03 19:24:27 DEBUG SubscriptionHelper$5:226 - Received messages
[{channel=/meta/disconnect}, {clientId=3q5942ic3yz9k0imlsvq9y09jd5,
advice={reconnect=none, interval=0}, channel=/meta/connect, id=13,
error=403::Unknown client, successful=false}]
2018-10-03 19:24:27 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:24:27 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:24:27 INFO SubscriptionHelper:238 - Restarting on unexpected
disconnect from Salesforce...
2018-10-03 19:24:27 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:24:27 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:24:27 DEBUG SubscriptionHelper:242 - Waiting to disconnect...
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:27 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:27 WARN SubscriptionHelper:169 - Connect failure:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:27 WARN SubscriptionHelper:169 - Connect failure:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:27 WARN SubscriptionHelper:169 - Connect failure:
{clientId=3q5942ic3yz9k0imlsvq9y09jd5, advice={reconnect=none, interval=0},
channel=/meta/connect, id=13, error=403::Unknown client, successful=false}
2018-10-03 19:24:32 DEBUG SubscriptionHelper:262 - Pausing for 1000 msecs
before restart attempt
2018-10-03 19:24:32 DEBUG SubscriptionHelper:262 - Pausing for 1000 msecs
before restart attempt
2018-10-03 19:24:32 DEBUG SubscriptionHelper:262 - Pausing for 1000 msecs
before restart attempt
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}
2018-10-03 19:24:33 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}
2018-10-03 19:24:33 DEBUG SubscriptionHelper:122 - [CHANNEL:META_HANDSHAKE]:
{ext={replay=true, payload.format=true}, minimumVersion=1.0,
clientId=4ax18g474mdxneef13qtpln3p55cw,
supportedConnectionTypes=[Ljava.lang.Object;@589f8852, channel=/meta/handshake,
id=14, version=1.0, successful=true}
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper$5:226 - Received messages
[{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}]
2018-10-03 19:24:33 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:24:33 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:24:33 DEBUG SubscriptionHelper:165 - [CHANNEL:META_CONNECT]:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:24:33 WARN SubscriptionHelper:169 - Connect failure:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:24:33 WARN SubscriptionHelper:169 - Connect failure:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:24:33 WARN SubscriptionHelper:169 - Connect failure:
{clientId=4ax18g474mdxneef13qtpln3p55cw, advice={reconnect=none, interval=0},
channel=/meta/connect, id=15, error=403::Unknown client, successful=false}
2018-10-03 19:26:23 ERROR SubscriptionHelper:281 - Error restarting: Error
during CONNECT: 403::Unknown client
org.apache.camel.CamelException: Error during CONNECT: 403::Unknown client
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper.doStart(SubscriptionHelper.java:221)
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper$4.run(SubscriptionHelper.java:278)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
at java.lang.Thread.run(Thread.java:748)
2018-10-03 19:26:23 ERROR SubscriptionHelper:281 - Error restarting: Error
during CONNECT: 403::Unknown client
org.apache.camel.CamelException: Error during CONNECT: 403::Unknown client
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper.doStart(SubscriptionHelper.java:221)
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper$4.run(SubscriptionHelper.java:278)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
at java.lang.Thread.run(Thread.java:748)
2018-10-03 19:26:23 ERROR SubscriptionHelper:281 - Error restarting: Error
during CONNECT: 403::Unknown client
org.apache.camel.CamelException: Error during CONNECT: 403::Unknown client
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper.doStart(SubscriptionHelper.java:221)
at
org.apache.camel.component.salesforce.internal.streaming.SubscriptionHelper$4.run(SubscriptionHelper.java:278)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
at java.lang.Thread.run(Thread.java:748)
2018-10-03 19:26:23 ERROR SubscriptionHelper:290 - Failed to restart after
pausing for 1000 msecs
2018-10-03 19:26:23 ERROR SubscriptionHelper:290 - Failed to restart after
pausing for 1000 msecs
2018-10-03 19:26:23 ERROR SubscriptionHelper:290 - Failed to restart after
pausing for 1000 msecs
