builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1454953025.28 results: success (0) buildid: 20160208071204 builduid: 1157bc73b6864290a3f3e33296e64e47 revision: f0641ff126c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.282804) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.283340) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.283706) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004413 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.301404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.301730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.314096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.314425) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004694 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.364838) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.365502) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.366287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.366783) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-08 09:37:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.6M=0s 2016-02-08 09:37:05 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.196510 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.579202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.579652) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014932 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.632318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:05.633164) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f0641ff126c2 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f0641ff126c2 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-08 09:37:05,793 Setting DEBUG logging. 2016-02-08 09:37:05,793 attempt 1/10 2016-02-08 09:37:05,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f0641ff126c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-08 09:37:06,032 unpacking tar archive at: mozilla-beta-f0641ff126c2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.530205 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:06.178123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:06.178662) ========= echo f0641ff126c2 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'f0641ff126c2'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False f0641ff126c2 program finished with exit code 0 elapsedTime=0.004665 script_repo_revision: 'f0641ff126c2' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:06.230693) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:06.231060) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:37:06.242442) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-08 09:37:06.242918) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:37:06 INFO - MultiFileLogger online at 20160208 09:37:06 in /builds/slave/test-pgo 09:37:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:37:06 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:37:06 INFO - {'append_to_log': False, 09:37:06 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:37:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:37:06 INFO - 'branch': 'Mozilla-Beta', 09:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:06 INFO - 'config_files': ('talos/linux_config.py',), 09:37:06 INFO - 'default_actions': ('clobber', 09:37:06 INFO - 'read-buildbot-config', 09:37:06 INFO - 'download-and-extract', 09:37:06 INFO - 'populate-webroot', 09:37:06 INFO - 'create-virtualenv', 09:37:06 INFO - 'install', 09:37:06 INFO - 'run-tests'), 09:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:06 INFO - 'download_minidump_stackwalk': True, 09:37:06 INFO - 'download_symbols': 'ondemand', 09:37:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:06 INFO - 'installer_path': 'installer.exe', 09:37:06 INFO - 'log_level': 'info', 09:37:06 INFO - 'log_name': 'talos', 09:37:06 INFO - 'log_to_console': True, 09:37:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:37:06 INFO - 'opt_config_files': (), 09:37:06 INFO - 'pip_index': False, 09:37:06 INFO - 'sps_profile': False, 09:37:06 INFO - 'sps_profile_interval': 0, 09:37:06 INFO - 'suite': 'dromaeojs', 09:37:06 INFO - 'system_bits': '32', 09:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:06 INFO - 'title': 'talos-linux64-ix-063', 09:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:06 INFO - 'use_talos_json': True, 09:37:06 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:06 INFO - 'work_dir': 'build'} 09:37:06 INFO - ##### 09:37:06 INFO - ##### Running clobber step. 09:37:06 INFO - ##### 09:37:06 INFO - Running pre-action listener: _resource_record_pre_action 09:37:06 INFO - Running main action method: clobber 09:37:06 INFO - rmtree: /builds/slave/test-pgo/build 09:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:37:06 INFO - Running post-action listener: _resource_record_post_action 09:37:06 INFO - ##### 09:37:06 INFO - ##### Running read-buildbot-config step. 09:37:06 INFO - ##### 09:37:06 INFO - Running pre-action listener: _resource_record_pre_action 09:37:06 INFO - Running main action method: read_buildbot_config 09:37:06 INFO - Using buildbot properties: 09:37:06 INFO - { 09:37:06 INFO - "properties": { 09:37:06 INFO - "buildnumber": 109, 09:37:06 INFO - "product": "firefox", 09:37:06 INFO - "script_repo_revision": "production", 09:37:06 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:37:06 INFO - "repository": "", 09:37:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:37:06 INFO - "buildid": "20160208071204", 09:37:06 INFO - "slavename": "talos-linux64-ix-063", 09:37:06 INFO - "pgo_build": "True", 09:37:06 INFO - "basedir": "/builds/slave/test-pgo", 09:37:06 INFO - "project": "", 09:37:06 INFO - "platform": "ubuntu64_hw", 09:37:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:37:06 INFO - "slavebuilddir": "test", 09:37:06 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:37:06 INFO - "branch": "mozilla-beta", 09:37:06 INFO - "repo_path": "releases/mozilla-beta", 09:37:06 INFO - "stage_platform": "linux64-pgo", 09:37:06 INFO - "builduid": "1157bc73b6864290a3f3e33296e64e47", 09:37:06 INFO - "revision": "f0641ff126c2" 09:37:06 INFO - }, 09:37:06 INFO - "sourcestamp": { 09:37:06 INFO - "repository": "", 09:37:06 INFO - "hasPatch": false, 09:37:06 INFO - "project": "", 09:37:06 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:37:06 INFO - "changes": [ 09:37:06 INFO - { 09:37:06 INFO - "category": null, 09:37:06 INFO - "files": [ 09:37:06 INFO - { 09:37:06 INFO - "url": null, 09:37:06 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:37:06 INFO - } 09:37:06 INFO - ], 09:37:06 INFO - "repository": "", 09:37:06 INFO - "rev": "f0641ff126c2", 09:37:06 INFO - "who": "sendchange", 09:37:06 INFO - "when": 1454952879, 09:37:06 INFO - "number": 7579150, 09:37:06 INFO - "comments": "Bug 1244122 P3 Execute browser_download.js service worker in separate scope. r=ehsan, a=sylvestre ba=ehsan", 09:37:06 INFO - "project": "", 09:37:06 INFO - "at": "Mon 08 Feb 2016 09:34:39", 09:37:06 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:37:06 INFO - "revlink": "", 09:37:06 INFO - "properties": [ 09:37:06 INFO - [ 09:37:06 INFO - "buildid", 09:37:06 INFO - "20160208071204", 09:37:06 INFO - "Change" 09:37:06 INFO - ], 09:37:06 INFO - [ 09:37:06 INFO - "builduid", 09:37:06 INFO - "1157bc73b6864290a3f3e33296e64e47", 09:37:06 INFO - "Change" 09:37:06 INFO - ], 09:37:06 INFO - [ 09:37:06 INFO - "pgo_build", 09:37:06 INFO - "True", 09:37:06 INFO - "Change" 09:37:06 INFO - ] 09:37:06 INFO - ], 09:37:06 INFO - "revision": "f0641ff126c2" 09:37:06 INFO - } 09:37:06 INFO - ], 09:37:06 INFO - "revision": "f0641ff126c2" 09:37:06 INFO - } 09:37:06 INFO - } 09:37:06 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:37:06 INFO - Running post-action listener: _resource_record_post_action 09:37:06 INFO - ##### 09:37:06 INFO - ##### Running download-and-extract step. 09:37:06 INFO - ##### 09:37:06 INFO - Running pre-action listener: _resource_record_pre_action 09:37:06 INFO - Running main action method: download_and_extract 09:37:06 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/test_packages.json 09:37:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:37:06 INFO - Downloaded 1270 bytes. 09:37:06 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:37:06 INFO - Using the following test package requirements: 09:37:06 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:37:06 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:37:06 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'jsshell-linux-x86_64.zip'], 09:37:06 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:37:06 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:37:06 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:37:06 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:37:06 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:37:06 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:37:06 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:37:06 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:37:06 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:07 INFO - Downloaded 21580352 bytes. 09:37:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:37:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:07 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:37:08 INFO - Return code: 0 09:37:08 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:08 INFO - Downloaded 21580352 bytes. 09:37:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:37:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:37:08 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:37:10 INFO - Return code: 0 09:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:37:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:37:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:37:10 INFO - Downloaded 11132038 bytes. 09:37:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:37:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:37:10 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:37:10 INFO - Return code: 0 09:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:37:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:37:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:37:11 INFO - Downloaded 57950221 bytes. 09:37:11 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:37:11 INFO - mkdir: /builds/slave/test-pgo/properties 09:37:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:37:11 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:37:11 INFO - Contents: 09:37:11 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:37:11 INFO - Running post-action listener: _resource_record_post_action 09:37:11 INFO - Running post-action listener: set_extra_try_arguments 09:37:11 INFO - ##### 09:37:11 INFO - ##### Running populate-webroot step. 09:37:11 INFO - ##### 09:37:11 INFO - Running pre-action listener: _resource_record_pre_action 09:37:11 INFO - Running main action method: populate_webroot 09:37:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:11 INFO - '--tppagecycles', 09:37:11 INFO - '7'], 09:37:11 INFO - 'tests': ['tsvgm']}}, 09:37:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:37:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:37:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:37:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'tests': ['damp', 'tps']}, 09:37:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['damp', 'tps']}, 09:37:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:37:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:11 INFO - 'other': {'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'other_l64': {'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'other_nol64': {'tests': ['a11yr', 09:37:11 INFO - 'ts_paint', 09:37:11 INFO - 'tpaint', 09:37:11 INFO - 'sessionrestore', 09:37:11 INFO - 'sessionrestore_no_auto_restore']}, 09:37:11 INFO - 'svgr': {'tests': ['tsvgx', 09:37:11 INFO - 'tsvgr_opacity', 09:37:11 INFO - 'tart', 09:37:11 INFO - 'tscrollx', 09:37:11 INFO - 'cart']}, 09:37:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['tsvgx', 09:37:11 INFO - 'tsvgr_opacity', 09:37:11 INFO - 'tart', 09:37:11 INFO - 'tscrollx', 09:37:11 INFO - 'cart']}, 09:37:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'tests': ['tp5o']}, 09:37:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'talos_options': ['--e10s'], 09:37:11 INFO - 'tests': ['tp5o']}, 09:37:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'talos_options': ['--xperf_path', 09:37:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:11 INFO - 'tests': ['tp5n']}, 09:37:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:11 INFO - 'talos_options': ['--e10s', 09:37:11 INFO - '--xperf_path', 09:37:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:11 INFO - 'tests': ['tp5n']}}, 09:37:11 INFO - 'talos.zip': {'path': '', 09:37:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:11 INFO - Running post-action listener: _resource_record_post_action 09:37:11 INFO - ##### 09:37:11 INFO - ##### Running create-virtualenv step. 09:37:11 INFO - ##### 09:37:11 INFO - Running pre-action listener: _resource_record_pre_action 09:37:11 INFO - Running main action method: create_virtualenv 09:37:11 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:37:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:37:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:37:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:11 INFO - Using real prefix '/usr' 09:37:11 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:37:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:14 INFO - Installing pip.................done. 09:37:14 INFO - Return code: 0 09:37:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:37:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:14 INFO - Using env: {'DISPLAY': ':0', 09:37:14 INFO - 'HOME': '/home/cltbld', 09:37:14 INFO - 'LANG': 'en_US.UTF-8', 09:37:14 INFO - 'LANGUAGE': 'en_US:en', 09:37:14 INFO - 'LOGNAME': 'cltbld', 09:37:14 INFO - 'MAIL': '/var/mail/cltbld', 09:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:14 INFO - 'MOZ_NO_REMOTE': '1', 09:37:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:14 INFO - 'NO_EM_RESTART': '1', 09:37:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:14 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:14 INFO - 'SHELL': '/bin/bash', 09:37:14 INFO - 'SHLVL': '1', 09:37:14 INFO - 'TERM': 'linux', 09:37:14 INFO - 'TMOUT': '86400', 09:37:14 INFO - 'USER': 'cltbld', 09:37:14 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:14 INFO - '_': '/tools/buildbot/bin/python'} 09:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:37:14 INFO - Cleaning up... 09:37:14 INFO - Return code: 0 09:37:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:37:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:37:14 INFO - Using env: {'DISPLAY': ':0', 09:37:14 INFO - 'HOME': '/home/cltbld', 09:37:14 INFO - 'LANG': 'en_US.UTF-8', 09:37:14 INFO - 'LANGUAGE': 'en_US:en', 09:37:14 INFO - 'LOGNAME': 'cltbld', 09:37:14 INFO - 'MAIL': '/var/mail/cltbld', 09:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:14 INFO - 'MOZ_NO_REMOTE': '1', 09:37:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:14 INFO - 'NO_EM_RESTART': '1', 09:37:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:14 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:14 INFO - 'SHELL': '/bin/bash', 09:37:14 INFO - 'SHLVL': '1', 09:37:14 INFO - 'TERM': 'linux', 09:37:14 INFO - 'TMOUT': '86400', 09:37:14 INFO - 'USER': 'cltbld', 09:37:14 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:14 INFO - '_': '/tools/buildbot/bin/python'} 09:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:14 INFO - Downloading/unpacking psutil>=0.7.1 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:16 INFO - Installing collected packages: psutil 09:37:16 INFO - Running setup.py install for psutil 09:37:16 INFO - building 'psutil._psutil_linux' extension 09:37:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:37:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:37:16 INFO - building 'psutil._psutil_posix' extension 09:37:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:37:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:37:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:16 INFO - Successfully installed psutil 09:37:16 INFO - Cleaning up... 09:37:16 INFO - Return code: 0 09:37:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:37:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:37:16 INFO - Using env: {'DISPLAY': ':0', 09:37:16 INFO - 'HOME': '/home/cltbld', 09:37:16 INFO - 'LANG': 'en_US.UTF-8', 09:37:16 INFO - 'LANGUAGE': 'en_US:en', 09:37:16 INFO - 'LOGNAME': 'cltbld', 09:37:16 INFO - 'MAIL': '/var/mail/cltbld', 09:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:16 INFO - 'MOZ_NO_REMOTE': '1', 09:37:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:16 INFO - 'NO_EM_RESTART': '1', 09:37:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:16 INFO - 'SHELL': '/bin/bash', 09:37:16 INFO - 'SHLVL': '1', 09:37:16 INFO - 'TERM': 'linux', 09:37:16 INFO - 'TMOUT': '86400', 09:37:16 INFO - 'USER': 'cltbld', 09:37:16 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:16 INFO - '_': '/tools/buildbot/bin/python'} 09:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:37:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:37:19 INFO - Installing collected packages: mozsystemmonitor 09:37:19 INFO - Running setup.py install for mozsystemmonitor 09:37:19 INFO - Successfully installed mozsystemmonitor 09:37:19 INFO - Cleaning up... 09:37:19 INFO - Return code: 0 09:37:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:37:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:19 INFO - Using env: {'DISPLAY': ':0', 09:37:19 INFO - 'HOME': '/home/cltbld', 09:37:19 INFO - 'LANG': 'en_US.UTF-8', 09:37:19 INFO - 'LANGUAGE': 'en_US:en', 09:37:19 INFO - 'LOGNAME': 'cltbld', 09:37:19 INFO - 'MAIL': '/var/mail/cltbld', 09:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:19 INFO - 'MOZ_NO_REMOTE': '1', 09:37:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:19 INFO - 'NO_EM_RESTART': '1', 09:37:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:19 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:19 INFO - 'SHELL': '/bin/bash', 09:37:19 INFO - 'SHLVL': '1', 09:37:19 INFO - 'TERM': 'linux', 09:37:19 INFO - 'TMOUT': '86400', 09:37:19 INFO - 'USER': 'cltbld', 09:37:19 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:19 INFO - '_': '/tools/buildbot/bin/python'} 09:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:19 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:37:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - Downloading docopt-0.6.1.tar.gz 09:37:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:21 INFO - Running setup.py install for blobuploader 09:37:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:21 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:37:21 INFO - Running setup.py install for requests 09:37:22 INFO - Running setup.py install for docopt 09:37:22 INFO - Successfully installed blobuploader requests docopt 09:37:22 INFO - Cleaning up... 09:37:22 INFO - Return code: 0 09:37:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:37:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:22 INFO - Using env: {'DISPLAY': ':0', 09:37:22 INFO - 'HOME': '/home/cltbld', 09:37:22 INFO - 'LANG': 'en_US.UTF-8', 09:37:22 INFO - 'LANGUAGE': 'en_US:en', 09:37:22 INFO - 'LOGNAME': 'cltbld', 09:37:22 INFO - 'MAIL': '/var/mail/cltbld', 09:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:22 INFO - 'MOZ_NO_REMOTE': '1', 09:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:22 INFO - 'NO_EM_RESTART': '1', 09:37:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:22 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:22 INFO - 'SHELL': '/bin/bash', 09:37:22 INFO - 'SHLVL': '1', 09:37:22 INFO - 'TERM': 'linux', 09:37:22 INFO - 'TMOUT': '86400', 09:37:22 INFO - 'USER': 'cltbld', 09:37:22 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:22 INFO - '_': '/tools/buildbot/bin/python'} 09:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:37:22 INFO - Running setup.py (path:/tmp/pip-KGYCAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:37:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:37:22 INFO - Running setup.py (path:/tmp/pip-XgrX9L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:37:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:37:22 INFO - Running setup.py (path:/tmp/pip-eejORi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:37:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:37:22 INFO - Running setup.py (path:/tmp/pip-cDVM9E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:37:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:37:22 INFO - Running setup.py (path:/tmp/pip-M8AQKw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:37:23 INFO - Running setup.py (path:/tmp/pip-AqAZEu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:37:23 INFO - Running setup.py (path:/tmp/pip-9M4y5a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:37:23 INFO - Running setup.py (path:/tmp/pip-OkVrMA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:37:23 INFO - Running setup.py (path:/tmp/pip-eWvsOE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:37:23 INFO - Running setup.py (path:/tmp/pip-Uzn42h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:37:23 INFO - Running setup.py (path:/tmp/pip-ABRwIe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:37:23 INFO - Running setup.py (path:/tmp/pip-1RSxLz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:37:23 INFO - Running setup.py (path:/tmp/pip-o43V_V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:37:23 INFO - Running setup.py (path:/tmp/pip-y8H43W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:37:23 INFO - Running setup.py (path:/tmp/pip-ezysue-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:37:23 INFO - Running setup.py (path:/tmp/pip-SjcPzw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:37:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:37:23 INFO - Running setup.py (path:/tmp/pip-JLHyAh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:37:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:23 INFO - Running setup.py install for manifestparser 09:37:24 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for mozcrash 09:37:24 INFO - Running setup.py install for mozdebug 09:37:24 INFO - Running setup.py install for mozdevice 09:37:24 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for mozfile 09:37:24 INFO - Running setup.py install for mozhttpd 09:37:24 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for mozinfo 09:37:24 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for mozInstall 09:37:24 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for mozleak 09:37:24 INFO - Running setup.py install for mozlog 09:37:24 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:37:24 INFO - Running setup.py install for moznetwork 09:37:25 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Running setup.py install for mozprocess 09:37:25 INFO - Running setup.py install for mozprofile 09:37:25 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Running setup.py install for mozrunner 09:37:25 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Running setup.py install for mozscreenshot 09:37:25 INFO - Running setup.py install for moztest 09:37:25 INFO - Running setup.py install for mozversion 09:37:25 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:37:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:25 INFO - Cleaning up... 09:37:25 INFO - Return code: 0 09:37:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:37:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:25 INFO - Using env: {'DISPLAY': ':0', 09:37:25 INFO - 'HOME': '/home/cltbld', 09:37:25 INFO - 'LANG': 'en_US.UTF-8', 09:37:25 INFO - 'LANGUAGE': 'en_US:en', 09:37:25 INFO - 'LOGNAME': 'cltbld', 09:37:25 INFO - 'MAIL': '/var/mail/cltbld', 09:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:25 INFO - 'MOZ_NO_REMOTE': '1', 09:37:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:25 INFO - 'NO_EM_RESTART': '1', 09:37:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:25 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:25 INFO - 'SHELL': '/bin/bash', 09:37:25 INFO - 'SHLVL': '1', 09:37:25 INFO - 'TERM': 'linux', 09:37:25 INFO - 'TMOUT': '86400', 09:37:25 INFO - 'USER': 'cltbld', 09:37:25 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:25 INFO - '_': '/tools/buildbot/bin/python'} 09:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:37:25 INFO - Running setup.py (path:/tmp/pip-rOaBOZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:37:26 INFO - Running setup.py (path:/tmp/pip-15slpu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:37:26 INFO - Running setup.py (path:/tmp/pip-gBP3fX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:37:26 INFO - Running setup.py (path:/tmp/pip-Lh7JLL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:37:26 INFO - Running setup.py (path:/tmp/pip-oAbg8Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:37:26 INFO - Running setup.py (path:/tmp/pip-6o9xqE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:37:26 INFO - Running setup.py (path:/tmp/pip-6jUYoM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:37:26 INFO - Running setup.py (path:/tmp/pip-y6UzVz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:37:26 INFO - Running setup.py (path:/tmp/pip-Jty3rk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:37:26 INFO - Running setup.py (path:/tmp/pip-ZIJick-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:37:26 INFO - Running setup.py (path:/tmp/pip-kSjOtZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:37:26 INFO - Running setup.py (path:/tmp/pip-gaqKHh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:37:26 INFO - Running setup.py (path:/tmp/pip-kzw3X6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:37:26 INFO - Running setup.py (path:/tmp/pip-LmvcIc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:37:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:37:26 INFO - Running setup.py (path:/tmp/pip-9OAKtZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:37:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:37:27 INFO - Running setup.py (path:/tmp/pip-naNUN9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:37:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:37:27 INFO - Running setup.py (path:/tmp/pip-4iyoMM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:28 INFO - Downloading blessings-1.6.tar.gz 09:37:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:29 INFO - Installing collected packages: blessings 09:37:29 INFO - Running setup.py install for blessings 09:37:29 INFO - Successfully installed blessings 09:37:29 INFO - Cleaning up... 09:37:29 INFO - Return code: 0 09:37:29 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:37:29 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:37:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:37:29 INFO - Reading from file tmpfile_stdout 09:37:29 INFO - Current package versions: 09:37:29 INFO - argparse == 1.2.1 09:37:29 INFO - blessings == 1.6 09:37:29 INFO - blobuploader == 1.2.4 09:37:29 INFO - docopt == 0.6.1 09:37:29 INFO - manifestparser == 1.1 09:37:29 INFO - mozInstall == 1.12 09:37:29 INFO - mozcrash == 0.16 09:37:29 INFO - mozdebug == 0.1 09:37:29 INFO - mozdevice == 0.47 09:37:29 INFO - mozfile == 1.2 09:37:29 INFO - mozhttpd == 0.7 09:37:29 INFO - mozinfo == 0.9 09:37:29 INFO - mozleak == 0.1 09:37:29 INFO - mozlog == 3.1 09:37:29 INFO - moznetwork == 0.27 09:37:29 INFO - mozprocess == 0.22 09:37:29 INFO - mozprofile == 0.27 09:37:29 INFO - mozrunner == 6.11 09:37:29 INFO - mozscreenshot == 0.1 09:37:29 INFO - mozsystemmonitor == 0.0 09:37:29 INFO - moztest == 0.7 09:37:29 INFO - mozversion == 1.4 09:37:29 INFO - psutil == 3.1.1 09:37:29 INFO - requests == 1.2.3 09:37:29 INFO - wsgiref == 0.1.2 09:37:29 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:37:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:29 INFO - Using env: {'DISPLAY': ':0', 09:37:29 INFO - 'HOME': '/home/cltbld', 09:37:29 INFO - 'LANG': 'en_US.UTF-8', 09:37:29 INFO - 'LANGUAGE': 'en_US:en', 09:37:29 INFO - 'LOGNAME': 'cltbld', 09:37:29 INFO - 'MAIL': '/var/mail/cltbld', 09:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:29 INFO - 'MOZ_NO_REMOTE': '1', 09:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:29 INFO - 'NO_EM_RESTART': '1', 09:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:29 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:29 INFO - 'SHELL': '/bin/bash', 09:37:29 INFO - 'SHLVL': '1', 09:37:29 INFO - 'TERM': 'linux', 09:37:29 INFO - 'TMOUT': '86400', 09:37:29 INFO - 'USER': 'cltbld', 09:37:29 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:29 INFO - '_': '/tools/buildbot/bin/python'} 09:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:37:29 INFO - Cleaning up... 09:37:29 INFO - Return code: 0 09:37:29 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:37:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:37:29 INFO - Using env: {'DISPLAY': ':0', 09:37:29 INFO - 'HOME': '/home/cltbld', 09:37:29 INFO - 'LANG': 'en_US.UTF-8', 09:37:29 INFO - 'LANGUAGE': 'en_US:en', 09:37:29 INFO - 'LOGNAME': 'cltbld', 09:37:29 INFO - 'MAIL': '/var/mail/cltbld', 09:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:29 INFO - 'MOZ_NO_REMOTE': '1', 09:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:29 INFO - 'NO_EM_RESTART': '1', 09:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:29 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:29 INFO - 'SHELL': '/bin/bash', 09:37:29 INFO - 'SHLVL': '1', 09:37:29 INFO - 'TERM': 'linux', 09:37:29 INFO - 'TMOUT': '86400', 09:37:29 INFO - 'USER': 'cltbld', 09:37:29 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:29 INFO - '_': '/tools/buildbot/bin/python'} 09:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:29 INFO - Downloading/unpacking jsonschema 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:31 INFO - Downloading/unpacking functools32 (from jsonschema) 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:31 INFO - warning: no files found matching '*.txt' 09:37:31 INFO - no previously-included directories found matching 'build' 09:37:31 INFO - no previously-included directories found matching 'dist' 09:37:31 INFO - no previously-included directories found matching '.git*' 09:37:31 INFO - Installing collected packages: jsonschema, functools32 09:37:31 INFO - Running setup.py install for functools32 09:37:32 INFO - warning: no files found matching '*.txt' 09:37:32 INFO - no previously-included directories found matching 'build' 09:37:32 INFO - no previously-included directories found matching 'dist' 09:37:32 INFO - no previously-included directories found matching '.git*' 09:37:32 INFO - Successfully installed jsonschema functools32 09:37:32 INFO - Cleaning up... 09:37:32 INFO - Return code: 0 09:37:32 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9ccc2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:37:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:37:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:37:32 INFO - Using env: {'DISPLAY': ':0', 09:37:32 INFO - 'HOME': '/home/cltbld', 09:37:32 INFO - 'LANG': 'en_US.UTF-8', 09:37:32 INFO - 'LANGUAGE': 'en_US:en', 09:37:32 INFO - 'LOGNAME': 'cltbld', 09:37:32 INFO - 'MAIL': '/var/mail/cltbld', 09:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:32 INFO - 'MOZ_NO_REMOTE': '1', 09:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:32 INFO - 'NO_EM_RESTART': '1', 09:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:32 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:32 INFO - 'SHELL': '/bin/bash', 09:37:32 INFO - 'SHLVL': '1', 09:37:32 INFO - 'TERM': 'linux', 09:37:32 INFO - 'TMOUT': '86400', 09:37:32 INFO - 'USER': 'cltbld', 09:37:32 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:32 INFO - '_': '/tools/buildbot/bin/python'} 09:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:32 INFO - Downloading/unpacking flake8 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:37:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:37:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:34 INFO - no previously-included directories found matching 'docs/_build' 09:37:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:37:34 INFO - Running setup.py install for pep8 09:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:37:34 INFO - no previously-included directories found matching 'docs/_build' 09:37:34 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:37:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:37:34 INFO - Cleaning up... 09:37:35 INFO - Return code: 0 09:37:35 INFO - Running post-action listener: _resource_record_post_action 09:37:35 INFO - Running post-action listener: _start_resource_monitoring 09:37:35 INFO - Starting resource monitoring. 09:37:35 INFO - ##### 09:37:35 INFO - ##### Running install step. 09:37:35 INFO - ##### 09:37:35 INFO - Running pre-action listener: _resource_record_pre_action 09:37:35 INFO - Running main action method: install 09:37:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:37:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:37:35 INFO - Reading from file tmpfile_stdout 09:37:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:37:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:37:35 INFO - Reading from file tmpfile_stdout 09:37:35 INFO - Output received: 09:37:35 INFO - Usage: mozinstall [options] installer 09:37:35 INFO - Options: 09:37:35 INFO - -h, --help show this help message and exit 09:37:35 INFO - -d DEST, --destination=DEST 09:37:35 INFO - Directory to install application into. [default: 09:37:35 INFO - "/builds/slave/test-pgo"] 09:37:35 INFO - --app=APP Application being installed. [default: firefox] 09:37:35 INFO - mkdir: /builds/slave/test-pgo/build/application 09:37:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:37:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:37:49 INFO - Reading from file tmpfile_stdout 09:37:49 INFO - Output received: 09:37:49 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:37:49 INFO - Running post-action listener: _resource_record_post_action 09:37:49 INFO - ##### 09:37:49 INFO - ##### Running run-tests step. 09:37:49 INFO - ##### 09:37:49 INFO - Running pre-action listener: _resource_record_pre_action 09:37:49 INFO - Running main action method: run_tests 09:37:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:37:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:37:49 INFO - Python 2.7.3 09:37:49 INFO - Return code: 0 09:37:49 INFO - grabbing minidump binary from tooltool 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032350>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10336e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:37:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:37:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:37:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:49 INFO - Return code: 0 09:37:49 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:37:49 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:37:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:37:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:37:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:37:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:37:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:37:51 INFO - Return code: 0 09:37:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:37:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost 09:37:51 INFO - Using env: {'DISPLAY': ':0', 09:37:51 INFO - 'HOME': '/home/cltbld', 09:37:51 INFO - 'LANG': 'en_US.UTF-8', 09:37:51 INFO - 'LANGUAGE': 'en_US:en', 09:37:51 INFO - 'LOGNAME': 'cltbld', 09:37:51 INFO - 'MAIL': '/var/mail/cltbld', 09:37:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:37:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:51 INFO - 'MOZ_NO_REMOTE': '1', 09:37:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:51 INFO - 'NO_EM_RESTART': '1', 09:37:51 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:37:51 INFO - 'PWD': '/builds/slave/test-pgo', 09:37:51 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:37:51 INFO - 'SHELL': '/bin/bash', 09:37:51 INFO - 'SHLVL': '1', 09:37:51 INFO - 'TERM': 'linux', 09:37:51 INFO - 'TMOUT': '86400', 09:37:51 INFO - 'USER': 'cltbld', 09:37:51 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555', 09:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:51 INFO - '_': '/tools/buildbot/bin/python'} 09:37:51 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost'] with output_timeout 3600 09:37:51 INFO - mozversion INFO | application_buildid: 20160208071204 09:37:51 INFO - mozversion INFO | application_changeset: f0641ff126c245afc2e4230ca328bce8e079f3ca 09:37:51 INFO - mozversion INFO | application_display_name: Nightly 09:37:51 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:51 INFO - mozversion INFO | application_name: Firefox 09:37:51 INFO - mozversion INFO | application_remotingname: firefox 09:37:51 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:51 INFO - mozversion INFO | application_vendor: Mozilla 09:37:51 INFO - mozversion INFO | application_version: 45.0 09:37:51 INFO - mozversion INFO | platform_buildid: 20160208071204 09:37:51 INFO - mozversion INFO | platform_changeset: f0641ff126c245afc2e4230ca328bce8e079f3ca 09:37:51 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:51 INFO - mozversion INFO | platform_version: 45.0 09:37:51 INFO - 2016-02-08 09:37:51,272 DEBUG : using testdate: 1454953071 09:37:51 INFO - 2016-02-08 09:37:51,272 DEBUG : actual date: 1454953071 09:37:51 INFO - 2016-02-08 09:37:51,288 INFO : starting webserver on 'localhost:40194' 09:37:51 INFO - 2016-02-08 09:37:51,289 INFO : Starting test suite talos-linux64-ix-063 09:37:51 INFO - 2016-02-08 09:37:51,289 INFO : Starting test dromaeo_css 09:37:51 INFO - 2016-02-08 09:37:51,289 DEBUG : operating with platform_type : linux_ 09:37:51 INFO - 2016-02-08 09:37:51,290 INFO : Initialising browser for dromaeo_css test... 09:37:51 INFO - 2016-02-08 09:37:51,302 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpryz1A9/profile http://localhost:40194/getInfo.html 09:37:55 INFO - 2016-02-08 09:37:55,343 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:37:55 INFO - 2016-02-08 09:37:55,343 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:37:55 INFO - 2016-02-08 09:37:55,343 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:37:55 INFO - 2016-02-08 09:37:55,343 DEBUG : BROWSER_OUTPUT: __metrics 09:37:55 INFO - 2016-02-08 09:37:55,513 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:56 INFO - 2016-02-08 09:37:56,223 INFO : Browser initialized. 09:37:56 INFO - 2016-02-08 09:37:56,223 INFO : Running cycle 1/1 for dromaeo_css test... 09:37:56 INFO - 2016-02-08 09:37:56,223 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpryz1A9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:38:08 INFO - 2016-02-08 09:38:08,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 134893568 09:38:08 INFO - 2016-02-08 09:38:08,038 DEBUG : BROWSER_OUTPUT: 09:38:58 INFO - 2016-02-08 09:38:58,763 DEBUG : BROWSER_OUTPUT: 1454953138763 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:41:06 INFO - 2016-02-08 09:41:06,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40194/tests/dromaeo/cssquery-ext.html) 09:41:07 INFO - 2016-02-08 09:41:07,781 DEBUG : BROWSER_OUTPUT: RSS: Main: 214933504 09:41:07 INFO - 2016-02-08 09:41:07,781 DEBUG : BROWSER_OUTPUT: 09:44:09 INFO - 2016-02-08 09:44:09,838 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-ext.html (next: http://localhost:40194/tests/dromaeo/cssquery-jquery.html) 09:44:10 INFO - 2016-02-08 09:44:10,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 214650880 09:44:10 INFO - 2016-02-08 09:44:10,404 DEBUG : BROWSER_OUTPUT: 09:47:12 INFO - 2016-02-08 09:47:12,123 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40194/tests/dromaeo/cssquery-mootools.html) 09:47:12 INFO - 2016-02-08 09:47:12,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 210661376 09:47:12 INFO - 2016-02-08 09:47:12,689 DEBUG : BROWSER_OUTPUT: 09:50:14 INFO - 2016-02-08 09:50:14,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40194/tests/dromaeo/cssquery-prototype.html) 09:50:14 INFO - 2016-02-08 09:50:14,968 DEBUG : BROWSER_OUTPUT: RSS: Main: 195436544 09:50:14 INFO - 2016-02-08 09:50:14,969 DEBUG : BROWSER_OUTPUT: 09:53:17 INFO - 2016-02-08 09:53:17,243 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40194/tests/dromaeo/cssquery-yui.html) 09:53:17 INFO - 2016-02-08 09:53:17,809 DEBUG : BROWSER_OUTPUT: RSS: Main: 197476352 09:53:17 INFO - 2016-02-08 09:53:17,809 DEBUG : BROWSER_OUTPUT: 09:56:19 INFO - 2016-02-08 09:56:19,883 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/dromaeo/cssquery-yui.html (next: http://localhost:40194/tests/dromaeo/cssquery-dojo.html) 09:56:20 INFO - 2016-02-08 09:56:20,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 240140288 09:56:20 INFO - 2016-02-08 09:56:20,133 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,134 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:56:20 INFO - 2016-02-08 09:56:20,134 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:56:20 INFO - 2016-02-08 09:56:20,134 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:56:20 INFO - 2016-02-08 09:56:20,135 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:56:20 INFO - 2016-02-08 09:56:20,136 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3252;3294.1105901406604;3357.6138744044442;3359.2609625882315;3376.4428869236576;5492.286002819638;5508.834734957953;5523.502884740377;5530.695811730353;5540.50135487806;5498.092814685572;5524.392316845154;5533.778648854041;5536.4186760390085;5540.445955404468;4824.155772739757;4951.3810773653295;5030.522100400474;5051.924221136667;5137.6660517066275;4912.0667073256145;4927.236278376839;4966.950330496703;4971.27916452116;4972.527609877074;4937.950620493803;4979.427365852937;5045.672031317971;5058.645894787366;5089.872753181163;5032.6477146599755;5062.164742817431;5063.443021267639;5064.265681476202;5094.1849304111165;4742.478327383985;4960.6793648895455;4961.404631444214;5014;5014.548690617826;5159;5187.455317191716;5229.529342359206;5232.372115346183;5244.711540865251;5884.941150588482;5898.793542226044;5904.704764761787;5939.940600594025;5946.5540084493405;5696.629719068247;5789.247397838296;5938.198343223653;5954.166416701665;5961.105834124873;5471.835844924659;5542.057850165482;5543.944560554383;5575;5586.91619625706;5997.250343707036;6000.429959153874;6026.969865150734;6033;6038.969805150946;5571;5675.3757086720425;5784.82645520635;5827.562932780059;5847.356790753014;5691.260136182353;5780.335261444903;5780.5953583248765;5783.334916484658;5819.94180058194;5794.826155215266;5828.53371730269;5854.560907931922;5854.7072646367515;5900.793472228452;5557.749901254453;5644.096944488862;5702.258706368146;5836.503897168704;5881.852953676193;4481.843135490242;4507.188706032945;4516.503184649686;4517.164324599894;4518;4055.270051390778;4060.878173654795;4064.7561146331313;4065.5324637667045;4087;5324.068288049653;5330.227117068081;5424.05079111154;5501.174823776466;5509.972450137724;5861.413858614104;5866.530677545786;5890.440408161217;5895.7052147393315;5896.3219229788265;5520.723963801794;5547.11246200614;5549.972250138723;5551.417101204315;5551.666899986014;4872.634552408513;5020.799168033238;5063.797448102108;5095.184770436712;5095.949040509621;6016.488598469092;6026.367231440723;6036.3360030396625;6039.818805435844;6051.818445446644;5883.882322353529;5892.057270836645;5898.1152827076285;5898.144769008384;5902.586818922806;5747.913781293368;5858.8242352729485;5909.2908850938165;6033.698315084316;6036.486898613667;5672.943270567242;5703.08750599902;5705.0871860503785;5705.543556515552;5792.565557583114;5779.913301300568;5787.479126878433;5788.102844059177;5791;5800.390958949419;5459.153831156177;5598.412166722435;5683.772649094155;5769.307683078057;5792.507636850914;5724.8282551523525;5782.392848750989;5787.305523337226;5800.39095894925;5800.535957123336;5441.238226648352;5658.632188907708;5697.601167918372;5712.343080545625;5721.970045391703;5693.5160511357;5699.145128230799;5757.107648314518;5757.165211044291;5797.130430435468;5553.389127196086;5564.054110801255;5586.608937374344;5588.30146231721;5588.552915766811 09:56:20 INFO - 2016-02-08 09:56:20,139 DEBUG : BROWSER_OUTPUT: |1;ext.html;575.0728790456575;601.4406601860268;612.1980205930232;613.5091926458838;652.8661624367003;32811.179720506996;33636.32727345452;33643.15892102699;33653;33679.83160084199;34714.13214669634;39569;39926.401103983466;39929.8003509982;39961.20077598454;9018.684346047907;9175;9178.219851312613;9178.678746243882;9179.72460826173;8370.790730231733;8565.743027709179;8865.113488651115;8983.685571005017;8986.236169925563;10835.404052777094;11087;11098.778024439507;11100.222984391095;11100.777984440307;10584.682459526188;10705.090067344285;10708.46457677113;10708.625198118028;10711.732206694856;10506.369617822878;10617.256792024562;10634.096101831276;10637.627683031134;10639.734006649818;41236.58763412358;41621.79189104065;41628.79185604083;41637.58362416367;41701.37447938283;3547.5210846535647;3598.3882739934274;3969.325214713506;4271.209826182167;4332.415123958257;32194.517082243787;32426.18934526632;32435.35129297401;32479.350412991844;33572.49641255383;4133.91732165358;4198.6431153352005;4238.258304796648;4310.547392523803;4327.8485253016;293822;294729;295816;296207;296278.51860740775;59158.40841591529;59788.402115978286;59813.401865980784;65751;66017.66991165014;77016;78590.6070469644;78799.60600197077;78896.21103788889;79042;18240.817591824176;18412.723809142874;18432.354867579572;18436.07819609014;18440.81559184391;14006.369713362923;14070.43718251279;14072.85927140736;14073.366698498592;14094.929525352307;21676.56646867054;21924.89037554802;22015.779842201373;22098.33704988853;22112.889435553043;7860.213978602094;8159.184081591912;8164.959175204205;8222.671093156327;8340.165983401732;963.9710808675751;998.2213873178932;1037.6783197208738;1051.395447617623;1124.8706398764084;29418.411631767245;29701.554476682868;30418.391632167677;30496.085117446513;30744;1090.5528733219342;1188.560232713884;1194.2416565480983;1214.2532342609231;1276.3745764575292;14942.701145977237;15798.763018554731;16036.278367473491;16997.49007529776;17966.371177008743;415776;421793;422237;424354;425571;58097.41902581005;58728.41271587229;58813;58871.41128588659;59075.113873291935;1441.4090223008516;1442.7547316956143;1463.7950686903841;1465.3479201755329;1472.580314610331;31167.844160779052;31223.375532489677;31671.20821979469;31707;31988.040358789272;46988;47690.52309476861;49994.75002624963;51160.976780464924;51314.74342628263;212060;212103;212402;212978;213237;31848.681513184572;32144.83927580347;32222.35555288928;32249.35501289914;32253.35493290168;41124.588754112076;41694.374584380654;41905;42463.57536424596;42859.7857010713;38421.423678644285;39663.603363965995;44602.55397446114;44922;46608.766956165004;38765.806170968965;39076.80461597674;39194.41208381819;39316.537268240514;40330.395044073775;39142.02144946399;44177.55822441734;44244.33633495436;44801;45261.32108018448;34222.31555368929;35007;35046.299074017865;35089.473657895665;35183.82408087943;26825.195244142706;26896.462070758083;26979.460410791282;27047.459050819267;27301.72336459805 09:56:20 INFO - 2016-02-08 09:56:20,140 DEBUG : BROWSER_OUTPUT: |2;jquery.html;378.2359633540247;383.48804346197824;384.70185606155223;388.49689651900786;390.13779547200687;15197.6960460791;15570.766438503431;17190.656186876255;18584.442466726003;18621.255149794008;15011.624709382271;18120.18459169337;18467.53831154222;18518.629627407445;18559;5854.795082172114;5987.55093367997;6016.75932962681;6062.514998800108;6063.060225665029;6008.909866352008;6025.246844144482;6041.4562689357945;6043.969780151093;6044.153818465418;5669.574781891354;5803.390643982384;5842.44496772808;5845.8830823383505;5848.912266315988;5288.735563221823;5299.12564426873;5301.920471192935;5480.177973304017;5784.623999440044;5330.040592693314;5388.164834450667;5395.10980688186;5405.216243644689;5405.675659460445;21004.579908401898;21907.67138492924;21908.12367505298;21994.670079948814;22738.658920116046;2306.688597039395;2376.964345534818;2428.1865575032384;2465.272744540365;2474.4927289905527;10372.896271037343;10403.323783954018;11817.409129543574;12341;13386.594407587258;1768.5401795533126;1808.484581894167;1856.2296646891523;1924.0956750327323;1954.912028958686;61376.69311653413;61832;62892.685536572484;63332.68333658348;71979.64010179968;10145.137663298703;10346.896531034594;10428.947855260827;10473.31923424975;10490.947545262225;14129.717405651829;14169.149851008973;14200.218987955555;14202;14209.147451152963;7814.296713295831;7929.563873986986;7932.206779322021;7978.242067003625;8547.230749232474;8162.959185204036;8172.305354044973;8186;8213.630386632616;8229.300509456763;3914.2171565686976;4032.7983600819844;4112.403701463271;4330.307150855848;4625.236835922035;1763.7971633262082;1825.9269629214682;1838.7058070708622;1889.6598612249652;1916.60709554541;970.5292932927524;974.605284859633;983.9262055345878;1010.6109147977934;1088.6407485529755;17391.130443477774;17831.554211144565;17874.5531361717;17876.480499157457;18294.54263643393;989.3272574649308;998.8801343838786;1019.6278358399131;1044.4307852220406;1069.1446842525947;9372;9462.337636365388;9614.278929080347;9657.517124143766;9775.413475191512;683231;690806;691149;698930;748816.5118348746;88274;89828.5508572453;90035;91229.98235097133;93878;1149.9597514086968;1183.1540448579308;1185.733210027737;1193.7314104326458;1204.9216800908089;16605.58486037859;16616.335346586002;16644.916775416044;16815.663686726442;16964.74552881683;38434.6156538431;38472.230555388174;39855.40216896691;40998.79500602478;41549.792251038554;56515.71742141263;58598.7070064647;59732.402675972684;60696.39303606907;61604.383956159865;13110.278934658303;15638.374465021272;15781.21093945321;15923.681526369639;16069.758953615472;15756.76364854551;15936.601584960468;17734.29062837472;18181.363652272106;20465.18139274472;16833.494995150166;17091.829081709024;17099.914500427418;17150.571235719206;17542.03518806427;16210.837891620933;16682.332706691595;16865.24106415239;17327.393541225996;17713.645727085128;16691.08199049064;16941.23764430579;16948.661026779642;17146.142692865058;18086.366977155736;11275.436228188722;12851.485940562272;13033.348332583144;15243;15450.150241736812;15647.530574082795;15667.608309792346;15820.367185312458;16023.439179629124;16411.097389643684 09:56:20 INFO - 2016-02-08 09:56:20,143 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1450.6155868694793;1502.4741340530811;1634.2645809385765;1655.8592519635831;1835.3943198744416;7014.824629384268;7289.198188199294;7291.708331666734;7301.89047164293;7325.6703448344815;6643.169603799525;6768.898466523006;7140.821479463003;7347.522411043292;7421.925780742191;2192.287506560366;2194.36363454598;2219.833512486562;2219.866807991518;2222.1333679864865;1854.1470923375264;2238.2166241815316;2255.593993081253;2309.157157637467;2312.0173926081434;1918.788933217345;2157.094020511381;2223.6998005269324;2223.84433089683;2225.7662945390744;2158.417227348615;2183.301343570058;2202.0201010550313;2212.4468882779306;2217.5786600545844;2195.6706494025866;2221.2780846224878;2231.0964059555904;2244.5286489837154;2252.132928822415;7270.309320614533;7340.706371745124;7344.889826652605;7347.2652734726635;7352.852942941111;4427.512973572904;4482.529334419872;4500.302453119772;4513.052259025609;4516.887077823048;3114.5795317632283;3126.8749250029973;3130.5930229070304;3142.4657808172437;3202.8398580071134;1035.9223058270659;1048.161470823346;1060.7507235799658;1089.8910108989116;1095.1512577752305;9993.550290236957;10024.498775061218;10072.546735396925;10076.143527800146;10087.89912100877;3893.844246230176;3910.2570511602703;3951.3480275754177;3955.0507878109047;3959.742616729903;8142.470739402039;8192.631331590093;8195.71315003972;8204.466709663853;8209.753707388787;4451.532589078165;4462.241418958784;4471.396361491222;4475.530069342737;4478.171538265431;4170.291050521419;4174.373843923375;4174.707770456039;4178.05993651426;4178.247915375262;3295.1432627516538;3315.1049216711353;3324.5678061851804;3325.833708314623;3327.733781297539;985.8028394321165;991.9752006199902;992.9702108936743;999.1956475037664;1020.9846852297073;1113.3876367997732;1116.0792346314324;1157.988420115805;1340.1958824705293;1417.5251290817691;6984.546004509691;7009.54437961541;7050.576965381991;7173.210946795848;7241.348278654947;658.2495954611746;668.5119862500402;670.2292363781593;671.2582596231192;804.8671969125029;5732.283464566929;5773.307203135567;5780.88438231233;5787.218725472091;5787.247657804459;8609.784755381166;8726.12738726135;8803.471791692518;8970.596323165473;8988.10118988096;8707.608157632922;8736.650533978569;8871.911280887238;8885.0670679579;8887.022427532966;6764.797056088325;6787;6854.314568543105;6901.447884169254;6998.965005175043;6858.588484690935;6905.067815844796;6905.37851593359;6913.308669133247;6920.377166055079;7761.068671759425;8036.598170091355;8042.115367309474;8050.235227653364;8057;7959.243871832166;8234.958825205835;8266.256036956704;8274.379421543288;8276.006879174538;6450.48396128318;6472.35276472349;6486.740530378732;6552.770653027121;6556.016597510412;7308.1595616502655;7370.6314684266645;7373.041504604367;7377.225391333833;7393.8521229574035;5895.263092113486;6800.414429688299;6852.349026842442;6949.68726407323;7043.2604576518725;6202.844928876814;6816.7614133505085;6872.896906546506;6954.443644508326;6973.058637083929;6368.745250189941;6583.144191255297;6601.141851559267;6604.174478190226;6814.522983391115;6294.464970477377;6310.6213627182515;6323.841903952438;6324.936750632619;6333.90499142504;6319.020551814476;6362.236531616236;6375.585586936834;6408.551401402044;6551.574147680385 09:56:20 INFO - 2016-02-08 09:56:20,143 DEBUG : BROWSER_OUTPUT: |4;prototype.html;343.55338060521325;345.7493317344924;351.6876536770733;354.27550658902527;357.223039888243;5972.761089556418;6023.397660233974;6063.787767428146;6067.089936509515;6117.143599896018;5779.104238842986;5844.386339434362;5884.146798714184;5980.88038239235;5981.58128930975;4631.62946964242;4680;4688.156131896258;4703.08289883472;4703.200455922484;4678.672492925497;4684.461286951993;4685.531446855304;4689.57793798558;4691.053212916636;4410.911781764363;4481.148581769469;4529.297958816389;4616.099860527199;4625.051864367813;4618.30725391194;4618.976905115486;4621.422322209707;4624.468186158601;4645.256758918574;4065.125997910434;4068.4914385701786;4069.593040695907;4070.61329173728;4075.3886916962692;5703.62926409782;5708.457696518824;5708.800191993302;5716;5723.599348045638;5196.870078248036;5321.946780532184;5335.413104558495;5339.359276886759;5340.065488539529;6344.429001389851;6347.270063942613;6353.983362661952;6355.555111142222;6358.459530939829;4445.533219012022;4449.3326001100095;4451.243288640939;4462.419885414875;4466.731996080245;6458.547901646886;6522.510811689142;6586.901196482057;6587.571807832475;6588.176477940257;4525.072360166158;4525.208088584551;4526.502084770673;4527.162474942146;4534.773261336986;4589.495155532955;4593.012502312019;4593.311003349458;4594.264917613233;4598.885027874263;5302.257683924254;5304.151335786333;5311.097113490716;5313.707746074003;5321.787128514893;6270.404311590391;6402.135711678957;6405.071264666691;6406.743730250738;6413.45485633717;1603.1903888536215;1624.3989723802033;1625.3579835964965;1625.6829918165845;1628.332383722668;1071.0628200324716;1073.3988966178965;1085.880553139154;1088.7931293054453;1090.8800031996475;6198;6198.6280823150755;6200.720967556471;6202.906956395657;6203.31763506014;6542.411182993458;6578.407943285128;6588.571742836701;6590.209174899042;6592.76925307612;6004.279486461653;6101.298350689639;6169.784057557964;6271.122042913996;6274.403931626488;3555;3575.910602234913;3578.5526809148855;3587.0673624857723;3589.8205089745406;6828.078209441662;6835.213950395669;6838.076859623987;6844.589324640538;6844.828879278058;6664.633445160466;6812.727490900308;6820.795376138723;6821.556598821061;6827.6244806536115;6842.794716158523;6852.006459063408;6857.245702972669;6857.759978400732;6863.691133898987;6538.346165383424;6648.5346025777735;6650.035744817072;6652.634105124264;6654.134962455044;6516.609003459807;6547.803565893031;6560.639164845979;6565.671716414256;6570.737170513126;6833.726650934106;6841.281665425058;6844.691988860613;6845.383915447634;6846.828829279308;4595.747233902168;4596.149712303235;4598.425196850394;4602.125596136723;4603.240465323184;6522.836929076811;6634.568753031038;6637.070810086689;6646.202455705346;6646.767363142267;6465.482761379172;6474.805755827333;6475.7733479328;6479.902801458076;6485.967570162119;6367.93632063692;6458.321876203119;6463.450606698295;6468.902966455601;6470.611763294218;6342.873142537216;6366.64983425916;6369.522285828489;6376.426121649074;6377.362263773585;6472.773452929125;6473.805785826433;6477.676116194266;6478.6760661967655;6490.350964903472;6182.103594978793;6277.811665650038;6290.591111577733;6293.024581189739;6295.622262664255 09:56:20 INFO - 2016-02-08 09:56:20,146 DEBUG : BROWSER_OUTPUT: |5;yui.html;742.0316486984483;753.355880721983;900.4282280751722;901.2114399900088;904.990950090499;12925.418356173972;13155.802662960063;13159.07886447949;13165.078444508876;13167.670808229776;10872.402017889033;10879.83680244797;10885.292455990375;10901.509432075556;10918;738.8005238585579;746.5669911451345;859.2653281444369;864.4381152251025;883.1168831168832;877.5217506458963;884.1379654836547;887.4896934262824;893.5710858787786;895.7491902267376;918.6463211663524;929.7815013471828;933.3326671429926;933.6405483888684;934.219926361489;755.2787088330658;765.2003656179306;859.7635650196183;881.4490943160524;881.7354793561906;669.1434963247052;672.7309076369443;675.4765057080753;675.8715843989626;676.2392308652766;12747.490100395973;12811.935940320333;12817.102802803807;12818.61544153677;12855.807162892565;9985.051420115078;11945.40272986356;12329.93835030828;12338.321392323418;12984.480620775157;9862.556184971694;10069.748256293578;10169.440680762555;10215.387076775418;10273.383596984206;3852.364359880616;3872.399778034381;3872.593377695358;3880.049387899954;3880.825362858678;47371;47869;48292.7585362071;49138.508614914106;49659.751701241265;10435.791284174275;10447.850736418988;10588;10636.627718029831;10647.520861561248;19180.904095479433;19192.616147676967;19204.327848525234;19209.615807684047;19278.71081933772;7997.480163789335;8129.593520323961;8172;8182.877256841152;8373.162683731578;4425.247707889666;4461.330800379904;4501.167284052461;4508.594226519564;4574.382458368144;3156.71589556941;3163.7469002479747;3169.5721077654684;3179.7933134346194;3181.650018497963;540.4973374761431;556.9136783798517;558.5336244236028;561.8763871948165;567.7530274330679;593.5666963116862;644.5584774429437;647.5952529668957;659.953803233769;666.2271764752863;8461.40770146084;8494.405391622651;8537.658493660185;8621.2671922886;8708.869366959501;565.6634302589953;591.7899145803264;592.2685483427963;625.602742258669;706.8268274272733;4064.227796718614;4191.580841915845;4215.894602634898;4239.215745087169;4262.616364527207;59629.403705963254;59679.403205967385;60049.699751500964;60183.097253540356;60618.393816062155;52463.475365246624;52821.2076818848;52884.206736898974;52948.735256323474;53402;48141.5185848144;48163.518364816606;48481.272780908315;48783.75608121937;49123.75438122858;8023.759287221393;8075.798105047421;8169.645958848968;8227.382946279173;8242.464239824392;35831.10422239465;36048.81975590105;36207.818960905024;36304.63695363013;36666.63333366632;41524.79237603793;43316.78341608272;46955.76522117368;47027.76486117548;47225.7638711818;10764.300320479144;10783.6225732099;11089.542334775995;11585.246958947642;11811.114166437379;11524.769504609692;11549.6535103947;11843.407829608657;12090.153689241668;12267.325297108388;10111.747206319607;10232.23258255619;10360.326578772356;10449.9477502612;10854.131669466266;10605.681829545127;10939.890601094206;10973.83539246896;11247.718807029889;11502.367369794742;9959.402435853872;10234.283600147919;10306.33008854422;10669.46652667348;10686.73283167927;9674.22606191489;9747.122758951728;9807.803843922939;9853.458059806493;10071.194304455772;8754.91245087541;8779.341549383695;8835.20483156519;8859.610627942366;8888.200061994452 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,863 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: __startTimestamp1454954180133__endTimestamp 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:56:20 INFO - 2016-02-08 09:56:20,147 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5430.02 Median:5588.30 stddev:586.59 (10.5%) stddev-sans-first:562.96 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: Values: 3252.0 3294.1 3357.6 3359.3 3376.4 5492.3 5508.8 5523.5 5530.7 5540.5 5498.1 5524.4 5533.8 5536.4 5540.4 4824.2 4951.4 5030.5 5051.9 5137.7 4912.1 4927.2 4967.0 4971.3 4972.5 4938.0 4979.4 5045.7 5058.6 5089.9 5032.6 5062.2 5063.4 5064.3 5094.2 4742.5 4960.7 4961.4 5014.0 5014.5 5159.0 5187.5 5229.5 5232.4 5244.7 5884.9 5898.8 5904.7 5939.9 5946.6 5696.6 5789.2 5938.2 5954.2 5961.1 5471.8 5542.1 5543.9 5575.0 5586.9 5997.3 6000.4 6027.0 6033.0 6039.0 5571.0 5675.4 5784.8 5827.6 5847.4 5691.3 5780.3 5780.6 5783.3 5819.9 5794.8 5828.5 5854.6 5854.7 5900.8 5557.7 5644.1 5702.3 5836.5 5881.9 4481.8 4507.2 4516.5 4517.2 4518.0 4055.3 4060.9 4064.8 4065.5 4087.0 5324.1 5330.2 5424.1 5501.2 5510.0 5861.4 5866.5 5890.4 5895.7 5896.3 5520.7 5547.1 5550.0 5551.4 5551.7 4872.6 5020.8 5063.8 5095.2 5095.9 6016.5 6026.4 6036.3 6039.8 6051.8 5883.9 5892.1 5898.1 5898.1 5902.6 5747.9 5858.8 5909.3 6033.7 6036.5 5672.9 5703.1 5705.1 5705.5 5792.6 5779.9 5787.5 5788.1 5791.0 5800.4 5459.2 5598.4 5683.8 5769.3 5792.5 5724.8 5782.4 5787.3 5800.4 5800.5 5441.2 5658.6 5697.6 5712.3 5722.0 5693.5 5699.1 5757.1 5757.2 5797.1 5553.4 5564.1 5586.6 5588.3 5588.6 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49728.02 Median:35026.65 stddev:84668.42 (241.7%) stddev-sans-first:84825.50 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: Values: 575.1 601.4 612.2 613.5 652.9 32811.2 33636.3 33643.2 33653.0 33679.8 34714.1 39569.0 39926.4 39929.8 39961.2 9018.7 9175.0 9178.2 9178.7 9179.7 8370.8 8565.7 8865.1 8983.7 8986.2 10835.4 11087.0 11098.8 11100.2 11100.8 10584.7 10705.1 10708.5 10708.6 10711.7 10506.4 10617.3 10634.1 10637.6 10639.7 41236.6 41621.8 41628.8 41637.6 41701.4 3547.5 3598.4 3969.3 4271.2 4332.4 32194.5 32426.2 32435.4 32479.4 33572.5 4133.9 4198.6 4238.3 4310.5 4327.8 293822.0 294729.0 295816.0 296207.0 296278.5 59158.4 59788.4 59813.4 65751.0 66017.7 77016.0 78590.6 78799.6 78896.2 79042.0 18240.8 18412.7 18432.4 18436.1 18440.8 14006.4 14070.4 14072.9 14073.4 14094.9 21676.6 21924.9 22015.8 22098.3 22112.9 7860.2 8159.2 8165.0 8222.7 8340.2 964.0 998.2 1037.7 1051.4 1124.9 29418.4 29701.6 30418.4 30496.1 30744.0 1090.6 1188.6 1194.2 1214.3 1276.4 14942.7 15798.8 16036.3 16997.5 17966.4 415776.0 421793.0 422237.0 424354.0 425571.0 58097.4 58728.4 58813.0 58871.4 59075.1 1441.4 1442.8 1463.8 1465.3 1472.6 31167.8 31223.4 31671.2 31707.0 31988.0 46988.0 47690.5 49994.8 51161.0 51314.7 212060.0 212103.0 212402.0 212978.0 213237.0 31848.7 32144.8 32222.4 32249.4 32253.4 41124.6 41694.4 41905.0 42463.6 42859.8 38421.4 39663.6 44602.6 44922.0 46608.8 38765.8 39076.8 39194.4 39316.5 40330.4 39142.0 44177.6 44244.3 44801.0 45261.3 34222.3 35007.0 35046.3 35089.5 35183.8 26825.2 26896.5 26979.5 27047.5 27301.7 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,148 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:35176.22 Median:21456.13 stddev:114769.25 (534.9%) stddev-sans-first:115061.47 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: Values: 378.2 383.5 384.7 388.5 390.1 15197.7 15570.8 17190.7 18584.4 18621.3 15011.6 18120.2 18467.5 18518.6 18559.0 5854.8 5987.6 6016.8 6062.5 6063.1 6008.9 6025.2 6041.5 6044.0 6044.2 5669.6 5803.4 5842.4 5845.9 5848.9 5288.7 5299.1 5301.9 5480.2 5784.6 5330.0 5388.2 5395.1 5405.2 5405.7 21004.6 21907.7 21908.1 21994.7 22738.7 2306.7 2377.0 2428.2 2465.3 2474.5 10372.9 10403.3 11817.4 12341.0 13386.6 1768.5 1808.5 1856.2 1924.1 1954.9 61376.7 61832.0 62892.7 63332.7 71979.6 10145.1 10346.9 10428.9 10473.3 10490.9 14129.7 14169.1 14200.2 14202.0 14209.1 7814.3 7929.6 7932.2 7978.2 8547.2 8163.0 8172.3 8186.0 8213.6 8229.3 3914.2 4032.8 4112.4 4330.3 4625.2 1763.8 1825.9 1838.7 1889.7 1916.6 970.5 974.6 983.9 1010.6 1088.6 17391.1 17831.6 17874.6 17876.5 18294.5 989.3 998.9 1019.6 1044.4 1069.1 9372.0 9462.3 9614.3 9657.5 9775.4 683231.0 690806.0 691149.0 698930.0 748816.5 88274.0 89828.6 90035.0 91230.0 93878.0 1150.0 1183.2 1185.7 1193.7 1204.9 16605.6 16616.3 16644.9 16815.7 16964.7 38434.6 38472.2 39855.4 40998.8 41549.8 56515.7 58598.7 59732.4 60696.4 61604.4 13110.3 15638.4 15781.2 15923.7 16069.8 15756.8 15936.6 17734.3 18181.4 20465.2 16833.5 17091.8 17099.9 17150.6 17542.0 16210.8 16682.3 16865.2 17327.4 17713.6 16691.1 16941.2 16948.7 17146.1 18086.4 11275.4 12851.5 13033.3 15243.0 15450.2 15647.5 15667.6 15820.4 16023.4 16411.1 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5197.15 Median:6392.07 stddev:2662.46 (41.7%) stddev-sans-first:2655.03 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: Values: 1450.6 1502.5 1634.3 1655.9 1835.4 7014.8 7289.2 7291.7 7301.9 7325.7 6643.2 6768.9 7140.8 7347.5 7421.9 2192.3 2194.4 2219.8 2219.9 2222.1 1854.1 2238.2 2255.6 2309.2 2312.0 1918.8 2157.1 2223.7 2223.8 2225.8 2158.4 2183.3 2202.0 2212.4 2217.6 2195.7 2221.3 2231.1 2244.5 2252.1 7270.3 7340.7 7344.9 7347.3 7352.9 4427.5 4482.5 4500.3 4513.1 4516.9 3114.6 3126.9 3130.6 3142.5 3202.8 1035.9 1048.2 1060.8 1089.9 1095.2 9993.6 10024.5 10072.5 10076.1 10087.9 3893.8 3910.3 3951.3 3955.1 3959.7 8142.5 8192.6 8195.7 8204.5 8209.8 4451.5 4462.2 4471.4 4475.5 4478.2 4170.3 4174.4 4174.7 4178.1 4178.2 3295.1 3315.1 3324.6 3325.8 3327.7 985.8 992.0 993.0 999.2 1021.0 1113.4 1116.1 1158.0 1340.2 1417.5 6984.5 7009.5 7050.6 7173.2 7241.3 658.2 668.5 670.2 671.3 804.9 5732.3 5773.3 5780.9 5787.2 5787.2 8609.8 8726.1 8803.5 8970.6 8988.1 8707.6 8736.7 8871.9 8885.1 8887.0 6764.8 6787.0 6854.3 6901.4 6999.0 6858.6 6905.1 6905.4 6913.3 6920.4 7761.1 8036.6 8042.1 8050.2 8057.0 7959.2 8235.0 8266.3 8274.4 8276.0 6450.5 6472.4 6486.7 6552.8 6556.0 7308.2 7370.6 7373.0 7377.2 7393.9 5895.3 6800.4 6852.3 6949.7 7043.3 6202.8 6816.8 6872.9 6954.4 6973.1 6368.7 6583.1 6601.1 6604.2 6814.5 6294.5 6310.6 6323.8 6324.9 6333.9 6319.0 6362.2 6375.6 6408.6 6551.6 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5362.68 Median:6109.22 stddev:1612.03 (26.4%) stddev-sans-first:1571.92 09:56:20 INFO - 2016-02-08 09:56:20,150 DEBUG : BROWSER_OUTPUT: Values: 343.6 345.7 351.7 354.3 357.2 5972.8 6023.4 6063.8 6067.1 6117.1 5779.1 5844.4 5884.1 5980.9 5981.6 4631.6 4680.0 4688.2 4703.1 4703.2 4678.7 4684.5 4685.5 4689.6 4691.1 4410.9 4481.1 4529.3 4616.1 4625.1 4618.3 4619.0 4621.4 4624.5 4645.3 4065.1 4068.5 4069.6 4070.6 4075.4 5703.6 5708.5 5708.8 5716.0 5723.6 5196.9 5321.9 5335.4 5339.4 5340.1 6344.4 6347.3 6354.0 6355.6 6358.5 4445.5 4449.3 4451.2 4462.4 4466.7 6458.5 6522.5 6586.9 6587.6 6588.2 4525.1 4525.2 4526.5 4527.2 4534.8 4589.5 4593.0 4593.3 4594.3 4598.9 5302.3 5304.2 5311.1 5313.7 5321.8 6270.4 6402.1 6405.1 6406.7 6413.5 1603.2 1624.4 1625.4 1625.7 1628.3 1071.1 1073.4 1085.9 1088.8 1090.9 6198.0 6198.6 6200.7 6202.9 6203.3 6542.4 6578.4 6588.6 6590.2 6592.8 6004.3 6101.3 6169.8 6271.1 6274.4 3555.0 3575.9 3578.6 3587.1 3589.8 6828.1 6835.2 6838.1 6844.6 6844.8 6664.6 6812.7 6820.8 6821.6 6827.6 6842.8 6852.0 6857.2 6857.8 6863.7 6538.3 6648.5 6650.0 6652.6 6654.1 6516.6 6547.8 6560.6 6565.7 6570.7 6833.7 6841.3 6844.7 6845.4 6846.8 4595.7 4596.1 4598.4 4602.1 4603.2 6522.8 6634.6 6637.1 6646.2 6646.8 6465.5 6474.8 6475.8 6479.9 6486.0 6367.9 6458.3 6463.5 6468.9 6470.6 6342.9 6366.6 6369.5 6376.4 6377.4 6472.8 6473.8 6477.7 6478.7 6490.4 6182.1 6277.8 6290.6 6293.0 6295.6 09:56:20 INFO - 2016-02-08 09:56:20,151 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,151 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13914.73 Median:48152.52 stddev:16489.09 (34.2%) stddev-sans-first:16505.67 09:56:20 INFO - 2016-02-08 09:56:20,152 DEBUG : BROWSER_OUTPUT: Values: 742.0 753.4 900.4 901.2 905.0 12925.4 13155.8 13159.1 13165.1 13167.7 10872.4 10879.8 10885.3 10901.5 10918.0 738.8 746.6 859.3 864.4 883.1 877.5 884.1 887.5 893.6 895.7 918.6 929.8 933.3 933.6 934.2 755.3 765.2 859.8 881.4 881.7 669.1 672.7 675.5 675.9 676.2 12747.5 12811.9 12817.1 12818.6 12855.8 9985.1 11945.4 12329.9 12338.3 12984.5 9862.6 10069.7 10169.4 10215.4 10273.4 3852.4 3872.4 3872.6 3880.0 3880.8 47371.0 47869.0 48292.8 49138.5 49659.8 10435.8 10447.9 10588.0 10636.6 10647.5 19180.9 19192.6 19204.3 19209.6 19278.7 7997.5 8129.6 8172.0 8182.9 8373.2 4425.2 4461.3 4501.2 4508.6 4574.4 3156.7 3163.7 3169.6 3179.8 3181.7 540.5 556.9 558.5 561.9 567.8 593.6 644.6 647.6 660.0 666.2 8461.4 8494.4 8537.7 8621.3 8708.9 565.7 591.8 592.3 625.6 706.8 4064.2 4191.6 4215.9 4239.2 4262.6 59629.4 59679.4 60049.7 60183.1 60618.4 52463.5 52821.2 52884.2 52948.7 53402.0 48141.5 48163.5 48481.3 48783.8 49123.8 8023.8 8075.8 8169.6 8227.4 8242.5 35831.1 36048.8 36207.8 36304.6 36666.6 41524.8 43316.8 46955.8 47027.8 47225.8 10764.3 10783.6 11089.5 11585.2 11811.1 11524.8 11549.7 11843.4 12090.2 12267.3 10111.7 10232.2 10360.3 10449.9 10854.1 10605.7 10939.9 10973.8 11247.7 11502.4 9959.4 10234.3 10306.3 10669.5 10686.7 9674.2 9747.1 9807.8 9853.5 10071.2 8754.9 8779.3 8835.2 8859.6 8888.2 09:56:20 INFO - 2016-02-08 09:56:20,152 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:56:20 INFO - 2016-02-08 09:56:20,152 DEBUG : BROWSER_OUTPUT: 09:56:20 INFO - 2016-02-08 09:56:20,739 INFO : Browser exited with error code: 0 09:56:20 INFO - 2016-02-08 09:56:20,755 INFO : Completed test dromaeo_css (00:18:29) 09:56:20 INFO - 2016-02-08 09:56:20,755 INFO : Starting test kraken 09:56:20 INFO - 2016-02-08 09:56:20,755 DEBUG : operating with platform_type : linux_ 09:56:20 INFO - 2016-02-08 09:56:20,755 INFO : Initialising browser for kraken test... 09:56:20 INFO - 2016-02-08 09:56:20,761 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuBLDRF/profile http://localhost:40194/getInfo.html 09:56:24 INFO - 2016-02-08 09:56:24,639 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:56:24 INFO - 2016-02-08 09:56:24,639 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:56:24 INFO - 2016-02-08 09:56:24,639 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:56:24 INFO - 2016-02-08 09:56:24,640 DEBUG : BROWSER_OUTPUT: __metrics 09:56:24 INFO - 2016-02-08 09:56:24,776 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:25 INFO - 2016-02-08 09:56:25,531 INFO : Browser initialized. 09:56:25 INFO - 2016-02-08 09:56:25,532 INFO : Running cycle 1/1 for kraken test... 09:56:25 INFO - 2016-02-08 09:56:25,532 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuBLDRF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:56:37 INFO - 2016-02-08 09:56:37,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 134529024 09:56:37 INFO - 2016-02-08 09:56:37,276 DEBUG : BROWSER_OUTPUT: 09:56:39 INFO - 2016-02-08 09:56:39,711 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40194/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:40 INFO - 2016-02-08 09:56:40,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 164315136 09:56:40 INFO - 2016-02-08 09:56:40,293 DEBUG : BROWSER_OUTPUT: 09:56:42 INFO - 2016-02-08 09:56:42,850 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40194/tests/kraken/driver.html?testName=audio-dft) 09:56:43 INFO - 2016-02-08 09:56:43,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 162840576 09:56:43 INFO - 2016-02-08 09:56:43,415 DEBUG : BROWSER_OUTPUT: 09:56:46 INFO - 2016-02-08 09:56:46,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40194/tests/kraken/driver.html?testName=audio-fft) 09:56:46 INFO - 2016-02-08 09:56:46,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 160780288 09:56:46 INFO - 2016-02-08 09:56:46,796 DEBUG : BROWSER_OUTPUT: 09:56:48 INFO - 2016-02-08 09:56:48,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40194/tests/kraken/driver.html?testName=audio-oscillator) 09:56:49 INFO - 2016-02-08 09:56:49,339 DEBUG : BROWSER_OUTPUT: RSS: Main: 158633984 09:56:49 INFO - 2016-02-08 09:56:49,339 DEBUG : BROWSER_OUTPUT: 09:56:51 INFO - 2016-02-08 09:56:51,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40194/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:52 INFO - 2016-02-08 09:56:52,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 159125504 09:56:52 INFO - 2016-02-08 09:56:52,052 DEBUG : BROWSER_OUTPUT: 09:56:54 INFO - 2016-02-08 09:56:54,882 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40194/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:55 INFO - 2016-02-08 09:56:55,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 158781440 09:56:55 INFO - 2016-02-08 09:56:55,449 DEBUG : BROWSER_OUTPUT: 09:56:58 INFO - 2016-02-08 09:56:58,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40194/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:59 INFO - 2016-02-08 09:56:59,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 159916032 09:56:59 INFO - 2016-02-08 09:56:59,357 DEBUG : BROWSER_OUTPUT: 09:57:02 INFO - 2016-02-08 09:57:02,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40194/tests/kraken/driver.html?testName=json-parse-financial) 09:57:02 INFO - 2016-02-08 09:57:02,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 158531584 09:57:02 INFO - 2016-02-08 09:57:02,601 DEBUG : BROWSER_OUTPUT: 09:57:04 INFO - 2016-02-08 09:57:04,155 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40194/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:04 INFO - 2016-02-08 09:57:04,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 166633472 09:57:04 INFO - 2016-02-08 09:57:04,716 DEBUG : BROWSER_OUTPUT: 09:57:06 INFO - 2016-02-08 09:57:06,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:07 INFO - 2016-02-08 09:57:07,064 DEBUG : BROWSER_OUTPUT: RSS: Main: 164679680 09:57:07 INFO - 2016-02-08 09:57:07,064 DEBUG : BROWSER_OUTPUT: 09:57:09 INFO - 2016-02-08 09:57:09,019 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:09 INFO - 2016-02-08 09:57:09,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 168951808 09:57:09 INFO - 2016-02-08 09:57:09,584 DEBUG : BROWSER_OUTPUT: 09:57:11 INFO - 2016-02-08 09:57:11,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:12 INFO - 2016-02-08 09:57:12,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 174280704 09:57:12 INFO - 2016-02-08 09:57:12,513 DEBUG : BROWSER_OUTPUT: 09:57:14 INFO - 2016-02-08 09:57:14,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:15 INFO - 2016-02-08 09:57:15,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 171745280 09:57:15 INFO - 2016-02-08 09:57:15,547 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,215 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40194/tests/kraken/driver.html?testName=ai-astar) 09:57:17 INFO - 2016-02-08 09:57:17,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 230395904 09:57:17 INFO - 2016-02-08 09:57:17,462 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,463 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:57:17 INFO - 2016-02-08 09:57:17,463 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:57:17 INFO - 2016-02-08 09:57:17,464 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:57:17 INFO - 2016-02-08 09:57:17,464 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:57:17 INFO - 2016-02-08 09:57:17,464 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;113;105;110;111;110;101;112;111;112 09:57:17 INFO - 2016-02-08 09:57:17,464 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;123;147;159;129;144;161;125;164;124 09:57:17 INFO - 2016-02-08 09:57:17,465 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;158;159;159;159;159;160;161;159;161 09:57:17 INFO - 2016-02-08 09:57:17,465 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;73;149;75;71;71;71;155;72 09:57:17 INFO - 2016-02-08 09:57:17,465 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;138;92;92;92;138;92;92;132 09:57:17 INFO - 2016-02-08 09:57:17,466 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;109;109;109;109;109 09:57:17 INFO - 2016-02-08 09:57:17,466 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;145;146;147;146;145;145;145 09:57:17 INFO - 2016-02-08 09:57:17,466 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;94;96;95;95;95;95;95;96;96;96 09:57:17 INFO - 2016-02-08 09:57:17,466 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;58;57;58;56;56;58;54;53 09:57:17 INFO - 2016-02-08 09:57:17,467 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;64;64;65;64;64;65;64;64 09:57:17 INFO - 2016-02-08 09:57:17,467 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;77;77;78;77;85;78;79;78 09:57:17 INFO - 2016-02-08 09:57:17,467 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;115;114;111;112;111;120;112;115;129 09:57:17 INFO - 2016-02-08 09:57:17,467 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;143;141;143;141;135;143;144;140;142 09:57:17 INFO - 2016-02-08 09:57:17,468 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;66;66;64;64;65;64;64;65 09:57:17 INFO - 2016-02-08 09:57:17,468 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:57:17 INFO - 2016-02-08 09:57:17,468 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:57:17 INFO - 2016-02-08 09:57:17,468 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,902 09:57:17 INFO - 2016-02-08 09:57:17,469 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:57:17 INFO - 2016-02-08 09:57:17,469 DEBUG : BROWSER_OUTPUT: __startTimestamp1454954237462__endTimestamp 09:57:17 INFO - 2016-02-08 09:57:17,469 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:3.69 (3.3%) stddev-sans-first:3.91 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: Values: 109.0 113.0 105.0 110.0 111.0 110.0 101.0 112.0 111.0 112.0 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.20 Median:146.50 stddev:16.06 (11.0%) stddev-sans-first:16.98 09:57:17 INFO - 2016-02-08 09:57:17,471 DEBUG : BROWSER_OUTPUT: Values: 146.0 123.0 147.0 159.0 129.0 144.0 161.0 125.0 164.0 124.0 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.70 Median:159.50 stddev:1.25 (0.8%) stddev-sans-first:1.01 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: Values: 162.0 158.0 159.0 159.0 159.0 159.0 160.0 161.0 159.0 161.0 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:71.00 stddev:33.95 (47.8%) stddev-sans-first:35.40 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 73.0 149.0 75.0 71.0 71.0 71.0 155.0 72.0 09:57:17 INFO - 2016-02-08 09:57:17,472 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.20 Median:92.00 stddev:21.32 (23.2%) stddev-sans-first:22.07 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 138.0 92.0 92.0 92.0 138.0 92.0 92.0 132.0 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 09:57:17 INFO - 2016-02-08 09:57:17,473 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 145.0 146.0 147.0 146.0 145.0 145.0 145.0 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.53 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: Values: 94.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.69 (3.0%) stddev-sans-first:1.79 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 58.0 57.0 58.0 56.0 56.0 58.0 54.0 53.0 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 09:57:17 INFO - 2016-02-08 09:57:17,474 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 64.0 64.0 65.0 64.0 64.0 65.0 64.0 64.0 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:2.41 (3.1%) stddev-sans-first:2.55 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 77.0 77.0 78.0 77.0 85.0 78.0 79.0 78.0 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:115.00 stddev:5.50 (4.8%) stddev-sans-first:5.81 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: Values: 117.0 115.0 114.0 111.0 112.0 111.0 120.0 112.0 115.0 129.0 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.60 Median:142.50 stddev:3.44 (2.4%) stddev-sans-first:2.69 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: Values: 134.0 143.0 141.0 143.0 141.0 135.0 143.0 144.0 140.0 142.0 09:57:17 INFO - 2016-02-08 09:57:17,475 DEBUG : BROWSER_OUTPUT: 09:57:17 INFO - 2016-02-08 09:57:17,476 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 09:57:17 INFO - 2016-02-08 09:57:17,476 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 66.0 66.0 64.0 64.0 65.0 64.0 64.0 65.0 09:57:17 INFO - 2016-02-08 09:57:17,476 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:57:17 INFO - 2016-02-08 09:57:17,476 DEBUG : BROWSER_OUTPUT: 09:57:18 INFO - 2016-02-08 09:57:18,143 INFO : Browser exited with error code: 0 09:57:18 INFO - 2016-02-08 09:57:18,193 INFO : Completed test kraken (00:00:57) 09:57:18 INFO - 2016-02-08 09:57:18,194 INFO : Starting test v8_7 09:57:18 INFO - 2016-02-08 09:57:18,194 DEBUG : operating with platform_type : linux_ 09:57:18 INFO - 2016-02-08 09:57:18,194 INFO : Initialising browser for v8_7 test... 09:57:18 INFO - 2016-02-08 09:57:18,200 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV_XZDL/profile http://localhost:40194/getInfo.html 09:57:21 INFO - 2016-02-08 09:57:21,781 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:57:21 INFO - 2016-02-08 09:57:21,781 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:57:21 INFO - 2016-02-08 09:57:21,781 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:57:21 INFO - 2016-02-08 09:57:21,781 DEBUG : BROWSER_OUTPUT: __metrics 09:57:21 INFO - 2016-02-08 09:57:21,944 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:22 INFO - 2016-02-08 09:57:22,920 INFO : Browser initialized. 09:57:22 INFO - 2016-02-08 09:57:22,921 INFO : Running cycle 1/1 for v8_7 test... 09:57:22 INFO - 2016-02-08 09:57:22,921 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV_XZDL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:57:34 INFO - 2016-02-08 09:57:34,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 134754304 09:57:34 INFO - 2016-02-08 09:57:34,704 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40194/tests/v8_7/run.html (next: http://localhost:40194/tests/v8_7/run.html) 09:57:56 INFO - 2016-02-08 09:57:56,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 426790912 09:57:56 INFO - 2016-02-08 09:57:56,320 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,320 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:57:56 INFO - 2016-02-08 09:57:56,320 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |0;Richards;171.6148961729878 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.93485073396008 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 09:57:56 INFO - 2016-02-08 09:57:56,321 DEBUG : BROWSER_OUTPUT: |4;RayTrace;936.3295880149813 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: |5;Earley;545.5537370430987 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: |6;Boyer;10536.842105263158 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: |7;RegExp;24357.14285714286 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: |8;Splay;530.471648118707 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:57:56 INFO - 2016-02-08 09:57:56,322 DEBUG : BROWSER_OUTPUT: __startTimestamp1454954276313__endTimestamp 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.61 Median:171.61 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: Values: 171.6 09:57:56 INFO - 2016-02-08 09:57:56,323 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.93 Median:164.93 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: Values: 164.9 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: Values: 278.4 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,324 DEBUG : BROWSER_OUTPUT: Values: 5191.7 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:936.33 Median:936.33 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: Values: 936.3 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:545.55 Median:545.55 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: Values: 545.6 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,325 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10536.84 Median:10536.84 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: Values: 10536.8 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24357.14 Median:24357.14 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: Values: 24357.1 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:530.47 Median:530.47 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: Values: 530.5 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,326 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 09:57:56 INFO - 2016-02-08 09:57:56,327 DEBUG : BROWSER_OUTPUT: Values: 4830.9 09:57:56 INFO - 2016-02-08 09:57:56,327 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:57:56 INFO - 2016-02-08 09:57:56,327 DEBUG : BROWSER_OUTPUT: 09:57:56 INFO - 2016-02-08 09:57:56,995 INFO : Browser exited with error code: 0 09:57:57 INFO - 2016-02-08 09:57:57,011 INFO : Completed test v8_7 (00:00:38) 09:57:57 INFO - 2016-02-08 09:57:57,355 INFO : Completed test suite (00:20:06) 09:57:57 INFO - 2016-02-08 09:57:57,355 DEBUG : Working with test: dromaeo_css 09:57:57 INFO - 2016-02-08 09:57:57,355 DEBUG : Generating results file: dromaeo_css 09:57:57 INFO - 2016-02-08 09:57:57,356 DEBUG : Working with test: kraken 09:57:57 INFO - 2016-02-08 09:57:57,356 DEBUG : Generating results file: kraken 09:57:57 INFO - 2016-02-08 09:57:57,357 INFO : javascript benchmark 09:57:57 INFO - 2016-02-08 09:57:57,357 DEBUG : Working with test: v8_7 09:57:57 INFO - 2016-02-08 09:57:57,357 DEBUG : Generating results file: v8_7 09:57:57 INFO - 2016-02-08 09:57:57,357 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:57:57 INFO - 2016-02-08 09:57:57,413 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:57:57 INFO - 2016-02-08 09:57:57,413 DEBUG : process_Request line: dromaeo_css 7202.05 graph.html#tests=[[72,53,35]] 09:57:57 INFO - 2016-02-08 09:57:57,413 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:57:57 INFO - 2016-02-08 09:57:57,449 DEBUG : process_Request line: kraken 1474.00 graph.html#tests=[[232,53,35]] 09:57:57 INFO - 2016-02-08 09:57:57,449 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:57:57 INFO - 2016-02-08 09:57:57,478 DEBUG : process_Request line: v8_7 23205.62 graph.html#tests=[[230,53,35]] 09:57:57 INFO - 2016-02-08 09:57:57,479 INFO : javascript benchmark 09:57:57 INFO - 2016-02-08 09:57:57,483 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3252.0, 3294.1105901406604, 3357.6138744044442, 3359.2609625882315, 3376.4428869236576, 5492.286002819638, 5508.834734957953, 5523.502884740377, 5530.695811730353, 5540.50135487806, 5498.092814685572, 5524.392316845154, 5533.778648854041, 5536.4186760390085, 5540.445955404468, 4824.155772739757, 4951.3810773653295, 5030.522100400474, 5051.924221136667, 5137.6660517066275, 4912.0667073256145, 4927.236278376839, 4966.950330496703, 4971.27916452116, 4972.527609877074, 4937.950620493803, 4979.427365852937, 5045.672031317971, 5058.645894787366, 5089.872753181163, 5032.6477146599755, 5062.164742817431, 5063.443021267639, 5064.265681476202, 5094.1849304111165, 4742.478327383985, 4960.6793648895455, 4961.404631444214, 5014.0, 5014.548690617826, 5159.0, 5187.455317191716, 5229.529342359206, 5232.372115346183, 5244.711540865251, 5884.941150588482, 5898.793542226044, 5904.704764761787, 5939.940600594025, 5946.5540084493405, 5696.629719068247, 5789.247397838296, 5938.198343223653, 5954.166416701665, 5961.105834124873, 5471.835844924659, 5542.057850165482, 5543.944560554383, 5575.0, 5586.91619625706, 5997.250343707036, 6000.429959153874, 6026.969865150734, 6033.0, 6038.969805150946, 5571.0, 5675.3757086720425, 5784.82645520635, 5827.562932780059, 5847.356790753014, 5691.260136182353, 5780.335261444903, 5780.5953583248765, 5783.334916484658, 5819.94180058194, 5794.826155215266, 5828.53371730269, 5854.560907931922, 5854.7072646367515, 5900.793472228452, 5557.749901254453, 5644.096944488862, 5702.258706368146, 5836.503897168704, 5881.852953676193, 4481.843135490242, 4507.188706032945, 4516.503184649686, 4517.164324599894, 4518.0, 4055.270051390778, 4060.878173654795, 4064.7561146331313, 4065.5324637667045, 4087.0, 5324.068288049653, 5330.227117068081, 5424.05079111154, 5501.174823776466, 5509.972450137724, 5861.413858614104, 5866.530677545786, 5890.440408161217, 5895.7052147393315, 5896.3219229788265, 5520.723963801794, 5547.11246200614, 5549.972250138723, 5551.417101204315, 5551.666899986014, 4872.634552408513, 5020.799168033238, 5063.797448102108, 5095.184770436712, 5095.949040509621, 6016.488598469092, 6026.367231440723, 6036.3360030396625, 6039.818805435844, 6051.818445446644, 5883.882322353529, 5892.057270836645, 5898.1152827076285, 5898.144769008384, 5902.586818922806, 5747.913781293368, 5858.8242352729485, 5909.2908850938165, 6033.698315084316, 6036.486898613667, 5672.943270567242, 5703.08750599902, 5705.0871860503785, 5705.543556515552, 5792.565557583114, 5779.913301300568, 5787.479126878433, 5788.102844059177, 5791.0, 5800.390958949419, 5459.153831156177, 5598.412166722435, 5683.772649094155, 5769.307683078057, 5792.507636850914, 5724.8282551523525, 5782.392848750989, 5787.305523337226, 5800.39095894925, 5800.535957123336, 5441.238226648352, 5658.632188907708, 5697.601167918372, 5712.343080545625, 5721.970045391703, 5693.5160511357, 5699.145128230799, 5757.107648314518, 5757.165211044291, 5797.130430435468, 5553.389127196086, 5564.054110801255, 5586.608937374344, 5588.30146231721, 5588.552915766811], "unit": "ms", "name": "dojo.html", "value": 5393.329747968561}, {"lowerIsBetter": false, "replicates": [575.0728790456575, 601.4406601860268, 612.1980205930232, 613.5091926458838, 652.8661624367003, 32811.179720506996, 33636.32727345452, 33643.15892102699, 33653.0, 33679.83160084199, 34714.13214669634, 39569.0, 39926.401103983466, 39929.8003509982, 39961.20077598454, 9018.684346047907, 9175.0, 9178.219851312613, 9178.678746243882, 9179.72460826173, 8370.790730231733, 8565.743027709179, 8865.113488651115, 8983.685571005017, 8986.236169925563, 10835.404052777094, 11087.0, 11098.778024439507, 11100.222984391095, 11100.777984440307, 10584.682459526188, 10705.090067344285, 10708.46457677113, 10708.625198118028, 10711.732206694856, 10506.369617822878, 10617.256792024562, 10634.096101831276, 10637.627683031134, 10639.734006649818, 41236.58763412358, 41621.79189104065, 41628.79185604083, 41637.58362416367, 41701.37447938283, 3547.5210846535647, 3598.3882739934274, 3969.325214713506, 4271.209826182167, 4332.415123958257, 32194.517082243787, 32426.18934526632, 32435.35129297401, 32479.350412991844, 33572.49641255383, 4133.91732165358, 4198.6431153352005, 4238.258304796648, 4310.547392523803, 4327.8485253016, 293822.0, 294729.0, 295816.0, 296207.0, 296278.51860740775, 59158.40841591529, 59788.402115978286, 59813.401865980784, 65751.0, 66017.66991165014, 77016.0, 78590.6070469644, 78799.60600197077, 78896.21103788889, 79042.0, 18240.817591824176, 18412.723809142874, 18432.354867579572, 18436.07819609014, 18440.81559184391, 14006.369713362923, 14070.43718251279, 14072.85927140736, 14073.366698498592, 14094.929525352307, 21676.56646867054, 21924.89037554802, 22015.779842201373, 22098.33704988853, 22112.889435553043, 7860.213978602094, 8159.184081591912, 8164.959175204205, 8222.671093156327, 8340.165983401732, 963.9710808675751, 998.2213873178932, 1037.6783197208738, 1051.395447617623, 1124.8706398764084, 29418.411631767245, 29701.554476682868, 30418.391632167677, 30496.085117446513, 30744.0, 1090.5528733219342, 1188.560232713884, 1194.2416565480983, 1214.2532342609231, 1276.3745764575292, 14942.701145977237, 15798.763018554731, 16036.278367473491, 16997.49007529776, 17966.371177008743, 415776.0, 421793.0, 422237.0, 424354.0, 425571.0, 58097.41902581005, 58728.41271587229, 58813.0, 58871.41128588659, 59075.113873291935, 1441.4090223008516, 1442.7547316956143, 1463.7950686903841, 1465.3479201755329, 1472.580314610331, 31167.844160779052, 31223.375532489677, 31671.20821979469, 31707.0, 31988.040358789272, 46988.0, 47690.52309476861, 49994.75002624963, 51160.976780464924, 51314.74342628263, 212060.0, 212103.0, 212402.0, 212978.0, 213237.0, 31848.681513184572, 32144.83927580347, 32222.35555288928, 32249.35501289914, 32253.35493290168, 41124.588754112076, 41694.374584380654, 41905.0, 42463.57536424596, 42859.7857010713, 38421.423678644285, 39663.603363965995, 44602.55397446114, 44922.0, 46608.766956165004, 38765.806170968965, 39076.80461597674, 39194.41208381819, 39316.537268240514, 40330.395044073775, 39142.02144946399, 44177.55822441734, 44244.33633495436, 44801.0, 45261.32108018448, 34222.31555368929, 35007.0, 35046.299074017865, 35089.473657895665, 35183.82408087943, 26825.195244142706, 26896.462070758083, 26979.460410791282, 27047.459050819267, 27301.72336459805], "unit": "ms", "name": "ext.html", "value": 20099.894073641895}, {"lowerIsBetter": false, "replicates": [378.2359633540247, 383.48804346197824, 384.70185606155223, 388.49689651900786, 390.13779547200687, 15197.6960460791, 15570.766438503431, 17190.656186876255, 18584.442466726003, 18621.255149794008, 15011.624709382271, 18120.18459169337, 18467.53831154222, 18518.629627407445, 18559.0, 5854.795082172114, 5987.55093367997, 6016.75932962681, 6062.514998800108, 6063.060225665029, 6008.909866352008, 6025.246844144482, 6041.4562689357945, 6043.969780151093, 6044.153818465418, 5669.574781891354, 5803.390643982384, 5842.44496772808, 5845.8830823383505, 5848.912266315988, 5288.735563221823, 5299.12564426873, 5301.920471192935, 5480.177973304017, 5784.623999440044, 5330.040592693314, 5388.164834450667, 5395.10980688186, 5405.216243644689, 5405.675659460445, 21004.579908401898, 21907.67138492924, 21908.12367505298, 21994.670079948814, 22738.658920116046, 2306.688597039395, 2376.964345534818, 2428.1865575032384, 2465.272744540365, 2474.4927289905527, 10372.896271037343, 10403.323783954018, 11817.409129543574, 12341.0, 13386.594407587258, 1768.5401795533126, 1808.484581894167, 1856.2296646891523, 1924.0956750327323, 1954.912028958686, 61376.69311653413, 61832.0, 62892.685536572484, 63332.68333658348, 71979.64010179968, 10145.137663298703, 10346.896531034594, 10428.947855260827, 10473.31923424975, 10490.947545262225, 14129.717405651829, 14169.149851008973, 14200.218987955555, 14202.0, 14209.147451152963, 7814.296713295831, 7929.563873986986, 7932.206779322021, 7978.242067003625, 8547.230749232474, 8162.959185204036, 8172.305354044973, 8186.0, 8213.630386632616, 8229.300509456763, 3914.2171565686976, 4032.7983600819844, 4112.403701463271, 4330.307150855848, 4625.236835922035, 1763.7971633262082, 1825.9269629214682, 1838.7058070708622, 1889.6598612249652, 1916.60709554541, 970.5292932927524, 974.605284859633, 983.9262055345878, 1010.6109147977934, 1088.6407485529755, 17391.130443477774, 17831.554211144565, 17874.5531361717, 17876.480499157457, 18294.54263643393, 989.3272574649308, 998.8801343838786, 1019.6278358399131, 1044.4307852220406, 1069.1446842525947, 9372.0, 9462.337636365388, 9614.278929080347, 9657.517124143766, 9775.413475191512, 683231.0, 690806.0, 691149.0, 698930.0, 748816.5118348746, 88274.0, 89828.5508572453, 90035.0, 91229.98235097133, 93878.0, 1149.9597514086968, 1183.1540448579308, 1185.733210027737, 1193.7314104326458, 1204.9216800908089, 16605.58486037859, 16616.335346586002, 16644.916775416044, 16815.663686726442, 16964.74552881683, 38434.6156538431, 38472.230555388174, 39855.40216896691, 40998.79500602478, 41549.792251038554, 56515.71742141263, 58598.7070064647, 59732.402675972684, 60696.39303606907, 61604.383956159865, 13110.278934658303, 15638.374465021272, 15781.21093945321, 15923.681526369639, 16069.758953615472, 15756.76364854551, 15936.601584960468, 17734.29062837472, 18181.363652272106, 20465.18139274472, 16833.494995150166, 17091.829081709024, 17099.914500427418, 17150.571235719206, 17542.03518806427, 16210.837891620933, 16682.332706691595, 16865.24106415239, 17327.393541225996, 17713.645727085128, 16691.08199049064, 16941.23764430579, 16948.661026779642, 17146.142692865058, 18086.366977155736, 11275.436228188722, 12851.485940562272, 13033.348332583144, 15243.0, 15450.150241736812, 15647.530574082795, 15667.608309792346, 15820.367185312458, 16023.439179629124, 16411.097389643684], "unit": "ms", "name": "jquery.html", "value": 9863.261430430397}, {"lowerIsBetter": false, "replicates": [1450.6155868694793, 1502.4741340530811, 1634.2645809385765, 1655.8592519635831, 1835.3943198744416, 7014.824629384268, 7289.198188199294, 7291.708331666734, 7301.89047164293, 7325.6703448344815, 6643.169603799525, 6768.898466523006, 7140.821479463003, 7347.522411043292, 7421.925780742191, 2192.287506560366, 2194.36363454598, 2219.833512486562, 2219.866807991518, 2222.1333679864865, 1854.1470923375264, 2238.2166241815316, 2255.593993081253, 2309.157157637467, 2312.0173926081434, 1918.788933217345, 2157.094020511381, 2223.6998005269324, 2223.84433089683, 2225.7662945390744, 2158.417227348615, 2183.301343570058, 2202.0201010550313, 2212.4468882779306, 2217.5786600545844, 2195.6706494025866, 2221.2780846224878, 2231.0964059555904, 2244.5286489837154, 2252.132928822415, 7270.309320614533, 7340.706371745124, 7344.889826652605, 7347.2652734726635, 7352.852942941111, 4427.512973572904, 4482.529334419872, 4500.302453119772, 4513.052259025609, 4516.887077823048, 3114.5795317632283, 3126.8749250029973, 3130.5930229070304, 3142.4657808172437, 3202.8398580071134, 1035.9223058270659, 1048.161470823346, 1060.7507235799658, 1089.8910108989116, 1095.1512577752305, 9993.550290236957, 10024.498775061218, 10072.546735396925, 10076.143527800146, 10087.89912100877, 3893.844246230176, 3910.2570511602703, 3951.3480275754177, 3955.0507878109047, 3959.742616729903, 8142.470739402039, 8192.631331590093, 8195.71315003972, 8204.466709663853, 8209.753707388787, 4451.532589078165, 4462.241418958784, 4471.396361491222, 4475.530069342737, 4478.171538265431, 4170.291050521419, 4174.373843923375, 4174.707770456039, 4178.05993651426, 4178.247915375262, 3295.1432627516538, 3315.1049216711353, 3324.5678061851804, 3325.833708314623, 3327.733781297539, 985.8028394321165, 991.9752006199902, 992.9702108936743, 999.1956475037664, 1020.9846852297073, 1113.3876367997732, 1116.0792346314324, 1157.988420115805, 1340.1958824705293, 1417.5251290817691, 6984.546004509691, 7009.54437961541, 7050.576965381991, 7173.210946795848, 7241.348278654947, 658.2495954611746, 668.5119862500402, 670.2292363781593, 671.2582596231192, 804.8671969125029, 5732.283464566929, 5773.307203135567, 5780.88438231233, 5787.218725472091, 5787.247657804459, 8609.784755381166, 8726.12738726135, 8803.471791692518, 8970.596323165473, 8988.10118988096, 8707.608157632922, 8736.650533978569, 8871.911280887238, 8885.0670679579, 8887.022427532966, 6764.797056088325, 6787.0, 6854.314568543105, 6901.447884169254, 6998.965005175043, 6858.588484690935, 6905.067815844796, 6905.37851593359, 6913.308669133247, 6920.377166055079, 7761.068671759425, 8036.598170091355, 8042.115367309474, 8050.235227653364, 8057.0, 7959.243871832166, 8234.958825205835, 8266.256036956704, 8274.379421543288, 8276.006879174538, 6450.48396128318, 6472.35276472349, 6486.740530378732, 6552.770653027121, 6556.016597510412, 7308.1595616502655, 7370.6314684266645, 7373.041504604367, 7377.225391333833, 7393.8521229574035, 5895.263092113486, 6800.414429688299, 6852.349026842442, 6949.68726407323, 7043.2604576518725, 6202.844928876814, 6816.7614133505085, 6872.896906546506, 6954.443644508326, 6973.058637083929, 6368.745250189941, 6583.144191255297, 6601.141851559267, 6604.174478190226, 6814.522983391115, 6294.464970477377, 6310.6213627182515, 6323.841903952438, 6324.936750632619, 6333.90499142504, 6319.020551814476, 6362.236531616236, 6375.585586936834, 6408.551401402044, 6551.574147680385], "unit": "ms", "name": "mootools.html", "value": 4260.027857751665}, {"lowerIsBetter": false, "replicates": [343.55338060521325, 345.7493317344924, 351.6876536770733, 354.27550658902527, 357.223039888243, 5972.761089556418, 6023.397660233974, 6063.787767428146, 6067.089936509515, 6117.143599896018, 5779.104238842986, 5844.386339434362, 5884.146798714184, 5980.88038239235, 5981.58128930975, 4631.62946964242, 4680.0, 4688.156131896258, 4703.08289883472, 4703.200455922484, 4678.672492925497, 4684.461286951993, 4685.531446855304, 4689.57793798558, 4691.053212916636, 4410.911781764363, 4481.148581769469, 4529.297958816389, 4616.099860527199, 4625.051864367813, 4618.30725391194, 4618.976905115486, 4621.422322209707, 4624.468186158601, 4645.256758918574, 4065.125997910434, 4068.4914385701786, 4069.593040695907, 4070.61329173728, 4075.3886916962692, 5703.62926409782, 5708.457696518824, 5708.800191993302, 5716.0, 5723.599348045638, 5196.870078248036, 5321.946780532184, 5335.413104558495, 5339.359276886759, 5340.065488539529, 6344.429001389851, 6347.270063942613, 6353.983362661952, 6355.555111142222, 6358.459530939829, 4445.533219012022, 4449.3326001100095, 4451.243288640939, 4462.419885414875, 4466.731996080245, 6458.547901646886, 6522.510811689142, 6586.901196482057, 6587.571807832475, 6588.176477940257, 4525.072360166158, 4525.208088584551, 4526.502084770673, 4527.162474942146, 4534.773261336986, 4589.495155532955, 4593.012502312019, 4593.311003349458, 4594.264917613233, 4598.885027874263, 5302.257683924254, 5304.151335786333, 5311.097113490716, 5313.707746074003, 5321.787128514893, 6270.404311590391, 6402.135711678957, 6405.071264666691, 6406.743730250738, 6413.45485633717, 1603.1903888536215, 1624.3989723802033, 1625.3579835964965, 1625.6829918165845, 1628.332383722668, 1071.0628200324716, 1073.3988966178965, 1085.880553139154, 1088.7931293054453, 1090.8800031996475, 6198.0, 6198.6280823150755, 6200.720967556471, 6202.906956395657, 6203.31763506014, 6542.411182993458, 6578.407943285128, 6588.571742836701, 6590.209174899042, 6592.76925307612, 6004.279486461653, 6101.298350689639, 6169.784057557964, 6271.122042913996, 6274.403931626488, 3555.0, 3575.910602234913, 3578.5526809148855, 3587.0673624857723, 3589.8205089745406, 6828.078209441662, 6835.213950395669, 6838.076859623987, 6844.589324640538, 6844.828879278058, 6664.633445160466, 6812.727490900308, 6820.795376138723, 6821.556598821061, 6827.6244806536115, 6842.794716158523, 6852.006459063408, 6857.245702972669, 6857.759978400732, 6863.691133898987, 6538.346165383424, 6648.5346025777735, 6650.035744817072, 6652.634105124264, 6654.134962455044, 6516.609003459807, 6547.803565893031, 6560.639164845979, 6565.671716414256, 6570.737170513126, 6833.726650934106, 6841.281665425058, 6844.691988860613, 6845.383915447634, 6846.828829279308, 4595.747233902168, 4596.149712303235, 4598.425196850394, 4602.125596136723, 4603.240465323184, 6522.836929076811, 6634.568753031038, 6637.070810086689, 6646.202455705346, 6646.767363142267, 6465.482761379172, 6474.805755827333, 6475.7733479328, 6479.902801458076, 6485.967570162119, 6367.93632063692, 6458.321876203119, 6463.450606698295, 6468.902966455601, 6470.611763294218, 6342.873142537216, 6366.64983425916, 6369.522285828489, 6376.426121649074, 6377.362263773585, 6472.773452929125, 6473.805785826433, 6477.676116194266, 6478.6760661967655, 6490.350964903472, 6182.103594978793, 6277.811665650038, 6290.591111577733, 6293.024581189739, 6295.622262664255], "unit": "ms", "name": "prototype.html", "value": 4841.159501582645}, {"lowerIsBetter": false, "replicates": [742.0316486984483, 753.355880721983, 900.4282280751722, 901.2114399900088, 904.990950090499, 12925.418356173972, 13155.802662960063, 13159.07886447949, 13165.078444508876, 13167.670808229776, 10872.402017889033, 10879.83680244797, 10885.292455990375, 10901.509432075556, 10918.0, 738.8005238585579, 746.5669911451345, 859.2653281444369, 864.4381152251025, 883.1168831168832, 877.5217506458963, 884.1379654836547, 887.4896934262824, 893.5710858787786, 895.7491902267376, 918.6463211663524, 929.7815013471828, 933.3326671429926, 933.6405483888684, 934.219926361489, 755.2787088330658, 765.2003656179306, 859.7635650196183, 881.4490943160524, 881.7354793561906, 669.1434963247052, 672.7309076369443, 675.4765057080753, 675.8715843989626, 676.2392308652766, 12747.490100395973, 12811.935940320333, 12817.102802803807, 12818.61544153677, 12855.807162892565, 9985.051420115078, 11945.40272986356, 12329.93835030828, 12338.321392323418, 12984.480620775157, 9862.556184971694, 10069.748256293578, 10169.440680762555, 10215.387076775418, 10273.383596984206, 3852.364359880616, 3872.399778034381, 3872.593377695358, 3880.049387899954, 3880.825362858678, 47371.0, 47869.0, 48292.7585362071, 49138.508614914106, 49659.751701241265, 10435.791284174275, 10447.850736418988, 10588.0, 10636.627718029831, 10647.520861561248, 19180.904095479433, 19192.616147676967, 19204.327848525234, 19209.615807684047, 19278.71081933772, 7997.480163789335, 8129.593520323961, 8172.0, 8182.877256841152, 8373.162683731578, 4425.247707889666, 4461.330800379904, 4501.167284052461, 4508.594226519564, 4574.382458368144, 3156.71589556941, 3163.7469002479747, 3169.5721077654684, 3179.7933134346194, 3181.650018497963, 540.4973374761431, 556.9136783798517, 558.5336244236028, 561.8763871948165, 567.7530274330679, 593.5666963116862, 644.5584774429437, 647.5952529668957, 659.953803233769, 666.2271764752863, 8461.40770146084, 8494.405391622651, 8537.658493660185, 8621.2671922886, 8708.869366959501, 565.6634302589953, 591.7899145803264, 592.2685483427963, 625.602742258669, 706.8268274272733, 4064.227796718614, 4191.580841915845, 4215.894602634898, 4239.215745087169, 4262.616364527207, 59629.403705963254, 59679.403205967385, 60049.699751500964, 60183.097253540356, 60618.393816062155, 52463.475365246624, 52821.2076818848, 52884.206736898974, 52948.735256323474, 53402.0, 48141.5185848144, 48163.518364816606, 48481.272780908315, 48783.75608121937, 49123.75438122858, 8023.759287221393, 8075.798105047421, 8169.645958848968, 8227.382946279173, 8242.464239824392, 35831.10422239465, 36048.81975590105, 36207.818960905024, 36304.63695363013, 36666.63333366632, 41524.79237603793, 43316.78341608272, 46955.76522117368, 47027.76486117548, 47225.7638711818, 10764.300320479144, 10783.6225732099, 11089.542334775995, 11585.246958947642, 11811.114166437379, 11524.769504609692, 11549.6535103947, 11843.407829608657, 12090.153689241668, 12267.325297108388, 10111.747206319607, 10232.23258255619, 10360.326578772356, 10449.9477502612, 10854.131669466266, 10605.681829545127, 10939.890601094206, 10973.83539246896, 11247.718807029889, 11502.367369794742, 9959.402435853872, 10234.283600147919, 10306.33008854422, 10669.46652667348, 10686.73283167927, 9674.22606191489, 9747.122758951728, 9807.803843922939, 9853.458059806493, 10071.194304455772, 8754.91245087541, 8779.341549383695, 8835.20483156519, 8859.610627942366, 8888.200061994452], "unit": "ms", "name": "yui.html", "value": 6327.887092342315}], "name": "dromaeo_css", "value": 7202.049102536572}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 113.0, 105.0, 110.0, 111.0, 110.0, 101.0, 112.0, 111.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 123.0, 147.0, 159.0, 129.0, 144.0, 161.0, 125.0, 164.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.2}, {"lowerIsBetter": true, "replicates": [162.0, 158.0, 159.0, 159.0, 159.0, 159.0, 160.0, 161.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 159.7}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 73.0, 149.0, 75.0, 71.0, 71.0, 71.0, 155.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 138.0, 92.0, 92.0, 92.0, 138.0, 92.0, 92.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 105.2}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 58.0, 57.0, 58.0, 56.0, 56.0, 58.0, 54.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.2}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 77.0, 77.0, 78.0, 77.0, 85.0, 78.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.4}, {"lowerIsBetter": true, "replicates": [117.0, 115.0, 114.0, 111.0, 112.0, 111.0, 120.0, 112.0, 115.0, 129.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.6}, {"lowerIsBetter": true, "replicates": [134.0, 143.0, 141.0, 143.0, 141.0, 135.0, 143.0, 144.0, 140.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.6}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 66.0, 66.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1474.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.6148961729878], "unit": "score", "name": "Richards", "value": 205.70475399999998}, {"lowerIsBetter": false, "replicates": [164.93485073396008], "unit": "score", "name": "DeltaBlue", "value": 400.87343399999986}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [936.3295880149813], "unit": "score", "name": "RayTrace", "value": 790.3082519999997}, {"lowerIsBetter": false, "replicates": [545.5537370430987], "unit": "score", "name": "Earley", "value": 1221.626679}, {"lowerIsBetter": false, "replicates": [10536.842105263158], "unit": "score", "name": "Boyer", "value": 63.25073426573423}, {"lowerIsBetter": false, "replicates": [24357.14285714286], "unit": "score", "name": "RegExp", "value": 37.40114369501463}, {"lowerIsBetter": false, "replicates": [530.471648118707], "unit": "score", "name": "Splay", "value": 153.61989710289703}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23205.615716154294}]} 09:57:57 INFO - RETURN: dromaeo_css: 7202.05 09:57:57 INFO - RETURN: kraken: 1474.00 09:57:57 INFO - RETURN: v8_7: 23205.62 09:57:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23205.62"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1474.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7202.05"}}} 09:57:57 INFO - Return code: 0 09:57:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:57:57 INFO - # TBPL SUCCESS # 09:57:57 INFO - Running post-action listener: _resource_record_post_action 09:57:57 INFO - Running post-run listener: _resource_record_post_run 09:57:58 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 0; Write bytes: 506294272; Read time: 0; Write time: 469864 09:57:58 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 245846016; Read time: 0; Write time: 311964 09:57:58 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 0; Write bytes: 260399104; Read time: 0; Write time: 157900 09:57:58 INFO - Running post-run listener: _upload_blobber_files 09:57:58 INFO - Blob upload gear active. 09:57:58 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:57:58 INFO - Copying logs to upload dir... 09:57:58 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.352596 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-08 09:57:58.605052) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:57:58.608808) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010071 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454944324/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:57:58.665084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:57:58.665902) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=33d5923976266a1c518c4bf7000001ab-1454953024.603231-1062054555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004618 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:57:58.719225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:57:58.719956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:57:58.720647) ========= ========= Total master_lag: 0.28 =========