ios9-log

[HTTP] --> GET /wd/hub/session/e47fef22-f2db-47af-97fa-c7884266e251/contexts {}


[MJSONWP] Calling AppiumDriver.getContexts() with args: ["e47fef22-f2db-47af-97fa-c...
[debug] [iOS] Executing iOS command 'getContexts'
[debug] [iOS] Getting list of available contexts
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: false 
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Debugger socket connected to ::1:27753
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message to remote debugger


[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_reportSetup:","__argument":{"WIRSimulatorNameKey":"iPhone","WIRSimulatorProductVersionKey":"9.0","WIRSimulatorBuildKey":"13A340"}}'


[debug] [RemoteDebugger] Received response from socket send: '["iPhone","13A340","9.0"]'
[debug] [RemoteDebugger] Sim name: iPhone
[debug] [RemoteDebugger] Sim build: 13A340
[debug] [RemoteDebugger] Sim platform version: 9.0


[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_reportConnectedApplicationList:","__argument":{"WIRApplicationDictionaryKey":{"PID:44667":{"WIRApplicationBundleIdentifierKey":"com.myapptest","WIRApplicationNameKey":"myapptest","WIRIsApplicationProxyKey":false,"WIRIsApplicationActiveKey":1,"WIRApplicationIdentifierKey":"PID:44667"}}}}'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationConnected:","__argument":{"WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44667","WIRApplicationNameKey":"","WIRIsApplicationProxyKey":true,"WIRIsApplicationActiveKey":1,"WIRApplicationIdentifierKey":"PID:44679"}}'
[debug] [RemoteDebugger] Notified that a new application PID:44679 has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:44667'
[debug] [RemoteDebugger]     id: '"PID:44667"'
[debug] [RemoteDebugger]     name: '"myapptest"'
[debug] [RemoteDebugger]     bundleId: '"com.myapptest"'
[debug] [RemoteDebugger]     isProxy: 'false'
[debug] [RemoteDebugger]     hostId: 'undefined'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger] Application: 'PID:44679'
[debug] [RemoteDebugger]     id: '"PID:44679"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Found app id key 'PID:44667' for bundle 'com.myapptest'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44679'
[debug] [RemoteDebugger] Using proxied app id 'PID:44679'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationConnected:","__argument":{"WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44667","WIRApplicationNameKey":"","WIRIsApplicationProxyKey":true,"WIRIsApplicationActiveKey":1,"WIRApplicationIdentifierKey":"PID:44675"}}'
[debug] [RemoteDebugger] Notified that a new application PID:44675 has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:44667'
[debug] [RemoteDebugger]     id: '"PID:44667"'
[debug] [RemoteDebugger]     name: '"myapptest"'


[debug] [RemoteDebugger]     bundleId: '"com.myapptest"'
[debug] [RemoteDebugger]     isProxy: 'false'
[debug] [RemoteDebugger]     hostId: 'undefined'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger] Application: 'PID:44679'
[debug] [RemoteDebugger]     id: '"PID:44679"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Application: 'PID:44675'
[debug] [RemoteDebugger]     id: '"PID:44675"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Found app id key 'PID:44667' for bundle 'com.myapptest'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44679'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44675'
[debug] [RemoteDebugger] Using proxied app id 'PID:44675'
[debug] [RemoteDebugger] Connected to application
[debug] [RemoteDebugger] Selecting application
[debug] [RemoteDebugger] Found app id key 'PID:44667' for bundle 'com.myapptest'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44679'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44675'
[debug] [RemoteDebugger] Trying out the possible app ids: PID:44679, PID:44675
[debug] [RemoteDebugger] Selecting app PID:44679 (try #1 of 20)
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to remote debugger


[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationConnected:","__argument":{"WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44667","WIRApplicationNameKey":"","WIRIsApplicationProxyKey":true,"WIRIsApplicationActiveKey":1,"WIRApplicationIdentifierKey":"PID:44677"}}'
[debug] [RemoteDebugger] We were notified we might have connected to the wrong app. Using id PID:44677 instead of PID:44667
[debug] [RemoteDebugger] Notified that a new application PID:44677 has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:44667'
[debug] [RemoteDebugger]     id: '"PID:44667"'
[debug] [RemoteDebugger]     name: '"myapptest"'
[debug] [RemoteDebugger]     bundleId: '"com.myapptest"'
[debug] [RemoteDebugger]     isProxy: 'false'
[debug] [RemoteDebugger]     hostId: 'undefined'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger] Application: 'PID:44679'
[debug] [RemoteDebugger]     id: '"PID:44679"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Application: 'PID:44675'
[debug] [RemoteDebugger]     id: '"PID:44675"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Application: 'PID:44677'
[debug] [RemoteDebugger]     id: '"PID:44677"'
[debug] [RemoteDebugger]     name: '""'
[debug] [RemoteDebugger]     bundleId: '"com.apple.WebKit.WebContent"'
[debug] [RemoteDebugger]     isProxy: 'true'
[debug] [RemoteDebugger]     hostId: '"PID:44667"'
[debug] [RemoteDebugger]     isActive: '1'
[debug] [RemoteDebugger]     pageDict: '{"promise":{"isFulfilled":false,"isRejected":false}}'
[debug] [RemoteDebugger] Found app id key 'PID:44667' for bundle 'com.myapptest'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44679'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44675'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.myapptest', with app id 'PID:44677'
[debug] [RemoteDebugger] Using proxied app id 'PID:44677'
[debug] [RemoteDebugger] Retrying connection
[debug] [RemoteDebugger] Selecting app PID:44675 (try #1 of 20)
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationSentListing:","__argument":{"WIRApplicationIdentifierKey":"PID:44679","WIRListingKey":{"1":{"WIRPageIdentifierKey":1,"WIRURLKey":"about:blank","WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb"}}}}'
[debug] [RemoteDebugger] Received response from socket send: '["PID:44679",{"1":{"WIRPage...'
[debug] [RemoteDebugger] Received response from socket send: '["PID:44679",{"1":{"WIRPage...'
[debug] [RemoteDebugger] Received page change notice for app PID:44679 but listening for PID:44677. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationSentListing:","__argument":{"WIRApplicationIdentifierKey":"PID:44675","WIRListingKey":{"1":{"WIRPageIdentifierKey":1,"WIRURLKey":"about:blank","WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb"}}}}'
[debug] [RemoteDebugger] Received response from socket send: '["PID:44675",{"1":{"WIRPage...'
[debug] [RemoteDebugger] Received page change notice for app PID:44675 but listening for PID:44677. 

Ignoring.
[debug] [RemoteDebugger] Received altered app id, updating from 'PID:44677' to 'PID:44679'


[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationSentListing:","__argument":{"WIRApplicationIdentifierKey":"PID:44667","WIRListingKey":{"5":{"WIRPageIdentifierKey":5,"WIRURLKey":"file:///Users/vic/Library/Developer/CoreSimulator/Devices/87AC75A4-3960-4F6B-ADD0-417818D59C9E/data/Containers/Bundle/Application/3B760BE7-F3E3-4F13-8377-9A0E89A3A719/myapptest.app/onboarding_terms.html","WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb"}}}}'


[debug] [RemoteDebugger] Received page change notice for app PID:44667 but listening for PID:44679. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__selector":"_rpc_applicationSentListing:","__argument":{"WIRApplicationIdentifierKey":"PID:44677","WIRListingKey":{"1":{"WIRPageIdentifierKey":1,"WIRURLKey":"about:blank","WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb"}}}}'
[debug] [RemoteDebugger] Received page change notice for app PID:44677 but listening for PID:44679. Ignoring.
[debug] [RemoteDebugger] Selecting app PID:44679: [{"id":1,"title":"","url":"about:blank","isKey":false}]
[debug] [UIAuto] Sending command to instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:51 +0000 Debug: Got new command 45 from instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:51 +0000 Debug: evaluating au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:51 +0000 Debug: evaluation finished


[debug] [Instruments] [INST] 2016-06-16 23:09:51 +0000 Debug: responding with:


[debug] [Instruments] [INST] 2016-06-16 23:09:51 +0000 Debug: Running system command #46: /Applications/Appium-1.5.2.app/Contents/Resources/node/bin/node /Users/vic/git/appium/builds/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/_0/mfxmfms95399181fm924s5740000ks/T/instruments_sock 2,{"status":0,"value":false}...


[debug] [UIAuto] Socket data received (28 bytes)


[debug] [UIAuto] Got result from instruments: {"status":0,"value":false}


[HTTP] --> GET /wd/hub/status {}


[MJSONWP] Calling AppiumDriver.getStatus() with args: []
[MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
[HTTP] <-- GET /wd/hub/status 200 13 ms - 121 


[debug] [UIAuto] Sending command to instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:55 +0000 Debug: Got new command 46 from instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:55 +0000 Debug: evaluating au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:55 +0000 Debug: evaluation finished


[debug] [Instruments] [INST] 2016-06-16 23:09:55 +0000 Debug: responding with:


[debug] [Instruments] [INST] 2016-06-16 23:09:55 +0000 Debug: Running system command #47: /Applications/Appium-1.5.2.app/Contents/Resources/node/bin/node /Users/vic/git/appium/builds/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/_0/mfxmfms95399181fm924s5740000ks/T/instruments_sock 2,{"status":0,"value":false}...


[debug] [UIAuto] Socket data received (28 bytes)


[debug] [UIAuto] Got result from instruments: {"status":0,"value":false}


[debug] [UIAuto] Sending command to instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:59 +0000 Debug: Got new command 47 from instruments: au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:59 +0000 Debug: evaluating au.alertIsPresent()


[debug] [Instruments] [INST] 2016-06-16 23:09:59 +0000 Debug: evaluation finished
[debug] [Instruments] [INST] 2016-06-16 23:09:59 +0000 Debug: responding with:


[debug] [Instruments] [INST] 2016-06-16 23:09:59 +0000 Debug: Running system command #48: /Applications/Appium-1.5.2.app/Contents/Resources/node/bin/node /Users/vic/git/appium/builds/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/_0/mfxmfms95399181fm924s5740000ks/T/instruments_sock 2,{"status":0,"value":false}...


[debug] [UIAuto] Socket data received (28 bytes)


[debug] [UIAuto] Got result from instruments: {"status":0,"value":false}


[MJSONWP] Responding to client with driver.getContexts() result: ["NATIVE_APP","WEBVIEW_4466...


[HTTP] <-- GET /wd/hub/session/e47fef22-f2db-47af-97fa-c7884266e251/contexts 200 12917 ms - 158