git.net

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

camel-salesforce : connect failure due to "error=403::Unknown client"


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='smudimala@xxxxxxxxxxxxxxx', 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='smudimala@xxxxxxxxxxxxxxx', 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='smudimala@xxxxxxxxxxxxxxx', 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