builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1447900055.41 results: success (0) buildid: 20151118154243 builduid: c9aa6ac322ad4a7981cc3e157b3d7972 revision: a1ac4564029c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.409021) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.409454) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.409743) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006466 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.435755) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.436060) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.452411) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.452671) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004710 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.502692) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.502958) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.503360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.503697) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-18 18:27:35-- 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% 42.7M=0s 2015-11-18 18:27:35 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.719923) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.720253) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018226 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.792399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 18:27:35.792726) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a1ac4564029c --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a1ac4564029c --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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-18 18:27:35,973 Setting DEBUG logging. 2015-11-18 18:27:35,974 attempt 1/10 2015-11-18 18:27:35,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a1ac4564029c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 18:27:36,865 unpacking tar archive at: fx-team-a1ac4564029c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.162410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 18:27:36.970168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.970495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.979532) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.979932) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.980389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-18 18:27:36.980681) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:27:37 INFO - MultiFileLogger online at 20151118 18:27:37 in /builds/slave/test-pgo 18:27:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:27:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:27:37 INFO - {'append_to_log': False, 18:27:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:27:37 INFO - 'blob_upload_branch': 'Fx-Team', 18:27:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:27:37 INFO - 'branch': 'Fx-Team', 18:27:37 INFO - 'buildbot_json_path': 'buildprops.json', 18:27:37 INFO - 'config_files': ('talos/linux_config.py',), 18:27:37 INFO - 'default_actions': ('clobber', 18:27:37 INFO - 'read-buildbot-config', 18:27:37 INFO - 'download-and-extract', 18:27:37 INFO - 'populate-webroot', 18:27:37 INFO - 'create-virtualenv', 18:27:37 INFO - 'install', 18:27:37 INFO - 'run-tests'), 18:27:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:27:37 INFO - 'download_minidump_stackwalk': True, 18:27:37 INFO - 'download_symbols': 'ondemand', 18:27:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:27:37 INFO - 'tooltool.py': '/tools/tooltool.py', 18:27:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:27:37 INFO - '/tools/misc-python/virtualenv.py')}, 18:27:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:27:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:27:37 INFO - 'installer_path': 'installer.exe', 18:27:37 INFO - 'log_level': 'info', 18:27:37 INFO - 'log_name': 'talos', 18:27:37 INFO - 'log_to_console': True, 18:27:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:27:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:27:37 INFO - 'opt_config_files': (), 18:27:37 INFO - 'pip_index': False, 18:27:37 INFO - 'sps_profile': False, 18:27:37 INFO - 'sps_profile_interval': 0, 18:27:37 INFO - 'suite': 'dromaeojs-e10s', 18:27:37 INFO - 'system_bits': '64', 18:27:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:27:37 INFO - 'title': 'talos-linux64-ix-058', 18:27:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:27:37 INFO - 'use_talos_json': True, 18:27:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:27:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:27:37 INFO - 'work_dir': 'build'} 18:27:37 INFO - ##### 18:27:37 INFO - ##### Running clobber step. 18:27:37 INFO - ##### 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: clobber 18:27:37 INFO - rmtree: /builds/slave/test-pgo/build 18:27:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:27:37 INFO - Running post-action listener: _resource_record_post_action 18:27:37 INFO - ##### 18:27:37 INFO - ##### Running read-buildbot-config step. 18:27:37 INFO - ##### 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: read_buildbot_config 18:27:37 INFO - Using buildbot properties: 18:27:37 INFO - { 18:27:37 INFO - "properties": { 18:27:37 INFO - "buildnumber": 341, 18:27:37 INFO - "product": "firefox", 18:27:37 INFO - "script_repo_revision": "production", 18:27:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 18:27:37 INFO - "repository": "", 18:27:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 18:27:37 INFO - "buildid": "20151118154243", 18:27:37 INFO - "slavename": "talos-linux64-ix-058", 18:27:37 INFO - "pgo_build": "True", 18:27:37 INFO - "basedir": "/builds/slave/test-pgo", 18:27:37 INFO - "project": "", 18:27:37 INFO - "platform": "ubuntu64_hw", 18:27:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:27:37 INFO - "slavebuilddir": "test", 18:27:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:27:37 INFO - "branch": "fx-team", 18:27:37 INFO - "repo_path": "integration/fx-team", 18:27:37 INFO - "stage_platform": "linux64-pgo", 18:27:37 INFO - "builduid": "c9aa6ac322ad4a7981cc3e157b3d7972", 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - }, 18:27:37 INFO - "sourcestamp": { 18:27:37 INFO - "repository": "", 18:27:37 INFO - "hasPatch": false, 18:27:37 INFO - "project": "", 18:27:37 INFO - "branch": "fx-team-linux64-pgo-talos", 18:27:37 INFO - "changes": [ 18:27:37 INFO - { 18:27:37 INFO - "category": null, 18:27:37 INFO - "files": [ 18:27:37 INFO - { 18:27:37 INFO - "url": null, 18:27:37 INFO - "name": "https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:27:37 INFO - } 18:27:37 INFO - ], 18:27:37 INFO - "repository": "", 18:27:37 INFO - "rev": "a1ac4564029c", 18:27:37 INFO - "who": "mozci-bot@mozilla.com", 18:27:37 INFO - "when": 1447900001, 18:27:37 INFO - "number": 6680569, 18:27:37 INFO - "comments": "", 18:27:37 INFO - "project": "", 18:27:37 INFO - "at": "Wed 18 Nov 2015 18:26:41", 18:27:37 INFO - "branch": "fx-team-linux64-pgo-talos", 18:27:37 INFO - "revlink": "", 18:27:37 INFO - "properties": [ 18:27:37 INFO - [ 18:27:37 INFO - "buildid", 18:27:37 INFO - "20151118154243", 18:27:37 INFO - "Change" 18:27:37 INFO - ], 18:27:37 INFO - [ 18:27:37 INFO - "builduid", 18:27:37 INFO - "c9aa6ac322ad4a7981cc3e157b3d7972", 18:27:37 INFO - "Change" 18:27:37 INFO - ], 18:27:37 INFO - [ 18:27:37 INFO - "pgo_build", 18:27:37 INFO - "True", 18:27:37 INFO - "Change" 18:27:37 INFO - ] 18:27:37 INFO - ], 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - } 18:27:37 INFO - ], 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - } 18:27:37 INFO - } 18:27:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:27:37 INFO - Running post-action listener: _resource_record_post_action 18:27:37 INFO - ##### 18:27:37 INFO - ##### Running download-and-extract step. 18:27:37 INFO - ##### 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: download_and_extract 18:27:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:27:37 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')]} 18:27:37 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:27:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json 18:27:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json 18:27:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:27:38 INFO - Downloaded 1302 bytes. 18:27:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:27:38 INFO - Using the following test package requirements: 18:27:38 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:27:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'jsshell-linux-x86_64.zip'], 18:27:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:27:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:27:38 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:27:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:27:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:27:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:27:38 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')]} 18:27:38 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:27:39 INFO - Downloaded 22477005 bytes. 18:27:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:27:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:27:41 INFO - Return code: 0 18:27:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:27:41 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')]} 18:27:41 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:27:42 INFO - Downloaded 22477005 bytes. 18:27:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:27:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:27:43 INFO - Return code: 0 18:27:43 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')]} 18:27:43 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:27:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:27:44 INFO - Downloaded 11130997 bytes. 18:27:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:27:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:27:44 INFO - Return code: 0 18:27:44 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')]} 18:27:44 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:27:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:27:46 INFO - Downloaded 57206488 bytes. 18:27:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:46 INFO - mkdir: /builds/slave/test-pgo/properties 18:27:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:27:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:27:46 INFO - Contents: 18:27:46 INFO - build_url:https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:46 INFO - Running post-action listener: _resource_record_post_action 18:27:46 INFO - Running post-action listener: set_extra_try_arguments 18:27:46 INFO - ##### 18:27:46 INFO - ##### Running populate-webroot step. 18:27:46 INFO - ##### 18:27:46 INFO - Running pre-action listener: _resource_record_pre_action 18:27:46 INFO - Running main action method: populate_webroot 18:27:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:46 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:27:46 INFO - '../fennec_ids.txt'], 18:27:46 INFO - 'tests': ['tcheck2']}, 18:27:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:46 INFO - '--tppagecycles', 18:27:46 INFO - '7'], 18:27:46 INFO - 'tests': ['tsvgm']}}, 18:27:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:27:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:27:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:27:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['damp', 'tps']}, 18:27:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['damp', 'tps']}, 18:27:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:27:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:46 INFO - 'other': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'other_l64': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'other_nol64': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore']}, 18:27:46 INFO - 'svgr': {'tests': ['tsvgx', 18:27:46 INFO - 'tsvgr_opacity', 18:27:46 INFO - 'tart', 18:27:46 INFO - 'tscrollx', 18:27:46 INFO - 'cart']}, 18:27:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['tsvgx', 18:27:46 INFO - 'tsvgr_opacity', 18:27:46 INFO - 'tart', 18:27:46 INFO - 'tscrollx', 18:27:46 INFO - 'cart']}, 18:27:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['tp5o']}, 18:27:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--e10s'], 18:27:46 INFO - 'tests': ['tp5o']}, 18:27:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--xperf_path', 18:27:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:46 INFO - 'tests': ['tp5n']}, 18:27:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--e10s', 18:27:46 INFO - '--xperf_path', 18:27:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:46 INFO - 'tests': ['tp5n']}}, 18:27:46 INFO - 'talos.zip': {'path': '', 18:27:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:46 INFO - Running post-action listener: _resource_record_post_action 18:27:46 INFO - ##### 18:27:46 INFO - ##### Running create-virtualenv step. 18:27:46 INFO - ##### 18:27:46 INFO - Running pre-action listener: _resource_record_pre_action 18:27:46 INFO - Running main action method: create_virtualenv 18:27:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:27:46 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 18:27:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:27:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:46 INFO - Using real prefix '/usr' 18:27:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:27:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:49 INFO - Installing pip.................done. 18:27:49 INFO - Return code: 0 18:27:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:27: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')]} 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:27:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:27:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:27:49 INFO - Using env: {'DISPLAY': ':0', 18:27:49 INFO - 'HOME': '/home/cltbld', 18:27:49 INFO - 'LANG': 'en_US.UTF-8', 18:27:49 INFO - 'LANGUAGE': 'en_US:en', 18:27:49 INFO - 'LOGNAME': 'cltbld', 18:27:49 INFO - 'MAIL': '/var/mail/cltbld', 18:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:49 INFO - 'MOZ_NO_REMOTE': '1', 18:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:49 INFO - 'NO_EM_RESTART': '1', 18:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:49 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:49 INFO - 'SHELL': '/bin/bash', 18:27:49 INFO - 'SHLVL': '1', 18:27:49 INFO - 'TERM': 'linux', 18:27:49 INFO - 'TMOUT': '86400', 18:27:49 INFO - 'USER': 'cltbld', 18:27:49 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:49 INFO - '_': '/tools/buildbot/bin/python'} 18:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:49 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 18:27:49 INFO - Cleaning up... 18:27:49 INFO - Return code: 0 18:27:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:27: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')]} 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:27:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:27:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:27:49 INFO - Using env: {'DISPLAY': ':0', 18:27:49 INFO - 'HOME': '/home/cltbld', 18:27:49 INFO - 'LANG': 'en_US.UTF-8', 18:27:49 INFO - 'LANGUAGE': 'en_US:en', 18:27:49 INFO - 'LOGNAME': 'cltbld', 18:27:49 INFO - 'MAIL': '/var/mail/cltbld', 18:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:49 INFO - 'MOZ_NO_REMOTE': '1', 18:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:49 INFO - 'NO_EM_RESTART': '1', 18:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:49 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:49 INFO - 'SHELL': '/bin/bash', 18:27:49 INFO - 'SHLVL': '1', 18:27:49 INFO - 'TERM': 'linux', 18:27:49 INFO - 'TMOUT': '86400', 18:27:49 INFO - 'USER': 'cltbld', 18:27:49 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:49 INFO - '_': '/tools/buildbot/bin/python'} 18:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:49 INFO - Downloading/unpacking psutil>=0.7.1 18:27:49 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 18:27:49 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 18:27:49 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 18:27:49 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 18:27:49 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 18:27:49 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 18:27:51 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:27:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:27:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:51 INFO - Installing collected packages: psutil 18:27:51 INFO - Running setup.py install for psutil 18:27:51 INFO - building 'psutil._psutil_linux' extension 18:27:51 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 18:27:51 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 18:27:51 INFO - building 'psutil._psutil_posix' extension 18:27:51 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 18:27:51 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 18:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:52 INFO - Successfully installed psutil 18:27:52 INFO - Cleaning up... 18:27:52 INFO - Return code: 0 18:27:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:27:52 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')]} 18:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:52 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')]} 18:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:27:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:27:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:27:52 INFO - Using env: {'DISPLAY': ':0', 18:27:52 INFO - 'HOME': '/home/cltbld', 18:27:52 INFO - 'LANG': 'en_US.UTF-8', 18:27:52 INFO - 'LANGUAGE': 'en_US:en', 18:27:52 INFO - 'LOGNAME': 'cltbld', 18:27:52 INFO - 'MAIL': '/var/mail/cltbld', 18:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:52 INFO - 'MOZ_NO_REMOTE': '1', 18:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:52 INFO - 'NO_EM_RESTART': '1', 18:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:52 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:52 INFO - 'SHELL': '/bin/bash', 18:27:52 INFO - 'SHLVL': '1', 18:27:52 INFO - 'TERM': 'linux', 18:27:52 INFO - 'TMOUT': '86400', 18:27:52 INFO - 'USER': 'cltbld', 18:27:52 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:52 INFO - '_': '/tools/buildbot/bin/python'} 18:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:27:52 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 18:27:52 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 18:27:52 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 18:27:52 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 18:27:52 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 18:27:52 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 18:27:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:27:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:27:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:27:54 INFO - Installing collected packages: mozsystemmonitor 18:27:54 INFO - Running setup.py install for mozsystemmonitor 18:27:54 INFO - Successfully installed mozsystemmonitor 18:27:54 INFO - Cleaning up... 18:27:54 INFO - Return code: 0 18:27:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:27:54 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')]} 18:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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')]} 18:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:27:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:27:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:27:54 INFO - Using env: {'DISPLAY': ':0', 18:27:54 INFO - 'HOME': '/home/cltbld', 18:27:54 INFO - 'LANG': 'en_US.UTF-8', 18:27:54 INFO - 'LANGUAGE': 'en_US:en', 18:27:54 INFO - 'LOGNAME': 'cltbld', 18:27:54 INFO - 'MAIL': '/var/mail/cltbld', 18:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:54 INFO - 'MOZ_NO_REMOTE': '1', 18:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:54 INFO - 'NO_EM_RESTART': '1', 18:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:54 INFO - 'SHELL': '/bin/bash', 18:27:54 INFO - 'SHLVL': '1', 18:27:54 INFO - 'TERM': 'linux', 18:27:54 INFO - 'TMOUT': '86400', 18:27:54 INFO - 'USER': 'cltbld', 18:27:54 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:54 INFO - '_': '/tools/buildbot/bin/python'} 18:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:54 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 18:27:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:27:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:27:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:27:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:27:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:27:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 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 18:27:56 INFO - Downloading docopt-0.6.1.tar.gz 18:27:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:27:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 18:27:56 INFO - Running setup.py install for blobuploader 18:27:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:27:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:27:56 INFO - Running setup.py install for requests 18:27:57 INFO - Running setup.py install for docopt 18:27:57 INFO - Successfully installed blobuploader requests docopt 18:27:57 INFO - Cleaning up... 18:27:57 INFO - Return code: 0 18:27:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:27:57 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')]} 18:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:57 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')]} 18:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:27:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:27:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:27:57 INFO - Using env: {'DISPLAY': ':0', 18:27:57 INFO - 'HOME': '/home/cltbld', 18:27:57 INFO - 'LANG': 'en_US.UTF-8', 18:27:57 INFO - 'LANGUAGE': 'en_US:en', 18:27:57 INFO - 'LOGNAME': 'cltbld', 18:27:57 INFO - 'MAIL': '/var/mail/cltbld', 18:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:57 INFO - 'MOZ_NO_REMOTE': '1', 18:27:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:57 INFO - 'NO_EM_RESTART': '1', 18:27:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:57 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:57 INFO - 'SHELL': '/bin/bash', 18:27:57 INFO - 'SHLVL': '1', 18:27:57 INFO - 'TERM': 'linux', 18:27:57 INFO - 'TMOUT': '86400', 18:27:57 INFO - 'USER': 'cltbld', 18:27:57 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:57 INFO - '_': '/tools/buildbot/bin/python'} 18:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:27:57 INFO - Running setup.py (path:/tmp/pip-Shq4Mh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:27:57 INFO - Running setup.py (path:/tmp/pip-cVKXy9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:27:57 INFO - Running setup.py (path:/tmp/pip-KZjkDv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:27:57 INFO - Running setup.py (path:/tmp/pip-3SvHUU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:27:57 INFO - Running setup.py (path:/tmp/pip-A3fqoD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:27:57 INFO - Running setup.py (path:/tmp/pip-damGF0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:27:57 INFO - Running setup.py (path:/tmp/pip-QeDDHu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:27:58 INFO - Running setup.py (path:/tmp/pip-3dAxzc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:58 INFO - Running setup.py (path:/tmp/pip-qxcgZZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:58 INFO - Running setup.py (path:/tmp/pip-nDB5Im-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:58 INFO - Running setup.py (path:/tmp/pip-8NWyAC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:58 INFO - Running setup.py (path:/tmp/pip-_GiG4R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:58 INFO - Running setup.py (path:/tmp/pip-J9eQ39-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:58 INFO - Running setup.py (path:/tmp/pip-RVbtXQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:58 INFO - Running setup.py (path:/tmp/pip-SaWfo5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:58 INFO - Running setup.py (path:/tmp/pip-NZrr6m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:58 INFO - Running setup.py (path:/tmp/pip-I9zJkW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:27:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:58 INFO - Running setup.py install for manifestparser 18:27:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:27:58 INFO - Running setup.py install for mozcrash 18:27:58 INFO - Running setup.py install for mozdebug 18:27:59 INFO - Running setup.py install for mozdevice 18:27:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for mozfile 18:27:59 INFO - Running setup.py install for mozhttpd 18:27:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for mozinfo 18:27:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for mozInstall 18:27:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for mozleak 18:27:59 INFO - Running setup.py install for mozlog 18:27:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for moznetwork 18:27:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:27:59 INFO - Running setup.py install for mozprocess 18:28:00 INFO - Running setup.py install for mozprofile 18:28:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:28:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:28:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:28:00 INFO - Running setup.py install for mozrunner 18:28:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:28:00 INFO - Running setup.py install for mozscreenshot 18:28:00 INFO - Running setup.py install for moztest 18:28:00 INFO - Running setup.py install for mozversion 18:28:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:28:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:00 INFO - Cleaning up... 18:28:00 INFO - Return code: 0 18:28:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:28:00 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')]} 18:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:00 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')]} 18:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:28:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:28:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:28:00 INFO - Using env: {'DISPLAY': ':0', 18:28:00 INFO - 'HOME': '/home/cltbld', 18:28:00 INFO - 'LANG': 'en_US.UTF-8', 18:28:00 INFO - 'LANGUAGE': 'en_US:en', 18:28:00 INFO - 'LOGNAME': 'cltbld', 18:28:00 INFO - 'MAIL': '/var/mail/cltbld', 18:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:00 INFO - 'MOZ_NO_REMOTE': '1', 18:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:00 INFO - 'NO_EM_RESTART': '1', 18:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:00 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:00 INFO - 'SHELL': '/bin/bash', 18:28:00 INFO - 'SHLVL': '1', 18:28:00 INFO - 'TERM': 'linux', 18:28:00 INFO - 'TMOUT': '86400', 18:28:00 INFO - 'USER': 'cltbld', 18:28:00 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:00 INFO - '_': '/tools/buildbot/bin/python'} 18:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:00 INFO - Running setup.py (path:/tmp/pip-67nBkf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:00 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)) 18:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:00 INFO - Running setup.py (path:/tmp/pip-6KJeFA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:00 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)) 18:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:00 INFO - Running setup.py (path:/tmp/pip-Jo_lC2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:00 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)) 18:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:01 INFO - Running setup.py (path:/tmp/pip-wKF85O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:01 INFO - Running setup.py (path:/tmp/pip-4wg4Tx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:01 INFO - Running setup.py (path:/tmp/pip-teDBaM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:01 INFO - Running setup.py (path:/tmp/pip-7iPveH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:01 INFO - Running setup.py (path:/tmp/pip-wsKqBy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:01 INFO - Running setup.py (path:/tmp/pip-y3jsUh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:01 INFO - Running setup.py (path:/tmp/pip-1ft3C3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:01 INFO - Running setup.py (path:/tmp/pip-a3O8eP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:01 INFO - Running setup.py (path:/tmp/pip-E9XEh7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:01 INFO - Running setup.py (path:/tmp/pip-gypgh3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:01 INFO - Running setup.py (path:/tmp/pip-VzjScp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:01 INFO - Running setup.py (path:/tmp/pip-bHZVec-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:01 INFO - Running setup.py (path:/tmp/pip-M1Z0od-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:01 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)) 18:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:01 INFO - Running setup.py (path:/tmp/pip-iyIXih-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:02 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)) 18:28:02 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)) 18:28:02 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)) 18:28:02 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)) 18:28:02 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)) 18:28:02 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)) 18:28:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:03 INFO - Downloading blessings-1.5.1.tar.gz 18:28:03 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:28:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:03 INFO - Installing collected packages: blessings 18:28:03 INFO - Running setup.py install for blessings 18:28:03 INFO - Successfully installed blessings 18:28:03 INFO - Cleaning up... 18:28:03 INFO - Return code: 0 18:28:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:28:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:28:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:28:04 INFO - Reading from file tmpfile_stdout 18:28:04 INFO - Current package versions: 18:28:04 INFO - argparse == 1.2.1 18:28:04 INFO - blessings == 1.5.1 18:28:04 INFO - blobuploader == 1.2.4 18:28:04 INFO - docopt == 0.6.1 18:28:04 INFO - manifestparser == 1.1 18:28:04 INFO - mozInstall == 1.12 18:28:04 INFO - mozcrash == 0.16 18:28:04 INFO - mozdebug == 0.1 18:28:04 INFO - mozdevice == 0.47 18:28:04 INFO - mozfile == 1.2 18:28:04 INFO - mozhttpd == 0.7 18:28:04 INFO - mozinfo == 0.9 18:28:04 INFO - mozleak == 0.1 18:28:04 INFO - mozlog == 3.0 18:28:04 INFO - moznetwork == 0.27 18:28:04 INFO - mozprocess == 0.22 18:28:04 INFO - mozprofile == 0.27 18:28:04 INFO - mozrunner == 6.11 18:28:04 INFO - mozscreenshot == 0.1 18:28:04 INFO - mozsystemmonitor == 0.0 18:28:04 INFO - moztest == 0.7 18:28:04 INFO - mozversion == 1.4 18:28:04 INFO - psutil == 3.1.1 18:28:04 INFO - requests == 1.2.3 18:28:04 INFO - wsgiref == 0.1.2 18:28:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:28:04 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')]} 18:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 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')]} 18:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f867cff2c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15086c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ba7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', '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 18:28:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 18:28:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 18:28:04 INFO - Using env: {'DISPLAY': ':0', 18:28:04 INFO - 'HOME': '/home/cltbld', 18:28:04 INFO - 'LANG': 'en_US.UTF-8', 18:28:04 INFO - 'LANGUAGE': 'en_US:en', 18:28:04 INFO - 'LOGNAME': 'cltbld', 18:28:04 INFO - 'MAIL': '/var/mail/cltbld', 18:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:04 INFO - 'MOZ_NO_REMOTE': '1', 18:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:04 INFO - 'NO_EM_RESTART': '1', 18:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:04 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:04 INFO - 'SHELL': '/bin/bash', 18:28:04 INFO - 'SHLVL': '1', 18:28:04 INFO - 'TERM': 'linux', 18:28:04 INFO - 'TMOUT': '86400', 18:28:04 INFO - 'USER': 'cltbld', 18:28:04 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:04 INFO - '_': '/tools/buildbot/bin/python'} 18:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 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)) 18:28:04 INFO - Cleaning up... 18:28:04 INFO - Return code: 0 18:28:04 INFO - Running post-action listener: _resource_record_post_action 18:28:04 INFO - Running post-action listener: _start_resource_monitoring 18:28:04 INFO - Starting resource monitoring. 18:28:04 INFO - ##### 18:28:04 INFO - ##### Running install step. 18:28:04 INFO - ##### 18:28:04 INFO - Running pre-action listener: _resource_record_pre_action 18:28:04 INFO - Running main action method: install 18:28:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:28:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:28:04 INFO - Reading from file tmpfile_stdout 18:28:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:28:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:28:04 INFO - Reading from file tmpfile_stdout 18:28:04 INFO - Output received: 18:28:04 INFO - Usage: mozinstall [options] installer 18:28:04 INFO - Options: 18:28:04 INFO - -h, --help show this help message and exit 18:28:04 INFO - -d DEST, --destination=DEST 18:28:04 INFO - Directory to install application into. [default: 18:28:04 INFO - "/builds/slave/test-pgo"] 18:28:04 INFO - --app=APP Application being installed. [default: firefox] 18:28:04 INFO - mkdir: /builds/slave/test-pgo/build/application 18:28:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:28:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:28:19 INFO - Reading from file tmpfile_stdout 18:28:19 INFO - Output received: 18:28:19 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:28:19 INFO - Running post-action listener: _resource_record_post_action 18:28:19 INFO - ##### 18:28:19 INFO - ##### Running run-tests step. 18:28:19 INFO - ##### 18:28:19 INFO - Running pre-action listener: _resource_record_pre_action 18:28:19 INFO - Running main action method: run_tests 18:28:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:28:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:28:19 INFO - Python 2.7.3 18:28:19 INFO - Return code: 0 18:28:19 INFO - grabbing minidump binary from tooltool 18:28: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')]} 18:28:19 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 0x15fed70>, '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 0x15ffb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15fffb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:28:19 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 18:28:19 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 18:28:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:19 INFO - Return code: 0 18:28:19 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:28:19 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:28:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:28:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:28:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 18:28:19 INFO - Using env: {'DISPLAY': ':0', 18:28:19 INFO - 'HOME': '/home/cltbld', 18:28:19 INFO - 'LANG': 'en_US.UTF-8', 18:28:19 INFO - 'LANGUAGE': 'en_US:en', 18:28:19 INFO - 'LOGNAME': 'cltbld', 18:28:19 INFO - 'MAIL': '/var/mail/cltbld', 18:28:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:28:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:19 INFO - 'MOZ_NO_REMOTE': '1', 18:28:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:28:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:19 INFO - 'NO_EM_RESTART': '1', 18:28:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:19 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:28:19 INFO - 'SHELL': '/bin/bash', 18:28:19 INFO - 'SHLVL': '1', 18:28:19 INFO - 'TERM': 'linux', 18:28:19 INFO - 'TMOUT': '86400', 18:28:19 INFO - 'USER': 'cltbld', 18:28:19 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505', 18:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:19 INFO - '_': '/tools/buildbot/bin/python'} 18:28:19 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 18:28:19 INFO - mozversion INFO | application_buildid: 20151118154243 18:28:19 INFO - mozversion INFO | application_changeset: a1ac4564029cec65bed930613865e0111adc7c7f 18:28:19 INFO - mozversion INFO | application_display_name: Nightly 18:28:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:19 INFO - mozversion INFO | application_name: Firefox 18:28:19 INFO - mozversion INFO | application_remotingname: firefox 18:28:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:28:19 INFO - mozversion INFO | application_vendor: Mozilla 18:28:19 INFO - mozversion INFO | application_version: 45.0a1 18:28:19 INFO - mozversion INFO | platform_buildid: 20151118154243 18:28:19 INFO - mozversion INFO | platform_changeset: a1ac4564029cec65bed930613865e0111adc7c7f 18:28:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:28:19 INFO - mozversion INFO | platform_version: 45.0a1 18:28:19 INFO - 2015-11-18 18:28:19,497 DEBUG : using testdate: 1447900099 18:28:19 INFO - 2015-11-18 18:28:19,498 DEBUG : actual date: 1447900099 18:28:19 INFO - 2015-11-18 18:28:19,506 INFO : starting webserver on 'localhost:39138' 18:28:19 INFO - 2015-11-18 18:28:19,507 INFO : Starting test suite talos-linux64-ix-058.e 18:28:19 INFO - 2015-11-18 18:28:19,507 INFO : Starting test dromaeo_css 18:28:19 INFO - 2015-11-18 18:28:19,507 DEBUG : operating with platform_type : linux_ 18:28:19 INFO - 2015-11-18 18:28:19,508 INFO : Initialising browser for dromaeo_css test... 18:28:19 INFO - 2015-11-18 18:28:19,522 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp47OJgB/profile http://localhost:39138/getInfo.html 18:28:24 INFO - 2015-11-18 18:28:24,107 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:28:24 INFO - 2015-11-18 18:28:24,107 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:28:24 INFO - 2015-11-18 18:28:24,107 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:28:24 INFO - 2015-11-18 18:28:24,107 DEBUG : BROWSER_OUTPUT: __metrics 18:28:25 INFO - 2015-11-18 18:28:25,233 INFO : Browser initialized. 18:28:25 INFO - 2015-11-18 18:28:25,234 INFO : Running cycle 1/1 for dromaeo_css test... 18:28:25 INFO - 2015-11-18 18:28:25,234 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp47OJgB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:28:37 INFO - 2015-11-18 18:28:37,090 DEBUG : BROWSER_OUTPUT: RSS: Main: 141905920 18:28:37 INFO - 2015-11-18 18:28:37,090 DEBUG : BROWSER_OUTPUT: 18:29:26 INFO - 2015-11-18 18:29:26,749 DEBUG : BROWSER_OUTPUT: 1447900166742 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] 18:31:35 INFO - 2015-11-18 18:31:35,272 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39138/tests/dromaeo/cssquery-ext.html) 18:31:36 INFO - 2015-11-18 18:31:36,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 147828736 18:31:36 INFO - 2015-11-18 18:31:36,322 DEBUG : BROWSER_OUTPUT: 18:34:38 INFO - 2015-11-18 18:34:38,354 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-ext.html (next: http://localhost:39138/tests/dromaeo/cssquery-jquery.html) 18:34:38 INFO - 2015-11-18 18:34:38,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 141148160 18:34:38 INFO - 2015-11-18 18:34:38,947 DEBUG : BROWSER_OUTPUT: 18:37:40 INFO - 2015-11-18 18:37:40,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39138/tests/dromaeo/cssquery-mootools.html) 18:37:41 INFO - 2015-11-18 18:37:41,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 142315520 18:37:41 INFO - 2015-11-18 18:37:41,259 DEBUG : BROWSER_OUTPUT: 18:40:42 INFO - 2015-11-18 18:40:42,912 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39138/tests/dromaeo/cssquery-prototype.html) 18:40:43 INFO - 2015-11-18 18:40:43,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 141131776 18:40:43 INFO - 2015-11-18 18:40:43,515 DEBUG : BROWSER_OUTPUT: 18:43:45 INFO - 2015-11-18 18:43:45,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39138/tests/dromaeo/cssquery-yui.html) 18:43:46 INFO - 2015-11-18 18:43:46,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 142192640 18:43:46 INFO - 2015-11-18 18:43:46,421 DEBUG : BROWSER_OUTPUT: 18:46:48 INFO - 2015-11-18 18:46:48,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/dromaeo/cssquery-yui.html (next: http://localhost:39138/tests/dromaeo/cssquery-dojo.html) 18:46:49 INFO - 2015-11-18 18:46:49,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 144605184 18:46:49 INFO - 2015-11-18 18:46:49,037 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,038 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:46:49 INFO - 2015-11-18 18:46:49,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:46:49 INFO - 2015-11-18 18:46:49,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:46:49 INFO - 2015-11-18 18:46:49,038 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:46:49 INFO - 2015-11-18 18:46:49,039 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3211.116942840719;3241.7082462578364;3262.4943133814268;3303.2072302647357;3305.8181800001;5278.340207474066;5331.173668081454;5358.115910874706;5361.3030306060255;5387.865303367418;5298.311219541454;5391.73041347933;5404.83785486436;5428.864278393042;5445.700486473243;4600.7239565626005;4879.609631229502;5022.472640372763;5034.42104158021;5052.393712754475;4796.664233503656;5000.349954505909;5023.472535383768;5070.873228169288;5133.922991155136;4846.68496547725;4896.902061958776;4915.3855768029;4943.728094954776;4980.153373926441;4998.475160108209;5006.674566153225;5009.899802003976;5029.170186919154;5047.596192304607;4787.736674482902;4890.413150421937;4990.600751939873;5000.7249601271915;5035.043341765053;5137.717425541594;5149.974250128763;5160.922586161173;5216.26972223889;5305.363356397257;5833.562482813763;5835.766569337221;5837.3287071986415;5842.561807864385;5855.355910849803;5676.290463692038;5783.826485205408;5788.739506722208;5874.383189765099;5889.8822023559715;5326.733663316819;5360.16917377807;5393.919091213635;5514.558835293207;5514.641548299348;5994.850128746729;5998.850028749316;6012.068129439944;6013.909791353133;6029.939700603026;5808.535317174616;5824.708764561755;5846.824595262149;5847.473727364558;5849.853753656193;5757.165211044375;5851.297844258716;5875.383084776037;5899.528037756969;5932.703364831741;5748.109043098327;5857.179994800731;5896.115582662635;5906.616069955439;5913.142594323885;5802.6808525531505;5846.269216347957;5863.73613187399;5876;5878.970605146947;4422.20400327944;4524.185646583646;4525;4526.298423744325;4568.132054909556;3952;4026.5570787213383;4026.59734026595;4048.291548978917;4084.2035803018125;5511.889762204813;5514.972425137848;5515.420880807537;5540.279763630702;5550.722463876871;5731.684757338387;5880.647161170344;5881.911771323434;5883.294004719461;5905.143754155709;5402.243685883979;5460.180972854104;5492.725363731797;5503.724813759376;5552.167174923794;5088.414832294259;5118.129917913963;5120.667156634807;5129.128048231735;5160.716160611202;5931.436513531207;6045.999999999912;6057.273127224761;6106.603070800384;6121.265448146251;5766.077427611562;5834.883302334015;5842.532597392284;5868.647881127146;5912.113183022409;6050.697465126638;6108.786192483241;6122.418370254819;6123.510119190365;6125.877482450237;5666.575006874418;5781.971090144522;5800.216970709069;5802.593818432669;5810.680412577349;5829.7085145743395;5859.824205273849;5939.10913362999;5960.254968128984;5988.880222395441;5685.061964775931;5703.600747947604;5713.200151978644;5713.8285851424525;5719.971400142973;5587.413321601173;5689.829305120853;5712.971435142797;5727.284089488814;5729.541636668973;5536.778528858962;5583.525400341016;5610.971945140248;5619.578531610227;5660.29246344207;5635.9436405635415;5646.407127251579;5668.7165641718575;5671.319441667027;5711.685857277889;5523.1991361253195;5525.254090697865;5527.198556209247;5580.609357344947;5649.971750141223 18:46:49 INFO - 2015-11-18 18:46:49,039 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.8263520943717;581.9010768169411;582.8455459303285;595.7021489255372;603.9400851505607;39066.80466597666;39614.405783913266;39825.203495930065;40113.99999999996;40176.19647607046;43801.56198438015;43896.78051609745;43986;44072.779636101775;44178.55821441785;8765.16730910564;8849.734507964771;8877.112288771135;8908.020117787071;8958.23854972328;8519.872201916976;8642.870356944619;8683.218510334069;8693.913060869374;8702.564871756418;8713.041565427799;8749.125087491231;8815.426997245158;8815.51514666693;8830.86753698695;8647.394682372267;8650.091740367234;8699.695510657097;8715.869261961076;8738.169873861973;8623.611937462829;8645.610947507377;8770.254528365096;8771.73684789451;8780.209781119729;39742.20515589672;39900.40149397762;40407.19185616271;40433.797831010954;40492.19015619701;2968.762499000075;3464.6015708193627;3468.739844511672;3537.221811201557;3570.393033184365;30125.246868828235;30418.84790576055;30849.691503084905;31003.37993240145;31509.212269693442;3397.371486275022;3402.132456223672;3407.4207384744404;3508.614052454228;3529.699975502085;276849;277565;279074;279184;279196.6040169806;54981.72509137429;55848.72075639596;61489;68397.65801171062;69619.30380696128;63262;63344;63610.36389636137;63721;63883.68058159773;14495.782563261559;14511.1293322401;14527.273636318354;14545.20001399912;14620;12021.218620789621;12084.818727719092;12101.515939362327;12112.21270617407;12287.692807679701;20405.285814996405;20844.47888802792;21129.154833806475;21134.260300889397;21160.576788464452;7870.567118808521;8096.230858068474;8101.230383113595;8112.148224436467;8155.510669359739;893.8625078746206;943.919766819828;963.629002833914;970.9805803883883;986.2208855004609;29358.412831743673;29919.905606607328;29953.700462995526;29957.40085198284;30310;1062.0759938853248;1064.3613831701073;1067.69570672358;1077.5312738958578;1084.2897901874285;15088.809016993953;15393.230338483256;15478.922605387126;15497.690046199239;15756.606084847741;322704;328205;328382;329324;331712;53329.73335133377;54262.72868635631;54267;54324.18513722297;54345;1273.4343837255647;1290.6708789258867;1292.3409061378763;1311.4623004568082;1318.9340532973313;28502.287442814097;28960.565591515722;29023.56464653074;29449.705502944696;29866;48365.5163448361;48474.75762621164;48669.756651216514;48773.268400974724;48775;302207;303574;304894;304923;305353;33692.66307336895;46597;47343.52656473391;47666.28500572425;47796.28305575489;45472.58955578279;45542.77228613836;45626.77186614178;45813.541864580926;45825.77087114543;37172.256554869295;37811;38194.427083594324;38557.228855422174;39253.41119883261;38425;39330.41004384994;39429.605703943744;39731.205375892896;39881.60118398779;36561.81719091388;36841.815790920875;37115.628843711216;37116.25767484689;37495;33397;33884.66115338815;35219.47170792492;35918.8204058978;35995.82002089973;26055.478890421706;26595.60106598364;26622;26783.330416739736;26790.380685423443 18:46:49 INFO - 2015-11-18 18:46:49,039 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.02259830662274;359.05210244953327;361.37662532132066;361.9782813031218;363.54919899324847;12420.503179872794;12780.424880880359;16544;17213.82786172138;18070.186841592127;11993.580224692138;12053.517859285617;12104.273743575392;12301.753964920696;12499.687507812308;4523.638108951292;4572.679912406133;4652.418447694038;4661.067786442708;4665.160271151191;4577;4650.790714417859;4677.508861569538;4702.482726900038;4703.459102203256;4641.048585040058;4646.837360692376;4669.976650116745;4751.311059896313;4752.049590081998;4000.2399544086675;4731.4795372509;4790.616750659939;4816.54242846929;4824.348712923763;4381.25518661825;4396.516383197878;4415.094905544356;4442.888927776799;4453.554644535553;13512.864871351358;13771.380287887068;13804.654883627889;14150.646233844236;14168.504102356368;2113.2392338758036;2114.439673486527;2149.161826887515;2182.454386403399;2188.1466228171025;9490;10002.649907253211;10048.145907597864;10053.346532475365;10395.94802025985;1728.1186594836597;1732.4975757030572;1740.8172141925043;1761.9823801761947;1769.513383819447;62382;62469;62726.686366568334;62738;63105.36894631087;6742.292059333798;6955.930440695528;7029.61337126463;7030.578165310098;7041.753538626123;8284.295834854105;8353.916460835435;8374.874376884352;8407.159284071666;8426.283765879847;5622.353429355677;5648.378678345379;5653.660780353192;5654.943450565524;5686.800961966312;5797.101449275285;5871.500922421556;5873.9412605873395;5880.0591905295805;5906.586538942319;3281.45855933773;3346;3348.7823291485975;3357.1774915145456;3364.747643926666;1163.4589915689075;1166.445938179358;1170.7599942011866;1175.7589694112687;1222.1750318534953;410.73712823792795;416.18843255650995;443.81803460581006;449.89202591377835;482.5295337046422;11668;11811.822822657667;12019.21875078117;12050.638480845588;12398.504059837505;488.065354845474;502.8943921776468;505.7850413574216;517.3093919617353;518.4167811212386;6121.969390153021;6467.967660161669;6474.902876456857;6487.902681459782;6518.413342799171;386522;392836;535091;542234;554014;78485.60757196292;79152.6042369796;79927.60579153654;80113;80323.59838200771;555.3030946162621;568.3407247592772;569.3196630027078;571.3115695586891;572.3475238228423;11059.668209953392;11107.111431085332;11135.168880586825;11317.490712917774;11328.150388720715;35190.15291161503;35910.28179436449;37293.4405983905;37323.440148397254;37665;49240.26139607837;49554.75222624008;50748.74625626848;51039;51347.2297915539;8493.830123397622;8603.35474839402;8657.696980605648;8661.480311181349;8665.956670216608;12505;12616.243025418504;12833.935830320788;12912.225266484042;12947.093703441045;10386.272960892666;10677.57289708403;10914.126869850552;10914.672559823219;11198.552057917592;10622.575096996034;10726.302790318605;10878.184136189659;10926.180536459957;11129.332240065622;10676.786464270515;10909;11128.888711112786;11202.83195752048;11265.605703800327;8350;8350.206730360605;8548.187922147386;8573.61418736146;8579.785505362415;10768.277075667855;10905.127589792955;11099.334039957628;11141.610043648434;11181.32912025281 18:46:49 INFO - 2015-11-18 18:46:49,040 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1482.7405204089284;1533.7622668486385;1600.6158521954735;1663.3679201903283;1767.8674099442544;7287.380572651325;7468.103827540703;7649.120351159619;7655.578943158131;7660.080790305171;7707.691692332314;7779.183185765498;7787.7274295399675;7814.648340824662;7814.92185078149;2141.4325203821;2149.602323570137;2162.502624396382;2166.1227202982736;2178.16140785797;2275.3287780104824;2275.613145765224;2351.8471299365488;2354;2357.95284094318;2280.5552917181158;2303.8617682939075;2312.8496647717843;2323.8373313868037;2325.790678838904;2183.3886511776727;2189.321310393775;2219.7336319641668;2220.3893929169526;2221.7000704904954;2348.412896775806;2356.1988923766003;2362.433016076129;2366.219147681261;2370.0519792083132;7383.07711536058;7518.28576285252;7523.3229009389415;7529.698812047512;7534.472586918918;4648.535146485358;4703.976480117578;4713.764311784397;4729.716217026989;4731.597814185799;3142.6543080261154;3166.113488223278;3208.807471551691;3219.806811591289;3233.7736358454918;1053.9894601053968;1068.4924660786064;1085.7177133945152;1116.8827273136367;1119.9888001119966;10217.74455638615;10276.94861525695;10284.845727314096;10296.742581435448;10302.072813446826;4113.362428823537;4156.750594964312;4226.049138943775;4226.091390351028;4236.724612900151;8378.539180345018;8402.285805706584;8433.987921449465;8468.830623387621;8471.407001509959;4635.930461043087;4671.392718946515;4687.29690546414;4704.15325241453;4716.5990890774665;4181.289180839264;4295.3771703102875;4300.397944287803;4322.222000040023;4344.804483798237;3597.2805438912324;3603.3874241378503;3613.114786877232;3625.3474374612297;3645.8177091145335;1129.6667483092508;1135.6138912769698;1138.800709875785;1141;1141.7716456708692;1297.5523444411663;1310.25315570126;1313.9408726607326;1351;1356.2879488268738;7069.681864316118;7135.571865688075;7149.92850071503;7170;7197.064381630355;469.70878055605743;472.2396940925108;474.7768548782136;477.3317355702045;503.7305041802691;5653.689047102366;5667.773289068474;5669.1496275558175;5701.344345400332;5702.45826646468;8931.73204803857;8974.19232269099;9018.909810901807;9045;9091.81816363669;8930.50882201472;8980.24885147403;9048.864267036;9052.728418147466;9074.682386116587;7011.088558487466;7013.053237812932;7127.643617819088;7179.353858152791;7182.42540596751;7140.678669459783;7146.035285236427;7171.605561694157;7202.819929501804;7205.171405288459;7918.089419716806;8007.599620019092;8131.2681858633005;8187.918120818954;8214.13751556078;7997;8064.55644939534;8163.061247956561;8165.305948994402;8195.426320157532;6054.66699331541;6174.845628859314;6379.74481020754;6612.471002319707;6835.760748373783;7175.174854891754;7185.173705023989;7200.243974382614;7215.891761623475;7250.202477727551;6540.869182616226;6657.101291325609;6657.700403481758;6745.5952642841585;6909.30906909305;6690.1971763386755;6754.155730533684;6838.863222735418;6845.178578570603;6890.483213758888;6524.5432819704065;6547.574407663486;6555.311692272434;6582.901256481253;6594.604323740591;6265.154204182377;6266.342034086355;6267.467265282502;6282.057691346335;6307.590006649553;6206.782762603287;6298.779542715983;6322.841928951813;6338.492920566251;6338.841528961812 18:46:49 INFO - 2015-11-18 18:46:49,042 DEBUG : BROWSER_OUTPUT: |4;prototype.html;337.1571072319202;340.5147664577976;344.44716230450126;344.8034620266446;348.4981626457901;6204.16243807087;6304.747810087597;6338.492920566349;6346.873062538746;6355;6024.0963855421705;6212.856564485257;6234.158388617535;6256.124142620037;6259.874802503947;4704.623630109583;4727.976360118195;4739.170645137105;4774.450938142106;4792.065547218293;4796.928046079294;4810.927836082444;4817.253325734499;4823.999999999983;4824.807007719687;4679.859604211879;4702.7883745231375;4729.361536192621;4760.024195040026;4770.093682200388;4719.292106184065;4720.740359280238;4733.360996265512;4737.265723812815;4738.336632871401;4019.2765302245584;4131.5042194936495;4176.895577610584;4181.581841815824;4193.098483825962;5905.350411454737;5929.822105336847;5932.466078052953;5937.8812423751715;5958.5828991970575;5480.095784195603;5480.479354461319;5498.862528436781;5510.118381059011;5513.641613295123;6458.806235812933;6556.377144171296;6583.275839657634;6597.63712995785;6601.910684737013;4476.1271552047365;4477.664175186874;4522.977385113087;4527.524609915978;4531.501534831166;6828.317168283182;6854.725810967555;6901.930980690179;6911.101556797584;6916.861662766716;4635.6986795858165;4641.280601506772;4642.257238841769;4650.302454631832;4656.8137274508645;4594.747288899142;4679.64902632304;4682.01677647691;4701.130290896195;4703.670743047954;5433.429489903582;5440.91838622421;5452.263944367539;5459.508644222039;5460.590455715837;6676.097312266078;6687.598744075371;6691.732330706717;6700.195976482853;6703.12859328294;1651.116652590882;1660.6346603747397;1671.1727694791157;1674.9497515074568;1675.0368538090647;1091.2088735666737;1093.6281664234198;1095.9232853700164;1113.7939481195842;1122.3602546548389;6325.557210995186;6337.778177763848;6341.524385671093;6349.904751428732;6358.618482891041;6840.486963477759;6854.485913556503;6866.793996180123;6875.621840798804;6905.136857892763;6267.2792628847355;6327.810165694945;6337.112804207414;6350.110984462087;6369.235691717024;3762.962370376316;3773.566039905446;3793.867214647474;3797.525309336333;3803.1823158020607;7071.575705457689;7212.855742885113;7267.672954716944;7281.5631062136445;7282.198958114604;7102;7110.182329032227;7130.144382674009;7134.393576545948;7143.357097861218;7069.9646501767165;7140.143182818095;7210.67551960163;7258.201597824235;7280.089988751406;6939.375456208965;6943.236244013054;6955.721771129301;6956.513044086865;6960.721571137098;6872.518923675295;6885.208201056943;6886.758963436256;6893.241743408121;6899.17209934811;7047.01341812157;7176.677049532679;7227.819304517429;7238.927610723826;7239.565626062243;4650.651201159859;4690.788914498788;4695.71825690446;4699.718016918996;4702.553257440538;6861.897071543831;6945.479089068239;6954.269801670752;6958.860822783618;6960.269171736894;6616.90074648871;6711;6742.764003259862;6752.763653272112;6752.966235168793;6733.192016957996;6738.966305168443;6741.797746067626;6750.189977202767;6760.1549806274215;6602.636854973022;6617.0405291233465;6625.271220165874;6628.5028622852515;6628.933710663025;6601.306862779339;6762.357576030269;6771.52599318043;6779.186497620317;6783.898241526283;6555.180602424697;6567.9671601641685;6577.901331479936;6581.2102547694585;6596.670166491752 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: |5;yui.html;841.6423020216406;861.3884142258994;863.6286396849353;873.1443185678031;874.6020560644903;12546.937265313683;12642.30467324298;12700.936495317534;13025.283609401478;13054.93472532636;13371.331433428339;13593.184408935445;13602.115862468932;13613.931930340359;13656.24890631015;830.1366578758084;844.6959094725894;848.6520526584102;849.2484151525913;849.7578190215789;836.7447928381841;840.9243168114868;845.4969293270494;849.9575021248944;850.2772643253247;882.1531329923281;885.0397318908991;885.123727509764;895.7626229049307;896.6413434626137;865.5109862927488;885.9468431894074;889.5018789477913;889.7241855024963;890.1410139215624;646.3730181723721;649.9057636642708;650.2197363164174;650.5251166648326;652.3867564489412;13495.392707328096;13518.324083795771;13553.661158471019;13622.86377136226;13672.794908076386;12713.555025574153;13071.215727056311;13127.934360328138;13403.731925361535;13507.122037067558;10842.241043126984;10916.399598022106;10918.890811091867;10958.342499449978;11051.944740276247;3659.469376940329;3660.6705396514176;3718.9442158367647;3827.349350610375;3838.692904567628;48868;49031;49083;49247;49367.50632493665;10170.186385089175;10222.846657300146;10253.84619230712;10254.53854576546;10292.330998485075;18755.812441875678;18821.247150114115;18993.525161871064;19012.714809277873;19038.524036899187;7644.885326720104;7721.189275126031;7815.491993020435;7837.255460731221;7914.722984695623;4365.4761428628135;4368.257396242647;4374.650027997816;4468.687191896536;4501.639868810487;3030.2121448423127;3043.5738996540504;3084.8303343316334;3091.1190310761326;3099.9225019374694;481.838584074339;483.8766114640744;484.4211167654689;496.5258178439582;497.6864575317599;603.5225399734117;622.3496446213718;678.7963610916705;684.360866460363;724.3119036914952;8078.192180781874;8970.955145224232;9317;9521.952390238142;9736.172425343784;569.7037540478927;677.9762708305186;682.3960794696629;697.5814511293285;702.7118881258659;3873.5351757789244;3975.483187185705;3991.341428664238;4032.153247817991;4098.774567398762;56476;56493.717531412905;56801.88791970373;56956.43043569594;56978;51087.23369149466;52101.218481722804;52294.47705522896;52331.47668523342;52340;47232.5276747235;47979;48160.7591962045;50626.74686626617;50631.74684126556;8786.956065219889;8824.955875220583;8865.512396818236;9234.538273086184;9266.721998340061;36010.27979440449;36373.454398183516;36427.27145457023;37251.44122838214;37292.813535932146;42609;46628.30057549071;46911.765441172574;47051.05897881955;47100;11072.557097716324;11621.302721836717;11907.871778254012;12042.63872083839;12130.939345303217;12444;12778.74442511126;12901.677458063248;13034.826865582143;13086.869131308946;11006.504707288352;11824.586139485076;12193.756124877631;12303.323317217639;12466.376681166086;11635.47640356203;12076.939615301868;12081.637550873487;12109;12523.74952500963;12181.208221465577;12274.81587776202;12481.750364992831;12528.123031387715;12596.937015314865;10298.639547615798;10586.153107751208;10624;10678.145748339959;10795.352278863293;8968.775780605538;9230.492322922304;9296.431417780022;10256.38461692301;10417.583296668048 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,294 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901209031__endTimestamp 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:46:49 INFO - 2015-11-18 18:46:49,043 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:46:49 INFO - 2015-11-18 18:46:49,044 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,044 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5422.27 Median:5610.97 stddev:606.30 (10.8%) stddev-sans-first:582.82 18:46:49 INFO - 2015-11-18 18:46:49,044 DEBUG : BROWSER_OUTPUT: Values: 3211.1 3241.7 3262.5 3303.2 3305.8 5278.3 5331.2 5358.1 5361.3 5387.9 5298.3 5391.7 5404.8 5428.9 5445.7 4600.7 4879.6 5022.5 5034.4 5052.4 4796.7 5000.3 5023.5 5070.9 5133.9 4846.7 4896.9 4915.4 4943.7 4980.2 4998.5 5006.7 5009.9 5029.2 5047.6 4787.7 4890.4 4990.6 5000.7 5035.0 5137.7 5150.0 5160.9 5216.3 5305.4 5833.6 5835.8 5837.3 5842.6 5855.4 5676.3 5783.8 5788.7 5874.4 5889.9 5326.7 5360.2 5393.9 5514.6 5514.6 5994.9 5998.9 6012.1 6013.9 6029.9 5808.5 5824.7 5846.8 5847.5 5849.9 5757.2 5851.3 5875.4 5899.5 5932.7 5748.1 5857.2 5896.1 5906.6 5913.1 5802.7 5846.3 5863.7 5876.0 5879.0 4422.2 4524.2 4525.0 4526.3 4568.1 3952.0 4026.6 4026.6 4048.3 4084.2 5511.9 5515.0 5515.4 5540.3 5550.7 5731.7 5880.6 5881.9 5883.3 5905.1 5402.2 5460.2 5492.7 5503.7 5552.2 5088.4 5118.1 5120.7 5129.1 5160.7 5931.4 6046.0 6057.3 6106.6 6121.3 5766.1 5834.9 5842.5 5868.6 5912.1 6050.7 6108.8 6122.4 6123.5 6125.9 5666.6 5782.0 5800.2 5802.6 5810.7 5829.7 5859.8 5939.1 5960.3 5988.9 5685.1 5703.6 5713.2 5713.8 5720.0 5587.4 5689.8 5713.0 5727.3 5729.5 5536.8 5583.5 5611.0 5619.6 5660.3 5635.9 5646.4 5668.7 5671.3 5711.7 5523.2 5525.3 5527.2 5580.6 5650.0 18:46:49 INFO - 2015-11-18 18:46:49,044 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,044 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48462.57 Median:38812.02 stddev:79466.71 (204.7%) stddev-sans-first:79608.30 18:46:49 INFO - 2015-11-18 18:46:49,045 DEBUG : BROWSER_OUTPUT: Values: 578.8 581.9 582.8 595.7 603.9 39066.8 39614.4 39825.2 40114.0 40176.2 43801.6 43896.8 43986.0 44072.8 44178.6 8765.2 8849.7 8877.1 8908.0 8958.2 8519.9 8642.9 8683.2 8693.9 8702.6 8713.0 8749.1 8815.4 8815.5 8830.9 8647.4 8650.1 8699.7 8715.9 8738.2 8623.6 8645.6 8770.3 8771.7 8780.2 39742.2 39900.4 40407.2 40433.8 40492.2 2968.8 3464.6 3468.7 3537.2 3570.4 30125.2 30418.8 30849.7 31003.4 31509.2 3397.4 3402.1 3407.4 3508.6 3529.7 276849.0 277565.0 279074.0 279184.0 279196.6 54981.7 55848.7 61489.0 68397.7 69619.3 63262.0 63344.0 63610.4 63721.0 63883.7 14495.8 14511.1 14527.3 14545.2 14620.0 12021.2 12084.8 12101.5 12112.2 12287.7 20405.3 20844.5 21129.2 21134.3 21160.6 7870.6 8096.2 8101.2 8112.1 8155.5 893.9 943.9 963.6 971.0 986.2 29358.4 29919.9 29953.7 29957.4 30310.0 1062.1 1064.4 1067.7 1077.5 1084.3 15088.8 15393.2 15478.9 15497.7 15756.6 322704.0 328205.0 328382.0 329324.0 331712.0 53329.7 54262.7 54267.0 54324.2 54345.0 1273.4 1290.7 1292.3 1311.5 1318.9 28502.3 28960.6 29023.6 29449.7 29866.0 48365.5 48474.8 48669.8 48773.3 48775.0 302207.0 303574.0 304894.0 304923.0 305353.0 33692.7 46597.0 47343.5 47666.3 47796.3 45472.6 45542.8 45626.8 45813.5 45825.8 37172.3 37811.0 38194.4 38557.2 39253.4 38425.0 39330.4 39429.6 39731.2 39881.6 36561.8 36841.8 37115.6 37116.3 37495.0 33397.0 33884.7 35219.5 35918.8 35995.8 26055.5 26595.6 26622.0 26783.3 26790.4 18:46:49 INFO - 2015-11-18 18:46:49,045 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,046 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25730.70 Median:4405.81 stddev:80259.59 (1821.7%) stddev-sans-first:80462.12 18:46:49 INFO - 2015-11-18 18:46:49,047 DEBUG : BROWSER_OUTPUT: Values: 358.0 359.1 361.4 362.0 363.5 12420.5 12780.4 16544.0 17213.8 18070.2 11993.6 12053.5 12104.3 12301.8 12499.7 4523.6 4572.7 4652.4 4661.1 4665.2 4577.0 4650.8 4677.5 4702.5 4703.5 4641.0 4646.8 4670.0 4751.3 4752.0 4000.2 4731.5 4790.6 4816.5 4824.3 4381.3 4396.5 4415.1 4442.9 4453.6 13512.9 13771.4 13804.7 14150.6 14168.5 2113.2 2114.4 2149.2 2182.5 2188.1 9490.0 10002.6 10048.1 10053.3 10395.9 1728.1 1732.5 1740.8 1762.0 1769.5 62382.0 62469.0 62726.7 62738.0 63105.4 6742.3 6955.9 7029.6 7030.6 7041.8 8284.3 8353.9 8374.9 8407.2 8426.3 5622.4 5648.4 5653.7 5654.9 5686.8 5797.1 5871.5 5873.9 5880.1 5906.6 3281.5 3346.0 3348.8 3357.2 3364.7 1163.5 1166.4 1170.8 1175.8 1222.2 410.7 416.2 443.8 449.9 482.5 11668.0 11811.8 12019.2 12050.6 12398.5 488.1 502.9 505.8 517.3 518.4 6122.0 6468.0 6474.9 6487.9 6518.4 386522.0 392836.0 535091.0 542234.0 554014.0 78485.6 79152.6 79927.6 80113.0 80323.6 555.3 568.3 569.3 571.3 572.3 11059.7 11107.1 11135.2 11317.5 11328.2 35190.2 35910.3 37293.4 37323.4 37665.0 49240.3 49554.8 50748.7 51039.0 51347.2 8493.8 8603.4 8657.7 8661.5 8666.0 12505.0 12616.2 12833.9 12912.2 12947.1 10386.3 10677.6 10914.1 10914.7 11198.6 10622.6 10726.3 10878.2 10926.2 11129.3 10676.8 10909.0 11128.9 11202.8 11265.6 8350.0 8350.2 8548.2 8573.6 8579.8 10768.3 10905.1 11099.3 11141.6 11181.3 18:46:49 INFO - 2015-11-18 18:46:49,047 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,047 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5300.31 Median:6114.76 stddev:2699.49 (44.1%) stddev-sans-first:2691.81 18:46:49 INFO - 2015-11-18 18:46:49,047 DEBUG : BROWSER_OUTPUT: Values: 1482.7 1533.8 1600.6 1663.4 1767.9 7287.4 7468.1 7649.1 7655.6 7660.1 7707.7 7779.2 7787.7 7814.6 7814.9 2141.4 2149.6 2162.5 2166.1 2178.2 2275.3 2275.6 2351.8 2354.0 2358.0 2280.6 2303.9 2312.8 2323.8 2325.8 2183.4 2189.3 2219.7 2220.4 2221.7 2348.4 2356.2 2362.4 2366.2 2370.1 7383.1 7518.3 7523.3 7529.7 7534.5 4648.5 4704.0 4713.8 4729.7 4731.6 3142.7 3166.1 3208.8 3219.8 3233.8 1054.0 1068.5 1085.7 1116.9 1120.0 10217.7 10276.9 10284.8 10296.7 10302.1 4113.4 4156.8 4226.0 4226.1 4236.7 8378.5 8402.3 8434.0 8468.8 8471.4 4635.9 4671.4 4687.3 4704.2 4716.6 4181.3 4295.4 4300.4 4322.2 4344.8 3597.3 3603.4 3613.1 3625.3 3645.8 1129.7 1135.6 1138.8 1141.0 1141.8 1297.6 1310.3 1313.9 1351.0 1356.3 7069.7 7135.6 7149.9 7170.0 7197.1 469.7 472.2 474.8 477.3 503.7 5653.7 5667.8 5669.1 5701.3 5702.5 8931.7 8974.2 9018.9 9045.0 9091.8 8930.5 8980.2 9048.9 9052.7 9074.7 7011.1 7013.1 7127.6 7179.4 7182.4 7140.7 7146.0 7171.6 7202.8 7205.2 7918.1 8007.6 8131.3 8187.9 8214.1 7997.0 8064.6 8163.1 8165.3 8195.4 6054.7 6174.8 6379.7 6612.5 6835.8 7175.2 7185.2 7200.2 7215.9 7250.2 6540.9 6657.1 6657.7 6745.6 6909.3 6690.2 6754.2 6838.9 6845.2 6890.5 6524.5 6547.6 6555.3 6582.9 6594.6 6265.2 6266.3 6267.5 6282.1 6307.6 6206.8 6298.8 6322.8 6338.5 6338.8 18:46:49 INFO - 2015-11-18 18:46:49,047 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,048 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5570.28 Median:6337.45 stddev:1704.46 (26.9%) stddev-sans-first:1663.37 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: Values: 337.2 340.5 344.4 344.8 348.5 6204.2 6304.7 6338.5 6346.9 6355.0 6024.1 6212.9 6234.2 6256.1 6259.9 4704.6 4728.0 4739.2 4774.5 4792.1 4796.9 4810.9 4817.3 4824.0 4824.8 4679.9 4702.8 4729.4 4760.0 4770.1 4719.3 4720.7 4733.4 4737.3 4738.3 4019.3 4131.5 4176.9 4181.6 4193.1 5905.4 5929.8 5932.5 5937.9 5958.6 5480.1 5480.5 5498.9 5510.1 5513.6 6458.8 6556.4 6583.3 6597.6 6601.9 4476.1 4477.7 4523.0 4527.5 4531.5 6828.3 6854.7 6901.9 6911.1 6916.9 4635.7 4641.3 4642.3 4650.3 4656.8 4594.7 4679.6 4682.0 4701.1 4703.7 5433.4 5440.9 5452.3 5459.5 5460.6 6676.1 6687.6 6691.7 6700.2 6703.1 1651.1 1660.6 1671.2 1674.9 1675.0 1091.2 1093.6 1095.9 1113.8 1122.4 6325.6 6337.8 6341.5 6349.9 6358.6 6840.5 6854.5 6866.8 6875.6 6905.1 6267.3 6327.8 6337.1 6350.1 6369.2 3763.0 3773.6 3793.9 3797.5 3803.2 7071.6 7212.9 7267.7 7281.6 7282.2 7102.0 7110.2 7130.1 7134.4 7143.4 7070.0 7140.1 7210.7 7258.2 7280.1 6939.4 6943.2 6955.7 6956.5 6960.7 6872.5 6885.2 6886.8 6893.2 6899.2 7047.0 7176.7 7227.8 7238.9 7239.6 4650.7 4690.8 4695.7 4699.7 4702.6 6861.9 6945.5 6954.3 6958.9 6960.3 6616.9 6711.0 6742.8 6752.8 6753.0 6733.2 6739.0 6741.8 6750.2 6760.2 6602.6 6617.0 6625.3 6628.5 6628.9 6601.3 6762.4 6771.5 6779.2 6783.9 6555.2 6568.0 6577.9 6581.2 6596.7 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14221.12 Median:4421.67 stddev:16250.82 (367.5%) stddev-sans-first:16265.34 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: Values: 841.6 861.4 863.6 873.1 874.6 12546.9 12642.3 12700.9 13025.3 13054.9 13371.3 13593.2 13602.1 13613.9 13656.2 830.1 844.7 848.7 849.2 849.8 836.7 840.9 845.5 850.0 850.3 882.2 885.0 885.1 895.8 896.6 865.5 885.9 889.5 889.7 890.1 646.4 649.9 650.2 650.5 652.4 13495.4 13518.3 13553.7 13622.9 13672.8 12713.6 13071.2 13127.9 13403.7 13507.1 10842.2 10916.4 10918.9 10958.3 11051.9 3659.5 3660.7 3718.9 3827.3 3838.7 48868.0 49031.0 49083.0 49247.0 49367.5 10170.2 10222.8 10253.8 10254.5 10292.3 18755.8 18821.2 18993.5 19012.7 19038.5 7644.9 7721.2 7815.5 7837.3 7914.7 4365.5 4368.3 4374.7 4468.7 4501.6 3030.2 3043.6 3084.8 3091.1 3099.9 481.8 483.9 484.4 496.5 497.7 603.5 622.3 678.8 684.4 724.3 8078.2 8971.0 9317.0 9522.0 9736.2 569.7 678.0 682.4 697.6 702.7 3873.5 3975.5 3991.3 4032.2 4098.8 56476.0 56493.7 56801.9 56956.4 56978.0 51087.2 52101.2 52294.5 52331.5 52340.0 47232.5 47979.0 48160.8 50626.7 50631.7 8787.0 8825.0 8865.5 9234.5 9266.7 36010.3 36373.5 36427.3 37251.4 37292.8 42609.0 46628.3 46911.8 47051.1 47100.0 11072.6 11621.3 11907.9 12042.6 12130.9 12444.0 12778.7 12901.7 13034.8 13086.9 11006.5 11824.6 12193.8 12303.3 12466.4 11635.5 12076.9 12081.6 12109.0 12523.7 12181.2 12274.8 12481.8 12528.1 12596.9 10298.6 10586.2 10624.0 10678.1 10795.4 8968.8 9230.5 9296.4 10256.4 10417.6 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:46:49 INFO - 2015-11-18 18:46:49,049 DEBUG : BROWSER_OUTPUT: 18:46:49 INFO - 2015-11-18 18:46:49,753 INFO : Browser exited with error code: 0 18:46:49 INFO - 2015-11-18 18:46:49,774 INFO : Completed test dromaeo_css (00:18:30) 18:46:49 INFO - 2015-11-18 18:46:49,775 INFO : Starting test kraken 18:46:49 INFO - 2015-11-18 18:46:49,775 DEBUG : operating with platform_type : linux_ 18:46:49 INFO - 2015-11-18 18:46:49,775 INFO : Initialising browser for kraken test... 18:46:49 INFO - 2015-11-18 18:46:49,780 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA1NmYO/profile http://localhost:39138/getInfo.html 18:46:54 INFO - 2015-11-18 18:46:54,525 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:46:54 INFO - 2015-11-18 18:46:54,526 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:46:54 INFO - 2015-11-18 18:46:54,526 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:46:54 INFO - 2015-11-18 18:46:54,526 DEBUG : BROWSER_OUTPUT: __metrics 18:46:55 INFO - 2015-11-18 18:46:55,641 INFO : Browser initialized. 18:46:55 INFO - 2015-11-18 18:46:55,642 INFO : Running cycle 1/1 for kraken test... 18:46:55 INFO - 2015-11-18 18:46:55,642 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA1NmYO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:47:07 INFO - 2015-11-18 18:47:07,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 142163968 18:47:07 INFO - 2015-11-18 18:47:07,486 DEBUG : BROWSER_OUTPUT: 18:47:10 INFO - 2015-11-18 18:47:10,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39138/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:10 INFO - 2015-11-18 18:47:10,628 DEBUG : BROWSER_OUTPUT: RSS: Main: 142323712 18:47:10 INFO - 2015-11-18 18:47:10,629 DEBUG : BROWSER_OUTPUT: 18:47:13 INFO - 2015-11-18 18:47:13,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39138/tests/kraken/driver.html?testName=audio-dft) 18:47:13 INFO - 2015-11-18 18:47:13,867 DEBUG : BROWSER_OUTPUT: RSS: Main: 141758464 18:47:13 INFO - 2015-11-18 18:47:13,867 DEBUG : BROWSER_OUTPUT: 18:47:16 INFO - 2015-11-18 18:47:16,753 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39138/tests/kraken/driver.html?testName=audio-fft) 18:47:17 INFO - 2015-11-18 18:47:17,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 136736768 18:47:17 INFO - 2015-11-18 18:47:17,328 DEBUG : BROWSER_OUTPUT: 18:47:19 INFO - 2015-11-18 18:47:19,328 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39138/tests/kraken/driver.html?testName=audio-oscillator) 18:47:19 INFO - 2015-11-18 18:47:19,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 136949760 18:47:19 INFO - 2015-11-18 18:47:19,911 DEBUG : BROWSER_OUTPUT: 18:47:22 INFO - 2015-11-18 18:47:22,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39138/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:22 INFO - 2015-11-18 18:47:22,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 137179136 18:47:22 INFO - 2015-11-18 18:47:22,665 DEBUG : BROWSER_OUTPUT: 18:47:25 INFO - 2015-11-18 18:47:25,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39138/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:26 INFO - 2015-11-18 18:47:26,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 137441280 18:47:26 INFO - 2015-11-18 18:47:26,216 DEBUG : BROWSER_OUTPUT: 18:47:29 INFO - 2015-11-18 18:47:29,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39138/tests/kraken/driver.html?testName=imaging-desaturate) 18:47:30 INFO - 2015-11-18 18:47:30,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 138665984 18:47:30 INFO - 2015-11-18 18:47:30,221 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39138/tests/kraken/driver.html?testName=json-parse-financial) 18:47:33 INFO - 2015-11-18 18:47:33,686 DEBUG : BROWSER_OUTPUT: RSS: Main: 139186176 18:47:33 INFO - 2015-11-18 18:47:33,687 DEBUG : BROWSER_OUTPUT: 18:47:35 INFO - 2015-11-18 18:47:35,268 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39138/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:47:35 INFO - 2015-11-18 18:47:35,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 139866112 18:47:35 INFO - 2015-11-18 18:47:35,842 DEBUG : BROWSER_OUTPUT: 18:47:37 INFO - 2015-11-18 18:47:37,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:47:38 INFO - 2015-11-18 18:47:38,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 138645504 18:47:38 INFO - 2015-11-18 18:47:38,278 DEBUG : BROWSER_OUTPUT: 18:47:40 INFO - 2015-11-18 18:47:40,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:47:40 INFO - 2015-11-18 18:47:40,973 DEBUG : BROWSER_OUTPUT: RSS: Main: 140673024 18:47:40 INFO - 2015-11-18 18:47:40,973 DEBUG : BROWSER_OUTPUT: 18:47:43 INFO - 2015-11-18 18:47:43,440 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:47:44 INFO - 2015-11-18 18:47:44,033 DEBUG : BROWSER_OUTPUT: RSS: Main: 140025856 18:47:44 INFO - 2015-11-18 18:47:44,033 DEBUG : BROWSER_OUTPUT: 18:47:46 INFO - 2015-11-18 18:47:46,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:47:47 INFO - 2015-11-18 18:47:47,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 141000704 18:47:47 INFO - 2015-11-18 18:47:47,234 DEBUG : BROWSER_OUTPUT: 18:47:48 INFO - 2015-11-18 18:47:48,924 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39138/tests/kraken/driver.html?testName=ai-astar) 18:47:49 INFO - 2015-11-18 18:47:49,174 DEBUG : BROWSER_OUTPUT: RSS: Main: 141234176 18:47:49 INFO - 2015-11-18 18:47:49,174 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,174 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:47:49 INFO - 2015-11-18 18:47:49,175 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:47:49 INFO - 2015-11-18 18:47:49,175 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:47:49 INFO - 2015-11-18 18:47:49,175 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:47:49 INFO - 2015-11-18 18:47:49,175 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;106;113;111;111;122;111;111;112;110 18:47:49 INFO - 2015-11-18 18:47:49,176 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;160;125;163;170;128;151;154;127;171;127 18:47:49 INFO - 2015-11-18 18:47:49,176 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;163;161;162;164;161;178;167;163 18:47:49 INFO - 2015-11-18 18:47:49,176 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;76;71;74;73;160;72;73;72;72 18:47:49 INFO - 2015-11-18 18:47:49,177 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;91;138;90;91;90;129;91;91;130 18:47:49 INFO - 2015-11-18 18:47:49,177 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;112;110;110;109;111;110;110 18:47:49 INFO - 2015-11-18 18:47:49,177 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;148;146;147;148;149;147;147;146;147 18:47:49 INFO - 2015-11-18 18:47:49,178 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;97;97;96;98;96;96;96;96 18:47:49 INFO - 2015-11-18 18:47:49,178 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;60;61;61;62;62;62;61;59;59 18:47:49 INFO - 2015-11-18 18:47:49,178 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;70;70;69;69;71;69 18:47:49 INFO - 2015-11-18 18:47:49,178 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;90;83;86;91;85;85;85;84;85 18:47:49 INFO - 2015-11-18 18:47:49,178 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;115;120;124;124;134;122;129;117;118 18:47:49 INFO - 2015-11-18 18:47:49,179 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;152;156;151;153;144;153;151;152;144 18:47:49 INFO - 2015-11-18 18:47:49,179 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;70;66;68;67;68;68;65;68;66 18:47:49 INFO - 2015-11-18 18:47:49,179 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:47:49 INFO - 2015-11-18 18:47:49,179 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:47:49 INFO - 2015-11-18 18:47:49,180 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,698 18:47:49 INFO - 2015-11-18 18:47:49,180 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:47:49 INFO - 2015-11-18 18:47:49,180 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901269173__endTimestamp 18:47:49 INFO - 2015-11-18 18:47:49,180 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,182 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:47:49 INFO - 2015-11-18 18:47:49,182 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:47:49 INFO - 2015-11-18 18:47:49,182 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,182 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:4.01 (3.6%) stddev-sans-first:4.26 18:47:49 INFO - 2015-11-18 18:47:49,183 DEBUG : BROWSER_OUTPUT: Values: 112.0 106.0 113.0 111.0 111.0 122.0 111.0 111.0 112.0 110.0 18:47:49 INFO - 2015-11-18 18:47:49,183 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,183 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.60 Median:157.00 stddev:18.96 (12.1%) stddev-sans-first:19.58 18:47:49 INFO - 2015-11-18 18:47:49,184 DEBUG : BROWSER_OUTPUT: Values: 160.0 125.0 163.0 170.0 128.0 151.0 154.0 127.0 171.0 127.0 18:47:49 INFO - 2015-11-18 18:47:49,184 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,184 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.50 Median:163.00 stddev:5.04 (3.1%) stddev-sans-first:5.32 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 163.0 161.0 162.0 164.0 161.0 178.0 167.0 163.0 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.60 Median:73.00 stddev:27.58 (37.8%) stddev-sans-first:29.08 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: Values: 73.0 76.0 71.0 74.0 73.0 160.0 72.0 73.0 72.0 72.0 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,185 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.20 Median:91.00 stddev:20.24 (22.2%) stddev-sans-first:20.98 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: Values: 91.0 91.0 138.0 90.0 91.0 90.0 129.0 91.0 91.0 130.0 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 112.0 110.0 110.0 109.0 111.0 110.0 110.0 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.99 (0.7%) stddev-sans-first:0.97 18:47:49 INFO - 2015-11-18 18:47:49,186 DEBUG : BROWSER_OUTPUT: Values: 146.0 148.0 146.0 147.0 148.0 149.0 147.0 147.0 146.0 147.0 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.50 Median:96.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 97.0 97.0 96.0 98.0 96.0 96.0 96.0 96.0 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:60.80 Median:61.00 stddev:1.14 (1.9%) stddev-sans-first:1.20 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: Values: 61.0 60.0 61.0 61.0 62.0 62.0 62.0 61.0 59.0 59.0 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,187 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 70.0 70.0 69.0 69.0 71.0 69.0 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.00 stddev:2.62 (3.1%) stddev-sans-first:2.69 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: Values: 84.0 90.0 83.0 86.0 91.0 85.0 85.0 85.0 84.0 85.0 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:123.00 stddev:5.70 (4.6%) stddev-sans-first:6.04 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: Values: 122.0 115.0 120.0 124.0 124.0 134.0 122.0 129.0 117.0 118.0 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,188 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.00 Median:152.00 stddev:4.37 (2.9%) stddev-sans-first:4.06 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: Values: 144.0 152.0 156.0 151.0 153.0 144.0 153.0 151.0 152.0 144.0 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.50 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 66.0 68.0 67.0 68.0 68.0 65.0 68.0 66.0 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:47:49 INFO - 2015-11-18 18:47:49,189 DEBUG : BROWSER_OUTPUT: 18:47:50 INFO - 2015-11-18 18:47:50,077 INFO : Browser exited with error code: 0 18:47:50 INFO - 2015-11-18 18:47:50,133 INFO : Completed test kraken (00:01:00) 18:47:50 INFO - 2015-11-18 18:47:50,133 INFO : Starting test v8_7 18:47:50 INFO - 2015-11-18 18:47:50,133 DEBUG : operating with platform_type : linux_ 18:47:50 INFO - 2015-11-18 18:47:50,134 INFO : Initialising browser for v8_7 test... 18:47:50 INFO - 2015-11-18 18:47:50,140 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTZQAnS/profile http://localhost:39138/getInfo.html 18:47:54 INFO - 2015-11-18 18:47:54,747 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:47:54 INFO - 2015-11-18 18:47:54,747 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:47:54 INFO - 2015-11-18 18:47:54,747 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:47:54 INFO - 2015-11-18 18:47:54,747 DEBUG : BROWSER_OUTPUT: __metrics 18:47:55 INFO - 2015-11-18 18:47:55,846 INFO : Browser initialized. 18:47:55 INFO - 2015-11-18 18:47:55,846 INFO : Running cycle 1/1 for v8_7 test... 18:47:55 INFO - 2015-11-18 18:47:55,846 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTZQAnS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:48:07 INFO - 2015-11-18 18:48:07,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 142639104 18:48:07 INFO - 2015-11-18 18:48:07,603 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,033 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39138/tests/v8_7/run.html (next: http://localhost:39138/tests/v8_7/run.html) 18:48:29 INFO - 2015-11-18 18:48:29,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 139014144 18:48:29 INFO - 2015-11-18 18:48:29,291 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,291 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:48:29 INFO - 2015-11-18 18:48:29,291 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |0;Richards;165.89250165892503 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.53590090961873 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.326369282891 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5075.757575757576 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1075.268817204301 18:48:29 INFO - 2015-11-18 18:48:29,292 DEBUG : BROWSER_OUTPUT: |5;Earley;725.6894049346879 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: |6;Boyer;12231.707317073171 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: |7;RegExp;25125 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: |8;Splay;593.8242280285035 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901309284__endTimestamp 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:48:29 INFO - 2015-11-18 18:48:29,293 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.89 Median:165.89 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: Values: 165.9 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.54 Median:193.54 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: Values: 193.5 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,294 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.33 Median:282.33 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: Values: 282.3 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5075.76 Median:5075.76 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: Values: 5075.8 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1075.27 Median:1075.27 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: Values: 1075.3 18:48:29 INFO - 2015-11-18 18:48:29,295 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:725.69 Median:725.69 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: Values: 725.7 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12231.71 Median:12231.71 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: Values: 12231.7 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,296 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25125.00 Median:25125.00 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: Values: 25125.0 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.82 Median:593.82 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: Values: 593.8 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: Values: 4854.4 18:48:29 INFO - 2015-11-18 18:48:29,297 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:48:29 INFO - 2015-11-18 18:48:29,298 DEBUG : BROWSER_OUTPUT: 18:48:30 INFO - 2015-11-18 18:48:30,153 INFO : Browser exited with error code: 0 18:48:30 INFO - 2015-11-18 18:48:30,168 INFO : Completed test v8_7 (00:00:40) 18:48:30 INFO - 2015-11-18 18:48:30,337 INFO : Completed test suite (00:20:10) 18:48:30 INFO - 2015-11-18 18:48:30,337 DEBUG : Working with test: dromaeo_css 18:48:30 INFO - 2015-11-18 18:48:30,337 DEBUG : Generating results file: dromaeo_css 18:48:30 INFO - 2015-11-18 18:48:30,338 DEBUG : Working with test: kraken 18:48:30 INFO - 2015-11-18 18:48:30,338 DEBUG : Generating results file: kraken 18:48:30 INFO - 2015-11-18 18:48:30,338 INFO : javascript benchmark 18:48:30 INFO - 2015-11-18 18:48:30,339 DEBUG : Working with test: v8_7 18:48:30 INFO - 2015-11-18 18:48:30,339 DEBUG : Generating results file: v8_7 18:48:30 INFO - 2015-11-18 18:48:30,339 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:30 INFO - 2015-11-18 18:48:30,387 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 18:48:30 INFO - 2015-11-18 18:48:30,388 DEBUG : process_Request line: dromaeo_css 6839.03 graph.html#tests=[[72,64,43]] 18:48:30 INFO - 2015-11-18 18:48:30,388 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:30 INFO - 2015-11-18 18:48:30,430 DEBUG : process_Request line: kraken 1518.50 graph.html#tests=[[232,64,43]] 18:48:30 INFO - 2015-11-18 18:48:30,430 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:30 INFO - 2015-11-18 18:48:30,460 DEBUG : process_Request line: v8_7 21339.62 graph.html#tests=[[230,64,43]] 18:48:30 INFO - 2015-11-18 18:48:30,461 INFO : javascript benchmark 18:48:30 INFO - 2015-11-18 18:48:30,465 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3211.116942840719, 3241.7082462578364, 3262.4943133814268, 3303.2072302647357, 3305.8181800001, 5278.340207474066, 5331.173668081454, 5358.115910874706, 5361.3030306060255, 5387.865303367418, 5298.311219541454, 5391.73041347933, 5404.83785486436, 5428.864278393042, 5445.700486473243, 4600.7239565626005, 4879.609631229502, 5022.472640372763, 5034.42104158021, 5052.393712754475, 4796.664233503656, 5000.349954505909, 5023.472535383768, 5070.873228169288, 5133.922991155136, 4846.68496547725, 4896.902061958776, 4915.3855768029, 4943.728094954776, 4980.153373926441, 4998.475160108209, 5006.674566153225, 5009.899802003976, 5029.170186919154, 5047.596192304607, 4787.736674482902, 4890.413150421937, 4990.600751939873, 5000.7249601271915, 5035.043341765053, 5137.717425541594, 5149.974250128763, 5160.922586161173, 5216.26972223889, 5305.363356397257, 5833.562482813763, 5835.766569337221, 5837.3287071986415, 5842.561807864385, 5855.355910849803, 5676.290463692038, 5783.826485205408, 5788.739506722208, 5874.383189765099, 5889.8822023559715, 5326.733663316819, 5360.16917377807, 5393.919091213635, 5514.558835293207, 5514.641548299348, 5994.850128746729, 5998.850028749316, 6012.068129439944, 6013.909791353133, 6029.939700603026, 5808.535317174616, 5824.708764561755, 5846.824595262149, 5847.473727364558, 5849.853753656193, 5757.165211044375, 5851.297844258716, 5875.383084776037, 5899.528037756969, 5932.703364831741, 5748.109043098327, 5857.179994800731, 5896.115582662635, 5906.616069955439, 5913.142594323885, 5802.6808525531505, 5846.269216347957, 5863.73613187399, 5876.0, 5878.970605146947, 4422.20400327944, 4524.185646583646, 4525.0, 4526.298423744325, 4568.132054909556, 3952.0, 4026.5570787213383, 4026.59734026595, 4048.291548978917, 4084.2035803018125, 5511.889762204813, 5514.972425137848, 5515.420880807537, 5540.279763630702, 5550.722463876871, 5731.684757338387, 5880.647161170344, 5881.911771323434, 5883.294004719461, 5905.143754155709, 5402.243685883979, 5460.180972854104, 5492.725363731797, 5503.724813759376, 5552.167174923794, 5088.414832294259, 5118.129917913963, 5120.667156634807, 5129.128048231735, 5160.716160611202, 5931.436513531207, 6045.999999999912, 6057.273127224761, 6106.603070800384, 6121.265448146251, 5766.077427611562, 5834.883302334015, 5842.532597392284, 5868.647881127146, 5912.113183022409, 6050.697465126638, 6108.786192483241, 6122.418370254819, 6123.510119190365, 6125.877482450237, 5666.575006874418, 5781.971090144522, 5800.216970709069, 5802.593818432669, 5810.680412577349, 5829.7085145743395, 5859.824205273849, 5939.10913362999, 5960.254968128984, 5988.880222395441, 5685.061964775931, 5703.600747947604, 5713.200151978644, 5713.8285851424525, 5719.971400142973, 5587.413321601173, 5689.829305120853, 5712.971435142797, 5727.284089488814, 5729.541636668973, 5536.778528858962, 5583.525400341016, 5610.971945140248, 5619.578531610227, 5660.29246344207, 5635.9436405635415, 5646.407127251579, 5668.7165641718575, 5671.319441667027, 5711.685857277889, 5523.1991361253195, 5525.254090697865, 5527.198556209247, 5580.609357344947, 5649.971750141223], "unit": "ms", "name": "dojo.html", "value": 5382.938599982867}, {"lowerIsBetter": false, "replicates": [578.8263520943717, 581.9010768169411, 582.8455459303285, 595.7021489255372, 603.9400851505607, 39066.80466597666, 39614.405783913266, 39825.203495930065, 40113.99999999996, 40176.19647607046, 43801.56198438015, 43896.78051609745, 43986.0, 44072.779636101775, 44178.55821441785, 8765.16730910564, 8849.734507964771, 8877.112288771135, 8908.020117787071, 8958.23854972328, 8519.872201916976, 8642.870356944619, 8683.218510334069, 8693.913060869374, 8702.564871756418, 8713.041565427799, 8749.125087491231, 8815.426997245158, 8815.51514666693, 8830.86753698695, 8647.394682372267, 8650.091740367234, 8699.695510657097, 8715.869261961076, 8738.169873861973, 8623.611937462829, 8645.610947507377, 8770.254528365096, 8771.73684789451, 8780.209781119729, 39742.20515589672, 39900.40149397762, 40407.19185616271, 40433.797831010954, 40492.19015619701, 2968.762499000075, 3464.6015708193627, 3468.739844511672, 3537.221811201557, 3570.393033184365, 30125.246868828235, 30418.84790576055, 30849.691503084905, 31003.37993240145, 31509.212269693442, 3397.371486275022, 3402.132456223672, 3407.4207384744404, 3508.614052454228, 3529.699975502085, 276849.0, 277565.0, 279074.0, 279184.0, 279196.6040169806, 54981.72509137429, 55848.72075639596, 61489.0, 68397.65801171062, 69619.30380696128, 63262.0, 63344.0, 63610.36389636137, 63721.0, 63883.68058159773, 14495.782563261559, 14511.1293322401, 14527.273636318354, 14545.20001399912, 14620.0, 12021.218620789621, 12084.818727719092, 12101.515939362327, 12112.21270617407, 12287.692807679701, 20405.285814996405, 20844.47888802792, 21129.154833806475, 21134.260300889397, 21160.576788464452, 7870.567118808521, 8096.230858068474, 8101.230383113595, 8112.148224436467, 8155.510669359739, 893.8625078746206, 943.919766819828, 963.629002833914, 970.9805803883883, 986.2208855004609, 29358.412831743673, 29919.905606607328, 29953.700462995526, 29957.40085198284, 30310.0, 1062.0759938853248, 1064.3613831701073, 1067.69570672358, 1077.5312738958578, 1084.2897901874285, 15088.809016993953, 15393.230338483256, 15478.922605387126, 15497.690046199239, 15756.606084847741, 322704.0, 328205.0, 328382.0, 329324.0, 331712.0, 53329.73335133377, 54262.72868635631, 54267.0, 54324.18513722297, 54345.0, 1273.4343837255647, 1290.6708789258867, 1292.3409061378763, 1311.4623004568082, 1318.9340532973313, 28502.287442814097, 28960.565591515722, 29023.56464653074, 29449.705502944696, 29866.0, 48365.5163448361, 48474.75762621164, 48669.756651216514, 48773.268400974724, 48775.0, 302207.0, 303574.0, 304894.0, 304923.0, 305353.0, 33692.66307336895, 46597.0, 47343.52656473391, 47666.28500572425, 47796.28305575489, 45472.58955578279, 45542.77228613836, 45626.77186614178, 45813.541864580926, 45825.77087114543, 37172.256554869295, 37811.0, 38194.427083594324, 38557.228855422174, 39253.41119883261, 38425.0, 39330.41004384994, 39429.605703943744, 39731.205375892896, 39881.60118398779, 36561.81719091388, 36841.815790920875, 37115.628843711216, 37116.25767484689, 37495.0, 33397.0, 33884.66115338815, 35219.47170792492, 35918.8204058978, 35995.82002089973, 26055.478890421706, 26595.60106598364, 26622.0, 26783.330416739736, 26790.380685423443], "unit": "ms", "name": "ext.html", "value": 19067.245637527234}, {"lowerIsBetter": false, "replicates": [358.02259830662274, 359.05210244953327, 361.37662532132066, 361.9782813031218, 363.54919899324847, 12420.503179872794, 12780.424880880359, 16544.0, 17213.82786172138, 18070.186841592127, 11993.580224692138, 12053.517859285617, 12104.273743575392, 12301.753964920696, 12499.687507812308, 4523.638108951292, 4572.679912406133, 4652.418447694038, 4661.067786442708, 4665.160271151191, 4577.0, 4650.790714417859, 4677.508861569538, 4702.482726900038, 4703.459102203256, 4641.048585040058, 4646.837360692376, 4669.976650116745, 4751.311059896313, 4752.049590081998, 4000.2399544086675, 4731.4795372509, 4790.616750659939, 4816.54242846929, 4824.348712923763, 4381.25518661825, 4396.516383197878, 4415.094905544356, 4442.888927776799, 4453.554644535553, 13512.864871351358, 13771.380287887068, 13804.654883627889, 14150.646233844236, 14168.504102356368, 2113.2392338758036, 2114.439673486527, 2149.161826887515, 2182.454386403399, 2188.1466228171025, 9490.0, 10002.649907253211, 10048.145907597864, 10053.346532475365, 10395.94802025985, 1728.1186594836597, 1732.4975757030572, 1740.8172141925043, 1761.9823801761947, 1769.513383819447, 62382.0, 62469.0, 62726.686366568334, 62738.0, 63105.36894631087, 6742.292059333798, 6955.930440695528, 7029.61337126463, 7030.578165310098, 7041.753538626123, 8284.295834854105, 8353.916460835435, 8374.874376884352, 8407.159284071666, 8426.283765879847, 5622.353429355677, 5648.378678345379, 5653.660780353192, 5654.943450565524, 5686.800961966312, 5797.101449275285, 5871.500922421556, 5873.9412605873395, 5880.0591905295805, 5906.586538942319, 3281.45855933773, 3346.0, 3348.7823291485975, 3357.1774915145456, 3364.747643926666, 1163.4589915689075, 1166.445938179358, 1170.7599942011866, 1175.7589694112687, 1222.1750318534953, 410.73712823792795, 416.18843255650995, 443.81803460581006, 449.89202591377835, 482.5295337046422, 11668.0, 11811.822822657667, 12019.21875078117, 12050.638480845588, 12398.504059837505, 488.065354845474, 502.8943921776468, 505.7850413574216, 517.3093919617353, 518.4167811212386, 6121.969390153021, 6467.967660161669, 6474.902876456857, 6487.902681459782, 6518.413342799171, 386522.0, 392836.0, 535091.0, 542234.0, 554014.0, 78485.60757196292, 79152.6042369796, 79927.60579153654, 80113.0, 80323.59838200771, 555.3030946162621, 568.3407247592772, 569.3196630027078, 571.3115695586891, 572.3475238228423, 11059.668209953392, 11107.111431085332, 11135.168880586825, 11317.490712917774, 11328.150388720715, 35190.15291161503, 35910.28179436449, 37293.4405983905, 37323.440148397254, 37665.0, 49240.26139607837, 49554.75222624008, 50748.74625626848, 51039.0, 51347.2297915539, 8493.830123397622, 8603.35474839402, 8657.696980605648, 8661.480311181349, 8665.956670216608, 12505.0, 12616.243025418504, 12833.935830320788, 12912.225266484042, 12947.093703441045, 10386.272960892666, 10677.57289708403, 10914.126869850552, 10914.672559823219, 11198.552057917592, 10622.575096996034, 10726.302790318605, 10878.184136189659, 10926.180536459957, 11129.332240065622, 10676.786464270515, 10909.0, 11128.888711112786, 11202.83195752048, 11265.605703800327, 8350.0, 8350.206730360605, 8548.187922147386, 8573.61418736146, 8579.785505362415, 10768.277075667855, 10905.127589792955, 11099.334039957628, 11141.610043648434, 11181.32912025281], "unit": "ms", "name": "jquery.html", "value": 7060.484545026861}, {"lowerIsBetter": false, "replicates": [1482.7405204089284, 1533.7622668486385, 1600.6158521954735, 1663.3679201903283, 1767.8674099442544, 7287.380572651325, 7468.103827540703, 7649.120351159619, 7655.578943158131, 7660.080790305171, 7707.691692332314, 7779.183185765498, 7787.7274295399675, 7814.648340824662, 7814.92185078149, 2141.4325203821, 2149.602323570137, 2162.502624396382, 2166.1227202982736, 2178.16140785797, 2275.3287780104824, 2275.613145765224, 2351.8471299365488, 2354.0, 2357.95284094318, 2280.5552917181158, 2303.8617682939075, 2312.8496647717843, 2323.8373313868037, 2325.790678838904, 2183.3886511776727, 2189.321310393775, 2219.7336319641668, 2220.3893929169526, 2221.7000704904954, 2348.412896775806, 2356.1988923766003, 2362.433016076129, 2366.219147681261, 2370.0519792083132, 7383.07711536058, 7518.28576285252, 7523.3229009389415, 7529.698812047512, 7534.472586918918, 4648.535146485358, 4703.976480117578, 4713.764311784397, 4729.716217026989, 4731.597814185799, 3142.6543080261154, 3166.113488223278, 3208.807471551691, 3219.806811591289, 3233.7736358454918, 1053.9894601053968, 1068.4924660786064, 1085.7177133945152, 1116.8827273136367, 1119.9888001119966, 10217.74455638615, 10276.94861525695, 10284.845727314096, 10296.742581435448, 10302.072813446826, 4113.362428823537, 4156.750594964312, 4226.049138943775, 4226.091390351028, 4236.724612900151, 8378.539180345018, 8402.285805706584, 8433.987921449465, 8468.830623387621, 8471.407001509959, 4635.930461043087, 4671.392718946515, 4687.29690546414, 4704.15325241453, 4716.5990890774665, 4181.289180839264, 4295.3771703102875, 4300.397944287803, 4322.222000040023, 4344.804483798237, 3597.2805438912324, 3603.3874241378503, 3613.114786877232, 3625.3474374612297, 3645.8177091145335, 1129.6667483092508, 1135.6138912769698, 1138.800709875785, 1141.0, 1141.7716456708692, 1297.5523444411663, 1310.25315570126, 1313.9408726607326, 1351.0, 1356.2879488268738, 7069.681864316118, 7135.571865688075, 7149.92850071503, 7170.0, 7197.064381630355, 469.70878055605743, 472.2396940925108, 474.7768548782136, 477.3317355702045, 503.7305041802691, 5653.689047102366, 5667.773289068474, 5669.1496275558175, 5701.344345400332, 5702.45826646468, 8931.73204803857, 8974.19232269099, 9018.909810901807, 9045.0, 9091.81816363669, 8930.50882201472, 8980.24885147403, 9048.864267036, 9052.728418147466, 9074.682386116587, 7011.088558487466, 7013.053237812932, 7127.643617819088, 7179.353858152791, 7182.42540596751, 7140.678669459783, 7146.035285236427, 7171.605561694157, 7202.819929501804, 7205.171405288459, 7918.089419716806, 8007.599620019092, 8131.2681858633005, 8187.918120818954, 8214.13751556078, 7997.0, 8064.55644939534, 8163.061247956561, 8165.305948994402, 8195.426320157532, 6054.66699331541, 6174.845628859314, 6379.74481020754, 6612.471002319707, 6835.760748373783, 7175.174854891754, 7185.173705023989, 7200.243974382614, 7215.891761623475, 7250.202477727551, 6540.869182616226, 6657.101291325609, 6657.700403481758, 6745.5952642841585, 6909.30906909305, 6690.1971763386755, 6754.155730533684, 6838.863222735418, 6845.178578570603, 6890.483213758888, 6524.5432819704065, 6547.574407663486, 6555.311692272434, 6582.901256481253, 6594.604323740591, 6265.154204182377, 6266.342034086355, 6267.467265282502, 6282.057691346335, 6307.590006649553, 6206.782762603287, 6298.779542715983, 6322.841928951813, 6338.492920566251, 6338.841528961812], "unit": "ms", "name": "mootools.html", "value": 4328.326114146229}, {"lowerIsBetter": false, "replicates": [337.1571072319202, 340.5147664577976, 344.44716230450126, 344.8034620266446, 348.4981626457901, 6204.16243807087, 6304.747810087597, 6338.492920566349, 6346.873062538746, 6355.0, 6024.0963855421705, 6212.856564485257, 6234.158388617535, 6256.124142620037, 6259.874802503947, 4704.623630109583, 4727.976360118195, 4739.170645137105, 4774.450938142106, 4792.065547218293, 4796.928046079294, 4810.927836082444, 4817.253325734499, 4823.999999999983, 4824.807007719687, 4679.859604211879, 4702.7883745231375, 4729.361536192621, 4760.024195040026, 4770.093682200388, 4719.292106184065, 4720.740359280238, 4733.360996265512, 4737.265723812815, 4738.336632871401, 4019.2765302245584, 4131.5042194936495, 4176.895577610584, 4181.581841815824, 4193.098483825962, 5905.350411454737, 5929.822105336847, 5932.466078052953, 5937.8812423751715, 5958.5828991970575, 5480.095784195603, 5480.479354461319, 5498.862528436781, 5510.118381059011, 5513.641613295123, 6458.806235812933, 6556.377144171296, 6583.275839657634, 6597.63712995785, 6601.910684737013, 4476.1271552047365, 4477.664175186874, 4522.977385113087, 4527.524609915978, 4531.501534831166, 6828.317168283182, 6854.725810967555, 6901.930980690179, 6911.101556797584, 6916.861662766716, 4635.6986795858165, 4641.280601506772, 4642.257238841769, 4650.302454631832, 4656.8137274508645, 4594.747288899142, 4679.64902632304, 4682.01677647691, 4701.130290896195, 4703.670743047954, 5433.429489903582, 5440.91838622421, 5452.263944367539, 5459.508644222039, 5460.590455715837, 6676.097312266078, 6687.598744075371, 6691.732330706717, 6700.195976482853, 6703.12859328294, 1651.116652590882, 1660.6346603747397, 1671.1727694791157, 1674.9497515074568, 1675.0368538090647, 1091.2088735666737, 1093.6281664234198, 1095.9232853700164, 1113.7939481195842, 1122.3602546548389, 6325.557210995186, 6337.778177763848, 6341.524385671093, 6349.904751428732, 6358.618482891041, 6840.486963477759, 6854.485913556503, 6866.793996180123, 6875.621840798804, 6905.136857892763, 6267.2792628847355, 6327.810165694945, 6337.112804207414, 6350.110984462087, 6369.235691717024, 3762.962370376316, 3773.566039905446, 3793.867214647474, 3797.525309336333, 3803.1823158020607, 7071.575705457689, 7212.855742885113, 7267.672954716944, 7281.5631062136445, 7282.198958114604, 7102.0, 7110.182329032227, 7130.144382674009, 7134.393576545948, 7143.357097861218, 7069.9646501767165, 7140.143182818095, 7210.67551960163, 7258.201597824235, 7280.089988751406, 6939.375456208965, 6943.236244013054, 6955.721771129301, 6956.513044086865, 6960.721571137098, 6872.518923675295, 6885.208201056943, 6886.758963436256, 6893.241743408121, 6899.17209934811, 7047.01341812157, 7176.677049532679, 7227.819304517429, 7238.927610723826, 7239.565626062243, 4650.651201159859, 4690.788914498788, 4695.71825690446, 4699.718016918996, 4702.553257440538, 6861.897071543831, 6945.479089068239, 6954.269801670752, 6958.860822783618, 6960.269171736894, 6616.90074648871, 6711.0, 6742.764003259862, 6752.763653272112, 6752.966235168793, 6733.192016957996, 6738.966305168443, 6741.797746067626, 6750.189977202767, 6760.1549806274215, 6602.636854973022, 6617.0405291233465, 6625.271220165874, 6628.5028622852515, 6628.933710663025, 6601.306862779339, 6762.357576030269, 6771.52599318043, 6779.186497620317, 6783.898241526283, 6555.180602424697, 6567.9671601641685, 6577.901331479936, 6581.2102547694585, 6596.670166491752], "unit": "ms", "name": "prototype.html", "value": 5011.455426523379}, {"lowerIsBetter": false, "replicates": [841.6423020216406, 861.3884142258994, 863.6286396849353, 873.1443185678031, 874.6020560644903, 12546.937265313683, 12642.30467324298, 12700.936495317534, 13025.283609401478, 13054.93472532636, 13371.331433428339, 13593.184408935445, 13602.115862468932, 13613.931930340359, 13656.24890631015, 830.1366578758084, 844.6959094725894, 848.6520526584102, 849.2484151525913, 849.7578190215789, 836.7447928381841, 840.9243168114868, 845.4969293270494, 849.9575021248944, 850.2772643253247, 882.1531329923281, 885.0397318908991, 885.123727509764, 895.7626229049307, 896.6413434626137, 865.5109862927488, 885.9468431894074, 889.5018789477913, 889.7241855024963, 890.1410139215624, 646.3730181723721, 649.9057636642708, 650.2197363164174, 650.5251166648326, 652.3867564489412, 13495.392707328096, 13518.324083795771, 13553.661158471019, 13622.86377136226, 13672.794908076386, 12713.555025574153, 13071.215727056311, 13127.934360328138, 13403.731925361535, 13507.122037067558, 10842.241043126984, 10916.399598022106, 10918.890811091867, 10958.342499449978, 11051.944740276247, 3659.469376940329, 3660.6705396514176, 3718.9442158367647, 3827.349350610375, 3838.692904567628, 48868.0, 49031.0, 49083.0, 49247.0, 49367.50632493665, 10170.186385089175, 10222.846657300146, 10253.84619230712, 10254.53854576546, 10292.330998485075, 18755.812441875678, 18821.247150114115, 18993.525161871064, 19012.714809277873, 19038.524036899187, 7644.885326720104, 7721.189275126031, 7815.491993020435, 7837.255460731221, 7914.722984695623, 4365.4761428628135, 4368.257396242647, 4374.650027997816, 4468.687191896536, 4501.639868810487, 3030.2121448423127, 3043.5738996540504, 3084.8303343316334, 3091.1190310761326, 3099.9225019374694, 481.838584074339, 483.8766114640744, 484.4211167654689, 496.5258178439582, 497.6864575317599, 603.5225399734117, 622.3496446213718, 678.7963610916705, 684.360866460363, 724.3119036914952, 8078.192180781874, 8970.955145224232, 9317.0, 9521.952390238142, 9736.172425343784, 569.7037540478927, 677.9762708305186, 682.3960794696629, 697.5814511293285, 702.7118881258659, 3873.5351757789244, 3975.483187185705, 3991.341428664238, 4032.153247817991, 4098.774567398762, 56476.0, 56493.717531412905, 56801.88791970373, 56956.43043569594, 56978.0, 51087.23369149466, 52101.218481722804, 52294.47705522896, 52331.47668523342, 52340.0, 47232.5276747235, 47979.0, 48160.7591962045, 50626.74686626617, 50631.74684126556, 8786.956065219889, 8824.955875220583, 8865.512396818236, 9234.538273086184, 9266.721998340061, 36010.27979440449, 36373.454398183516, 36427.27145457023, 37251.44122838214, 37292.813535932146, 42609.0, 46628.30057549071, 46911.765441172574, 47051.05897881955, 47100.0, 11072.557097716324, 11621.302721836717, 11907.871778254012, 12042.63872083839, 12130.939345303217, 12444.0, 12778.74442511126, 12901.677458063248, 13034.826865582143, 13086.869131308946, 11006.504707288352, 11824.586139485076, 12193.756124877631, 12303.323317217639, 12466.376681166086, 11635.47640356203, 12076.939615301868, 12081.637550873487, 12109.0, 12523.74952500963, 12181.208221465577, 12274.81587776202, 12481.750364992831, 12528.123031387715, 12596.937015314865, 10298.639547615798, 10586.153107751208, 10624.0, 10678.145748339959, 10795.352278863293, 8968.775780605538, 9230.492322922304, 9296.431417780022, 10256.38461692301, 10417.583296668048], "unit": "ms", "name": "yui.html", "value": 6508.811192115313}], "name": "dromaeo_css", "value": 6839.024822290997}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 106.0, 113.0, 111.0, 111.0, 122.0, 111.0, 111.0, 112.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [160.0, 125.0, 163.0, 170.0, 128.0, 151.0, 154.0, 127.0, 171.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.6}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 163.0, 161.0, 162.0, 164.0, 161.0, 178.0, 167.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 164.5}, {"lowerIsBetter": true, "replicates": [73.0, 76.0, 71.0, 74.0, 73.0, 160.0, 72.0, 73.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.6}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 138.0, 90.0, 91.0, 90.0, 129.0, 91.0, 91.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 103.2}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 112.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 146.0, 147.0, 148.0, 149.0, 147.0, 147.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 97.0, 97.0, 96.0, 98.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.5}, {"lowerIsBetter": true, "replicates": [61.0, 60.0, 61.0, 61.0, 62.0, 62.0, 62.0, 61.0, 59.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 60.8}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 70.0, 69.0, 69.0, 71.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [84.0, 90.0, 83.0, 86.0, 91.0, 85.0, 85.0, 85.0, 84.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.8}, {"lowerIsBetter": true, "replicates": [122.0, 115.0, 120.0, 124.0, 124.0, 134.0, 122.0, 129.0, 117.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.5}, {"lowerIsBetter": true, "replicates": [144.0, 152.0, 156.0, 151.0, 153.0, 144.0, 153.0, 151.0, 152.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.0}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 66.0, 68.0, 67.0, 68.0, 68.0, 65.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1518.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.89250165892503], "unit": "score", "name": "Richards", "value": 212.80045599999988}, {"lowerIsBetter": false, "replicates": [193.53590090961873], "unit": "score", "name": "DeltaBlue", "value": 341.6317059999999}, {"lowerIsBetter": false, "replicates": [282.326369282891], "unit": "score", "name": "Encrypt", "value": 942.813102}, {"lowerIsBetter": false, "replicates": [5075.757575757576], "unit": "score", "name": "Decrypt", "value": 52.441629850746295}, {"lowerIsBetter": false, "replicates": [1075.268817204301], "unit": "score", "name": "RayTrace", "value": 688.1897699999998}, {"lowerIsBetter": false, "replicates": [725.6894049346879], "unit": "score", "name": "Earley", "value": 918.3860139999999}, {"lowerIsBetter": false, "replicates": [12231.707317073171], "unit": "score", "name": "Boyer", "value": 54.4865064805583}, {"lowerIsBetter": false, "replicates": [25125.0], "unit": "score", "name": "RegExp", "value": 36.25810945273629}, {"lowerIsBetter": false, "replicates": [593.8242280285035], "unit": "score", "name": "Splay", "value": 137.23084400000005}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 21339.621716305523}]} 18:48:30 INFO - RETURN: dromaeo_css: 6839.03 18:48:30 INFO - RETURN: kraken: 1518.50 18:48:30 INFO - RETURN: v8_7: 21339.62 18:48:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21339.62"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1518.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "6839.03"}}} 18:48:30 INFO - Return code: 0 18:48:30 INFO - # TBPL SUCCESS # 18:48:30 INFO - Running post-action listener: _resource_record_post_action 18:48:30 INFO - Running post-run listener: _resource_record_post_run 18:48:31 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 12288; Write bytes: 516075520; Read time: 336; Write time: 687960 18:48:31 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 194371584; Read time: 224; Write time: 348552 18:48:31 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 8192; Write bytes: 275730432; Read time: 112; Write time: 206032 18:48:31 INFO - Running post-run listener: _upload_blobber_files 18:48:31 INFO - Blob upload gear active. 18:48:31 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:48:31 INFO - Copying logs to upload dir... 18:48:31 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.509527 ========= master_lag: 3.93 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-18 18:48:35.420556) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:35.424591) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009981 build_url: 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.27 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:35.702981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:35.703335) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1447898504.848634-1653395505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:35.719161) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:48:35.719482) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:48:35.719771) ========= ========= Total master_lag: 4.37 =========