Error in running RPA desktop fundamemtal examples:RoboticsFlowPlugin executePlugin nodeId 'null' exception

spatraining
rpadesktopfundamen
roboticsflowplugine
connection-refused

#1

While working on course RPA Desktop fundametals,
We are getting following errors on local machine:
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/logback-classic.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/slf4j-log4j12.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
16:45:07,623 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
16:45:07,623 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
16:45:07,623 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml]
16:45:07,654 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@74a10858 - URL [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml] is not of type file
16:45:07,889 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml]
16:45:07,889 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 30 seconds
16:45:07,904 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
16:45:07,904 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
16:45:07,951 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1825027294 - No compression will be used
16:45:07,951 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1825027294 - Will use the pattern logs/rpa-%d{yyyy-MM-dd}.%i.log for the active file
16:45:07,951 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - The date pattern is ‘yyyy-MM-dd’ from file name pattern ‘logs/rpa-%d{yyyy-MM-dd}.%i.log’.
16:45:07,951 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - Roll-over at midnight.
16:45:07,951 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - Setting initial period to Sun Dec 03 16:45:07 IST 2017
16:45:07,951 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
16:45:07,951 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
16:45:07,951 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
16:45:07,982 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: logs/rpa-2017-12-03.0.log
16:45:07,982 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [null]
16:45:07,982 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [com.workfusion.common.utils.AnsiConsoleAppender]
16:45:07,982 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
16:45:08,167 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.openqa.jetty] to INFO
16:45:08,167 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.eclipse.jetty] to INFO
16:45:08,182 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.seleniumhq.jetty9] to INFO
16:45:08,182 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.http] to INFO
16:45:08,183 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.openqa.selenium.remote.server.rest.ResultConfig] to DEBUG
16:45:08,183 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to DEBUG
16:45:08,183 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
16:45:08,183 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
16:45:08,183 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
16:45:08,183 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@16aa0a0a - Registering current configuration as safe fallback point
SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
16:45:08.383 INFO [main] o.s.c.support.AbstractApplicationContext.prepareRefresh[:582] - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@353d0772: startup date [Sun Dec 03 16:45:08 IST 2017]; root of context hierarchy
16:45:08.451 INFO [main] o.s.b.f.xml.XmlBeanDefinitionReader.loadBeanDefinitions[:317] - Loading XML bean definitions from class path resource [resources/spring/studio-webharvest-context.xml]
16:45:09.725 INFO [main] o.s.c.io.support.PropertiesLoaderSupport.loadProperties[:172] - Loading properties file from class path resource [resources/properties/workfusion.properties]
16:45:09.737 INFO [main] o.s.c.io.support.PropertiesLoaderSupport.loadProperties[:172] - Loading properties file from class path resource [resources/properties/webharvest-ide.properties]
16:45:09.737 INFO [main] o.s.b.f.a.AutowiredAnnotationBeanPostProcessor.[:155] - JSR-330 ‘javax.inject.Inject’ annotation found and supported for autowiring
16:45:10.741 INFO [main] org.reflections.Reflections.scan[:205] - Reflections took 140 ms to scan 2 urls, producing 54 keys and 173 values
16:45:10.840 INFO [main] c.f.c.w.p.d.s.DatabaseDataStoreServiceFactory.[:35] - PostgreSQL driver resolved
16:45:11 [DEBUG] Couldn’t find template in cache for “recorder/putVarsToContext.ftl”(“en_IN”, Cp1252, parsed); will try to load it.
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/putVarsToContext_en_IN.ftl”): Not found
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/putVarsToContext_en.ftl”): Not found
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/putVarsToContext.ftl”): Found
16:45:11 [DEBUG] Loading template for “recorder/putVarsToContext.ftl”(“en_IN”, Cp1252, parsed) from "jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/workfusion-machine.jar!/templates/recorder/putVarsToContext.ftl"
16:45:11 [DEBUG] Couldn’t find template in cache for “recorder/defineVarsForRecorder.ftl”(“en_IN”, Cp1252, parsed); will try to load it.
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/defineVarsForRecorder_en_IN.ftl”): Not found
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/defineVarsForRecorder_en.ftl”): Not found
16:45:11 [DEBUG] TemplateLoader.findTemplateSource(“recorder/defineVarsForRecorder.ftl”): Found
16:45:11 [DEBUG] Loading template for “recorder/defineVarsForRecorder.ftl”(“en_IN”, Cp1252, parsed) from "jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/workfusion-machine.jar!/templates/recorder/defineVarsForRecorder.ftl"
16:45:11.455 INFO [main] c.w.s.rpa.wf.datastore.LocalDataStoreDao.createAndUpdate[:82] - Start upload data from ‘C:\Users\tilak.patil\eclipse-workspace\AssignmentRestCall\datastore\emp.csv’…
16:45:11.534 INFO [main] c.w.s.rpa.wf.datastore.LocalDataStoreDao.createAndUpdate[:85] - Finished upload data.
16:45:11.557 INFO [main] org.webharvest.definition.XmlParser.parse[:78] - XML parsed in 8ms.
16:45:12.043 WARN [main] c.w.s.r.w.w.l.StudioWebHarvestTaskExecutor.execute[:61] - Machine run 940CDA46-634B-46CD-9555-74E0945388FF doesn’t use proxy
16:45:12.047 INFO [main] o.w.runtime.processors.BaseProcessor.run[:123] - ScriptProcessor starts processing…
16:45:12.047 INFO [main] o.w.runtime.processors.BaseProcessor.run[:142] - ScriptProcessor processor executed in 0ms.
16:45:12.047 INFO [main] o.w.runtime.processors.BaseProcessor.run[:123] - ScriptProcessor starts processing…
16:45:12.063 INFO [main] o.w.runtime.processors.BaseProcessor.run[:142] - ScriptProcessor processor executed in 16ms.
16:45:12.063 INFO [main] o.w.runtime.processors.BaseProcessor.run[:123] - SeleniumFlowPlugin starts processing…
16:45:12.063 WARN [main] c.f.c.w.p.selenium.SeleniumFlowPlugin.executePlugin[:37] - Deprecated functionality. Use RoboticsFlowPlugin instead of SeleniumFlowPlugin.
16:45:12.063 INFO [main] c.f.c.w.p.selenium.RoboticsFlowPlugin.initPluginVariables[:123] - Select robot location : RobotLocation{SeleniumServerLocation{protocol=‘http’, host=‘127.0.0.1’, port=4444, context=‘null’, file=’/wd/hub’}, username=’’, password=’'
16:45:12.079 INFO [main] c.f.c.w.plugin.selenium.CapabilityHelper.buildIdentification[:64] - Identification Capability: {host.name=local, context.path=local, run.name=null, run.uuid=940CDA46-634B-46CD-9555-74E0945388FF, run.author.fullname=null, run.author.email=null, task.name=WWI TODO: Config name should be here}
16:45:12.094 INFO [main] c.f.c.w.plugin.selenium.CapabilityHelper.buildIdentification[:64] - Identification Capability: {host.name=local, context.path=local, run.name=null, run.uuid=940CDA46-634B-46CD-9555-74E0945388FF, run.author.fullname=null, run.author.email=null, task.name=WWI TODO: Config name should be here}
16:45:12.094 INFO [main] c.f.c.w.plugin.selenium.CapabilityHelper.buildIdentification[:64] - Identification Capability: {host.name=local, context.path=local, run.name=null, run.uuid=940CDA46-634B-46CD-9555-74E0945388FF, run.author.fullname=null, run.author.email=null, task.name=WWI TODO: Config name should be here}
16:45:12.134 INFO [main] c.f.c.webharvest.selenium.RobotLocation.getURL[:38] - URL : http://127.0.0.1:4444/wd/hub with userInfo : null
16:45:13.427 INFO [main] c.f.c.w.p.selenium.RoboticsFlowPlugin.closeDrivers[:154] - Drivers count to close: 0
16:45:13.431 ERROR [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.main[:143] - RoboticsFlowPlugin executePlugin nodeId ‘null’ exception:
org.webharvest.exception.PluginException: RoboticsFlowPlugin executePlugin nodeId ‘null’ exception:
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RoboticsFlowPlugin.executePlugin(RoboticsFlowPlugin.java:106) ~[workfusion-machine.jar:na]
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.SeleniumFlowPlugin.executePlugin(SeleniumFlowPlugin.java:38) ~[workfusion-machine.jar:na]
at org.webharvest.runtime.processors.WebHarvestPlugin.execute(WebHarvestPlugin.java:125) ~[workfusion-webharvest-core.jar:na]
at org.webharvest.runtime.processors.BaseProcessor.run(BaseProcessor.java:127) ~[workfusion-webharvest-core.jar:na]
at org.webharvest.runtime.Scraper.execute(Scraper.java:169) ~[workfusion-webharvest-core.jar:na]
at org.webharvest.runtime.Scraper.execute(Scraper.java:182) ~[workfusion-webharvest-core.jar:na]
at com.workfusion.studio.rpa.wf.webharvest.launch.StudioWebHarvestTaskExecutor.execute(StudioWebHarvestTaskExecutor.java:108) ~[com.workfusion.studio.wf_8.4.0.jar:na]
at com.workfusion.studio.rpa.wf.webharvest.launch.SingleThreadWebHarvestProcess.processTaskInputs(SingleThreadWebHarvestProcess.java:75) ~[com.workfusion.studio.wf_8.4.0.jar:na]
at com.workfusion.studio.rpa.wf.webharvest.launch.SingleThreadWebHarvestProcess.start(SingleThreadWebHarvestProcess.java:44) ~[com.workfusion.studio.wf_8.4.0.jar:na]
at com.workfusion.studio.rpa.wf.webharvest.launch.WebHarvestMainLauncher.launch(WebHarvestMainLauncher.java:83) ~[com.workfusion.studio.wf_8.4.0.jar:na]
at com.workfusion.studio.rpa.wf.webharvest.launch.WebHarvestMainLauncher.main(WebHarvestMainLauncher.java:141) ~[com.workfusion.studio.wf_8.4.0.jar:na]
Caused by: java.lang.RuntimeException: Connect to 127.0.0.1:4444 [/127.0.0.1] failed: Connection refused: connect
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.SeleniumClient.execute(SeleniumClient.java:128) ~[rpa-api.jar:na]
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.SeleniumClient.findAndLockNode(SeleniumClient.java:109) ~[rpa-api.jar:na]
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RoboticsFlowPlugin.executePlugin(RoboticsFlowPlugin.java:94) ~[workfusion-machine.jar:na]
… 10 common frames omitted
Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:4444 [/127.0.0.1] failed: Connection refused: connect
at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) ~[httpclient.jar:4.5.1]
at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.SeleniumClient.execute(SeleniumClient.java:125) ~[rpa-api.jar:na]
… 12 common frames omitted
Caused by: java.net.ConnectException: Connection refused: connect
at java.net.DualStackPlainSocketImpl.connect0(Native Method) ~[na:1.8.0_112]
at java.net.DualStackPlainSocketImpl.socketConnect(DualStackPlainSocketImpl.java:79) ~[na:1.8.0_112]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[na:1.8.0_112]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[na:1.8.0_112]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[na:1.8.0_112]
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:172) ~[na:1.8.0_112]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[na:1.8.0_112]
at java.net.Socket.connect(Socket.java:589) ~[na:1.8.0_112]
at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) ~[httpclient.jar:4.5.1]
at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) ~[httpclient.jar:4.5.1]
… 23 common frames omitted
16:45:13.431 INFO [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:132] - -------------------------------------------
16:45:13.431 INFO [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:133] - EXECUTION FAILED
16:45:13.431 INFO [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:134] - -------------------------------------------
16:45:13.431 INFO [Thread-1] o.s.c.support.AbstractApplicationContext.doClose[:987] - Closing org.springframework.context.support.ClassPathXmlApplicationContext@353d0772: startup date [Sun Dec 03 16:45:08 IST 2017]; root of context hierarchy

