Test Case ends and then repeats

Hi

My script runs on Firefox correctly, it loops around and adds 30 users driven from an Excel file. At the end of the script the browser closes and then starts again but using Chrome.
Because the script is adding users it will repeat and fail.
How to stop it and effectively only run ONCE on Firefox.

Thanks

2019-11-26 14:42:33.559 DEBUG testcase.Add users - 5: click(findTestObject(“Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c”))
2019-11-26 14:42:34.357 DEBUG testcase.Add users - 6: click(findTestObject(“Object Repository/Page_TecEFX/button_Save”))
2019-11-26 14:42:34.946 DEBUG testcase.Add users - 7: if (index == indexmax)
2019-11-26 14:42:34.947 DEBUG testcase.Add users - 1: break
2019-11-26 14:42:34.947 DEBUG testcase.Add users - 9: closeBrowser()
1574779354956 Marionette INFO Stopped listening on port 64384
[Parent 12468, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 15140, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 15140, Chrome_ChildThread] [Child 27412, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 27412, Chrome_ChildThread[Parent 12468, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 7312, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 7312, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome
###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost

[GPU 29912, Chrome_ChildThread] WARNIN
###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv

2019-11-26 14:42:35.474 INFO c.k.katalon.core.main.TestCaseExecutor - END Test Cases/Add users
2019-11-26 14:42:35.475 INFO c.k.katalon.core.main.TestCaseExecutor - =================== Smart XPath Report =======================
2019-11-26 14:42:35.475 INFO c.k.katalon.core.main.TestCaseExecutor - Refer to the link below to preview and approve auto healing on broke

Can you post the whole code?

I’m guessing you have some calls somewhere to start Chrome/WebDriver which I can’t see in that short log output.

WebUI.openBrowser('')

WebUI.navigateToUrl('http://aaa.bbb.ccc.ddd/#/user/login')

WebUI.setText(findTestObject('Page_TecEFX/input_TecEFX_usename'), 'admin@sp1.com')

WebUI.setEncryptedText(findTestObject('Page_TecEFX/input_TecEFX_userPassword'), 'cvW8qx4B2o3F4VwP/kNsqA==')

WebUI.click(findTestObject('Page_TecEFX/button_Login'))

WebUI.click(findTestObject('Page_TecEFX/span_USERS'))

def indexmax = findTestData('List of users').getRowNumbers()

for (def index = 1; index <= indexmax; index++) {
    WebUI.click(findTestObject('Object Repository/Page_TecEFX/button_Add'))

    WebUI.setText(findTestObject('Object Repository/Page_TecEFX/input__email'), findTestData('List of users').getValue('Email', 
            index))

    WebUI.setText(findTestObject('Object Repository/Page_TecEFX/input__givenName'), findTestData('List of users').getValue(
            'UserName', index))

    WebUI.setText(findTestObject('Object Repository/Page_TecEFX/input__jobTitle'), findTestData('List of users').getValue(
            'JobTitle', index))

    WebUI.click(findTestObject('Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c'))

    WebUI.click(findTestObject('Object Repository/Page_TecEFX/button_Save'))
	
    if (index == indexmax) {
        break;
    }
	
    WebUI.delay(2)

    if (WebUI.verifyElementClickable(findTestObject('Object Repository/Page_TecEFX/button_Add'))) {
    } else {
        if (WebUI.verifyElementClickable(findTestObject('Object Repository/Page_TecEFX/button_Cancel'))) {
            WebUI.click(findTestObject('Object Repository/Page_TecEFX/button_Cancel'))
        } else {
            WebUI.delay(1)
        }
    }
}

WebUI.closeBrowser()

It’s still not clear to me what your actual problem is.

Are you saying, when you execute this in Firefox, it works but then goes on to execute again? Are you saying the second time it runs in Chrome or are you saying when you run it in Chrome it fails?

Regarding the code, here are some “first glance” changes I would make:

Change <= to <

for (def index = 1; index < indexmax; index++) {

When you make that change, you should not need these lines so you can delete them:

    if (index == indexmax) {
        break;
    }

And this is an empty if construct. What are you trying to achieve here?

if (WebUI.verifyElementClickable(findTestObject('Object Repository/Page_TecEFX/button_Add'))) { 
}

What is your default FailureHandling set to in Project Settings?

The script is adding users from an Excel spreadsheet. If I run it twice (regardless of browser) it will fail because the users will already exist. I delete the users and rerun it and that’s fine.
What’s happened is that suddenly the first run in Firefox (my default) in Katalon runs fine but then it ends, closes the browser and continues to start again but from the logging it appears to be running in Chrome. So really two issues:

  1. Why is it starting again? - I cannot see an instruction to do that.
  2. Why Chrome

Thanks

That’s much clearer thank you.

Right, that’s what I thought you were saying. That’s weird. I see no evidence from your script that you are invoking a Chome/WebDriver instance, either. Very strange.

Laborious idea: Start a new test. Add the bare bones of this test to the new test. Does it behave the same?

So the Add dialogue has buttons for Cancel and Save.
To verify the save is successful I was trying to see if the Add button is available to open up the Add dialogue again ready for the next user.
If the Save fails then the Cancel will still be available and so I execute a Click of Button Cancel to go back a level. Then the Add button is available again.
Either way I can then continue with the loop.
It’s probably a bit clumsy but just starting out.
Thanks again.

Try this:

if (!WebUI.verifyElementClickable(findTestObject('Object Repository/Page_TecEFX/button_Add'))) {
  if (WebUI.verifyElementClickable(findTestObject('Object Repository/Page_TecEFX/button_Cancel'))) { 
    WebUI.click(findTestObject('Object Repository/Page_TecEFX/button_Cancel')) 
  } else { 
    WebUI.delay(1) 
  } 
}

I changed the code to only do 3 iterations. The whole log is listed.
The first pass worked, browser closed and it started again.

Thanks

2019-11-26 16:55:38.265 INFO  c.k.katalon.core.main.TestSuiteExecutor  - START Test Suites/Volume Testing Suite
2019-11-26 16:55:38.317 INFO  c.k.katalon.core.main.TestSuiteExecutor  - hostName = paulw - xxxx-Server.home
2019-11-26 16:55:38.318 INFO  c.k.katalon.core.main.TestSuiteExecutor  - os = Windows 10 64bit
2019-11-26 16:55:38.318 INFO  c.k.katalon.core.main.TestSuiteExecutor  - hostAddress = 192.168.1.64
2019-11-26 16:55:38.319 INFO  c.k.katalon.core.main.TestSuiteExecutor  - katalonVersion = 7.1.0.1
2019-11-26 16:55:38.715 INFO  c.k.katalon.core.main.TestCaseExecutor   - --------------------
2019-11-26 16:55:38.716 INFO  c.k.katalon.core.main.TestCaseExecutor   - START Test Cases/Add users
2019-11-26 16:55:38.744 INFO  c.k.katalon.core.main.TestCaseExecutor   - UserName = Testing
2019-11-26 16:55:38.745 INFO  c.k.katalon.core.main.TestCaseExecutor   - JobTitle = Job Title 1
2019-11-26 16:55:38.746 INFO  c.k.katalon.core.main.TestCaseExecutor   - Email = user1@domain.com
2019-11-26 16:55:39.228 DEBUG testcase.Add users                       - 1: openBrowser("")
2019-11-26 16:55:39.502 INFO  c.k.k.core.webui.driver.DriverFactory    - Starting 'Firefox' driver
Nov 26, 2019 4:55:39 PM org.openqa.selenium.remote.DesiredCapabilities firefox
INFO: Using `new FirefoxOptions()` is preferred to `DesiredCapabilities.firefox()`
2019-11-26 16:55:39.604 INFO  c.k.k.core.webui.driver.DriverFactory    - Action delay is set to 0 seconds
1574787341362	mozrunner::runner	INFO	Running command: "C:\\Program Files (x86)\\Mozilla Firefox\\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\\Users\\paulw\\AppData\\Local\\Temp\\rust_mozprofile.MSql9kp6hZiO"
1574787341753	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["topSites"], be one of ["activeTab", "tabs", "tabHide"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787341754	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
1574787341823	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
1574787341823	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
1574787341823	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
1574787341823	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
1574787342326	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["topSites"], be one of ["activeTab", "tabs", "tabHide"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787342326	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
1574787343486	Marionette	INFO	Listening on port 49859
1574787343977	Marionette	WARN	TLS certificate errors will be ignored for this session
1574787343982	Marionette	INFO	Proxy settings initialised: {"proxyType":"direct"}
Nov 26, 2019 4:55:44 PM org.openqa.selenium.remote.ProtocolHandshake createSession
INFO: Detected dialect: W3C
2019-11-26 16:56:05.109 INFO  c.k.k.core.webui.driver.DriverFactory    - sessionId = a7eabb0b-d7de-4dd3-b170-083005846841
2019-11-26 16:56:05.121 INFO  c.k.k.core.webui.driver.DriverFactory    - browser = Firefox 70.0
2019-11-26 16:56:05.122 INFO  c.k.k.core.webui.driver.DriverFactory    - platform = Windows 10
2019-11-26 16:56:05.122 INFO  c.k.k.core.webui.driver.DriverFactory    - seleniumVersion = 3.141.59
2019-11-26 16:56:05.123 INFO  c.k.k.core.webui.driver.DriverFactory    - proxyInformation = ProxyInformation{proxyOption=NO_PROXY, proxyServerType=HTTP, password=, proxyServerAddress=, proxyServerPort=0}
2019-11-26 16:56:05.133 DEBUG testcase.Add users                       - 2: navigateToUrl("http://aaa.bbb.ccc.ddd/#/user/login")
2019-11-26 16:56:14.118 DEBUG testcase.Add users                       - 3: setText(findTestObject("Page_TecEFX/input_TecEFX_usename"), "admin@sp1.com")
2019-11-26 16:56:14.835 DEBUG testcase.Add users                       - 4: setEncryptedText(findTestObject("Page_TecEFX/input_TecEFX_userPassword"), "cvW8qx4B2o3F4VwP/kNsqA==")
2019-11-26 16:56:15.281 DEBUG testcase.Add users                       - 5: click(findTestObject("Page_TecEFX/button_Login"))
2019-11-26 16:56:15.998 DEBUG testcase.Add users                       - 6: click(findTestObject("Page_TecEFX/span_USERS"))
2019-11-26 16:56:17.704 DEBUG testcase.Add users                       - 7: indexmax = findTestData("List of users").getRowNumbers()
2019-11-26 16:56:18.372 DEBUG testcase.Add users                       - 8: for ([index = 1, index < 3, (index++)])
2019-11-26 16:56:18.373 DEBUG testcase.Add users                       - 1: click(findTestObject("Object Repository/Page_TecEFX/button_Add"))
2019-11-26 16:56:19.050 DEBUG testcase.Add users                       - 2: setText(findTestObject("Object Repository/Page_TecEFX/input__email"), findTestData("List of users").getValue("Email", index))
2019-11-26 16:56:19.770 DEBUG testcase.Add users                       - 3: setText(findTestObject("Object Repository/Page_TecEFX/input__givenName"), findTestData("List of users").getValue("UserName", index))
2019-11-26 16:56:20.318 DEBUG testcase.Add users                       - 4: setText(findTestObject("Object Repository/Page_TecEFX/input__jobTitle"), findTestData("List of users").getValue("JobTitle", index))
2019-11-26 16:56:20.877 DEBUG testcase.Add users                       - 5: click(findTestObject("Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c"))
2019-11-26 16:56:21.690 DEBUG testcase.Add users                       - 6: click(findTestObject("Object Repository/Page_TecEFX/button_Save"))
2019-11-26 16:56:22.290 DEBUG testcase.Add users                       - 7: delay(2)
2019-11-26 16:56:24.311 DEBUG testcase.Add users                       - 8: if (!(verifyElementClickable(findTestObject("Object Repository/Page_TecEFX/button_Add"))))
2019-11-26 16:56:24.704 DEBUG testcase.Add users                       - 1: click(findTestObject("Object Repository/Page_TecEFX/button_Add"))
2019-11-26 16:56:25.341 DEBUG testcase.Add users                       - 2: setText(findTestObject("Object Repository/Page_TecEFX/input__email"), findTestData("List of users").getValue("Email", index))
2019-11-26 16:56:26.348 DEBUG testcase.Add users                       - 3: setText(findTestObject("Object Repository/Page_TecEFX/input__givenName"), findTestData("List of users").getValue("UserName", index))
2019-11-26 16:56:26.871 DEBUG testcase.Add users                       - 4: setText(findTestObject("Object Repository/Page_TecEFX/input__jobTitle"), findTestData("List of users").getValue("JobTitle", index))
2019-11-26 16:56:27.455 DEBUG testcase.Add users                       - 5: click(findTestObject("Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c"))
2019-11-26 16:56:28.264 DEBUG testcase.Add users                       - 6: click(findTestObject("Object Repository/Page_TecEFX/button_Save"))
2019-11-26 16:56:28.879 DEBUG testcase.Add users                       - 7: delay(2)
2019-11-26 16:56:30.881 DEBUG testcase.Add users                       - 8: if (!(verifyElementClickable(findTestObject("Object Repository/Page_TecEFX/button_Add"))))
2019-11-26 16:56:31.257 DEBUG testcase.Add users                       - 9: closeBrowser()
1574787391270	Marionette	INFO	Stopped listening on port 49859
[Parent 33580, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 5868, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 5868, Chrome[Child 35372, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 35372, Chrome_ChildThread] W[Parent 33580, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 20824, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 20824, Chrome_ChildThread] WARNING: [GPU 15
###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost

352, Chrome_
###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv

2019-11-26 16:56:32.087 INFO  c.k.katalon.core.main.TestCaseExecutor   - END Test Cases/Add users
2019-11-26 16:56:32.088 INFO  c.k.katalon.core.main.TestCaseExecutor   - =================== Smart XPath Report =======================
2019-11-26 16:56:32.088 INFO  c.k.katalon.core.main.TestCaseExecutor   - Refer to the link below to preview and approve auto healing on broken test objects.
2019-11-26 16:56:32.088 INFO  c.k.katalon.core.main.TestCaseExecutor   - https://docs.katalon.com/katalon-studio/docs/auto-healing-smart-xpath.html
2019-11-26 16:56:32.088 INFO  c.k.katalon.core.main.TestCaseExecutor   - ===============================================================
2019-11-26 16:56:32.114 INFO  c.k.katalon.core.main.TestCaseExecutor   - --------------------
2019-11-26 16:56:32.114 INFO  c.k.katalon.core.main.TestCaseExecutor   - START Test Cases/Add users
2019-11-26 16:56:32.124 INFO  c.k.katalon.core.main.TestCaseExecutor   - UserName = Testing
2019-11-26 16:56:32.125 INFO  c.k.katalon.core.main.TestCaseExecutor   - JobTitle = Job Title 2
2019-11-26 16:56:32.125 INFO  c.k.katalon.core.main.TestCaseExecutor   - Email = user2@domain.com
2019-11-26 16:56:32.127 DEBUG testcase.Add users                       - 1: openBrowser("")
2019-11-26 16:56:32.132 INFO  c.k.k.core.webui.driver.DriverFactory    - Starting 'Firefox' driver
Nov 26, 2019 4:56:32 PM org.openqa.selenium.remote.DesiredCapabilities firefox
INFO: Using `new FirefoxOptions()` is preferred to `DesiredCapabilities.firefox()`
1574787392765	mozrunner::runner	INFO	Running command: "C:\\Program Files (x86)\\Mozilla Firefox\\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\\Users\\paulw\\AppData\\Local\\Temp\\rust_mozprofile.oO0RcJdeAZJy"
1574787393095	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["activeTab", "tabs", "tabHide"], be one of ["topSites"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["dns"], be one of ["contextualIdentities"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787393095	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
1574787393171	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
1574787393172	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
1574787393172	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
1574787393172	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
1574787393663	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["activeTab", "tabs", "tabHide"], be one of ["topSites"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["dns"], be one of ["contextualIdentities"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787393663	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
1574787394749	Marionette	INFO	Listening on port 49915
1574787394776	Marionette	WARN	TLS certificate errors will be ignored for this session
1574787394781	Marionette	INFO	Proxy settings initialised: {"proxyType":"direct"}
Nov 26, 2019 4:56:34 PM org.openqa.selenium.remote.ProtocolHandshake createSession
INFO: Detected dialect: W3C
2019-11-26 16:56:55.837 INFO  c.k.k.core.webui.driver.DriverFactory    - sessionId = b9d623a0-68c0-47db-acbe-7f837fdcf4dc
2019-11-26 16:56:55.845 INFO  c.k.k.core.webui.driver.DriverFactory    - browser = Firefox 70.0
2019-11-26 16:56:55.846 INFO  c.k.k.core.webui.driver.DriverFactory    - platform = Windows 10
2019-11-26 16:56:55.846 INFO  c.k.k.core.webui.driver.DriverFactory    - seleniumVersion = 3.141.59
2019-11-26 16:56:55.846 INFO  c.k.k.core.webui.driver.DriverFactory    - proxyInformation = ProxyInformation{proxyOption=NO_PROXY, proxyServerType=HTTP, password=, proxyServerAddress=, proxyServerPort=0}
2019-11-26 16:56:55.856 DEBUG testcase.Add users                       - 2: navigateToUrl("http://aaa.bbb.ccc.ddd/#/user/login")
2019-11-26 16:57:03.182 DEBUG testcase.Add users                       - 3: setText(findTestObject("Page_TecEFX/input_TecEFX_usename"), "admin@sp1.com")
2019-11-26 16:57:04.029 DEBUG testcase.Add users                       - 4: setEncryptedText(findTestObject("Page_TecEFX/input_TecEFX_userPassword"), "cvW8qx4B2o3F4VwP/kNsqA==")
2019-11-26 16:57:04.516 DEBUG testcase.Add users                       - 5: click(findTestObject("Page_TecEFX/button_Login"))
2019-11-26 16:57:05.229 DEBUG testcase.Add users                       - 6: click(findTestObject("Page_TecEFX/span_USERS"))
2019-11-26 16:57:06.947 DEBUG testcase.Add users                       - 7: indexmax = findTestData("List of users").getRowNumbers()
2019-11-26 16:57:06.974 DEBUG testcase.Add users                       - 8: for ([index = 1, index < 3, (index++)])
2019-11-26 16:57:06.975 DEBUG testcase.Add users                       - 1: click(findTestObject("Object Repository/Page_TecEFX/button_Add"))
2019-11-26 16:57:07.929 DEBUG testcase.Add users                       - 2: setText(findTestObject("Object Repository/Page_TecEFX/input__email"), findTestData("List of users").getValue("Email", index))
2019-11-26 16:57:08.630 DEBUG testcase.Add users                       - 3: setText(findTestObject("Object Repository/Page_TecEFX/input__givenName"), findTestData("List of users").getValue("UserName", index))
2019-11-26 16:57:09.171 DEBUG testcase.Add users                       - 4: setText(findTestObject("Object Repository/Page_TecEFX/input__jobTitle"), findTestData("List of users").getValue("JobTitle", index))
2019-11-26 16:57:09.726 DEBUG testcase.Add users                       - 5: click(findTestObject("Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c"))
2019-11-26 16:57:10.530 DEBUG testcase.Add users                       - 6: click(findTestObject("Object Repository/Page_TecEFX/button_Save"))
2019-11-26 16:57:11.197 DEBUG testcase.Add users                       - 7: delay(2)
2019-11-26 16:57:13.199 DEBUG testcase.Add users                       - 8: if (!(verifyElementClickable(findTestObject("Object Repository/Page_TecEFX/button_Add"))))
2019-11-26 16:57:13.580 DEBUG testcase.Add users                       - 1: click(findTestObject("Object Repository/Page_TecEFX/button_Add"))
2019-11-26 16:57:14.292 DEBUG testcase.Add users                       - 2: setText(findTestObject("Object Repository/Page_TecEFX/input__email"), findTestData("List of users").getValue("Email", index))
2019-11-26 16:57:15.282 DEBUG testcase.Add users                       - 3: setText(findTestObject("Object Repository/Page_TecEFX/input__givenName"), findTestData("List of users").getValue("UserName", index))
2019-11-26 16:57:15.803 DEBUG testcase.Add users                       - 4: setText(findTestObject("Object Repository/Page_TecEFX/input__jobTitle"), findTestData("List of users").getValue("JobTitle", index))
2019-11-26 16:57:16.359 DEBUG testcase.Add users                       - 5: click(findTestObject("Object Repository/Page_TecEFX/div_SP_FINANCE_MANAGER_ui-chkbox-box ui-wid_2ce58c"))
2019-11-26 16:57:17.156 DEBUG testcase.Add users                       - 6: click(findTestObject("Object Repository/Page_TecEFX/button_Save"))
1574787438049	Marionette	WARN	TimedPromise timed out after 500 ms: stacktrace:
bail@chrome://marionette/content/sync.js:237:64
2019-11-26 16:57:18.252 DEBUG testcase.Add users                       - 7: delay(2)
2019-11-26 16:57:20.254 DEBUG testcase.Add users                       - 8: if (!(verifyElementClickable(findTestObject("Object Repository/Page_TecEFX/button_Add"))))
2019-11-26 16:57:20.635 DEBUG testcase.Add users                       - 9: closeBrowser()
1574787440641	Marionette	INFO	Stopped listening on port 49915
[Parent 15544, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 22052, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 22052, Chrome_ChildThread] WARNING: pipe[Child 35748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Parent 15544, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 23740, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1572401533/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[Child 23740, Chrome[GP
###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost

U
###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv

2019-11-26 16:57:21.506 INFO  c.k.katalon.core.main.TestCaseExecutor   - END Test Cases/Add users
2019-11-26 16:57:21.507 INFO  c.k.katalon.core.main.TestCaseExecutor   - =================== Smart XPath Report =======================
2019-11-26 16:57:21.507 INFO  c.k.katalon.core.main.TestCaseExecutor   - Refer to the link below to preview and approve auto healing on broken test objects.
2019-11-26 16:57:21.507 INFO  c.k.katalon.core.main.TestCaseExecutor   - https://docs.katalon.com/katalon-studio/docs/auto-healing-smart-xpath.html
2019-11-26 16:57:21.507 INFO  c.k.katalon.core.main.TestCaseExecutor   - ===============================================================
2019-11-26 16:57:21.532 INFO  c.k.katalon.core.main.TestCaseExecutor   - --------------------
2019-11-26 16:57:21.532 INFO  c.k.katalon.core.main.TestCaseExecutor   - START Test Cases/Add users
2019-11-26 16:57:21.539 INFO  c.k.katalon.core.main.TestCaseExecutor   - UserName = Testing
2019-11-26 16:57:21.539 INFO  c.k.katalon.core.main.TestCaseExecutor   - JobTitle = Job Title 3
2019-11-26 16:57:21.540 INFO  c.k.katalon.core.main.TestCaseExecutor   - Email = user3@domain.com
2019-11-26 16:57:21.541 DEBUG testcase.Add users                       - 1: openBrowser("")
2019-11-26 16:57:21.545 INFO  c.k.k.core.webui.driver.DriverFactory    - Starting 'Firefox' driver
Nov 26, 2019 4:57:21 PM org.openqa.selenium.remote.DesiredCapabilities firefox
INFO: Using `new FirefoxOptions()` is preferred to `DesiredCapabilities.firefox()`
1574787442170	mozrunner::runner	INFO	Running command: "C:\\Program Files (x86)\\Mozilla Firefox\\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\\Users\\paulw\\AppData\\Local\\Temp\\rust_mozprofile.jwFcihCc0UOU"
1574787442493	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["topSites"], be one of ["activeTab", "tabs", "tabHide"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787442494	addons.webextension.<unknown>	WARN	Loading extension 'null': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
1574787442563	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
1574787442563	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
1574787442563	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
1574787442564	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
1574787443040	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing permissions.12: Value "debugger" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["bookmarks"], be one of ["find"], be one of ["history"], be one of ["menus.overrideContext"], be one of ["search"], be one of ["topSites"], be one of ["activeTab", "tabs", "tabHide"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["webNavigation"], or be one of ["webRequest", "webRequestBlocking"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["browsingData"], be one of ["captivePortal"], be one of ["devtools"], be one of ["identity"], be one of ["normandyAddonStudy"], be one of ["menus", "contextMenus"], be one of ["pkcs11"], be one of ["geckoProfiler"], be one of ["sessions"], be one of ["urlbar"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["activityLog"], be one of ["management"], be one of ["networkStatus"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$/, or match the pattern /^file:\/\/\/.*$/], or match the pattern /^resource:\/\/(\*|\*\.[^*/]+|[^*/]+)\/.*$|^about:/]
1574787443041	addons.webextension.{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}	WARN	Loading extension '{54dcd9e9-903f-43e9-95f0-22bd0e0f3c0a}': Reading manifest: Error processing offline_enabled: An unexpected property was found in the WebExtension manifest.
JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
1574787444159	Marionette	INFO	Listening on port 49970
1574787444182	Marionette	WARN	TLS certificate errors will be ignored for this session
1574787444187	Marionette	INFO	Proxy settings initialised: {"proxyType":"direct"}
Nov 26, 2019 4:57:24 PM org.openqa.selenium.remote.ProtocolHandshake createSession
INFO: Detected dialect: W3C

I will try this idea but also just tried another test. I rerun the script but targeted Chrome browser, it worked and repeated, again and again.
Perhaps my Chrome browser thought is a red-herring.
Main problem is it’s repeating.
Thanks

So this is a Test Suite? Are you including the test twice?

It’s a good thought but I don’t think I am.

image

image

I think it is. All mentions of “chrome” in your log appear to be talking about something called “browser chrome” not the browser called “Chrome”. Confusing? Blame Google. Browser chrome is the browser parts, the menus, the back buttons, the whole shell in which a webpage is hosted - everything you think of as belonging to the browser itself and not the web page it is hosting. That terminology was in use long before Google released their browser and called it “Chrome”.

https://www.pcmag.com/encyclopedia/term/38972/browser-chrome

Couple more things to try:

  1. Run the Test Case alone.

  2. It seems you have the auto-heeling XPath plugin. Does disabling it (if that’s possible?) have any effect?

I’m out of ideas here.

Callout @ThanhTo

kindly define ‘script’. the only logical explanation i can think is … such behaviour may occure if you run a test collection set with various profiles
otherwise is just voodoo

2 Likes

Disabled XPath, still the same, but here’s something.

There are 30 lines in my Excel that’s driving the original Test Case (I’ve manually updated the For Loop to 2 for quicker testing)

The image shows the Test Progress and it increments each time it starts again. I’m betting when it hits 30 it will stop.

Can#t explain why but it’s got to be connected.

image

New Test:

WebUI.openBrowser('')

WebUI.navigateToUrl('http://aaa.bbb.ccc.ddd/#/user/login')
WebUI.setText(findTestObject('Page_TecEFX/input_TecEFX_usename'), 'admin@sp1.com')

WebUI.setEncryptedText(findTestObject('Page_TecEFX/input_TecEFX_userPassword'), 'cvW8qx4B2o3F4VwP/kNsqA==')

def indexmax = findTestData('List of users').getRowNumbers()

for (def index = 1; index < indexmax; index++) {
  println "Current Index: " + index
}
WebUI.closeBrowser()

That’s what I meant by bare-bones. Check I have enough to hit the page, and loop.

Understand, that works fine and print Current INdex: 1,…,30
I guess I just build this backup. It also worked as part of a Test Case.

Do you know where that 30 comes from?

Anyway, I think I have a way forward so thank you very much. I’ll continue and report back tomorrow. Cheers

image

I’m not sure what’s going on. I suspect your suite is screwed up somewhere - that or the test itself.

Check back in later, @ThanhTo may have other questions/ideas for you. (He’s about five hours away at a guess).