Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
$ ./wptdriver.sh --browser android:0cfe40ea1408275a --serverUrl http://localhost:80/ --location Nexus5 --processvideo yes -m debug
node --expose-gc src/agent_main --serverUrl http://localhost:80/ --location Nexus5 --browser browser_android_chrome.BrowserAndroidChrome --deviceSerial 0cfe40ea1408275a --chromedriver /home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-* --captureDir /home/vagrant/wpt/agent/js/lib/capture --processvideo yes
D Sep_11_08:00:22.662 browser_base.js:141 createBrowser : Creating browser browser_android_chrome.BrowserAndroidChrome
I Sep_11_08:00:22.675 browser_android_chrome.js:108 new BrowserAndroidChrome : BrowserAndroidChrome({"flags":{"serverUrl":"http://localhost:80/","location":"Nexus5","browser":"browser_android_chrome.BrowserAndroidChrome","deviceSerial":"0cfe40ea1408275a","chromedriver":"/home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-*","captureDir":"/home/vagrant/wpt/agent/js/lib/capture","processvideo":"yes"},"task":{}})
D Sep_11_08:00:22.692 process_utils.js:487 unknown : Calling Make dirs
D Sep_11_08:00:22.694 process_utils.js:481 cb : Callback for Make dirs
D Sep_11_08:00:22.705 process_utils.js:487 unknown : Calling Work read
D Sep_11_08:00:22.706 process_utils.js:481 cb : Callback for Work read
D Sep_11_08:00:22.763 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell date
D Sep_11_08:00:22.787 process_utils.js:263 unknown : Exec with timeout(10000): ./ipfw_config clear --device 0cfe40ea1408275a
D Sep_11_08:00:22.811 process_utils.js:327 ChildProcess.<anonymous> : stderr[164] +++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
+++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
W Sep_11_08:00:22.859 wpt_client.js:512 Client.<anonymous> : Agent is not ready: adb -s 0cfe40ea1408275a shell date failed, code 255, stderr[24] error: device not found
^CA Sep_11_08:00:24.840 wpt_client.js:406 Client.onSignal_ : Received SIGINT, exiting.
vagrant@precise32:~/wpt/agent/js$ ./wptdriver.sh --browser android:0cfe40ea1408275a --serverUrl http://localhost:80/ --location Nexus5 --processvideo yes -m debug
node --expose-gc src/agent_main --serverUrl http://localhost:80/ --location Nexus5 --browser browser_android_chrome.BrowserAndroidChrome --deviceSerial 0cfe40ea1408275a --chromedriver /home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-* --captureDir /home/vagrant/wpt/agent/js/lib/capture --processvideo yes
D Sep_11_09:17:16.940 browser_base.js:141 createBrowser : Creating browser browser_android_chrome.BrowserAndroidChrome
I Sep_11_09:17:16.953 browser_android_chrome.js:108 new BrowserAndroidChrome : BrowserAndroidChrome({"flags":{"serverUrl":"http://localhost:80/","location":"Nexus5","browser":"browser_android_chrome.BrowserAndroidChrome","deviceSerial":"0cfe40ea1408275a","chromedriver":"/home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-*","captureDir":"/home/vagrant/wpt/agent/js/lib/capture","processvideo":"yes"},"task":{}})
D Sep_11_09:17:16.972 process_utils.js:487 unknown : Calling Make dirs
D Sep_11_09:17:16.974 process_utils.js:481 cb : Callback for Make dirs
D Sep_11_09:17:16.984 process_utils.js:487 unknown : Calling Work read
D Sep_11_09:17:16.985 process_utils.js:481 cb : Callback for Work read
D Sep_11_09:17:17.044 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell date
D Sep_11_09:17:17.133 process_utils.js:327 ChildProcess.<anonymous> : stdout[30] Fri Sep 11 10:17:13 BST 2015
I Sep_11_09:17:17.148 wpt_client.js:490 Client.<anonymous> : Get work: http://localhost:80/work/getwork.php?location=Nexus5&pc=0cfe40ea1408275a&f=json
2D Sep_11_09:17:27.341 process_utils.js:327 ChildProcess.<anonymous> : stdout[30] Fri Sep 11 10:17:23 BST 2015
1
I Sep_11_09:17:27.376 wpt_client.js:558 Client.processJobResponse_ : Got job: {"Test ID":"150911_N4_1","url":"https:\/\/www.lostmy.name\/","runs":1,"browser":"Nexus5 - Chrome","orientation":"default"}
D Sep_11_09:17:27.378 wpt_client.js:602 Client.startNextRun_ : Setting watchdog timeout to 1800000ms
I Sep_11_09:17:27.390 agent_main.js:394 Agent.<anonymous> : Starting run 1a/1 of job 150911_N4_1
D Sep_11_09:17:27.418 process_utils.js:487 unknown : Calling Make dirs
D Sep_11_09:17:27.419 process_utils.js:481 cb : Callback for Make dirs
D Sep_11_09:17:27.431 process_utils.js:487 unknown : Calling Clean video dir
D Sep_11_09:17:27.432 process_utils.js:481 cb : Callback for Clean video dir
D Sep_11_09:17:27.442 process_utils.js:487 unknown : Calling Tmp read
D Sep_11_09:17:27.445 process_utils.js:481 cb : Callback for Tmp read
D Sep_11_09:17:27.468 process_utils.js:263 unknown : Exec with timeout(10000): ./ipfw_config clear --device 0cfe40ea1408275a
D Sep_11_09:17:27.489 process_utils.js:327 ChildProcess.<anonymous> : stderr[164] +++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
+++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
D Sep_11_09:17:27.729 browser_base.js:141 createBrowser : Creating browser browser_android_chrome.BrowserAndroidChrome
I Sep_11_09:17:27.743 browser_android_chrome.js:108 new BrowserAndroidChrome : BrowserAndroidChrome({"cmd":"run","runNumber":1,"isCacheWarm":false,"exitWhenDone":false,"timeout":120000,"runTempDir":"runtmp/0cfe40ea1408275a","workDir":"work/0cfe40ea1408275a","flags":{"captureDir":"/home/vagrant/wpt/agent/js/lib/capture","processvideo":"yes","serverUrl":"http://localhost:80/","browser":"browser_android_chrome.BrowserAndroidChrome","location":"Nexus5","deviceSerial":"0cfe40ea1408275a","chromedriver":"/home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-*"},"task":{"Test ID":"150911_N4_1","url":"https://www.lostmy.name/","runs":1,"browser":"Nexus5 - Chrome","orientation":"default","hostsFile":"127.0.0.1 localhost\n"}})
1.
D Sep_11_09:17:27.765 browser_base.js:98 BrowserAndroidChrome.BrowserBase.killChildProcessIfNeeded : Browser/driver process already unset, not killing
D Sep_11_09:17:27.803 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell ps
D Sep_11_09:17:27.925 process_utils.js:327 ChildProcess.<anonymous> : stdout[14892] USER PID PPID VSIZE RSS WCHAN PC NAME
root 1 0 8856 716 ffffffff 00000000 S /init
root 2 0 0 0 ffffffff 00000000 S kthreadd
root 3 2 0 0 ffffffff 00000000 S ksoftirqd/0
root 4 2 0 0 ffffffff 00000000 R kworker/0:0
root 5 2 0 0 ffffffff 00000000 S kworker/0:0H
root 6 2 0 0 ffffffff 00000000 S kworker/u:0
root 7 2 0 0 ffffffff 00000000 D kworker/u:0H
root 8 2 0 0 ffffffff 00000000 S migration/0
root 13 2 0 0 ffffffff 00000000 S khelper
root 14 2 0 0 ffffffff 00000000 S netns
root 15 2 0 0 ffffffff 00000000 S kworker/0:1
root 17 2 0 0 ffffffff 00000000 S kworker/0:1H
root 18 2 0 0 ffffffff 00000000 S modem_notifier
root 19 2 0 0 ffffffff 00000000 S smd_channel_clo
root 20 2...
D Sep_11_09:17:27.934 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.android.chrome
D Sep_11_09:17:28.622 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:28.625 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.chrome.beta
121
D Sep_11_09:17:29.700 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell dumpsys window windows
D Sep_11_09:17:29.827 process_utils.js:327 ChildProcess.<anonymous> : stdout[13578] WINDOW MANAGER WINDOWS (dumpsys window windows)
Window #6 Window{2c64f322 u0 SearchPanel}:
mDisplayId=0 mSession=Session{2c3454a4 920:u0a10022} mClient=android.os.BinderProxy@3b0dc8ed
mOwnerUid=10022 mShowToOwnerOnly=false package=com.android.systemui appop=NONE
mAttrs=WM.LayoutParams{(0,0)(fillxfill) gr=#800053 sim=#31 ty=2024 fl=#1820100 fmt=-3 surfaceInsets=Rect(0, 0 - 0, 0)}
Requested w=0 h=0 mLayoutSeq=39
mBaseLayer=211000 mSubLayer=0 mAnimLayer=211000+0=211000 mLastLayer=0
mToken=WindowToken{31827d75 null}
mRootToken=WindowToken{31827d75 null}
mViewVisibility=0x8 mHaveFrame=true mObscured=false
mSeq=0 mSystemUiVisibility=0x0
mGivenContentInsets=[0,0][0,0] mGivenVisibleInsets=[0,0][0,0]
mConfiguration=null
mHasSurface=false mShownFrame=[0.0,0.0][0.0,0.0] isReadyForDisplay()=false
mFrame=[0,0][1080,1920] last=[0,0][0,0]
mSystemDecorRect=[0,0][0,0] last=[0,0][0,0]
Frames: containing=[0,0][1080,1920] parent=[0,0][1080,1920]
...
D Sep_11_09:17:29.846 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell cat /etc/hosts
D Sep_11_09:17:29.900 process_utils.js:327 ChildProcess.<anonymous> : stdout[21] 127.0.0.1 localhost
D Sep_11_09:17:29.928 process_utils.js:487 unknown : Calling Write local flags file
D Sep_11_09:17:29.930 process_utils.js:481 cb : Callback for Write local flags file
D Sep_11_09:17:29.952 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell '[[ -w "$EXTERNAL_STORAGE" ]] && (touch "$EXTERNAL_STORAGE/adb_test" && rm "$EXTERNAL_STORAGE/adb_test") &>/dev/null && echo "$EXTERNAL_STORAGE"'
D Sep_11_09:17:30.030 process_utils.js:327 ChildProcess.<anonymous> : stdout[26] /storage/emulated/legacy
D Sep_11_09:17:30.031 adb.js:253 Adb.<anonymous> : Found storage path $EXTERNAL_STORAGE --> /storage/emulated/legacy
D Sep_11_09:17:30.045 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a push runtmp/0cfe40ea1408275a/wpt_chrome_command_line /storage/emulated/legacy/wpt_chrome_command_line
D Sep_11_09:17:30.190 process_utils.js:327 ChildProcess.<anonymous> : stderr[29] 6 KB/s (325 bytes in 0.047s)
D Sep_11_09:17:30.210 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'echo x'
D Sep_11_09:17:30.428 process_utils.js:327 ChildProcess.<anonymous> : stdout[3] x
D Sep_11_09:17:30.462 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'cp /storage/emulated/legacy/wpt_chrome_command_line /data/local/chrome-command-line'
D Sep_11_09:17:30.549 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:30.593 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell rm /storage/emulated/legacy/wpt_chrome_command_line
1
D Sep_11_09:17:30.704 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'chmod 666 /data/local/chrome-command-line'
2
D Sep_11_09:17:30.906 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'ls /data/data/com.android.chrome'
D Sep_11_09:17:30.985 process_utils.js:327 ChildProcess.<anonymous> : stdout[87] app_ChromeDocumentActivity
app_chrome
app_textures
cache
files
lib
shared_prefs
D Sep_11_09:17:31.041 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/app_ChromeDocumentActivity'
D Sep_11_09:17:31.120 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:31.198 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/app_chrome'
1
D Sep_11_09:17:31.374 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/app_textures'
2
D Sep_11_09:17:31.554 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/cache'
D Sep_11_09:17:31.685 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:31.755 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/files'
1
D Sep_11_09:17:32.004 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm /storage/emulated/legacy/Download/*'
D Sep_11_09:17:32.101 process_utils.js:327 ChildProcess.<anonymous> : stdout[68] rm: /storage/emulated/legacy/Download/*: No such file or directory
D Sep_11_09:17:32.223 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'service call notification 1'
D Sep_11_09:17:32.362 process_utils.js:327 ChildProcess.<anonymous> : stdout[36] Result: Parcel(00000000 '....')
D Sep_11_09:17:32.445 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'ndc resolver flushdefaultif'
D Sep_11_09:17:32.528 process_utils.js:327 ChildProcess.<anonymous> : stdout[32] 500 0 Resolver unknown command
D Sep_11_09:17:32.571 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am start -n com.android.chrome/com.google.android.apps.chrome.Main -d about:blank
D Sep_11_09:17:33.103 process_utils.js:327 ChildProcess.<anonymous> : stdout[97] Starting: Intent { dat=about:blank cmp=com.android.chrome/com.google.android.apps.chrome.Main }
D Sep_11_09:17:33.132 process_utils.js:592 Server.<anonymous> : Allocated port 14672
D Sep_11_09:17:33.133 browser_android_chrome.js:472 BrowserAndroidChrome.<anonymous> : Selected DevTools port 14672
D Sep_11_09:17:33.153 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a forward tcp:14672 localabstract:chrome_devtools_remote
D Sep_11_09:17:33.161 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:35.756 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell getprop ro.build.version.release
D Sep_11_09:17:35.827 process_utils.js:327 ChildProcess.<anonymous> : stdout[7] 5.1.1
D Sep_11_09:17:35.836 process_utils.js:487 unknown : Calling Network.clearBrowserCache
D Sep_11_09:17:35.836 devtools.js:226 DevTools.command_ : Send command: {"method":"Network.clearBrowserCache","id":1}
D Sep_11_09:17:35.857 devtools.js:168 DevTools.onMessage_ : Command response id: 1
D Sep_11_09:17:35.858 process_utils.js:481 Function.cb : Callback for Network.clearBrowserCache
D Sep_11_09:17:35.873 process_utils.js:487 unknown : Calling Network.clearBrowserCookies
D Sep_11_09:17:35.875 devtools.js:226 DevTools.command_ : Send command: {"method":"Network.clearBrowserCookies","id":2}
D Sep_11_09:17:35.896 devtools.js:168 DevTools.onMessage_ : Command response id: 2
D Sep_11_09:17:35.897 process_utils.js:481 Function.cb : Callback for Network.clearBrowserCookies
D Sep_11_09:17:35.919 process_utils.js:487 unknown : Calling Page.navigate
D Sep_11_09:17:35.920 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.navigate","params":{"url":"data:text/html;charset=utf-8,"},"id":3}
D Sep_11_09:17:35.942 devtools.js:168 DevTools.onMessage_ : Command response id: 3
D Sep_11_09:17:35.942 process_utils.js:481 Function.cb : Callback for Page.navigate
D Sep_11_09:17:36.447 process_utils.js:487 unknown : Calling Network.enable
D Sep_11_09:17:36.449 devtools.js:226 DevTools.command_ : Send command: {"method":"Network.enable","id":4}
D Sep_11_09:17:36.478 devtools.js:168 DevTools.onMessage_ : Command response id: 4
D Sep_11_09:17:36.479 process_utils.js:481 Function.cb : Callback for Network.enable
D Sep_11_09:17:36.483 process_utils.js:487 unknown : Calling Page.enable
D Sep_11_09:17:36.484 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.enable","id":5}
D Sep_11_09:17:36.505 devtools.js:168 DevTools.onMessage_ : Command response id: 5
D Sep_11_09:17:36.506 process_utils.js:481 Function.cb : Callback for Page.enable
D Sep_11_09:17:36.530 process_utils.js:487 unknown : Calling Tracing.start
D Sep_11_09:17:36.532 devtools.js:226 DevTools.command_ : Send command: {"method":"Tracing.start","params":{"categories":"-*,blink.console,disabled-by-default-devtools.timeline,devtools.timeline,disabled-by-default-devtools.timeline.frame,devtools.timeline.frame","options":"record-as-much-as-possible"},"id":6}
D Sep_11_09:17:36.550 devtools.js:168 DevTools.onMessage_ : Command response id: 6
D Sep_11_09:17:36.552 process_utils.js:481 Function.cb : Callback for Tracing.start
D Sep_11_09:17:36.552 wd_server.js:869 WebDriverServer.<anonymous> : Started tracing
D Sep_11_09:17:36.565 wd_server.js:967 WebDriverServer.<anonymous> : Waiting up to 120000ms for the page to load
I Sep_11_09:17:36.579 wd_server.js:385 WebDriverServer.<anonymous> : Test started
D Sep_11_09:17:36.592 process_utils.js:487 unknown : Calling Page.navigate
D Sep_11_09:17:36.594 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.navigate","params":{"url":"https://www.lostmy.name/"},"id":7}
D Sep_11_09:17:36.620 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9077.2"}}
D Sep_11_09:17:36.621 devtools.js:168 DevTools.onMessage_ : Command response id: 7
D Sep_11_09:17:36.622 process_utils.js:481 Function.cb : Callback for Page.navigate
D Sep_11_09:17:37.155 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9077.2","loaderId":"9077.4","url":"https://www.lostmy.name/","mimeType":"text/html","securityOrigin":"https://www.lostmy.name"}}}
D Sep_11_09:17:38.651 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameResized"}
D Sep_11_09:17:40.058 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.domContentEventFired","params":{"timestamp":425.861429}}
D Sep_11_09:17:41.437 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9077.32","parentFrameId":"9077.2"}}
D Sep_11_09:17:41.438 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9077.32"}}
D Sep_11_09:17:41.444 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9077.32","loaderId":"9077.33","url":"about:blank","mimeType":"text/html","securityOrigin":"https://www.lostmy.name","parentId":"9077.2","name":"fb_xdm_frame_https"}}}
D Sep_11_09:17:41.447 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9077.32"}}
D Sep_11_09:17:41.448 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameScheduledNavigation","params":{"frameId":"9077.32","delay":0}}
D Sep_11_09:17:41.495 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9077.32"}}
D Sep_11_09:17:41.496 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameClearedScheduledNavigation","params":{"frameId":"9077.32"}}
D Sep_11_09:17:41.822 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9077.32","loaderId":"9077.35","url":"https://s-static.ak.facebook.com/connect/xd_arbiter/44OwK74u0Ie.js?version=41","mimeType":"text/html","securityOrigin":"https://s-static.ak.facebook.com","parentId":"9077.2","name":"fb_xdm_frame_https"}}}
D Sep_11_09:17:41.910 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9077.32"}}
D Sep_11_09:17:43.733 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9077.38","parentFrameId":"9077.2"}}
D Sep_11_09:17:43.734 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9077.38"}}
I Sep_11_09:17:43.735 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.loadEventFired","params":{"timestamp":429.408426}}
I Sep_11_09:17:43.738 wd_server.js:1107 WebDriverServer.<anonymous> : Test finished, waiting for browser to coalesce
D Sep_11_09:17:43.827 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9077.38","loaderId":"9077.39","url":"https://www.facebook.com/connect/ping?client_id=111550222339737&domain=www.lostmy.name&origin=2&redirect_uri=https%3A%2F%2Fs-static.ak.facebook.com%2Fconnect%2Fxd_arbiter%2F44OwK74u0Ie.js%3Fversion%3D41%23cb%3Df279033164%26domain%3Dwww.lostmy.name%26origin%3Dhttps%253A%252F%252Fwww.lostmy.name%252Ff296acb15%26relation%3Dparent&response_type=token%2Csigned_request%2Ccode&sdk=joey","mimeType":"text/html","securityOrigin":"https://www.facebook.com","parentId":"9077.2","name":"f269d0c574"}}}
D Sep_11_09:17:44.324 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9077.38"}}
D Sep_11_09:17:44.986 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9077.2"}}
I Sep_11_09:17:53.786 wd_server.js:1323 WebDriverServer.<anonymous> : Test passed
D Sep_11_09:17:53.789 wd_server.js:1195 WebDriverServer.scheduleCollectMetrics_ : Collecting metrics
D Sep_11_09:17:53.792 wd_server.js:1346 WebDriverServer.<anonymous> : Done collecting results
D Sep_11_09:17:53.799 wd_server.js:1377 WebDriverServer.<anonymous> : Scheduling Stop
D Sep_11_09:17:53.837 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell screencap -p /storage/emulated/legacy/wpt_screenshot.png
D Sep_11_09:17:55.543 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:55.544 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a pull /storage/emulated/legacy/wpt_screenshot.png runtmp/0cfe40ea1408275a/screen.png
D Sep_11_09:17:57.291 process_utils.js:327 ChildProcess.<anonymous> : stderr[35] 596 KB/s (1004406 bytes in 1.645s)
D Sep_11_09:17:57.301 wd_server.js:448 WebDriverServer.addScreenshot_ : Adding screenshot screen.png (runtmp/0cfe40ea1408275a/screen.png): end of run
D Sep_11_09:17:57.314 process_utils.js:263 unknown : Exec with timeout(10000): convert runtmp/0cfe40ea1408275a/screen.png -set colorspace sRGB -resize '50%' -quality 30 runtmp/0cfe40ea1408275a/screen.jpg
D Sep_11_09:17:57.767 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:17:57.840 process_utils.js:487 unknown : Calling Tracing.end
D Sep_11_09:17:57.841 devtools.js:226 DevTools.command_ : Send command: {"method":"Tracing.end","id":8}
D Sep_11_09:17:57.860 devtools.js:168 DevTools.onMessage_ : Command response id: 8
D Sep_11_09:17:57.861 process_utils.js:481 Function.cb : Callback for Tracing.end
D Sep_11_09:18:01.080 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : #DevTools message: {"method":"Tracing.tracingComplete","params":{}}
D Sep_11_09:18:01.081 wd_server.js:890 WebDriverServer.onTracingMessage_ : Signalling finish of tracing
D Sep_11_09:18:01.218 process_utils.js:487 unknown : Calling Runtime.evaluate
D Sep_11_09:18:01.219 devtools.js:226 DevTools.command_ : Send command: {"method":"Runtime.evaluate","params":{"expression":"(function() {var marks = window.performance.getEntriesByType(\"mark\");var m = [];if (marks.length) { for (var i = 0; i < marks.length; i++) m.push({\"entryType\": marks[i].entryType, \"name\": marks[i].name, \"startTime\": marks[i].startTime});}return m;})();","returnByValue":true},"id":9}
D Sep_11_09:18:01.257 devtools.js:168 DevTools.onMessage_ : Command response id: 9
D Sep_11_09:18:01.258 process_utils.js:481 Function.cb : Callback for Runtime.evaluate
D Sep_11_09:18:01.287 process_utils.js:487 unknown : Calling Runtime.evaluate
D Sep_11_09:18:01.288 devtools.js:226 DevTools.command_ : Send command: {"method":"Runtime.evaluate","params":{"expression":"(function() {var pageData = {};var domCount = document.documentElement.getElementsByTagName(\"*\").length;if (domCount === undefined) domCount = 0;pageData[\"domElements\"] = domCount;function addTime(name) { if (window.performance.timing[name] > 0) { pageData[name] = Math.max(0, Math.round( window.performance.timing[name] - window.performance.timing[\"navigationStart\"])); }};addTime(\"domContentLoadedEventStart\");addTime(\"domContentLoadedEventEnd\");addTime(\"loadEventStart\");addTime(\"loadEventEnd\");pageData[\"firstPaint\"] = 0;if (window[\"chrome\"] !== undefined && window.chrome[\"loadTimes\"] !== undefined) { var chromeTimes = window.chrome.loadTimes(); if (chromeTimes[\"firstPaintTime\"] !== undefined && chromeTimes[\"firstPaintTime\"] > 0) { var startTime = chromeTimes[\"requestTime\"] ? chromeTimes[\"requestTime\"] : chromeTimes[\"startLoadTime\"]; if (chromeTimes[\"firstPaintTime\"] >= startTime) pageData[\"firstPaint\"] = Math.round( (chromeTimes[\"firstPaintTime\"] - startTime) * 1000.0); }}return pageData;})();","returnByValue":true},"id":10}
D Sep_11_09:18:01.307 devtools.js:168 DevTools.onMessage_ : Command response id: 10
D Sep_11_09:18:01.308 process_utils.js:481 Function.cb : Callback for Runtime.evaluate
D Sep_11_09:18:01.321 wd_server.js:1356 WebDriverServer.<anonymous> : Sending 'done' IPC
D Sep_11_09:18:01.324 agent_main.js:132 Agent.<anonymous> : got IPC: done
D Sep_11_09:18:01.339 agent_main.js:196 Agent.<anonymous> : Adding screenshot screen.jpg
D Sep_11_09:18:01.340 browser_base.js:98 BrowserAndroidChrome.BrowserBase.killChildProcessIfNeeded : Browser/driver process already unset, not killing
D Sep_11_09:18:01.342 browser_android_chrome.js:503 BrowserAndroidChrome.releaseDevToolsPortIfNeeded_ : Releasing DevTools port 14672
A Sep_11_09:18:01.357 wpt_client.js:659 Client.finishRun_ : Finished run 1a/1 of job 150911_N4_1
D Sep_11_09:18:01.361 wpt_client.js:821 Client.submitResult_ : submitResult_: job=150911_N4_1
D Sep_11_09:18:01.362 wpt_client.js:722 unknown : Adding 1_devtools.json (811482 bytes) to results zip
D Sep_11_09:18:01.363 wpt_client.js:722 unknown : Adding 1_trace.json (18 bytes) to results zip
D Sep_11_09:18:01.359 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a forward --remove tcp:14672
D Sep_11_09:18:01.365 wpt_client.js:722 unknown : Adding 1_page_data.json (145 bytes) to results zip
D Sep_11_09:18:01.366 wpt_client.js:722 unknown : Adding 1_images.json (54 bytes) to results zip
D Sep_11_09:18:01.373 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:01.375 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell ps
D Sep_11_09:18:01.520 process_utils.js:327 ChildProcess.<anonymous> : stdout[14305] USER PID PPID VSIZE RSS WCHAN PC NAME
root 1 0 8856 716 ffffffff 00000000 S /init
root 2 0 0 0 ffffffff 00000000 S kthreadd
root 3 2 0 0 ffffffff 00000000 S ksoftirqd/0
root 4 2 0 0 ffffffff 00000000 S kworker/0:0
root 5 2 0 0 ffffffff 00000000 S kworker/0:0H
root 6 2 0 0 ffffffff 00000000 S kworker/u:0
root 7 2 0 0 ffffffff 00000000 D kworker/u:0H
root 8 2 0 0 ffffffff 00000000 S migration/0
root 13 2 0 0 ffffffff 00000000 S khelper
root 14 2 0 0 ffffffff 00000000 S netns
root 15 2 0 0 ffffffff 00000000 S kworker/0:1
root 17 2 0 0 ffffffff 00000000 S kworker/0:1H
root 18 2 0 0 ffffffff 00000000 S modem_notifier
root 19 2 0 0 ffffffff 00000000 S smd_channel_clo
root 20 2...
D Sep_11_09:18:01.534 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.android.chrome
D Sep_11_09:18:01.907 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=id body=150911_N4_1 headers=["Content-Type: text/plain"]
D Sep_11_09:18:01.908 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=location body=Nexus5 headers=undefined
D Sep_11_09:18:01.909 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=timeline body=1 headers=undefined
D Sep_11_09:18:01.910 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=pc body=0cfe40ea1408275a headers=undefined
D Sep_11_09:18:01.911 multipart.js:115 Multipart.exports.Multipart.addFilePart : addFilePart: name=file filename=1_screen.jpg size=26408
D Sep_11_09:18:01.923 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=id body=150911_N4_1 headers=["Content-Type: text/plain"]
D Sep_11_09:18:01.924 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=location body=Nexus5 headers=undefined
D Sep_11_09:18:01.924 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=timeline body=1 headers=undefined
D Sep_11_09:18:01.925 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=pc body=0cfe40ea1408275a headers=undefined
D Sep_11_09:18:01.926 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=_runNumber body=1 headers=undefined
D Sep_11_09:18:01.926 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=_cacheWarmed body=0 headers=undefined
D Sep_11_09:18:01.927 multipart.js:115 Multipart.exports.Multipart.addFilePart : addFilePart: name=file filename=1_results.zip size=231150
D Sep_11_09:18:02.094 wpt_client.js:602 Client.startNextRun_ : Setting watchdog timeout to 1800000ms
I Sep_11_09:18:02.107 agent_main.js:394 Agent.<anonymous> : Starting run 1b/1 of job 150911_N4_1
D Sep_11_09:18:02.133 process_utils.js:487 unknown : Calling Make dirs
D Sep_11_09:18:02.134 process_utils.js:481 cb : Callback for Make dirs
D Sep_11_09:18:02.144 process_utils.js:487 unknown : Calling Clean video dir
D Sep_11_09:18:02.146 process_utils.js:481 cb : Callback for Clean video dir
D Sep_11_09:18:02.156 process_utils.js:487 unknown : Calling Tmp read
D Sep_11_09:18:02.158 process_utils.js:481 cb : Callback for Tmp read
D Sep_11_09:18:02.178 process_utils.js:263 unknown : Exec with timeout(10000): ./ipfw_config clear --device 0cfe40ea1408275a
D Sep_11_09:18:02.207 process_utils.js:327 ChildProcess.<anonymous> : stderr[164] +++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
+++ /sbin/ipfw list
./ipfw_config: line 47: /sbin/ipfw: No such file or directory
D Sep_11_09:18:02.211 browser_base.js:141 createBrowser : Creating browser browser_android_chrome.BrowserAndroidChrome
I Sep_11_09:18:02.213 browser_android_chrome.js:108 new BrowserAndroidChrome : BrowserAndroidChrome({"cmd":"run","runNumber":1,"isCacheWarm":true,"exitWhenDone":true,"timeout":120000,"runTempDir":"runtmp/0cfe40ea1408275a","workDir":"work/0cfe40ea1408275a","flags":{"captureDir":"/home/vagrant/wpt/agent/js/lib/capture","processvideo":"yes","serverUrl":"http://localhost:80/","browser":"browser_android_chrome.BrowserAndroidChrome","location":"Nexus5","deviceSerial":"0cfe40ea1408275a","chromedriver":"/home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-*"},"task":{"Test ID":"150911_N4_1","url":"https://www.lostmy.name/","runs":1,"browser":"Nexus5 - Chrome","orientation":"default","hostsFile":"127.0.0.1 localhost\n"}})
1.
D Sep_11_09:18:02.217 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:02.218 browser_base.js:98 BrowserAndroidChrome.BrowserBase.killChildProcessIfNeeded : Browser/driver process already unset, not killing
D Sep_11_09:18:02.241 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell ps
D Sep_11_09:18:02.367 process_utils.js:327 ChildProcess.<anonymous> : stdout[15335] USER PID PPID VSIZE RSS WCHAN PC NAME
root 1 0 8856 716 ffffffff 00000000 S /init
root 2 0 0 0 ffffffff 00000000 S kthreadd
root 3 2 0 0 ffffffff 00000000 S ksoftirqd/0
root 4 2 0 0 ffffffff 00000000 S kworker/0:0
root 5 2 0 0 ffffffff 00000000 S kworker/0:0H
root 6 2 0 0 ffffffff 00000000 S kworker/u:0
root 7 2 0 0 ffffffff 00000000 D kworker/u:0H
root 8 2 0 0 ffffffff 00000000 S migration/0
root 13 2 0 0 ffffffff 00000000 S khelper
root 14 2 0 0 ffffffff 00000000 S netns
root 15 2 0 0 ffffffff 00000000 S kworker/0:1
root 17 2 0 0 ffffffff 00000000 S kworker/0:1H
root 18 2 0 0 ffffffff 00000000 S modem_notifier
root 19 2 0 0 ffffffff 00000000 S smd_channel_clo
root 20 2...
D Sep_11_09:18:02.377 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.android.chrome
D Sep_11_09:18:02.938 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:02.945 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell dumpsys window windows
D Sep_11_09:18:03.048 process_utils.js:327 ChildProcess.<anonymous> : stdout[13436] WINDOW MANAGER WINDOWS (dumpsys window windows)
Window #6 Window{2c64f322 u0 SearchPanel}:
mDisplayId=0 mSession=Session{2c3454a4 920:u0a10022} mClient=android.os.BinderProxy@3b0dc8ed
mOwnerUid=10022 mShowToOwnerOnly=false package=com.android.systemui appop=NONE
mAttrs=WM.LayoutParams{(0,0)(fillxfill) gr=#800053 sim=#31 ty=2024 fl=#1820100 fmt=-3 surfaceInsets=Rect(0, 0 - 0, 0)}
Requested w=0 h=0 mLayoutSeq=39
mBaseLayer=211000 mSubLayer=0 mAnimLayer=211000+0=211000 mLastLayer=0
mToken=WindowToken{31827d75 null}
mRootToken=WindowToken{31827d75 null}
mViewVisibility=0x8 mHaveFrame=true mObscured=false
mSeq=0 mSystemUiVisibility=0x0
mGivenContentInsets=[0,0][0,0] mGivenVisibleInsets=[0,0][0,0]
mConfiguration=null
mHasSurface=false mShownFrame=[0.0,0.0][0.0,0.0] isReadyForDisplay()=false
mFrame=[0,0][1080,1920] last=[0,0][0,0]
mSystemDecorRect=[0,0][0,0] last=[0,0][0,0]
Frames: containing=[0,0][1080,1920] parent=[0,0][1080,1920]
...
D Sep_11_09:18:03.063 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell cat /etc/hosts
D Sep_11_09:18:03.127 process_utils.js:327 ChildProcess.<anonymous> : stdout[21] 127.0.0.1 localhost
D Sep_11_09:18:03.155 process_utils.js:487 unknown : Calling Write local flags file
D Sep_11_09:18:03.157 process_utils.js:481 cb : Callback for Write local flags file
D Sep_11_09:18:03.179 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell '[[ -w "$EXTERNAL_STORAGE" ]] && (touch "$EXTERNAL_STORAGE/adb_test" && rm "$EXTERNAL_STORAGE/adb_test") &>/dev/null && echo "$EXTERNAL_STORAGE"'
D Sep_11_09:18:03.261 process_utils.js:327 ChildProcess.<anonymous> : stdout[26] /storage/emulated/legacy
D Sep_11_09:18:03.262 adb.js:253 Adb.<anonymous> : Found storage path $EXTERNAL_STORAGE --> /storage/emulated/legacy
D Sep_11_09:18:03.279 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a push runtmp/0cfe40ea1408275a/wpt_chrome_command_line /storage/emulated/legacy/wpt_chrome_command_line
D Sep_11_09:18:03.436 process_utils.js:327 ChildProcess.<anonymous> : stderr[29] 6 KB/s (325 bytes in 0.050s)
D Sep_11_09:18:03.448 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'echo x'
D Sep_11_09:18:03.540 process_utils.js:327 ChildProcess.<anonymous> : stdout[3] x
D Sep_11_09:18:03.569 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'cp /storage/emulated/legacy/wpt_chrome_command_line /data/local/chrome-command-line'
D Sep_11_09:18:03.667 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:03.701 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell rm /storage/emulated/legacy/wpt_chrome_command_line
1
D Sep_11_09:18:03.803 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'chmod 666 /data/local/chrome-command-line'
2
D Sep_11_09:18:04.014 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm -r /data/data/com.android.chrome/app_tabs'
D Sep_11_09:18:04.104 process_utils.js:327 ChildProcess.<anonymous> : stdout[71] rm: /data/data/com.android.chrome/app_tabs: No such file or directory
D Sep_11_09:18:04.206 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'rm /storage/emulated/legacy/Download/*'
D Sep_11_09:18:04.322 process_utils.js:327 ChildProcess.<anonymous> : stdout[68] rm: /storage/emulated/legacy/Download/*: No such file or directory
D Sep_11_09:18:04.425 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'service call notification 1'
D Sep_11_09:18:04.526 process_utils.js:327 ChildProcess.<anonymous> : stdout[36] Result: Parcel(00000000 '....')
D Sep_11_09:18:04.609 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell su -c 'ndc resolver flushdefaultif'
D Sep_11_09:18:04.720 process_utils.js:327 ChildProcess.<anonymous> : stdout[32] 500 0 Resolver unknown command
D Sep_11_09:18:04.763 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am start -n com.android.chrome/com.google.android.apps.chrome.Main -d about:blank
D Sep_11_09:18:05.275 process_utils.js:327 ChildProcess.<anonymous> : stdout[97] Starting: Intent { dat=about:blank cmp=com.android.chrome/com.google.android.apps.chrome.Main }
D Sep_11_09:18:05.294 process_utils.js:592 Server.<anonymous> : Allocated port 32666
D Sep_11_09:18:05.295 browser_android_chrome.js:472 BrowserAndroidChrome.<anonymous> : Selected DevTools port 32666
D Sep_11_09:18:05.316 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a forward tcp:32666 localabstract:chrome_devtools_remote
D Sep_11_09:18:05.320 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:07.746 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell getprop ro.build.version.release
D Sep_11_09:18:07.817 process_utils.js:327 ChildProcess.<anonymous> : stdout[7] 5.1.1
D Sep_11_09:18:07.824 process_utils.js:487 unknown : Calling Page.navigate
D Sep_11_09:18:07.825 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.navigate","params":{"url":"data:text/html;charset=utf-8,"},"id":1}
D Sep_11_09:18:07.848 devtools.js:168 DevTools.onMessage_ : Command response id: 1
D Sep_11_09:18:07.849 process_utils.js:481 Function.cb : Callback for Page.navigate
D Sep_11_09:18:08.363 process_utils.js:487 unknown : Calling Network.enable
D Sep_11_09:18:08.365 devtools.js:226 DevTools.command_ : Send command: {"method":"Network.enable","id":2}
D Sep_11_09:18:08.384 devtools.js:168 DevTools.onMessage_ : Command response id: 2
D Sep_11_09:18:08.385 process_utils.js:481 Function.cb : Callback for Network.enable
D Sep_11_09:18:08.386 process_utils.js:487 unknown : Calling Page.enable
D Sep_11_09:18:08.387 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.enable","id":3}
D Sep_11_09:18:08.405 devtools.js:168 DevTools.onMessage_ : Command response id: 3
D Sep_11_09:18:08.407 process_utils.js:481 Function.cb : Callback for Page.enable
D Sep_11_09:18:08.433 process_utils.js:487 unknown : Calling Tracing.start
D Sep_11_09:18:08.434 devtools.js:226 DevTools.command_ : Send command: {"method":"Tracing.start","params":{"categories":"-*,blink.console,disabled-by-default-devtools.timeline,devtools.timeline,disabled-by-default-devtools.timeline.frame,devtools.timeline.frame","options":"record-as-much-as-possible"},"id":4}
D Sep_11_09:18:08.482 devtools.js:168 DevTools.onMessage_ : Command response id: 4
D Sep_11_09:18:08.483 process_utils.js:481 Function.cb : Callback for Tracing.start
D Sep_11_09:18:08.484 wd_server.js:869 WebDriverServer.<anonymous> : Started tracing
D Sep_11_09:18:08.498 wd_server.js:967 WebDriverServer.<anonymous> : Waiting up to 120000ms for the page to load
I Sep_11_09:18:08.510 wd_server.js:385 WebDriverServer.<anonymous> : Test started
D Sep_11_09:18:08.522 process_utils.js:487 unknown : Calling Page.navigate
D Sep_11_09:18:08.523 devtools.js:226 DevTools.command_ : Send command: {"method":"Page.navigate","params":{"url":"https://www.lostmy.name/"},"id":5}
D Sep_11_09:18:08.643 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.2"}}
D Sep_11_09:18:08.649 devtools.js:168 DevTools.onMessage_ : Command response id: 5
D Sep_11_09:18:08.650 process_utils.js:481 Function.cb : Callback for Page.navigate
D Sep_11_09:18:09.190 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.2","loaderId":"9868.4","url":"https://www.lostmy.name/","mimeType":"text/html","securityOrigin":"https://www.lostmy.name"}}}
D Sep_11_09:18:09.874 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameResized"}
D Sep_11_09:18:11.707 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.domContentEventFired","params":{"timestamp":457.516473}}
D Sep_11_09:18:11.772 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9868.34","parentFrameId":"9868.2"}}
D Sep_11_09:18:11.777 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.34"}}
D Sep_11_09:18:11.778 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.34","loaderId":"9868.35","url":"about:blank","mimeType":"text/html","securityOrigin":"https://www.lostmy.name","parentId":"9868.2","name":"fb_xdm_frame_https"}}}
D Sep_11_09:18:11.779 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.34"}}
D Sep_11_09:18:11.780 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameScheduledNavigation","params":{"frameId":"9868.34","delay":0}}
D Sep_11_09:18:11.780 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9868.36","parentFrameId":"9868.2"}}
D Sep_11_09:18:11.781 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.36"}}
D Sep_11_09:18:11.782 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.36","loaderId":"9868.37","url":"about:blank","mimeType":"text/html","securityOrigin":"https://www.lostmy.name","parentId":"9868.2","name":"f24ecd534"}}}
D Sep_11_09:18:11.783 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.36"}}
D Sep_11_09:18:11.783 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameScheduledNavigation","params":{"frameId":"9868.36","delay":0}}
D Sep_11_09:18:12.168 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.34"}}
D Sep_11_09:18:12.169 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameClearedScheduledNavigation","params":{"frameId":"9868.34"}}
D Sep_11_09:18:12.173 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.36"}}
D Sep_11_09:18:12.174 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameClearedScheduledNavigation","params":{"frameId":"9868.36"}}
D Sep_11_09:18:12.370 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.34","loaderId":"9868.40","url":"https://s-static.ak.facebook.com/connect/xd_arbiter/44OwK74u0Ie.js?version=41","mimeType":"text/html","securityOrigin":"https://s-static.ak.facebook.com","parentId":"9868.2","name":"fb_xdm_frame_https"}}}
D Sep_11_09:18:12.429 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.36","loaderId":"9868.41","url":"https://www.facebook.com/connect/ping?client_id=111550222339737&domain=www.lostmy.name&origin=2&redirect_uri=https%3A%2F%2Fs-static.ak.facebook.com%2Fconnect%2Fxd_arbiter%2F44OwK74u0Ie.js%3Fversion%3D41%23cb%3Df49aa0f24%26domain%3Dwww.lostmy.name%26origin%3Dhttps%253A%252F%252Fwww.lostmy.name%252Ff1513424b8%26relation%3Dparent&response_type=token%2Csigned_request%2Ccode&sdk=joey","mimeType":"text/html","securityOrigin":"https://www.facebook.com","parentId":"9868.2","name":"f24ecd534"}}}
D Sep_11_09:18:12.855 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.34"}}
D Sep_11_09:18:12.884 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.36"}}
I Sep_11_09:18:14.581 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.loadEventFired","params":{"timestamp":460.297341}}
D Sep_11_09:18:14.582 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.2"}}
I Sep_11_09:18:14.585 wd_server.js:1107 WebDriverServer.<anonymous> : Test finished, waiting for browser to coalesce
D Sep_11_09:18:15.107 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9868.67","parentFrameId":"9868.2"}}
D Sep_11_09:18:15.114 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.67"}}
D Sep_11_09:18:15.167 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.67","loaderId":"9868.68","url":"https://www.youtube.com/embed/fb9-hU8Yk-Y?autohide=2&showinfo=0&rel=0&enablejsapi=1&origin=https%3A%2F%2Fwww.lostmy.name","mimeType":"text/html","securityOrigin":"https://www.youtube.com","parentId":"9868.2","name":"amber"}}}
W Sep_11_09:18:16.334 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.111","timestamp":462.141561,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
W Sep_11_09:18:16.705 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.114","timestamp":462.507009,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
W Sep_11_09:18:16.782 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.118","timestamp":462.574604,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
W Sep_11_09:18:16.845 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.119","timestamp":462.653006,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
W Sep_11_09:18:16.854 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.120","timestamp":462.663463,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
D Sep_11_09:18:17.072 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameAttached","params":{"frameId":"9868.83","parentFrameId":"9868.67"}}
D Sep_11_09:18:17.073 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStartedLoading","params":{"frameId":"9868.83"}}
D Sep_11_09:18:17.074 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameNavigated","params":{"frame":{"id":"9868.83","loaderId":"9868.84","url":"about:blank","mimeType":"text/html","securityOrigin":"https://www.youtube.com","parentId":"9868.67","name":""}}}
D Sep_11_09:18:17.075 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.83"}}
D Sep_11_09:18:17.076 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameDetached","params":{"frameId":"9868.83"}}
W Sep_11_09:18:17.077 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Network.loadingFailed","params":{"requestId":"9868.121","timestamp":462.884215,"type":"XHR","errorText":"net::ERR_UNKNOWN_URL_SCHEME"}}
D Sep_11_09:18:17.100 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : DevTools message: {"method":"Page.frameStoppedLoading","params":{"frameId":"9868.67"}}
I Sep_11_09:18:24.627 wd_server.js:1323 WebDriverServer.<anonymous> : Test passed
D Sep_11_09:18:24.630 wd_server.js:1195 WebDriverServer.scheduleCollectMetrics_ : Collecting metrics
D Sep_11_09:18:24.633 wd_server.js:1346 WebDriverServer.<anonymous> : Done collecting results
D Sep_11_09:18:24.639 wd_server.js:1377 WebDriverServer.<anonymous> : Scheduling Stop
D Sep_11_09:18:24.640 wd_server.js:1381 WebDriverServer.<anonymous> : Disconnecting IPC
D Sep_11_09:18:24.677 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell screencap -p /storage/emulated/legacy/wpt_screenshot.png
D Sep_11_09:18:26.394 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:26.400 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a pull /storage/emulated/legacy/wpt_screenshot.png runtmp/0cfe40ea1408275a/screen.png
D Sep_11_09:18:28.200 process_utils.js:327 ChildProcess.<anonymous> : stderr[35] 579 KB/s (1009233 bytes in 1.702s)
D Sep_11_09:18:28.210 wd_server.js:448 WebDriverServer.addScreenshot_ : Adding screenshot screen.png (runtmp/0cfe40ea1408275a/screen.png): end of run
D Sep_11_09:18:28.223 process_utils.js:263 unknown : Exec with timeout(10000): convert runtmp/0cfe40ea1408275a/screen.png -set colorspace sRGB -resize '50%' -quality 30 runtmp/0cfe40ea1408275a/screen.jpg
D Sep_11_09:18:28.689 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:28.769 process_utils.js:487 unknown : Calling Tracing.end
D Sep_11_09:18:28.769 devtools.js:226 DevTools.command_ : Send command: {"method":"Tracing.end","id":6}
D Sep_11_09:18:32.040 devtools.js:168 DevTools.onMessage_ : Command response id: 6
D Sep_11_09:18:32.041 process_utils.js:481 Function.cb : Callback for Tracing.end
D Sep_11_09:18:32.088 wd_server.js:341 WebDriverServer.onDevToolsMessage_ : #DevTools message: {"method":"Tracing.tracingComplete","params":{}}
D Sep_11_09:18:32.089 wd_server.js:890 WebDriverServer.onTracingMessage_ : Signalling finish of tracing
D Sep_11_09:18:32.183 process_utils.js:487 unknown : Calling Runtime.evaluate
D Sep_11_09:18:32.185 devtools.js:226 DevTools.command_ : Send command: {"method":"Runtime.evaluate","params":{"expression":"(function() {var marks = window.performance.getEntriesByType(\"mark\");var m = [];if (marks.length) { for (var i = 0; i < marks.length; i++) m.push({\"entryType\": marks[i].entryType, \"name\": marks[i].name, \"startTime\": marks[i].startTime});}return m;})();","returnByValue":true},"id":7}
D Sep_11_09:18:32.273 devtools.js:168 DevTools.onMessage_ : Command response id: 7
D Sep_11_09:18:32.274 process_utils.js:481 Function.cb : Callback for Runtime.evaluate
D Sep_11_09:18:32.305 process_utils.js:487 unknown : Calling Runtime.evaluate
D Sep_11_09:18:32.306 devtools.js:226 DevTools.command_ : Send command: {"method":"Runtime.evaluate","params":{"expression":"(function() {var pageData = {};var domCount = document.documentElement.getElementsByTagName(\"*\").length;if (domCount === undefined) domCount = 0;pageData[\"domElements\"] = domCount;function addTime(name) { if (window.performance.timing[name] > 0) { pageData[name] = Math.max(0, Math.round( window.performance.timing[name] - window.performance.timing[\"navigationStart\"])); }};addTime(\"domContentLoadedEventStart\");addTime(\"domContentLoadedEventEnd\");addTime(\"loadEventStart\");addTime(\"loadEventEnd\");pageData[\"firstPaint\"] = 0;if (window[\"chrome\"] !== undefined && window.chrome[\"loadTimes\"] !== undefined) { var chromeTimes = window.chrome.loadTimes(); if (chromeTimes[\"firstPaintTime\"] !== undefined && chromeTimes[\"firstPaintTime\"] > 0) { var startTime = chromeTimes[\"requestTime\"] ? chromeTimes[\"requestTime\"] : chromeTimes[\"startLoadTime\"]; if (chromeTimes[\"firstPaintTime\"] >= startTime) pageData[\"firstPaint\"] = Math.round( (chromeTimes[\"firstPaintTime\"] - startTime) * 1000.0); }}return pageData;})();","returnByValue":true},"id":8}
D Sep_11_09:18:32.333 devtools.js:168 DevTools.onMessage_ : Command response id: 8
D Sep_11_09:18:32.333 process_utils.js:481 Function.cb : Callback for Runtime.evaluate
D Sep_11_09:18:32.351 wd_server.js:1356 WebDriverServer.<anonymous> : Sending 'done' IPC
D Sep_11_09:18:32.353 agent_main.js:132 Agent.<anonymous> : got IPC: done
D Sep_11_09:18:32.367 agent_main.js:196 Agent.<anonymous> : Adding screenshot screen.jpg
D Sep_11_09:18:32.365 browser_base.js:98 BrowserAndroidChrome.BrowserBase.killChildProcessIfNeeded : Browser/driver process already unset, not killing
D Sep_11_09:18:32.368 browser_android_chrome.js:503 BrowserAndroidChrome.releaseDevToolsPortIfNeeded_ : Releasing DevTools port 32666
D Sep_11_09:18:32.383 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a forward --remove tcp:32666
D Sep_11_09:18:32.392 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:32.396 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell ps
D Sep_11_09:18:32.540 process_utils.js:327 ChildProcess.<anonymous> : stdout[14303] USER PID PPID VSIZE RSS WCHAN PC NAME
root 1 0 8856 716 ffffffff 00000000 S /init
root 2 0 0 0 ffffffff 00000000 S kthreadd
root 3 2 0 0 ffffffff 00000000 S ksoftirqd/0
root 4 2 0 0 ffffffff 00000000 S kworker/0:0
root 5 2 0 0 ffffffff 00000000 S kworker/0:0H
root 6 2 0 0 ffffffff 00000000 S kworker/u:0
root 7 2 0 0 ffffffff 00000000 D kworker/u:0H
root 8 2 0 0 ffffffff 00000000 S migration/0
root 13 2 0 0 ffffffff 00000000 S khelper
root 14 2 0 0 ffffffff 00000000 S netns
root 15 2 0 0 ffffffff 00000000 S kworker/0:1
root 17 2 0 0 ffffffff 00000000 S kworker/0:1H
root 18 2 0 0 ffffffff 00000000 S modem_notifier
root 19 2 0 0 ffffffff 00000000 S smd_channel_clo
root 20 2...
D Sep_11_09:18:32.548 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.android.chrome
D Sep_11_09:18:33.280 process_utils.js:327 ChildProcess.<anonymous>
1.
D Sep_11_09:18:33.865 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell dumpsys window windows
D Sep_11_09:18:33.976 process_utils.js:327 ChildProcess.<anonymous> : stdout[13436] WINDOW MANAGER WINDOWS (dumpsys window windows)
Window #6 Window{2c64f322 u0 SearchPanel}:
mDisplayId=0 mSession=Session{2c3454a4 920:u0a10022} mClient=android.os.BinderProxy@3b0dc8ed
mOwnerUid=10022 mShowToOwnerOnly=false package=com.android.systemui appop=NONE
mAttrs=WM.LayoutParams{(0,0)(fillxfill) gr=#800053 sim=#31 ty=2024 fl=#1820100 fmt=-3 surfaceInsets=Rect(0, 0 - 0, 0)}
Requested w=0 h=0 mLayoutSeq=39
mBaseLayer=211000 mSubLayer=0 mAnimLayer=211000+0=211000 mLastLayer=0
mToken=WindowToken{31827d75 null}
mRootToken=WindowToken{31827d75 null}
mViewVisibility=0x8 mHaveFrame=true mObscured=false
mSeq=0 mSystemUiVisibility=0x0
mGivenContentInsets=[0,0][0,0] mGivenVisibleInsets=[0,0][0,0]
mConfiguration=null
mHasSurface=false mShownFrame=[0.0,0.0][0.0,0.0] isReadyForDisplay()=false
mFrame=[0,0][1080,1920] last=[0,0][0,0]
mSystemDecorRect=[0,0][0,0] last=[0,0][0,0]
Frames: containing=[0,0][1080,1920] parent=[0,0][1080,1920]
...
D Sep_11_09:18:34.067 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell am force-stop com.android.chrome
D Sep_11_09:18:34.593 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:34.600 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell dumpsys window windows
D Sep_11_09:18:34.690 process_utils.js:327 ChildProcess.<anonymous> : stdout[13436] WINDOW MANAGER WINDOWS (dumpsys window windows)
Window #6 Window{2c64f322 u0 SearchPanel}:
mDisplayId=0 mSession=Session{2c3454a4 920:u0a10022} mClient=android.os.BinderProxy@3b0dc8ed
mOwnerUid=10022 mShowToOwnerOnly=false package=com.android.systemui appop=NONE
mAttrs=WM.LayoutParams{(0,0)(fillxfill) gr=#800053 sim=#31 ty=2024 fl=#1820100 fmt=-3 surfaceInsets=Rect(0, 0 - 0, 0)}
Requested w=0 h=0 mLayoutSeq=39
mBaseLayer=211000 mSubLayer=0 mAnimLayer=211000+0=211000 mLastLayer=0
mToken=WindowToken{31827d75 null}
mRootToken=WindowToken{31827d75 null}
mViewVisibility=0x8 mHaveFrame=true mObscured=false
mSeq=0 mSystemUiVisibility=0x0
mGivenContentInsets=[0,0][0,0] mGivenVisibleInsets=[0,0][0,0]
mConfiguration=null
mHasSurface=false mShownFrame=[0.0,0.0][0.0,0.0] isReadyForDisplay()=false
mFrame=[0,0][1080,1920] last=[0,0][0,0]
mSystemDecorRect=[0,0][0,0] last=[0,0][0,0]
Frames: containing=[0,0][1080,1920] parent=[0,0][1080,1920]
...
D Sep_11_09:18:34.751 wd_server.js:1059 WebDriverServer.<anonymous> : The main control flow has gone idle, history: []
.D Sep_11_09:18:37.389 process_utils.js:263 unknown : Exec with timeout(10000): ps -u 1000 -o 'ppid=' -o 'pid=' -o 'command='
D Sep_11_09:18:37.396 process_utils.js:327 ChildProcess.<anonymous> : stdout[543] 20404 20419 sshd: vagrant@pts/0
20419 20420 -bash
20420 20799 node --expose-gc src/agent_main --serverUrl http://localhost:80/ --location Nexus5 --browser browser_android_chrome.BrowserAndroidChrome --deviceSerial 0cfe40ea1408275a --chromedriver /home/vagrant/wpt/agent/js/lib/webdriver/chromedriver/Linux i686/chromedriver-* --captureDir /home/vagrant/wpt/agent/js/lib/capture --processvideo yes
20799 20856 /home/vagrant/.nvm/versions/node/v0.12.7/bin/node --expose-gc ./src/wd_server.js
20799 20964 ps -u 1000 -o ppid= -o pid= -o command=
D Sep_11_09:18:37.403 process_utils.js:89 unknown : Killing 20856: /home/vagrant/.nvm/versions/node/v0.12.7/bin/node --expose-gc ./src/wd_server.js
D Sep_11_09:18:37.416 process_utils.js:263 unknown : Exec with timeout(10000): kill -9 20856
I Sep_11_09:18:37.422 agent_main.js:149 Agent.<anonymous> : wd_server child process exit code null signal SIGKILL
D Sep_11_09:18:37.423 process_utils.js:327 ChildProcess.<anonymous>
D Sep_11_09:18:37.521 process_utils.js:487 unknown : Calling Make dirs
D Sep_11_09:18:37.523 process_utils.js:481 cb : Callback for Make dirs
D Sep_11_09:18:37.533 process_utils.js:487 unknown : Calling Clean video dir
D Sep_11_09:18:37.534 process_utils.js:481 cb : Callback for Clean video dir
D Sep_11_09:18:37.548 process_utils.js:487 unknown : Calling Tmp read
D Sep_11_09:18:37.550 process_utils.js:481 cb : Callback for Tmp read
A Sep_11_09:18:37.573 wpt_client.js:659 Client.finishRun_ : Finished run 1b/1 of finished job 150911_N4_1
D Sep_11_09:18:37.574 wpt_client.js:821 Client.submitResult_ : submitResult_: job=150911_N4_1
D Sep_11_09:18:37.575 wpt_client.js:722 unknown : Adding 1_Cached_devtools.json (683102 bytes) to results zip
D Sep_11_09:18:37.576 wpt_client.js:722 unknown : Adding 1_Cached_trace.json (18 bytes) to results zip
D Sep_11_09:18:37.577 wpt_client.js:722 unknown : Adding 1_Cached_page_data.json (145 bytes) to results zip
D Sep_11_09:18:37.578 wpt_client.js:722 unknown : Adding 1_Cached_images.json (54 bytes) to results zip
D Sep_11_09:18:37.892 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=id body=150911_N4_1 headers=["Content-Type: text/plain"]
D Sep_11_09:18:37.894 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=location body=Nexus5 headers=undefined
D Sep_11_09:18:37.894 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=timeline body=1 headers=undefined
D Sep_11_09:18:37.895 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=pc body=0cfe40ea1408275a headers=undefined
D Sep_11_09:18:37.895 multipart.js:115 Multipart.exports.Multipart.addFilePart : addFilePart: name=file filename=1_Cached_screen.jpg size=26244
D Sep_11_09:18:37.905 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=id body=150911_N4_1 headers=["Content-Type: text/plain"]
D Sep_11_09:18:37.906 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=location body=Nexus5 headers=undefined
D Sep_11_09:18:37.906 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=timeline body=1 headers=undefined
D Sep_11_09:18:37.907 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=pc body=0cfe40ea1408275a headers=undefined
D Sep_11_09:18:37.908 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=_runNumber body=1 headers=undefined
D Sep_11_09:18:37.908 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=_cacheWarmed body=1 headers=undefined
D Sep_11_09:18:37.908 multipart.js:115 Multipart.exports.Multipart.addFilePart : addFilePart: name=file filename=1_Cached_results.zip size=223116
D Sep_11_09:18:38.017 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=id body=150911_N4_1 headers=["Content-Type: text/plain"]
D Sep_11_09:18:38.017 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=location body=Nexus5 headers=undefined
D Sep_11_09:18:38.018 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=timeline body=1 headers=undefined
D Sep_11_09:18:38.018 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=pc body=0cfe40ea1408275a headers=undefined
D Sep_11_09:18:38.018 multipart.js:92 Multipart.exports.Multipart.addPart : addPart: name=done body=1 headers=undefined
D Sep_11_09:18:38.108 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell date
D Sep_11_09:18:38.177 process_utils.js:327 ChildProcess.<anonymous> : stdout[30] Fri Sep 11 10:18:34 BST 2015
I Sep_11_09:18:38.197 wpt_client.js:490 Client.<anonymous> : Get work: http://localhost:80/work/getwork.php?location=Nexus5&pc=0cfe40ea1408275a&f=json
2
D Sep_11_09:18:48.410 process_utils.js:327 ChildProcess.<anonymous> : stdout[30] Fri Sep 11 10:18:44 BST 2015
1
D Sep_11_09:18:58.507 process_utils.js:263 unknown : Exec with timeout(60000): adb -s 0cfe40ea1408275a shell date
D Sep_11_09:18:58.646 process_utils.js:327 ChildProcess.<anonymous> : stdout[30] Fri Sep 11 10:18:54 BST 2015
I Sep_11_09:18:58.662 wpt_client.js:490 Client.<anonymous> : Get work: http://localhost:80/work/getwork.php?location=Nexus5&pc=0cfe40ea1408275a&f=json
^CA Sep_11_09:19:04.414 wpt_client.js:406 Client.onSignal_ : Received SIGINT, exiting.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.