could you please help us in this…as we are not able to complete this course…
Thanks,
Tilak Patil


#2

Hello

  1. What sample are you executing? Some samples require small changes in different OS…
  2. Do you have RPA agent (Hub+Node) running on your local machine? Do you have Desktop driver in running Node?
  3. Do you point Run Configuration properly on your local RPA Hub?

Any of those points can brake your execution…


#3

Thanks for your points…
We will check on this and let you know.


#4

@tilak_patil - i see that you are using eclipse oxygen package. I had the same issue, but when i switched to eclipse neon 3 package, things started working. Hope it will solve the problem for you too.


#5

Hi, tried the suggested points
and after disabling antivirus .
it worked…
Thanks .


#6

Hi,
I was able to run a the auto It examples .
Now when I tried to run examples of Robotics Simplified API.
It throws exception:

   SLF4J: Class path contains multiple SLF4J bindings.
    SLF4J: Found binding in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/logback-classic.jar!/org/slf4j/impl/StaticLoggerBinder.class]
    SLF4J: Found binding in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/slf4j-log4j12.jar!/org/slf4j/impl/StaticLoggerBinder.class]
    SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
    23:06:39,040 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
    23:06:39,041 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
    23:06:39,041 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml]
    23:06:39,074 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@74a10858 - URL [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml] is not of type file
    23:06:39,335 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/rpa-selenium-server.jar!/logback.xml] 
    23:06:39,335 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 30 seconds
    23:06:39,342 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
    23:06:39,347 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
    23:06:39,386 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1825027294 - No compression will be used
    23:06:39,388 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1825027294 - Will use the pattern logs/rpa-%d{yyyy-MM-dd}.%i.log for the active file
    23:06:39,391 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - The date pattern is 'yyyy-MM-dd' from file name pattern 'logs/rpa-%d{yyyy-MM-dd}.%i.log'.
    23:06:39,391 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - Roll-over at midnight.
    23:06:39,394 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - Setting initial period to Fri Dec 08 23:06:39 IST 2017
    23:06:39,394 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead
    23:06:39,395 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@69a10787 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy
    23:06:39,397 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
    23:06:39,415 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: logs/rpa-2017-12-08.0.log
    23:06:39,415 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [null]
    23:06:39,417 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [com.workfusion.common.utils.AnsiConsoleAppender]
    23:06:39,421 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
    23:06:39,594 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.openqa.jetty] to INFO
    23:06:39,594 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.eclipse.jetty] to INFO
    23:06:39,594 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.seleniumhq.jetty9] to INFO
    23:06:39,594 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.http] to INFO
    23:06:39,595 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.openqa.selenium.remote.server.rest.ResultConfig] to DEBUG
    23:06:39,595 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to DEBUG
    23:06:39,595 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
    23:06:39,596 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
    23:06:39,596 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
    23:06:39,597 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@16aa0a0a - Registering current configuration as safe fallback point
    SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
    23:06:39.804 INFO  [main] o.s.c.support.AbstractApplicationContext.prepareRefresh[:582] - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@353d0772: startup date [Fri Dec 08 23:06:39 IST 2017]; root of context hierarchy
    23:06:39.866 INFO  [main] o.s.b.f.xml.XmlBeanDefinitionReader.loadBeanDefinitions[:317] - Loading XML bean definitions from class path resource [resources/spring/studio-webharvest-context.xml]
    23:06:41.233 INFO  [main] o.s.c.io.support.PropertiesLoaderSupport.loadProperties[:172] - Loading properties file from class path resource [resources/properties/workfusion.properties]
    23:06:41.254 INFO  [main] o.s.c.io.support.PropertiesLoaderSupport.loadProperties[:172] - Loading properties file from class path resource [resources/properties/webharvest-ide.properties]
    23:06:41.270 INFO  [main] o.s.b.f.a.AutowiredAnnotationBeanPostProcessor.<init>[:155] - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
    23:06:42.427 INFO  [main] org.reflections.Reflections.scan[:205] - Reflections took 143 ms to scan 2 urls, producing 54 keys and 173 values 
    23:06:42.530 INFO  [main] c.f.c.w.p.d.s.DatabaseDataStoreServiceFactory.<init>[:35] - PostgreSQL driver resolved
    23:06:42 [DEBUG] Couldn't find template in cache for "recorder/putVarsToContext.ftl"("en_IN", Cp1252, parsed); will try to load it.
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/putVarsToContext_en_IN.ftl"): Not found
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/putVarsToContext_en.ftl"): Not found
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/putVarsToContext.ftl"): Found
    23:06:42 [DEBUG] Loading template for "recorder/putVarsToContext.ftl"("en_IN", Cp1252, parsed) from "jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/workfusion-machine.jar!/templates/recorder/putVarsToContext.ftl"
    23:06:42 [DEBUG] Couldn't find template in cache for "recorder/defineVarsForRecorder.ftl"("en_IN", Cp1252, parsed); will try to load it.
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/defineVarsForRecorder_en_IN.ftl"): Not found
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/defineVarsForRecorder_en.ftl"): Not found
    23:06:42 [DEBUG] TemplateLoader.findTemplateSource("recorder/defineVarsForRecorder.ftl"): Found
    23:06:42 [DEBUG] Loading template for "recorder/defineVarsForRecorder.ftl"("en_IN", Cp1252, parsed) from "jar:file:/D:/Tilak%20patil/RPALatest/eclipse-java-oxygen-1a-win32-x86_64/eclipse/configuration/org.eclipse.osgi/542/0/.cp/lib/workfusion-machine.jar!/templates/recorder/defineVarsForRecorder.ftl"
    23:06:43.112 INFO  [main] c.w.s.rpa.wf.datastore.LocalDataStoreDao.createAndUpdate[:82] - Start upload data from 'C:\Users\tilak.patil\eclipse-workspace\AssignmentRestCall\datastore\emptable.csv'...
    23:06:43.181 INFO  [main] c.w.s.rpa.wf.datastore.LocalDataStoreDao.createAndUpdate[:85] - Finished upload data.
    23:06:43.200 INFO  [main] org.webharvest.definition.XmlParser.parse[:78] - XML parsed in 3ms.
    23:06:43.865 WARN  [main] c.w.s.r.w.w.l.StudioWebHarvestTaskExecutor.execute[:61] - Machine run 649212F0-574A-4492-A781-AA70542C67D0 doesn't use proxy
    23:06:43.869 INFO  [main] o.w.runtime.processors.BaseProcessor.run[:123] - RoboticsFlowPlugin starts processing...
    23:06:43.876 INFO  [main] c.f.c.w.p.selenium.RoboticsFlowPlugin.initPluginVariables[:123] - Select robot location : RobotLocation{SeleniumServerLocation{protocol='http', host='127.0.0.1', port=4444, context='null', file='/wd/hub'}, username='', password=''
    23:06:43.895 INFO  [main] c.f.c.w.plugin.selenium.CapabilityHelper.buildIdentification[:64] - Identification Capability: {host.name=local, context.path=local, run.name=null, run.uuid=649212F0-574A-4492-A781-AA70542C67D0, run.author.fullname=null, run.author.email=null, task.name=WWI TODO: Config name should be here}
    23:06:43.933 INFO  [main] c.f.c.webharvest.selenium.RobotLocation.getURL[:38] - URL : http://127.0.0.1:4444/wd/hub with userInfo : null
    23:06:44.141 INFO  [main] c.f.c.w.plugin.selenium.SeleniumClient.getResponseString[:139] - Response with code 200: HttpResponseProxy{HTTP/1.1 200 OK [Date: Fri, 08 Dec 2017 17:36:44 GMT, Content-Length: 2, Server: Jetty(9.2.z-SNAPSHOT)] ResponseEntityProxy{[Content-Length: 2,Chunked: false]}}
    23:06:44.147 INFO  [main] o.w.runtime.processors.BaseProcessor.run[:123] -     RobotPlugin starts processing...
    23:06:44.148 INFO  [main] c.f.c.w.plugin.selenium.CapabilityHelper.buildIdentification[:64] - Identification Capability: {host.name=local, context.path=local, run.name=null, run.uuid=649212F0-574A-4492-A781-AA70542C67D0, run.author.fullname=null, run.author.email=null, task.name=WWI TODO: Config name should be here}
    23:06:44.149 INFO  [main] c.f.c.w.plugin.selenium.RobotPlugin.createWebDriver[:384] - DriverInfo : [Name : roboticsDriver,  type : firefox, closeOnCompleted : true,   sessionId : null, nodeId : null,  privateMode : true,  blockImages : false]
    23:06:44.150 INFO  [main] c.f.c.webharvest.selenium.RobotLocation.getURL[:38] - URL : http://127.0.0.1:4444/wd/hub with userInfo : null
    23:06:44.306 WARN  [main] c.f.c.w.plugin.selenium.SeleniumUtils.closeDriverQuietly[:64] - IDriverWrapper is NULL. Can't to close.
    23:06:44.308 INFO  [main] c.f.c.w.p.selenium.RoboticsFlowPlugin.closeDrivers[:154] - Drivers count to close: 0
    23:06:44.334 ERROR [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.main[:143] - RoboticsFlowPlugin executePlugin nodeId 'null' exception: 
    org.webharvest.exception.PluginException: RoboticsFlowPlugin executePlugin nodeId 'null' exception: 
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RoboticsFlowPlugin.executePlugin(RoboticsFlowPlugin.java:106) ~[workfusion-machine.jar:na]
    	at org.webharvest.runtime.processors.WebHarvestPlugin.execute(WebHarvestPlugin.java:125) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.processors.BaseProcessor.run(BaseProcessor.java:127) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.Scraper.execute(Scraper.java:169) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.Scraper.execute(Scraper.java:182) ~[workfusion-webharvest-core.jar:na]
    	at com.workfusion.studio.rpa.wf.webharvest.launch.StudioWebHarvestTaskExecutor.execute(StudioWebHarvestTaskExecutor.java:108) ~[com.workfusion.studio.wf_8.4.0.jar:na]
    	at com.workfusion.studio.rpa.wf.webharvest.launch.SingleThreadWebHarvestProcess.processTaskInputs(SingleThreadWebHarvestProcess.java:75) ~[com.workfusion.studio.wf_8.4.0.jar:na]
    	at com.workfusion.studio.rpa.wf.webharvest.launch.SingleThreadWebHarvestProcess.start(SingleThreadWebHarvestProcess.java:44) ~[com.workfusion.studio.wf_8.4.0.jar:na]
    	at com.workfusion.studio.rpa.wf.webharvest.launch.WebHarvestMainLauncher.launch(WebHarvestMainLauncher.java:83) ~[com.workfusion.studio.wf_8.4.0.jar:na]
    	at com.workfusion.studio.rpa.wf.webharvest.launch.WebHarvestMainLauncher.main(WebHarvestMainLauncher.java:141) ~[com.workfusion.studio.wf_8.4.0.jar:na]
    Caused by: org.webharvest.exception.PluginException: org.openqa.selenium.WebDriverException: Error forwarding the new session cannot find : Capabilities [{acceptSslCerts=true, extra.block.images=false, extra.start.in.private=true, extra.executor.id={run.name=null, task.name=WWI TODO: Config name should be here, context.path=local, host.name=local, run.author.email=null, run.uuid=649212F0-574A-4492-A781-AA70542C67D0, run.author.fullname=null}, browserName=firefox, maximize.on.startup=true, javascriptEnabled=true, extra.parent.browser.capabilities=null, platform=WINDOWS}]
    Command duration or timeout: 72 milliseconds
    Build info: version: '8.4.0', revision: 'unknown', time: '2017-07-05 15:37:04'
    System info: host: 'N10187', ip: '192.168.1.138', os.name: 'Windows 10', os.arch: 'amd64', os.version: '10.0', java.version: '1.8.0_112'
    Driver info: com.freedomoss.crowdcontrol.webharvest.selenium.wrapper.RemoteDriverWrapper
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RobotPlugin.executePlugin(RobotPlugin.java:187) ~[workfusion-machine.jar:na]
    	at org.webharvest.runtime.processors.WebHarvestPlugin.execute(WebHarvestPlugin.java:125) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.processors.BaseProcessor.run(BaseProcessor.java:127) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.processors.BodyProcessor.execute(BodyProcessor.java:27) ~[workfusion-webharvest-core.jar:na]
    	at org.webharvest.runtime.processors.WebHarvestPlugin.executeBody(WebHarvestPlugin.java:246) ~[workfusion-webharvest-core.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RoboticsFlowPlugin.executePlugin(RoboticsFlowPlugin.java:98) ~[workfusion-machine.jar:na]
    	... 9 common frames omitted
    Caused by: org.openqa.selenium.WebDriverException: Error forwarding the new session cannot find : Capabilities [{acceptSslCerts=true, extra.block.images=false, extra.start.in.private=true, extra.executor.id={run.name=null, task.name=WWI TODO: Config name should be here, context.path=local, host.name=local, run.author.email=null, run.uuid=649212F0-574A-4492-A781-AA70542C67D0, run.author.fullname=null}, browserName=firefox, maximize.on.startup=true, javascriptEnabled=true, extra.parent.browser.capabilities=null, platform=WINDOWS}]
    Command duration or timeout: 72 milliseconds
    Build info: version: '8.4.0', revision: 'unknown', time: '2017-07-05 15:37:04'
    System info: host: 'N10187', ip: '192.168.1.138', os.name: 'Windows 10', os.arch: 'amd64', os.version: '10.0', java.version: '1.8.0_112'
    Driver info: com.freedomoss.crowdcontrol.webharvest.selenium.wrapper.RemoteDriverWrapper
    	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[na:1.8.0_112]
    	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) ~[na:1.8.0_112]
    	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) ~[na:1.8.0_112]
    	at java.lang.reflect.Constructor.newInstance(Constructor.java:423) ~[na:1.8.0_112]
    	at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:206) ~[rpa-selenium-remote-driver.jar:na]
    	at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:158) ~[rpa-selenium-remote-driver.jar:na]
    	at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:646) ~[rpa-selenium-remote-driver.jar:na]
    	at org.openqa.selenium.remote.RemoteWebDriver.startSession(RemoteWebDriver.java:212) ~[rpa-selenium-remote-driver.jar:na]
    	at org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:94) ~[rpa-selenium-remote-driver.jar:na]
    	at org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:121) ~[rpa-selenium-remote-driver.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.selenium.wrapper.RemoteDriverWrapper.<init>(RemoteDriverWrapper.java:44) ~[rpa-api.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RobotPlugin.createSeleniumDriver(RobotPlugin.java:407) ~[workfusion-machine.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RobotPlugin.createWebDriver(RobotPlugin.java:386) ~[workfusion-machine.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RobotPlugin.initDriverWrapper(RobotPlugin.java:208) ~[workfusion-machine.jar:na]
    	at com.freedomoss.crowdcontrol.webharvest.plugin.selenium.RobotPlugin.executePlugin(RobotPlugin.java:174) ~[workfusion-machine.jar:na]
    	... 14 common frames omitted
    Caused by: org.openqa.grid.common.exception.GridException: Error forwarding the new session cannot find : Capabilities [{acceptSslCerts=true, extra.block.images=false, extra.start.in.private=true, extra.executor.id={run.name=null, task.name=WWI TODO: Config name should be here, context.path=local, host.name=local, run.author.email=null, run.uuid=649212F0-574A-4492-A781-AA70542C67D0, run.author.fullname=null}, browserName=firefox, maximize.on.startup=true, javascriptEnabled=true, extra.parent.browser.capabilities=null, platform=WINDOWS}]
    	at org.openqa.grid.web.servlet.handler.RequestHandler.process(RequestHandler.java:115) ~[rpa-selenium-server.jar:na]
    	at org.openqa.grid.web.servlet.DriverServlet.process(DriverServlet.java:83) ~[na:na]
    	at org.openqa.grid.web.servlet.DriverServlet.doPost(DriverServlet.java:67) ~[na:na]
    	at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) ~[na:na]
    	at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) ~[na:na]
    	at org.seleniumhq.jetty9.servlet.ServletHolder.handle(ServletHolder.java:808) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.servlet.ServletHandler.doHandle(ServletHandler.java:587) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.session.SessionHandler.doHandle(SessionHandler.java:221) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.servlet.ServletHandler.doScope(ServletHandler.java:515) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.session.SessionHandler.doScope(SessionHandler.java:185) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.handler.ContextHandler.doScope(ContextHandler.java:1061) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.Server.handle(Server.java:499) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.HttpChannel.handle(HttpChannel.java:310) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.server.HttpConnection.onFillable(HttpConnection.java:257) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.io.AbstractConnection$2.run(AbstractConnection.java:540) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) ~[jetty-repacked.jar:na]
    	at org.seleniumhq.jetty9.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) ~[jetty-repacked.jar:na]
    	at java.lang.Thread.run(Thread.java:745) ~[na:1.8.0_112]
    23:06:44.335 INFO  [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:132] - -------------------------------------------
    23:06:44.336 INFO  [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:133] - EXECUTION FAILED
    23:06:44.336 INFO  [main] c.w.s.r.w.w.l.WebHarvestMainLauncher.printFinishMessage[:134] - -------------------------------------------
    23:06:44.338 INFO  [Thread-1] o.s.c.support.AbstractApplicationContext.doClose[:987] - Closing org.springframework.context.support.ClassPathXmlApplicationContext@353d0772: startup date [Fri Dec 08 23:06:39 IST 2017]; root of context hierarchy

