Failing webview logs

gistfile1.txt

2020-04-06 17:37:45:437 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:45:438 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:45:438 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:45:440 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:45:440 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"ON AIR"}
2020-04-06 17:37:46:707 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"8D000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"8D000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:46:708 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"8D000000-0000-0000-2B93-000000000000","ELEMENT":"8D000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:46:708 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 1272 ms - 137
2020-04-06 17:37:46:709 - [HTTP]
2020-04-06 17:37:47:658 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:47:658 - [HTTP] {"using":"id","value":"Live Radio"}
2020-04-06 17:37:47:659 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["id","Live Radio","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:47:659 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:47:659 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:47:660 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:47:661 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:47:662 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"id","value":"Live Radio"}
2020-04-06 17:37:48:293 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"4E000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"4E000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:48:294 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"4E000000-0000-0000-2B93-000000000000","ELEMENT":"4E000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:48:294 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 636 ms - 137
2020-04-06 17:37:48:295 - [HTTP]
2020-04-06 17:37:48:298 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:48:298 - [HTTP] {"using":"id","value":"My Library"}
2020-04-06 17:37:48:299 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["id","My Library","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:48:299 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:48:300 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:48:300 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:48:302 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:48:303 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"id","value":"My Library"}
2020-04-06 17:37:48:953 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"52000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"52000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:48:954 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"52000000-0000-0000-2B93-000000000000","ELEMENT":"52000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:48:955 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 657 ms - 137
2020-04-06 17:37:48:955 - [HTTP]
2020-04-06 17:37:48:957 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:48:958 - [HTTP] {"using":"id","value":"Playlists"}
2020-04-06 17:37:48:958 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["id","Playlists","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:48:958 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:48:958 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:48:959 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:48:959 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:48:960 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"id","value":"Playlists"}
2020-04-06 17:37:49:655 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"50000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"50000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:49:657 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"50000000-0000-0000-2B93-000000000000","ELEMENT":"50000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:49:660 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 701 ms - 137
2020-04-06 17:37:49:661 - [HTTP]
2020-04-06 17:37:49:667 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:49:669 - [HTTP] {"using":"id","value":"Podcasts"}
2020-04-06 17:37:49:674 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["id","Podcasts","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:49:676 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:49:676 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:49:677 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:49:691 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:49:692 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"id","value":"Podcasts"}
2020-04-06 17:37:50:413 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"51000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"51000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:50:415 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"51000000-0000-0000-2B93-000000000000","ELEMENT":"51000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:50:418 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 750 ms - 137
2020-04-06 17:37:50:418 - [HTTP]
2020-04-06 17:37:50:423 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:50:423 - [HTTP] {"using":"name","value":"Settings"}
2020-04-06 17:37:50:425 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Settings","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:50:426 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:50:426 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:50:426 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:50:435 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:50:436 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Settings"}
2020-04-06 17:37:51:122 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"59000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"59000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:51:123 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"59000000-0000-0000-2B93-000000000000","ELEMENT":"59000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:51:124 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 701 ms - 137
2020-04-06 17:37:51:124 - [HTTP]
2020-04-06 17:37:51:128 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/59000000-0000-0000-2B93-000000000000/click
2020-04-06 17:37:51:128 - [HTTP] {"id":"59000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:51:129 - [W3C (38b1ea4f)] Driver proxy active, passing request on via HTTP proxy
2020-04-06 17:37:51:129 - [debug] [XCUITest] Executing command 'proxyReqRes'
2020-04-06 17:37:51:134 - [debug] [WD Proxy] Matched '/wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/59000000-0000-0000-2B93-000000000000/click' to command name 'click'
2020-04-06 17:37:51:135 - [debug] [WD Proxy] Proxying [POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/59000000-0000-0000-2B93-000000000000/click] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/59000000-0000-0000-2B93-000000000000/click] with body: {"id":"59000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:51:708 - [debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:51:708 - [WD Proxy] Replacing sessionId A4F13D50-B23E-4187-ADD3-DAA013356453 with 38b1ea4f-8049-48e9-ade5-7879f429b640
2020-04-06 17:37:51:709 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/59000000-0000-0000-2B93-000000000000/click 200 581 ms - 65
2020-04-06 17:37:51:710 - [HTTP]
2020-04-06 17:37:51:716 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:51:716 - [HTTP] {"using":"name","value":"Settings"}
2020-04-06 17:37:51:716 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Settings","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:51:717 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:51:717 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:51:717 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:51:718 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:51:718 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Settings"}
2020-04-06 17:37:52:632 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"C5000000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"C5000000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:52:633 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"C5000000-0000-0000-2B93-000000000000","ELEMENT":"C5000000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:52:634 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 918 ms - 137
2020-04-06 17:37:52:634 - [HTTP]
2020-04-06 17:37:52:636 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:52:637 - [HTTP] {"using":"name","value":"HD Audio"}
2020-04-06 17:37:52:637 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","HD Audio","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:52:637 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:52:638 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:52:638 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:52:639 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:52:639 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"HD Audio"}
2020-04-06 17:37:52:810 - [WD Proxy] Got response with status 404: {"value":{"error":"no such element","message":"unable to find an element using 'name', value 'HD Audio'","traceback":"(\n\t0   WebDriverAgentLib                   0x000000010f9f91bd FBNoSuchElementErrorResponseForRequest + 301\n\t1   WebDriverAgentLib                   0x000000010f9f8f69 +[FBFindElementCommands handleFindElement:] + 425\n\t2   WebDriverAgentLib                   0x000000010f9d5996 -[FBRoute_TargetAction mountRequest:intoResponse:] + 182\n\t3   WebDriverAgentLib                   0x000000010f9c101b __37-[FBWebServer registerRouteHandlers:]_block_invoke + 555\n\t4   RoutingHTTPServer                   0x000000010fac48ef -[RoutingHTTPServer handleRoute:withRequest:response:] + 136\n\t5   RoutingHTTPServer                   0x000000010fac5202 __72-[RoutingHTTPServer routeMethod:withPath:parameters:request:connection:]_block_invoke + 47\n\t6   libdispatch.dylib                   0x00007fff5223f7b9 _dispatch_client_callout + 8\n\t7   libdispatch.dylib                   0x00007fff5224c861 _dispat...
2020-04-06 17:37:52:810 - [debug] [W3C] Matched W3C error code 'no such element' to NoSuchElementError
2020-04-06 17:37:52:811 - [debug] [W3C (38b1ea4f)] Encountered internal error running command: NoSuchElementError: An element could not be located on the page using the given search parameters.
2020-04-06 17:37:52:811 - [debug] [W3C (38b1ea4f)]     at XCUITestDriver.doNativeFind (/Users/theo.king/code/global/app/guacamole/testing/app-at/node_modules/appium/node_modules/appium-xcuitest-driver/lib/commands/find.js:126:11)
2020-04-06 17:37:52:812 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 404 175 ms - 422
2020-04-06 17:37:52:812 - [HTTP]
2020-04-06 17:37:53:319 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:53:319 - [HTTP] {"using":"name","value":"SIGN IN"}
2020-04-06 17:37:53:320 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","SIGN IN","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:53:321 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:53:321 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:53:322 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:53:323 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:53:323 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"SIGN IN"}
2020-04-06 17:37:53:771 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"1E010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"1E010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:53:772 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"1E010000-0000-0000-2B93-000000000000","ELEMENT":"1E010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:53:772 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 453 ms - 137
2020-04-06 17:37:53:772 - [HTTP]
2020-04-06 17:37:53:775 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/1E010000-0000-0000-2B93-000000000000/click
2020-04-06 17:37:53:775 - [HTTP] {"id":"1E010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:53:776 - [W3C (38b1ea4f)] Driver proxy active, passing request on via HTTP proxy
2020-04-06 17:37:53:776 - [debug] [XCUITest] Executing command 'proxyReqRes'
2020-04-06 17:37:53:777 - [debug] [WD Proxy] Matched '/wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/1E010000-0000-0000-2B93-000000000000/click' to command name 'click'
2020-04-06 17:37:53:778 - [debug] [WD Proxy] Proxying [POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/1E010000-0000-0000-2B93-000000000000/click] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/1E010000-0000-0000-2B93-000000000000/click] with body: {"id":"1E010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:54:123 - [debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:54:123 - [WD Proxy] Replacing sessionId A4F13D50-B23E-4187-ADD3-DAA013356453 with 38b1ea4f-8049-48e9-ade5-7879f429b640
2020-04-06 17:37:54:124 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/1E010000-0000-0000-2B93-000000000000/click 200 349 ms - 65
2020-04-06 17:37:54:124 - [HTTP]
2020-04-06 17:37:54:127 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:54:127 - [HTTP] {"using":"name","value":"TAKE CONTROL"}
2020-04-06 17:37:54:131 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","TAKE CONTROL","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:54:132 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:54:132 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:54:132 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:54:134 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:54:135 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"TAKE CONTROL"}
2020-04-06 17:37:54:841 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"32010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"32010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:54:841 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"32010000-0000-0000-2B93-000000000000","ELEMENT":"32010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:54:842 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 715 ms - 137
2020-04-06 17:37:54:843 - [HTTP]
2020-04-06 17:37:54:846 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:54:846 - [HTTP] {"using":"name","value":"CREATE AN ACCOUNT"}
2020-04-06 17:37:54:848 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","CREATE AN ACCOUNT","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:54:848 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:54:848 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:54:849 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:54:850 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:54:850 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"CREATE AN ACCOUNT"}
2020-04-06 17:37:55:158 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:55:158 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000","ELEMENT":"2D010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:55:159 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 312 ms - 137
2020-04-06 17:37:55:159 - [HTTP]
2020-04-06 17:37:55:162 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:55:162 - [HTTP] {"using":"name","value":"CONTINUE WITH FACEBOOK"}
2020-04-06 17:37:55:163 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","CONTINUE WITH FACEBOOK","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:55:163 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:55:163 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:55:163 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:55:164 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:55:164 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"CONTINUE WITH FACEBOOK"}
2020-04-06 17:37:55:410 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:55:410 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000","ELEMENT":"37010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:55:411 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 249 ms - 137
2020-04-06 17:37:55:411 - [HTTP]
2020-04-06 17:37:55:414 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:55:414 - [HTTP] {"using":"name","value":"CONTINUE WITH TWITTER"}
2020-04-06 17:37:55:415 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","CONTINUE WITH TWITTER","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:55:416 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:55:416 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:55:416 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:55:417 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:55:418 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"CONTINUE WITH TWITTER"}
2020-04-06 17:37:55:654 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:55:655 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000","ELEMENT":"36010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:55:656 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 241 ms - 137
2020-04-06 17:37:55:656 - [HTTP]
2020-04-06 17:37:55:658 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:55:658 - [HTTP] {"using":"name","value":"SIGN IN WITH EMAIL"}
2020-04-06 17:37:55:659 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","SIGN IN WITH EMAIL","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:55:659 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:55:659 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:55:659 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:55:660 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:55:660 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"SIGN IN WITH EMAIL"}
2020-04-06 17:37:55:890 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:55:891 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000","ELEMENT":"35010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:55:891 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 233 ms - 137
2020-04-06 17:37:55:892 - [HTTP]
2020-04-06 17:37:55:894 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:55:894 - [HTTP] {"using":"name","value":"CREATE AN ACCOUNT"}
2020-04-06 17:37:55:895 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","CREATE AN ACCOUNT","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:55:895 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:55:895 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:55:895 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:55:896 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:55:896 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"CREATE AN ACCOUNT"}
2020-04-06 17:37:56:147 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:56:147 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000","ELEMENT":"2D010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:56:148 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 254 ms - 137
2020-04-06 17:37:56:148 - [HTTP]
2020-04-06 17:37:56:151 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:56:151 - [HTTP] {"using":"name","value":"Sign in to personalise playlists and download your favourite shows"}
2020-04-06 17:37:56:151 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Sign in to personalise playlists and download your favourite shows","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:56:152 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:56:152 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:56:152 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:56:152 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:56:153 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Sign in to personalise playlists and download your favourite shows"}
2020-04-06 17:37:56:400 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"33010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"33010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:56:401 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"33010000-0000-0000-2B93-000000000000","ELEMENT":"33010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:56:401 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 251 ms - 137
2020-04-06 17:37:56:402 - [HTTP]
2020-04-06 17:37:56:405 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:56:405 - [HTTP] {"using":"name","value":"Terms & Conditions"}
2020-04-06 17:37:56:405 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Terms & Conditions","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:56:406 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:56:406 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:56:406 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:56:406 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:56:407 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Terms & Conditions"}
2020-04-06 17:37:56:656 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"3B010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"3B010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:56:657 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"3B010000-0000-0000-2B93-000000000000","ELEMENT":"3B010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:56:657 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 252 ms - 137
2020-04-06 17:37:56:658 - [HTTP]
2020-04-06 17:37:56:660 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:56:661 - [HTTP] {"using":"name","value":"Privacy Statement"}
2020-04-06 17:37:56:661 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Privacy Statement","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:56:661 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:56:661 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:56:661 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:56:662 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:56:662 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Privacy Statement"}
2020-04-06 17:37:56:896 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"3C010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"3C010000-0000-0000-2B93-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:56:897 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"3C010000-0000-0000-2B93-000000000000","ELEMENT":"3C010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:56:898 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 237 ms - 137
2020-04-06 17:37:56:898 - [HTTP]
2020-04-06 17:37:56:901 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/37010000-0000-0000-2B93-000000000000/click
2020-04-06 17:37:56:901 - [HTTP] {"id":"37010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:56:901 - [W3C (38b1ea4f)] Driver proxy active, passing request on via HTTP proxy
2020-04-06 17:37:56:902 - [debug] [XCUITest] Executing command 'proxyReqRes'
2020-04-06 17:37:56:902 - [debug] [WD Proxy] Matched '/wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/37010000-0000-0000-2B93-000000000000/click' to command name 'click'
2020-04-06 17:37:56:902 - [debug] [WD Proxy] Proxying [POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/37010000-0000-0000-2B93-000000000000/click] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/37010000-0000-0000-2B93-000000000000/click] with body: {"id":"37010000-0000-0000-2B93-000000000000"}
2020-04-06 17:37:57:145 - [debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:57:148 - [WD Proxy] Replacing sessionId A4F13D50-B23E-4187-ADD3-DAA013356453 with 38b1ea4f-8049-48e9-ade5-7879f429b640
2020-04-06 17:37:57:152 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/37010000-0000-0000-2B93-000000000000/click 200 249 ms - 65
2020-04-06 17:37:57:152 - [HTTP]
2020-04-06 17:37:57:158 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:57:159 - [HTTP] {"using":"name","value":"Continue"}
2020-04-06 17:37:57:163 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Continue","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:57:164 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:57:164 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:57:164 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:57:165 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:57:166 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Continue"}
2020-04-06 17:37:57:291 - [WD Proxy] Got response with status 404: {"value":{"error":"no such element","message":"unable to find an element using 'name', value 'Continue'","traceback":"(\n\t0   WebDriverAgentLib                   0x000000010f9f91bd FBNoSuchElementErrorResponseForRequest + 301\n\t1   WebDriverAgentLib                   0x000000010f9f8f69 +[FBFindElementCommands handleFindElement:] + 425\n\t2   WebDriverAgentLib                   0x000000010f9d5996 -[FBRoute_TargetAction mountRequest:intoResponse:] + 182\n\t3   WebDriverAgentLib                   0x000000010f9c101b __37-[FBWebServer registerRouteHandlers:]_block_invoke + 555\n\t4   RoutingHTTPServer                   0x000000010fac48ef -[RoutingHTTPServer handleRoute:withRequest:response:] + 136\n\t5   RoutingHTTPServer                   0x000000010fac5202 __72-[RoutingHTTPServer routeMethod:withPath:parameters:request:connection:]_block_invoke + 47\n\t6   libdispatch.dylib                   0x00007fff5223f7b9 _dispatch_client_callout + 8\n\t7   libdispatch.dylib                   0x00007fff5224c861 _dispat...
2020-04-06 17:37:57:292 - [debug] [W3C] Matched W3C error code 'no such element' to NoSuchElementError
2020-04-06 17:37:57:292 - [debug] [W3C (38b1ea4f)] Encountered internal error running command: NoSuchElementError: An element could not be located on the page using the given search parameters.
2020-04-06 17:37:57:293 - [debug] [W3C (38b1ea4f)]     at XCUITestDriver.doNativeFind (/Users/theo.king/code/global/app/guacamole/testing/app-at/node_modules/appium/node_modules/appium-xcuitest-driver/lib/commands/find.js:126:11)
2020-04-06 17:37:57:293 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 404 135 ms - 422
2020-04-06 17:37:57:293 - [HTTP]
2020-04-06 17:37:57:802 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:37:57:802 - [HTTP] {"using":"name","value":"Continue"}
2020-04-06 17:37:57:803 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Continue","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:57:804 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:37:57:805 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:37:57:805 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:37:57:807 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:37:57:808 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"name","value":"Continue"}
2020-04-06 17:37:58:253 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"05030000-0000-0000-B854-000000000000","element-6066-11e4-a52e-4f735466cecf":"05030000-0000-0000-B854-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:58:254 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"05030000-0000-0000-B854-000000000000","ELEMENT":"05030000-0000-0000-B854-000000000000"}
2020-04-06 17:37:58:254 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 452 ms - 137
2020-04-06 17:37:58:254 - [HTTP]
2020-04-06 17:37:58:256 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/05030000-0000-0000-B854-000000000000/click
2020-04-06 17:37:58:256 - [HTTP] {"id":"05030000-0000-0000-B854-000000000000"}
2020-04-06 17:37:58:257 - [W3C (38b1ea4f)] Driver proxy active, passing request on via HTTP proxy
2020-04-06 17:37:58:257 - [debug] [XCUITest] Executing command 'proxyReqRes'
2020-04-06 17:37:58:257 - [debug] [WD Proxy] Matched '/wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/05030000-0000-0000-B854-000000000000/click' to command name 'click'
2020-04-06 17:37:58:258 - [debug] [WD Proxy] Proxying [POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/05030000-0000-0000-B854-000000000000/click] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/05030000-0000-0000-B854-000000000000/click] with body: {"id":"05030000-0000-0000-B854-000000000000"}
2020-04-06 17:37:58:587 - [debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:37:58:587 - [WD Proxy] Replacing sessionId A4F13D50-B23E-4187-ADD3-DAA013356453 with 38b1ea4f-8049-48e9-ade5-7879f429b640
2020-04-06 17:37:58:588 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element/05030000-0000-0000-B854-000000000000/click 200 331 ms - 65
2020-04-06 17:37:58:589 - [HTTP]
2020-04-06 17:37:58:602 - [HTTP] --> GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts
2020-04-06 17:37:58:603 - [HTTP] {}
2020-04-06 17:37:58:620 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.getContexts() with args: ["38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:58:621 - [debug] [XCUITest] Executing command 'getContexts'
2020-04-06 17:37:58:622 - [debug] [XCUITest] Getting list of available contexts
2020-04-06 17:37:58:622 - [debug] [iOS] Retrieving contexts and views
2020-04-06 17:37:58:622 - [debug] [XCUITest] Selecting by url: false
2020-04-06 17:37:58:709 - [RemoteDebugger] Remote Debugger version 8.6.1
2020-04-06 17:37:58:710 - [debug] [RemoteDebugger] useNewSafari --> false
2020-04-06 17:37:58:712 - [debug] [RemoteDebugger] Checking which communication style to use (non-Safari on platform version '13.3')
2020-04-06 17:37:58:712 - [debug] [RemoteDebugger] Platform version equal or higher than '12.2': true
2020-04-06 17:37:58:713 - [RemoteDebugger] Setting communication protocol: using full Web Inspector protocol communication
2020-04-06 17:37:58:714 - [debug] [RemoteDebugger] Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.HAyt0kb7nV/com.apple.webinspectord_sim.socket'
2020-04-06 17:37:58:719 - [debug] [RemoteDebugger] Debugger socket connected
2020-04-06 17:37:58:719 - [debug] [RemoteDebugger] Sending connection key request
2020-04-06 17:37:58:722 - [debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 0): 'setConnectionKey'
2020-04-06 17:37:58:733 - [debug] [RemoteDebugger] Sending to Web Inspector took 12ms
2020-04-06 17:37:58:733 - [debug] [RemoteDebugger] Sending connection key request
2020-04-06 17:37:58:733 - [debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 1): 'setConnectionKey'
2020-04-06 17:37:58:738 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2020-04-06 17:37:58:739 - [debug] [RemoteDebugger] Selecting application
2020-04-06 17:37:58:739 - [debug] [RemoteDebugger] No applications currently connected.
2020-04-06 17:37:58:739 - [debug] [XCUITest] No web frames found.
2020-04-06 17:37:58:740 - [debug] [W3C (38b1ea4f)] Responding to client with driver.getContexts() result: ["NATIVE_APP"]
2020-04-06 17:37:58:742 - [HTTP] <-- GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts 200 139 ms - 24
2020-04-06 17:37:58:742 - [HTTP]
2020-04-06 17:37:59:750 - [HTTP] --> GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts
2020-04-06 17:37:59:750 - [HTTP] {}
2020-04-06 17:37:59:754 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.getContexts() with args: ["38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:37:59:754 - [debug] [XCUITest] Executing command 'getContexts'
2020-04-06 17:37:59:754 - [debug] [XCUITest] Getting list of available contexts
2020-04-06 17:37:59:755 - [debug] [iOS] Retrieving contexts and views
2020-04-06 17:37:59:755 - [debug] [XCUITest] Selecting by url: false
2020-04-06 17:37:59:755 - [debug] [RemoteDebugger] Sending connection key request
2020-04-06 17:37:59:755 - [debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 2): 'setConnectionKey'
2020-04-06 17:37:59:761 - [debug] [RemoteDebugger] Sending to Web Inspector took 6ms
2020-04-06 17:37:59:761 - [debug] [RemoteDebugger] Selecting application
2020-04-06 17:37:59:762 - [debug] [RemoteDebugger] No applications currently connected.
2020-04-06 17:37:59:762 - [debug] [XCUITest] No web frames found.
2020-04-06 17:37:59:762 - [debug] [W3C (38b1ea4f)] Responding to client with driver.getContexts() result: ["NATIVE_APP"]
2020-04-06 17:37:59:763 - [HTTP] <-- GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts 200 13 ms - 24
2020-04-06 17:37:59:764 - [HTTP]
2020-04-06 17:38:00:791 - [debug] [RemoteDebugger] Received connected applications list: PID:27373
2020-04-06 17:38:00:795 - [debug] [RemoteDebugger] Received connected driver list: {}
2020-04-06 17:38:00:796 - [debug] [RemoteDebugger] Notified that new application 'PID:37683' has connected
2020-04-06 17:38:00:799 - [debug] [RemoteDebugger] Notified that new application 'PID:37675' has connected
2020-04-06 17:38:00:799 - [debug] [RemoteDebugger] Found app id key 'PID:37675' for bundle 'com.thisisglobal.guacamole.development'
2020-04-06 17:38:00:800 - [debug] [RemoteDebugger] Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.thisisglobal.guacamole.development', with app id 'PID:37683'
2020-04-06 17:38:00:801 - [debug] [RemoteDebugger] Using proxied app id 'PID:37683'
2020-04-06 17:38:00:802 - [HTTP] --> GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts
2020-04-06 17:38:00:802 - [HTTP] {}
2020-04-06 17:38:00:803 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.getContexts() with args: ["38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:38:00:803 - [debug] [XCUITest] Executing command 'getContexts'
2020-04-06 17:38:00:803 - [debug] [XCUITest] Getting list of available contexts
2020-04-06 17:38:00:804 - [debug] [iOS] Retrieving contexts and views
2020-04-06 17:38:00:804 - [debug] [XCUITest] Selecting by url: false
2020-04-06 17:38:00:804 - [debug] [RemoteDebugger] Selecting application
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger] Current applications available:
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]     Application: 'PID:27373'
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         id: "PID:27373"
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         name: "Web"
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         bundleId: "process-SafariViewService"
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:00:805 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]     Application: 'PID:37683'
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         id: "PID:37683"
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:00:806 - [debug] [RemoteDebugger]         hostId: "PID:37675"
2020-04-06 17:38:00:807 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:807 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:807 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:00:807 - [debug] [RemoteDebugger]     Application: 'PID:37675'
2020-04-06 17:38:00:807 - [debug] [RemoteDebugger]         id: "PID:37675"
2020-04-06 17:38:00:808 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:00:808 - [debug] [RemoteDebugger]         name: "Global Player"
2020-04-06 17:38:00:808 - [debug] [RemoteDebugger]         bundleId: "com.thisisglobal.guacamole.development"
2020-04-06 17:38:00:808 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:00:809 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:809 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:809 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:00:810 - [debug] [RemoteDebugger] Checking for bundle identifiers: process-SafariViewService, com.apple.SafariViewService, *, com.thisisglobal.guacamole.development
2020-04-06 17:38:00:810 - [debug] [RemoteDebugger] Found app id key 'PID:27373' for bundle 'process-SafariViewService'
2020-04-06 17:38:00:810 - [debug] [RemoteDebugger] Found app id key 'PID:37683' for bundle 'com.apple.SafariViewService'
2020-04-06 17:38:00:811 - [debug] [RemoteDebugger] Found app id key 'PID:37683' for bundle '*'
2020-04-06 17:38:00:811 - [debug] [RemoteDebugger] Found app id key 'PID:37675' for bundle 'com.thisisglobal.guacamole.development'
2020-04-06 17:38:00:811 - [debug] [RemoteDebugger] Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.thisisglobal.guacamole.development', with app id 'PID:37683'
2020-04-06 17:38:00:811 - [debug] [RemoteDebugger] Trying out the possible app ids: PID:27373, PID:37683, PID:37675 (try #1 of 20)
2020-04-06 17:38:00:811 - [debug] [RemoteDebugger] Attempting app 'PID:27373'
2020-04-06 17:38:00:812 - [debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to app 'PID:27373' (id: 3): 'connectToApp'
2020-04-06 17:38:00:821 - [debug] [RemoteDebugger] Received response from send (id: 3): '["PID:27373",{"17":{"WIRTitleKey":"Log in to Facebook | Facebook","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=...'
2020-04-06 17:38:00:822 - [debug] [RemoteDebugger] Page changed: {
2020-04-06 17:38:00:823 - [debug] [RemoteDebugger]   "17": {
2020-04-06 17:38:00:823 - [debug] [RemoteDebugger]     "WIRTitleKey": "Log in to Facebook | Facebook",
2020-04-06 17:38:00:823 - [debug] [RemoteDebugger]     "WIRTypeKey": "WIRTypeWeb",
2020-04-06 17:38:00:823 - [debug] [RemoteDebugger]     "WIRURLKey": "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr",
2020-04-06 17:38:00:823 - [debug] [RemoteDebugger]     "WIRPageIdentifierKey": 17
2020-04-06 17:38:00:824 - [debug] [RemoteDebugger]   }
2020-04-06 17:38:00:824 - [debug] [RemoteDebugger] }
2020-04-06 17:38:00:824 - [debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"27373","pageArray":[{"id":17,"title":"Log in to Facebook | Facebook","url":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr","isKey":false}]}
2020-04-06 17:38:00:825 - [debug] [XCUITest] No key id found. Choosing first id from page array
2020-04-06 17:38:00:825 - [debug] [XCUITest] We do not appear to have window set yet, ignoring
2020-04-06 17:38:00:825 - [debug] [RemoteDebugger] Sending to Web Inspector took 13ms
2020-04-06 17:38:00:826 - [debug] [RemoteDebugger] Received altered app id, updating from 'PID:37683' to 'PID:27373'
2020-04-06 17:38:00:827 - [debug] [RemoteDebugger] Current applications available:
2020-04-06 17:38:00:827 - [debug] [RemoteDebugger]     Application: 'PID:27373'
2020-04-06 17:38:00:828 - [debug] [RemoteDebugger]         id: "PID:27373"
2020-04-06 17:38:00:828 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:00:828 - [debug] [RemoteDebugger]         name: "Web"
2020-04-06 17:38:00:828 - [debug] [RemoteDebugger]         bundleId: "process-SafariViewService"
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]         pageArray:
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]           - id: 17
2020-04-06 17:38:00:829 - [debug] [RemoteDebugger]             title: "Log in to Facebook | Facebook"
2020-04-06 17:38:00:830 - [debug] [RemoteDebugger]             url: "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr"
2020-04-06 17:38:00:833 - [debug] [RemoteDebugger]             isKey: false
2020-04-06 17:38:00:834 - [debug] [RemoteDebugger]     Application: 'PID:37683'
2020-04-06 17:38:00:834 - [debug] [RemoteDebugger]         id: "PID:37683"
2020-04-06 17:38:00:834 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:00:834 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         hostId: "PID:37675"
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]     Application: 'PID:37675'
2020-04-06 17:38:00:835 - [debug] [RemoteDebugger]         id: "PID:37675"
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         name: "Global Player"
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         bundleId: "com.thisisglobal.guacamole.development"
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:00:836 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:00:837 - [debug] [RemoteDebugger] Finally selecting app PID:27373: [{"id":17,"title":"Log in to Facebook | Facebook","url":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr","isKey":false}]
2020-04-06 17:38:00:838 - [debug] [RemoteDebugger] Selected app after 34ms
2020-04-06 17:38:00:838 - [debug] [W3C (38b1ea4f)] Responding to client with driver.getContexts() result: ["NATIVE_APP","WEBVIEW_27373.17"]
2020-04-06 17:38:00:839 - [HTTP] <-- GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts 200 37 ms - 43
2020-04-06 17:38:00:839 - [HTTP]
2020-04-06 17:38:00:842 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:38:00:842 - [HTTP] {"using":"class name","value":"XCUIElementTypeWebView"}
2020-04-06 17:38:00:843 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["class name","XCUIElementTypeWebView","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:38:00:843 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:38:00:843 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:38:00:844 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:38:00:848 - [debug] [WD Proxy] Matched '/element' to command name 'findElement'
2020-04-06 17:38:00:848 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element] with body: {"using":"class name","value":"XCUIElementTypeWebView"}
2020-04-06 17:38:00:993 - [debug] [RemoteDebugger] Notified that new application 'PID:30861' has connected
2020-04-06 17:38:01:044 - [debug] [RemoteDebugger] Notified that new application 'PID:30879' has connected
2020-04-06 17:38:01:052 - [debug] [RemoteDebugger] Page changed: {
2020-04-06 17:38:01:052 - [debug] [RemoteDebugger]   "1": {
2020-04-06 17:38:01:053 - [debug] [RemoteDebugger]     "WIRTitleKey": "Log in to Facebook",
2020-04-06 17:38:01:053 - [debug] [RemoteDebugger]     "WIRTypeKey": "WIRTypeWeb",
2020-04-06 17:38:01:053 - [debug] [RemoteDebugger]     "WIRURLKey": "https://m.facebook.com/?stype=lo&jlou=AfcRTnFueq87swXAxV-12AEjRLCXu0qhTwW2SDkkM6BIiZFJ5HG3an0ROSGQ5AusR9ge-rC9ReBR692h6beB1L5Vz50K1nUzgy75FFD33KXHCw&smuh=4003&lh=Ac8nP84iGQjJXjSa&_rdr",
2020-04-06 17:38:01:054 - [debug] [RemoteDebugger]     "WIRPageIdentifierKey": 1
2020-04-06 17:38:01:054 - [debug] [RemoteDebugger]   }
2020-04-06 17:38:01:054 - [debug] [RemoteDebugger] }
2020-04-06 17:38:01:055 - [debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"30861","pageArray":[{"id":1,"title":"Log in to Facebook","url":"https://m.facebook.com/?stype=lo&jlou=AfcRTnFueq87swXAxV-12AEjRLCXu0qhTwW2SDkkM6BIiZFJ5HG3an0ROSGQ5AusR9ge-rC9ReBR692h6beB1L5Vz50K1nUzgy75FFD33KXHCw&smuh=4003&lh=Ac8nP84iGQjJXjSa&_rdr","isKey":false}]}
2020-04-06 17:38:01:056 - [debug] [XCUITest] No key id found. Choosing first id from page array
2020-04-06 17:38:01:056 - [debug] [XCUITest] We do not appear to have window set yet, ignoring
2020-04-06 17:38:21:178 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"D4010000-0000-0000-ED6A-000000000000","element-6066-11e4-a52e-4f735466cecf":"D4010000-0000-0000-ED6A-000000000000"},"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:38:21:178 - [debug] [W3C (38b1ea4f)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"D4010000-0000-0000-ED6A-000000000000","ELEMENT":"D4010000-0000-0000-ED6A-000000000000"}
2020-04-06 17:38:21:179 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element 200 20337 ms - 137
2020-04-06 17:38:21:180 - [HTTP]
2020-04-06 17:38:21:183 - [HTTP] --> GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts
2020-04-06 17:38:21:183 - [HTTP] {}
2020-04-06 17:38:21:184 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.getContexts() with args: ["38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:38:21:184 - [debug] [XCUITest] Executing command 'getContexts'
2020-04-06 17:38:21:185 - [debug] [XCUITest] Getting list of available contexts
2020-04-06 17:38:21:185 - [debug] [iOS] Retrieving contexts and views
2020-04-06 17:38:21:185 - [debug] [XCUITest] Selecting by url: false
2020-04-06 17:38:21:185 - [debug] [RemoteDebugger] Selecting application
2020-04-06 17:38:21:185 - [debug] [RemoteDebugger] Current applications available:
2020-04-06 17:38:21:186 - [debug] [RemoteDebugger]     Application: 'PID:27373'
2020-04-06 17:38:21:186 - [debug] [RemoteDebugger]         id: "PID:27373"
2020-04-06 17:38:21:186 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:186 - [debug] [RemoteDebugger]         name: "Web"
2020-04-06 17:38:21:187 - [debug] [RemoteDebugger]         bundleId: "process-SafariViewService"
2020-04-06 17:38:21:187 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:187 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:187 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:188 - [debug] [RemoteDebugger]         pageArray:
2020-04-06 17:38:21:188 - [debug] [RemoteDebugger]           - id: 17
2020-04-06 17:38:21:188 - [debug] [RemoteDebugger]             title: "Log in to Facebook | Facebook"
2020-04-06 17:38:21:188 - [debug] [RemoteDebugger]             url: "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr"
2020-04-06 17:38:21:189 - [debug] [RemoteDebugger]             isKey: false
2020-04-06 17:38:21:189 - [debug] [RemoteDebugger]     Application: 'PID:37683'
2020-04-06 17:38:21:189 - [debug] [RemoteDebugger]         id: "PID:37683"
2020-04-06 17:38:21:189 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:21:190 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:21:190 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:21:190 - [debug] [RemoteDebugger]         hostId: "PID:37675"
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]     Application: 'PID:37675'
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         id: "PID:37675"
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:191 - [debug] [RemoteDebugger]         name: "Global Player"
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         bundleId: "com.thisisglobal.guacamole.development"
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]     Application: 'PID:30861'
2020-04-06 17:38:21:192 - [debug] [RemoteDebugger]         id: "PID:30861"
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         name: "Safari"
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         bundleId: "com.apple.mobilesafari"
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         isActive: 0
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         isAutomationEnabled: true
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]         pageArray:
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]           - id: 1
2020-04-06 17:38:21:193 - [debug] [RemoteDebugger]             title: "Log in to Facebook"
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]             url: "https://m.facebook.com/?stype=lo&jlou=AfcRTnFueq87swXAxV-12AEjRLCXu0qhTwW2SDkkM6BIiZFJ5HG3an0ROSGQ5AusR9ge-rC9ReBR692h6beB1L5Vz50K1nUzgy75FFD33KXHCw&smuh=4003&lh=Ac8nP84iGQjJXjSa&_rdr"
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]             isKey: false
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]     Application: 'PID:30879'
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]         id: "PID:30879"
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:21:194 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:21:195 - [debug] [RemoteDebugger]         hostId: "PID:30861"
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger]         isActive: 0
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger] Checking for bundle identifiers: process-SafariViewService, com.apple.SafariViewService, *, com.thisisglobal.guacamole.development
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger] Found app id key 'PID:27373' for bundle 'process-SafariViewService'
2020-04-06 17:38:21:196 - [debug] [RemoteDebugger] Found app id key 'PID:37683' for bundle 'com.apple.SafariViewService'
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Found app id key 'PID:37683' for bundle '*'
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Found app id key 'PID:37675' for bundle 'com.thisisglobal.guacamole.development'
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.thisisglobal.guacamole.development', with app id 'PID:37683'
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Trying out the possible app ids: PID:27373, PID:37683, PID:37675 (try #1 of 20)
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Attempting app 'PID:27373'
2020-04-06 17:38:21:197 - [debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to app 'PID:27373' (id: 4): 'connectToApp'
2020-04-06 17:38:21:201 - [debug] [RemoteDebugger] Received response from send (id: 4): '["PID:27373",{"17":{"WIRTitleKey":"Log in to Facebook | Facebook","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=...'
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger] Received page change notice for app 'PID:27373' but the listing has not changed. Ignoring.
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger] Page changed: {
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger]   "17": {
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger]     "WIRTitleKey": "Log in to Facebook | Facebook",
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger]     "WIRTypeKey": "WIRTypeWeb",
2020-04-06 17:38:21:202 - [debug] [RemoteDebugger]     "WIRURLKey": "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr",
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger]     "WIRPageIdentifierKey": 17
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger]   }
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger] }
2020-04-06 17:38:21:203 - [debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"27373","pageArray":[{"id":17,"title":"Log in to Facebook | Facebook","url":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr","isKey":false}]}
2020-04-06 17:38:21:203 - [debug] [XCUITest] No key id found. Choosing first id from page array
2020-04-06 17:38:21:203 - [debug] [XCUITest] We do not appear to have window set yet, ignoring
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger] Sending to Web Inspector took 6ms
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger] Current applications available:
2020-04-06 17:38:21:203 - [debug] [RemoteDebugger]     Application: 'PID:27373'
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         id: "PID:27373"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         name: "Web"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         bundleId: "process-SafariViewService"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         pageArray:
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]           - id: 17
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]             title: "Log in to Facebook | Facebook"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]             url: "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr"
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]             isKey: false
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]     Application: 'PID:37683'
2020-04-06 17:38:21:204 - [debug] [RemoteDebugger]         id: "PID:37683"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         hostId: "PID:37675"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]     Application: 'PID:37675'
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         id: "PID:37675"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         name: "Global Player"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         bundleId: "com.thisisglobal.guacamole.development"
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:205 - [debug] [RemoteDebugger]         isActive: 2
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]     Application: 'PID:30861'
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         id: "PID:30861"
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         isProxy: false
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         name: "Safari"
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         bundleId: "com.apple.mobilesafari"
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         hostId: undefined
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         isActive: 0
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         isAutomationEnabled: true
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]         pageArray:
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]           - id: 1
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]             title: "Log in to Facebook"
2020-04-06 17:38:21:206 - [debug] [RemoteDebugger]             url: "https://m.facebook.com/?stype=lo&jlou=AfcRTnFueq87swXAxV-12AEjRLCXu0qhTwW2SDkkM6BIiZFJ5HG3an0ROSGQ5AusR9ge-rC9ReBR692h6beB1L5Vz50K1nUzgy75FFD33KXHCw&smuh=4003&lh=Ac8nP84iGQjJXjSa&_rdr"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]             isKey: false
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]     Application: 'PID:30879'
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         id: "PID:30879"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         isProxy: true
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         name: "com.apple.WebKit.WebContent"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         bundleId: "process-com.apple.WebKit.WebContent"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         hostId: "PID:30861"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         isActive: 0
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         isAutomationEnabled: "Unknown"
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger]         pageArray: 'Waiting for data'
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger] Finally selecting app PID:27373: [{"id":17,"title":"Log in to Facebook | Facebook","url":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr","isKey":false}]
2020-04-06 17:38:21:207 - [debug] [RemoteDebugger] Selected app after 22ms
2020-04-06 17:38:21:208 - [debug] [W3C (38b1ea4f)] Responding to client with driver.getContexts() result: ["NATIVE_APP","WEBVIEW_27373.17"]
2020-04-06 17:38:21:208 - [HTTP] <-- GET /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/contexts 200 25 ms - 43
2020-04-06 17:38:21:208 - [HTTP]
2020-04-06 17:38:21:210 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/context
2020-04-06 17:38:21:210 - [HTTP] {"name":"WEBVIEW_27373.17"}
2020-04-06 17:38:21:210 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.setContext() with args: ["WEBVIEW_27373.17","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:38:21:210 - [debug] [XCUITest] Executing command 'setContext'
2020-04-06 17:38:21:211 - [debug] [XCUITest] Attempting to set context to 'WEBVIEW_27373.17' from 'NATIVE_APP'
2020-04-06 17:38:21:211 - [debug] [RemoteDebugger] Selecting page '17' on app 'PID:27373' and forwarding socket setup
2020-04-06 17:38:21:211 - [debug] [RemoteDebugger] Sending '_rpc_forwardIndicateWebView:' message to app 'PID:27373', page '17' (id: 5): 'indicateWebView'
2020-04-06 17:38:21:216 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2020-04-06 17:38:21:217 - [debug] [RemoteDebugger] Sending '_rpc_forwardIndicateWebView:' message to app 'PID:27373', page '17' (id: 6): 'indicateWebView'
2020-04-06 17:38:21:221 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2020-04-06 17:38:21:222 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketSetup:' message to app 'PID:27373', page '17' (id: 7): 'setSenderKey'
2020-04-06 17:38:21:226 - [debug] [RemoteDebugger] Sending to Web Inspector took 4ms
2020-04-06 17:38:21:226 - [debug] [RemoteDebugger] Sender key set
2020-04-06 17:38:21:227 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 8): 'Inspector.enable'
2020-04-06 17:38:21:231 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2020-04-06 17:38:21:231 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 9): 'Page.enable'
2020-04-06 17:38:21:234 - [debug] [RemoteDebugger] Sending to Web Inspector took 3ms
2020-04-06 17:38:21:234 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 10): 'Network.enable'
2020-04-06 17:38:21:239 - [debug] [RemoteDebugger] Sending to Web Inspector took 4ms
2020-04-06 17:38:21:239 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 11): 'Runtime.enable'
2020-04-06 17:38:21:245 - [debug] [RemoteDebugger] Target created for app 'PID:27373' and page '17': {"targetId":"page-117","type":"page"}
2020-04-06 17:38:21:245 - [debug] [RemoteDebugger] Handling message (id: '8')
2020-04-06 17:38:21:246 - [debug] [RemoteDebugger] Handling message (id: '9')
2020-04-06 17:38:21:246 - [debug] [RemoteDebugger] Handling message (id: '10')
2020-04-06 17:38:21:246 - [debug] [RemoteDebugger] Handling message (id: '11')
2020-04-06 17:38:21:247 - [debug] [RemoteDebugger] Sending to Web Inspector took 8ms
2020-04-06 17:38:21:247 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 12): 'Heap.enable'
2020-04-06 17:38:21:251 - [debug] [RemoteDebugger] Sending to Web Inspector took 4ms
2020-04-06 17:38:21:251 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 13): 'Debugger.enable'
2020-04-06 17:38:21:256 - [debug] [RemoteDebugger] Handling message (id: '12')
2020-04-06 17:38:21:256 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2020-04-06 17:38:21:256 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 14): 'Console.enable'
2020-04-06 17:38:21:260 - [debug] [RemoteDebugger] Sending to Web Inspector took 4ms
2020-04-06 17:38:21:260 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 15): 'Inspector.initialized'
2020-04-06 17:38:21:264 - [debug] [RemoteDebugger] Sending to Web Inspector took 4ms
2020-04-06 17:38:21:265 - [debug] [RemoteDebugger] Checking document readyState
2020-04-06 17:38:21:265 - [debug] [RemoteDebugger] Sending javascript command: 'document.readyState;'
2020-04-06 17:38:21:265 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17' (id: 16): 'Runtime.evaluate'
2020-04-06 17:38:21:289 - [debug] [RemoteDebugger] Handling message (id: '13')
2020-04-06 17:38:21:289 - [debug] [RemoteDebugger] Handling message (id: '14')
2020-04-06 17:38:21:289 - [debug] [RemoteDebugger] Handling message (id: '15')
2020-04-06 17:38:21:289 - [debug] [RemoteDebugger] Handling message (id: '16')
2020-04-06 17:38:21:290 - [RemoteDebugger] Setting communication protocol: using Target-based communication
2020-04-06 17:38:21:291 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17', target 'page-117' (id: 17): 'Runtime.evaluate'
2020-04-06 17:38:21:459 - [debug] [RemoteDebugger] Page changed: {
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]   "17": {
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]     "WIRTitleKey": "Log in to Facebook | Facebook",
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]     "WIRTypeKey": "WIRTypeWeb",
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]     "WIRURLKey": "https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr",
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]     "WIRPageIdentifierKey": 17,
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]     "WIRConnectionIdentifierKey": "3f012480-f9f3-494b-a48e-6ab13e8cfff0"
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger]   }
2020-04-06 17:38:21:460 - [debug] [RemoteDebugger] }
2020-04-06 17:38:21:460 - [debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"27373","pageArray":[{"id":17,"title":"Log in to Facebook | Facebook","url":"https://m.facebook.com/login.php?skip_api_login=1&api_key=166993440513641&kid_directed_site=0&app_id=166993440513641&signed_next=1&next=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth%3Fauth_type%3Drerequest%26cbt%3D1586194677124%26client_id%3D166993440513641%26default_audience%3Dfriends%26display%3Dtouch%26e2e%3D%257B%2522init%2522%253A36183.188391885%257D%26fbapp_pres%3D0%26ies%3D1%26redirect_uri%3Dfb166993440513641%253A%252F%252Fauthorize%252F%26response_type%3Dtoken_or_nonce%252Csigned_request%26return_scopes%3Dtrue%26scope%3Demail%26sdk%3Dios%26sdk_version%3D5.15.0%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%26ret%3Dlogin%26logger_id%3DDD6D7242-D5AB-4251-A4D2-A2BD3CCE1414&cancel_url=fb166993440513641%3A%2F%2Fauthorize%2F%3Ferror%3Daccess_denied%26error_code%3D200%26error_description%3DPermissions%2Berror%26error_reason%3Duser_denied%26state%3D%257B%2522challenge%2522%253A%2522pn7bmFMXEgDx4Nx%25252F37xZDl0gLLo%25253D%2522%252C%25220_auth_logger_id%2522%253A%2522DD6D7242-D5AB-4251-A4D2-A2BD3CCE1414%2522%252C%2522com.facebook.sdk_client_state%2522%253Atrue%252C%25223_method%2522%253A%2522sfvc_auth%2522%257D%23_%3D_&display=touch&locale=en_GB&pl_dbl=0&refsrc=https%3A%2F%2Fm.facebook.com%2Fv5.0%2Fdialog%2Foauth&_rdr","isKey":true}]}
2020-04-06 17:38:21:460 - [debug] [XCUITest] We are in the middle of selecting a page, ignoring
2020-04-06 17:38:26:275 - [debug] [RemoteDebugger] Page readiness check timed out after 5000ms
2020-04-06 17:38:26:275 - [debug] [RemoteDebugger] Page unloading
2020-04-06 17:38:26:276 - [debug] [RemoteDebugger] Waiting for dom...
2020-04-06 17:38:26:276 - [debug] [RemoteDebugger] Page loaded, verifying whether ready
2020-04-06 17:38:26:780 - [debug] [RemoteDebugger] Checking document readyState
2020-04-06 17:38:26:780 - [debug] [RemoteDebugger] Sending javascript command: 'document.readyState;'
2020-04-06 17:38:26:781 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17', target 'page-117' (id: 19): 'Runtime.evaluate'
2020-04-06 17:38:31:789 - [debug] [RemoteDebugger] Page readiness check timed out after 5000ms
2020-04-06 17:38:31:789 - [debug] [RemoteDebugger] Page was not ready, retrying
2020-04-06 17:38:32:292 - [debug] [RemoteDebugger] Checking document readyState
2020-04-06 17:38:32:293 - [debug] [RemoteDebugger] Sending javascript command: 'document.readyState;'
2020-04-06 17:38:32:293 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17', target 'page-117' (id: 21): 'Runtime.evaluate'
2020-04-06 17:38:37:302 - [debug] [RemoteDebugger] Page readiness check timed out after 5000ms
2020-04-06 17:38:37:302 - [debug] [RemoteDebugger] Page is ready
2020-04-06 17:38:37:302 - [debug] [RemoteDebugger] Selected page after 16091ms
2020-04-06 17:38:37:302 - [debug] [RemoteDebugger] Starting to listen for JavaScript console
2020-04-06 17:38:37:303 - [debug] [RemoteDebugger] Starting to listen for network events
2020-04-06 17:38:37:304 - [debug] [W3C (38b1ea4f)] Responding to client with driver.setContext() result: null
2020-04-06 17:38:37:305 - [HTTP] <-- POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/context 200 16094 ms - 14
2020-04-06 17:38:37:305 - [HTTP]
2020-04-06 17:38:37:312 - [HTTP] --> POST /wd/hub/session/38b1ea4f-8049-48e9-ade5-7879f429b640/element
2020-04-06 17:38:37:313 - [HTTP] {"using":"name","value":"Mobile number or email address"}
2020-04-06 17:38:37:313 - [debug] [W3C (38b1ea4f)] Calling AppiumDriver.findElement() with args: ["name","Mobile number or email address","38b1ea4f-8049-48e9-ade5-7879f429b640"]
2020-04-06 17:38:37:313 - [debug] [XCUITest] Executing command 'findElement'
2020-04-06 17:38:37:314 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2020-04-06 17:38:37:314 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:38:37:314 - [debug] [RemoteDebugger] Executing atom 'find_element'
2020-04-06 17:38:37:318 - [debug] [RemoteDebugger] Executing 'find_element' atom in default context
2020-04-06 17:38:37:318 - [debug] [RemoteDebugger] Sending javascript command: '(function(){return function(){var k=this;functi...'
2020-04-06 17:38:37:319 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:27373', page '17', target 'page-117' (id: 23): 'Runtime.evaluate'
2020-04-06 17:38:37:817 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:38:37:818 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2020-04-06 17:38:37:818 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/elements] with body: {"using":"class name","value":"XCUIElementTypeScrollView"}
2020-04-06 17:38:53:033 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"27010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"27010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:38:53:033 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:38:53:036 - [debug] [WD Proxy] Matched '/element/27010000-0000-0000-2B93-000000000000/elements' to command name 'findElementsFromElement'
2020-04-06 17:38:53:037 - [debug] [WD Proxy] Proxying [POST /element/27010000-0000-0000-2B93-000000000000/elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/27010000-0000-0000-2B93-000000000000/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
2020-04-06 17:39:48:867 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:39:49:367 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:39:49:368 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2020-04-06 17:39:49:368 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/elements] with body: {"using":"class name","value":"XCUIElementTypeScrollView"}
2020-04-06 17:40:04:589 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"27010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"27010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:40:04:589 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:40:04:590 - [debug] [WD Proxy] Matched '/element/27010000-0000-0000-2B93-000000000000/elements' to command name 'findElementsFromElement'
2020-04-06 17:40:04:590 - [debug] [WD Proxy] Proxying [POST /element/27010000-0000-0000-2B93-000000000000/elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/27010000-0000-0000-2B93-000000000000/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
2020-04-06 17:41:00:473 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:41:00:978 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:41:00:979 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2020-04-06 17:41:00:979 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/elements] with body: {"using":"class name","value":"XCUIElementTypeScrollView"}
2020-04-06 17:41:16:205 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"27010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"27010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:41:16:205 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:41:16:206 - [debug] [WD Proxy] Matched '/element/27010000-0000-0000-2B93-000000000000/elements' to command name 'findElementsFromElement'
2020-04-06 17:41:16:206 - [debug] [WD Proxy] Proxying [POST /element/27010000-0000-0000-2B93-000000000000/elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/27010000-0000-0000-2B93-000000000000/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
2020-04-06 17:42:12:069 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:42:12:570 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:42:12:571 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2020-04-06 17:42:12:571 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/elements] with body: {"using":"class name","value":"XCUIElementTypeScrollView"}
2020-04-06 17:42:27:793 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"27010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"27010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:42:27:793 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:42:27:795 - [debug] [WD Proxy] Matched '/element/27010000-0000-0000-2B93-000000000000/elements' to command name 'findElementsFromElement'
2020-04-06 17:42:27:796 - [debug] [WD Proxy] Proxying [POST /element/27010000-0000-0000-2B93-000000000000/elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/27010000-0000-0000-2B93-000000000000/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
2020-04-06 17:43:23:684 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:43:24:193 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:43:24:194 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2020-04-06 17:43:24:194 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/elements] with body: {"using":"class name","value":"XCUIElementTypeScrollView"}
2020-04-06 17:43:37:325 - [debug] [XCUITest] Error received while executing atom: operation timed out
2020-04-06 17:43:39:482 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"27010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"27010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}
2020-04-06 17:43:39:482 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2020-04-06 17:43:39:484 - [debug] [WD Proxy] Matched '/element/27010000-0000-0000-2B93-000000000000/elements' to command name 'findElementsFromElement'
2020-04-06 17:43:39:484 - [debug] [WD Proxy] Proxying [POST /element/27010000-0000-0000-2B93-000000000000/elements] to [POST http://127.0.0.1:8100/session/A4F13D50-B23E-4187-ADD3-DAA013356453/element/27010000-0000-0000-2B93-000000000000/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
2020-04-06 17:44:35:418 - [debug] [WD Proxy] Got response with status 200: {"value":[{"ELEMENT":"2D010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"2D010000-0000-0000-2B93-000000000000"},{"ELEMENT":"35010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"35010000-0000-0000-2B93-000000000000"},{"ELEMENT":"36010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"36010000-0000-0000-2B93-000000000000"},{"ELEMENT":"37010000-0000-0000-2B93-000000000000","element-6066-11e4-a52e-4f735466cecf":"37010000-0000-0000-2B93-000000000000"}],"sessionId":"A4F13D50-B23E-4187-ADD3-DAA013356453"}