builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1448422969.79 results: success (0) buildid: 20151124173005 builduid: d2f08f29c500421c8d9a4ce721b90239 revision: f39db2865764809d45ea45f70e1ea0d56bdcc082 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.793949) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.794385) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.794678) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006693 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.819129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.819414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.839450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.839705) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004749 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.887931) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.888221) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.888617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:49.888882) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 19:42:50-- 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% 77.2M=0s 2015-11-24 19:42:50 (77.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111707 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.013516) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.013810) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021985 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.066715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.067038) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f39db2865764809d45ea45f70e1ea0d56bdcc082 --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 f39db2865764809d45ea45f70e1ea0d56bdcc082 --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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 19:42:50,634 truncating revision to first 12 chars 2015-11-24 19:42:50,634 Setting DEBUG logging. 2015-11-24 19:42:50,635 attempt 1/10 2015-11-24 19:42:50,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f39db2865764?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 19:42:50,908 unpacking tar archive at: fx-team-f39db2865764/testing/mozharness/ program finished with exit code 0 elapsedTime=0.856411 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.939324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.939602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.952215) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.952472) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:42:50.952833) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-24 19:42:50.953106) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:42:51 INFO - MultiFileLogger online at 20151124 19:42:51 in /builds/slave/test-pgo 19:42:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 19:42:51 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:42:51 INFO - {'append_to_log': False, 19:42:51 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:42:51 INFO - 'blob_upload_branch': 'Fx-Team', 19:42:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:42:51 INFO - 'branch': 'Fx-Team', 19:42:51 INFO - 'buildbot_json_path': 'buildprops.json', 19:42:51 INFO - 'config_files': ('talos/linux_config.py',), 19:42:51 INFO - 'default_actions': ('clobber', 19:42:51 INFO - 'read-buildbot-config', 19:42:51 INFO - 'download-and-extract', 19:42:51 INFO - 'populate-webroot', 19:42:51 INFO - 'create-virtualenv', 19:42:51 INFO - 'install', 19:42:51 INFO - 'run-tests'), 19:42:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:42:51 INFO - 'download_minidump_stackwalk': True, 19:42:51 INFO - 'download_symbols': 'ondemand', 19:42:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:42:51 INFO - 'tooltool.py': '/tools/tooltool.py', 19:42:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:42:51 INFO - '/tools/misc-python/virtualenv.py')}, 19:42:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:42:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:42:51 INFO - 'installer_path': 'installer.exe', 19:42:51 INFO - 'log_level': 'info', 19:42:51 INFO - 'log_name': 'talos', 19:42:51 INFO - 'log_to_console': True, 19:42:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:42:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:42:51 INFO - 'opt_config_files': (), 19:42:51 INFO - 'pip_index': False, 19:42:51 INFO - 'sps_profile': False, 19:42:51 INFO - 'sps_profile_interval': 0, 19:42:51 INFO - 'suite': 'dromaeojs', 19:42:51 INFO - 'system_bits': '64', 19:42:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:42:51 INFO - 'title': 'talos-linux64-ix-051', 19:42:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:42:51 INFO - 'use_talos_json': True, 19:42:51 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:42:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:42:51 INFO - 'work_dir': 'build'} 19:42:51 INFO - ##### 19:42:51 INFO - ##### Running clobber step. 19:42:51 INFO - ##### 19:42:51 INFO - Running pre-action listener: _resource_record_pre_action 19:42:51 INFO - Running main action method: clobber 19:42:51 INFO - rmtree: /builds/slave/test-pgo/build 19:42:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:42:52 INFO - Running post-action listener: _resource_record_post_action 19:42:52 INFO - ##### 19:42:52 INFO - ##### Running read-buildbot-config step. 19:42:52 INFO - ##### 19:42:52 INFO - Running pre-action listener: _resource_record_pre_action 19:42:52 INFO - Running main action method: read_buildbot_config 19:42:52 INFO - Using buildbot properties: 19:42:52 INFO - { 19:42:52 INFO - "properties": { 19:42:52 INFO - "buildnumber": 337, 19:42:52 INFO - "product": "firefox", 19:42:52 INFO - "script_repo_revision": "production", 19:42:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:42:52 INFO - "repository": "", 19:42:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 19:42:52 INFO - "buildid": "20151124173005", 19:42:52 INFO - "slavename": "talos-linux64-ix-051", 19:42:52 INFO - "pgo_build": "True", 19:42:52 INFO - "basedir": "/builds/slave/test-pgo", 19:42:52 INFO - "project": "", 19:42:52 INFO - "platform": "ubuntu64_hw", 19:42:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:42:52 INFO - "slavebuilddir": "test", 19:42:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 19:42:52 INFO - "branch": "fx-team", 19:42:52 INFO - "repo_path": "integration/fx-team", 19:42:52 INFO - "stage_platform": "linux64-pgo", 19:42:52 INFO - "builduid": "d2f08f29c500421c8d9a4ce721b90239", 19:42:52 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:52 INFO - }, 19:42:52 INFO - "sourcestamp": { 19:42:52 INFO - "repository": "", 19:42:52 INFO - "hasPatch": false, 19:42:52 INFO - "project": "", 19:42:52 INFO - "branch": "fx-team-linux64-pgo-talos", 19:42:52 INFO - "changes": [ 19:42:52 INFO - { 19:42:52 INFO - "category": null, 19:42:52 INFO - "files": [ 19:42:52 INFO - { 19:42:52 INFO - "url": null, 19:42:52 INFO - "name": "https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 19:42:52 INFO - } 19:42:52 INFO - ], 19:42:52 INFO - "repository": "", 19:42:52 INFO - "rev": "f39db2865764809d45ea45f70e1ea0d56bdcc082", 19:42:52 INFO - "who": "sendchange", 19:42:52 INFO - "when": 1448422951, 19:42:52 INFO - "number": 6703557, 19:42:52 INFO - "comments": "", 19:42:52 INFO - "project": "", 19:42:52 INFO - "at": "Tue 24 Nov 2015 19:42:31", 19:42:52 INFO - "branch": "fx-team-linux64-pgo-talos", 19:42:52 INFO - "revlink": "", 19:42:52 INFO - "properties": [ 19:42:52 INFO - [ 19:42:52 INFO - "buildid", 19:42:52 INFO - "20151124173005", 19:42:52 INFO - "Change" 19:42:52 INFO - ], 19:42:52 INFO - [ 19:42:52 INFO - "builduid", 19:42:52 INFO - "d2f08f29c500421c8d9a4ce721b90239", 19:42:52 INFO - "Change" 19:42:52 INFO - ], 19:42:52 INFO - [ 19:42:52 INFO - "pgo_build", 19:42:52 INFO - "True", 19:42:52 INFO - "Change" 19:42:52 INFO - ] 19:42:52 INFO - ], 19:42:52 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:52 INFO - } 19:42:52 INFO - ], 19:42:52 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 19:42:52 INFO - } 19:42:52 INFO - } 19:42:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 19:42:52 INFO - Running post-action listener: _resource_record_post_action 19:42:52 INFO - ##### 19:42:52 INFO - ##### Running download-and-extract step. 19:42:52 INFO - ##### 19:42:52 INFO - Running pre-action listener: _resource_record_pre_action 19:42:52 INFO - Running main action method: download_and_extract 19:42:52 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:42: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')]} 19:42:52 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:42:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json 19:42:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json 19:42:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:42:54 INFO - Downloaded 1302 bytes. 19:42:54 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:42:54 INFO - Using the following test package requirements: 19:42:54 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:54 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:42:54 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'jsshell-linux-x86_64.zip'], 19:42:54 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:42:54 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:54 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:42:54 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:42:54 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:42:54 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:42:54 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:42:54 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:42:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:42: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')]} 19:42:54 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:42:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:57 INFO - Downloaded 22530453 bytes. 19:42:57 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 19:42:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:57 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 19:42:58 INFO - Return code: 0 19:42:58 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 19:42:58 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')]} 19:42:58 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:42:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:42:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:42:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:06 INFO - Downloaded 22530453 bytes. 19:43:06 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 19:43:06 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:43:06 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 19:43:07 INFO - Return code: 0 19:43:07 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')]} 19:43:07 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:43:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:08 INFO - Downloaded 11131717 bytes. 19:43:08 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 19:43:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:08 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 19:43:09 INFO - Return code: 0 19:43:09 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')]} 19:43:09 INFO - https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:43:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/l8-vlC9-QQ2FogrSydxV7g/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 19:43:12 INFO - Downloaded 58394943 bytes. 19:43:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:12 INFO - mkdir: /builds/slave/test-pgo/properties 19:43:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:43:12 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:43:12 INFO - Contents: 19:43:12 INFO - build_url:https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:43:12 INFO - Running post-action listener: _resource_record_post_action 19:43:12 INFO - Running post-action listener: set_extra_try_arguments 19:43:12 INFO - ##### 19:43:12 INFO - ##### Running populate-webroot step. 19:43:12 INFO - ##### 19:43:12 INFO - Running pre-action listener: _resource_record_pre_action 19:43:12 INFO - Running main action method: populate_webroot 19:43:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:43:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:43:12 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 19:43:12 INFO - '../fennec_ids.txt'], 19:43:12 INFO - 'tests': ['tcheck2']}, 19:43:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:43:12 INFO - '--tppagecycles', 19:43:12 INFO - '7'], 19:43:12 INFO - 'tests': ['tsvgm']}}, 19:43:12 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:43:12 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:43:12 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:43:12 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:43:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'tests': ['damp', 'tps']}, 19:43:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['damp', 'tps']}, 19:43:12 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:43:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:43:12 INFO - 'other': {'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'other_l64': {'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'other_nol64': {'tests': ['a11yr', 19:43:12 INFO - 'ts_paint', 19:43:12 INFO - 'tpaint', 19:43:12 INFO - 'sessionrestore', 19:43:12 INFO - 'sessionrestore_no_auto_restore']}, 19:43:12 INFO - 'svgr': {'tests': ['tsvgx', 19:43:12 INFO - 'tsvgr_opacity', 19:43:12 INFO - 'tart', 19:43:12 INFO - 'tscrollx', 19:43:12 INFO - 'cart']}, 19:43:12 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['tsvgx', 19:43:12 INFO - 'tsvgr_opacity', 19:43:12 INFO - 'tart', 19:43:12 INFO - 'tscrollx', 19:43:12 INFO - 'cart']}, 19:43:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'tests': ['tp5o']}, 19:43:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'talos_options': ['--e10s'], 19:43:12 INFO - 'tests': ['tp5o']}, 19:43:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'talos_options': ['--xperf_path', 19:43:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:12 INFO - 'tests': ['tp5n']}, 19:43:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:12 INFO - 'talos_options': ['--e10s', 19:43:12 INFO - '--xperf_path', 19:43:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:12 INFO - 'tests': ['tp5n']}}, 19:43:12 INFO - 'talos.zip': {'path': '', 19:43:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:43:12 INFO - Running post-action listener: _resource_record_post_action 19:43:12 INFO - ##### 19:43:12 INFO - ##### Running create-virtualenv step. 19:43:12 INFO - ##### 19:43:12 INFO - Running pre-action listener: _resource_record_pre_action 19:43:12 INFO - Running main action method: create_virtualenv 19:43:12 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:43:12 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 19:43:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:43:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:43:12 INFO - Using real prefix '/usr' 19:43:12 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:43:12 INFO - Installing distribute.............................................................................................................................................................................................done. 19:43:14 INFO - Installing pip.................done. 19:43:14 INFO - Return code: 0 19:43:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:14 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:14 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 19:43:14 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 19:43:14 INFO - Using env: {'DISPLAY': ':0', 19:43:14 INFO - 'HOME': '/home/cltbld', 19:43:14 INFO - 'LANG': 'en_US.UTF-8', 19:43:14 INFO - 'LANGUAGE': 'en_US:en', 19:43:14 INFO - 'LOGNAME': 'cltbld', 19:43:14 INFO - 'MAIL': '/var/mail/cltbld', 19:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:14 INFO - 'MOZ_NO_REMOTE': '1', 19:43:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:14 INFO - 'NO_EM_RESTART': '1', 19:43:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:14 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:14 INFO - 'SHELL': '/bin/bash', 19:43:14 INFO - 'SHLVL': '1', 19:43:14 INFO - 'TERM': 'linux', 19:43:14 INFO - 'TMOUT': '86400', 19:43:14 INFO - 'USER': 'cltbld', 19:43:14 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:14 INFO - '_': '/tools/buildbot/bin/python'} 19:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:43:14 INFO - Cleaning up... 19:43:14 INFO - Return code: 0 19:43:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:14 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:14 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 19:43:14 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 19:43:14 INFO - Using env: {'DISPLAY': ':0', 19:43:14 INFO - 'HOME': '/home/cltbld', 19:43:14 INFO - 'LANG': 'en_US.UTF-8', 19:43:14 INFO - 'LANGUAGE': 'en_US:en', 19:43:14 INFO - 'LOGNAME': 'cltbld', 19:43:14 INFO - 'MAIL': '/var/mail/cltbld', 19:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:14 INFO - 'MOZ_NO_REMOTE': '1', 19:43:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:14 INFO - 'NO_EM_RESTART': '1', 19:43:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:14 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:14 INFO - 'SHELL': '/bin/bash', 19:43:14 INFO - 'SHLVL': '1', 19:43:14 INFO - 'TERM': 'linux', 19:43:14 INFO - 'TMOUT': '86400', 19:43:14 INFO - 'USER': 'cltbld', 19:43:14 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:14 INFO - '_': '/tools/buildbot/bin/python'} 19:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:15 INFO - Downloading/unpacking psutil>=0.7.1 19:43:15 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 19:43:15 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 19:43:15 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 19:43:15 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 19:43:15 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 19:43:15 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 19:43:16 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 19:43:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 19:43:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:43:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:16 INFO - Installing collected packages: psutil 19:43:16 INFO - Running setup.py install for psutil 19:43:16 INFO - building 'psutil._psutil_linux' extension 19:43:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:43:17 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 19:43:17 INFO - building 'psutil._psutil_posix' extension 19:43:17 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 19:43:17 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 19:43:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:17 INFO - Successfully installed psutil 19:43:17 INFO - Cleaning up... 19:43:17 INFO - Return code: 0 19:43:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:43:17 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')]} 19:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:17 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')]} 19:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:17 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:17 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 19:43:17 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 19:43:17 INFO - Using env: {'DISPLAY': ':0', 19:43:17 INFO - 'HOME': '/home/cltbld', 19:43:17 INFO - 'LANG': 'en_US.UTF-8', 19:43:17 INFO - 'LANGUAGE': 'en_US:en', 19:43:17 INFO - 'LOGNAME': 'cltbld', 19:43:17 INFO - 'MAIL': '/var/mail/cltbld', 19:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:17 INFO - 'MOZ_NO_REMOTE': '1', 19:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:17 INFO - 'NO_EM_RESTART': '1', 19:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:17 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:17 INFO - 'SHELL': '/bin/bash', 19:43:17 INFO - 'SHLVL': '1', 19:43:17 INFO - 'TERM': 'linux', 19:43:17 INFO - 'TMOUT': '86400', 19:43:17 INFO - 'USER': 'cltbld', 19:43:17 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:17 INFO - '_': '/tools/buildbot/bin/python'} 19:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:43:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 19:43:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:43:19 INFO - Installing collected packages: mozsystemmonitor 19:43:19 INFO - Running setup.py install for mozsystemmonitor 19:43:19 INFO - Successfully installed mozsystemmonitor 19:43:19 INFO - Cleaning up... 19:43:19 INFO - Return code: 0 19:43:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:43: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')]} 19:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43: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')]} 19:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:19 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:19 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 19:43:19 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 19:43:19 INFO - Using env: {'DISPLAY': ':0', 19:43:19 INFO - 'HOME': '/home/cltbld', 19:43:19 INFO - 'LANG': 'en_US.UTF-8', 19:43:19 INFO - 'LANGUAGE': 'en_US:en', 19:43:19 INFO - 'LOGNAME': 'cltbld', 19:43:19 INFO - 'MAIL': '/var/mail/cltbld', 19:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:19 INFO - 'MOZ_NO_REMOTE': '1', 19:43:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:19 INFO - 'NO_EM_RESTART': '1', 19:43:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:19 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:19 INFO - 'SHELL': '/bin/bash', 19:43:19 INFO - 'SHLVL': '1', 19:43:19 INFO - 'TERM': 'linux', 19:43:19 INFO - 'TMOUT': '86400', 19:43:19 INFO - 'USER': 'cltbld', 19:43:19 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:19 INFO - '_': '/tools/buildbot/bin/python'} 19:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:43:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 19:43:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:43:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 19:43:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:43:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:22 INFO - Downloading docopt-0.6.1.tar.gz 19:43:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 19:43:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:43:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:43:22 INFO - Running setup.py install for blobuploader 19:43:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:43:22 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:43:22 INFO - Running setup.py install for requests 19:43:22 INFO - Running setup.py install for docopt 19:43:22 INFO - Successfully installed blobuploader requests docopt 19:43:22 INFO - Cleaning up... 19:43:22 INFO - Return code: 0 19:43:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:22 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:22 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 19:43:22 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 19:43:22 INFO - Using env: {'DISPLAY': ':0', 19:43:22 INFO - 'HOME': '/home/cltbld', 19:43:22 INFO - 'LANG': 'en_US.UTF-8', 19:43:22 INFO - 'LANGUAGE': 'en_US:en', 19:43:22 INFO - 'LOGNAME': 'cltbld', 19:43:22 INFO - 'MAIL': '/var/mail/cltbld', 19:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:22 INFO - 'MOZ_NO_REMOTE': '1', 19:43:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:22 INFO - 'NO_EM_RESTART': '1', 19:43:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:22 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:22 INFO - 'SHELL': '/bin/bash', 19:43:22 INFO - 'SHLVL': '1', 19:43:22 INFO - 'TERM': 'linux', 19:43:22 INFO - 'TMOUT': '86400', 19:43:22 INFO - 'USER': 'cltbld', 19:43:22 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:22 INFO - '_': '/tools/buildbot/bin/python'} 19:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:23 INFO - Running setup.py (path:/tmp/pip-MmqhsX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:23 INFO - Running setup.py (path:/tmp/pip-j6dXUY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:23 INFO - Running setup.py (path:/tmp/pip-7iz0g2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:23 INFO - Running setup.py (path:/tmp/pip-KiNsuJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:23 INFO - Running setup.py (path:/tmp/pip-EJonzH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:23 INFO - Running setup.py (path:/tmp/pip-DpbSvr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:23 INFO - Running setup.py (path:/tmp/pip-NPkfvh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:23 INFO - Running setup.py (path:/tmp/pip-I7JHiE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:23 INFO - Running setup.py (path:/tmp/pip-b5_siZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:23 INFO - Running setup.py (path:/tmp/pip-oWjvyT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:23 INFO - Running setup.py (path:/tmp/pip-wYpyFw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:23 INFO - Running setup.py (path:/tmp/pip-L0g0Ub-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:23 INFO - Running setup.py (path:/tmp/pip-soUD3_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:23 INFO - Running setup.py (path:/tmp/pip-LKuPC5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:24 INFO - Running setup.py (path:/tmp/pip-ro0kgk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:24 INFO - Running setup.py (path:/tmp/pip-jsHFVc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:24 INFO - Running setup.py (path:/tmp/pip-1j4e1o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:43:24 INFO - Running setup.py install for manifestparser 19:43:24 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:43:24 INFO - Running setup.py install for mozcrash 19:43:24 INFO - Running setup.py install for mozdebug 19:43:24 INFO - Running setup.py install for mozdevice 19:43:24 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:43:24 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:43:24 INFO - Running setup.py install for mozfile 19:43:24 INFO - Running setup.py install for mozhttpd 19:43:24 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:43:24 INFO - Running setup.py install for mozinfo 19:43:24 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:43:24 INFO - Running setup.py install for mozInstall 19:43:25 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Running setup.py install for mozleak 19:43:25 INFO - Running setup.py install for mozlog 19:43:25 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Running setup.py install for moznetwork 19:43:25 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Running setup.py install for mozprocess 19:43:25 INFO - Running setup.py install for mozprofile 19:43:25 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Running setup.py install for mozrunner 19:43:25 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Running setup.py install for mozscreenshot 19:43:25 INFO - Running setup.py install for moztest 19:43:25 INFO - Running setup.py install for mozversion 19:43:25 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:43:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:43:25 INFO - Cleaning up... 19:43:26 INFO - Return code: 0 19:43:26 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:26 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')]} 19:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:26 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')]} 19:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:26 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:26 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 19:43:26 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 19:43:26 INFO - Using env: {'DISPLAY': ':0', 19:43:26 INFO - 'HOME': '/home/cltbld', 19:43:26 INFO - 'LANG': 'en_US.UTF-8', 19:43:26 INFO - 'LANGUAGE': 'en_US:en', 19:43:26 INFO - 'LOGNAME': 'cltbld', 19:43:26 INFO - 'MAIL': '/var/mail/cltbld', 19:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:26 INFO - 'MOZ_NO_REMOTE': '1', 19:43:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:26 INFO - 'NO_EM_RESTART': '1', 19:43:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:26 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:26 INFO - 'SHELL': '/bin/bash', 19:43:26 INFO - 'SHLVL': '1', 19:43:26 INFO - 'TERM': 'linux', 19:43:26 INFO - 'TMOUT': '86400', 19:43:26 INFO - 'USER': 'cltbld', 19:43:26 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:26 INFO - '_': '/tools/buildbot/bin/python'} 19:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:26 INFO - Running setup.py (path:/tmp/pip-kVf6Pw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:26 INFO - Running setup.py (path:/tmp/pip-gL14rI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:26 INFO - Running setup.py (path:/tmp/pip-czH5vH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:26 INFO - Running setup.py (path:/tmp/pip-_1yOuK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:26 INFO - Running setup.py (path:/tmp/pip-9ufXCl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:26 INFO - Running setup.py (path:/tmp/pip-2jouOj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:26 INFO - Running setup.py (path:/tmp/pip-2XXFYC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:26 INFO - Running setup.py (path:/tmp/pip-wqKo6p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:26 INFO - Running setup.py (path:/tmp/pip-rFvVb1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:26 INFO - Running setup.py (path:/tmp/pip-afwwTY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:43:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:26 INFO - Running setup.py (path:/tmp/pip-2HyiSM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:43:27 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)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:27 INFO - Running setup.py (path:/tmp/pip-g3djQZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:43:27 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)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:27 INFO - Running setup.py (path:/tmp/pip-HVxu2t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:43:27 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)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:27 INFO - Running setup.py (path:/tmp/pip-DxTyvC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:43:27 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)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:27 INFO - Running setup.py (path:/tmp/pip-ozd5WW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:27 INFO - Running setup.py (path:/tmp/pip-k1AUKs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 19:43:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:27 INFO - Running setup.py (path:/tmp/pip-wWoiQH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:29 INFO - Downloading blessings-1.5.1.tar.gz 19:43:29 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 19:43:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:43:29 INFO - Installing collected packages: blessings 19:43:29 INFO - Running setup.py install for blessings 19:43:29 INFO - Successfully installed blessings 19:43:29 INFO - Cleaning up... 19:43:29 INFO - Return code: 0 19:43:29 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:43:29 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:43:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:43:29 INFO - Reading from file tmpfile_stdout 19:43:29 INFO - Current package versions: 19:43:29 INFO - argparse == 1.2.1 19:43:29 INFO - blessings == 1.5.1 19:43:29 INFO - blobuploader == 1.2.4 19:43:29 INFO - docopt == 0.6.1 19:43:29 INFO - manifestparser == 1.1 19:43:29 INFO - mozInstall == 1.12 19:43:29 INFO - mozcrash == 0.16 19:43:29 INFO - mozdebug == 0.1 19:43:29 INFO - mozdevice == 0.47 19:43:29 INFO - mozfile == 1.2 19:43:29 INFO - mozhttpd == 0.7 19:43:29 INFO - mozinfo == 0.9 19:43:29 INFO - mozleak == 0.1 19:43:29 INFO - mozlog == 3.1 19:43:29 INFO - moznetwork == 0.27 19:43:29 INFO - mozprocess == 0.22 19:43:29 INFO - mozprofile == 0.27 19:43:29 INFO - mozrunner == 6.11 19:43:29 INFO - mozscreenshot == 0.1 19:43:29 INFO - mozsystemmonitor == 0.0 19:43:29 INFO - moztest == 0.7 19:43:29 INFO - mozversion == 1.4 19:43:29 INFO - psutil == 3.1.1 19:43:29 INFO - requests == 1.2.3 19:43:29 INFO - wsgiref == 0.1.2 19:43:29 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:29 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 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:29 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 19:43:29 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 19:43:29 INFO - Using env: {'DISPLAY': ':0', 19:43:29 INFO - 'HOME': '/home/cltbld', 19:43:29 INFO - 'LANG': 'en_US.UTF-8', 19:43:29 INFO - 'LANGUAGE': 'en_US:en', 19:43:29 INFO - 'LOGNAME': 'cltbld', 19:43:29 INFO - 'MAIL': '/var/mail/cltbld', 19:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:29 INFO - 'MOZ_NO_REMOTE': '1', 19:43:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:29 INFO - 'NO_EM_RESTART': '1', 19:43:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:29 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:29 INFO - 'SHELL': '/bin/bash', 19:43:29 INFO - 'SHLVL': '1', 19:43:29 INFO - 'TERM': 'linux', 19:43:29 INFO - 'TMOUT': '86400', 19:43:29 INFO - 'USER': 'cltbld', 19:43:29 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:29 INFO - '_': '/tools/buildbot/bin/python'} 19:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:43:29 INFO - Cleaning up... 19:43:29 INFO - Return code: 0 19:43:29 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:29 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, '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': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', '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 19:43:29 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', 'jsonschema'] in /builds/slave/test-pgo/build 19:43:29 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 jsonschema 19:43:29 INFO - Using env: {'DISPLAY': ':0', 19:43:29 INFO - 'HOME': '/home/cltbld', 19:43:29 INFO - 'LANG': 'en_US.UTF-8', 19:43:29 INFO - 'LANGUAGE': 'en_US:en', 19:43:29 INFO - 'LOGNAME': 'cltbld', 19:43:29 INFO - 'MAIL': '/var/mail/cltbld', 19:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:29 INFO - 'MOZ_NO_REMOTE': '1', 19:43:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:29 INFO - 'NO_EM_RESTART': '1', 19:43:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:29 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:29 INFO - 'SHELL': '/bin/bash', 19:43:29 INFO - 'SHLVL': '1', 19:43:29 INFO - 'TERM': 'linux', 19:43:29 INFO - 'TMOUT': '86400', 19:43:29 INFO - 'USER': 'cltbld', 19:43:29 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:29 INFO - '_': '/tools/buildbot/bin/python'} 19:43:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:30 INFO - Downloading/unpacking jsonschema 19:43:30 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 19:43:30 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 19:43:30 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 19:43:30 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 19:43:30 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 19:43:30 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 19:43:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:43:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 19:43:31 INFO - Downloading/unpacking functools32 (from jsonschema) 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:43:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 19:43:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:43:31 INFO - warning: no files found matching '*.txt' 19:43:31 INFO - no previously-included directories found matching 'build' 19:43:31 INFO - no previously-included directories found matching 'dist' 19:43:31 INFO - no previously-included directories found matching '.git*' 19:43:31 INFO - Installing collected packages: jsonschema, functools32 19:43:32 INFO - Running setup.py install for functools32 19:43:32 INFO - warning: no files found matching '*.txt' 19:43:32 INFO - no previously-included directories found matching 'build' 19:43:32 INFO - no previously-included directories found matching 'dist' 19:43:32 INFO - no previously-included directories found matching '.git*' 19:43:32 INFO - Successfully installed jsonschema functools32 19:43:32 INFO - Cleaning up... 19:43:32 INFO - Return code: 0 19:43:32 INFO - Running post-action listener: _resource_record_post_action 19:43:32 INFO - Running post-action listener: _start_resource_monitoring 19:43:32 INFO - Starting resource monitoring. 19:43:32 INFO - ##### 19:43:32 INFO - ##### Running install step. 19:43:32 INFO - ##### 19:43:32 INFO - Running pre-action listener: _resource_record_pre_action 19:43:32 INFO - Running main action method: install 19:43:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:43:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:43:32 INFO - Reading from file tmpfile_stdout 19:43:32 INFO - Detecting whether we're running mozinstall >=1.0... 19:43:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:43:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:43:32 INFO - Reading from file tmpfile_stdout 19:43:32 INFO - Output received: 19:43:32 INFO - Usage: mozinstall [options] installer 19:43:32 INFO - Options: 19:43:32 INFO - -h, --help show this help message and exit 19:43:32 INFO - -d DEST, --destination=DEST 19:43:32 INFO - Directory to install application into. [default: 19:43:32 INFO - "/builds/slave/test-pgo"] 19:43:32 INFO - --app=APP Application being installed. [default: firefox] 19:43:32 INFO - mkdir: /builds/slave/test-pgo/build/application 19:43:32 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'] 19:43:32 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 19:43:47 INFO - Reading from file tmpfile_stdout 19:43:47 INFO - Output received: 19:43:47 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:43:47 INFO - Running post-action listener: _resource_record_post_action 19:43:47 INFO - ##### 19:43:47 INFO - ##### Running run-tests step. 19:43:47 INFO - ##### 19:43:47 INFO - Running pre-action listener: _resource_record_pre_action 19:43:47 INFO - Running main action method: run_tests 19:43:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:43:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:43:47 INFO - Python 2.7.3 19:43:47 INFO - Return code: 0 19:43:47 INFO - grabbing minidump binary from tooltool 19:43:47 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')]} 19:43:47 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 0x19a9450>, '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 0x19aa350>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aa7e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:43:47 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 19:43:47 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 19:43:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:43:47 INFO - Return code: 0 19:43:47 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:43:47 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:43:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:43:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:43:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:43:47 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:43:47 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 --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost 19:43:47 INFO - Using env: {'DISPLAY': ':0', 19:43:47 INFO - 'HOME': '/home/cltbld', 19:43:47 INFO - 'LANG': 'en_US.UTF-8', 19:43:47 INFO - 'LANGUAGE': 'en_US:en', 19:43:47 INFO - 'LOGNAME': 'cltbld', 19:43:47 INFO - 'MAIL': '/var/mail/cltbld', 19:43:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:43:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:47 INFO - 'MOZ_NO_REMOTE': '1', 19:43:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:43:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:47 INFO - 'NO_EM_RESTART': '1', 19:43:47 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:43:47 INFO - 'PWD': '/builds/slave/test-pgo', 19:43:47 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:43:47 INFO - 'SHELL': '/bin/bash', 19:43:47 INFO - 'SHLVL': '1', 19:43:47 INFO - 'TERM': 'linux', 19:43:47 INFO - 'TMOUT': '86400', 19:43:47 INFO - 'USER': 'cltbld', 19:43:47 INFO - 'XDG_SESSION_COOKIE': 'd333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128', 19:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:47 INFO - '_': '/tools/buildbot/bin/python'} 19:43:47 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] with output_timeout 3600 19:43:47 INFO - mozversion INFO | application_buildid: 20151124173005 19:43:47 INFO - mozversion INFO | application_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 19:43:47 INFO - mozversion INFO | application_display_name: Nightly 19:43:47 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:43:47 INFO - mozversion INFO | application_name: Firefox 19:43:47 INFO - mozversion INFO | application_remotingname: firefox 19:43:47 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 19:43:47 INFO - mozversion INFO | application_vendor: Mozilla 19:43:47 INFO - mozversion INFO | application_version: 45.0a1 19:43:47 INFO - mozversion INFO | platform_buildid: 20151124173005 19:43:47 INFO - mozversion INFO | platform_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 19:43:47 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 19:43:47 INFO - mozversion INFO | platform_version: 45.0a1 19:43:47 INFO - 2015-11-24 19:43:47,378 DEBUG : using testdate: 1448423027 19:43:47 INFO - 2015-11-24 19:43:47,378 DEBUG : actual date: 1448423027 19:43:47 INFO - 2015-11-24 19:43:47,386 INFO : starting webserver on 'localhost:34502' 19:43:47 INFO - 2015-11-24 19:43:47,387 INFO : Starting test suite talos-linux64-ix-051 19:43:47 INFO - 2015-11-24 19:43:47,387 INFO : Starting test dromaeo_css 19:43:47 INFO - 2015-11-24 19:43:47,388 DEBUG : operating with platform_type : linux_ 19:43:47 INFO - 2015-11-24 19:43:47,389 INFO : Initialising browser for dromaeo_css test... 19:43:47 INFO - 2015-11-24 19:43:47,403 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA5m5Ed/profile http://localhost:34502/getInfo.html 19:43:51 INFO - 2015-11-24 19:43:51,329 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:43:51 INFO - 2015-11-24 19:43:51,329 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:43:51 INFO - 2015-11-24 19:43:51,329 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:43:51 INFO - 2015-11-24 19:43:51,329 DEBUG : BROWSER_OUTPUT: __metrics 19:43:51 INFO - 2015-11-24 19:43:51,486 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:43:52 INFO - 2015-11-24 19:43:52,622 INFO : Browser initialized. 19:43:52 INFO - 2015-11-24 19:43:52,623 INFO : Running cycle 1/1 for dromaeo_css test... 19:43:52 INFO - 2015-11-24 19:43:52,623 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpA5m5Ed/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:44:04 INFO - 2015-11-24 19:44:04,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 138969088 19:44:04 INFO - 2015-11-24 19:44:04,316 DEBUG : BROWSER_OUTPUT: 19:44:54 INFO - 2015-11-24 19:44:54,961 DEBUG : BROWSER_OUTPUT: 1448423094960 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] 19:47:05 INFO - 2015-11-24 19:47:05,044 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34502/tests/dromaeo/cssquery-ext.html) 19:47:06 INFO - 2015-11-24 19:47:06,277 DEBUG : BROWSER_OUTPUT: RSS: Main: 270569472 19:47:06 INFO - 2015-11-24 19:47:06,278 DEBUG : BROWSER_OUTPUT: 19:50:08 INFO - 2015-11-24 19:50:08,850 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-ext.html (next: http://localhost:34502/tests/dromaeo/cssquery-jquery.html) 19:50:09 INFO - 2015-11-24 19:50:09,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 219131904 19:50:09 INFO - 2015-11-24 19:50:09,412 DEBUG : BROWSER_OUTPUT: 19:53:11 INFO - 2015-11-24 19:53:11,326 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34502/tests/dromaeo/cssquery-mootools.html) 19:53:11 INFO - 2015-11-24 19:53:11,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 213721088 19:53:11 INFO - 2015-11-24 19:53:11,900 DEBUG : BROWSER_OUTPUT: 19:56:13 INFO - 2015-11-24 19:56:13,776 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34502/tests/dromaeo/cssquery-prototype.html) 19:56:14 INFO - 2015-11-24 19:56:14,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 199868416 19:56:14 INFO - 2015-11-24 19:56:14,342 DEBUG : BROWSER_OUTPUT: 19:59:16 INFO - 2015-11-24 19:59:16,930 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34502/tests/dromaeo/cssquery-yui.html) 19:59:17 INFO - 2015-11-24 19:59:17,511 DEBUG : BROWSER_OUTPUT: RSS: Main: 203177984 19:59:17 INFO - 2015-11-24 19:59:17,512 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/dromaeo/cssquery-yui.html (next: http://localhost:34502/tests/dromaeo/cssquery-dojo.html) 20:02:20 INFO - 2015-11-24 20:02:20,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 275431424 20:02:20 INFO - 2015-11-24 20:02:20,287 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,287 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:02:20 INFO - 2015-11-24 20:02:20,287 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:02:20 INFO - 2015-11-24 20:02:20,288 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:02:20 INFO - 2015-11-24 20:02:20,288 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:02:20 INFO - 2015-11-24 20:02:20,288 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3175;3258.804471731695;3301.4387554115797;3315.1380641033325;3319.5850518685165;5245.10833158363;5372.140457526787;5471.863203419912;5487.6158668893195;5497.532709719673;5449.3188351456065;5527.834164975057;5590.496855283024;5604.271444712192;5616.6068375213645;5003.724795136266;5004.924926126102;5015.824446144386;5024.171011783052;5047.798088076473;4804.6876952997945;5103.515166059218;5112.8977420451565;5132;5183.948160518403;4910.484399138093;4913.950860491385;4946.9010619787405;4962.60299176065;4981.402231732215;4994.101061808873;5032.169692000815;5033.320501732256;5035.647504674675;5084.644074914758;4799.1601469743;4974.850754477371;4997.375328083989;5005.199168133081;5046.7224302663335;5161.458046905059;5217.5565076968505;5261.6842989420375;5290.761915713763;5321.547668448186;6008.789692360747;6044.2746870375395;6044.576879618428;6045.365236650133;6053.878922421571;5686.772529098831;5842.73707683151;6079;6092.66490343031;6104.3590423005835;5594.776208951637;5623.0721930880945;5645.040343141676;5645.915311270334;5705.6006079574445;6038.788642397495;6048.848778780478;6051.485623722033;6056.152138700585;6073.271207455134;5693.857653558612;5734;5767.884642307215;5851.151583020438;5902.8819423611285;5898.675572843535;5900.527957763368;5913.172155898178;5973.342932277446;5988.311344195473;5842.883142337129;5929.73316200772;5930.822075337746;5935.584509084409;5937.881242375129;5673.375928647845;5793.131030345397;5925.496332811673;5926.911096333559;5932.525397968152;4467.262901621261;4475.328700694857;4510.75190864499;4513.1650644630845;4553.453585569753;4027.8791636250958;4035.273650742836;4051.4327994080854;4068.3083875741195;4093.7543747375253;5298.3906850712665;5446.101393270145;5618.775248990077;5618.803341883015;5625.887482250332;5849.181114643953;5902.704864756745;5963.105534169909;5967.045272756338;6012.819615411545;5568.860778480571;5582.637128586629;5592.720363981784;5595.21666966625;5602.1036634138345;4856.077345304381;4986.700797952134;4988.201887697951;4989.126902791997;5041.117804384218;6075.210222671025;6092.45167934888;6159.876802463926;6177.413145751147;6180.567360284737;5944.5838791284195;5945.078512830518;5952;5957.165996760544;6002.459778619946;5749.281339832521;5850.7074646266665;6133.04937734634;6137.938620613737;6140.846478838065;5689.886202276014;5843.883122337615;5886.323072846678;5979.342272349951;5987.850303742267;5939.07944268639;5974.462298393166;5987.13186587951;5988.730507127277;5991.490723288569;5817.447342502455;5824.097264923944;5833.300003999547;5834.795782147605;5840.445157710011;5656.660600363992;5676.148577713376;5741.368449470471;5788.27646544182;5881.617694849821;5734.2258795062135;5746.856328591652;5750.7987220447085;5752.71236438168;5764.855878603068;5817.185594016925;5851.444112809277;5858.5898987070495;5871.178035075179;5879.676617785892;5404.7297635118875;5427.6472029317965;5501.449855014466;5653.491185793298;5734.741936612946 20:02:20 INFO - 2015-11-24 20:02:20,288 DEBUG : BROWSER_OUTPUT: |1;ext.html;572.3332317849705;585.3794977324036;587.667967598307;590.4272855330331;597.2176448852007;31314.843425782867;40722.18555628886;41266.38100428496;41515.79242103789;41535.58464415355;43573.7821310893;43719.56280437195;44038.779806101;44044.33933491;44083.77958110213;7619.504732192418;7823.374130069581;8667.739967800977;8835.160659737314;8840.115988401172;10360.326578772356;10627.628033018846;10643.946780266087;10652.201084918623;10656.573737050508;10366.378017318984;10437.634682786103;10482.633107841228;10488.79022419551;10493.898140695232;7862.449628525977;8062.919370806276;10170.796584068352;10318.277720559563;10385.79228415435;9913.40519568821;10040.548175332127;10048.145907597864;10050.497475126214;10052.095311421934;37239.069023274365;37297.627023729685;37695.4345684815;37729;37800.24399512022;3340.048086295418;3457.11843479911;3522.6477352264824;3648.6716195542263;3916.471276377681;30220.69779302201;30260.394792104256;30398.240043999078;31204.063878083467;31465.37069258602;3915.549711783153;3928.8821335359985;3941.881743547698;3964.32606456903;4122.299209134424;277281;278605;278950;279094;279608;53921.730391347795;55106.72446637741;56472;58901.41098589045;59563.702181488814;55550;55688.72155639277;55871;56206.43793562094;56389.43610563924;13738.450461981609;13876.375563099684;13877.653058673412;13881.375338109809;13882.167069975834;10418.166546676248;10459.424731639832;10460.52927618259;10462.581496740046;10574.577016919236;19199.808001920082;19425.222991080198;19465.610687786164;19476.70784938227;19499.902500487406;7571.507851989713;7852.450328476952;7875.448718589644;7895.763127106196;7960.522368657899;914.3508109242513;995.5221493682928;1031.9587216511406;1122.2985633978763;1143.0341361549476;25228.873855630973;25287.36781580475;25364.11225607095;25471.36321591975;25661.61507577388;1021.8007488539663;1082.976587125159;1108.5288752280248;1146.5585749486509;1215.3133479584008;14478.927605361905;14569.41722331095;14679.19264440466;14757.483488077865;14927.701445971019;305127;309600;309661;309881;310330;52382.21426678603;52615.73692131515;52803.73598131985;52835.73582132142;52900.47099528956;1287.22766340197;1301.9804702929653;1303.48512337628;1305.0799186573443;1461.9839211747947;26710.59934101029;27693.446131077668;27739;28116.71883281141;28282.85858570694;43968.56031439645;44246.557534424246;44281.33577996397;44286;44309;287342;290715;291783;292273;292287;26657.208087085262;28092.157235282975;28664;28827.56758648664;31392.843035784674;33981.15047123842;34640;39205.2158956825;39875.40186897141;40797.79601101975;33387.16532086717;33573.32853342968;34110.147246319044;34145;34798.47802283018;34120;34180.65819341874;34435.65564344425;36285.63714362823;37294.25411491701;31151.53272700866;31161;36545.45181822222;36597.634023659426;37007.259854802214;28007.299817504725;31296.530552041284;32253.033188860136;33466;33759.15602109967;24201;25369.873150634867;25399.6190057153;25448.745512544636;25890.87054564715 20:02:20 INFO - 2015-11-24 20:02:20,288 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.34962368489346;359.40697848549905;359.83447614097514;359.8506619752803;362.26821819923737;14189.574312770608;14272.857271427283;17163.828361716394;17765.111744412792;18462.90768546157;16781.91609041953;17011.31954721813;17427;17796.733049004244;17930.641387172247;5319.5744340452675;5437;5446.754896029688;5451.318585176853;5454.91817622736;3883.3010058189516;4014.45804816349;5392.1911713242935;5426.2674538937135;5907.350191478956;5949.732262048218;5989.1016347548;6015.969920150393;6020.066889632114;6020.638761674291;5849.941500585003;5943.791967281124;5973.253343332061;5976.641401515923;6014.398560143951;5210.66130701507;5216.765245563959;5219.112750832368;5252.973735131338;5259.342582177228;19832.80167198324;19920.70118948217;20141.295054673163;20413.48966275855;20425;2141.7751136130637;2171.4788450771703;2194.3416974907464;2214.745304290011;2370.2533701883854;13238.73522529544;13444.798328025088;13465.932670336684;13652.453901843914;13688.863111368859;1859.2191279662575;1870.6352261308914;1913.3685883658497;1951.1951444913398;1969.4682435742413;62782;65712;66229.66885165591;66547;66973.3302666972;10121.24090693201;10148.340357876714;10249.538770755335;10251.794964100825;10292.897071029343;13439.395227214798;13487.52793652237;13543.119697219649;13568.59294221175;13568.728625427437;6940.6529673516125;7086.50394472382;7092.326229008236;7104;7135.82160445993;6656.70044847983;6693.330666933268;6694.765683201064;6705.6982435790505;6719.764808231786;4341.739495630272;4408.096340250241;4411.051623900877;4418.955810441918;4448.755318457516;1708.2654458582929;1723.707718258121;1766.5230387795223;1771.176402972624;1816.6548355812618;426.88260728299343;444.69093979684453;906.5110754382279;1015.4059874973076;1015.6140666546665;9457;10125.898741012496;10638.574457021787;10749.140068794477;10861.45692715377;421.8586773430935;430.8815075854165;433.6552440809564;442.28570858063995;449.49880882815427;5402.783888644489;5599.860003499945;5644.379118296984;5689.9146512802345;5695.686737229409;507219;507823;510086;513767;517993;78472.6076369626;80275;82092;82573;83323.58338208392;477.32696897374535;482.30788818046216;483.7291116974435;485.76440426393174;485.8299595141743;10429.895701042893;10446.582136714751;10465.843012354668;10478.528466219192;10538.262321637718;34970.12574685653;35480.29039419249;35619.10952226215;36460.27079458449;36754.81622591977;49701.751491242314;50941.490585095155;51074.23388649099;51271.48728512667;56224.71887640536;13689.041767076209;14286.142831430148;14496.130232186102;14755.336009879367;14791.556253312418;17940.91029544844;18006.63986720232;18209.089545522937;18394;18878.716819248;14690.853091469377;14731.852681473476;15220.543383698507;15608.84391156074;15913.443029493912;15275.847241527443;15391.61520961985;15541.766873496681;15698.450554230547;16105.758413623571;11978.820317695066;12419.379031048593;15607.609809754393;15684.372625094868;15850.207489625242;14350.784738229144;14525.782113268098;14594.489192878198;14665.606767357083;15055.924720376328;15338.309776060327;15423.6144096394;15496.457623983106;15804.68390632158;16436.260368283696 20:02:20 INFO - 2015-11-24 20:02:20,289 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1454.614527150305;1500.2873635023361;1519.3694616734056;1640.786697729295;1764.8058713541516;6695.12963314769;7435.553866767991;7815.140334563201;7847.960760196205;7856.253655902694;7572.12920514141;7727.652255648495;7743.380529557636;7766.883496747553;7770.4560680752365;1998.4304473225134;2045.0286114095834;2045.2023710752744;2283.725952885652;2334.206369834256;2011.1251605551631;2296.9310920672406;2354.0936739355384;2363.9409014774596;2365.124903785602;2244.528648983707;2278.4190031541934;2292.713410823647;2294.2314324701247;2300.298408985259;1960.8823470591667;2022.0395312226722;2287.2452090809993;2308.134449581407;2308.376738280663;2266.8526545774635;2309.364924645719;2310.7573704760925;2311.4683622766856;2313.236631911482;7551.962240188819;7736.149023607455;7738.071431428265;7739.110002349689;7747.651355689008;4649.139909116824;4783;4783.928241076386;4787.54518320759;4802.663813533054;2975.494165991765;3123.6563977962405;3135.6237251529733;3148.8110713357273;3161.873525058995;1052.2529004406804;1054.0328973938415;1066.9679909602723;1083.1984331594642;1130.8077626803463;10499.73750656232;10522.684319470429;10524.736881577945;10530.578776848917;10544.789104217949;4264.189803937242;4375.321825117112;4393;4443.289073748185;4448.354988526646;9100.08999100098;9114.361994660443;9115.906091268958;9117.133872282122;9120.407173533831;4716.575508204278;4744.525547445228;4755.809767609326;4759.16714574948;4772.594329482033;4276.957230427718;4295.420118284054;4341.587549182823;4353.390525326457;4353.978230108829;3718.6095459976855;3748.887533374003;3758.567764707094;3789.2989796887664;3796.1838204786104;1212.3816853404828;1227.7728620205291;1230.3171739684383;1235.234154824015;1238.0405185980937;1059.7191744187796;1081.8323159910226;1082.3614067700096;1226.172333674766;1337.9397927093305;7172.46206534512;7210.351068403764;7219.675114619855;7224.582060262791;7343.082114735658;476.276060388208;480.075853981085;484.46224690593414;489.17573887998833;490.399260905388;5429.294191755047;5510.256115424447;5697.344805347355;5853.09277062056;6008;8647.697330593524;8803.647854085764;8910.86633700495;9041.547922603844;9071.501067441353;8719.52042637661;8796.38425310235;8826.382153249211;8827.073157318517;8828.558572071372;6850.595627896269;6951.617661028591;6976.337247961435;7033.050538177383;7046.048783414276;6849;6957.547759395623;6959.269276726073;7008.123984501937;7011.439084873095;8065.475744076616;8139.430239883388;8166.83666326682;8228.958855205685;8270.875936860832;8237.1516223482;8326.70856520019;8617;8647.0920553341;8652.221300083023;6965.094537710065;6975.058367120374;7000.544964577286;7039.824004399931;7041.647917604202;7509.9249007509225;7577.431692622965;7590.7722768317035;7592.16486186508;7597.924020759942;7137.785866424016;7146.392556632536;7213.891791623025;7232.312930271616;7280.3083707047745;7082.752103676346;7099.82250443743;7180;7185.17370502378;7224.530405523624;7017.894731579133;7070.222275549789;7070.434365250665;7114.324139206767;7135.607541585263;6414.807555773335;6451.935480645134;6477.093206951125;6484.416402523795;6489.3510648934725;6386.233651961795;6451.516136289703;6498.187726534184;6505.577137486048;6507.023946408077 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: |4;prototype.html;334.32966901362767;337.6016300765097;344.8655024540429;348.49642266924303;350.9578850537936;5861.7948371807;5969.567303847078;5980.31226408962;6004.939950600493;6011.128386383972;5779.21980532628;5914.704264786754;5940.643561386309;5945.554083443737;5953.493953013988;4614.100250451163;4617.399738034068;4627.167109920213;4628.352030715702;4629.213033784265;4369.978150109245;4451.399061126739;4499.820007199708;4579.656525760582;4626.907461850761;4530.728156310615;4551.590356867881;4558.680892337537;4564.817407303721;4569.885752856172;4591.219492686251;4594.081183763261;4597.954020459802;4600.976995115003;4609.585137337622;4049.8177582008584;4050;4051.858184963512;4055.9188816223805;4058.4521089652812;5543.194205928478;5589.217509548666;5622.32532096151;5632.887342253173;5636.887262254773;5357.142857142839;5359.330083739533;5362.678239305654;5364.222187782789;5373.301470808769;6079.544034197453;6321.367863213688;6328.873422531569;6331.113644089831;6333.904991425086;4359.411479450266;4404.933925991113;4504.504504504502;4518.390017347649;4519.7740112994215;6540;6573.474122070223;6578.243501997236;6583.177102862142;6587.670616469205;4611.100835337077;4611.239145540948;4613.1465678848845;4613.446386433649;4617.168909596305;4546.181687296253;4657.906841863144;4671.766411679402;4679.110968915895;4680;5345.599080069011;5360.222767698662;5365.892682146413;5369.24830523727;5375.3280839895015;6389.648569328639;6424.421802037839;6431.6462594556815;6435.967820160869;6436.227652681709;1641.827608101156;1673.0045622854382;1674.4976507047836;1676.094908749286;1676.522191175509;1102.7077824376547;1117.9105671546256;1120.960766373173;1122.5004872831512;1141.5833220874322;6074.787382441594;6095.725692343855;6096.603720758137;6104.481119104926;6123.326434092246;6513.739450422025;6521.6413097279155;6537.411632952962;6541.313162118005;6543.378379053982;5919.437653422918;6087.604305720114;6090.6041107328765;6090.994985827378;6100.572959892765;3514.0687717754818;3519.9296014079573;3542.875999340011;3549.591796943333;3553.8578456861437;6730.528862979642;6732.192136943598;6734.292899245509;6737.629430381376;6740.460763138937;6670.666466676646;6676.298988606223;6689.397954184147;6689.732410703517;6692.966535167293;6685.164354455693;6766.898496522556;6776.390124888783;6779.881319582312;6780.694868730919;6358.046293056079;6450.451711604566;6482.319356467503;6640.302768209269;6655.068290439439;6519.3480651936325;6537.640429776408;6543.607383557001;6551.181102362205;6561.114249576437;6825.078614386995;6829.6585170742255;6832.658367081725;6841.760538381132;6848.280930502425;4382.298832186835;4480.305552639346;4508.278675411918;4508.954910450985;4509.594136527729;6543.738250469928;6558.311377305315;6563.343665633399;6566.540342176002;6571.178602674666;6374.203224596926;6400.327965563735;6401.775937842153;6409.519286053472;6413.551051426542;6289.559730818799;6396.29640739509;6420.871582568229;6453.774117905851;6477.578957367756;6260.499160067275;6331.430171284606;6334.588251763805;6335.271443784024;6346.650934198478;6489.837754055998;6493.318201588954;6509.934900651122;6512.251091124582;6516.902246466401;6183.659898705614;6347.365263473615;6350.714217860115;6351.809445716636;6357.078223657451 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: |5;yui.html;770.9460337776354;816.8611336072867;828.353883970503;862.9352798540111;875.5972252763728;13199.076064675464;13325.200487970716;13348.532801351954;13356.933215333935;13358.732825343499;12381.814272785892;12569.685757856058;13092.279924604143;13175.60473185806;13193.076484646079;816.4896939412866;822.0956947357919;829.7178959153887;830.6552780596076;832.7252006837729;813.9104698483162;828.494618282847;833.4790755777639;833.5332213960202;836.3309352517991;869.5956380283167;873.3755364914085;874.1477059866643;874.6545114679698;878.5958459108786;872.2803686958284;874.5059041641484;874.7244617945358;875.9386842920998;876.3033388456192;644.9387308205713;646.3084499585445;646.8512242184324;647.3203136706439;648.1994736500418;12276;12320.445579948924;12385.93807030959;12413.379331033413;12428.689282767913;10892.455377231187;11161.441927903572;11523.07815374768;12104;12204.450799714034;10158.238132140044;10207.591696332138;10244.846327305097;10310.639127630571;10385.480725963671;3524.1894364296354;3581.982090089559;3589.74871758977;3595.8381872815526;3721.0697325668584;41475;41548;42224.57775422268;44440.777796111135;49659.50340496585;9480.383775054599;9505.809883802285;9810.558524866397;9860.408375497349;10232.181425485944;16536.255868485947;16573.50279491617;16584.50246492607;16595;16660.333586656398;7495.812604684817;7606.429517786189;7614.619269036526;7625.733099341496;7820.257075577811;4440.555944405598;4441.578050085237;4460.397846290709;4488.259437192892;4526.185286648369;3096.2723759916403;3155.2269693925136;3155.4162479941283;3159.0996565978585;3160.8577614007427;560.5963706131579;573.2919843992665;586.3110844757393;592.567425779175;608;531.7979167916167;579.8173575323759;579.9652020878676;637.827786497643;689.0387908867102;8567.700130495527;8635.956820215859;8701.434406763667;8861.73414797557;8947.418417802823;553.1674829381755;579.9594028418055;595.6723801908933;643.340575909687;709.4749885085;3890.319194141014;4117.094239267356;4128.422020917074;4178.185253875526;4178.623923846869;57179.42820571741;57393.426065739644;58023.419765802646;58222.41777582255;58501.414985850446;45932.540674593496;52404.47595524072;53096.46903530993;53481;53754.46245537573;42155.15689686251;49546;49813.50186498161;49916.75041624841;49937.500624993285;8585.65657373698;8795.736127916172;8828.293736500937;9018.819623607622;9603;33314.66685333115;34541.13647158841;34951.300973980884;36045.81977090098;36223.27553448969;40360;43196;45595.54404455913;48834.511654883;49446.752766235935;10974.231803773659;11205.775884482428;11303.547858085913;11468.311901285615;11753.118516111155;12038.699032524257;12084.36291027081;12087.873932948714;12234.266444159235;12288.754224915272;11803.527858885549;11809.940950295193;11915.642530724092;12113.81829272544;12263.816042759545;10512.885685949144;10654.467276636293;10781.19141064439;10809.783804324026;10922.726931826768;11556.133290003452;11729.882701173221;11768.70578235551;11996.940015299868;12253.816192757295;9395.248380129437;9586.904130958601;9595.13643772064;9854.211663066793;9896.653617123366;8913.242374398249;9216;9534.521024533533;9566.617335306788;9597.136257736836 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1007 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:02:20 INFO - 2015-11-24 20:02:20,292 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424140280__endTimestamp 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5484.17 Median:5689.89 stddev:629.47 (11.1%) stddev-sans-first:604.78 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: Values: 3175.0 3258.8 3301.4 3315.1 3319.6 5245.1 5372.1 5471.9 5487.6 5497.5 5449.3 5527.8 5590.5 5604.3 5616.6 5003.7 5004.9 5015.8 5024.2 5047.8 4804.7 5103.5 5112.9 5132.0 5183.9 4910.5 4914.0 4946.9 4962.6 4981.4 4994.1 5032.2 5033.3 5035.6 5084.6 4799.2 4974.9 4997.4 5005.2 5046.7 5161.5 5217.6 5261.7 5290.8 5321.5 6008.8 6044.3 6044.6 6045.4 6053.9 5686.8 5842.7 6079.0 6092.7 6104.4 5594.8 5623.1 5645.0 5645.9 5705.6 6038.8 6048.8 6051.5 6056.2 6073.3 5693.9 5734.0 5767.9 5851.2 5902.9 5898.7 5900.5 5913.2 5973.3 5988.3 5842.9 5929.7 5930.8 5935.6 5937.9 5673.4 5793.1 5925.5 5926.9 5932.5 4467.3 4475.3 4510.8 4513.2 4553.5 4027.9 4035.3 4051.4 4068.3 4093.8 5298.4 5446.1 5618.8 5618.8 5625.9 5849.2 5902.7 5963.1 5967.0 6012.8 5568.9 5582.6 5592.7 5595.2 5602.1 4856.1 4986.7 4988.2 4989.1 5041.1 6075.2 6092.5 6159.9 6177.4 6180.6 5944.6 5945.1 5952.0 5957.2 6002.5 5749.3 5850.7 6133.0 6137.9 6140.8 5689.9 5843.9 5886.3 5979.3 5987.9 5939.1 5974.5 5987.1 5988.7 5991.5 5817.4 5824.1 5833.3 5834.8 5840.4 5656.7 5676.1 5741.4 5788.3 5881.6 5734.2 5746.9 5750.8 5752.7 5764.9 5817.2 5851.4 5858.6 5871.2 5879.7 5404.7 5427.6 5501.4 5653.5 5734.7 20:02:20 INFO - 2015-11-24 20:02:20,293 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,294 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45737.99 Median:31250.30 stddev:76541.61 (244.9%) stddev-sans-first:76681.20 20:02:20 INFO - 2015-11-24 20:02:20,294 DEBUG : BROWSER_OUTPUT: Values: 572.3 585.4 587.7 590.4 597.2 31314.8 40722.2 41266.4 41515.8 41535.6 43573.8 43719.6 44038.8 44044.3 44083.8 7619.5 7823.4 8667.7 8835.2 8840.1 10360.3 10627.6 10643.9 10652.2 10656.6 10366.4 10437.6 10482.6 10488.8 10493.9 7862.4 8062.9 10170.8 10318.3 10385.8 9913.4 10040.5 10048.1 10050.5 10052.1 37239.1 37297.6 37695.4 37729.0 37800.2 3340.0 3457.1 3522.6 3648.7 3916.5 30220.7 30260.4 30398.2 31204.1 31465.4 3915.5 3928.9 3941.9 3964.3 4122.3 277281.0 278605.0 278950.0 279094.0 279608.0 53921.7 55106.7 56472.0 58901.4 59563.7 55550.0 55688.7 55871.0 56206.4 56389.4 13738.5 13876.4 13877.7 13881.4 13882.2 10418.2 10459.4 10460.5 10462.6 10574.6 19199.8 19425.2 19465.6 19476.7 19499.9 7571.5 7852.5 7875.4 7895.8 7960.5 914.4 995.5 1032.0 1122.3 1143.0 25228.9 25287.4 25364.1 25471.4 25661.6 1021.8 1083.0 1108.5 1146.6 1215.3 14478.9 14569.4 14679.2 14757.5 14927.7 305127.0 309600.0 309661.0 309881.0 310330.0 52382.2 52615.7 52803.7 52835.7 52900.5 1287.2 1302.0 1303.5 1305.1 1462.0 26710.6 27693.4 27739.0 28116.7 28282.9 43968.6 44246.6 44281.3 44286.0 44309.0 287342.0 290715.0 291783.0 292273.0 292287.0 26657.2 28092.2 28664.0 28827.6 31392.8 33981.2 34640.0 39205.2 39875.4 40797.8 33387.2 33573.3 34110.1 34145.0 34798.5 34120.0 34180.7 34435.7 36285.6 37294.3 31151.5 31161.0 36545.5 36597.6 37007.3 28007.3 31296.5 32253.0 33466.0 33759.2 24201.0 25369.9 25399.6 25448.7 25890.9 20:02:20 INFO - 2015-11-24 20:02:20,294 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,294 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28484.97 Median:18670.19 stddev:83714.28 (448.4%) stddev-sans-first:83922.48 20:02:20 INFO - 2015-11-24 20:02:20,296 DEBUG : BROWSER_OUTPUT: Values: 357.3 359.4 359.8 359.9 362.3 14189.6 14272.9 17163.8 17765.1 18462.9 16781.9 17011.3 17427.0 17796.7 17930.6 5319.6 5437.0 5446.8 5451.3 5454.9 3883.3 4014.5 5392.2 5426.3 5907.4 5949.7 5989.1 6016.0 6020.1 6020.6 5849.9 5943.8 5973.3 5976.6 6014.4 5210.7 5216.8 5219.1 5253.0 5259.3 19832.8 19920.7 20141.3 20413.5 20425.0 2141.8 2171.5 2194.3 2214.7 2370.3 13238.7 13444.8 13465.9 13652.5 13688.9 1859.2 1870.6 1913.4 1951.2 1969.5 62782.0 65712.0 66229.7 66547.0 66973.3 10121.2 10148.3 10249.5 10251.8 10292.9 13439.4 13487.5 13543.1 13568.6 13568.7 6940.7 7086.5 7092.3 7104.0 7135.8 6656.7 6693.3 6694.8 6705.7 6719.8 4341.7 4408.1 4411.1 4419.0 4448.8 1708.3 1723.7 1766.5 1771.2 1816.7 426.9 444.7 906.5 1015.4 1015.6 9457.0 10125.9 10638.6 10749.1 10861.5 421.9 430.9 433.7 442.3 449.5 5402.8 5599.9 5644.4 5689.9 5695.7 507219.0 507823.0 510086.0 513767.0 517993.0 78472.6 80275.0 82092.0 82573.0 83323.6 477.3 482.3 483.7 485.8 485.8 10429.9 10446.6 10465.8 10478.5 10538.3 34970.1 35480.3 35619.1 36460.3 36754.8 49701.8 50941.5 51074.2 51271.5 56224.7 13689.0 14286.1 14496.1 14755.3 14791.6 17940.9 18006.6 18209.1 18394.0 18878.7 14690.9 14731.9 15220.5 15608.8 15913.4 15275.8 15391.6 15541.8 15698.5 16105.8 11978.8 12419.4 15607.6 15684.4 15850.2 14350.8 14525.8 14594.5 14665.6 15055.9 15338.3 15423.6 15496.5 15804.7 16436.3 20:02:20 INFO - 2015-11-24 20:02:20,296 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,296 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5404.45 Median:6400.52 stddev:2785.22 (43.5%) stddev-sans-first:2777.21 20:02:20 INFO - 2015-11-24 20:02:20,297 DEBUG : BROWSER_OUTPUT: Values: 1454.6 1500.3 1519.4 1640.8 1764.8 6695.1 7435.6 7815.1 7848.0 7856.3 7572.1 7727.7 7743.4 7766.9 7770.5 1998.4 2045.0 2045.2 2283.7 2334.2 2011.1 2296.9 2354.1 2363.9 2365.1 2244.5 2278.4 2292.7 2294.2 2300.3 1960.9 2022.0 2287.2 2308.1 2308.4 2266.9 2309.4 2310.8 2311.5 2313.2 7552.0 7736.1 7738.1 7739.1 7747.7 4649.1 4783.0 4783.9 4787.5 4802.7 2975.5 3123.7 3135.6 3148.8 3161.9 1052.3 1054.0 1067.0 1083.2 1130.8 10499.7 10522.7 10524.7 10530.6 10544.8 4264.2 4375.3 4393.0 4443.3 4448.4 9100.1 9114.4 9115.9 9117.1 9120.4 4716.6 4744.5 4755.8 4759.2 4772.6 4277.0 4295.4 4341.6 4353.4 4354.0 3718.6 3748.9 3758.6 3789.3 3796.2 1212.4 1227.8 1230.3 1235.2 1238.0 1059.7 1081.8 1082.4 1226.2 1337.9 7172.5 7210.4 7219.7 7224.6 7343.1 476.3 480.1 484.5 489.2 490.4 5429.3 5510.3 5697.3 5853.1 6008.0 8647.7 8803.6 8910.9 9041.5 9071.5 8719.5 8796.4 8826.4 8827.1 8828.6 6850.6 6951.6 6976.3 7033.1 7046.0 6849.0 6957.5 6959.3 7008.1 7011.4 8065.5 8139.4 8166.8 8229.0 8270.9 8237.2 8326.7 8617.0 8647.1 8652.2 6965.1 6975.1 7000.5 7039.8 7041.6 7509.9 7577.4 7590.8 7592.2 7597.9 7137.8 7146.4 7213.9 7232.3 7280.3 7082.8 7099.8 7180.0 7185.2 7224.5 7017.9 7070.2 7070.4 7114.3 7135.6 6414.8 6451.9 6477.1 6484.4 6489.4 6386.2 6451.5 6498.2 6505.6 6507.0 20:02:20 INFO - 2015-11-24 20:02:20,298 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,298 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5332.10 Median:6042.96 stddev:1593.03 (26.4%) stddev-sans-first:1552.71 20:02:20 INFO - 2015-11-24 20:02:20,299 DEBUG : BROWSER_OUTPUT: Values: 334.3 337.6 344.9 348.5 351.0 5861.8 5969.6 5980.3 6004.9 6011.1 5779.2 5914.7 5940.6 5945.6 5953.5 4614.1 4617.4 4627.2 4628.4 4629.2 4370.0 4451.4 4499.8 4579.7 4626.9 4530.7 4551.6 4558.7 4564.8 4569.9 4591.2 4594.1 4598.0 4601.0 4609.6 4049.8 4050.0 4051.9 4055.9 4058.5 5543.2 5589.2 5622.3 5632.9 5636.9 5357.1 5359.3 5362.7 5364.2 5373.3 6079.5 6321.4 6328.9 6331.1 6333.9 4359.4 4404.9 4504.5 4518.4 4519.8 6540.0 6573.5 6578.2 6583.2 6587.7 4611.1 4611.2 4613.1 4613.4 4617.2 4546.2 4657.9 4671.8 4679.1 4680.0 5345.6 5360.2 5365.9 5369.2 5375.3 6389.6 6424.4 6431.6 6436.0 6436.2 1641.8 1673.0 1674.5 1676.1 1676.5 1102.7 1117.9 1121.0 1122.5 1141.6 6074.8 6095.7 6096.6 6104.5 6123.3 6513.7 6521.6 6537.4 6541.3 6543.4 5919.4 6087.6 6090.6 6091.0 6100.6 3514.1 3519.9 3542.9 3549.6 3553.9 6730.5 6732.2 6734.3 6737.6 6740.5 6670.7 6676.3 6689.4 6689.7 6693.0 6685.2 6766.9 6776.4 6779.9 6780.7 6358.0 6450.5 6482.3 6640.3 6655.1 6519.3 6537.6 6543.6 6551.2 6561.1 6825.1 6829.7 6832.7 6841.8 6848.3 4382.3 4480.3 4508.3 4509.0 4509.6 6543.7 6558.3 6563.3 6566.5 6571.2 6374.2 6400.3 6401.8 6409.5 6413.6 6289.6 6396.3 6420.9 6453.8 6477.6 6260.5 6331.4 6334.6 6335.3 6346.7 6489.8 6493.3 6509.9 6512.3 6516.9 6183.7 6347.4 6350.7 6351.8 6357.1 20:02:20 INFO - 2015-11-24 20:02:20,299 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,299 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13707.15 Median:49140.63 stddev:15939.20 (32.4%) stddev-sans-first:15954.30 20:02:20 INFO - 2015-11-24 20:02:20,301 DEBUG : BROWSER_OUTPUT: Values: 770.9 816.9 828.4 862.9 875.6 13199.1 13325.2 13348.5 13356.9 13358.7 12381.8 12569.7 13092.3 13175.6 13193.1 816.5 822.1 829.7 830.7 832.7 813.9 828.5 833.5 833.5 836.3 869.6 873.4 874.1 874.7 878.6 872.3 874.5 874.7 875.9 876.3 644.9 646.3 646.9 647.3 648.2 12276.0 12320.4 12385.9 12413.4 12428.7 10892.5 11161.4 11523.1 12104.0 12204.5 10158.2 10207.6 10244.8 10310.6 10385.5 3524.2 3582.0 3589.7 3595.8 3721.1 41475.0 41548.0 42224.6 44440.8 49659.5 9480.4 9505.8 9810.6 9860.4 10232.2 16536.3 16573.5 16584.5 16595.0 16660.3 7495.8 7606.4 7614.6 7625.7 7820.3 4440.6 4441.6 4460.4 4488.3 4526.2 3096.3 3155.2 3155.4 3159.1 3160.9 560.6 573.3 586.3 592.6 608.0 531.8 579.8 580.0 637.8 689.0 8567.7 8636.0 8701.4 8861.7 8947.4 553.2 580.0 595.7 643.3 709.5 3890.3 4117.1 4128.4 4178.2 4178.6 57179.4 57393.4 58023.4 58222.4 58501.4 45932.5 52404.5 53096.5 53481.0 53754.5 42155.2 49546.0 49813.5 49916.8 49937.5 8585.7 8795.7 8828.3 9018.8 9603.0 33314.7 34541.1 34951.3 36045.8 36223.3 40360.0 43196.0 45595.5 48834.5 49446.8 10974.2 11205.8 11303.5 11468.3 11753.1 12038.7 12084.4 12087.9 12234.3 12288.8 11803.5 11809.9 11915.6 12113.8 12263.8 10512.9 10654.5 10781.2 10809.8 10922.7 11556.1 11729.9 11768.7 11996.9 12253.8 9395.2 9586.9 9595.1 9854.2 9896.7 8913.2 9216.0 9534.5 9566.6 9597.1 20:02:20 INFO - 2015-11-24 20:02:20,301 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:02:20 INFO - 2015-11-24 20:02:20,301 DEBUG : BROWSER_OUTPUT: 20:02:20 INFO - 2015-11-24 20:02:20,977 INFO : Browser exited with error code: 0 20:02:20 INFO - 2015-11-24 20:02:20,994 INFO : Completed test dromaeo_css (00:18:33) 20:02:20 INFO - 2015-11-24 20:02:20,994 INFO : Starting test kraken 20:02:20 INFO - 2015-11-24 20:02:20,994 DEBUG : operating with platform_type : linux_ 20:02:20 INFO - 2015-11-24 20:02:20,994 INFO : Initialising browser for kraken test... 20:02:21 INFO - 2015-11-24 20:02:21,000 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6RqbD6/profile http://localhost:34502/getInfo.html 20:02:24 INFO - 2015-11-24 20:02:24,707 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:02:24 INFO - 2015-11-24 20:02:24,707 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:02:24 INFO - 2015-11-24 20:02:24,707 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:02:24 INFO - 2015-11-24 20:02:24,708 DEBUG : BROWSER_OUTPUT: __metrics 20:02:24 INFO - 2015-11-24 20:02:24,796 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:02:26 INFO - 2015-11-24 20:02:26,119 INFO : Browser initialized. 20:02:26 INFO - 2015-11-24 20:02:26,119 INFO : Running cycle 1/1 for kraken test... 20:02:26 INFO - 2015-11-24 20:02:26,120 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6RqbD6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:02:37 INFO - 2015-11-24 20:02:37,755 DEBUG : BROWSER_OUTPUT: RSS: Main: 139038720 20:02:37 INFO - 2015-11-24 20:02:37,755 DEBUG : BROWSER_OUTPUT: 20:02:40 INFO - 2015-11-24 20:02:40,229 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34502/tests/kraken/driver.html?testName=audio-beat-detection) 20:02:40 INFO - 2015-11-24 20:02:40,810 DEBUG : BROWSER_OUTPUT: RSS: Main: 177610752 20:02:40 INFO - 2015-11-24 20:02:40,810 DEBUG : BROWSER_OUTPUT: 20:02:43 INFO - 2015-11-24 20:02:43,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34502/tests/kraken/driver.html?testName=audio-dft) 20:02:43 INFO - 2015-11-24 20:02:43,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 167362560 20:02:43 INFO - 2015-11-24 20:02:43,989 DEBUG : BROWSER_OUTPUT: 20:02:46 INFO - 2015-11-24 20:02:46,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34502/tests/kraken/driver.html?testName=audio-fft) 20:02:47 INFO - 2015-11-24 20:02:47,400 DEBUG : BROWSER_OUTPUT: RSS: Main: 151838720 20:02:47 INFO - 2015-11-24 20:02:47,400 DEBUG : BROWSER_OUTPUT: 20:02:49 INFO - 2015-11-24 20:02:49,364 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34502/tests/kraken/driver.html?testName=audio-oscillator) 20:02:49 INFO - 2015-11-24 20:02:49,929 DEBUG : BROWSER_OUTPUT: RSS: Main: 152125440 20:02:49 INFO - 2015-11-24 20:02:49,929 DEBUG : BROWSER_OUTPUT: 20:02:52 INFO - 2015-11-24 20:02:52,103 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34502/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:02:52 INFO - 2015-11-24 20:02:52,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 154103808 20:02:52 INFO - 2015-11-24 20:02:52,664 DEBUG : BROWSER_OUTPUT: 20:02:55 INFO - 2015-11-24 20:02:55,567 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34502/tests/kraken/driver.html?testName=imaging-darkroom) 20:02:56 INFO - 2015-11-24 20:02:56,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 153862144 20:02:56 INFO - 2015-11-24 20:02:56,134 DEBUG : BROWSER_OUTPUT: 20:02:59 INFO - 2015-11-24 20:02:59,544 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34502/tests/kraken/driver.html?testName=imaging-desaturate) 20:03:00 INFO - 2015-11-24 20:03:00,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 154107904 20:03:00 INFO - 2015-11-24 20:03:00,117 DEBUG : BROWSER_OUTPUT: 20:03:02 INFO - 2015-11-24 20:03:02,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34502/tests/kraken/driver.html?testName=json-parse-financial) 20:03:03 INFO - 2015-11-24 20:03:03,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 155033600 20:03:03 INFO - 2015-11-24 20:03:03,430 DEBUG : BROWSER_OUTPUT: 20:03:04 INFO - 2015-11-24 20:03:04,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34502/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:03:05 INFO - 2015-11-24 20:03:05,558 DEBUG : BROWSER_OUTPUT: RSS: Main: 167583744 20:03:05 INFO - 2015-11-24 20:03:05,558 DEBUG : BROWSER_OUTPUT: 20:03:07 INFO - 2015-11-24 20:03:07,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:03:07 INFO - 2015-11-24 20:03:07,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 165236736 20:03:07 INFO - 2015-11-24 20:03:07,989 DEBUG : BROWSER_OUTPUT: 20:03:10 INFO - 2015-11-24 20:03:10,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:03:10 INFO - 2015-11-24 20:03:10,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 174100480 20:03:10 INFO - 2015-11-24 20:03:10,630 DEBUG : BROWSER_OUTPUT: 20:03:13 INFO - 2015-11-24 20:03:13,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:03:13 INFO - 2015-11-24 20:03:13,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 180199424 20:03:13 INFO - 2015-11-24 20:03:13,611 DEBUG : BROWSER_OUTPUT: 20:03:16 INFO - 2015-11-24 20:03:16,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:03:16 INFO - 2015-11-24 20:03:16,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 179306496 20:03:16 INFO - 2015-11-24 20:03:16,699 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34502/tests/kraken/driver.html?testName=ai-astar) 20:03:18 INFO - 2015-11-24 20:03:18,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 237137920 20:03:18 INFO - 2015-11-24 20:03:18,598 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,599 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:03:18 INFO - 2015-11-24 20:03:18,599 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:03:18 INFO - 2015-11-24 20:03:18,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:03:18 INFO - 2015-11-24 20:03:18,600 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:03:18 INFO - 2015-11-24 20:03:18,600 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;112;103;111;112;111;99;110;108;99 20:03:18 INFO - 2015-11-24 20:03:18,600 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;132;150;163;129;149;154;133;169;130 20:03:18 INFO - 2015-11-24 20:03:18,601 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;161;160;160;162;163;160;164 20:03:18 INFO - 2015-11-24 20:03:18,601 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;84;94;70;72;72;128;92;71 20:03:18 INFO - 2015-11-24 20:03:18,601 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;92;138;92;93;93;138;92;93;133 20:03:18 INFO - 2015-11-24 20:03:18,601 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;109;109;109;109;109;109 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;147;145;146;146;145;145;145;145 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;96;96;96;96;97 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;59;58;58;58;60;61;61;57 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;70;70;70;70;70;71;70;76;70 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;91;83;84;83;84;84;84;86;83 20:03:18 INFO - 2015-11-24 20:03:18,603 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;120;119;115;115;128;117;116;124;123 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;141;154;140;147;135;146;147;146;136 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;67;73;66;65;65;65;65;65 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,900 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:03:18 INFO - 2015-11-24 20:03:18,604 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424198599__endTimestamp 20:03:18 INFO - 2015-11-24 20:03:18,605 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,608 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:03:18 INFO - 2015-11-24 20:03:18,608 DEBUG : BROWSER_OUTPUT: Number of tests: 14 20:03:18 INFO - 2015-11-24 20:03:18,608 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,608 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.70 Median:112.00 stddev:5.33 (4.8%) stddev-sans-first:5.43 20:03:18 INFO - 2015-11-24 20:03:18,608 DEBUG : BROWSER_OUTPUT: Values: 112.0 112.0 103.0 111.0 112.0 111.0 99.0 110.0 108.0 99.0 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.90 Median:150.00 stddev:14.27 (9.5%) stddev-sans-first:15.06 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: Values: 150.0 132.0 150.0 163.0 129.0 149.0 154.0 133.0 169.0 130.0 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.40 (0.9%) stddev-sans-first:1.48 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 161.0 160.0 160.0 162.0 163.0 160.0 164.0 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.90 Median:73.00 stddev:18.21 (24.9%) stddev-sans-first:18.95 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 84.0 94.0 70.0 72.0 72.0 128.0 92.0 71.0 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:92.50 stddev:21.19 (22.9%) stddev-sans-first:21.97 20:03:18 INFO - 2015-11-24 20:03:18,609 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 138.0 92.0 93.0 93.0 138.0 92.0 93.0 133.0 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 147.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 97.0 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.50 stddev:1.58 (2.7%) stddev-sans-first:1.56 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 59.0 58.0 58.0 58.0 60.0 61.0 61.0 57.0 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.00 stddev:1.87 (2.7%) stddev-sans-first:1.99 20:03:18 INFO - 2015-11-24 20:03:18,610 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 70.0 70.0 70.0 71.0 70.0 76.0 70.0 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:2.55 (3.0%) stddev-sans-first:2.55 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: Values: 82.0 91.0 83.0 84.0 83.0 84.0 84.0 84.0 86.0 83.0 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:121.00 stddev:4.33 (3.6%) stddev-sans-first:4.53 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: Values: 122.0 120.0 119.0 115.0 115.0 128.0 117.0 116.0 124.0 123.0 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.80 Median:146.00 stddev:6.20 (4.2%) stddev-sans-first:6.06 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: Values: 136.0 141.0 154.0 140.0 147.0 135.0 146.0 147.0 146.0 136.0 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:65.50 stddev:2.49 (3.8%) stddev-sans-first:2.60 20:03:18 INFO - 2015-11-24 20:03:18,611 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 67.0 73.0 66.0 65.0 65.0 65.0 65.0 65.0 20:03:18 INFO - 2015-11-24 20:03:18,612 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:03:18 INFO - 2015-11-24 20:03:18,612 DEBUG : BROWSER_OUTPUT: 20:03:19 INFO - 2015-11-24 20:03:19,409 INFO : Browser exited with error code: 0 20:03:19 INFO - 2015-11-24 20:03:19,463 INFO : Completed test kraken (00:00:58) 20:03:19 INFO - 2015-11-24 20:03:19,463 INFO : Starting test v8_7 20:03:19 INFO - 2015-11-24 20:03:19,463 DEBUG : operating with platform_type : linux_ 20:03:19 INFO - 2015-11-24 20:03:19,463 INFO : Initialising browser for v8_7 test... 20:03:19 INFO - 2015-11-24 20:03:19,470 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4HFdtY/profile http://localhost:34502/getInfo.html 20:03:23 INFO - 2015-11-24 20:03:23,192 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:03:23 INFO - 2015-11-24 20:03:23,193 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:03:23 INFO - 2015-11-24 20:03:23,193 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:03:23 INFO - 2015-11-24 20:03:23,193 DEBUG : BROWSER_OUTPUT: __metrics 20:03:23 INFO - 2015-11-24 20:03:23,329 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:03:24 INFO - 2015-11-24 20:03:24,390 INFO : Browser initialized. 20:03:24 INFO - 2015-11-24 20:03:24,390 INFO : Running cycle 1/1 for v8_7 test... 20:03:24 INFO - 2015-11-24 20:03:24,391 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4HFdtY/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:03:36 INFO - 2015-11-24 20:03:36,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 139612160 20:03:36 INFO - 2015-11-24 20:03:36,094 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34502/tests/v8_7/run.html (next: http://localhost:34502/tests/v8_7/run.html) 20:03:57 INFO - 2015-11-24 20:03:57,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 602112000 20:03:57 INFO - 2015-11-24 20:03:57,776 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,776 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:03:57 INFO - 2015-11-24 20:03:57,776 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:03:57 INFO - 2015-11-24 20:03:57,776 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:03:57 INFO - 2015-11-24 20:03:57,777 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:03:57 INFO - 2015-11-24 20:03:57,777 DEBUG : BROWSER_OUTPUT: |0;Richards;165.28925619834712 20:03:57 INFO - 2015-11-24 20:03:57,778 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.62826002335538 20:03:57 INFO - 2015-11-24 20:03:57,778 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.5270768358378 20:03:57 INFO - 2015-11-24 20:03:57,778 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1140.2508551881415 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |5;Earley;791.1392405063291 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |6;Boyer;12280.487804878048 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |7;RegExp;25846.153846153848 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |8;Splay;625.7822277847309 20:03:57 INFO - 2015-11-24 20:03:57,779 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4906.862745098039 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424237775__endTimestamp 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: Number of tests: 10 20:03:57 INFO - 2015-11-24 20:03:57,780 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.29 Median:165.29 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: Values: 165.3 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.63 Median:194.63 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: Values: 194.6 20:03:57 INFO - 2015-11-24 20:03:57,781 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,782 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.53 Median:283.53 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,782 DEBUG : BROWSER_OUTPUT: Values: 283.5 20:03:57 INFO - 2015-11-24 20:03:57,782 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,783 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,783 DEBUG : BROWSER_OUTPUT: Values: 5138.5 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1140.25 Median:1140.25 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: Values: 1140.3 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:791.14 Median:791.14 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: Values: 791.1 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,784 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12280.49 Median:12280.49 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: Values: 12280.5 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25846.15 Median:25846.15 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: Values: 25846.2 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:625.78 Median:625.78 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,785 DEBUG : BROWSER_OUTPUT: Values: 625.8 20:03:57 INFO - 2015-11-24 20:03:57,786 DEBUG : BROWSER_OUTPUT: 20:03:57 INFO - 2015-11-24 20:03:57,786 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4906.86 Median:4906.86 stddev:0.00 (0.0%) 20:03:57 INFO - 2015-11-24 20:03:57,786 DEBUG : BROWSER_OUTPUT: Values: 4906.9 20:03:57 INFO - 2015-11-24 20:03:57,786 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:03:57 INFO - 2015-11-24 20:03:57,786 DEBUG : BROWSER_OUTPUT: 20:03:58 INFO - 2015-11-24 20:03:58,621 INFO : Browser exited with error code: 0 20:03:58 INFO - 2015-11-24 20:03:58,633 INFO : Completed test v8_7 (00:00:39) 20:03:58 INFO - 2015-11-24 20:03:58,766 INFO : Completed test suite (00:20:11) 20:03:58 INFO - 2015-11-24 20:03:58,767 DEBUG : Working with test: dromaeo_css 20:03:58 INFO - 2015-11-24 20:03:58,767 DEBUG : Generating results file: dromaeo_css 20:03:58 INFO - 2015-11-24 20:03:58,768 DEBUG : Working with test: kraken 20:03:58 INFO - 2015-11-24 20:03:58,768 DEBUG : Generating results file: kraken 20:03:58 INFO - 2015-11-24 20:03:58,768 INFO : javascript benchmark 20:03:58 INFO - 2015-11-24 20:03:58,768 DEBUG : Working with test: v8_7 20:03:58 INFO - 2015-11-24 20:03:58,769 DEBUG : Generating results file: v8_7 20:03:58 INFO - 2015-11-24 20:03:58,769 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:58 INFO - 2015-11-24 20:03:58,830 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 20:03:58 INFO - 2015-11-24 20:03:58,830 DEBUG : process_Request line: dromaeo_css 6957.53 graph.html#tests=[[72,64,35]] 20:03:58 INFO - 2015-11-24 20:03:58,830 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:58 INFO - 2015-11-24 20:03:58,870 DEBUG : process_Request line: kraken 1496.70 graph.html#tests=[[232,64,35]] 20:03:58 INFO - 2015-11-24 20:03:58,871 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:03:58 INFO - 2015-11-24 20:03:58,922 DEBUG : process_Request line: v8_7 20795.01 graph.html#tests=[[230,64,35]] 20:03:58 INFO - 2015-11-24 20:03:58,926 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3175.0, 3258.804471731695, 3301.4387554115797, 3315.1380641033325, 3319.5850518685165, 5245.10833158363, 5372.140457526787, 5471.863203419912, 5487.6158668893195, 5497.532709719673, 5449.3188351456065, 5527.834164975057, 5590.496855283024, 5604.271444712192, 5616.6068375213645, 5003.724795136266, 5004.924926126102, 5015.824446144386, 5024.171011783052, 5047.798088076473, 4804.6876952997945, 5103.515166059218, 5112.8977420451565, 5132.0, 5183.948160518403, 4910.484399138093, 4913.950860491385, 4946.9010619787405, 4962.60299176065, 4981.402231732215, 4994.101061808873, 5032.169692000815, 5033.320501732256, 5035.647504674675, 5084.644074914758, 4799.1601469743, 4974.850754477371, 4997.375328083989, 5005.199168133081, 5046.7224302663335, 5161.458046905059, 5217.5565076968505, 5261.6842989420375, 5290.761915713763, 5321.547668448186, 6008.789692360747, 6044.2746870375395, 6044.576879618428, 6045.365236650133, 6053.878922421571, 5686.772529098831, 5842.73707683151, 6079.0, 6092.66490343031, 6104.3590423005835, 5594.776208951637, 5623.0721930880945, 5645.040343141676, 5645.915311270334, 5705.6006079574445, 6038.788642397495, 6048.848778780478, 6051.485623722033, 6056.152138700585, 6073.271207455134, 5693.857653558612, 5734.0, 5767.884642307215, 5851.151583020438, 5902.8819423611285, 5898.675572843535, 5900.527957763368, 5913.172155898178, 5973.342932277446, 5988.311344195473, 5842.883142337129, 5929.73316200772, 5930.822075337746, 5935.584509084409, 5937.881242375129, 5673.375928647845, 5793.131030345397, 5925.496332811673, 5926.911096333559, 5932.525397968152, 4467.262901621261, 4475.328700694857, 4510.75190864499, 4513.1650644630845, 4553.453585569753, 4027.8791636250958, 4035.273650742836, 4051.4327994080854, 4068.3083875741195, 4093.7543747375253, 5298.3906850712665, 5446.101393270145, 5618.775248990077, 5618.803341883015, 5625.887482250332, 5849.181114643953, 5902.704864756745, 5963.105534169909, 5967.045272756338, 6012.819615411545, 5568.860778480571, 5582.637128586629, 5592.720363981784, 5595.21666966625, 5602.1036634138345, 4856.077345304381, 4986.700797952134, 4988.201887697951, 4989.126902791997, 5041.117804384218, 6075.210222671025, 6092.45167934888, 6159.876802463926, 6177.413145751147, 6180.567360284737, 5944.5838791284195, 5945.078512830518, 5952.0, 5957.165996760544, 6002.459778619946, 5749.281339832521, 5850.7074646266665, 6133.04937734634, 6137.938620613737, 6140.846478838065, 5689.886202276014, 5843.883122337615, 5886.323072846678, 5979.342272349951, 5987.850303742267, 5939.07944268639, 5974.462298393166, 5987.13186587951, 5988.730507127277, 5991.490723288569, 5817.447342502455, 5824.097264923944, 5833.300003999547, 5834.795782147605, 5840.445157710011, 5656.660600363992, 5676.148577713376, 5741.368449470471, 5788.27646544182, 5881.617694849821, 5734.2258795062135, 5746.856328591652, 5750.7987220447085, 5752.71236438168, 5764.855878603068, 5817.185594016925, 5851.444112809277, 5858.5898987070495, 5871.178035075179, 5879.676617785892, 5404.7297635118875, 5427.6472029317965, 5501.449855014466, 5653.491185793298, 5734.741936612946], "unit": "ms", "name": "dojo.html", "value": 5442.370134491929}, {"lowerIsBetter": false, "replicates": [572.3332317849705, 585.3794977324036, 587.667967598307, 590.4272855330331, 597.2176448852007, 31314.843425782867, 40722.18555628886, 41266.38100428496, 41515.79242103789, 41535.58464415355, 43573.7821310893, 43719.56280437195, 44038.779806101, 44044.33933491, 44083.77958110213, 7619.504732192418, 7823.374130069581, 8667.739967800977, 8835.160659737314, 8840.115988401172, 10360.326578772356, 10627.628033018846, 10643.946780266087, 10652.201084918623, 10656.573737050508, 10366.378017318984, 10437.634682786103, 10482.633107841228, 10488.79022419551, 10493.898140695232, 7862.449628525977, 8062.919370806276, 10170.796584068352, 10318.277720559563, 10385.79228415435, 9913.40519568821, 10040.548175332127, 10048.145907597864, 10050.497475126214, 10052.095311421934, 37239.069023274365, 37297.627023729685, 37695.4345684815, 37729.0, 37800.24399512022, 3340.048086295418, 3457.11843479911, 3522.6477352264824, 3648.6716195542263, 3916.471276377681, 30220.69779302201, 30260.394792104256, 30398.240043999078, 31204.063878083467, 31465.37069258602, 3915.549711783153, 3928.8821335359985, 3941.881743547698, 3964.32606456903, 4122.299209134424, 277281.0, 278605.0, 278950.0, 279094.0, 279608.0, 53921.730391347795, 55106.72446637741, 56472.0, 58901.41098589045, 59563.702181488814, 55550.0, 55688.72155639277, 55871.0, 56206.43793562094, 56389.43610563924, 13738.450461981609, 13876.375563099684, 13877.653058673412, 13881.375338109809, 13882.167069975834, 10418.166546676248, 10459.424731639832, 10460.52927618259, 10462.581496740046, 10574.577016919236, 19199.808001920082, 19425.222991080198, 19465.610687786164, 19476.70784938227, 19499.902500487406, 7571.507851989713, 7852.450328476952, 7875.448718589644, 7895.763127106196, 7960.522368657899, 914.3508109242513, 995.5221493682928, 1031.9587216511406, 1122.2985633978763, 1143.0341361549476, 25228.873855630973, 25287.36781580475, 25364.11225607095, 25471.36321591975, 25661.61507577388, 1021.8007488539663, 1082.976587125159, 1108.5288752280248, 1146.5585749486509, 1215.3133479584008, 14478.927605361905, 14569.41722331095, 14679.19264440466, 14757.483488077865, 14927.701445971019, 305127.0, 309600.0, 309661.0, 309881.0, 310330.0, 52382.21426678603, 52615.73692131515, 52803.73598131985, 52835.73582132142, 52900.47099528956, 1287.22766340197, 1301.9804702929653, 1303.48512337628, 1305.0799186573443, 1461.9839211747947, 26710.59934101029, 27693.446131077668, 27739.0, 28116.71883281141, 28282.85858570694, 43968.56031439645, 44246.557534424246, 44281.33577996397, 44286.0, 44309.0, 287342.0, 290715.0, 291783.0, 292273.0, 292287.0, 26657.208087085262, 28092.157235282975, 28664.0, 28827.56758648664, 31392.843035784674, 33981.15047123842, 34640.0, 39205.2158956825, 39875.40186897141, 40797.79601101975, 33387.16532086717, 33573.32853342968, 34110.147246319044, 34145.0, 34798.47802283018, 34120.0, 34180.65819341874, 34435.65564344425, 36285.63714362823, 37294.25411491701, 31151.53272700866, 31161.0, 36545.45181822222, 36597.634023659426, 37007.259854802214, 28007.299817504725, 31296.530552041284, 32253.033188860136, 33466.0, 33759.15602109967, 24201.0, 25369.873150634867, 25399.6190057153, 25448.745512544636, 25890.87054564715], "unit": "ms", "name": "ext.html", "value": 18396.787096745953}, {"lowerIsBetter": false, "replicates": [357.34962368489346, 359.40697848549905, 359.83447614097514, 359.8506619752803, 362.26821819923737, 14189.574312770608, 14272.857271427283, 17163.828361716394, 17765.111744412792, 18462.90768546157, 16781.91609041953, 17011.31954721813, 17427.0, 17796.733049004244, 17930.641387172247, 5319.5744340452675, 5437.0, 5446.754896029688, 5451.318585176853, 5454.91817622736, 3883.3010058189516, 4014.45804816349, 5392.1911713242935, 5426.2674538937135, 5907.350191478956, 5949.732262048218, 5989.1016347548, 6015.969920150393, 6020.066889632114, 6020.638761674291, 5849.941500585003, 5943.791967281124, 5973.253343332061, 5976.641401515923, 6014.398560143951, 5210.66130701507, 5216.765245563959, 5219.112750832368, 5252.973735131338, 5259.342582177228, 19832.80167198324, 19920.70118948217, 20141.295054673163, 20413.48966275855, 20425.0, 2141.7751136130637, 2171.4788450771703, 2194.3416974907464, 2214.745304290011, 2370.2533701883854, 13238.73522529544, 13444.798328025088, 13465.932670336684, 13652.453901843914, 13688.863111368859, 1859.2191279662575, 1870.6352261308914, 1913.3685883658497, 1951.1951444913398, 1969.4682435742413, 62782.0, 65712.0, 66229.66885165591, 66547.0, 66973.3302666972, 10121.24090693201, 10148.340357876714, 10249.538770755335, 10251.794964100825, 10292.897071029343, 13439.395227214798, 13487.52793652237, 13543.119697219649, 13568.59294221175, 13568.728625427437, 6940.6529673516125, 7086.50394472382, 7092.326229008236, 7104.0, 7135.82160445993, 6656.70044847983, 6693.330666933268, 6694.765683201064, 6705.6982435790505, 6719.764808231786, 4341.739495630272, 4408.096340250241, 4411.051623900877, 4418.955810441918, 4448.755318457516, 1708.2654458582929, 1723.707718258121, 1766.5230387795223, 1771.176402972624, 1816.6548355812618, 426.88260728299343, 444.69093979684453, 906.5110754382279, 1015.4059874973076, 1015.6140666546665, 9457.0, 10125.898741012496, 10638.574457021787, 10749.140068794477, 10861.45692715377, 421.8586773430935, 430.8815075854165, 433.6552440809564, 442.28570858063995, 449.49880882815427, 5402.783888644489, 5599.860003499945, 5644.379118296984, 5689.9146512802345, 5695.686737229409, 507219.0, 507823.0, 510086.0, 513767.0, 517993.0, 78472.6076369626, 80275.0, 82092.0, 82573.0, 83323.58338208392, 477.32696897374535, 482.30788818046216, 483.7291116974435, 485.76440426393174, 485.8299595141743, 10429.895701042893, 10446.582136714751, 10465.843012354668, 10478.528466219192, 10538.262321637718, 34970.12574685653, 35480.29039419249, 35619.10952226215, 36460.27079458449, 36754.81622591977, 49701.751491242314, 50941.490585095155, 51074.23388649099, 51271.48728512667, 56224.71887640536, 13689.041767076209, 14286.142831430148, 14496.130232186102, 14755.336009879367, 14791.556253312418, 17940.91029544844, 18006.63986720232, 18209.089545522937, 18394.0, 18878.716819248, 14690.853091469377, 14731.852681473476, 15220.543383698507, 15608.84391156074, 15913.443029493912, 15275.847241527443, 15391.61520961985, 15541.766873496681, 15698.450554230547, 16105.758413623571, 11978.820317695066, 12419.379031048593, 15607.609809754393, 15684.372625094868, 15850.207489625242, 14350.784738229144, 14525.782113268098, 14594.489192878198, 14665.606767357083, 15055.924720376328, 15338.309776060327, 15423.6144096394, 15496.457623983106, 15804.68390632158, 16436.260368283696], "unit": "ms", "name": "jquery.html", "value": 8515.073147228364}, {"lowerIsBetter": false, "replicates": [1454.614527150305, 1500.2873635023361, 1519.3694616734056, 1640.786697729295, 1764.8058713541516, 6695.12963314769, 7435.553866767991, 7815.140334563201, 7847.960760196205, 7856.253655902694, 7572.12920514141, 7727.652255648495, 7743.380529557636, 7766.883496747553, 7770.4560680752365, 1998.4304473225134, 2045.0286114095834, 2045.2023710752744, 2283.725952885652, 2334.206369834256, 2011.1251605551631, 2296.9310920672406, 2354.0936739355384, 2363.9409014774596, 2365.124903785602, 2244.528648983707, 2278.4190031541934, 2292.713410823647, 2294.2314324701247, 2300.298408985259, 1960.8823470591667, 2022.0395312226722, 2287.2452090809993, 2308.134449581407, 2308.376738280663, 2266.8526545774635, 2309.364924645719, 2310.7573704760925, 2311.4683622766856, 2313.236631911482, 7551.962240188819, 7736.149023607455, 7738.071431428265, 7739.110002349689, 7747.651355689008, 4649.139909116824, 4783.0, 4783.928241076386, 4787.54518320759, 4802.663813533054, 2975.494165991765, 3123.6563977962405, 3135.6237251529733, 3148.8110713357273, 3161.873525058995, 1052.2529004406804, 1054.0328973938415, 1066.9679909602723, 1083.1984331594642, 1130.8077626803463, 10499.73750656232, 10522.684319470429, 10524.736881577945, 10530.578776848917, 10544.789104217949, 4264.189803937242, 4375.321825117112, 4393.0, 4443.289073748185, 4448.354988526646, 9100.08999100098, 9114.361994660443, 9115.906091268958, 9117.133872282122, 9120.407173533831, 4716.575508204278, 4744.525547445228, 4755.809767609326, 4759.16714574948, 4772.594329482033, 4276.957230427718, 4295.420118284054, 4341.587549182823, 4353.390525326457, 4353.978230108829, 3718.6095459976855, 3748.887533374003, 3758.567764707094, 3789.2989796887664, 3796.1838204786104, 1212.3816853404828, 1227.7728620205291, 1230.3171739684383, 1235.234154824015, 1238.0405185980937, 1059.7191744187796, 1081.8323159910226, 1082.3614067700096, 1226.172333674766, 1337.9397927093305, 7172.46206534512, 7210.351068403764, 7219.675114619855, 7224.582060262791, 7343.082114735658, 476.276060388208, 480.075853981085, 484.46224690593414, 489.17573887998833, 490.399260905388, 5429.294191755047, 5510.256115424447, 5697.344805347355, 5853.09277062056, 6008.0, 8647.697330593524, 8803.647854085764, 8910.86633700495, 9041.547922603844, 9071.501067441353, 8719.52042637661, 8796.38425310235, 8826.382153249211, 8827.073157318517, 8828.558572071372, 6850.595627896269, 6951.617661028591, 6976.337247961435, 7033.050538177383, 7046.048783414276, 6849.0, 6957.547759395623, 6959.269276726073, 7008.123984501937, 7011.439084873095, 8065.475744076616, 8139.430239883388, 8166.83666326682, 8228.958855205685, 8270.875936860832, 8237.1516223482, 8326.70856520019, 8617.0, 8647.0920553341, 8652.221300083023, 6965.094537710065, 6975.058367120374, 7000.544964577286, 7039.824004399931, 7041.647917604202, 7509.9249007509225, 7577.431692622965, 7590.7722768317035, 7592.16486186508, 7597.924020759942, 7137.785866424016, 7146.392556632536, 7213.891791623025, 7232.312930271616, 7280.3083707047745, 7082.752103676346, 7099.82250443743, 7180.0, 7185.17370502378, 7224.530405523624, 7017.894731579133, 7070.222275549789, 7070.434365250665, 7114.324139206767, 7135.607541585263, 6414.807555773335, 6451.935480645134, 6477.093206951125, 6484.416402523795, 6489.3510648934725, 6386.233651961795, 6451.516136289703, 6498.187726534184, 6505.577137486048, 6507.023946408077], "unit": "ms", "name": "mootools.html", "value": 4380.153199262177}, {"lowerIsBetter": false, "replicates": [334.32966901362767, 337.6016300765097, 344.8655024540429, 348.49642266924303, 350.9578850537936, 5861.7948371807, 5969.567303847078, 5980.31226408962, 6004.939950600493, 6011.128386383972, 5779.21980532628, 5914.704264786754, 5940.643561386309, 5945.554083443737, 5953.493953013988, 4614.100250451163, 4617.399738034068, 4627.167109920213, 4628.352030715702, 4629.213033784265, 4369.978150109245, 4451.399061126739, 4499.820007199708, 4579.656525760582, 4626.907461850761, 4530.728156310615, 4551.590356867881, 4558.680892337537, 4564.817407303721, 4569.885752856172, 4591.219492686251, 4594.081183763261, 4597.954020459802, 4600.976995115003, 4609.585137337622, 4049.8177582008584, 4050.0, 4051.858184963512, 4055.9188816223805, 4058.4521089652812, 5543.194205928478, 5589.217509548666, 5622.32532096151, 5632.887342253173, 5636.887262254773, 5357.142857142839, 5359.330083739533, 5362.678239305654, 5364.222187782789, 5373.301470808769, 6079.544034197453, 6321.367863213688, 6328.873422531569, 6331.113644089831, 6333.904991425086, 4359.411479450266, 4404.933925991113, 4504.504504504502, 4518.390017347649, 4519.7740112994215, 6540.0, 6573.474122070223, 6578.243501997236, 6583.177102862142, 6587.670616469205, 4611.100835337077, 4611.239145540948, 4613.1465678848845, 4613.446386433649, 4617.168909596305, 4546.181687296253, 4657.906841863144, 4671.766411679402, 4679.110968915895, 4680.0, 5345.599080069011, 5360.222767698662, 5365.892682146413, 5369.24830523727, 5375.3280839895015, 6389.648569328639, 6424.421802037839, 6431.6462594556815, 6435.967820160869, 6436.227652681709, 1641.827608101156, 1673.0045622854382, 1674.4976507047836, 1676.094908749286, 1676.522191175509, 1102.7077824376547, 1117.9105671546256, 1120.960766373173, 1122.5004872831512, 1141.5833220874322, 6074.787382441594, 6095.725692343855, 6096.603720758137, 6104.481119104926, 6123.326434092246, 6513.739450422025, 6521.6413097279155, 6537.411632952962, 6541.313162118005, 6543.378379053982, 5919.437653422918, 6087.604305720114, 6090.6041107328765, 6090.994985827378, 6100.572959892765, 3514.0687717754818, 3519.9296014079573, 3542.875999340011, 3549.591796943333, 3553.8578456861437, 6730.528862979642, 6732.192136943598, 6734.292899245509, 6737.629430381376, 6740.460763138937, 6670.666466676646, 6676.298988606223, 6689.397954184147, 6689.732410703517, 6692.966535167293, 6685.164354455693, 6766.898496522556, 6776.390124888783, 6779.881319582312, 6780.694868730919, 6358.046293056079, 6450.451711604566, 6482.319356467503, 6640.302768209269, 6655.068290439439, 6519.3480651936325, 6537.640429776408, 6543.607383557001, 6551.181102362205, 6561.114249576437, 6825.078614386995, 6829.6585170742255, 6832.658367081725, 6841.760538381132, 6848.280930502425, 4382.298832186835, 4480.305552639346, 4508.278675411918, 4508.954910450985, 4509.594136527729, 6543.738250469928, 6558.311377305315, 6563.343665633399, 6566.540342176002, 6571.178602674666, 6374.203224596926, 6400.327965563735, 6401.775937842153, 6409.519286053472, 6413.551051426542, 6289.559730818799, 6396.29640739509, 6420.871582568229, 6453.774117905851, 6477.578957367756, 6260.499160067275, 6331.430171284606, 6334.588251763805, 6335.271443784024, 6346.650934198478, 6489.837754055998, 6493.318201588954, 6509.934900651122, 6512.251091124582, 6516.902246466401, 6183.659898705614, 6347.365263473615, 6350.714217860115, 6351.809445716636, 6357.078223657451], "unit": "ms", "name": "prototype.html", "value": 4819.88052468948}, {"lowerIsBetter": false, "replicates": [770.9460337776354, 816.8611336072867, 828.353883970503, 862.9352798540111, 875.5972252763728, 13199.076064675464, 13325.200487970716, 13348.532801351954, 13356.933215333935, 13358.732825343499, 12381.814272785892, 12569.685757856058, 13092.279924604143, 13175.60473185806, 13193.076484646079, 816.4896939412866, 822.0956947357919, 829.7178959153887, 830.6552780596076, 832.7252006837729, 813.9104698483162, 828.494618282847, 833.4790755777639, 833.5332213960202, 836.3309352517991, 869.5956380283167, 873.3755364914085, 874.1477059866643, 874.6545114679698, 878.5958459108786, 872.2803686958284, 874.5059041641484, 874.7244617945358, 875.9386842920998, 876.3033388456192, 644.9387308205713, 646.3084499585445, 646.8512242184324, 647.3203136706439, 648.1994736500418, 12276.0, 12320.445579948924, 12385.93807030959, 12413.379331033413, 12428.689282767913, 10892.455377231187, 11161.441927903572, 11523.07815374768, 12104.0, 12204.450799714034, 10158.238132140044, 10207.591696332138, 10244.846327305097, 10310.639127630571, 10385.480725963671, 3524.1894364296354, 3581.982090089559, 3589.74871758977, 3595.8381872815526, 3721.0697325668584, 41475.0, 41548.0, 42224.57775422268, 44440.777796111135, 49659.50340496585, 9480.383775054599, 9505.809883802285, 9810.558524866397, 9860.408375497349, 10232.181425485944, 16536.255868485947, 16573.50279491617, 16584.50246492607, 16595.0, 16660.333586656398, 7495.812604684817, 7606.429517786189, 7614.619269036526, 7625.733099341496, 7820.257075577811, 4440.555944405598, 4441.578050085237, 4460.397846290709, 4488.259437192892, 4526.185286648369, 3096.2723759916403, 3155.2269693925136, 3155.4162479941283, 3159.0996565978585, 3160.8577614007427, 560.5963706131579, 573.2919843992665, 586.3110844757393, 592.567425779175, 608.0, 531.7979167916167, 579.8173575323759, 579.9652020878676, 637.827786497643, 689.0387908867102, 8567.700130495527, 8635.956820215859, 8701.434406763667, 8861.73414797557, 8947.418417802823, 553.1674829381755, 579.9594028418055, 595.6723801908933, 643.340575909687, 709.4749885085, 3890.319194141014, 4117.094239267356, 4128.422020917074, 4178.185253875526, 4178.623923846869, 57179.42820571741, 57393.426065739644, 58023.419765802646, 58222.41777582255, 58501.414985850446, 45932.540674593496, 52404.47595524072, 53096.46903530993, 53481.0, 53754.46245537573, 42155.15689686251, 49546.0, 49813.50186498161, 49916.75041624841, 49937.500624993285, 8585.65657373698, 8795.736127916172, 8828.293736500937, 9018.819623607622, 9603.0, 33314.66685333115, 34541.13647158841, 34951.300973980884, 36045.81977090098, 36223.27553448969, 40360.0, 43196.0, 45595.54404455913, 48834.511654883, 49446.752766235935, 10974.231803773659, 11205.775884482428, 11303.547858085913, 11468.311901285615, 11753.118516111155, 12038.699032524257, 12084.36291027081, 12087.873932948714, 12234.266444159235, 12288.754224915272, 11803.527858885549, 11809.940950295193, 11915.642530724092, 12113.81829272544, 12263.816042759545, 10512.885685949144, 10654.467276636293, 10781.19141064439, 10809.783804324026, 10922.726931826768, 11556.133290003452, 11729.882701173221, 11768.70578235551, 11996.940015299868, 12253.816192757295, 9395.248380129437, 9586.904130958601, 9595.13643772064, 9854.211663066793, 9896.653617123366, 8913.242374398249, 9216.0, 9534.521024533533, 9566.617335306788, 9597.136257736836], "unit": "ms", "name": "yui.html", "value": 6301.5638543892155}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 112.0, 103.0, 111.0, 112.0, 111.0, 99.0, 110.0, 108.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 107.7}, {"lowerIsBetter": true, "replicates": [150.0, 132.0, 150.0, 163.0, 129.0, 149.0, 154.0, 133.0, 169.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.9}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 162.0, 163.0, 160.0, 164.0], "unit": "ms", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 84.0, 94.0, 70.0, 72.0, 72.0, 128.0, 92.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 82.9}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 138.0, 92.0, 93.0, 93.0, 138.0, 92.0, 93.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 59.0, 58.0, 58.0, 58.0, 60.0, 61.0, 61.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 58.6}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 70.0, 70.0, 70.0, 71.0, 70.0, 76.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.8}, {"lowerIsBetter": true, "replicates": [82.0, 91.0, 83.0, 84.0, 83.0, 84.0, 84.0, 84.0, 86.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.4}, {"lowerIsBetter": true, "replicates": [122.0, 120.0, 119.0, 115.0, 115.0, 128.0, 117.0, 116.0, 124.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.9}, {"lowerIsBetter": true, "replicates": [136.0, 141.0, 154.0, 140.0, 147.0, 135.0, 146.0, 147.0, 146.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.8}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 67.0, 73.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.2}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.28925619834712], "unit": "score", "name": "Richards", "value": 213.5771000000001}, {"lowerIsBetter": false, "replicates": [194.62826002335538], "unit": "score", "name": "DeltaBlue", "value": 339.714284}, {"lowerIsBetter": false, "replicates": [283.5270768358378], "unit": "score", "name": "Encrypt", "value": 938.8203869999999}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [1140.2508551881415], "unit": "score", "name": "RayTrace", "value": 648.9703529999999}, {"lowerIsBetter": false, "replicates": [791.1392405063291], "unit": "score", "name": "Earley", "value": 842.4092320000002}, {"lowerIsBetter": false, "replicates": [12280.487804878048], "unit": "score", "name": "Boyer", "value": 54.27007547169812}, {"lowerIsBetter": false, "replicates": [25846.153846153848], "unit": "score", "name": "RegExp", "value": 35.24644345238097}, {"lowerIsBetter": false, "replicates": [625.7822277847309], "unit": "score", "name": "Splay", "value": 130.22261800000004}, {"lowerIsBetter": false, "replicates": [4906.862745098039], "unit": "score", "name": "NavierStokes", "value": 302.43356643356645}], "name": "v8_7"}]} 20:03:58 INFO - RETURN: dromaeo_css: 6957.53 20:03:58 INFO - RETURN: kraken: 1496.70 20:03:58 INFO - RETURN: v8_7: 20795.01 20:03:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20795.01"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1496.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6957.53"}}} 20:03:59 INFO - Return code: 0 20:03:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:03:59 INFO - # TBPL SUCCESS # 20:03:59 INFO - Running post-action listener: _resource_record_post_action 20:03:59 INFO - Running post-run listener: _resource_record_post_run 20:04:00 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 499716096; Read time: 8; Write time: 413172 20:04:00 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 164229120; Read time: 0; Write time: 158000 20:04:00 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264482816; Read time: 8; Write time: 167820 20:04:00 INFO - Running post-run listener: _upload_blobber_files 20:04:00 INFO - Blob upload gear active. 20:04:00 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:04:00 INFO - Copying logs to upload dir... 20:04:00 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.719141 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-24 20:03:59.820294) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:59.823721) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009555 build_url: 'https://queue.taskcluster.net/v1/task/l8-vlC9-QQ2FogrSydxV7g/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.09 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:59.926223) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:59.926559) ========= 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=d333f129428bd91e5d9d687c000001bd-1448420200.578481-137066128 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004609 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:03:59.976277) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:03:59.976645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:03:59.976964) ========= ========= Total master_lag: 0.41 =========