I have RPAInstaller-8.4.3.
Both hub & node are in running state.

Could you please help.
Thanks


#7

I see you execute script in browserName=firefox
Suspect that you don’t have FireFox configured on your local node(s)


#8

Hi ,
I have tried the scripts with IE & chrome, but still not able to ran it successfully.
This are configuration in hub & node json
hub json:

{
  "host": null,
  "port": 4444,
  "newSessionWaitTimeout": 300000,
  "servlets": ["com.workfusion.grid.web.WFServlet"],
  "prioritizer": null,
  "capabilityMatcher": "com.xing.qa.selenium.grid.hub.ConfigurableCapabilityMatcher",
  "capabilityMatcherConfig" : [{"platform":"platform"},{"browserName":"exact"},
                           {"version":"rvm"},{"country":"exact"}],
  "throwOnCapabilityNotPresent": true,
  "nodePolling": 5000,
  "cleanUpCycle": 5000,
  "timeout": 300000,
  "browserTimeout": 0,
  "maxSession": 30,
  "jettyMaxThreads": -1
}

Node0 json:

{
  "capabilities":[
{
  "platform": "WINDOWS",
  "browserName": "internet explorer",
  "version": "11",
  "maxInstances": 1,
  "seleniumProtocol": "WebDriver",
  "country": "India"
},
{
  "platform": "WINDOWS",
  "browserName": "chrome",
  "maxInstances": 1,
  "version": "62",
  "seleniumProtocol": "WebDriver",
  "country": "India"
},
{
  "platform": "WINDOWS",
  "browserName": "desktop",
  "version": "1.0",
  "maxInstances": 1,
  "seleniumProtocol": "WebDriver",
  "country": "India"
}, {
  "platform": "WINDOWS",
  "browserName": "firefox",
  "version": "46",
  "maxInstances": 1,
  "seleniumProtocol": "WebDriver",
  "country": "India"
}
  ],
  "configuration":{
"maxSession":2,
"port":5000,
"host": "127.0.0.1",
"register":true,
"registerCycle": 5000,
"hubPort":4444,
"hubHost":"127.0.0.1",
"nodeTimeout":120,
"nodePolling":2000,
"cleanUpCycle":2000,
"timeout":600000
  }
}

Following are error logs of eclipse:
With Chrome:chrome.txt (25.5 KB)

With IE:internet explorer.txt (11.7 KB)

Also , I am working on Win 10 machine .Do I need to have admin rights/admin account to run this programs?.
I have RPAInstaller-8.4.3. &
Both hub & node are in running state.

Waiting for your reply.

Thanks.


#9

hi,

I am facing same issue. Have checked my nodes and they have the chrome, firefox and internet explorer listed. However not able to run through this, it gives below error -
Connect to 127.0.0.1:4444 [/127.0.0.1] failed: Connection refused: connect.

I could run the sample file - create file s3put with same RPA HUB. 127.0.0.1:4444
When we say we are connecting to the hub. how do we check what node the hub has and why is the connection failing while trying to do it through the robotic flow


#10

please run the following bat and keep both (start-hub.bat,start-node0.bat)cmd window open.

C:\RPA\rpa-grid\start-hub.bat
C:\RPA\rpa-grid\start-node0.bat

and then try running the script.