1.6.3

gistfile1.txt

2018-01-25 08:40:50:837 - info: [Appium] Welcome to Appium v1.6.3
2018-01-25 08:40:50:838 - info: [Appium] Non-default server args:
2018-01-25 08:40:50:839 - info: [Appium]   log: 'log.txt'
2018-01-25 08:40:50:839 - info: [Appium]   logTimestamp: true
2018-01-25 08:40:50:856 - info: [Appium] Appium REST http interface listener started on 0.0.0.0:4723
2018-01-25 08:41:01:379 - info: [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true,"port":4723,"desiredCapabilities":{"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true},"requestOrigins":{"url":"http://webdriver.io","version":"4.10.1","name":"webdriverio"}}}
2018-01-25 08:41:01:381 - info: [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true,"port":4723,"desiredCapabilities":{"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true},"requestOrigins":{"url":"http://webdriver.io","version":"4.10.1","name":"webdriverio"}},null,null,null,null]
2018-01-25 08:41:01:383 - info: [Appium] Creating new AndroidDriver session
2018-01-25 08:41:01:383 - info: [Appium] Capabilities:
2018-01-25 08:41:01:384 - info: [Appium]   javascriptEnabled: true
2018-01-25 08:41:01:384 - info: [Appium]   locationContextEnabled: true
2018-01-25 08:41:01:384 - info: [Appium]   handlesAlerts: true
2018-01-25 08:41:01:385 - info: [Appium]   rotatable: true
2018-01-25 08:41:01:385 - info: [Appium]   platformName: 'Android'
2018-01-25 08:41:01:386 - info: [Appium]   deviceName: 'Android'
2018-01-25 08:41:01:386 - info: [Appium]   udid: 'localhost:7401'
2018-01-25 08:41:01:387 - info: [Appium]   appPackage: 'com.pointvoucher.playlondonpv'
2018-01-25 08:41:01:387 - info: [Appium]   appActivity: 'com.unity3d.player.UnityPlayerActivity'
2018-01-25 08:41:01:387 - info: [Appium]   fullReset: false
2018-01-25 08:41:01:388 - info: [Appium]   noReset: true
2018-01-25 08:41:01:388 - info: [Appium]   noPermsCheck: true
2018-01-25 08:41:01:388 - info: [Appium]   launch: true
2018-01-25 08:41:01:388 - info: [Appium]   port: 4723
2018-01-25 08:41:01:389 - info: [Appium]   desiredCapabilities: { platformName: 'Android',
  deviceName: 'Android',
  udid: 'localhost:7401',
  appPackage: 'com.pointvoucher.playlondonpv',
  appActivity: 'com.unity3d.player.UnityPlayerActivity',
  fullReset: false,
  noReset: true,
  noPermsCheck: true,
  launch: true }
2018-01-25 08:41:01:389 - info: [Appium]   requestOrigins: { url: 'http://webdriver.io',
  version: '4.10.1',
  name: 'webdriverio' }
2018-01-25 08:41:01:390 - info: [debug] [AndroidDriver] AndroidDriver version: 1.10.38
2018-01-25 08:41:01:394 - warn: [BaseDriver] The following capabilities were provided, but are not recognized by appium: javascriptEnabled, locationContextEnabled, handlesAlerts, rotatable, noPermsCheck, launch, port, desiredCapabilities, requestOrigins.
2018-01-25 08:41:01:394 - info: [BaseDriver] Session created with session id: 57942e8e-503e-41a5-a9c2-1b990ee6366f
2018-01-25 08:41:01:407 - info: [debug] [AndroidDriver] Getting Java version
2018-01-25 08:41:01:448 - info: [AndroidDriver] Java version is: 1.8.0_151
2018-01-25 08:41:01:451 - info: [ADB] Checking whether adb is present
2018-01-25 08:41:01:454 - info: [ADB] Using adb from /home/trixo/Android/Sdk/platform-tools/adb
2018-01-25 08:41:01:454 - info: [AndroidDriver] Retrieving device list
2018-01-25 08:41:01:454 - info: [debug] [ADB] Trying to find a connected android device
2018-01-25 08:41:01:455 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:01:460 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:01:461 - info: [AndroidDriver] Using device: localhost:7401
2018-01-25 08:41:01:461 - info: [ADB] Checking whether adb is present
2018-01-25 08:41:01:463 - info: [ADB] Using adb from /home/trixo/Android/Sdk/platform-tools/adb
2018-01-25 08:41:01:463 - info: [debug] [ADB] Setting device id to localhost:7401
2018-01-25 08:41:01:463 - info: [AndroidDriver] App file was not listed, instead we're going to run com.pointvoucher.playlondonpv directly on the device
2018-01-25 08:41:01:464 - info: [debug] [AndroidDriver] Checking whether package is present on the device
2018-01-25 08:41:01:464 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:01:469 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:01:472 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","pm","list","packages","com.pointvoucher.playlondonpv"]
2018-01-25 08:41:03:065 - info: [AndroidDriver] Starting Android session
2018-01-25 08:41:03:068 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","wait-for-device"]
2018-01-25 08:41:03:078 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:03:082 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:03:083 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","echo","ping"]
2018-01-25 08:41:03:106 - info: [debug] [Logcat] Starting logcat capture
2018-01-25 08:41:03:161 - info: [debug] [AndroidDriver] Pushing settings apk to device...
2018-01-25 08:41:03:162 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","install","/home/trixo/Downloads/package/node_modules/io.appium.settings/bin/settings_apk-debug.apk"]
2018-01-25 08:41:05:794 - info: [debug] [AndroidDriver] Pushing unlock helper app to device...
2018-01-25 08:41:05:795 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","install","/home/trixo/Downloads/package/node_modules/appium-unlock/bin/unlock_apk-debug.apk"]
2018-01-25 08:41:07:701 - info: [ADB] Getting device platform version
2018-01-25 08:41:07:701 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:07:706 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:07:706 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","getprop","ro.build.version.release"]
2018-01-25 08:41:07:747 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:07:754 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:07:755 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","dumpsys","window"]
2018-01-25 08:41:07:816 - info: [AndroidDriver] Screen already unlocked, doing nothing
2018-01-25 08:41:07:818 - warn: [AndroidDriver] No app sent in, not parsing package/activity
2018-01-25 08:41:07:818 - info: [debug] [AndroidDriver] No app capability. Assuming it is already on the device
2018-01-25 08:41:07:820 - info: [debug] [AndroidBootstrap] Watching for bootstrap disconnect
2018-01-25 08:41:07:821 - info: [debug] [ADB] Forwarding system: 4724 to device: 4724
2018-01-25 08:41:07:822 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","forward","tcp:4724","tcp:4724"]
2018-01-25 08:41:07:828 - info: [debug] [UiAutomator] Starting UiAutomator
2018-01-25 08:41:07:828 - info: [debug] [UiAutomator] Moving to state 'starting'
2018-01-25 08:41:07:828 - info: [debug] [UiAutomator] Parsing uiautomator jar
2018-01-25 08:41:07:829 - info: [debug] [UiAutomator] Found jar name: 'AppiumBootstrap.jar'
2018-01-25 08:41:07:830 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","push","/home/trixo/Downloads/package/node_modules/appium-android-bootstrap/bootstrap/bin/AppiumBootstrap.jar","/data/local/tmp/"]
2018-01-25 08:41:07:877 - info: [debug] [ADB] Attempting to kill all uiautomator processes
2018-01-25 08:41:07:877 - info: [debug] [ADB] Getting all processes with uiautomator
2018-01-25 08:41:07:878 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:07:882 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:07:882 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","ps"]
2018-01-25 08:41:08:041 - info: [debug] [ADB] Attempting to kill process 8991
2018-01-25 08:41:08:041 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:08:050 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:08:050 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","kill",8991]
2018-01-25 08:41:08:073 - info: [debug] [UiAutomator] Starting UIAutomator
2018-01-25 08:41:08:074 - info: [debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","localhost:7401","shell","uiautomator","runtest","AppiumBootstrap.jar","-c","io.appium.android.bootstrap.Bootstrap","-e","pkg","com.pointvoucher.playlondonpv","-e","disableAndroidWatchers",false,"-e","acceptSslCerts",false]
2018-01-25 08:41:10:109 - info: [debug] [UiAutomator] Moving to state 'online'
2018-01-25 08:41:10:113 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Registered crash watchers.
2018-01-25 08:41:10:114 - info: [AndroidBootstrap] Android bootstrap socket is now connected
2018-01-25 08:41:10:117 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:10:122 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Client connected
2018-01-25 08:41:10:123 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:10:123 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","getprop","ro.build.version.sdk"]
2018-01-25 08:41:10:165 - info: [debug] [ADB] Device API level: 23
2018-01-25 08:41:10:166 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:41:10:174 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:41:10:175 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","am","start","-W","-n","com.pointvoucher.playlondonpv/com.unity3d.player.UnityPlayerActivity","-S","-a","android.intent.action.MAIN","-c","android.intent.category.LAUNCHER","-f","0x10200000"]
2018-01-25 08:41:13:243 - info: [Appium] New AndroidDriver session created successfully, session 57942e8e-503e-41a5-a9c2-1b990ee6366f added to master session list
2018-01-25 08:41:13:245 - info: [debug] [MJSONWP] Responding to client with driver.createSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":true,"warnings":{},"desired":{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true,"port":4723,"desiredCapabilities":{"platformName":"Android","deviceName":"Android","udid":"localhost:7401","appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","fullReset":false,"noReset":true,"noPermsCheck":true,"launch":true},"requestOrigins":{"url":"http://webdriver.io","version":"4.10.1","name":"webdriverio"}},"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"localhost:7401","udid":"localhost:7401","app...
2018-01-25 08:41:13:250 - info: [HTTP] <-- POST /wd/hub/session 200 11870 ms - 1674 
2018-01-25 08:42:13:245 - warn: [BaseDriver] Shutting down because we waited 60 seconds for a command
2018-01-25 08:42:13:254 - info: [debug] [AndroidDriver] Shutting down Android driver
2018-01-25 08:42:13:255 - warn: [Appium] Closing session, cause was 'New Command Timeout of 60 seconds expired. Try customizing the timeout using the 'newCommandTimeout' desired capability'
2018-01-25 08:42:13:256 - info: [Appium] Removing session 57942e8e-503e-41a5-a9c2-1b990ee6366f from our master session list
2018-01-25 08:42:13:257 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:42:13:263 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:42:13:263 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","am","force-stop","com.pointvoucher.playlondonpv"]
2018-01-25 08:42:16:651 - info: [debug] [ADB] Pressing the HOME button
2018-01-25 08:42:16:653 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:42:16:663 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:42:16:665 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","input","keyevent",3]
2018-01-25 08:42:18:862 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"shutdown"}
2018-01-25 08:42:18:871 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"shutdown"}
2018-01-25 08:42:18:878 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type SHUTDOWN
2018-01-25 08:42:18:901 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":"OK, shutting down"}
2018-01-25 08:42:18:902 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-01-25 08:42:18:905 - info: [debug] [UiAutomator] Shutting down UiAutomator
2018-01-25 08:42:18:905 - info: [debug] [UiAutomator] Moving to state 'stopping'
2018-01-25 08:42:18:908 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Closed client connection
2018-01-25 08:42:18:909 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: numtests=1
2018-01-25 08:42:18:909 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=.
2018-01-25 08:42:18:910 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2018-01-25 08:42:18:910 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2018-01-25 08:42:18:911 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2018-01-25 08:42:18:911 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: current=1
2018-01-25 08:42:18:912 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: 0
2018-01-25 08:42:18:913 - info: [debug] [UiAutomator] UiAutomator shut down normally
2018-01-25 08:42:18:913 - info: [debug] [UiAutomator] Moving to state 'stopped'
2018-01-25 08:42:18:914 - info: [debug] [ADB] Attempting to kill all uiautomator processes
2018-01-25 08:42:18:915 - info: [debug] [ADB] Getting all processes with uiautomator
2018-01-25 08:42:18:915 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:42:18:919 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:42:18:919 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","ps"]
2018-01-25 08:42:19:151 - info: [debug] [ADB] Attempting to kill process 8991
2018-01-25 08:42:19:152 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:42:19:161 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:42:19:162 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","kill",8991]
2018-01-25 08:42:19:188 - info: [debug] [UiAutomator] Moving to state 'stopped'
2018-01-25 08:42:19:188 - info: [debug] [ADB] Getting connected devices...
2018-01-25 08:42:19:197 - info: [debug] [ADB] 2 device(s) connected
2018-01-25 08:42:19:197 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7401","shell","am","force-stop","io.appium.unlock"]
2018-01-25 08:42:21:255 - info: [debug] [Logcat] Stopping logcat capture
2018-01-25 08:42:21:258 - info: [debug] [AndroidDriver] Not cleaning generated files. Add `clearSystemFiles` capability if wanted.