builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1452625836.35 results: success (0) buildid: 20160112083200 builduid: af58687ccb324f198dddf12917eec649 revision: 4c7f027941323f5b24164ce3965f8172782041d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.348903) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.349351) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.349648) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004428 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.367036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.367342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.378194) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.378621) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.428954) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.429276) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.429635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.429907) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-12 11:10:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-01-12 11:10:36 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159003 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.704504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.704853) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016630 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.789034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:36.789407) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 4c7f027941323f5b24164ce3965f8172782041d9 --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/b2g-inbound --rev 4c7f027941323f5b24164ce3965f8172782041d9 --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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-12 11:10:37,083 truncating revision to first 12 chars 2016-01-12 11:10:37,083 Setting DEBUG logging. 2016-01-12 11:10:37,083 attempt 1/10 2016-01-12 11:10:37,083 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/4c7f02794132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-12 11:10:37,372 unpacking tar archive at: b2g-inbound-4c7f02794132/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612807 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:37.415478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:37.415862) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:37.426461) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:37.426902) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 11:10:37.427355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-01-12 11:10:37.427657) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 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', 'B2G-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:10:37 INFO - MultiFileLogger online at 20160112 11:10:37 in /builds/slave/test-pgo 11:10:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 11:10:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 11:10:37 INFO - {'append_to_log': False, 11:10:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 11:10:37 INFO - 'blob_upload_branch': 'B2G-Inbound', 11:10:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 11:10:37 INFO - 'branch': 'B2G-Inbound', 11:10:37 INFO - 'buildbot_json_path': 'buildprops.json', 11:10:37 INFO - 'config_files': ('talos/linux_config.py',), 11:10:37 INFO - 'default_actions': ('clobber', 11:10:37 INFO - 'read-buildbot-config', 11:10:37 INFO - 'download-and-extract', 11:10:37 INFO - 'populate-webroot', 11:10:37 INFO - 'create-virtualenv', 11:10:37 INFO - 'install', 11:10:37 INFO - 'run-tests'), 11:10:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:10:37 INFO - 'download_minidump_stackwalk': True, 11:10:37 INFO - 'download_symbols': 'ondemand', 11:10:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:10:37 INFO - 'tooltool.py': '/tools/tooltool.py', 11:10:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:10:37 INFO - '/tools/misc-python/virtualenv.py')}, 11:10:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:10:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:10:37 INFO - 'installer_path': 'installer.exe', 11:10:37 INFO - 'log_level': 'info', 11:10:37 INFO - 'log_name': 'talos', 11:10:37 INFO - 'log_to_console': True, 11:10:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:10:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:10:37 INFO - 'opt_config_files': (), 11:10:37 INFO - 'pip_index': False, 11:10:37 INFO - 'sps_profile': False, 11:10:37 INFO - 'sps_profile_interval': 0, 11:10:37 INFO - 'suite': 'dromaeojs', 11:10:37 INFO - 'system_bits': '32', 11:10:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:10:37 INFO - 'title': 'talos-linux64-ix-003', 11:10:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:10:37 INFO - 'use_talos_json': True, 11:10:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 11:10:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:10:37 INFO - 'work_dir': 'build'} 11:10:37 INFO - ##### 11:10:37 INFO - ##### Running clobber step. 11:10:37 INFO - ##### 11:10:37 INFO - Running pre-action listener: _resource_record_pre_action 11:10:37 INFO - Running main action method: clobber 11:10:37 INFO - rmtree: /builds/slave/test-pgo/build 11:10:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 11:10:38 INFO - Running post-action listener: _resource_record_post_action 11:10:38 INFO - ##### 11:10:38 INFO - ##### Running read-buildbot-config step. 11:10:38 INFO - ##### 11:10:38 INFO - Running pre-action listener: _resource_record_pre_action 11:10:38 INFO - Running main action method: read_buildbot_config 11:10:38 INFO - Using buildbot properties: 11:10:38 INFO - { 11:10:38 INFO - "project": "", 11:10:38 INFO - "product": "firefox", 11:10:38 INFO - "script_repo_revision": "production", 11:10:38 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 11:10:38 INFO - "repository": "", 11:10:38 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 11:10:38 INFO - "buildid": "20160112083200", 11:10:38 INFO - "pgo_build": "True", 11:10:38 INFO - "basedir": "/builds/slave/test-pgo", 11:10:38 INFO - "buildnumber": 202, 11:10:38 INFO - "slavename": "talos-linux64-ix-003", 11:10:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:10:38 INFO - "platform": "ubuntu64_hw", 11:10:38 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 11:10:38 INFO - "branch": "b2g-inbound", 11:10:38 INFO - "repo_path": "integration/b2g-inbound", 11:10:38 INFO - "revision": "4c7f027941323f5b24164ce3965f8172782041d9", 11:10:38 INFO - "stage_platform": "linux64-pgo", 11:10:38 INFO - "builduid": "af58687ccb324f198dddf12917eec649", 11:10:38 INFO - "slavebuilddir": "test" 11:10:38 INFO - } 11:10:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 11:10:38 INFO - Running post-action listener: _resource_record_post_action 11:10:38 INFO - ##### 11:10:38 INFO - ##### Running download-and-extract step. 11:10:38 INFO - ##### 11:10:38 INFO - Running pre-action listener: _resource_record_pre_action 11:10:38 INFO - Running main action method: download_and_extract 11:10:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 11:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:38 INFO - https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 11:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/test_packages.json 11:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/test_packages.json 11:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 11:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 11:10:39 INFO - Downloaded 1302 bytes. 11:10:39 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 11:10:39 INFO - Using the following test package requirements: 11:10:39 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 11:10:39 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:10:39 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'jsshell-linux-x86_64.zip'], 11:10:39 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:10:39 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 11:10:39 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:10:39 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:10:39 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:10:39 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 11:10:39 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:10:39 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:10:39 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')]} 11:10:39 INFO - https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:10:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:10:44 INFO - Downloaded 24027144 bytes. 11:10:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 11:10:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 11:10:45 INFO - Return code: 0 11:10:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:10:45 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')]} 11:10:45 INFO - https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:10:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:10:48 INFO - Downloaded 24027144 bytes. 11:10:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 11:10:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 11:10:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 11:10:49 INFO - Return code: 0 11:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:49 INFO - https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:10:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:10:51 INFO - Downloaded 11132285 bytes. 11:10:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 11:10:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 11:10:52 INFO - Return code: 0 11:10: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')]} 11:10:52 INFO - https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:10:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 11:10:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 11:10:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 11:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:11:00 INFO - Downloaded 55739620 bytes. 11:11:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 11:11:00 INFO - mkdir: /builds/slave/test-pgo/properties 11:11:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 11:11:00 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 11:11:00 INFO - Contents: 11:11:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 11:11:00 INFO - Running post-action listener: _resource_record_post_action 11:11:00 INFO - Running post-action listener: set_extra_try_arguments 11:11:00 INFO - ##### 11:11:00 INFO - ##### Running populate-webroot step. 11:11:00 INFO - ##### 11:11:00 INFO - Running pre-action listener: _resource_record_pre_action 11:11:00 INFO - Running main action method: populate_webroot 11:11:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:11:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:11:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:11:00 INFO - '--tppagecycles', 11:11:00 INFO - '7'], 11:11:00 INFO - 'tests': ['tsvgm']}}, 11:11:00 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:11:00 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:11:00 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:11:00 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:11:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'tests': ['damp', 'tps']}, 11:11:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['damp', 'tps']}, 11:11:00 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:11:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:11:00 INFO - 'other': {'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'other_l64': {'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'other_nol64': {'tests': ['a11yr', 11:11:00 INFO - 'ts_paint', 11:11:00 INFO - 'tpaint', 11:11:00 INFO - 'sessionrestore', 11:11:00 INFO - 'sessionrestore_no_auto_restore']}, 11:11:00 INFO - 'svgr': {'tests': ['tsvgx', 11:11:00 INFO - 'tsvgr_opacity', 11:11:00 INFO - 'tart', 11:11:00 INFO - 'tscrollx', 11:11:00 INFO - 'cart']}, 11:11:00 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['tsvgx', 11:11:00 INFO - 'tsvgr_opacity', 11:11:00 INFO - 'tart', 11:11:00 INFO - 'tscrollx', 11:11:00 INFO - 'cart']}, 11:11:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'tests': ['tp5o']}, 11:11:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'talos_options': ['--e10s'], 11:11:00 INFO - 'tests': ['tp5o']}, 11:11:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'talos_options': ['--xperf_path', 11:11:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:00 INFO - 'tests': ['tp5n']}, 11:11:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:00 INFO - 'talos_options': ['--e10s', 11:11:00 INFO - '--xperf_path', 11:11:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:00 INFO - 'tests': ['tp5n']}}, 11:11:00 INFO - 'talos.zip': {'path': '', 11:11:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:11:00 INFO - Running post-action listener: _resource_record_post_action 11:11:00 INFO - ##### 11:11:00 INFO - ##### Running create-virtualenv step. 11:11:00 INFO - ##### 11:11:00 INFO - Running pre-action listener: _resource_record_pre_action 11:11:00 INFO - Running main action method: create_virtualenv 11:11:00 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 11:11:00 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 11:11:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 11:11:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:11:00 INFO - Using real prefix '/usr' 11:11:00 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 11:11:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:11:02 INFO - Installing pip.................done. 11:11:02 INFO - Return code: 0 11:11:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 11:11:02 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')]} 11:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:02 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')]} 11:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:02 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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:02 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 11:11:02 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 11:11:02 INFO - Using env: {'DISPLAY': ':0', 11:11:02 INFO - 'HOME': '/home/cltbld', 11:11:02 INFO - 'LANG': 'en_US.UTF-8', 11:11:02 INFO - 'LANGUAGE': 'en_US:en', 11:11:02 INFO - 'LOGNAME': 'cltbld', 11:11:02 INFO - 'MAIL': '/var/mail/cltbld', 11:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:02 INFO - 'MOZ_NO_REMOTE': '1', 11:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:02 INFO - 'NO_EM_RESTART': '1', 11:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:02 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:02 INFO - 'SHELL': '/bin/bash', 11:11:02 INFO - 'SHLVL': '1', 11:11:02 INFO - 'TERM': 'linux', 11:11:02 INFO - 'TMOUT': '86400', 11:11:02 INFO - 'USER': 'cltbld', 11:11:02 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:02 INFO - '_': '/tools/buildbot/bin/python'} 11:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:02 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 11:11:02 INFO - Cleaning up... 11:11:02 INFO - Return code: 0 11:11:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 11:11:02 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')]} 11:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:02 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')]} 11:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:02 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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:02 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 11:11:02 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 11:11:02 INFO - Using env: {'DISPLAY': ':0', 11:11:02 INFO - 'HOME': '/home/cltbld', 11:11:02 INFO - 'LANG': 'en_US.UTF-8', 11:11:02 INFO - 'LANGUAGE': 'en_US:en', 11:11:02 INFO - 'LOGNAME': 'cltbld', 11:11:02 INFO - 'MAIL': '/var/mail/cltbld', 11:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:02 INFO - 'MOZ_NO_REMOTE': '1', 11:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:02 INFO - 'NO_EM_RESTART': '1', 11:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:02 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:02 INFO - 'SHELL': '/bin/bash', 11:11:02 INFO - 'SHLVL': '1', 11:11:02 INFO - 'TERM': 'linux', 11:11:02 INFO - 'TMOUT': '86400', 11:11:02 INFO - 'USER': 'cltbld', 11:11:02 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:02 INFO - '_': '/tools/buildbot/bin/python'} 11:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:03 INFO - Downloading/unpacking psutil>=0.7.1 11:11:03 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 11:11:03 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 11:11:03 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 11:11:03 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 11:11:03 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 11:11:03 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 11:11:04 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 11:11:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 11:11:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 11:11:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:04 INFO - Installing collected packages: psutil 11:11:04 INFO - Running setup.py install for psutil 11:11:05 INFO - building 'psutil._psutil_linux' extension 11:11:05 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 11:11:05 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 11:11:05 INFO - building 'psutil._psutil_posix' extension 11:11:05 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 11:11:05 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 11:11:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:05 INFO - Successfully installed psutil 11:11:05 INFO - Cleaning up... 11:11:05 INFO - Return code: 0 11:11:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 11:11:05 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')]} 11:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:05 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')]} 11:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:05 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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:05 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 11:11:05 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 11:11:05 INFO - Using env: {'DISPLAY': ':0', 11:11:05 INFO - 'HOME': '/home/cltbld', 11:11:05 INFO - 'LANG': 'en_US.UTF-8', 11:11:05 INFO - 'LANGUAGE': 'en_US:en', 11:11:05 INFO - 'LOGNAME': 'cltbld', 11:11:05 INFO - 'MAIL': '/var/mail/cltbld', 11:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:05 INFO - 'MOZ_NO_REMOTE': '1', 11:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:05 INFO - 'NO_EM_RESTART': '1', 11:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:05 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:05 INFO - 'SHELL': '/bin/bash', 11:11:05 INFO - 'SHLVL': '1', 11:11:05 INFO - 'TERM': 'linux', 11:11:05 INFO - 'TMOUT': '86400', 11:11:05 INFO - 'USER': 'cltbld', 11:11:05 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:05 INFO - '_': '/tools/buildbot/bin/python'} 11:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:11:05 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 11:11:05 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 11:11:05 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 11:11:05 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 11:11:05 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 11:11:05 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 11:11:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:11:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 11:11:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:11:07 INFO - Installing collected packages: mozsystemmonitor 11:11:07 INFO - Running setup.py install for mozsystemmonitor 11:11:07 INFO - Successfully installed mozsystemmonitor 11:11:07 INFO - Cleaning up... 11:11:07 INFO - Return code: 0 11:11:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 11:11: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')]} 11:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:07 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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:07 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 11:11:07 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 11:11:07 INFO - Using env: {'DISPLAY': ':0', 11:11:07 INFO - 'HOME': '/home/cltbld', 11:11:07 INFO - 'LANG': 'en_US.UTF-8', 11:11:07 INFO - 'LANGUAGE': 'en_US:en', 11:11:07 INFO - 'LOGNAME': 'cltbld', 11:11:07 INFO - 'MAIL': '/var/mail/cltbld', 11:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:07 INFO - 'MOZ_NO_REMOTE': '1', 11:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:07 INFO - 'NO_EM_RESTART': '1', 11:11:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:07 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:07 INFO - 'SHELL': '/bin/bash', 11:11:07 INFO - 'SHLVL': '1', 11:11:07 INFO - 'TERM': 'linux', 11:11:07 INFO - 'TMOUT': '86400', 11:11:07 INFO - 'USER': 'cltbld', 11:11:07 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:07 INFO - '_': '/tools/buildbot/bin/python'} 11:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:07 INFO - Downloading/unpacking blobuploader==1.2.4 11:11:07 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 11:11:07 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 11:11:07 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 11:11:07 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 11:11:07 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 11:11:07 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 11:11:09 INFO - Downloading blobuploader-1.2.4.tar.gz 11:11:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 11:11:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:11:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:11:09 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 11:11:09 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 11:11:09 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 11:11:09 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 11:11:09 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 11:11:09 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 11:11:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 11:11:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 11:11:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:11:10 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 11:11:10 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 11:11:10 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 11:11:10 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 11:11:10 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 11:11:10 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 11:11:10 INFO - Downloading docopt-0.6.1.tar.gz 11:11:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 11:11:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 11:11:10 INFO - Installing collected packages: blobuploader, requests, docopt 11:11:10 INFO - Running setup.py install for blobuploader 11:11:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:11:10 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 11:11:10 INFO - Running setup.py install for requests 11:11:10 INFO - Running setup.py install for docopt 11:11:10 INFO - Successfully installed blobuploader requests docopt 11:11:10 INFO - Cleaning up... 11:11:10 INFO - Return code: 0 11:11:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:10 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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:10 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 11:11:10 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 11:11:10 INFO - Using env: {'DISPLAY': ':0', 11:11:10 INFO - 'HOME': '/home/cltbld', 11:11:10 INFO - 'LANG': 'en_US.UTF-8', 11:11:10 INFO - 'LANGUAGE': 'en_US:en', 11:11:10 INFO - 'LOGNAME': 'cltbld', 11:11:10 INFO - 'MAIL': '/var/mail/cltbld', 11:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:10 INFO - 'MOZ_NO_REMOTE': '1', 11:11:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:10 INFO - 'NO_EM_RESTART': '1', 11:11:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:10 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:10 INFO - 'SHELL': '/bin/bash', 11:11:10 INFO - 'SHLVL': '1', 11:11:10 INFO - 'TERM': 'linux', 11:11:10 INFO - 'TMOUT': '86400', 11:11:10 INFO - 'USER': 'cltbld', 11:11:10 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:10 INFO - '_': '/tools/buildbot/bin/python'} 11:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:11:11 INFO - Running setup.py (path:/tmp/pip-eI2GAF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:11:11 INFO - Running setup.py (path:/tmp/pip-EeBrJe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:11:11 INFO - Running setup.py (path:/tmp/pip-mo3vEK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:11:11 INFO - Running setup.py (path:/tmp/pip-kjpJ9W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:11:11 INFO - Running setup.py (path:/tmp/pip-Lw3hdB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:11:11 INFO - Running setup.py (path:/tmp/pip-9jV3kb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:11:11 INFO - Running setup.py (path:/tmp/pip-bAKioO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:11:11 INFO - Running setup.py (path:/tmp/pip-4udRcG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:11:11 INFO - Running setup.py (path:/tmp/pip-qEqn4R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:11:11 INFO - Running setup.py (path:/tmp/pip-TIeq3A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:11:11 INFO - Running setup.py (path:/tmp/pip-PZSoIH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:11:11 INFO - Running setup.py (path:/tmp/pip-Cq40cH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:11:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:11:11 INFO - Running setup.py (path:/tmp/pip-PWEkRA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:11:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:11:12 INFO - Running setup.py (path:/tmp/pip-cUR6PQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:11:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:11:12 INFO - Running setup.py (path:/tmp/pip-6kDReb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:11:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:11:12 INFO - Running setup.py (path:/tmp/pip-cN3HTs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:11:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:11:12 INFO - Running setup.py (path:/tmp/pip-gWbp5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:11:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:11:12 INFO - Running setup.py install for manifestparser 11:11:12 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 11:11:12 INFO - Running setup.py install for mozcrash 11:11:12 INFO - Running setup.py install for mozdebug 11:11:12 INFO - Running setup.py install for mozdevice 11:11:12 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 11:11:12 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 11:11:12 INFO - Running setup.py install for mozfile 11:11:12 INFO - Running setup.py install for mozhttpd 11:11:12 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 11:11:12 INFO - Running setup.py install for mozinfo 11:11:12 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 11:11:12 INFO - Running setup.py install for mozInstall 11:11:13 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Running setup.py install for mozleak 11:11:13 INFO - Running setup.py install for mozlog 11:11:13 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Running setup.py install for moznetwork 11:11:13 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Running setup.py install for mozprocess 11:11:13 INFO - Running setup.py install for mozprofile 11:11:13 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Running setup.py install for mozrunner 11:11:13 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 11:11:13 INFO - Running setup.py install for mozscreenshot 11:11:13 INFO - Running setup.py install for moztest 11:11:13 INFO - Running setup.py install for mozversion 11:11:14 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 11:11:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:11:14 INFO - Cleaning up... 11:11:14 INFO - Return code: 0 11:11:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:11: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')]} 11:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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', '-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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:14 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 11:11:14 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 11:11:14 INFO - Using env: {'DISPLAY': ':0', 11:11:14 INFO - 'HOME': '/home/cltbld', 11:11:14 INFO - 'LANG': 'en_US.UTF-8', 11:11:14 INFO - 'LANGUAGE': 'en_US:en', 11:11:14 INFO - 'LOGNAME': 'cltbld', 11:11:14 INFO - 'MAIL': '/var/mail/cltbld', 11:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:14 INFO - 'MOZ_NO_REMOTE': '1', 11:11:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:14 INFO - 'NO_EM_RESTART': '1', 11:11:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:14 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:14 INFO - 'SHELL': '/bin/bash', 11:11:14 INFO - 'SHLVL': '1', 11:11:14 INFO - 'TERM': 'linux', 11:11:14 INFO - 'TMOUT': '86400', 11:11:14 INFO - 'USER': 'cltbld', 11:11:14 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:14 INFO - '_': '/tools/buildbot/bin/python'} 11:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:11:14 INFO - Running setup.py (path:/tmp/pip-OJyJeB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:11:14 INFO - Running setup.py (path:/tmp/pip-2UieKQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:11:14 INFO - Running setup.py (path:/tmp/pip-O8GpeT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:11:14 INFO - Running setup.py (path:/tmp/pip-EvpWd1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:11:14 INFO - Running setup.py (path:/tmp/pip-63cCrY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:11:14 INFO - Running setup.py (path:/tmp/pip-cgmRsI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:11:14 INFO - Running setup.py (path:/tmp/pip-7CNAAo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:11:14 INFO - Running setup.py (path:/tmp/pip-P0p8IB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:11:14 INFO - Running setup.py (path:/tmp/pip-oW3Weq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:11:14 INFO - Running setup.py (path:/tmp/pip-cb8iB6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:11:14 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)) 11:11:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:11:14 INFO - Running setup.py (path:/tmp/pip-aeQVUT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:11:15 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:11:15 INFO - Running setup.py (path:/tmp/pip-owwtgz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:11:15 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:11:15 INFO - Running setup.py (path:/tmp/pip-KtS5P9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:11:15 INFO - Running setup.py (path:/tmp/pip-FNyurZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:11:15 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:11:15 INFO - Running setup.py (path:/tmp/pip-taohTr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:11:15 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:11:15 INFO - Running setup.py (path:/tmp/pip-af_hTc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:11:15 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)) 11:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:11:15 INFO - Running setup.py (path:/tmp/pip-nXPb2u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:11:15 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)) 11:11:15 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)) 11:11:15 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)) 11:11:15 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)) 11:11:15 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)) 11:11:15 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)) 11:11:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 11:11: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 11:11: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 11:11: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 11:11: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 11:11: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 11:11: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 11:11:17 INFO - Downloading blessings-1.5.1.tar.gz 11:11:17 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 11:11:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 11:11:17 INFO - Installing collected packages: blessings 11:11:17 INFO - Running setup.py install for blessings 11:11:17 INFO - Successfully installed blessings 11:11:17 INFO - Cleaning up... 11:11:17 INFO - Return code: 0 11:11:17 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 11:11:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:11:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:11:17 INFO - Reading from file tmpfile_stdout 11:11:17 INFO - Current package versions: 11:11:17 INFO - argparse == 1.2.1 11:11:17 INFO - blessings == 1.5.1 11:11:17 INFO - blobuploader == 1.2.4 11:11:17 INFO - docopt == 0.6.1 11:11:17 INFO - manifestparser == 1.1 11:11:17 INFO - mozInstall == 1.12 11:11:17 INFO - mozcrash == 0.16 11:11:17 INFO - mozdebug == 0.1 11:11:17 INFO - mozdevice == 0.47 11:11:17 INFO - mozfile == 1.2 11:11:17 INFO - mozhttpd == 0.7 11:11:17 INFO - mozinfo == 0.9 11:11:17 INFO - mozleak == 0.1 11:11:17 INFO - mozlog == 3.1 11:11:17 INFO - moznetwork == 0.27 11:11:17 INFO - mozprocess == 0.22 11:11:17 INFO - mozprofile == 0.28 11:11:17 INFO - mozrunner == 6.11 11:11:17 INFO - mozscreenshot == 0.1 11:11:17 INFO - mozsystemmonitor == 0.0 11:11:17 INFO - moztest == 0.7 11:11:17 INFO - mozversion == 1.4 11:11:17 INFO - psutil == 3.1.1 11:11:17 INFO - requests == 1.2.3 11:11:17 INFO - wsgiref == 0.1.2 11:11:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:11: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')]} 11:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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', '-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 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:17 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 11:11:17 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 11:11:17 INFO - Using env: {'DISPLAY': ':0', 11:11:17 INFO - 'HOME': '/home/cltbld', 11:11:17 INFO - 'LANG': 'en_US.UTF-8', 11:11:17 INFO - 'LANGUAGE': 'en_US:en', 11:11:17 INFO - 'LOGNAME': 'cltbld', 11:11:17 INFO - 'MAIL': '/var/mail/cltbld', 11:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:17 INFO - 'MOZ_NO_REMOTE': '1', 11:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:17 INFO - 'NO_EM_RESTART': '1', 11:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:17 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:17 INFO - 'SHELL': '/bin/bash', 11:11:17 INFO - 'SHLVL': '1', 11:11:17 INFO - 'TERM': 'linux', 11:11:17 INFO - 'TMOUT': '86400', 11:11:17 INFO - 'USER': 'cltbld', 11:11:17 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:17 INFO - '_': '/tools/buildbot/bin/python'} 11:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 11:11:17 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 2)) 11:11:17 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 3)) 11:11:17 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 4)) 11:11:17 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 5)) 11:11:17 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 6)) 11:11:17 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 7)) 11:11:17 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 8)) 11:11:17 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 9)) 11:11:17 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 11:11:17 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 4)) 11:11:17 INFO - Cleaning up... 11:11:17 INFO - Return code: 0 11:11:17 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 11:11: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')]} 11:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11: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', 'jsonschema'] in /builds/slave/test-pgo/build 11:11: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 jsonschema 11:11:17 INFO - Using env: {'DISPLAY': ':0', 11:11:17 INFO - 'HOME': '/home/cltbld', 11:11:17 INFO - 'LANG': 'en_US.UTF-8', 11:11:17 INFO - 'LANGUAGE': 'en_US:en', 11:11:17 INFO - 'LOGNAME': 'cltbld', 11:11:17 INFO - 'MAIL': '/var/mail/cltbld', 11:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:17 INFO - 'MOZ_NO_REMOTE': '1', 11:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:17 INFO - 'NO_EM_RESTART': '1', 11:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:17 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:17 INFO - 'SHELL': '/bin/bash', 11:11:17 INFO - 'SHLVL': '1', 11:11:17 INFO - 'TERM': 'linux', 11:11:17 INFO - 'TMOUT': '86400', 11:11:17 INFO - 'USER': 'cltbld', 11:11:17 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:17 INFO - '_': '/tools/buildbot/bin/python'} 11:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:18 INFO - Downloading/unpacking jsonschema 11:11:18 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 11:11:18 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 11:11:18 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 11:11:18 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 11:11:18 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 11:11:18 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 11:11:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:11:19 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 11:11:19 INFO - Downloading/unpacking functools32 (from jsonschema) 11:11: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 11:11: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 11:11: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 11:11: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 11:11: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 11:11: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 11:11:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:11:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 11:11:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 11:11:20 INFO - warning: no files found matching '*.txt' 11:11:20 INFO - no previously-included directories found matching 'build' 11:11:20 INFO - no previously-included directories found matching 'dist' 11:11:20 INFO - no previously-included directories found matching '.git*' 11:11:20 INFO - Installing collected packages: jsonschema, functools32 11:11:20 INFO - Running setup.py install for functools32 11:11:20 INFO - warning: no files found matching '*.txt' 11:11:20 INFO - no previously-included directories found matching 'build' 11:11:20 INFO - no previously-included directories found matching 'dist' 11:11:20 INFO - no previously-included directories found matching '.git*' 11:11:20 INFO - Successfully installed jsonschema functools32 11:11:20 INFO - Cleaning up... 11:11:20 INFO - Return code: 0 11:11:20 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 11:11:20 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')]} 11:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:20 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')]} 11:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:20 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ccaf68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, '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': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', '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 11:11:20 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', 'flake8'] in /builds/slave/test-pgo/build 11:11:20 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 flake8 11:11:20 INFO - Using env: {'DISPLAY': ':0', 11:11:20 INFO - 'HOME': '/home/cltbld', 11:11:20 INFO - 'LANG': 'en_US.UTF-8', 11:11:20 INFO - 'LANGUAGE': 'en_US:en', 11:11:20 INFO - 'LOGNAME': 'cltbld', 11:11:20 INFO - 'MAIL': '/var/mail/cltbld', 11:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:20 INFO - 'MOZ_NO_REMOTE': '1', 11:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:20 INFO - 'NO_EM_RESTART': '1', 11:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:20 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:20 INFO - 'SHELL': '/bin/bash', 11:11:20 INFO - 'SHLVL': '1', 11:11:20 INFO - 'TERM': 'linux', 11:11:20 INFO - 'TMOUT': '86400', 11:11:20 INFO - 'USER': 'cltbld', 11:11:20 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:20 INFO - '_': '/tools/buildbot/bin/python'} 11:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:20 INFO - Downloading/unpacking flake8 11:11:20 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 11:11:20 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 11:11:20 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 11:11:20 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 11:11:20 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 11:11:20 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 11:11:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:11:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 11:11:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:11:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 11:11:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 11:11:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 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 11:11:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 11:11:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 11:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:11:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:11:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:11:22 INFO - no previously-included directories found matching 'docs/_build' 11:11:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:11:22 INFO - Running setup.py install for pep8 11:11:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:11:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:11:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:11:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:11:23 INFO - no previously-included directories found matching 'docs/_build' 11:11:23 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 11:11:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:11:23 INFO - Cleaning up... 11:11:23 INFO - Return code: 0 11:11:23 INFO - Running post-action listener: _resource_record_post_action 11:11:23 INFO - Running post-action listener: _start_resource_monitoring 11:11:23 INFO - Starting resource monitoring. 11:11:23 INFO - ##### 11:11:23 INFO - ##### Running install step. 11:11:23 INFO - ##### 11:11:23 INFO - Running pre-action listener: _resource_record_pre_action 11:11:23 INFO - Running main action method: install 11:11:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:11:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:11:23 INFO - Reading from file tmpfile_stdout 11:11:23 INFO - Detecting whether we're running mozinstall >=1.0... 11:11:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 11:11:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 11:11:23 INFO - Reading from file tmpfile_stdout 11:11:23 INFO - Output received: 11:11:23 INFO - Usage: mozinstall [options] installer 11:11:23 INFO - Options: 11:11:23 INFO - -h, --help show this help message and exit 11:11:23 INFO - -d DEST, --destination=DEST 11:11:23 INFO - Directory to install application into. [default: 11:11:23 INFO - "/builds/slave/test-pgo"] 11:11:23 INFO - --app=APP Application being installed. [default: firefox] 11:11:23 INFO - mkdir: /builds/slave/test-pgo/build/application 11:11:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 11:11:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 11:11:39 INFO - Reading from file tmpfile_stdout 11:11:39 INFO - Output received: 11:11:39 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 11:11:39 INFO - Running post-action listener: _resource_record_post_action 11:11:39 INFO - ##### 11:11:39 INFO - ##### Running run-tests step. 11:11:39 INFO - ##### 11:11:39 INFO - Running pre-action listener: _resource_record_pre_action 11:11:39 INFO - Running main action method: run_tests 11:11:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 11:11:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 11:11:39 INFO - Python 2.7.3 11:11:39 INFO - Return code: 0 11:11:39 INFO - grabbing minidump binary from tooltool 11:11:39 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')]} 11:11:39 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 0x1a42840>, '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 0x19af6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19afb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 11:11:39 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 11:11:39 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 11:11:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:11:39 INFO - Return code: 0 11:11:39 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 11:11:39 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 11:11:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 11:11:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 11:11:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 11:11:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 11:11:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 11:11:40 INFO - Return code: 0 11:11:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 11:11:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 11:11:40 INFO - Using env: {'DISPLAY': ':0', 11:11:40 INFO - 'HOME': '/home/cltbld', 11:11:40 INFO - 'LANG': 'en_US.UTF-8', 11:11:40 INFO - 'LANGUAGE': 'en_US:en', 11:11:40 INFO - 'LOGNAME': 'cltbld', 11:11:40 INFO - 'MAIL': '/var/mail/cltbld', 11:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 11:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:40 INFO - 'MOZ_NO_REMOTE': '1', 11:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:40 INFO - 'NO_EM_RESTART': '1', 11:11:40 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:11:40 INFO - 'PWD': '/builds/slave/test-pgo', 11:11:40 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 11:11:40 INFO - 'SHELL': '/bin/bash', 11:11:40 INFO - 'SHLVL': '1', 11:11:40 INFO - 'TERM': 'linux', 11:11:40 INFO - 'TMOUT': '86400', 11:11:40 INFO - 'USER': 'cltbld', 11:11:40 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1452625835.834768-1003834015', 11:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:40 INFO - '_': '/tools/buildbot/bin/python'} 11:11:40 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:11:40 INFO - mozversion application_buildid: 20160112083200 11:11:40 INFO - mozversion application_changeset: 4c7f027941323f5b24164ce3965f8172782041d9 11:11:40 INFO - mozversion application_display_name: Nightly 11:11:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:11:40 INFO - mozversion application_name: Firefox 11:11:40 INFO - mozversion application_remotingname: firefox 11:11:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/b2g-inbound 11:11:40 INFO - mozversion application_vendor: Mozilla 11:11:40 INFO - mozversion application_version: 46.0a1 11:11:40 INFO - mozversion platform_buildid: 20160112083200 11:11:40 INFO - mozversion platform_changeset: 4c7f027941323f5b24164ce3965f8172782041d9 11:11:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/b2g-inbound 11:11:40 INFO - mozversion platform_version: 46.0a1 11:11:40 INFO - using testdate: 1452625900 11:11:40 INFO - actual date: 1452625900 11:11:40 INFO - starting webserver on 'localhost:59367' 11:11:40 INFO - SUITE-START | Running 3 tests 11:11:40 INFO - TEST-START | dromaeo_css 11:11:40 INFO - operating with platform_type : linux_ 11:11:40 INFO - Initialising browser for dromaeo_css test... 11:11:40 INFO - TEST-INFO | started process 9623 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc5ezNb/profile http://localhost:59367/getInfo.html) 11:11:44 INFO - PROCESS | 9623 | __metrics Screen width/height:1600/1200 11:11:44 INFO - PROCESS | 9623 | colorDepth:24 11:11:44 INFO - PROCESS | 9623 | Browser inner width/height: 1024/697 11:11:44 INFO - PROCESS | 9623 | __metrics 11:11:44 INFO - PROCESS | 9623 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 11:11:44 INFO - PROCESS | 9623 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:11:46 INFO - TEST-INFO | 9623: exit 0 11:11:46 INFO - Browser initialized. 11:11:46 INFO - Running cycle 1/1 for dromaeo_css test... 11:11:46 INFO - TEST-INFO | started process 9716 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc5ezNb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:58 INFO - PROCESS | 9716 | RSS: Main: 147914752 11:11:58 INFO - PROCESS | 9716 | 11:12:49 INFO - PROCESS | 9716 | 1452625969694 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] 11:14:57 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59367/tests/dromaeo/cssquery-ext.html) 11:14:58 INFO - PROCESS | 9716 | RSS: Main: 231079936 11:14:58 INFO - PROCESS | 9716 | 11:18:01 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-ext.html (next: http://localhost:59367/tests/dromaeo/cssquery-jquery.html) 11:18:01 INFO - PROCESS | 9716 | RSS: Main: 231161856 11:18:01 INFO - PROCESS | 9716 | 11:21:03 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59367/tests/dromaeo/cssquery-mootools.html) 11:21:04 INFO - PROCESS | 9716 | RSS: Main: 227282944 11:21:04 INFO - PROCESS | 9716 | 11:24:06 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59367/tests/dromaeo/cssquery-prototype.html) 11:24:06 INFO - PROCESS | 9716 | RSS: Main: 213303296 11:24:06 INFO - PROCESS | 9716 | 11:27:09 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59367/tests/dromaeo/cssquery-yui.html) 11:27:09 INFO - PROCESS | 9716 | RSS: Main: 217485312 11:27:09 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:59367/tests/dromaeo/cssquery-yui.html (next: http://localhost:59367/tests/dromaeo/cssquery-dojo.html) 11:30:12 INFO - PROCESS | 9716 | RSS: Main: 286232576 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | __start_tp_report 11:30:12 INFO - PROCESS | 9716 | _x_x_mozilla_page_load 11:30:12 INFO - PROCESS | 9716 | _x_x_mozilla_page_load_details 11:30:12 INFO - PROCESS | 9716 | |i|pagename|runs| 11:30:12 INFO - PROCESS | 9716 | |0;dojo.html;3136.5608814765933;3151.401233765583;3177.793443426177;3185.761067919905;3209.309998350355;4916.115099282128;4981.775820088095;5009.899802003958;5087.135187018206;5091.949080509198;5111;5165.767540460679;5166.483351664831;5169.534741873231;5191.792328306863;4482.798274077658;4639.350490931272;4680.743819214175;4686.133065382898;4822.059698358804;4559.430071241095;4775.880602984918;4786.688865223767;4788.760561971905;4864.927026094594;4628.5140060293525;4679.181143299926;4748.121597504472;4748.38271024768;4788.449328327252;4680.485146633868;4702.95297047032;4704.035672687125;4706.388169537972;4740.336352910595;4684.929726054112;4694.835680751157;4700.506446823103;4721.26820342844;4727.290906364038;4852.611791056743;4909.189983652729;4991.251312303148;5063.240513922904;5086.262491938685;5506.752196151183;5514.200440936082;5517.69652669103;5531.889362212773;5534.695591742453;5276.630635855453;5544.112941929272;5545.750441230114;5545.77816887328;5553.916691249635;5095.617828662828;5186.0665080285535;5240.2401651760665;5261.579073674096;5304.310439642822;5622.212890195376;5624.268845050199;5630.211770352154;5639.661620302795;5667.914981275284;5321.840344789663;5401.459854014567;5416.160495123262;5419.078756611386;5426.70153141581;5425.538829199562;5437.4562543745305;5446.700431476228;5449.809256675997;5495.945040549544;5461.42655021225;5489.1217405214975;5490.450954904478;5492.286002819608;5492.533134683516;5271.314729085195;5339.546138578186;5402.216678581583;5480.150576660624;5550.139728342114;4289.206496798102;4329.242382583036;4331.913361732811;4336;4370.847020354272;3804.410316400962;3812.3137835189377;3816.3702989007056;3837.366834472281;3863.30460517104;4938.4320803107385;4988.575971042429;5036.622253331015;5057.317262169634;5093.694378337312;5464.316960379952;5480.177973303957;5505.697186654692;5507.669539827623;5549.084401073858;5110.233464980209;5146.794128234904;5148.6910785353;5159.329287192641;5191.688498690015;4599.402077729874;4688.132695451352;4746.169420351424;4757.452892917289;4761.761911904391;5682.374938756733;5689.374168841424;5695.401982791747;5745.195672605839;5752.453516915886;5478.506934375925;5483.232347471437;5484.61607687458;5525.502704756591;5529.391766905717;5481.972590137024;5572.192032155233;5580.5535557156145;5697.800576979786;5723.8569035774435;5416.891662166656;5449.945500544944;5453.9092181563055;5454.318210223722;5457.345118585795;5487.847552014122;5521.254630624814;5542.72286385671;5546.639468434701;5592.440755924537;5179.9640071985;5248.186531087687;5311.495407936394;5364.704941228114;5393.83818485446;5354.11657076578;5359.517643412111;5361.169018802092;5361.410244873139;5364.8122315718765;5038.546530812245;5284.894302113859;5285.524302812765;5287.814926477555;5337.226102215234;5323.7071961040965;5361.463853614608;5430.755615997368;5501.944980550144;5503.394626591152;5285.68285902847;5288.153895376721;5290.841274761763;5290.920636190383;5292.967871265067 11:30:12 INFO - PROCESS | 9716 | |1;ext.html;510.38498609175946;524.2215310264257;527.8126265175862;551.2062629813068;553.3802141601406;28153.155405337846;35548.64451355486;36230.27539449209;36265.81867090661;36277.455838162416;39327;39734.40398394026;39839.40240896382;39903.60096399035;39998.800006000005;8413.915860841389;8614.612342444605;8620.224179823814;8624.396292259544;8627.137286271385;8327.583620818934;8439.70461033864;8449.95775021124;8459.196376344269;8674.262687671555;8649.567521623925;8710.303175745925;8720.43317184384;8727.563621818916;8737.519436431026;8551.358648101386;8612.5693715314;8634.913650863473;8635.525046122491;8638.913610863905;8531.872021919677;8634.740957771213;8647.567621618893;8648.048714641385;8649.740507784776;38903.41644875329;39113.41329880025;39304;39379.8031009846;39398.80300598507;3258.4949332853444;3376.4935259710996;3470.9305813883834;3494.178867966026;3580.997320750194;27041.594376084373;27292.181234562995;27526.17421477359;27683.169504914884;27687.169384918485;3775.226078653897;3817.7518461300206;3902.512185976753;3964.5002921684095;4036.1120553478186;194738.02630986896;194773;195012;195344;196449;60154.586087252355;61419;61590.53299403588;61618;61762;65259.34740652628;65526.344736552026;65845.34154658392;65893.34106658968;65997;16209;16283.267252973645;16317.836821631632;16330.836691633169;16330.836691633169;13012.089153759336;13047.478100875858;13053.216806991612;13082.934585327013;13094.410751516205;19403.223871045284;20206.393808185778;20334.288299909433;20381.694274585894;20384.694229586567;7650.158482566913;7754.534727916343;7857.449978501564;7882.645280962371;7902.407319450949;815.9020917489939;845.5476320168681;884.4030279561399;910.7950711089952;940.9012053734397;24695.135670251457;25657.871710641328;26072.217833465027;26354.341141471617;26497.33756656099;1002.8595996560488;1015.94920253987;1022.4121130350078;1029.4749677664445;1055.2033214922685;12929.418176182095;15269.694606108038;15812.206947863753;15880.60298492547;16219.432319868984;324413;326859;328203;329648;331378;45419;46452.30321545179;51956.22065669018;52817;52828;1263.3304348695206;1293.8253335799734;1304.373900527752;1309.0771006440436;1313.6650154210615;26038.739612603633;26618.334541636614;26626.201213963614;26956.32609184786;27045;39377.60622393739;43579.34630980601;44427.55572444234;49815.75092124516;50948.23577646264;194760;197247;197763;197879;199112;32222.677773221967;32577.511337329484;32680;32696.50955235626;32702.34595308128;42688.35967460553;43154.78422607992;43490;43904.56095439132;43986.34020489759;35901.461478077326;35935.28129437449;36549.45175822418;38651.42022869603;40382.79808600938;33748.49377259294;38019;38126.428103579026;40267.798661006505;41191.7940410296;33887.322253555285;34065.659343406245;38312.616873830906;38641;38872;28574.85712571507;30491;30636.540451893685;30895;30985;20919.423543930694;22681.432964176027;22817.31548053561;22875;22910.65634015458 11:30:12 INFO - PROCESS | 9716 | |2;jquery.html;367.7168580193251;368.5945459994005;368.8967089215017;369.2062066556902;370.8961490782581;13007.804882926765;13154.408051637676;16154.111523866197;16702.749458758128;17573.912130439345;14649.560513184595;15073.321700523475;15383.230838458088;15385.615359616015;15833.12917789521;5139.691618502892;5300.681959082448;5301.734913254341;5302.761375738101;5303.814366497173;3973.9801300993454;4097.44684467596;5199.68801871887;5262.736863156846;5604.075327570967;5736.512396446286;5758.42415758425;5765.596408251424;5781.768729250846;5799.4780469757925;5054.848354549369;5237.7904883804795;5238.109521381374;5240.633155679104;5245.186996015624;4886.804527818918;5020.748962551857;5029.321041659366;5041.1682072458;5041.924371134436;12087.335196564185;12131.150819442642;12275.386230688518;12295.4467048983;12822.102452828305;2048.4878780304925;2061.288855344904;2099.769025407204;2141.635921893282;2157.546915147821;5603.271574695264;5604.495595396393;5613.915791263134;8880.889888763904;9399.295052870993;1714.4770844892303;1817.1913498493175;1833.6791061564172;1861.7393564900924;1864.906754662275;50412.23557175481;51340.74329628328;52581.47418525804;52907;53729.99999999961;10074.194064474825;10111.494425278854;10115.443650599287;10125.189984801198;10180.745481362877;12946;13072;13152.605421837168;13183.14309569875;13197.604071877859;7551.207123251979;7582.54504729718;7583.431242656823;7662.387009039264;7666.769996900102;7504;7517.210692877278;7543.886841697379;7565.583892885944;7599.784034554445;4383.276759334674;4393.648508119343;4399.802008909607;4414.337849322627;4449.2881139018245;1719.6990526658035;1803.1164729282557;1820.1172431370774;1851.9537011574819;1876.6127140511235;939.1031564855547;947.1901524196712;998.1366118307757;1007.2697294461457;1066.7866426714688;15170.31733571992;15516.146611936227;15942.123183224801;16206.270717817726;16227.594310142342;929.990700093004;936.3866667332908;949.9810003799886;972.7422233108232;974.201155052864;8971.730848074569;9265.861012084824;9272.58273377686;9311.068893110634;9347.579358928864;426459;435297;491968;492511;495916;59826;61100.388996110356;66431;67123;67199.66400167967;1112.8942750438696;1140.4924808460155;1141.0301243942583;1143.2683082826998;1157.3113997171502;13686.863131368558;14693;14712.926435368183;14747;15033.872459565358;28887.277818054717;29512.557311640772;29581.85209073941;30329.24176895595;30419.695803041686;35187.12032199215;35498.11254718652;35714.46428303629;37167.814160929025;37321.626783731816;6824.7952561423235;6996.825079373057;6997.580145191305;7129.322714342129;7300.744473943599;9703.272254581076;9742.318037737574;10231.7442063949;10628.84056739165;10703.357798532114;8791.736247912573;8933.635706631549;9023.052579479061;9155.450672959643;9319;9274.118958698913;9320.440773553608;9506.47714375716;9507.334486585873;9648.855267171139;3292.1769557610596;4530.750808705553;6721.495887808336;9396.859047114162;9516.143547080796;7166.713331466892;7172.888202328648;7242.601656908921;7330.376917962033;7440.516366436164;8024.959875200587;9140.817183656423;9229.86155207659;9232.446053236827;9252.074792520963 11:30:12 INFO - PROCESS | 9716 | |3;mootools.html;1269.9301538415384;1417.2701058954638;1431.9284035798214;1558.2286768049817;1739.9478015659536;6831.180258368997;7407.370373518251;7570.810729731759;7580.848383032343;7582.848343033136;7395.963020184891;7578.280063393969;7580.583067931276;7585.317321441069;7589.734359297426;2310.584094862924;2339.883005849709;2342.367560758594;2343.4727186383097;2343.4727186383097;2245.4274160089235;2262.9434264143447;2324.895379707917;2325.337278875521;2340.929772106831;2298.161171172518;2301.1600765720477;2301.344116926676;2332.4285550040263;2336.894839732208;2274.283600665785;2287.645414960684;2287.8512896661664;2296.2422400607843;2300.424893776564;2253.346529506441;2259.5932732108213;2261.2537862505333;2262.6945362376196;2263.5020295534955;7647.929289899418;7677.731279405194;7683.538987660758;7688.34649054831;7690.423218258597;4706.7175969441605;4796.664233503621;4799.13615549201;4799.9760001199775;4824.541668541518;3018.864151113205;3032.438998785232;3101.7518598512065;3111.424386488507;3132.4048430798075;1000.974975625608;1039.4802598700649;1053.6417618009914;1063.1972860490357;1092.0662833277513;10518.737031574195;10519.474026298654;10528.052475277185;10533.999999999924;10545.630902918361;4386.122775444924;4440.666949978765;4509.977450112729;4516.345129956138;4521.886952826206;8981.865272020925;8997.955010224907;8998.460092394478;9017.413868098552;9020.548972551478;4934.827281045146;4953.7275449849885;4961.6030717543235;4974.129527332775;4981.975090124526;3949.881503554898;3981.8606348778235;4257.872263832028;4399.824007039682;4511.390962220058;3580.785152890835;3606.8016259105475;3609.8556057757396;3623.3840247158046;3643;1212.5089338817793;1222.1689251309017;1234.906832918195;1249.731307768834;1251.993740031294;1285.7878450055823;1298.2859427314938;1301.5249433956537;1363.6113707593486;1364.7952807078818;7289.635518224068;7325;7454.888176677354;7455;7457.776266712007;501.9222020586815;505.4313896866026;508.95419412253074;510.12258914666717;515.3918376315983;5527.53015993637;5619.1290349995;5637.23897273871;5643.238162848045;5646.2377579027125;8887.200151986352;9321.813563728687;9370.578323975438;9460.90539094614;9499.050094990584;9267.073292670813;9285.25717942563;9292.581833817494;9296.628134874665;9310.022447643036;6901.585904845625;7074.434045276365;7129.215786263507;7136.6431678415875;7202.71189152428;6810.965945170243;6842.965785171242;6864.519483636098;6885.552439091443;6904.27505111956;7826.099998500245;8182.263596276363;8195.42632015777;8214.439256541476;8329.416940814328;8430.704925327584;8619.224269815746;8756.036835948167;8769.649214031622;8770.473771573726;6013.128096426081;6164.969175154096;6648.434883034803;6656.966715166393;6969.407600354027;6859.622720750406;6900.6204658744255;6927.203371612329;6983.860322793617;6989.47578931572;6619.867602647823;6996.125484314461;6996.510244282851;7089.680964356513;7112.324329188718;6869.690863911036;6908.067410899464;6919.827004324932;7005.334493223136;7078.4691148163065;6737.461003119641;6885.518013739191;6962.7911162665205;7020.473464490082;7020.719171233093;6342.302346741762;6353.52348573868;6374.3306952771145;6382.585131966407;6389.840253993687;6323.905141422974;6396.42432181106;6418.646974416265;6419.197600299963;6439.967800160969 11:30:12 INFO - PROCESS | 9716 | |4;prototype.html;329.94390953537896;334.62856229585174;335.20221871944767;335.61739616836803;339.1115277971714;5759.5968282220265;5827.155062515938;5835.503982161516;5847.941520584793;5913.379095195007;5638.323401191852;5777.768889244436;5792.68140252286;5801.738921748521;5813.622114562561;4497.460304763425;4498.752568608726;4506.36910832482;4511.909761804762;4512.16524942885;4302.290122129861;4373.540778218289;4376.1247750449875;4376.584224498668;4490.8877278067985;4337.696361254713;4394.494633117184;4425.800838962238;4428;4449.0434556570335;4406;4417.403650507173;4421.204183247014;4424.823007079729;4432.623227025674;3928.52857657083;3934.8622798202214;3942.8225729842225;3945.3687410014545;3951.7036222283155;5417.458254174551;5532.861678458031;5533.335999680024;5533.418991005927;5546.168074788814;5222.3472065991755;5227.50338717825;5230.816921407771;5235.73821308933;5236.659617124875;6115.235595550556;6129.11127886454;6130.846728831771;6134.355892631299;6135.938640613582;4384.978075109604;4395.758233297167;4400.1419723153995;4401.757903315316;4401.779911004437;6153.138560601519;6214.430235919122;6307.779227727007;6428.132202152697;6443.613383197023;4380.299152135707;4432.667549933768;4470.932936005963;4479.328100784908;4494.52807455219;4566.337881007302;4566.383538222298;4570.840020599263;4577.198990176706;4578.107269082497;5212.635115541876;5224.05966925957;5227.764750586232;5229.267902493654;5234.2933703949475;5919.911201331984;6187.876242475125;6189.102580125857;6190.7523699051535;6200.56596038282;1557.672888693387;1581.818090919536;1591.2203020519862;1591.3157342342904;1592.1402442681053;1050.7845891592206;1062.0123285344548;1063.2291588598205;1076.8976947190004;1077.4128100185417;5861.267341582302;5869.706514674249;5871.236739223874;5872.207252021008;5872.911906321408;6360.268569114519;6364.77723279683;6368.29948705642;6374.904376434357;6375.521835862329;5861.238039054895;5877.38287479817;5881.235439392937;5885.558583106199;5889.911651325234;3308.4599478558193;3425.092350527112;3431.571053618298;3431.7254619630867;3431.742619303512;6635.6350400728425;6639.170103737033;6644.103046088813;6649.102371179829;6650.268470468342;6484.1246431731115;6484.481241500669;6488.902666460007;6489.772857949949;6497.220333560003;6480.995445705828;6630.1380820494;6650.068990341356;6653.168353955755;6658.5671931324305;6326.177596912372;6328.873422531616;6331.303556608677;6337;6423.068655044899;6330.177076979963;6340.778072767431;6342.048692696132;6342.587731797418;6353.269374021862;6387.712552935222;6479.222493300834;6508.088867558451;6525.3148419417175;6713.966430167818;4422.977885110554;4439;4451.777411129366;4463.330500424962;4464.308032255006;6326.367363263636;6340.207474065742;6393.904091438725;6394.776182833579;6398.424141827258;6057.273127224761;6189;6223.097650840693;6228.875422491434;6274.090256912813;6246.937530624818;6250;6252.124702541557;6257.68711564429;6260.593061450991;6160.907586386297;6218.968905155626;6219.564630475823;6220.942439785338;6232.345603711545;6162.445379915829;6213.565050446426;6278.811635650938;6281.277653069956;6290.591111577733;6132.08018797184;6137.570370074231;6143.232095988002;6144.170536977451;6144.201253836973 11:30:12 INFO - PROCESS | 9716 | |5;yui.html;794.301015106706;810.2100452059242;829.3075282139413;849.8300339932015;869.2741560796734;13354.933225333873;13466.932665336684;13520.256385898783;13520.391582378805;13525.391357388917;12768.744625107493;12788.104832661717;12823.935880320585;12826.551070712503;12842.486300547967;631.6368088349194;637.537785105797;776.4138075752799;777.5762209595756;796.3867821777228;781.4490783997268;784.5528049012037;784.9843003139933;786.996065019677;790.4190420041265;831.7380025292044;832.1262674192104;832.904216015157;838.0488145954355;838.9973981092567;654.5450911616429;662.4303099334569;786.8583654942137;807.7092246791151;810.0603300171772;607.1105829959087;608.2184393054947;608.8112685067643;610.38351265222;611.3702886027398;13266;13300.73398532024;13318.800217996737;13323.000774941918;13350.065495415227;12636.557720479832;12973.41619627119;13132.474701011948;13191.538296159588;13344.199348039148;10671.38476524643;10719.98160174782;10803.243772935897;10841.67474975752;10867.239293249475;3245.480723084319;3294.6870047345465;3304.4573480043864;3362.3947689415895;3368.6294507604143;47901.56295311146;48596.757016214695;48776.75611621919;48806.755966220655;48813.51186488125;10339.322747007269;10488.26582139243;10525;10731.892681073245;10749.516271767789;18990.525236869187;19151.904240478707;19178.13698383576;19222.80777192238;19223.711644325347;7158.570485770871;7287.672054757549;7336.339729424378;7502.949587057817;7620.390368770485;4336.956630433655;4346.891327716832;4367.62875155609;4397.978010109929;4454.465464144323;3035.8633861476287;3062.4793785056595;3071.4932036213772;3072.938541229163;3075.1082186165763;548.1257394455837;549.5603517186235;561.6854561445604;562.9971850140723;569.4846164221386;646.1374065622447;650.2241039564392;676.9120014398097;684.8287928017995;687.7145984416411;8621.31029517637;8834.55827208637;8871.689490867917;8931.591313847826;9036.096390361043;545.7625932719201;557.0474488624417;557.2671936403616;563.4112352591552;616.5992105131701;3736.5889752127246;3799.9240015200094;3896.376579747227;3937.724359294822;4143.81352839123;57894.710526447096;58843;58918.41081589215;58929.70535147383;58959.115613265836;48349.274760878616;49427.258591121164;49571;50199;50468;44814.27279946781;44909.55090449119;45319.54680453219;45838.54161458409;46003.30995035077;8780.165884240987;8812;8926.196642302224;9080.636774528944;9175.26597872182;32869.50695739518;33398;33440.66559334376;33453.33093338071;33997.32005359929;41430;42925.78537107295;44229;45267.547324526335;45294.094118116795;10542.789144217226;10830.163347505097;11184.552617895193;11221.831672525082;11272.098232141572;12599.030153060192;12643.304618246084;12743.74512509726;12970.783974114076;13104.148230364995;11118.777624447304;11484.368359740294;11510.712232193928;11572.074234061423;11941.282204913192;11201.551937922717;11322.490487927898;11412.144089193509;11430.48562814692;11801.468933897824;11699.203256818524;12054.216475929037;12110.636680899586;12142.271463712206;12161.939190303992;9755.878074021579;10177.694669159937;10259.333143345659;10572.89427105719;10900.073493752803;9664.855027174457;9762.197784747334;9939.503024848584;10397.688069357931;10507.527161277609 11:30:12 INFO - PROCESS | 9716 | __end_tp_report 11:30:12 INFO - PROCESS | 9716 | __start_cc_report 11:30:12 INFO - PROCESS | 9716 | _x_x_mozilla_cycle_collect,732 11:30:12 INFO - PROCESS | 9716 | __end_cc_report 11:30:12 INFO - PROCESS | 9716 | __startTimestamp1452627012815__endTimestamp 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | ------- Summary: start ------- 11:30:12 INFO - PROCESS | 9716 | Number of tests: 6 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#0] dojo.html Cycles:165 Average:5104.98 Median:5287.81 stddev:541.58 (10.2%) stddev-sans-first:520.76 11:30:12 INFO - PROCESS | 9716 | Values: 3136.6 3151.4 3177.8 3185.8 3209.3 4916.1 4981.8 5009.9 5087.1 5091.9 5111.0 5165.8 5166.5 5169.5 5191.8 4482.8 4639.4 4680.7 4686.1 4822.1 4559.4 4775.9 4786.7 4788.8 4864.9 4628.5 4679.2 4748.1 4748.4 4788.4 4680.5 4703.0 4704.0 4706.4 4740.3 4684.9 4694.8 4700.5 4721.3 4727.3 4852.6 4909.2 4991.3 5063.2 5086.3 5506.8 5514.2 5517.7 5531.9 5534.7 5276.6 5544.1 5545.8 5545.8 5553.9 5095.6 5186.1 5240.2 5261.6 5304.3 5622.2 5624.3 5630.2 5639.7 5667.9 5321.8 5401.5 5416.2 5419.1 5426.7 5425.5 5437.5 5446.7 5449.8 5495.9 5461.4 5489.1 5490.5 5492.3 5492.5 5271.3 5339.5 5402.2 5480.2 5550.1 4289.2 4329.2 4331.9 4336.0 4370.8 3804.4 3812.3 3816.4 3837.4 3863.3 4938.4 4988.6 5036.6 5057.3 5093.7 5464.3 5480.2 5505.7 5507.7 5549.1 5110.2 5146.8 5148.7 5159.3 5191.7 4599.4 4688.1 4746.2 4757.5 4761.8 5682.4 5689.4 5695.4 5745.2 5752.5 5478.5 5483.2 5484.6 5525.5 5529.4 5482.0 5572.2 5580.6 5697.8 5723.9 5416.9 5449.9 5453.9 5454.3 5457.3 5487.8 5521.3 5542.7 5546.6 5592.4 5180.0 5248.2 5311.5 5364.7 5393.8 5354.1 5359.5 5361.2 5361.4 5364.8 5038.5 5284.9 5285.5 5287.8 5337.2 5323.7 5361.5 5430.8 5501.9 5503.4 5285.7 5288.2 5290.8 5290.9 5293.0 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#1] ext.html Cycles:180 Average:41793.56 Median:20162.34 stddev:64972.30 (322.2%) stddev-sans-first:65080.62 11:30:12 INFO - PROCESS | 9716 | Values: 510.4 524.2 527.8 551.2 553.4 28153.2 35548.6 36230.3 36265.8 36277.5 39327.0 39734.4 39839.4 39903.6 39998.8 8413.9 8614.6 8620.2 8624.4 8627.1 8327.6 8439.7 8450.0 8459.2 8674.3 8649.6 8710.3 8720.4 8727.6 8737.5 8551.4 8612.6 8634.9 8635.5 8638.9 8531.9 8634.7 8647.6 8648.0 8649.7 38903.4 39113.4 39304.0 39379.8 39398.8 3258.5 3376.5 3470.9 3494.2 3581.0 27041.6 27292.2 27526.2 27683.2 27687.2 3775.2 3817.8 3902.5 3964.5 4036.1 194738.0 194773.0 195012.0 195344.0 196449.0 60154.6 61419.0 61590.5 61618.0 61762.0 65259.3 65526.3 65845.3 65893.3 65997.0 16209.0 16283.3 16317.8 16330.8 16330.8 13012.1 13047.5 13053.2 13082.9 13094.4 19403.2 20206.4 20334.3 20381.7 20384.7 7650.2 7754.5 7857.4 7882.6 7902.4 815.9 845.5 884.4 910.8 940.9 24695.1 25657.9 26072.2 26354.3 26497.3 1002.9 1015.9 1022.4 1029.5 1055.2 12929.4 15269.7 15812.2 15880.6 16219.4 324413.0 326859.0 328203.0 329648.0 331378.0 45419.0 46452.3 51956.2 52817.0 52828.0 1263.3 1293.8 1304.4 1309.1 1313.7 26038.7 26618.3 26626.2 26956.3 27045.0 39377.6 43579.3 44427.6 49815.8 50948.2 194760.0 197247.0 197763.0 197879.0 199112.0 32222.7 32577.5 32680.0 32696.5 32702.3 42688.4 43154.8 43490.0 43904.6 43986.3 35901.5 35935.3 36549.5 38651.4 40382.8 33748.5 38019.0 38126.4 40267.8 41191.8 33887.3 34065.7 38312.6 38641.0 38872.0 28574.9 30491.0 30636.5 30895.0 30985.0 20919.4 22681.4 22817.3 22875.0 22910.7 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#2] jquery.html Cycles:180 Average:24365.90 Median:5025.04 stddev:76656.16 (1525.5%) stddev-sans-first:76850.02 11:30:12 INFO - PROCESS | 9716 | Values: 367.7 368.6 368.9 369.2 370.9 13007.8 13154.4 16154.1 16702.7 17573.9 14649.6 15073.3 15383.2 15385.6 15833.1 5139.7 5300.7 5301.7 5302.8 5303.8 3974.0 4097.4 5199.7 5262.7 5604.1 5736.5 5758.4 5765.6 5781.8 5799.5 5054.8 5237.8 5238.1 5240.6 5245.2 4886.8 5020.7 5029.3 5041.2 5041.9 12087.3 12131.2 12275.4 12295.4 12822.1 2048.5 2061.3 2099.8 2141.6 2157.5 5603.3 5604.5 5613.9 8880.9 9399.3 1714.5 1817.2 1833.7 1861.7 1864.9 50412.2 51340.7 52581.5 52907.0 53730.0 10074.2 10111.5 10115.4 10125.2 10180.7 12946.0 13072.0 13152.6 13183.1 13197.6 7551.2 7582.5 7583.4 7662.4 7666.8 7504.0 7517.2 7543.9 7565.6 7599.8 4383.3 4393.6 4399.8 4414.3 4449.3 1719.7 1803.1 1820.1 1852.0 1876.6 939.1 947.2 998.1 1007.3 1066.8 15170.3 15516.1 15942.1 16206.3 16227.6 930.0 936.4 950.0 972.7 974.2 8971.7 9265.9 9272.6 9311.1 9347.6 426459.0 435297.0 491968.0 492511.0 495916.0 59826.0 61100.4 66431.0 67123.0 67199.7 1112.9 1140.5 1141.0 1143.3 1157.3 13686.9 14693.0 14712.9 14747.0 15033.9 28887.3 29512.6 29581.9 30329.2 30419.7 35187.1 35498.1 35714.5 37167.8 37321.6 6824.8 6996.8 6997.6 7129.3 7300.7 9703.3 9742.3 10231.7 10628.8 10703.4 8791.7 8933.6 9023.1 9155.5 9319.0 9274.1 9320.4 9506.5 9507.3 9648.9 3292.2 4530.8 6721.5 9396.9 9516.1 7166.7 7172.9 7242.6 7330.4 7440.5 8025.0 9140.8 9229.9 9232.4 9252.1 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#3] mootools.html Cycles:180 Average:5383.24 Median:6089.05 stddev:2773.99 (45.6%) stddev-sans-first:2764.54 11:30:12 INFO - PROCESS | 9716 | Values: 1269.9 1417.3 1431.9 1558.2 1739.9 6831.2 7407.4 7570.8 7580.8 7582.8 7396.0 7578.3 7580.6 7585.3 7589.7 2310.6 2339.9 2342.4 2343.5 2343.5 2245.4 2262.9 2324.9 2325.3 2340.9 2298.2 2301.2 2301.3 2332.4 2336.9 2274.3 2287.6 2287.9 2296.2 2300.4 2253.3 2259.6 2261.3 2262.7 2263.5 7647.9 7677.7 7683.5 7688.3 7690.4 4706.7 4796.7 4799.1 4800.0 4824.5 3018.9 3032.4 3101.8 3111.4 3132.4 1001.0 1039.5 1053.6 1063.2 1092.1 10518.7 10519.5 10528.1 10534.0 10545.6 4386.1 4440.7 4510.0 4516.3 4521.9 8981.9 8998.0 8998.5 9017.4 9020.5 4934.8 4953.7 4961.6 4974.1 4982.0 3949.9 3981.9 4257.9 4399.8 4511.4 3580.8 3606.8 3609.9 3623.4 3643.0 1212.5 1222.2 1234.9 1249.7 1252.0 1285.8 1298.3 1301.5 1363.6 1364.8 7289.6 7325.0 7454.9 7455.0 7457.8 501.9 505.4 509.0 510.1 515.4 5527.5 5619.1 5637.2 5643.2 5646.2 8887.2 9321.8 9370.6 9460.9 9499.1 9267.1 9285.3 9292.6 9296.6 9310.0 6901.6 7074.4 7129.2 7136.6 7202.7 6811.0 6843.0 6864.5 6885.6 6904.3 7826.1 8182.3 8195.4 8214.4 8329.4 8430.7 8619.2 8756.0 8769.6 8770.5 6013.1 6165.0 6648.4 6657.0 6969.4 6859.6 6900.6 6927.2 6983.9 6989.5 6619.9 6996.1 6996.5 7089.7 7112.3 6869.7 6908.1 6919.8 7005.3 7078.5 6737.5 6885.5 6962.8 7020.5 7020.7 6342.3 6353.5 6374.3 6382.6 6389.8 6323.9 6396.4 6418.6 6419.2 6440.0 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#4] prototype.html Cycles:180 Average:5182.08 Median:5865.49 stddev:1549.18 (26.4%) stddev-sans-first:1510.11 11:30:12 INFO - PROCESS | 9716 | Values: 329.9 334.6 335.2 335.6 339.1 5759.6 5827.2 5835.5 5847.9 5913.4 5638.3 5777.8 5792.7 5801.7 5813.6 4497.5 4498.8 4506.4 4511.9 4512.2 4302.3 4373.5 4376.1 4376.6 4490.9 4337.7 4394.5 4425.8 4428.0 4449.0 4406.0 4417.4 4421.2 4424.8 4432.6 3928.5 3934.9 3942.8 3945.4 3951.7 5417.5 5532.9 5533.3 5533.4 5546.2 5222.3 5227.5 5230.8 5235.7 5236.7 6115.2 6129.1 6130.8 6134.4 6135.9 4385.0 4395.8 4400.1 4401.8 4401.8 6153.1 6214.4 6307.8 6428.1 6443.6 4380.3 4432.7 4470.9 4479.3 4494.5 4566.3 4566.4 4570.8 4577.2 4578.1 5212.6 5224.1 5227.8 5229.3 5234.3 5919.9 6187.9 6189.1 6190.8 6200.6 1557.7 1581.8 1591.2 1591.3 1592.1 1050.8 1062.0 1063.2 1076.9 1077.4 5861.3 5869.7 5871.2 5872.2 5872.9 6360.3 6364.8 6368.3 6374.9 6375.5 5861.2 5877.4 5881.2 5885.6 5889.9 3308.5 3425.1 3431.6 3431.7 3431.7 6635.6 6639.2 6644.1 6649.1 6650.3 6484.1 6484.5 6488.9 6489.8 6497.2 6481.0 6630.1 6650.1 6653.2 6658.6 6326.2 6328.9 6331.3 6337.0 6423.1 6330.2 6340.8 6342.0 6342.6 6353.3 6387.7 6479.2 6508.1 6525.3 6714.0 4423.0 4439.0 4451.8 4463.3 4464.3 6326.4 6340.2 6393.9 6394.8 6398.4 6057.3 6189.0 6223.1 6228.9 6274.1 6246.9 6250.0 6252.1 6257.7 6260.6 6160.9 6219.0 6219.6 6220.9 6232.3 6162.4 6213.6 6278.8 6281.3 6290.6 6132.1 6137.6 6143.2 6144.2 6144.2 11:30:12 INFO - PROCESS | 9716 | 11:30:12 INFO - PROCESS | 9716 | [#5] yui.html Cycles:180 Average:13835.25 Median:4382.80 stddev:15802.82 (360.6%) stddev-sans-first:15816.81 11:30:12 INFO - PROCESS | 9716 | Values: 794.3 810.2 829.3 849.8 869.3 13354.9 13466.9 13520.3 13520.4 13525.4 12768.7 12788.1 12823.9 12826.6 12842.5 631.6 637.5 776.4 777.6 796.4 781.4 784.6 785.0 787.0 790.4 831.7 832.1 832.9 838.0 839.0 654.5 662.4 786.9 807.7 810.1 607.1 608.2 608.8 610.4 611.4 13266.0 13300.7 13318.8 13323.0 13350.1 12636.6 12973.4 13132.5 13191.5 13344.2 10671.4 10720.0 10803.2 10841.7 10867.2 3245.5 3294.7 3304.5 3362.4 3368.6 47901.6 48596.8 48776.8 48806.8 48813.5 10339.3 10488.3 10525.0 10731.9 10749.5 18990.5 19151.9 19178.1 19222.8 19223.7 7158.6 7287.7 7336.3 7502.9 7620.4 4337.0 4346.9 4367.6 4398.0 4454.5 3035.9 3062.5 3071.5 3072.9 3075.1 548.1 549.6 561.7 563.0 569.5 646.1 650.2 676.9 684.8 687.7 8621.3 8834.6 8871.7 8931.6 9036.1 545.8 557.0 557.3 563.4 616.6 3736.6 3799.9 3896.4 3937.7 4143.8 57894.7 58843.0 58918.4 58929.7 58959.1 48349.3 49427.3 49571.0 50199.0 50468.0 44814.3 44909.6 45319.5 45838.5 46003.3 8780.2 8812.0 8926.2 9080.6 9175.3 32869.5 33398.0 33440.7 33453.3 33997.3 41430.0 42925.8 44229.0 45267.5 45294.1 10542.8 10830.2 11184.6 11221.8 11272.1 12599.0 12643.3 12743.7 12970.8 13104.1 11118.8 11484.4 11510.7 11572.1 11941.3 11201.6 11322.5 11412.1 11430.5 11801.5 11699.2 12054.2 12110.6 12142.3 12161.9 9755.9 10177.7 10259.3 10572.9 10900.1 9664.9 9762.2 9939.5 10397.7 10507.5 11:30:12 INFO - PROCESS | 9716 | -------- Summary: end -------- 11:30:12 INFO - PROCESS | 9716 | 11:30:13 INFO - TEST-INFO | 9716: exit 0 11:30:13 INFO - TEST-OK | dromaeo_css | took 1112835ms 11:30:13 INFO - TEST-START | kraken 11:30:13 INFO - operating with platform_type : linux_ 11:30:13 INFO - Initialising browser for kraken test... 11:30:13 INFO - TEST-INFO | started process 9905 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmph4N6oH/profile http://localhost:59367/getInfo.html) 11:30:17 INFO - PROCESS | 9905 | __metrics Screen width/height:1600/1200 11:30:17 INFO - PROCESS | 9905 | colorDepth:24 11:30:17 INFO - PROCESS | 9905 | Browser inner width/height: 1024/697 11:30:17 INFO - PROCESS | 9905 | __metrics 11:30:17 INFO - PROCESS | 9905 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 11:30:18 INFO - PROCESS | 9905 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:30:18 INFO - TEST-INFO | 9905: exit 0 11:30:18 INFO - Browser initialized. 11:30:18 INFO - Running cycle 1/1 for kraken test... 11:30:18 INFO - TEST-INFO | started process 9990 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmph4N6oH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:30:30 INFO - PROCESS | 9990 | RSS: Main: 148504576 11:30:30 INFO - PROCESS | 9990 | 11:30:33 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59367/tests/kraken/driver.html?testName=audio-beat-detection) 11:30:33 INFO - PROCESS | 9990 | RSS: Main: 185466880 11:30:33 INFO - PROCESS | 9990 | 11:30:36 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59367/tests/kraken/driver.html?testName=audio-dft) 11:30:37 INFO - PROCESS | 9990 | RSS: Main: 175157248 11:30:37 INFO - PROCESS | 9990 | 11:30:39 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59367/tests/kraken/driver.html?testName=audio-fft) 11:30:40 INFO - PROCESS | 9990 | RSS: Main: 158769152 11:30:40 INFO - PROCESS | 9990 | 11:30:42 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59367/tests/kraken/driver.html?testName=audio-oscillator) 11:30:42 INFO - PROCESS | 9990 | RSS: Main: 162525184 11:30:42 INFO - PROCESS | 9990 | 11:30:45 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:30:45 INFO - PROCESS | 9990 | RSS: Main: 163479552 11:30:45 INFO - PROCESS | 9990 | 11:30:48 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59367/tests/kraken/driver.html?testName=imaging-darkroom) 11:30:49 INFO - PROCESS | 9990 | RSS: Main: 165453824 11:30:49 INFO - PROCESS | 9990 | 11:30:52 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59367/tests/kraken/driver.html?testName=imaging-desaturate) 11:30:53 INFO - PROCESS | 9990 | RSS: Main: 164245504 11:30:53 INFO - PROCESS | 9990 | 11:30:55 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59367/tests/kraken/driver.html?testName=json-parse-financial) 11:30:56 INFO - PROCESS | 9990 | RSS: Main: 164495360 11:30:56 INFO - PROCESS | 9990 | 11:30:58 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:30:58 INFO - PROCESS | 9990 | RSS: Main: 173752320 11:30:58 INFO - PROCESS | 9990 | 11:31:00 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:31:01 INFO - PROCESS | 9990 | RSS: Main: 172879872 11:31:01 INFO - PROCESS | 9990 | 11:31:03 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:31:03 INFO - PROCESS | 9990 | RSS: Main: 180977664 11:31:03 INFO - PROCESS | 9990 | 11:31:06 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:31:06 INFO - PROCESS | 9990 | RSS: Main: 186126336 11:31:06 INFO - PROCESS | 9990 | 11:31:09 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:31:09 INFO - PROCESS | 9990 | RSS: Main: 183627776 11:31:09 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | Cycle 1(1): loaded http://localhost:59367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59367/tests/kraken/driver.html?testName=ai-astar) 11:31:11 INFO - PROCESS | 9990 | RSS: Main: 242012160 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | __start_tp_report 11:31:11 INFO - PROCESS | 9990 | _x_x_mozilla_page_load 11:31:11 INFO - PROCESS | 9990 | _x_x_mozilla_page_load_details 11:31:11 INFO - PROCESS | 9990 | |i|pagename|runs| 11:31:11 INFO - PROCESS | 9990 | |0;ai-astar;110;110;106;109;110;111;100;111;110;111 11:31:11 INFO - PROCESS | 9990 | |1;audio-beat-detection;163;126;155;168;134;154;150;125;176;128 11:31:11 INFO - PROCESS | 9990 | |2;audio-dft;160;162;160;159;159;160;160;164;161;162 11:31:11 INFO - PROCESS | 9990 | |3;audio-fft;75;73;85;94;74;71;72;104;92;73 11:31:11 INFO - PROCESS | 9990 | |4;audio-oscillator;96;106;139;97;96;96;141;99;96;142 11:31:11 INFO - PROCESS | 9990 | |5;imaging-gaussian-blur;109;110;109;109;109;109;110;109;110;109 11:31:11 INFO - PROCESS | 9990 | |6;imaging-darkroom;145;145;146;146;145;146;145;145;145;146 11:31:11 INFO - PROCESS | 9990 | |7;imaging-desaturate;96;96;96;95;96;96;96;96;96;96 11:31:11 INFO - PROCESS | 9990 | |8;json-parse-financial;57;56;57;56;55;56;57;65;52;53 11:31:11 INFO - PROCESS | 9990 | |9;json-stringify-tinderbox;67;67;67;67;67;67;68;67;67;67 11:31:11 INFO - PROCESS | 9990 | |10;stanford-crypto-aes;84;87;87;85;86;85;95;86;95;84 11:31:11 INFO - PROCESS | 9990 | |11;stanford-crypto-ccm;117;122;123;115;124;120;119;123;125;122 11:31:11 INFO - PROCESS | 9990 | |12;stanford-crypto-pbkdf2;139;139;142;148;153;136;147;145;150;139 11:31:11 INFO - PROCESS | 9990 | |13;stanford-crypto-sha256-iterative;66;68;65;67;66;66;67;66;66;67 11:31:11 INFO - PROCESS | 9990 | __end_tp_report 11:31:11 INFO - PROCESS | 9990 | __start_cc_report 11:31:11 INFO - PROCESS | 9990 | _x_x_mozilla_cycle_collect,955 11:31:11 INFO - PROCESS | 9990 | __end_cc_report 11:31:11 INFO - PROCESS | 9990 | __startTimestamp1452627071706__endTimestamp 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | ------- Summary: start ------- 11:31:11 INFO - PROCESS | 9990 | Number of tests: 14 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:3.43 (3.1%) stddev-sans-first:3.61 11:31:11 INFO - PROCESS | 9990 | Values: 110.0 110.0 106.0 109.0 110.0 111.0 100.0 111.0 110.0 111.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:154.50 stddev:18.58 (12.0%) stddev-sans-first:18.89 11:31:11 INFO - PROCESS | 9990 | Values: 163.0 126.0 155.0 168.0 134.0 154.0 150.0 125.0 176.0 128.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.57 (1.0%) stddev-sans-first:1.64 11:31:11 INFO - PROCESS | 9990 | Values: 160.0 162.0 160.0 159.0 159.0 160.0 160.0 164.0 161.0 162.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#3] audio-fft Cycles:10 Average:81.30 Median:74.50 stddev:11.68 (15.7%) stddev-sans-first:12.17 11:31:11 INFO - PROCESS | 9990 | Values: 75.0 73.0 85.0 94.0 74.0 71.0 72.0 104.0 92.0 73.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#4] audio-oscillator Cycles:10 Average:110.80 Median:96.00 stddev:20.84 (21.7%) stddev-sans-first:21.41 11:31:11 INFO - PROCESS | 9990 | Values: 96.0 106.0 139.0 97.0 96.0 96.0 141.0 99.0 96.0 142.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 11:31:11 INFO - PROCESS | 9990 | Values: 109.0 110.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 109.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 11:31:11 INFO - PROCESS | 9990 | Values: 145.0 145.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 146.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 11:31:11 INFO - PROCESS | 9990 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:3.47 (6.1%) stddev-sans-first:3.67 11:31:11 INFO - PROCESS | 9990 | Values: 57.0 56.0 57.0 56.0 55.0 56.0 57.0 65.0 52.0 53.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 11:31:11 INFO - PROCESS | 9990 | Values: 67.0 67.0 67.0 67.0 67.0 67.0 68.0 67.0 67.0 67.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:86.50 stddev:4.14 (4.8%) stddev-sans-first:4.21 11:31:11 INFO - PROCESS | 9990 | Values: 84.0 87.0 87.0 85.0 86.0 85.0 95.0 86.0 95.0 84.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:122.50 stddev:3.20 (2.6%) stddev-sans-first:3.05 11:31:11 INFO - PROCESS | 9990 | Values: 117.0 122.0 123.0 115.0 124.0 120.0 119.0 123.0 125.0 122.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.80 Median:146.00 stddev:5.63 (3.9%) stddev-sans-first:5.70 11:31:11 INFO - PROCESS | 9990 | Values: 139.0 139.0 142.0 148.0 153.0 136.0 147.0 145.0 150.0 139.0 11:31:11 INFO - PROCESS | 9990 | 11:31:11 INFO - PROCESS | 9990 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 11:31:11 INFO - PROCESS | 9990 | Values: 66.0 68.0 65.0 67.0 66.0 66.0 67.0 66.0 66.0 67.0 11:31:11 INFO - PROCESS | 9990 | -------- Summary: end -------- 11:31:11 INFO - PROCESS | 9990 | 11:31:12 INFO - TEST-INFO | 9990: exit 0 11:31:12 INFO - TEST-OK | kraken | took 59156ms 11:31:12 INFO - TEST-START | v8_7 11:31:12 INFO - operating with platform_type : linux_ 11:31:12 INFO - Initialising browser for v8_7 test... 11:31:12 INFO - TEST-INFO | started process 10240 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUrlwat/profile http://localhost:59367/getInfo.html) 11:31:16 INFO - PROCESS | 10240 | __metrics Screen width/height:1600/1200 11:31:16 INFO - PROCESS | 10240 | colorDepth:24 11:31:16 INFO - PROCESS | 10240 | Browser inner width/height: 1024/697 11:31:16 INFO - PROCESS | 10240 | __metrics 11:31:16 INFO - PROCESS | 10240 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 11:31:17 INFO - PROCESS | 10240 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:31:18 INFO - TEST-INFO | 10240: exit 0 11:31:18 INFO - Browser initialized. 11:31:18 INFO - Running cycle 1/1 for v8_7 test... 11:31:18 INFO - TEST-INFO | started process 10320 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUrlwat/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:30 INFO - PROCESS | 10320 | RSS: Main: 148586496 11:31:30 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | Cycle 1(1): loaded http://localhost:59367/tests/v8_7/run.html (next: http://localhost:59367/tests/v8_7/run.html) 11:31:52 INFO - PROCESS | 10320 | RSS: Main: 582643712 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | __start_tp_report 11:31:52 INFO - PROCESS | 10320 | _x_x_mozilla_page_load 11:31:52 INFO - PROCESS | 10320 | _x_x_mozilla_page_load_details 11:31:52 INFO - PROCESS | 10320 | |i|pagename|runs| 11:31:52 INFO - PROCESS | 10320 | |0;Richards;167.11229946524065 11:31:52 INFO - PROCESS | 10320 | |1;DeltaBlue;193.4984520123839 11:31:52 INFO - PROCESS | 10320 | |2;Encrypt;284.4141069397042 11:31:52 INFO - PROCESS | 10320 | |3;Decrypt;5175.257731958763 11:31:52 INFO - PROCESS | 10320 | |4;RayTrace;1972.3865877712033 11:31:52 INFO - PROCESS | 10320 | |5;Earley;780.64012490242 11:31:52 INFO - PROCESS | 10320 | |6;Boyer;12219.512195121952 11:31:52 INFO - PROCESS | 10320 | |7;RegExp;42208.333333333336 11:31:52 INFO - PROCESS | 10320 | |8;Splay;619.1950464396285 11:31:52 INFO - PROCESS | 10320 | |9;NavierStokes;4840.579710144928 11:31:52 INFO - PROCESS | 10320 | __end_tp_report 11:31:52 INFO - PROCESS | 10320 | __startTimestamp1452627112816__endTimestamp 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | ------- Summary: start ------- 11:31:52 INFO - PROCESS | 10320 | Number of tests: 10 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#0] Richards Cycles:1 Average:167.11 Median:167.11 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 167.1 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#1] DeltaBlue Cycles:1 Average:193.50 Median:193.50 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 193.5 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#2] Encrypt Cycles:1 Average:284.41 Median:284.41 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 284.4 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 5175.3 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#4] RayTrace Cycles:1 Average:1972.39 Median:1972.39 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 1972.4 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#5] Earley Cycles:1 Average:780.64 Median:780.64 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 780.6 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 12219.5 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#7] RegExp Cycles:1 Average:42208.33 Median:42208.33 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 42208.3 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#8] Splay Cycles:1 Average:619.20 Median:619.20 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 619.2 11:31:52 INFO - PROCESS | 10320 | 11:31:52 INFO - PROCESS | 10320 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 11:31:52 INFO - PROCESS | 10320 | Values: 4840.6 11:31:52 INFO - PROCESS | 10320 | -------- Summary: end -------- 11:31:52 INFO - PROCESS | 10320 | 11:31:53 INFO - TEST-INFO | 10320: exit 0 11:31:53 INFO - TEST-OK | v8_7 | took 41008ms 11:31:53 INFO - SUITE-END | took 1213s 11:31:53 INFO - Completed test suite (00:20:13) 11:31:53 INFO - Working with test: dromaeo_css 11:31:53 INFO - Generating results file: dromaeo_css 11:31:53 INFO - Working with test: kraken 11:31:53 INFO - Generating results file: kraken 11:31:53 INFO - javascript benchmark 11:31:53 INFO - Working with test: v8_7 11:31:53 INFO - Generating results file: v8_7 11:31:53 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:31:53 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 11:31:53 INFO - process_Request line: dromaeo_css 6690.41 graph.html#tests=[[72,201,35]] 11:31:53 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:31:53 INFO - process_Request line: kraken 1502.20 graph.html#tests=[[232,201,35]] 11:31:53 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 11:31:53 INFO - process_Request line: v8_7 18819.91 graph.html#tests=[[230,201,35]] 11:31:53 INFO - RETURN: dromaeo_css: 6690.41 11:31:53 INFO - RETURN: kraken: 1502.20 11:31:53 INFO - RETURN: v8_7: 18819.91 11:31:53 INFO - javascript benchmark 11:31:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3136.5608814765933, 3151.401233765583, 3177.793443426177, 3185.761067919905, 3209.309998350355, 4916.115099282128, 4981.775820088095, 5009.899802003958, 5087.135187018206, 5091.949080509198, 5111.0, 5165.767540460679, 5166.483351664831, 5169.534741873231, 5191.792328306863, 4482.798274077658, 4639.350490931272, 4680.743819214175, 4686.133065382898, 4822.059698358804, 4559.430071241095, 4775.880602984918, 4786.688865223767, 4788.760561971905, 4864.927026094594, 4628.5140060293525, 4679.181143299926, 4748.121597504472, 4748.38271024768, 4788.449328327252, 4680.485146633868, 4702.95297047032, 4704.035672687125, 4706.388169537972, 4740.336352910595, 4684.929726054112, 4694.835680751157, 4700.506446823103, 4721.26820342844, 4727.290906364038, 4852.611791056743, 4909.189983652729, 4991.251312303148, 5063.240513922904, 5086.262491938685, 5506.752196151183, 5514.200440936082, 5517.69652669103, 5531.889362212773, 5534.695591742453, 5276.630635855453, 5544.112941929272, 5545.750441230114, 5545.77816887328, 5553.916691249635, 5095.617828662828, 5186.0665080285535, 5240.2401651760665, 5261.579073674096, 5304.310439642822, 5622.212890195376, 5624.268845050199, 5630.211770352154, 5639.661620302795, 5667.914981275284, 5321.840344789663, 5401.459854014567, 5416.160495123262, 5419.078756611386, 5426.70153141581, 5425.538829199562, 5437.4562543745305, 5446.700431476228, 5449.809256675997, 5495.945040549544, 5461.42655021225, 5489.1217405214975, 5490.450954904478, 5492.286002819608, 5492.533134683516, 5271.314729085195, 5339.546138578186, 5402.216678581583, 5480.150576660624, 5550.139728342114, 4289.206496798102, 4329.242382583036, 4331.913361732811, 4336.0, 4370.847020354272, 3804.410316400962, 3812.3137835189377, 3816.3702989007056, 3837.366834472281, 3863.30460517104, 4938.4320803107385, 4988.575971042429, 5036.622253331015, 5057.317262169634, 5093.694378337312, 5464.316960379952, 5480.177973303957, 5505.697186654692, 5507.669539827623, 5549.084401073858, 5110.233464980209, 5146.794128234904, 5148.6910785353, 5159.329287192641, 5191.688498690015, 4599.402077729874, 4688.132695451352, 4746.169420351424, 4757.452892917289, 4761.761911904391, 5682.374938756733, 5689.374168841424, 5695.401982791747, 5745.195672605839, 5752.453516915886, 5478.506934375925, 5483.232347471437, 5484.61607687458, 5525.502704756591, 5529.391766905717, 5481.972590137024, 5572.192032155233, 5580.5535557156145, 5697.800576979786, 5723.8569035774435, 5416.891662166656, 5449.945500544944, 5453.9092181563055, 5454.318210223722, 5457.345118585795, 5487.847552014122, 5521.254630624814, 5542.72286385671, 5546.639468434701, 5592.440755924537, 5179.9640071985, 5248.186531087687, 5311.495407936394, 5364.704941228114, 5393.83818485446, 5354.11657076578, 5359.517643412111, 5361.169018802092, 5361.410244873139, 5364.8122315718765, 5038.546530812245, 5284.894302113859, 5285.524302812765, 5287.814926477555, 5337.226102215234, 5323.7071961040965, 5361.463853614608, 5430.755615997368, 5501.944980550144, 5503.394626591152, 5285.68285902847, 5288.153895376721, 5290.841274761763, 5290.920636190383, 5292.967871265067], "unit": "score", "name": "dojo.html", "value": 5071.9670140418075}, {"lowerIsBetter": false, "replicates": [510.38498609175946, 524.2215310264257, 527.8126265175862, 551.2062629813068, 553.3802141601406, 28153.155405337846, 35548.64451355486, 36230.27539449209, 36265.81867090661, 36277.455838162416, 39327.0, 39734.40398394026, 39839.40240896382, 39903.60096399035, 39998.800006000005, 8413.915860841389, 8614.612342444605, 8620.224179823814, 8624.396292259544, 8627.137286271385, 8327.583620818934, 8439.70461033864, 8449.95775021124, 8459.196376344269, 8674.262687671555, 8649.567521623925, 8710.303175745925, 8720.43317184384, 8727.563621818916, 8737.519436431026, 8551.358648101386, 8612.5693715314, 8634.913650863473, 8635.525046122491, 8638.913610863905, 8531.872021919677, 8634.740957771213, 8647.567621618893, 8648.048714641385, 8649.740507784776, 38903.41644875329, 39113.41329880025, 39304.0, 39379.8031009846, 39398.80300598507, 3258.4949332853444, 3376.4935259710996, 3470.9305813883834, 3494.178867966026, 3580.997320750194, 27041.594376084373, 27292.181234562995, 27526.17421477359, 27683.169504914884, 27687.169384918485, 3775.226078653897, 3817.7518461300206, 3902.512185976753, 3964.5002921684095, 4036.1120553478186, 194738.02630986896, 194773.0, 195012.0, 195344.0, 196449.0, 60154.586087252355, 61419.0, 61590.53299403588, 61618.0, 61762.0, 65259.34740652628, 65526.344736552026, 65845.34154658392, 65893.34106658968, 65997.0, 16209.0, 16283.267252973645, 16317.836821631632, 16330.836691633169, 16330.836691633169, 13012.089153759336, 13047.478100875858, 13053.216806991612, 13082.934585327013, 13094.410751516205, 19403.223871045284, 20206.393808185778, 20334.288299909433, 20381.694274585894, 20384.694229586567, 7650.158482566913, 7754.534727916343, 7857.449978501564, 7882.645280962371, 7902.407319450949, 815.9020917489939, 845.5476320168681, 884.4030279561399, 910.7950711089952, 940.9012053734397, 24695.135670251457, 25657.871710641328, 26072.217833465027, 26354.341141471617, 26497.33756656099, 1002.8595996560488, 1015.94920253987, 1022.4121130350078, 1029.4749677664445, 1055.2033214922685, 12929.418176182095, 15269.694606108038, 15812.206947863753, 15880.60298492547, 16219.432319868984, 324413.0, 326859.0, 328203.0, 329648.0, 331378.0, 45419.0, 46452.30321545179, 51956.22065669018, 52817.0, 52828.0, 1263.3304348695206, 1293.8253335799734, 1304.373900527752, 1309.0771006440436, 1313.6650154210615, 26038.739612603633, 26618.334541636614, 26626.201213963614, 26956.32609184786, 27045.0, 39377.60622393739, 43579.34630980601, 44427.55572444234, 49815.75092124516, 50948.23577646264, 194760.0, 197247.0, 197763.0, 197879.0, 199112.0, 32222.677773221967, 32577.511337329484, 32680.0, 32696.50955235626, 32702.34595308128, 42688.35967460553, 43154.78422607992, 43490.0, 43904.56095439132, 43986.34020489759, 35901.461478077326, 35935.28129437449, 36549.45175822418, 38651.42022869603, 40382.79808600938, 33748.49377259294, 38019.0, 38126.428103579026, 40267.798661006505, 41191.7940410296, 33887.322253555285, 34065.659343406245, 38312.616873830906, 38641.0, 38872.0, 28574.85712571507, 30491.0, 30636.540451893685, 30895.0, 30985.0, 20919.423543930694, 22681.432964176027, 22817.31548053561, 22875.0, 22910.65634015458], "unit": "score", "name": "ext.html", "value": 17875.015838054}, {"lowerIsBetter": false, "replicates": [367.7168580193251, 368.5945459994005, 368.8967089215017, 369.2062066556902, 370.8961490782581, 13007.804882926765, 13154.408051637676, 16154.111523866197, 16702.749458758128, 17573.912130439345, 14649.560513184595, 15073.321700523475, 15383.230838458088, 15385.615359616015, 15833.12917789521, 5139.691618502892, 5300.681959082448, 5301.734913254341, 5302.761375738101, 5303.814366497173, 3973.9801300993454, 4097.44684467596, 5199.68801871887, 5262.736863156846, 5604.075327570967, 5736.512396446286, 5758.42415758425, 5765.596408251424, 5781.768729250846, 5799.4780469757925, 5054.848354549369, 5237.7904883804795, 5238.109521381374, 5240.633155679104, 5245.186996015624, 4886.804527818918, 5020.748962551857, 5029.321041659366, 5041.1682072458, 5041.924371134436, 12087.335196564185, 12131.150819442642, 12275.386230688518, 12295.4467048983, 12822.102452828305, 2048.4878780304925, 2061.288855344904, 2099.769025407204, 2141.635921893282, 2157.546915147821, 5603.271574695264, 5604.495595396393, 5613.915791263134, 8880.889888763904, 9399.295052870993, 1714.4770844892303, 1817.1913498493175, 1833.6791061564172, 1861.7393564900924, 1864.906754662275, 50412.23557175481, 51340.74329628328, 52581.47418525804, 52907.0, 53729.99999999961, 10074.194064474825, 10111.494425278854, 10115.443650599287, 10125.189984801198, 10180.745481362877, 12946.0, 13072.0, 13152.605421837168, 13183.14309569875, 13197.604071877859, 7551.207123251979, 7582.54504729718, 7583.431242656823, 7662.387009039264, 7666.769996900102, 7504.0, 7517.210692877278, 7543.886841697379, 7565.583892885944, 7599.784034554445, 4383.276759334674, 4393.648508119343, 4399.802008909607, 4414.337849322627, 4449.2881139018245, 1719.6990526658035, 1803.1164729282557, 1820.1172431370774, 1851.9537011574819, 1876.6127140511235, 939.1031564855547, 947.1901524196712, 998.1366118307757, 1007.2697294461457, 1066.7866426714688, 15170.31733571992, 15516.146611936227, 15942.123183224801, 16206.270717817726, 16227.594310142342, 929.990700093004, 936.3866667332908, 949.9810003799886, 972.7422233108232, 974.201155052864, 8971.730848074569, 9265.861012084824, 9272.58273377686, 9311.068893110634, 9347.579358928864, 426459.0, 435297.0, 491968.0, 492511.0, 495916.0, 59826.0, 61100.388996110356, 66431.0, 67123.0, 67199.66400167967, 1112.8942750438696, 1140.4924808460155, 1141.0301243942583, 1143.2683082826998, 1157.3113997171502, 13686.863131368558, 14693.0, 14712.926435368183, 14747.0, 15033.872459565358, 28887.277818054717, 29512.557311640772, 29581.85209073941, 30329.24176895595, 30419.695803041686, 35187.12032199215, 35498.11254718652, 35714.46428303629, 37167.814160929025, 37321.626783731816, 6824.7952561423235, 6996.825079373057, 6997.580145191305, 7129.322714342129, 7300.744473943599, 9703.272254581076, 9742.318037737574, 10231.7442063949, 10628.84056739165, 10703.357798532114, 8791.736247912573, 8933.635706631549, 9023.052579479061, 9155.450672959643, 9319.0, 9274.118958698913, 9320.440773553608, 9506.47714375716, 9507.334486585873, 9648.855267171139, 3292.1769557610596, 4530.750808705553, 6721.495887808336, 9396.859047114162, 9516.143547080796, 7166.713331466892, 7172.888202328648, 7242.601656908921, 7330.376917962033, 7440.516366436164, 8024.959875200587, 9140.817183656423, 9229.86155207659, 9232.446053236827, 9252.074792520963], "unit": "score", "name": "jquery.html", "value": 7664.880183368054}, {"lowerIsBetter": false, "replicates": [1269.9301538415384, 1417.2701058954638, 1431.9284035798214, 1558.2286768049817, 1739.9478015659536, 6831.180258368997, 7407.370373518251, 7570.810729731759, 7580.848383032343, 7582.848343033136, 7395.963020184891, 7578.280063393969, 7580.583067931276, 7585.317321441069, 7589.734359297426, 2310.584094862924, 2339.883005849709, 2342.367560758594, 2343.4727186383097, 2343.4727186383097, 2245.4274160089235, 2262.9434264143447, 2324.895379707917, 2325.337278875521, 2340.929772106831, 2298.161171172518, 2301.1600765720477, 2301.344116926676, 2332.4285550040263, 2336.894839732208, 2274.283600665785, 2287.645414960684, 2287.8512896661664, 2296.2422400607843, 2300.424893776564, 2253.346529506441, 2259.5932732108213, 2261.2537862505333, 2262.6945362376196, 2263.5020295534955, 7647.929289899418, 7677.731279405194, 7683.538987660758, 7688.34649054831, 7690.423218258597, 4706.7175969441605, 4796.664233503621, 4799.13615549201, 4799.9760001199775, 4824.541668541518, 3018.864151113205, 3032.438998785232, 3101.7518598512065, 3111.424386488507, 3132.4048430798075, 1000.974975625608, 1039.4802598700649, 1053.6417618009914, 1063.1972860490357, 1092.0662833277513, 10518.737031574195, 10519.474026298654, 10528.052475277185, 10533.999999999924, 10545.630902918361, 4386.122775444924, 4440.666949978765, 4509.977450112729, 4516.345129956138, 4521.886952826206, 8981.865272020925, 8997.955010224907, 8998.460092394478, 9017.413868098552, 9020.548972551478, 4934.827281045146, 4953.7275449849885, 4961.6030717543235, 4974.129527332775, 4981.975090124526, 3949.881503554898, 3981.8606348778235, 4257.872263832028, 4399.824007039682, 4511.390962220058, 3580.785152890835, 3606.8016259105475, 3609.8556057757396, 3623.3840247158046, 3643.0, 1212.5089338817793, 1222.1689251309017, 1234.906832918195, 1249.731307768834, 1251.993740031294, 1285.7878450055823, 1298.2859427314938, 1301.5249433956537, 1363.6113707593486, 1364.7952807078818, 7289.635518224068, 7325.0, 7454.888176677354, 7455.0, 7457.776266712007, 501.9222020586815, 505.4313896866026, 508.95419412253074, 510.12258914666717, 515.3918376315983, 5527.53015993637, 5619.1290349995, 5637.23897273871, 5643.238162848045, 5646.2377579027125, 8887.200151986352, 9321.813563728687, 9370.578323975438, 9460.90539094614, 9499.050094990584, 9267.073292670813, 9285.25717942563, 9292.581833817494, 9296.628134874665, 9310.022447643036, 6901.585904845625, 7074.434045276365, 7129.215786263507, 7136.6431678415875, 7202.71189152428, 6810.965945170243, 6842.965785171242, 6864.519483636098, 6885.552439091443, 6904.27505111956, 7826.099998500245, 8182.263596276363, 8195.42632015777, 8214.439256541476, 8329.416940814328, 8430.704925327584, 8619.224269815746, 8756.036835948167, 8769.649214031622, 8770.473771573726, 6013.128096426081, 6164.969175154096, 6648.434883034803, 6656.966715166393, 6969.407600354027, 6859.622720750406, 6900.6204658744255, 6927.203371612329, 6983.860322793617, 6989.47578931572, 6619.867602647823, 6996.125484314461, 6996.510244282851, 7089.680964356513, 7112.324329188718, 6869.690863911036, 6908.067410899464, 6919.827004324932, 7005.334493223136, 7078.4691148163065, 6737.461003119641, 6885.518013739191, 6962.7911162665205, 7020.473464490082, 7020.719171233093, 6342.302346741762, 6353.52348573868, 6374.3306952771145, 6382.585131966407, 6389.840253993687, 6323.905141422974, 6396.42432181106, 6418.646974416265, 6419.197600299963, 6439.967800160969], "unit": "score", "name": "mootools.html", "value": 4384.234395798617}, {"lowerIsBetter": false, "replicates": [329.94390953537896, 334.62856229585174, 335.20221871944767, 335.61739616836803, 339.1115277971714, 5759.5968282220265, 5827.155062515938, 5835.503982161516, 5847.941520584793, 5913.379095195007, 5638.323401191852, 5777.768889244436, 5792.68140252286, 5801.738921748521, 5813.622114562561, 4497.460304763425, 4498.752568608726, 4506.36910832482, 4511.909761804762, 4512.16524942885, 4302.290122129861, 4373.540778218289, 4376.1247750449875, 4376.584224498668, 4490.8877278067985, 4337.696361254713, 4394.494633117184, 4425.800838962238, 4428.0, 4449.0434556570335, 4406.0, 4417.403650507173, 4421.204183247014, 4424.823007079729, 4432.623227025674, 3928.52857657083, 3934.8622798202214, 3942.8225729842225, 3945.3687410014545, 3951.7036222283155, 5417.458254174551, 5532.861678458031, 5533.335999680024, 5533.418991005927, 5546.168074788814, 5222.3472065991755, 5227.50338717825, 5230.816921407771, 5235.73821308933, 5236.659617124875, 6115.235595550556, 6129.11127886454, 6130.846728831771, 6134.355892631299, 6135.938640613582, 4384.978075109604, 4395.758233297167, 4400.1419723153995, 4401.757903315316, 4401.779911004437, 6153.138560601519, 6214.430235919122, 6307.779227727007, 6428.132202152697, 6443.613383197023, 4380.299152135707, 4432.667549933768, 4470.932936005963, 4479.328100784908, 4494.52807455219, 4566.337881007302, 4566.383538222298, 4570.840020599263, 4577.198990176706, 4578.107269082497, 5212.635115541876, 5224.05966925957, 5227.764750586232, 5229.267902493654, 5234.2933703949475, 5919.911201331984, 6187.876242475125, 6189.102580125857, 6190.7523699051535, 6200.56596038282, 1557.672888693387, 1581.818090919536, 1591.2203020519862, 1591.3157342342904, 1592.1402442681053, 1050.7845891592206, 1062.0123285344548, 1063.2291588598205, 1076.8976947190004, 1077.4128100185417, 5861.267341582302, 5869.706514674249, 5871.236739223874, 5872.207252021008, 5872.911906321408, 6360.268569114519, 6364.77723279683, 6368.29948705642, 6374.904376434357, 6375.521835862329, 5861.238039054895, 5877.38287479817, 5881.235439392937, 5885.558583106199, 5889.911651325234, 3308.4599478558193, 3425.092350527112, 3431.571053618298, 3431.7254619630867, 3431.742619303512, 6635.6350400728425, 6639.170103737033, 6644.103046088813, 6649.102371179829, 6650.268470468342, 6484.1246431731115, 6484.481241500669, 6488.902666460007, 6489.772857949949, 6497.220333560003, 6480.995445705828, 6630.1380820494, 6650.068990341356, 6653.168353955755, 6658.5671931324305, 6326.177596912372, 6328.873422531616, 6331.303556608677, 6337.0, 6423.068655044899, 6330.177076979963, 6340.778072767431, 6342.048692696132, 6342.587731797418, 6353.269374021862, 6387.712552935222, 6479.222493300834, 6508.088867558451, 6525.3148419417175, 6713.966430167818, 4422.977885110554, 4439.0, 4451.777411129366, 4463.330500424962, 4464.308032255006, 6326.367363263636, 6340.207474065742, 6393.904091438725, 6394.776182833579, 6398.424141827258, 6057.273127224761, 6189.0, 6223.097650840693, 6228.875422491434, 6274.090256912813, 6246.937530624818, 6250.0, 6252.124702541557, 6257.68711564429, 6260.593061450991, 6160.907586386297, 6218.968905155626, 6219.564630475823, 6220.942439785338, 6232.345603711545, 6162.445379915829, 6213.565050446426, 6278.811635650938, 6281.277653069956, 6290.591111577733, 6132.08018797184, 6137.570370074231, 6143.232095988002, 6144.170536977451, 6144.201253836973], "unit": "score", "name": "prototype.html", "value": 4681.04113358551}, {"lowerIsBetter": false, "replicates": [794.301015106706, 810.2100452059242, 829.3075282139413, 849.8300339932015, 869.2741560796734, 13354.933225333873, 13466.932665336684, 13520.256385898783, 13520.391582378805, 13525.391357388917, 12768.744625107493, 12788.104832661717, 12823.935880320585, 12826.551070712503, 12842.486300547967, 631.6368088349194, 637.537785105797, 776.4138075752799, 777.5762209595756, 796.3867821777228, 781.4490783997268, 784.5528049012037, 784.9843003139933, 786.996065019677, 790.4190420041265, 831.7380025292044, 832.1262674192104, 832.904216015157, 838.0488145954355, 838.9973981092567, 654.5450911616429, 662.4303099334569, 786.8583654942137, 807.7092246791151, 810.0603300171772, 607.1105829959087, 608.2184393054947, 608.8112685067643, 610.38351265222, 611.3702886027398, 13266.0, 13300.73398532024, 13318.800217996737, 13323.000774941918, 13350.065495415227, 12636.557720479832, 12973.41619627119, 13132.474701011948, 13191.538296159588, 13344.199348039148, 10671.38476524643, 10719.98160174782, 10803.243772935897, 10841.67474975752, 10867.239293249475, 3245.480723084319, 3294.6870047345465, 3304.4573480043864, 3362.3947689415895, 3368.6294507604143, 47901.56295311146, 48596.757016214695, 48776.75611621919, 48806.755966220655, 48813.51186488125, 10339.322747007269, 10488.26582139243, 10525.0, 10731.892681073245, 10749.516271767789, 18990.525236869187, 19151.904240478707, 19178.13698383576, 19222.80777192238, 19223.711644325347, 7158.570485770871, 7287.672054757549, 7336.339729424378, 7502.949587057817, 7620.390368770485, 4336.956630433655, 4346.891327716832, 4367.62875155609, 4397.978010109929, 4454.465464144323, 3035.8633861476287, 3062.4793785056595, 3071.4932036213772, 3072.938541229163, 3075.1082186165763, 548.1257394455837, 549.5603517186235, 561.6854561445604, 562.9971850140723, 569.4846164221386, 646.1374065622447, 650.2241039564392, 676.9120014398097, 684.8287928017995, 687.7145984416411, 8621.31029517637, 8834.55827208637, 8871.689490867917, 8931.591313847826, 9036.096390361043, 545.7625932719201, 557.0474488624417, 557.2671936403616, 563.4112352591552, 616.5992105131701, 3736.5889752127246, 3799.9240015200094, 3896.376579747227, 3937.724359294822, 4143.81352839123, 57894.710526447096, 58843.0, 58918.41081589215, 58929.70535147383, 58959.115613265836, 48349.274760878616, 49427.258591121164, 49571.0, 50199.0, 50468.0, 44814.27279946781, 44909.55090449119, 45319.54680453219, 45838.54161458409, 46003.30995035077, 8780.165884240987, 8812.0, 8926.196642302224, 9080.636774528944, 9175.26597872182, 32869.50695739518, 33398.0, 33440.66559334376, 33453.33093338071, 33997.32005359929, 41430.0, 42925.78537107295, 44229.0, 45267.547324526335, 45294.094118116795, 10542.789144217226, 10830.163347505097, 11184.552617895193, 11221.831672525082, 11272.098232141572, 12599.030153060192, 12643.304618246084, 12743.74512509726, 12970.783974114076, 13104.148230364995, 11118.777624447304, 11484.368359740294, 11510.712232193928, 11572.074234061423, 11941.282204913192, 11201.551937922717, 11322.490487927898, 11412.144089193509, 11430.48562814692, 11801.468933897824, 11699.203256818524, 12054.216475929037, 12110.636680899586, 12142.271463712206, 12161.939190303992, 9755.878074021579, 10177.694669159937, 10259.333143345659, 10572.89427105719, 10900.073493752803, 9664.855027174457, 9762.197784747334, 9939.503024848584, 10397.688069357931, 10507.527161277609], "unit": "score", "name": "yui.html", "value": 6288.033110682881}], "name": "dromaeo_css", "value": 6690.412228583789}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 106.0, 109.0, 110.0, 111.0, 100.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 108.8}, {"lowerIsBetter": true, "replicates": [163.0, 126.0, 155.0, 168.0, 134.0, 154.0, 150.0, 125.0, 176.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 147.9}, {"lowerIsBetter": true, "replicates": [160.0, 162.0, 160.0, 159.0, 159.0, 160.0, 160.0, 164.0, 161.0, 162.0], "unit": "score", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [75.0, 73.0, 85.0, 94.0, 74.0, 71.0, 72.0, 104.0, 92.0, 73.0], "unit": "score", "name": "audio-fft", "value": 81.3}, {"lowerIsBetter": true, "replicates": [96.0, 106.0, 139.0, 97.0, 96.0, 96.0, 141.0, 99.0, 96.0, 142.0], "unit": "score", "name": "audio-oscillator", "value": 110.8}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 57.0, 56.0, 55.0, 56.0, 57.0, 65.0, 52.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [84.0, 87.0, 87.0, 85.0, 86.0, 85.0, 95.0, 86.0, 95.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.4}, {"lowerIsBetter": true, "replicates": [117.0, 122.0, 123.0, 115.0, 124.0, 120.0, 119.0, 123.0, 125.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.0}, {"lowerIsBetter": true, "replicates": [139.0, 139.0, 142.0, 148.0, 153.0, 136.0, 147.0, 145.0, 150.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.8}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 65.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.4}], "name": "kraken", "value": 1502.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.11229946524065], "unit": "score", "name": "Richards", "value": 211.24716800000004}, {"lowerIsBetter": false, "replicates": [193.4984520123839], "unit": "score", "name": "DeltaBlue", "value": 341.6978240000001}, {"lowerIsBetter": false, "replicates": [284.4141069397042], "unit": "score", "name": "Encrypt", "value": 935.8923959999998}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1972.3865877712033], "unit": "score", "name": "RayTrace", "value": 375.1744229999998}, {"lowerIsBetter": false, "replicates": [780.64012490242], "unit": "score", "name": "Earley", "value": 853.7391030000001}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [42208.333333333336], "unit": "score", "name": "RegExp", "value": 21.583060217176715}, {"lowerIsBetter": false, "replicates": [619.1950464396285], "unit": "score", "name": "Splay", "value": 131.60796499999995}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 18819.905695371854}]} 11:31:53 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "18819.91"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1502.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6690.41"}}} 11:31:53 INFO - Return code: 0 11:31:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 11:31:53 INFO - # TBPL SUCCESS # 11:31:53 INFO - Running post-action listener: _resource_record_post_action 11:31:53 INFO - Running post-run listener: _resource_record_post_run 11:31:55 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 0; Write bytes: 494592000; Read time: 0; Write time: 387236 11:31:55 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 154042368; Read time: 0; Write time: 166380 11:31:55 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 0; Write bytes: 314019840; Read time: 0; Write time: 214536 11:31:55 INFO - Running post-run listener: _upload_blobber_files 11:31:55 INFO - Blob upload gear active. 11:31:55 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 11:31:55 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:31:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'B2G-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 11:31:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b B2G-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 11:31:55 INFO - (blobuploader) - INFO - Open directory for files ... 11:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 11:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:56 INFO - (blobuploader) - INFO - Done attempting. 11:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:57 INFO - (blobuploader) - INFO - Done attempting. 11:31:57 INFO - (blobuploader) - INFO - Iteration through files over. 11:31:57 INFO - Return code: 0 11:31:57 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 11:31:57 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 11:31:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/921bb4c438a7a975e1ae1a84682aaf1a230a0c5c5bc117f8a2e7054e1f67f7a0153e4c82b7470afd6129d7db52270e1f906f9cb5b6e54a027bfc0b62d406bdea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/b1dc9956ffc599922398b3325b54406f3eedd07f1bb467f21476093fae07ab297cafed558784660e65f75835537602ff80ede0a4c30d0831af432ae9a0a42086"} 11:31:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 11:31:57 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 11:31:57 INFO - Contents: 11:31:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/921bb4c438a7a975e1ae1a84682aaf1a230a0c5c5bc117f8a2e7054e1f67f7a0153e4c82b7470afd6129d7db52270e1f906f9cb5b6e54a027bfc0b62d406bdea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/b1dc9956ffc599922398b3325b54406f3eedd07f1bb467f21476093fae07ab297cafed558784660e65f75835537602ff80ede0a4c30d0831af432ae9a0a42086"} 11:31:57 INFO - Copying logs to upload dir... 11:31:57 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1279.928702 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-01-12 11:31:57.375113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 11:31:57.379048) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/921bb4c438a7a975e1ae1a84682aaf1a230a0c5c5bc117f8a2e7054e1f67f7a0153e4c82b7470afd6129d7db52270e1f906f9cb5b6e54a027bfc0b62d406bdea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/b1dc9956ffc599922398b3325b54406f3eedd07f1bb467f21476093fae07ab297cafed558784660e65f75835537602ff80ede0a4c30d0831af432ae9a0a42086"} build_url:https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011530 build_url: 'https://queue.taskcluster.net/v1/task/Av6J2b86TIqs8MLm5ibXZA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/921bb4c438a7a975e1ae1a84682aaf1a230a0c5c5bc117f8a2e7054e1f67f7a0153e4c82b7470afd6129d7db52270e1f906f9cb5b6e54a027bfc0b62d406bdea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/b1dc9956ffc599922398b3325b54406f3eedd07f1bb467f21476093fae07ab297cafed558784660e65f75835537602ff80ede0a4c30d0831af432ae9a0a42086"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 11:31:57.404639) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:31:57.405009) ========= 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=7de08804244eb36560b44971000001c3-1452625835.834768-1003834015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 11:31:57.489727) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 11:31:57.490080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 11:31:57.490418) ========= ========= Total master_lag: 0.37 =========