builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1451800470.22 results: success (0) buildid: 20160102193046 builduid: 0531c267c0354ced9d873f4a9ad7e523 revision: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.223569) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.224007) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.224312) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006284 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.259215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.259583) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.308435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.308707) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.357999) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.358379) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.358727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.359001) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-02 21:54:30-- 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% 82.9M=0s 2016-01-02 21:54:30 (82.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.238279 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.609869) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.610196) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013238 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.663126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:30.663454) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-02 21:54:30,932 truncating revision to first 12 chars 2016-01-02 21:54:30,932 Setting DEBUG logging. 2016-01-02 21:54:30,932 attempt 1/10 2016-01-02 21:54:30,932 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d7a0ad85d9fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-02 21:54:31,186 unpacking tar archive at: mozilla-central-d7a0ad85d9fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.534516 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:31.217600) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:31.217897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:31.228516) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:31.228758) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-02 21:54:31.229128) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-02 21:54:31.229417) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:54:31 INFO - MultiFileLogger online at 20160102 21:54:31 in /builds/slave/test-pgo 21:54:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:54:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:54:31 INFO - {'append_to_log': False, 21:54:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:54:31 INFO - 'blob_upload_branch': 'Firefox', 21:54:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:54:31 INFO - 'branch': 'Firefox', 21:54:31 INFO - 'buildbot_json_path': 'buildprops.json', 21:54:31 INFO - 'config_files': ('talos/linux_config.py',), 21:54:31 INFO - 'default_actions': ('clobber', 21:54:31 INFO - 'read-buildbot-config', 21:54:31 INFO - 'download-and-extract', 21:54:31 INFO - 'populate-webroot', 21:54:31 INFO - 'create-virtualenv', 21:54:31 INFO - 'install', 21:54:31 INFO - 'run-tests'), 21:54:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:54:31 INFO - 'download_minidump_stackwalk': True, 21:54:31 INFO - 'download_symbols': 'ondemand', 21:54:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:54:31 INFO - 'tooltool.py': '/tools/tooltool.py', 21:54:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:54:31 INFO - '/tools/misc-python/virtualenv.py')}, 21:54:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:54:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:54:31 INFO - 'installer_path': 'installer.exe', 21:54:31 INFO - 'log_level': 'info', 21:54:31 INFO - 'log_name': 'talos', 21:54:31 INFO - 'log_to_console': True, 21:54:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:54:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:54:31 INFO - 'opt_config_files': (), 21:54:31 INFO - 'pip_index': False, 21:54:31 INFO - 'sps_profile': False, 21:54:31 INFO - 'sps_profile_interval': 0, 21:54:31 INFO - 'suite': 'dromaeojs-e10s', 21:54:31 INFO - 'system_bits': '32', 21:54:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:54:31 INFO - 'title': 'talos-linux64-ix-015', 21:54:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:54:31 INFO - 'use_talos_json': True, 21:54:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:54:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:54:31 INFO - 'work_dir': 'build'} 21:54:31 INFO - ##### 21:54:31 INFO - ##### Running clobber step. 21:54:31 INFO - ##### 21:54:31 INFO - Running pre-action listener: _resource_record_pre_action 21:54:31 INFO - Running main action method: clobber 21:54:31 INFO - rmtree: /builds/slave/test-pgo/build 21:54:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:54:31 INFO - Running post-action listener: _resource_record_post_action 21:54:31 INFO - ##### 21:54:31 INFO - ##### Running read-buildbot-config step. 21:54:31 INFO - ##### 21:54:31 INFO - Running pre-action listener: _resource_record_pre_action 21:54:31 INFO - Running main action method: read_buildbot_config 21:54:31 INFO - Using buildbot properties: 21:54:31 INFO - { 21:54:31 INFO - "project": "", 21:54:31 INFO - "product": "firefox", 21:54:31 INFO - "script_repo_revision": "production", 21:54:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:54:31 INFO - "repository": "", 21:54:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 21:54:31 INFO - "buildid": "20160102193046", 21:54:31 INFO - "pgo_build": "True", 21:54:31 INFO - "basedir": "/builds/slave/test-pgo", 21:54:31 INFO - "buildnumber": 284, 21:54:31 INFO - "slavename": "talos-linux64-ix-015", 21:54:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:54:31 INFO - "platform": "ubuntu64_hw", 21:54:31 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:54:31 INFO - "branch": "mozilla-central", 21:54:31 INFO - "repo_path": "mozilla-central", 21:54:31 INFO - "revision": "d7a0ad85d9fb77916f9d77d62697b852f3dc63e6", 21:54:31 INFO - "stage_platform": "linux64-pgo", 21:54:31 INFO - "builduid": "0531c267c0354ced9d873f4a9ad7e523", 21:54:31 INFO - "slavebuilddir": "test" 21:54:31 INFO - } 21:54:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:54:31 INFO - Running post-action listener: _resource_record_post_action 21:54:31 INFO - ##### 21:54:31 INFO - ##### Running download-and-extract step. 21:54:31 INFO - ##### 21:54:31 INFO - Running pre-action listener: _resource_record_pre_action 21:54:31 INFO - Running main action method: download_and_extract 21:54:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:54:31 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')]} 21:54:31 INFO - https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:54:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/test_packages.json 21:54:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/test_packages.json 21:54:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:54:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:54:35 INFO - Downloaded 1302 bytes. 21:54:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:54:35 INFO - Using the following test package requirements: 21:54:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:54:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:54:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'jsshell-linux-x86_64.zip'], 21:54:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:54:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:54:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:54:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:54:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:54:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:54:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:54:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:54:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:54:35 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')]} 21:54:35 INFO - https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:54:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:41 INFO - Downloaded 23197903 bytes. 21:54:41 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 21:54:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:41 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 21:54:42 INFO - Return code: 0 21:54:42 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 21:54:42 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')]} 21:54:42 INFO - https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:54:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:46 INFO - Downloaded 23197903 bytes. 21:54:46 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 21:54:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:46 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 21:54:47 INFO - Return code: 0 21:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:47 INFO - https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:48 INFO - Downloaded 11132285 bytes. 21:54:48 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 21:54:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:48 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 21:54:49 INFO - Return code: 0 21:54: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')]} 21:54:49 INFO - https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:54:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:54:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:54:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ouzwny2jSkuKZKBx3yyDJg/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 21:54:50 INFO - Downloaded 54984600 bytes. 21:54:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:54:50 INFO - mkdir: /builds/slave/test-pgo/properties 21:54:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:54:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:54:50 INFO - Contents: 21:54:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:54:50 INFO - Running post-action listener: _resource_record_post_action 21:54:50 INFO - Running post-action listener: set_extra_try_arguments 21:54:50 INFO - ##### 21:54:50 INFO - ##### Running populate-webroot step. 21:54:50 INFO - ##### 21:54:50 INFO - Running pre-action listener: _resource_record_pre_action 21:54:50 INFO - Running main action method: populate_webroot 21:54:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:54:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:54:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:54:50 INFO - '--tppagecycles', 21:54:50 INFO - '7'], 21:54:50 INFO - 'tests': ['tsvgm']}}, 21:54:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:54:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:54:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:54:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:54:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'tests': ['damp', 'tps']}, 21:54:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['damp', 'tps']}, 21:54:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:54:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:54:50 INFO - 'other': {'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'other_l64': {'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'other_nol64': {'tests': ['a11yr', 21:54:50 INFO - 'ts_paint', 21:54:50 INFO - 'tpaint', 21:54:50 INFO - 'sessionrestore', 21:54:50 INFO - 'sessionrestore_no_auto_restore']}, 21:54:50 INFO - 'svgr': {'tests': ['tsvgx', 21:54:50 INFO - 'tsvgr_opacity', 21:54:50 INFO - 'tart', 21:54:50 INFO - 'tscrollx', 21:54:50 INFO - 'cart']}, 21:54:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['tsvgx', 21:54:50 INFO - 'tsvgr_opacity', 21:54:50 INFO - 'tart', 21:54:50 INFO - 'tscrollx', 21:54:50 INFO - 'cart']}, 21:54:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'tests': ['tp5o']}, 21:54:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'talos_options': ['--e10s'], 21:54:50 INFO - 'tests': ['tp5o']}, 21:54:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'talos_options': ['--xperf_path', 21:54:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:50 INFO - 'tests': ['tp5n']}, 21:54:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:50 INFO - 'talos_options': ['--e10s', 21:54:50 INFO - '--xperf_path', 21:54:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:50 INFO - 'tests': ['tp5n']}}, 21:54:50 INFO - 'talos.zip': {'path': '', 21:54:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:54:50 INFO - Running post-action listener: _resource_record_post_action 21:54:50 INFO - ##### 21:54:50 INFO - ##### Running create-virtualenv step. 21:54:50 INFO - ##### 21:54:50 INFO - Running pre-action listener: _resource_record_pre_action 21:54:50 INFO - Running main action method: create_virtualenv 21:54:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:54:50 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 21:54:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:54:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:54:50 INFO - Using real prefix '/usr' 21:54:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:54:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:54:52 INFO - Installing pip.................done. 21:54:52 INFO - Return code: 0 21:54:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:54: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')]} 21:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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')]} 21:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:54:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 21:54:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:54:52 INFO - Using env: {'DISPLAY': ':0', 21:54:52 INFO - 'HOME': '/home/cltbld', 21:54:52 INFO - 'LANG': 'en_US.UTF-8', 21:54:52 INFO - 'LANGUAGE': 'en_US:en', 21:54:52 INFO - 'LOGNAME': 'cltbld', 21:54:52 INFO - 'MAIL': '/var/mail/cltbld', 21:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:52 INFO - 'MOZ_NO_REMOTE': '1', 21:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:52 INFO - 'NO_EM_RESTART': '1', 21:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:54:52 INFO - 'PWD': '/builds/slave/test-pgo', 21:54:52 INFO - 'SHELL': '/bin/bash', 21:54:52 INFO - 'SHLVL': '1', 21:54:52 INFO - 'TERM': 'linux', 21:54:52 INFO - 'TMOUT': '86400', 21:54:52 INFO - 'USER': 'cltbld', 21:54:52 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:52 INFO - '_': '/tools/buildbot/bin/python'} 21:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:53 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 21:54:53 INFO - Cleaning up... 21:54:53 INFO - Return code: 0 21:54:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:54:53 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')]} 21:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:53 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')]} 21:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:53 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:54:53 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 21:54:53 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 21:54:53 INFO - Using env: {'DISPLAY': ':0', 21:54:53 INFO - 'HOME': '/home/cltbld', 21:54:53 INFO - 'LANG': 'en_US.UTF-8', 21:54:53 INFO - 'LANGUAGE': 'en_US:en', 21:54:53 INFO - 'LOGNAME': 'cltbld', 21:54:53 INFO - 'MAIL': '/var/mail/cltbld', 21:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:53 INFO - 'MOZ_NO_REMOTE': '1', 21:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:53 INFO - 'NO_EM_RESTART': '1', 21:54:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:54:53 INFO - 'PWD': '/builds/slave/test-pgo', 21:54:53 INFO - 'SHELL': '/bin/bash', 21:54:53 INFO - 'SHLVL': '1', 21:54:53 INFO - 'TERM': 'linux', 21:54:53 INFO - 'TMOUT': '86400', 21:54:53 INFO - 'USER': 'cltbld', 21:54:53 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:53 INFO - '_': '/tools/buildbot/bin/python'} 21:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:53 INFO - Downloading/unpacking psutil>=0.7.1 21:54:53 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 21:54:53 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 21:54:53 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 21:54:53 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 21:54:53 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 21:54:53 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 21:54:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:54:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:54:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:54:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:55 INFO - Installing collected packages: psutil 21:54:55 INFO - Running setup.py install for psutil 21:54:55 INFO - building 'psutil._psutil_linux' extension 21:54:55 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 21:54:55 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 21:54:55 INFO - building 'psutil._psutil_posix' extension 21:54:55 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 21:54:55 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 21:54:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:55 INFO - Successfully installed psutil 21:54:55 INFO - Cleaning up... 21:54:55 INFO - Return code: 0 21:54:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:54:55 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')]} 21:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:55 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')]} 21:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:55 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:54:55 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 21:54:55 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 21:54:55 INFO - Using env: {'DISPLAY': ':0', 21:54:55 INFO - 'HOME': '/home/cltbld', 21:54:55 INFO - 'LANG': 'en_US.UTF-8', 21:54:55 INFO - 'LANGUAGE': 'en_US:en', 21:54:55 INFO - 'LOGNAME': 'cltbld', 21:54:55 INFO - 'MAIL': '/var/mail/cltbld', 21:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:55 INFO - 'MOZ_NO_REMOTE': '1', 21:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:55 INFO - 'NO_EM_RESTART': '1', 21:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:54:55 INFO - 'PWD': '/builds/slave/test-pgo', 21:54:55 INFO - 'SHELL': '/bin/bash', 21:54:55 INFO - 'SHLVL': '1', 21:54:55 INFO - 'TERM': 'linux', 21:54:55 INFO - 'TMOUT': '86400', 21:54:55 INFO - 'USER': 'cltbld', 21:54:55 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:55 INFO - '_': '/tools/buildbot/bin/python'} 21:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:54:55 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 21:54:55 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 21:54:55 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 21:54:55 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 21:54:55 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 21:54:55 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 21:54:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:54:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:54:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:54:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:54:57 INFO - Installing collected packages: mozsystemmonitor 21:54:57 INFO - Running setup.py install for mozsystemmonitor 21:54:57 INFO - Successfully installed mozsystemmonitor 21:54:57 INFO - Cleaning up... 21:54:57 INFO - Return code: 0 21:54:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:57 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:54:57 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 21:54:57 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 21:54:57 INFO - Using env: {'DISPLAY': ':0', 21:54:57 INFO - 'HOME': '/home/cltbld', 21:54:57 INFO - 'LANG': 'en_US.UTF-8', 21:54:57 INFO - 'LANGUAGE': 'en_US:en', 21:54:57 INFO - 'LOGNAME': 'cltbld', 21:54:57 INFO - 'MAIL': '/var/mail/cltbld', 21:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:57 INFO - 'MOZ_NO_REMOTE': '1', 21:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:57 INFO - 'NO_EM_RESTART': '1', 21:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:54:57 INFO - 'PWD': '/builds/slave/test-pgo', 21:54:57 INFO - 'SHELL': '/bin/bash', 21:54:57 INFO - 'SHLVL': '1', 21:54:57 INFO - 'TERM': 'linux', 21:54:57 INFO - 'TMOUT': '86400', 21:54:57 INFO - 'USER': 'cltbld', 21:54:57 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:57 INFO - '_': '/tools/buildbot/bin/python'} 21:54:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:58 INFO - Downloading/unpacking blobuploader==1.2.4 21:54:58 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 21:54:58 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 21:54:58 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 21:54:58 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 21:54:58 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 21:54:58 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 21:54:59 INFO - Downloading blobuploader-1.2.4.tar.gz 21:54:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:54:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:54:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:55:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:55:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:55:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 INFO - Downloading docopt-0.6.1.tar.gz 21:55:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:55:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:00 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:00 INFO - Running setup.py install for blobuploader 21:55:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:55:00 INFO - Running setup.py install for requests 21:55:00 INFO - Running setup.py install for docopt 21:55:01 INFO - Successfully installed blobuploader requests docopt 21:55:01 INFO - Cleaning up... 21:55:01 INFO - Return code: 0 21:55:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:01 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')]} 21:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:01 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')]} 21:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:01 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:55:01 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 21:55:01 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 21:55:01 INFO - Using env: {'DISPLAY': ':0', 21:55:01 INFO - 'HOME': '/home/cltbld', 21:55:01 INFO - 'LANG': 'en_US.UTF-8', 21:55:01 INFO - 'LANGUAGE': 'en_US:en', 21:55:01 INFO - 'LOGNAME': 'cltbld', 21:55:01 INFO - 'MAIL': '/var/mail/cltbld', 21:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:01 INFO - 'MOZ_NO_REMOTE': '1', 21:55:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:01 INFO - 'NO_EM_RESTART': '1', 21:55:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:01 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:01 INFO - 'SHELL': '/bin/bash', 21:55:01 INFO - 'SHLVL': '1', 21:55:01 INFO - 'TERM': 'linux', 21:55:01 INFO - 'TMOUT': '86400', 21:55:01 INFO - 'USER': 'cltbld', 21:55:01 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:01 INFO - '_': '/tools/buildbot/bin/python'} 21:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:01 INFO - Running setup.py (path:/tmp/pip-dfdyEa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:01 INFO - Running setup.py (path:/tmp/pip-oAQzHL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:01 INFO - Running setup.py (path:/tmp/pip-9pALty-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:01 INFO - Running setup.py (path:/tmp/pip-hndNYG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:01 INFO - Running setup.py (path:/tmp/pip-YWy8Rg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:01 INFO - Running setup.py (path:/tmp/pip-zA7rpg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:01 INFO - Running setup.py (path:/tmp/pip-29vdzZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:01 INFO - Running setup.py (path:/tmp/pip-18Fzo4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:01 INFO - Running setup.py (path:/tmp/pip-CU9t0r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:01 INFO - Running setup.py (path:/tmp/pip-NDNGFZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:02 INFO - Running setup.py (path:/tmp/pip-CPeyju-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:02 INFO - Running setup.py (path:/tmp/pip-CVy7dD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:02 INFO - Running setup.py (path:/tmp/pip-w4hAgr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:02 INFO - Running setup.py (path:/tmp/pip-K2YRTe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:02 INFO - Running setup.py (path:/tmp/pip-xziC_2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:02 INFO - Running setup.py (path:/tmp/pip-A2lKqd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:02 INFO - Running setup.py (path:/tmp/pip-eLM9ND-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:55:02 INFO - Running setup.py install for manifestparser 21:55:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:55:02 INFO - Running setup.py install for mozcrash 21:55:02 INFO - Running setup.py install for mozdebug 21:55:02 INFO - Running setup.py install for mozdevice 21:55:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:55:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:55:02 INFO - Running setup.py install for mozfile 21:55:03 INFO - Running setup.py install for mozhttpd 21:55:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for mozinfo 21:55:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for mozInstall 21:55:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for mozleak 21:55:03 INFO - Running setup.py install for mozlog 21:55:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for moznetwork 21:55:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for mozprocess 21:55:03 INFO - Running setup.py install for mozprofile 21:55:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:55:03 INFO - Running setup.py install for mozrunner 21:55:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:55:04 INFO - Running setup.py install for mozscreenshot 21:55:04 INFO - Running setup.py install for moztest 21:55:04 INFO - Running setup.py install for mozversion 21:55:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:55:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:55:04 INFO - Cleaning up... 21:55:04 INFO - Return code: 0 21:55:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:55:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 21:55:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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 21:55:04 INFO - Using env: {'DISPLAY': ':0', 21:55:04 INFO - 'HOME': '/home/cltbld', 21:55:04 INFO - 'LANG': 'en_US.UTF-8', 21:55:04 INFO - 'LANGUAGE': 'en_US:en', 21:55:04 INFO - 'LOGNAME': 'cltbld', 21:55:04 INFO - 'MAIL': '/var/mail/cltbld', 21:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:04 INFO - 'MOZ_NO_REMOTE': '1', 21:55:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:04 INFO - 'NO_EM_RESTART': '1', 21:55:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:04 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:04 INFO - 'SHELL': '/bin/bash', 21:55:04 INFO - 'SHLVL': '1', 21:55:04 INFO - 'TERM': 'linux', 21:55:04 INFO - 'TMOUT': '86400', 21:55:04 INFO - 'USER': 'cltbld', 21:55:04 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:04 INFO - '_': '/tools/buildbot/bin/python'} 21:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:04 INFO - Running setup.py (path:/tmp/pip-PI5o7o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:04 INFO - Running setup.py (path:/tmp/pip-kgZg6s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:04 INFO - Running setup.py (path:/tmp/pip-yDcFYF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:04 INFO - Running setup.py (path:/tmp/pip-rtZ4jz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:04 INFO - Running setup.py (path:/tmp/pip-BHcd5P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:04 INFO - Running setup.py (path:/tmp/pip-XVFHfU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:04 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)) 21:55:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:04 INFO - Running setup.py (path:/tmp/pip-ZSvxty-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:05 INFO - Running setup.py (path:/tmp/pip-aZ2Dbu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:05 INFO - Running setup.py (path:/tmp/pip-b9leLh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:05 INFO - Running setup.py (path:/tmp/pip-mPObuK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:05 INFO - Running setup.py (path:/tmp/pip-nSbQuT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:05 INFO - Running setup.py (path:/tmp/pip-wzsfXX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:05 INFO - Running setup.py (path:/tmp/pip-mjq3Iw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:05 INFO - Running setup.py (path:/tmp/pip-N1PE5M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:05 INFO - Running setup.py (path:/tmp/pip-W4RJr8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:05 INFO - Running setup.py (path:/tmp/pip-57bZKW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:05 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)) 21:55:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:05 INFO - Running setup.py (path:/tmp/pip-oHx1Gy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:05 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)) 21:55:05 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)) 21:55:05 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)) 21:55:05 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)) 21:55:05 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)) 21:55:05 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)) 21:55:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:07 INFO - Downloading blessings-1.5.1.tar.gz 21:55:07 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 21:55:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:55:07 INFO - Installing collected packages: blessings 21:55:07 INFO - Running setup.py install for blessings 21:55:07 INFO - Successfully installed blessings 21:55:07 INFO - Cleaning up... 21:55:07 INFO - Return code: 0 21:55:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:55:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:55:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:55:07 INFO - Reading from file tmpfile_stdout 21:55:07 INFO - Current package versions: 21:55:07 INFO - argparse == 1.2.1 21:55:07 INFO - blessings == 1.5.1 21:55:07 INFO - blobuploader == 1.2.4 21:55:07 INFO - docopt == 0.6.1 21:55:07 INFO - manifestparser == 1.1 21:55:07 INFO - mozInstall == 1.12 21:55:07 INFO - mozcrash == 0.16 21:55:07 INFO - mozdebug == 0.1 21:55:07 INFO - mozdevice == 0.47 21:55:07 INFO - mozfile == 1.2 21:55:07 INFO - mozhttpd == 0.7 21:55:07 INFO - mozinfo == 0.9 21:55:07 INFO - mozleak == 0.1 21:55:07 INFO - mozlog == 3.1 21:55:07 INFO - moznetwork == 0.27 21:55:07 INFO - mozprocess == 0.22 21:55:07 INFO - mozprofile == 0.28 21:55:07 INFO - mozrunner == 6.11 21:55:07 INFO - mozscreenshot == 0.1 21:55:07 INFO - mozsystemmonitor == 0.0 21:55:07 INFO - moztest == 0.7 21:55:07 INFO - mozversion == 1.4 21:55:07 INFO - psutil == 3.1.1 21:55:07 INFO - requests == 1.2.3 21:55:07 INFO - wsgiref == 0.1.2 21:55:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55: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')]} 21:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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', '-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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:55:07 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 21:55:07 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 21:55:07 INFO - Using env: {'DISPLAY': ':0', 21:55:07 INFO - 'HOME': '/home/cltbld', 21:55:07 INFO - 'LANG': 'en_US.UTF-8', 21:55:07 INFO - 'LANGUAGE': 'en_US:en', 21:55:07 INFO - 'LOGNAME': 'cltbld', 21:55:07 INFO - 'MAIL': '/var/mail/cltbld', 21:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:07 INFO - 'MOZ_NO_REMOTE': '1', 21:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:07 INFO - 'NO_EM_RESTART': '1', 21:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:07 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:07 INFO - 'SHELL': '/bin/bash', 21:55:07 INFO - 'SHLVL': '1', 21:55:07 INFO - 'TERM': 'linux', 21:55:07 INFO - 'TMOUT': '86400', 21:55:07 INFO - 'USER': 'cltbld', 21:55:07 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:07 INFO - '_': '/tools/buildbot/bin/python'} 21:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 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)) 21:55:08 INFO - Cleaning up... 21:55:08 INFO - Return code: 0 21:55:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:08 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:55:08 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 21:55:08 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 21:55:08 INFO - Using env: {'DISPLAY': ':0', 21:55:08 INFO - 'HOME': '/home/cltbld', 21:55:08 INFO - 'LANG': 'en_US.UTF-8', 21:55:08 INFO - 'LANGUAGE': 'en_US:en', 21:55:08 INFO - 'LOGNAME': 'cltbld', 21:55:08 INFO - 'MAIL': '/var/mail/cltbld', 21:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:08 INFO - 'MOZ_NO_REMOTE': '1', 21:55:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:08 INFO - 'NO_EM_RESTART': '1', 21:55:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:08 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:08 INFO - 'SHELL': '/bin/bash', 21:55:08 INFO - 'SHLVL': '1', 21:55:08 INFO - 'TERM': 'linux', 21:55:08 INFO - 'TMOUT': '86400', 21:55:08 INFO - 'USER': 'cltbld', 21:55:08 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:08 INFO - '_': '/tools/buildbot/bin/python'} 21:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:08 INFO - Downloading/unpacking jsonschema 21:55:08 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 21:55:08 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 21:55:08 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 21:55:08 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 21:55:08 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 21:55:08 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 21:55:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:10 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 21:55:10 INFO - Downloading/unpacking functools32 (from jsonschema) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:55:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:10 INFO - warning: no files found matching '*.txt' 21:55:10 INFO - no previously-included directories found matching 'build' 21:55:10 INFO - no previously-included directories found matching 'dist' 21:55:10 INFO - no previously-included directories found matching '.git*' 21:55:10 INFO - Installing collected packages: jsonschema, functools32 21:55:10 INFO - Running setup.py install for functools32 21:55:10 INFO - warning: no files found matching '*.txt' 21:55:10 INFO - no previously-included directories found matching 'build' 21:55:10 INFO - no previously-included directories found matching 'dist' 21:55:10 INFO - no previously-included directories found matching '.git*' 21:55:10 INFO - Successfully installed jsonschema functools32 21:55:10 INFO - Cleaning up... 21:55:10 INFO - Return code: 0 21:55:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:55: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')]} 21:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:10 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 0x7f929b364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2649480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, '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': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', '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 21:55:10 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 21:55:10 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 21:55:10 INFO - Using env: {'DISPLAY': ':0', 21:55:10 INFO - 'HOME': '/home/cltbld', 21:55:10 INFO - 'LANG': 'en_US.UTF-8', 21:55:10 INFO - 'LANGUAGE': 'en_US:en', 21:55:10 INFO - 'LOGNAME': 'cltbld', 21:55:10 INFO - 'MAIL': '/var/mail/cltbld', 21:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:10 INFO - 'MOZ_NO_REMOTE': '1', 21:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:10 INFO - 'NO_EM_RESTART': '1', 21:55:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:10 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:10 INFO - 'SHELL': '/bin/bash', 21:55:10 INFO - 'SHLVL': '1', 21:55:10 INFO - 'TERM': 'linux', 21:55:10 INFO - 'TMOUT': '86400', 21:55:10 INFO - 'USER': 'cltbld', 21:55:10 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:10 INFO - '_': '/tools/buildbot/bin/python'} 21:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:10 INFO - Downloading/unpacking flake8 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:55:12 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 21:55:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:55:12 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 21:55:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 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 21:55:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:55:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:55:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:55:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:55:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:55:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:55:13 INFO - no previously-included directories found matching 'docs/_build' 21:55:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:55:13 INFO - Running setup.py install for pep8 21:55:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:55:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:55:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:55:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:55:13 INFO - no previously-included directories found matching 'docs/_build' 21:55:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:55:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:55:13 INFO - Cleaning up... 21:55:13 INFO - Return code: 0 21:55:13 INFO - Running post-action listener: _resource_record_post_action 21:55:13 INFO - Running post-action listener: _start_resource_monitoring 21:55:13 INFO - Starting resource monitoring. 21:55:13 INFO - ##### 21:55:13 INFO - ##### Running install step. 21:55:13 INFO - ##### 21:55:13 INFO - Running pre-action listener: _resource_record_pre_action 21:55:13 INFO - Running main action method: install 21:55:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:55:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:55:13 INFO - Reading from file tmpfile_stdout 21:55:13 INFO - Detecting whether we're running mozinstall >=1.0... 21:55:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:55:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:55:13 INFO - Reading from file tmpfile_stdout 21:55:13 INFO - Output received: 21:55:13 INFO - Usage: mozinstall [options] installer 21:55:13 INFO - Options: 21:55:13 INFO - -h, --help show this help message and exit 21:55:13 INFO - -d DEST, --destination=DEST 21:55:13 INFO - Directory to install application into. [default: 21:55:13 INFO - "/builds/slave/test-pgo"] 21:55:13 INFO - --app=APP Application being installed. [default: firefox] 21:55:13 INFO - mkdir: /builds/slave/test-pgo/build/application 21:55:13 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'] 21:55:13 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 21:55:29 INFO - Reading from file tmpfile_stdout 21:55:29 INFO - Output received: 21:55:29 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:55:29 INFO - Running post-action listener: _resource_record_post_action 21:55:29 INFO - ##### 21:55:29 INFO - ##### Running run-tests step. 21:55:29 INFO - ##### 21:55:29 INFO - Running pre-action listener: _resource_record_pre_action 21:55:29 INFO - Running main action method: run_tests 21:55:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:55:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:55:29 INFO - Python 2.7.3 21:55:29 INFO - Return code: 0 21:55:29 INFO - grabbing minidump binary from tooltool 21:55:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:29 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 0x26d8660>, '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 0x26d9560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:55:29 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 21:55:29 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 21:55:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:55:29 INFO - Return code: 0 21:55:29 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:55:29 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:55:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:55:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:55:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:55:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:55:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:55:30 INFO - Return code: 0 21:55:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 21:55:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:55:30 INFO - Using env: {'DISPLAY': ':0', 21:55:30 INFO - 'HOME': '/home/cltbld', 21:55:30 INFO - 'LANG': 'en_US.UTF-8', 21:55:30 INFO - 'LANGUAGE': 'en_US:en', 21:55:30 INFO - 'LOGNAME': 'cltbld', 21:55:30 INFO - 'MAIL': '/var/mail/cltbld', 21:55:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:55:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:55:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:30 INFO - 'MOZ_NO_REMOTE': '1', 21:55:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:55:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:30 INFO - 'NO_EM_RESTART': '1', 21:55:30 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:30 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:30 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:55:30 INFO - 'SHELL': '/bin/bash', 21:55:30 INFO - 'SHLVL': '1', 21:55:30 INFO - 'TERM': 'linux', 21:55:30 INFO - 'TMOUT': '86400', 21:55:30 INFO - 'USER': 'cltbld', 21:55:30 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980', 21:55:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:30 INFO - '_': '/tools/buildbot/bin/python'} 21:55:30 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:55:30 INFO - mozversion application_buildid: 20160102193046 21:55:30 INFO - mozversion application_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 21:55:30 INFO - mozversion application_display_name: Nightly 21:55:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:55:30 INFO - mozversion application_name: Firefox 21:55:30 INFO - mozversion application_remotingname: firefox 21:55:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:55:30 INFO - mozversion application_vendor: Mozilla 21:55:30 INFO - mozversion application_version: 46.0a1 21:55:30 INFO - mozversion platform_buildid: 20160102193046 21:55:30 INFO - mozversion platform_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 21:55:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:55:30 INFO - mozversion platform_version: 46.0a1 21:55:30 INFO - using testdate: 1451800530 21:55:30 INFO - actual date: 1451800530 21:55:30 INFO - starting webserver on 'localhost:35483' 21:55:30 INFO - SUITE-START | Running 3 tests 21:55:30 INFO - TEST-START | dromaeo_css 21:55:30 INFO - operating with platform_type : linux_ 21:55:30 INFO - Initialising browser for dromaeo_css test... 21:55:30 INFO - TEST-INFO | started process 17691 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLgyo1N/profile http://localhost:35483/getInfo.html) 21:55:35 INFO - PROCESS | 17691 | __metrics Screen width/height:1600/1200 21:55:35 INFO - PROCESS | 17691 | colorDepth:24 21:55:35 INFO - PROCESS | 17691 | Browser inner width/height: 1024/697 21:55:35 INFO - PROCESS | 17691 | __metrics 21:55:35 INFO - PROCESS | 17691 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:55:36 INFO - TEST-INFO | 17691: exit 0 21:55:36 INFO - Browser initialized. 21:55:36 INFO - Running cycle 1/1 for dromaeo_css test... 21:55:36 INFO - TEST-INFO | started process 17823 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLgyo1N/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:48 INFO - PROCESS | 17823 | RSS: Main: 150859776 21:55:48 INFO - PROCESS | 17823 | 21:56:37 INFO - PROCESS | 17823 | 1451800597868 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] 21:58:46 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35483/tests/dromaeo/cssquery-ext.html) 21:58:47 INFO - PROCESS | 17823 | RSS: Main: 157523968 21:58:47 INFO - PROCESS | 17823 | 22:01:50 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-ext.html (next: http://localhost:35483/tests/dromaeo/cssquery-jquery.html) 22:01:50 INFO - PROCESS | 17823 | RSS: Main: 154947584 22:01:50 INFO - PROCESS | 17823 | 22:04:52 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35483/tests/dromaeo/cssquery-mootools.html) 22:04:52 INFO - PROCESS | 17823 | RSS: Main: 153780224 22:04:52 INFO - PROCESS | 17823 | 22:07:54 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35483/tests/dromaeo/cssquery-prototype.html) 22:07:55 INFO - PROCESS | 17823 | RSS: Main: 152440832 22:07:55 INFO - PROCESS | 17823 | 22:10:57 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35483/tests/dromaeo/cssquery-yui.html) 22:10:58 INFO - PROCESS | 17823 | RSS: Main: 153288704 22:10:58 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | Cycle 1(1): loaded http://localhost:35483/tests/dromaeo/cssquery-yui.html (next: http://localhost:35483/tests/dromaeo/cssquery-dojo.html) 22:14:00 INFO - PROCESS | 17823 | RSS: Main: 154918912 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | __start_tp_report 22:14:00 INFO - PROCESS | 17823 | _x_x_mozilla_page_load 22:14:00 INFO - PROCESS | 17823 | _x_x_mozilla_page_load_details 22:14:00 INFO - PROCESS | 17823 | |i|pagename|runs| 22:14:00 INFO - PROCESS | 17823 | |0;dojo.html;3141.32461520773;3191.0586377018776;3222.822744749038;3232.224266176117;3237.41726489232;5270.446603106676;5381.165919282513;5384.138537833947;5397.271368365269;5429.049916264653;5365.919511207335;5379.515843574078;5387.78448862046;5394.083005888999;5412.809181979968;4905.460399356077;4935.876603084916;4973.129702302101;4989.975050124763;5031.52200540948;5002.074616196015;5015.047141043208;5054.469280725508;5056.241563765428;5058.519440653132;4831.9033619327765;4885.389326334208;4990.925136122943;4999.3000979862845;5010.323606313137;4982.352294201767;4984.750762461862;4992.500749924979;4997.725125118117;5043.747812609392;4780.617550595944;4974.204127339644;4982.327385802906;4986.326845875797;5014.774335154927;5128.615353848476;5175.249588809602;5196.350456192976;5226.451222621646;5229.660072095302;5801.7099145043;5806.88386232273;5820.5634577406445;5822.301323841124;5823.184754134425;5693.089105743061;5818.5345172386105;5866.325372582165;5875.265591801025;5885.382034886318;5434.184872269152;5514.255575497336;5524.281843360378;5533.142362933751;5568.832935011957;5985.6408615483215;6012.158297838306;6016.037434010537;6017.819465416044;6018.337982821886;5623.690697011703;5694.800681976111;5744.080947048452;5746.856328591735;5752.338481074647;5862.70686465675;5862.912056319158;5867.530597552186;5876.236089308362;5899.115132730125;5759.798407055733;5817.069268916953;5826.184334193216;5830.154627579062;5844.59087863854;5672.205891175238;5749.310082790092;5779.508741756998;5788.160716696139;5875.441833025856;4489.932651010237;4495.415595972503;4496.595306422438;4506.188886000551;4528.0943811237885;3984.2230765000545;3997.200559887976;3997.2605068062503;4039.3940908863906;4053.4730485036757;5215.426303106655;5379.300690910157;5462.262594549765;5528.695921724263;5550.333959924835;5804.709764511757;5813.069908814569;5822.7088645567555;5858.941410585925;5861.794837180678;5407.107827208467;5492.203630473559;5498.9826882026955;5499.697516636624;5512.173174023928;5009.223570346602;5022.196448568212;5032.194848824171;5038.244263360525;5040.042391945519;6029.698515074228;6061.696915154224;6082;6091.390860913962;6110.938890611126;5814.970925145347;5847.678377689098;5856.238688970407;5875.089361149029;5897.351291357862;5902.557308201816;6003.399660034136;6066.211392518944;6079.513638908965;6120.969395152996;5723.170140329712;5749.482546570829;5750.367459579359;5788;5797.391273916178;5804.9709751452665;5807.419258074327;5811.535077193899;5814.854628634149;5826.41735826414;5592.496675299242;5663.518600918803;5678.7728490861555;5680.11958146488;5700.486956173799;5612.607117501736;5722.141678748055;5735.3977832327;5752.165935939349;5780.132980053026;5600.467955544217;5602.52378547828;5641.097424412074;5645.887082258414;5663.490285874291;5633.577481688972;5709.65742055478;5751.194832723506;5756.856078597901;5765.106408506688;5618.9604923089355;5689.914651280152;5693.117566777156;5702.800401985911;5728.427157284238 22:14:00 INFO - PROCESS | 17823 | |1;ext.html;539.0620320642084;541.2422608348312;560.8950367775482;562.5274769193876;577.5408550202588;34834.65165348349;35557.2888542229;35754.642453575456;35809.820950895206;35977.46033809495;38287;38599;38707.61292387075;38963.610363896354;39139.804300978525;8467.280281176092;8640.351973601973;8689.869651955225;8690.261327787146;8694.434861733967;8261.049979252402;8353.707620233294;8364.832703345959;8460;8518.23335899769;8592.269657079156;8658.567071646425;8737.912620873774;8741.213290803826;8753.643185306257;8665.436746611482;8716.346274029473;8741.912580874237;8742;8767;8437.493750374935;8559.786005349853;8560.186782255676;8563.272121869648;8587.785305367415;38584.61415385838;38750;38829.41755873664;38956.61043389587;38993.61006389928;3116.298832762633;3190.8564114614665;3429;3744.812759362021;3749.1189570450924;28594.857025714948;28829.85585072061;28848.134555963356;29018.41963160746;29729.108126756233;3387.6781705737917;3443.5695538057744;3455.0844026333043;3526.0303416560396;3574.982125089358;192700;193196;193519;193776;366484.9999999973;47627.285590716165;59753.70123149444;60267.69866150641;60704.392956069874;60725.39274607286;52588.21117683238;53108;53235.201471977954;53542.732286339095;53569;13894.93052534751;13998.44006239739;14008.649783755487;14025.368858401396;14064.945129115153;10561.207909406825;10752.408617525954;10755.677329680122;10784.299020563638;10788.136949044214;18729.81270187308;18801.247950081844;19477.80522194788;19481.415557533295;19545.804541954683;7074.823129421806;7169.139703235645;7199.928000719926;7289.744858929912;7371.6682749276415;876.8509353409936;985.9260555458369;1046.4819914142397;1055.5250137438184;1075.6934273732106;28229.71770282271;28446.862125515163;28455.857720711265;29041.128766137048;29187.416251675273;1032.2877214721818;1032.2980373346047;1042.0777611813448;1051.8685164354456;1052.4579841381694;13864.376103075387;13879.583612491642;13973.79039314411;14023.368948397347;14040.508582199574;313771;315633;316212;317033;317268;49798.50201498011;50021.749891250314;50466;50568;50727.239091413656;1330.1686445971268;1345.0382976172084;1347.407140858039;1366.419271809477;1370.8149399830895;27110.1866943992;28068.438631226854;28258.85870570634;28356.858215708788;29276.853615731787;42836.78581607072;42967.355489668305;43245;43333;43493.78253108714;236576;236899;237378;238129;238481;27291.59062614102;27899.44201116007;30604.693953061076;30967.225819354695;31077.844610776803;37237.255254895295;37500.62499374971;38091.80954095212;38220.42669359906;38254.42618360782;33685.326293474485;34298.65701342955;34964.650353497156;35165;35384.64615353814;33578.832105840294;34726.65273347234;35157.29685406226;35754;36372.454413183295;32326.676733233307;32696.836515817267;32792.672073279915;33050;35235;27233;27356.58965115485;29204.561931570617;29275.56086658659;31009.6814662642;22596.209132680277;22600.208992685177;22874.885625571766;22944.19695310723;24023 22:14:00 INFO - PROCESS | 17823 | |2;jquery.html;354.7392666390203;356.3496618670923;360.47190865382214;361.10806308418216;361.4271379862917;16001.919990400047;16202.270897809598;16748.330066797345;17361.392351267707;17785.7332140018;10819.945900270488;10847.186461015415;11057.336559806417;11061.834072488899;11111.666650000492;4841.176999910015;4910.705357678533;5008.148614735485;5027.371578552681;5033.974830125844;4923.286123512089;4960.107180707471;4960.67755595887;4966.627502937295;4970.577500912409;4934.5312195341385;4948.752562371867;4957.702537847723;5075.796968121271;5247.370315562119;5140.614453915952;5142.562882155022;5159.58723302135;5166.509181627739;5168.121419358718;4999.500049995007;5191.506806853343;5228.92156617651;5273.604479664041;5277.525022747971;11872.881271187263;12058.457369418396;12118.575849845301;12906.548270810477;12991;1943;1987.8509111816527;1995.0523501336893;2108.378028481586;2109.9683504747286;9193.586288617029;9241.537923103819;9253.491057991807;9295.395799273025;9402;1615.6203292226317;1617.490490495502;1626.9507059697796;1628.7964004499438;1656.892302000366;59410;61686.69156654233;62771.686141569;62776;63085.05372419417;10238.846417303746;10332.845007324897;10353.119984801226;10354.223433242538;10380.896191038144;13497.19016858972;13657.385417656229;13666.93166534161;13676.863231367559;13687.657808554866;7302.671379787922;7307.269273072757;7320.890186647204;7443.106827180773;7581.848363032708;6903.965480172667;6919.930800692029;6927.064846245692;6959.756408525677;6964.268751781091;4383.07955329378;4428.4243048403505;4470.932936005963;4476.462824461086;4486.887827804331;1763.5679258581729;1802.2880962019929;1810.2577943043289;1813.4468986959105;1813.8458231050508;437.62145743931904;444.2381316043002;899.9685011024714;979.6669132494986;997.6258902911409;10554.525046372932;10869.239153259196;11070.39112848801;11094.44527773608;11142.610008649659;446.06994416641663;941.228192881844;976.3897564022486;983.8770153730784;1004.7086344960026;8900.599473023729;8947.686830961014;8968.955155224312;8991.77520561978;9114.13415725505;565875;571018;571591;571744;603112;37721.62278377181;37881.81059094687;37944.241115177545;76713.23286767173;80848.59575702084;1138.260130914912;1150.3672979861044;1150.9827352589718;1161.0537412009185;1162.5001249462644;14225.786613201017;14366;14458.493952711604;14465.855341446451;14608.78086828677;30294.091177264716;30774.538381924736;30986.380272393973;31108.844455778482;32501.512477313332;36248;37436.812815935744;37681.62318376781;38682.22635547329;40133.79933100316;9271.351005429826;9359.85960210611;9368.859467108136;9411.341206115507;9510.09654082861;11028.28316159447;16076.598085047968;16984.57538561546;17296.827031730027;17318.567035824206;13006.674833129247;13096.476140954255;13383;13852.861471385157;13923.23422211788;13939;13969.231692256617;14181.574552763434;14670.486532971296;14820.851791482379;14001.22993235382;14026.438942442188;14277.07259519993;14370.49703260381;14499.347529361416;8953.865692014495;8955.41789783662;8971.147740964598;9173.816523669355;9786.266030047633;9881.752956176153;11610.825837612612;13349.665033496573;13932;13996 22:14:00 INFO - PROCESS | 17823 | |3;mootools.html;1444.7543917534017;1454.2946670864633;1586.159335552157;1717.5276798880298;1750.4661078371091;7108.857822843546;7230.783076507707;7253.782386528406;7291.671874765635;7403.9629801850915;7414.814629634248;7487.812804679886;7533.84932301355;7544.773656790305;7558.508696934696;2212.822974162063;2226.532428190082;2246.8539544929527;2247.3932038349635;2248;2252.7296724393013;2257.3002369265582;2326.0230703104735;2327.522857814144;2327.7555856635063;2212.723409573803;2212.8451008429415;2236.474428509299;2236.809871160953;2254.797068263856;2230.966535501969;2274.783895529922;2275.4425165834477;2276.704028476304;2280.897359618821;2188.6498160294436;2237.4518243030398;2244.5623103494827;2245.1356227852393;2247.3145690564534;7295.416366690652;7301.780946571611;7369.189389167187;7369.4841361104745;7399.70401183952;4323.978380108079;4365;4414.624756895668;4447.533009034038;4452.421185245929;2883.5098033334384;2898.6521617405833;2969.049904030711;2982.0606508949613;2997.8501074946166;900.3472482450237;920.746794631475;925.6251218256617;934.5794392523323;939.8449255872773;9643.324967252294;9676.080772326617;9696.321257511978;9715.951420242924;9727.513624318755;3924.803759811998;4012.6188012139382;4113.197926404382;4176;4200.096979149497;7934.603269836485;8049.356051515864;8063.314618257514;8078.071021832447;8102.39232057598;4454.131444368382;4496.57282558163;4499.6400287976885;4521.253993091103;4531.252343363374;4123.422720819088;4125;4177.895552611149;4183.330667093312;4186.225548273579;3270.4930735735998;3303.3393321335348;3337.215754297738;3345.765796394278;3354;1003.799240151958;1005.9597616095274;1006.9194464442827;1007.7228762090484;1013.8428543575758;1031.742064483879;1058.0847566854616;1062.3997441445558;1100.4937728644913;1221.6029790318182;6859.416949559343;6917.273686262971;6926;6926.099607051152;6932.930670693329;1097.314178638351;1124.3310230412892;1131.3777422417638;1132.0830127596673;1141.5662048421548;5523.806666766724;5580.525655319262;5586.525145362609;5608.10270356741;5622;8556.786080348042;8638.352123590756;8835.911640883509;8892.066333035067;8939.240164586083;8747.825043499095;8864.113588641085;8872.512011839412;8879.778005549784;8892.911070889208;6958.234594194634;7050.92949070513;7070.469714771412;7081.89377159343;7114.928850711427;6795.694193761194;6799.218089919723;6835.4189893859575;6849.554778939353;6870.935005074221;7751.418643601865;7833.49082309648;7907.327877130509;7913.248241417056;7946.602669866599;7858.135605083322;8113.472624279403;8114.06688230861;8116.594170291343;8138.267555919759;6034.034554471264;6036.154938308729;6129.049997250433;6151.415615516519;6161.106639537153;7246.855062898818;7263.346298832919;7294.088238970129;7315.341619254293;7365.447591430557;6619.569727967667;6627.237867645283;6699.5310328276555;6743.718693448229;6799.524033317621;6478.578892371981;6507.479401647762;6554.049662798963;6605.207375115017;6685.76599819004;6379.681015949277;6394.90407643895;6401.775937842153;6409.198850143732;6415.582987105823;6001.489873360813;6003.669798161143;6016.909746353896;6091.482224011009;6097.359777223328;5548.140038294071;5595.664260144405;5595.972020140036;5605.691686957257;5611.494965453129 22:14:00 INFO - PROCESS | 17823 | |4;prototype.html;332.0453695625078;338.5700160795789;342.5717852684145;346.1450217961634;349.9807510586917;6096.177016102824;6146.969265153673;6196.845078873025;6239.251289845213;6256.687165641711;5966.642001479903;6056.666883321415;6058.666773327476;6099.481544068748;6162.784302549411;4702.365180700596;4711.269753188261;4716;4720.055988802236;4720.858374248761;4691.812327506913;4725.527447255264;4732.219183834681;4741.288806679008;4742.715437073769;4518.774061296938;4620.653450991173;4624.838130665427;4628.1669299526075;4684.695494792844;4570.748608826513;4614.100250451163;4623.144718227122;4650.674452788307;4663.230566956482;3975.7018223633054;4030.717849750519;4054.9797251013556;4061.390791381287;4069.04377471294;5864.970675146597;5888.705564721747;5894.056950887837;5921.082232254007;5931.525477961753;5395.541378982791;5398.190271459273;5403.8108666196495;5404.91892621611;5412.269343638637;6446.484281257535;6450.741970321182;6494.447971922392;6505.154329937078;6511.544191906569;4353.847615333448;4467.1735728889935;4474.932876006863;4476.395686582302;4481.529439408848;6724.630145342005;6797.320267973163;6865.485088618374;6867.759628413031;6886.10480637524;4592.632589392841;4594.218982772936;4601.401817763669;4608.5161058088415;4610.40064791575;4500.752458614807;4654.604358629555;4660.300954856731;4665.486796452387;4671.065786842577;5389.056915039852;5397.136458166753;5403.5136837684795;5409.161579955114;5411.756470958816;6552.770653027216;6603.735850565923;6606.636634985026;6619.536632435779;6630.1380820494;1633.5752704296967;1650.3646096253028;1654.627708765518;1658.6433916707965;1660.593154677112;1086;1090.3294473898495;1091.6670415523183;1097.4622435006788;1123.9775204495863;6259.96870015647;6303.369663033752;6310.463610593151;6315.052742088724;6330.39861213184;6790.219124800612;6805.251422343538;6813.965930170318;6818.556793808387;6822.829429264308;6159.907601385893;6294.276158241861;6310;6312.179416675803;6318.115463835067;3701.3522633539023;3711.4432835074413;3728.5898551159353;3732.4401339799247;3738.962610373916;7011;7108.68010939509;7140.143182818095;7227.927720722831;7235.6020418876415;6930.584164950018;6973.476989225828;7020.438364930793;7035.1909530404655;7037.753678621224;7039.26087760788;7147.928520714726;7177.461690373243;7203.243659415791;7213.098362704557;6886.10480637514;6894.517383783288;6896.241413444617;6901.309869013058;6904.896426553706;6776.864462710817;6805.8638827222185;6808.319168083152;6817.079694241413;6818.829529261808;7069.575825450489;7169.964150179216;7194.460415468756;7201.2438693938475;7204.387627051759;4686.671932964796;4738.691985020962;4741.715497070187;4745.715257084586;4757.928631070606;6783.694733736895;6788.7284508619105;6799.946008368711;6873.725050997904;6895.862082758417;6513.772017979348;6616;6627.436667883284;6631.171103612049;6635.971424429221;6605.1083103780375;6609.603423794588;6618.841702702104;6619.205695316593;6627.4698024158915;6492.188476440445;6510.641914694737;6529.640869752209;6530.575512591667;6544.770933017512;6561;6660.700268487833;6676.866462670622;6678.732850686112;6687.531872768859;6375.5537112403545;6434.227892652912;6507.28419873823;6512.5766825156215;6519.7392104315295 22:14:00 INFO - PROCESS | 17823 | |5;yui.html;809.5668817182808;819.3854609043213;819.6680344460495;820.7291593774052;834.1325021977144;12848.871511284884;13015.804762928552;13030.869691303084;13043.739125217502;13080.73838523229;12175;12182;12182.147249692525;12187.390630468486;12201.450934707937;635.4408120853642;645.5416654175541;763.7403282883819;770.5261264322457;772.0272456704564;767.3209209849296;769.5959621198888;769.9153093159748;771.3405038691893;772.3357912195507;799.5962039170211;800.1638287989066;802.1777677880191;808.4946908182386;808.5916612110905;786.6027656033724;791.1020991175029;794.6900708723603;800.6877317846045;806.9435139540235;593.0481577068786;593.5637306579661;594.0257976917858;594.9881002379972;596.3827438601071;12336.198147120498;12418.192817466836;12479.31363774992;12483.063770217179;12504;11308.151888608305;11331.603393881174;11477.426128693533;11692.824607630891;11845.763084738343;9417.952910235404;9664.420134791935;9700.223982081487;9715.805683886354;9852.556634951445;3515.753897227195;3544.5746510418917;3595.6404359564094;3629.2197177606677;3681.834317455721;45483.31775023377;45618.31572526415;45647;45814.54185458136;45853.54146458559;9002.819943601091;9243;9335.533223338942;9586;9621.663241786504;18307.176177072062;18454.446366609023;18456.538586535444;18465.35371262026;18466.169022394024;7234.421246300283;7334.229905859915;7382.5570465772225;7443.7692838936855;7645.694172233159;4044.393340998815;4090.897727556835;4095.2628526864855;4103.281925662997;4150.91698166035;2928.5167947288883;2953.2321596385095;2959.215807810932;2959.8372089535283;2963.688812674638;502.5125628140698;526.4630077321187;527.9841604751864;528.1997773373342;532.3957308454932;514.1490832671934;587.5270407322147;602.5053103845363;657.1274003265826;688.1535711075405;7584.08990921093;7747.30274275318;7945.165757595485;8227.177282271725;8310;485.3665965914515;542.5036091975797;591.4499515450672;675.4056430341268;693.7329128285646;3600.891973240807;3686.5576130863933;3805.238952209569;3946.4474973503734;4014.7189696720948;52929.735351323;58389.12416313759;58404;58481.70759146177;58657.70671146702;49144;50215.24677129846;50257.24614130791;50317.74841125771;50531.49468505341;43686.7815660926;44169.55830441719;44310.55689443064;44595.55404445979;44812.55187448149;8029.799255018671;8033.19668033192;8182.22268884455;8233.135520770233;8316.293115085286;31026.53460198144;31970;32714.836425817717;33265.83367083149;33334;38464.80767596144;39266.214675705756;39771;45398.31902521531;45662.77168614136;10730.051609756285;10764.515596798006;10813.24307298513;10817.350958942488;10897.346159230472;10628.627998020032;10728.088112510211;10793.352398856094;10814.783704326026;11162.72093197644;10178.796424071625;10217.284790064625;10478.528466219192;10592.89407105919;10607.840882386925;10045.949770251102;10341.193452841948;10354.223433242387;10434.843477347998;10688.305260158066;10026.348287361297;10390.792184156426;10428.113610343205;10569.524371403459;10722.678319650424;8843.91156088431;8861.955690221508;9037.665253180345;9079.773005674911;9316.955368056908;8408.537530435884;8634.697785577726;9173.266138709021;9232.538373081454;9261.978111671384 22:14:00 INFO - PROCESS | 17823 | __end_tp_report 22:14:00 INFO - PROCESS | 17823 | __start_cc_report 22:14:00 INFO - PROCESS | 17823 | _x_x_mozilla_cycle_collect,308 22:14:00 INFO - PROCESS | 17823 | __end_cc_report 22:14:00 INFO - PROCESS | 17823 | __startTimestamp1451801640810__endTimestamp 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | ------- Summary: start ------- 22:14:00 INFO - PROCESS | 17823 | Number of tests: 6 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#0] dojo.html Cycles:165 Average:5413.60 Median:5641.10 stddev:605.66 (10.7%) stddev-sans-first:580.69 22:14:00 INFO - PROCESS | 17823 | Values: 3141.3 3191.1 3222.8 3232.2 3237.4 5270.4 5381.2 5384.1 5397.3 5429.0 5365.9 5379.5 5387.8 5394.1 5412.8 4905.5 4935.9 4973.1 4990.0 5031.5 5002.1 5015.0 5054.5 5056.2 5058.5 4831.9 4885.4 4990.9 4999.3 5010.3 4982.4 4984.8 4992.5 4997.7 5043.7 4780.6 4974.2 4982.3 4986.3 5014.8 5128.6 5175.2 5196.4 5226.5 5229.7 5801.7 5806.9 5820.6 5822.3 5823.2 5693.1 5818.5 5866.3 5875.3 5885.4 5434.2 5514.3 5524.3 5533.1 5568.8 5985.6 6012.2 6016.0 6017.8 6018.3 5623.7 5694.8 5744.1 5746.9 5752.3 5862.7 5862.9 5867.5 5876.2 5899.1 5759.8 5817.1 5826.2 5830.2 5844.6 5672.2 5749.3 5779.5 5788.2 5875.4 4489.9 4495.4 4496.6 4506.2 4528.1 3984.2 3997.2 3997.3 4039.4 4053.5 5215.4 5379.3 5462.3 5528.7 5550.3 5804.7 5813.1 5822.7 5858.9 5861.8 5407.1 5492.2 5499.0 5499.7 5512.2 5009.2 5022.2 5032.2 5038.2 5040.0 6029.7 6061.7 6082.0 6091.4 6110.9 5815.0 5847.7 5856.2 5875.1 5897.4 5902.6 6003.4 6066.2 6079.5 6121.0 5723.2 5749.5 5750.4 5788.0 5797.4 5805.0 5807.4 5811.5 5814.9 5826.4 5592.5 5663.5 5678.8 5680.1 5700.5 5612.6 5722.1 5735.4 5752.2 5780.1 5600.5 5602.5 5641.1 5645.9 5663.5 5633.6 5709.7 5751.2 5756.9 5765.1 5619.0 5689.9 5693.1 5702.8 5728.4 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#1] ext.html Cycles:180 Average:42395.65 Median:34780.65 stddev:69750.06 (200.5%) stddev-sans-first:69874.93 22:14:00 INFO - PROCESS | 17823 | Values: 539.1 541.2 560.9 562.5 577.5 34834.7 35557.3 35754.6 35809.8 35977.5 38287.0 38599.0 38707.6 38963.6 39139.8 8467.3 8640.4 8689.9 8690.3 8694.4 8261.0 8353.7 8364.8 8460.0 8518.2 8592.3 8658.6 8737.9 8741.2 8753.6 8665.4 8716.3 8741.9 8742.0 8767.0 8437.5 8559.8 8560.2 8563.3 8587.8 38584.6 38750.0 38829.4 38956.6 38993.6 3116.3 3190.9 3429.0 3744.8 3749.1 28594.9 28829.9 28848.1 29018.4 29729.1 3387.7 3443.6 3455.1 3526.0 3575.0 192700.0 193196.0 193519.0 193776.0 366485.0 47627.3 59753.7 60267.7 60704.4 60725.4 52588.2 53108.0 53235.2 53542.7 53569.0 13894.9 13998.4 14008.6 14025.4 14064.9 10561.2 10752.4 10755.7 10784.3 10788.1 18729.8 18801.2 19477.8 19481.4 19545.8 7074.8 7169.1 7199.9 7289.7 7371.7 876.9 985.9 1046.5 1055.5 1075.7 28229.7 28446.9 28455.9 29041.1 29187.4 1032.3 1032.3 1042.1 1051.9 1052.5 13864.4 13879.6 13973.8 14023.4 14040.5 313771.0 315633.0 316212.0 317033.0 317268.0 49798.5 50021.7 50466.0 50568.0 50727.2 1330.2 1345.0 1347.4 1366.4 1370.8 27110.2 28068.4 28258.9 28356.9 29276.9 42836.8 42967.4 43245.0 43333.0 43493.8 236576.0 236899.0 237378.0 238129.0 238481.0 27291.6 27899.4 30604.7 30967.2 31077.8 37237.3 37500.6 38091.8 38220.4 38254.4 33685.3 34298.7 34964.7 35165.0 35384.6 33578.8 34726.7 35157.3 35754.0 36372.5 32326.7 32696.8 32792.7 33050.0 35235.0 27233.0 27356.6 29204.6 29275.6 31009.7 22596.2 22600.2 22874.9 22944.2 24023.0 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#2] jquery.html Cycles:180 Average:28092.18 Median:36842.41 stddev:94055.15 (255.3%) stddev-sans-first:94295.94 22:14:00 INFO - PROCESS | 17823 | Values: 354.7 356.3 360.5 361.1 361.4 16001.9 16202.3 16748.3 17361.4 17785.7 10819.9 10847.2 11057.3 11061.8 11111.7 4841.2 4910.7 5008.1 5027.4 5034.0 4923.3 4960.1 4960.7 4966.6 4970.6 4934.5 4948.8 4957.7 5075.8 5247.4 5140.6 5142.6 5159.6 5166.5 5168.1 4999.5 5191.5 5228.9 5273.6 5277.5 11872.9 12058.5 12118.6 12906.5 12991.0 1943.0 1987.9 1995.1 2108.4 2110.0 9193.6 9241.5 9253.5 9295.4 9402.0 1615.6 1617.5 1627.0 1628.8 1656.9 59410.0 61686.7 62771.7 62776.0 63085.1 10238.8 10332.8 10353.1 10354.2 10380.9 13497.2 13657.4 13666.9 13676.9 13687.7 7302.7 7307.3 7320.9 7443.1 7581.8 6904.0 6919.9 6927.1 6959.8 6964.3 4383.1 4428.4 4470.9 4476.5 4486.9 1763.6 1802.3 1810.3 1813.4 1813.8 437.6 444.2 900.0 979.7 997.6 10554.5 10869.2 11070.4 11094.4 11142.6 446.1 941.2 976.4 983.9 1004.7 8900.6 8947.7 8969.0 8991.8 9114.1 565875.0 571018.0 571591.0 571744.0 603112.0 37721.6 37881.8 37944.2 76713.2 80848.6 1138.3 1150.4 1151.0 1161.1 1162.5 14225.8 14366.0 14458.5 14465.9 14608.8 30294.1 30774.5 30986.4 31108.8 32501.5 36248.0 37436.8 37681.6 38682.2 40133.8 9271.4 9359.9 9368.9 9411.3 9510.1 11028.3 16076.6 16984.6 17296.8 17318.6 13006.7 13096.5 13383.0 13852.9 13923.2 13939.0 13969.2 14181.6 14670.5 14820.9 14001.2 14026.4 14277.1 14370.5 14499.3 8953.9 8955.4 8971.1 9173.8 9786.3 9881.8 11610.8 13349.7 13932.0 13996.0 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#3] mootools.html Cycles:180 Average:5136.52 Median:6094.42 stddev:2602.66 (42.7%) stddev-sans-first:2595.17 22:14:00 INFO - PROCESS | 17823 | Values: 1444.8 1454.3 1586.2 1717.5 1750.5 7108.9 7230.8 7253.8 7291.7 7404.0 7414.8 7487.8 7533.8 7544.8 7558.5 2212.8 2226.5 2246.9 2247.4 2248.0 2252.7 2257.3 2326.0 2327.5 2327.8 2212.7 2212.8 2236.5 2236.8 2254.8 2231.0 2274.8 2275.4 2276.7 2280.9 2188.6 2237.5 2244.6 2245.1 2247.3 7295.4 7301.8 7369.2 7369.5 7399.7 4324.0 4365.0 4414.6 4447.5 4452.4 2883.5 2898.7 2969.0 2982.1 2997.9 900.3 920.7 925.6 934.6 939.8 9643.3 9676.1 9696.3 9716.0 9727.5 3924.8 4012.6 4113.2 4176.0 4200.1 7934.6 8049.4 8063.3 8078.1 8102.4 4454.1 4496.6 4499.6 4521.3 4531.3 4123.4 4125.0 4177.9 4183.3 4186.2 3270.5 3303.3 3337.2 3345.8 3354.0 1003.8 1006.0 1006.9 1007.7 1013.8 1031.7 1058.1 1062.4 1100.5 1221.6 6859.4 6917.3 6926.0 6926.1 6932.9 1097.3 1124.3 1131.4 1132.1 1141.6 5523.8 5580.5 5586.5 5608.1 5622.0 8556.8 8638.4 8835.9 8892.1 8939.2 8747.8 8864.1 8872.5 8879.8 8892.9 6958.2 7050.9 7070.5 7081.9 7114.9 6795.7 6799.2 6835.4 6849.6 6870.9 7751.4 7833.5 7907.3 7913.2 7946.6 7858.1 8113.5 8114.1 8116.6 8138.3 6034.0 6036.2 6129.0 6151.4 6161.1 7246.9 7263.3 7294.1 7315.3 7365.4 6619.6 6627.2 6699.5 6743.7 6799.5 6478.6 6507.5 6554.0 6605.2 6685.8 6379.7 6394.9 6401.8 6409.2 6415.6 6001.5 6003.7 6016.9 6091.5 6097.4 5548.1 5595.7 5596.0 5605.7 5611.5 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#4] prototype.html Cycles:180 Average:5502.23 Median:6258.33 stddev:1685.70 (26.9%) stddev-sans-first:1645.15 22:14:00 INFO - PROCESS | 17823 | Values: 332.0 338.6 342.6 346.1 350.0 6096.2 6147.0 6196.8 6239.3 6256.7 5966.6 6056.7 6058.7 6099.5 6162.8 4702.4 4711.3 4716.0 4720.1 4720.9 4691.8 4725.5 4732.2 4741.3 4742.7 4518.8 4620.7 4624.8 4628.2 4684.7 4570.7 4614.1 4623.1 4650.7 4663.2 3975.7 4030.7 4055.0 4061.4 4069.0 5865.0 5888.7 5894.1 5921.1 5931.5 5395.5 5398.2 5403.8 5404.9 5412.3 6446.5 6450.7 6494.4 6505.2 6511.5 4353.8 4467.2 4474.9 4476.4 4481.5 6724.6 6797.3 6865.5 6867.8 6886.1 4592.6 4594.2 4601.4 4608.5 4610.4 4500.8 4654.6 4660.3 4665.5 4671.1 5389.1 5397.1 5403.5 5409.2 5411.8 6552.8 6603.7 6606.6 6619.5 6630.1 1633.6 1650.4 1654.6 1658.6 1660.6 1086.0 1090.3 1091.7 1097.5 1124.0 6260.0 6303.4 6310.5 6315.1 6330.4 6790.2 6805.3 6814.0 6818.6 6822.8 6159.9 6294.3 6310.0 6312.2 6318.1 3701.4 3711.4 3728.6 3732.4 3739.0 7011.0 7108.7 7140.1 7227.9 7235.6 6930.6 6973.5 7020.4 7035.2 7037.8 7039.3 7147.9 7177.5 7203.2 7213.1 6886.1 6894.5 6896.2 6901.3 6904.9 6776.9 6805.9 6808.3 6817.1 6818.8 7069.6 7170.0 7194.5 7201.2 7204.4 4686.7 4738.7 4741.7 4745.7 4757.9 6783.7 6788.7 6799.9 6873.7 6895.9 6513.8 6616.0 6627.4 6631.2 6636.0 6605.1 6609.6 6618.8 6619.2 6627.5 6492.2 6510.6 6529.6 6530.6 6544.8 6561.0 6660.7 6676.9 6678.7 6687.5 6375.6 6434.2 6507.3 6512.6 6519.7 22:14:00 INFO - PROCESS | 17823 | 22:14:00 INFO - PROCESS | 17823 | [#5] yui.html Cycles:180 Average:13147.08 Median:24814.68 stddev:15434.29 (62.2%) stddev-sans-first:15449.78 22:14:00 INFO - PROCESS | 17823 | Values: 809.6 819.4 819.7 820.7 834.1 12848.9 13015.8 13030.9 13043.7 13080.7 12175.0 12182.0 12182.1 12187.4 12201.5 635.4 645.5 763.7 770.5 772.0 767.3 769.6 769.9 771.3 772.3 799.6 800.2 802.2 808.5 808.6 786.6 791.1 794.7 800.7 806.9 593.0 593.6 594.0 595.0 596.4 12336.2 12418.2 12479.3 12483.1 12504.0 11308.2 11331.6 11477.4 11692.8 11845.8 9418.0 9664.4 9700.2 9715.8 9852.6 3515.8 3544.6 3595.6 3629.2 3681.8 45483.3 45618.3 45647.0 45814.5 45853.5 9002.8 9243.0 9335.5 9586.0 9621.7 18307.2 18454.4 18456.5 18465.4 18466.2 7234.4 7334.2 7382.6 7443.8 7645.7 4044.4 4090.9 4095.3 4103.3 4150.9 2928.5 2953.2 2959.2 2959.8 2963.7 502.5 526.5 528.0 528.2 532.4 514.1 587.5 602.5 657.1 688.2 7584.1 7747.3 7945.2 8227.2 8310.0 485.4 542.5 591.4 675.4 693.7 3600.9 3686.6 3805.2 3946.4 4014.7 52929.7 58389.1 58404.0 58481.7 58657.7 49144.0 50215.2 50257.2 50317.7 50531.5 43686.8 44169.6 44310.6 44595.6 44812.6 8029.8 8033.2 8182.2 8233.1 8316.3 31026.5 31970.0 32714.8 33265.8 33334.0 38464.8 39266.2 39771.0 45398.3 45662.8 10730.1 10764.5 10813.2 10817.4 10897.3 10628.6 10728.1 10793.4 10814.8 11162.7 10178.8 10217.3 10478.5 10592.9 10607.8 10045.9 10341.2 10354.2 10434.8 10688.3 10026.3 10390.8 10428.1 10569.5 10722.7 8843.9 8862.0 9037.7 9079.8 9317.0 8408.5 8634.7 9173.3 9232.5 9262.0 22:14:00 INFO - PROCESS | 17823 | -------- Summary: end -------- 22:14:00 INFO - PROCESS | 17823 | 22:14:01 INFO - TEST-INFO | 17823: exit 0 22:14:01 INFO - TEST-OK | dromaeo_css | took 1110781ms 22:14:01 INFO - TEST-START | kraken 22:14:01 INFO - operating with platform_type : linux_ 22:14:01 INFO - Initialising browser for kraken test... 22:14:01 INFO - TEST-INFO | started process 18068 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgq5iId/profile http://localhost:35483/getInfo.html) 22:14:06 INFO - PROCESS | 18068 | __metrics Screen width/height:1600/1200 22:14:06 INFO - PROCESS | 18068 | colorDepth:24 22:14:06 INFO - PROCESS | 18068 | Browser inner width/height: 1024/697 22:14:06 INFO - PROCESS | 18068 | __metrics 22:14:06 INFO - PROCESS | 18068 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:14:07 INFO - TEST-INFO | 18068: exit 0 22:14:07 INFO - Browser initialized. 22:14:07 INFO - Running cycle 1/1 for kraken test... 22:14:07 INFO - TEST-INFO | started process 18191 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgq5iId/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:19 INFO - PROCESS | 18191 | RSS: Main: 150413312 22:14:19 INFO - PROCESS | 18191 | 22:14:21 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35483/tests/kraken/driver.html?testName=audio-beat-detection) 22:14:22 INFO - PROCESS | 18191 | RSS: Main: 151736320 22:14:22 INFO - PROCESS | 18191 | 22:14:24 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35483/tests/kraken/driver.html?testName=audio-dft) 22:14:25 INFO - PROCESS | 18191 | RSS: Main: 151605248 22:14:25 INFO - PROCESS | 18191 | 22:14:28 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35483/tests/kraken/driver.html?testName=audio-fft) 22:14:28 INFO - PROCESS | 18191 | RSS: Main: 145731584 22:14:28 INFO - PROCESS | 18191 | 22:14:31 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35483/tests/kraken/driver.html?testName=audio-oscillator) 22:14:31 INFO - PROCESS | 18191 | RSS: Main: 146534400 22:14:31 INFO - PROCESS | 18191 | 22:14:33 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35483/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:14:34 INFO - PROCESS | 18191 | RSS: Main: 146345984 22:14:34 INFO - PROCESS | 18191 | 22:14:37 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35483/tests/kraken/driver.html?testName=imaging-darkroom) 22:14:38 INFO - PROCESS | 18191 | RSS: Main: 145657856 22:14:38 INFO - PROCESS | 18191 | 22:14:41 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35483/tests/kraken/driver.html?testName=imaging-desaturate) 22:14:42 INFO - PROCESS | 18191 | RSS: Main: 147890176 22:14:42 INFO - PROCESS | 18191 | 22:14:44 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35483/tests/kraken/driver.html?testName=json-parse-financial) 22:14:45 INFO - PROCESS | 18191 | RSS: Main: 148328448 22:14:45 INFO - PROCESS | 18191 | 22:14:47 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35483/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:14:47 INFO - PROCESS | 18191 | RSS: Main: 148402176 22:14:47 INFO - PROCESS | 18191 | 22:14:49 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:14:50 INFO - PROCESS | 18191 | RSS: Main: 149078016 22:14:50 INFO - PROCESS | 18191 | 22:14:52 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:14:52 INFO - PROCESS | 18191 | RSS: Main: 149204992 22:14:52 INFO - PROCESS | 18191 | 22:14:55 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:14:55 INFO - PROCESS | 18191 | RSS: Main: 148348928 22:14:55 INFO - PROCESS | 18191 | 22:14:58 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:14:59 INFO - PROCESS | 18191 | RSS: Main: 148561920 22:14:59 INFO - PROCESS | 18191 | 22:15:00 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:35483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35483/tests/kraken/driver.html?testName=ai-astar) 22:15:01 INFO - PROCESS | 18191 | RSS: Main: 149499904 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | __start_tp_report 22:15:01 INFO - PROCESS | 18191 | _x_x_mozilla_page_load 22:15:01 INFO - PROCESS | 18191 | _x_x_mozilla_page_load_details 22:15:01 INFO - PROCESS | 18191 | |i|pagename|runs| 22:15:01 INFO - PROCESS | 18191 | |0;ai-astar;114;106;111;111;112;121;110;110;114;110 22:15:01 INFO - PROCESS | 18191 | |1;audio-beat-detection;160;129;153;166;128;157;149;127;165;129 22:15:01 INFO - PROCESS | 18191 | |2;audio-dft;164;164;162;163;161;164;162;169;169;163 22:15:01 INFO - PROCESS | 18191 | |3;audio-fft;75;72;72;73;75;73;74;130;73;71 22:15:01 INFO - PROCESS | 18191 | |4;audio-oscillator;96;98;142;96;96;95;143;96;97;144 22:15:01 INFO - PROCESS | 18191 | |5;imaging-gaussian-blur;109;110;110;110;110;111;110;110;110;110 22:15:01 INFO - PROCESS | 18191 | |6;imaging-darkroom;148;147;148;147;149;147;148;147;147;147 22:15:01 INFO - PROCESS | 18191 | |7;imaging-desaturate;96;96;97;96;96;96;96;97;96;95 22:15:01 INFO - PROCESS | 18191 | |8;json-parse-financial;60;59;60;58;60;60;60;61;58;58 22:15:01 INFO - PROCESS | 18191 | |9;json-stringify-tinderbox;67;68;67;68;68;70;68;68;68;68 22:15:01 INFO - PROCESS | 18191 | |10;stanford-crypto-aes;86;86;87;87;86;85;87;85;90;85 22:15:01 INFO - PROCESS | 18191 | |11;stanford-crypto-ccm;121;117;127;130;139;124;131;121;128;126 22:15:01 INFO - PROCESS | 18191 | |12;stanford-crypto-pbkdf2;145;157;156;156;158;148;154;153;164;146 22:15:01 INFO - PROCESS | 18191 | |13;stanford-crypto-sha256-iterative;72;69;68;71;72;70;72;69;68;71 22:15:01 INFO - PROCESS | 18191 | __end_tp_report 22:15:01 INFO - PROCESS | 18191 | __start_cc_report 22:15:01 INFO - PROCESS | 18191 | _x_x_mozilla_cycle_collect,753 22:15:01 INFO - PROCESS | 18191 | __end_cc_report 22:15:01 INFO - PROCESS | 18191 | __startTimestamp1451801701101__endTimestamp 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | ------- Summary: start ------- 22:15:01 INFO - PROCESS | 18191 | Number of tests: 14 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:3.93 (3.5%) stddev-sans-first:4.09 22:15:01 INFO - PROCESS | 18191 | Values: 114.0 106.0 111.0 111.0 112.0 121.0 110.0 110.0 114.0 110.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:155.00 stddev:16.32 (10.5%) stddev-sans-first:16.54 22:15:01 INFO - PROCESS | 18191 | Values: 160.0 129.0 153.0 166.0 128.0 157.0 149.0 127.0 165.0 129.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.77 (1.7%) stddev-sans-first:2.93 22:15:01 INFO - PROCESS | 18191 | Values: 164.0 164.0 162.0 163.0 161.0 164.0 162.0 169.0 169.0 163.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#3] audio-fft Cycles:10 Average:78.80 Median:73.00 stddev:18.04 (24.7%) stddev-sans-first:19.08 22:15:01 INFO - PROCESS | 18191 | Values: 75.0 72.0 72.0 73.0 75.0 73.0 74.0 130.0 73.0 71.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:96.00 stddev:22.58 (23.5%) stddev-sans-first:23.35 22:15:01 INFO - PROCESS | 18191 | Values: 96.0 98.0 142.0 96.0 96.0 95.0 143.0 96.0 97.0 144.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 22:15:01 INFO - PROCESS | 18191 | Values: 109.0 110.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 110.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 22:15:01 INFO - PROCESS | 18191 | Values: 148.0 147.0 148.0 147.0 149.0 147.0 148.0 147.0 147.0 147.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:15:01 INFO - PROCESS | 18191 | Values: 96.0 96.0 97.0 96.0 96.0 96.0 96.0 97.0 96.0 95.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:60.00 stddev:1.07 (1.8%) stddev-sans-first:1.12 22:15:01 INFO - PROCESS | 18191 | Values: 60.0 59.0 60.0 58.0 60.0 60.0 60.0 61.0 58.0 58.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 22:15:01 INFO - PROCESS | 18191 | Values: 67.0 68.0 67.0 68.0 68.0 70.0 68.0 68.0 68.0 68.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.50 stddev:1.51 (1.7%) stddev-sans-first:1.59 22:15:01 INFO - PROCESS | 18191 | Values: 86.0 86.0 87.0 87.0 86.0 85.0 87.0 85.0 90.0 85.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:127.50 stddev:6.22 (4.9%) stddev-sans-first:6.28 22:15:01 INFO - PROCESS | 18191 | Values: 121.0 117.0 127.0 130.0 139.0 124.0 131.0 121.0 128.0 126.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:156.00 stddev:5.91 (3.8%) stddev-sans-first:5.36 22:15:01 INFO - PROCESS | 18191 | Values: 145.0 157.0 156.0 156.0 158.0 148.0 154.0 153.0 164.0 146.0 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - PROCESS | 18191 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:71.00 stddev:1.62 (2.3%) stddev-sans-first:1.58 22:15:01 INFO - PROCESS | 18191 | Values: 72.0 69.0 68.0 71.0 72.0 70.0 72.0 69.0 68.0 71.0 22:15:01 INFO - PROCESS | 18191 | -------- Summary: end -------- 22:15:01 INFO - PROCESS | 18191 | 22:15:01 INFO - TEST-INFO | 18191: exit 0 22:15:02 INFO - TEST-OK | kraken | took 60545ms 22:15:02 INFO - TEST-START | v8_7 22:15:02 INFO - operating with platform_type : linux_ 22:15:02 INFO - Initialising browser for v8_7 test... 22:15:02 INFO - TEST-INFO | started process 18478 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfhLW6Z/profile http://localhost:35483/getInfo.html) 22:15:06 INFO - PROCESS | 18478 | __metrics Screen width/height:1600/1200 22:15:06 INFO - PROCESS | 18478 | colorDepth:24 22:15:06 INFO - PROCESS | 18478 | Browser inner width/height: 1024/697 22:15:06 INFO - PROCESS | 18478 | __metrics 22:15:06 INFO - PROCESS | 18478 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:15:07 INFO - TEST-INFO | 18478: exit 0 22:15:07 INFO - Browser initialized. 22:15:07 INFO - Running cycle 1/1 for v8_7 test... 22:15:07 INFO - TEST-INFO | started process 18602 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfhLW6Z/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:19 INFO - PROCESS | 18602 | RSS: Main: 150331392 22:15:19 INFO - PROCESS | 18602 | 22:15:40 INFO - PROCESS | 18602 | Cycle 1(1): loaded http://localhost:35483/tests/v8_7/run.html (next: http://localhost:35483/tests/v8_7/run.html) 22:15:41 INFO - PROCESS | 18602 | RSS: Main: 145416192 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | __start_tp_report 22:15:41 INFO - PROCESS | 18602 | _x_x_mozilla_page_load 22:15:41 INFO - PROCESS | 18602 | _x_x_mozilla_page_load_details 22:15:41 INFO - PROCESS | 18602 | |i|pagename|runs| 22:15:41 INFO - PROCESS | 18602 | |0;Richards;167.44809109176154 22:15:41 INFO - PROCESS | 18602 | |1;DeltaBlue;190.36740909956217 22:15:41 INFO - PROCESS | 18602 | |2;Encrypt;281.37310073157005 22:15:41 INFO - PROCESS | 18602 | |3;Decrypt;5246.073298429319 22:15:41 INFO - PROCESS | 18602 | |4;RayTrace;1077.5862068965516 22:15:41 INFO - PROCESS | 18602 | |5;Earley;717.3601147776184 22:15:41 INFO - PROCESS | 18602 | |6;Boyer;12345.67901234568 22:15:41 INFO - PROCESS | 18602 | |7;RegExp;25600 22:15:41 INFO - PROCESS | 18602 | |8;Splay;689.6787423103212 22:15:41 INFO - PROCESS | 18602 | |9;NavierStokes;4835.748792270531 22:15:41 INFO - PROCESS | 18602 | __end_tp_report 22:15:41 INFO - PROCESS | 18602 | __startTimestamp1451801741173__endTimestamp 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | ------- Summary: start ------- 22:15:41 INFO - PROCESS | 18602 | Number of tests: 10 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#0] Richards Cycles:1 Average:167.45 Median:167.45 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 167.4 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#1] DeltaBlue Cycles:1 Average:190.37 Median:190.37 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 190.4 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#2] Encrypt Cycles:1 Average:281.37 Median:281.37 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 281.4 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 5246.1 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#4] RayTrace Cycles:1 Average:1077.59 Median:1077.59 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 1077.6 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#5] Earley Cycles:1 Average:717.36 Median:717.36 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 717.4 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 12345.7 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#7] RegExp Cycles:1 Average:25600.00 Median:25600.00 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 25600.0 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#8] Splay Cycles:1 Average:689.68 Median:689.68 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 689.7 22:15:41 INFO - PROCESS | 18602 | 22:15:41 INFO - PROCESS | 18602 | [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 22:15:41 INFO - PROCESS | 18602 | Values: 4835.7 22:15:41 INFO - PROCESS | 18602 | -------- Summary: end -------- 22:15:41 INFO - PROCESS | 18602 | 22:15:42 INFO - TEST-INFO | 18602: exit 0 22:15:42 INFO - TEST-OK | v8_7 | took 40021ms 22:15:42 INFO - SUITE-END | took 1211s 22:15:42 INFO - Completed test suite (00:20:11) 22:15:42 INFO - Working with test: dromaeo_css 22:15:42 INFO - Generating results file: dromaeo_css 22:15:42 INFO - Working with test: kraken 22:15:42 INFO - Generating results file: kraken 22:15:42 INFO - javascript benchmark 22:15:42 INFO - Working with test: v8_7 22:15:42 INFO - Generating results file: v8_7 22:15:42 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:15:42 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 22:15:42 INFO - process_Request line: dromaeo_css 6765.78 graph.html#tests=[[72,1,43]] 22:15:42 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:15:42 INFO - process_Request line: kraken 1529.10 graph.html#tests=[[232,1,43]] 22:15:42 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:15:42 INFO - process_Request line: v8_7 20947.86 graph.html#tests=[[230,1,43]] 22:15:42 INFO - RETURN: dromaeo_css: 6765.78 22:15:42 INFO - RETURN: kraken: 1529.10 22:15:42 INFO - RETURN: v8_7: 20947.86 22:15:42 INFO - javascript benchmark 22:15:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3141.32461520773, 3191.0586377018776, 3222.822744749038, 3232.224266176117, 3237.41726489232, 5270.446603106676, 5381.165919282513, 5384.138537833947, 5397.271368365269, 5429.049916264653, 5365.919511207335, 5379.515843574078, 5387.78448862046, 5394.083005888999, 5412.809181979968, 4905.460399356077, 4935.876603084916, 4973.129702302101, 4989.975050124763, 5031.52200540948, 5002.074616196015, 5015.047141043208, 5054.469280725508, 5056.241563765428, 5058.519440653132, 4831.9033619327765, 4885.389326334208, 4990.925136122943, 4999.3000979862845, 5010.323606313137, 4982.352294201767, 4984.750762461862, 4992.500749924979, 4997.725125118117, 5043.747812609392, 4780.617550595944, 4974.204127339644, 4982.327385802906, 4986.326845875797, 5014.774335154927, 5128.615353848476, 5175.249588809602, 5196.350456192976, 5226.451222621646, 5229.660072095302, 5801.7099145043, 5806.88386232273, 5820.5634577406445, 5822.301323841124, 5823.184754134425, 5693.089105743061, 5818.5345172386105, 5866.325372582165, 5875.265591801025, 5885.382034886318, 5434.184872269152, 5514.255575497336, 5524.281843360378, 5533.142362933751, 5568.832935011957, 5985.6408615483215, 6012.158297838306, 6016.037434010537, 6017.819465416044, 6018.337982821886, 5623.690697011703, 5694.800681976111, 5744.080947048452, 5746.856328591735, 5752.338481074647, 5862.70686465675, 5862.912056319158, 5867.530597552186, 5876.236089308362, 5899.115132730125, 5759.798407055733, 5817.069268916953, 5826.184334193216, 5830.154627579062, 5844.59087863854, 5672.205891175238, 5749.310082790092, 5779.508741756998, 5788.160716696139, 5875.441833025856, 4489.932651010237, 4495.415595972503, 4496.595306422438, 4506.188886000551, 4528.0943811237885, 3984.2230765000545, 3997.200559887976, 3997.2605068062503, 4039.3940908863906, 4053.4730485036757, 5215.426303106655, 5379.300690910157, 5462.262594549765, 5528.695921724263, 5550.333959924835, 5804.709764511757, 5813.069908814569, 5822.7088645567555, 5858.941410585925, 5861.794837180678, 5407.107827208467, 5492.203630473559, 5498.9826882026955, 5499.697516636624, 5512.173174023928, 5009.223570346602, 5022.196448568212, 5032.194848824171, 5038.244263360525, 5040.042391945519, 6029.698515074228, 6061.696915154224, 6082.0, 6091.390860913962, 6110.938890611126, 5814.970925145347, 5847.678377689098, 5856.238688970407, 5875.089361149029, 5897.351291357862, 5902.557308201816, 6003.399660034136, 6066.211392518944, 6079.513638908965, 6120.969395152996, 5723.170140329712, 5749.482546570829, 5750.367459579359, 5788.0, 5797.391273916178, 5804.9709751452665, 5807.419258074327, 5811.535077193899, 5814.854628634149, 5826.41735826414, 5592.496675299242, 5663.518600918803, 5678.7728490861555, 5680.11958146488, 5700.486956173799, 5612.607117501736, 5722.141678748055, 5735.3977832327, 5752.165935939349, 5780.132980053026, 5600.467955544217, 5602.52378547828, 5641.097424412074, 5645.887082258414, 5663.490285874291, 5633.577481688972, 5709.65742055478, 5751.194832723506, 5756.856078597901, 5765.106408506688, 5618.9604923089355, 5689.914651280152, 5693.117566777156, 5702.800401985911, 5728.427157284238], "unit": "score", "name": "dojo.html", "value": 5373.697349801786}, {"lowerIsBetter": false, "replicates": [539.0620320642084, 541.2422608348312, 560.8950367775482, 562.5274769193876, 577.5408550202588, 34834.65165348349, 35557.2888542229, 35754.642453575456, 35809.820950895206, 35977.46033809495, 38287.0, 38599.0, 38707.61292387075, 38963.610363896354, 39139.804300978525, 8467.280281176092, 8640.351973601973, 8689.869651955225, 8690.261327787146, 8694.434861733967, 8261.049979252402, 8353.707620233294, 8364.832703345959, 8460.0, 8518.23335899769, 8592.269657079156, 8658.567071646425, 8737.912620873774, 8741.213290803826, 8753.643185306257, 8665.436746611482, 8716.346274029473, 8741.912580874237, 8742.0, 8767.0, 8437.493750374935, 8559.786005349853, 8560.186782255676, 8563.272121869648, 8587.785305367415, 38584.61415385838, 38750.0, 38829.41755873664, 38956.61043389587, 38993.61006389928, 3116.298832762633, 3190.8564114614665, 3429.0, 3744.812759362021, 3749.1189570450924, 28594.857025714948, 28829.85585072061, 28848.134555963356, 29018.41963160746, 29729.108126756233, 3387.6781705737917, 3443.5695538057744, 3455.0844026333043, 3526.0303416560396, 3574.982125089358, 192700.0, 193196.0, 193519.0, 193776.0, 366484.9999999973, 47627.285590716165, 59753.70123149444, 60267.69866150641, 60704.392956069874, 60725.39274607286, 52588.21117683238, 53108.0, 53235.201471977954, 53542.732286339095, 53569.0, 13894.93052534751, 13998.44006239739, 14008.649783755487, 14025.368858401396, 14064.945129115153, 10561.207909406825, 10752.408617525954, 10755.677329680122, 10784.299020563638, 10788.136949044214, 18729.81270187308, 18801.247950081844, 19477.80522194788, 19481.415557533295, 19545.804541954683, 7074.823129421806, 7169.139703235645, 7199.928000719926, 7289.744858929912, 7371.6682749276415, 876.8509353409936, 985.9260555458369, 1046.4819914142397, 1055.5250137438184, 1075.6934273732106, 28229.71770282271, 28446.862125515163, 28455.857720711265, 29041.128766137048, 29187.416251675273, 1032.2877214721818, 1032.2980373346047, 1042.0777611813448, 1051.8685164354456, 1052.4579841381694, 13864.376103075387, 13879.583612491642, 13973.79039314411, 14023.368948397347, 14040.508582199574, 313771.0, 315633.0, 316212.0, 317033.0, 317268.0, 49798.50201498011, 50021.749891250314, 50466.0, 50568.0, 50727.239091413656, 1330.1686445971268, 1345.0382976172084, 1347.407140858039, 1366.419271809477, 1370.8149399830895, 27110.1866943992, 28068.438631226854, 28258.85870570634, 28356.858215708788, 29276.853615731787, 42836.78581607072, 42967.355489668305, 43245.0, 43333.0, 43493.78253108714, 236576.0, 236899.0, 237378.0, 238129.0, 238481.0, 27291.59062614102, 27899.44201116007, 30604.693953061076, 30967.225819354695, 31077.844610776803, 37237.255254895295, 37500.62499374971, 38091.80954095212, 38220.42669359906, 38254.42618360782, 33685.326293474485, 34298.65701342955, 34964.650353497156, 35165.0, 35384.64615353814, 33578.832105840294, 34726.65273347234, 35157.29685406226, 35754.0, 36372.454413183295, 32326.676733233307, 32696.836515817267, 32792.672073279915, 33050.0, 35235.0, 27233.0, 27356.58965115485, 29204.561931570617, 29275.56086658659, 31009.6814662642, 22596.209132680277, 22600.208992685177, 22874.885625571766, 22944.19695310723, 24023.0], "unit": "score", "name": "ext.html", "value": 17498.90697073302}, {"lowerIsBetter": false, "replicates": [354.7392666390203, 356.3496618670923, 360.47190865382214, 361.10806308418216, 361.4271379862917, 16001.919990400047, 16202.270897809598, 16748.330066797345, 17361.392351267707, 17785.7332140018, 10819.945900270488, 10847.186461015415, 11057.336559806417, 11061.834072488899, 11111.666650000492, 4841.176999910015, 4910.705357678533, 5008.148614735485, 5027.371578552681, 5033.974830125844, 4923.286123512089, 4960.107180707471, 4960.67755595887, 4966.627502937295, 4970.577500912409, 4934.5312195341385, 4948.752562371867, 4957.702537847723, 5075.796968121271, 5247.370315562119, 5140.614453915952, 5142.562882155022, 5159.58723302135, 5166.509181627739, 5168.121419358718, 4999.500049995007, 5191.506806853343, 5228.92156617651, 5273.604479664041, 5277.525022747971, 11872.881271187263, 12058.457369418396, 12118.575849845301, 12906.548270810477, 12991.0, 1943.0, 1987.8509111816527, 1995.0523501336893, 2108.378028481586, 2109.9683504747286, 9193.586288617029, 9241.537923103819, 9253.491057991807, 9295.395799273025, 9402.0, 1615.6203292226317, 1617.490490495502, 1626.9507059697796, 1628.7964004499438, 1656.892302000366, 59410.0, 61686.69156654233, 62771.686141569, 62776.0, 63085.05372419417, 10238.846417303746, 10332.845007324897, 10353.119984801226, 10354.223433242538, 10380.896191038144, 13497.19016858972, 13657.385417656229, 13666.93166534161, 13676.863231367559, 13687.657808554866, 7302.671379787922, 7307.269273072757, 7320.890186647204, 7443.106827180773, 7581.848363032708, 6903.965480172667, 6919.930800692029, 6927.064846245692, 6959.756408525677, 6964.268751781091, 4383.07955329378, 4428.4243048403505, 4470.932936005963, 4476.462824461086, 4486.887827804331, 1763.5679258581729, 1802.2880962019929, 1810.2577943043289, 1813.4468986959105, 1813.8458231050508, 437.62145743931904, 444.2381316043002, 899.9685011024714, 979.6669132494986, 997.6258902911409, 10554.525046372932, 10869.239153259196, 11070.39112848801, 11094.44527773608, 11142.610008649659, 446.06994416641663, 941.228192881844, 976.3897564022486, 983.8770153730784, 1004.7086344960026, 8900.599473023729, 8947.686830961014, 8968.955155224312, 8991.77520561978, 9114.13415725505, 565875.0, 571018.0, 571591.0, 571744.0, 603112.0, 37721.62278377181, 37881.81059094687, 37944.241115177545, 76713.23286767173, 80848.59575702084, 1138.260130914912, 1150.3672979861044, 1150.9827352589718, 1161.0537412009185, 1162.5001249462644, 14225.786613201017, 14366.0, 14458.493952711604, 14465.855341446451, 14608.78086828677, 30294.091177264716, 30774.538381924736, 30986.380272393973, 31108.844455778482, 32501.512477313332, 36248.0, 37436.812815935744, 37681.62318376781, 38682.22635547329, 40133.79933100316, 9271.351005429826, 9359.85960210611, 9368.859467108136, 9411.341206115507, 9510.09654082861, 11028.28316159447, 16076.598085047968, 16984.57538561546, 17296.827031730027, 17318.567035824206, 13006.674833129247, 13096.476140954255, 13383.0, 13852.861471385157, 13923.23422211788, 13939.0, 13969.231692256617, 14181.574552763434, 14670.486532971296, 14820.851791482379, 14001.22993235382, 14026.438942442188, 14277.07259519993, 14370.49703260381, 14499.347529361416, 8953.865692014495, 8955.41789783662, 8971.147740964598, 9173.816523669355, 9786.266030047633, 9881.752956176153, 11610.825837612612, 13349.665033496573, 13932.0, 13996.0], "unit": "score", "name": "jquery.html", "value": 8120.586931172509}, {"lowerIsBetter": false, "replicates": [1444.7543917534017, 1454.2946670864633, 1586.159335552157, 1717.5276798880298, 1750.4661078371091, 7108.857822843546, 7230.783076507707, 7253.782386528406, 7291.671874765635, 7403.9629801850915, 7414.814629634248, 7487.812804679886, 7533.84932301355, 7544.773656790305, 7558.508696934696, 2212.822974162063, 2226.532428190082, 2246.8539544929527, 2247.3932038349635, 2248.0, 2252.7296724393013, 2257.3002369265582, 2326.0230703104735, 2327.522857814144, 2327.7555856635063, 2212.723409573803, 2212.8451008429415, 2236.474428509299, 2236.809871160953, 2254.797068263856, 2230.966535501969, 2274.783895529922, 2275.4425165834477, 2276.704028476304, 2280.897359618821, 2188.6498160294436, 2237.4518243030398, 2244.5623103494827, 2245.1356227852393, 2247.3145690564534, 7295.416366690652, 7301.780946571611, 7369.189389167187, 7369.4841361104745, 7399.70401183952, 4323.978380108079, 4365.0, 4414.624756895668, 4447.533009034038, 4452.421185245929, 2883.5098033334384, 2898.6521617405833, 2969.049904030711, 2982.0606508949613, 2997.8501074946166, 900.3472482450237, 920.746794631475, 925.6251218256617, 934.5794392523323, 939.8449255872773, 9643.324967252294, 9676.080772326617, 9696.321257511978, 9715.951420242924, 9727.513624318755, 3924.803759811998, 4012.6188012139382, 4113.197926404382, 4176.0, 4200.096979149497, 7934.603269836485, 8049.356051515864, 8063.314618257514, 8078.071021832447, 8102.39232057598, 4454.131444368382, 4496.57282558163, 4499.6400287976885, 4521.253993091103, 4531.252343363374, 4123.422720819088, 4125.0, 4177.895552611149, 4183.330667093312, 4186.225548273579, 3270.4930735735998, 3303.3393321335348, 3337.215754297738, 3345.765796394278, 3354.0, 1003.799240151958, 1005.9597616095274, 1006.9194464442827, 1007.7228762090484, 1013.8428543575758, 1031.742064483879, 1058.0847566854616, 1062.3997441445558, 1100.4937728644913, 1221.6029790318182, 6859.416949559343, 6917.273686262971, 6926.0, 6926.099607051152, 6932.930670693329, 1097.314178638351, 1124.3310230412892, 1131.3777422417638, 1132.0830127596673, 1141.5662048421548, 5523.806666766724, 5580.525655319262, 5586.525145362609, 5608.10270356741, 5622.0, 8556.786080348042, 8638.352123590756, 8835.911640883509, 8892.066333035067, 8939.240164586083, 8747.825043499095, 8864.113588641085, 8872.512011839412, 8879.778005549784, 8892.911070889208, 6958.234594194634, 7050.92949070513, 7070.469714771412, 7081.89377159343, 7114.928850711427, 6795.694193761194, 6799.218089919723, 6835.4189893859575, 6849.554778939353, 6870.935005074221, 7751.418643601865, 7833.49082309648, 7907.327877130509, 7913.248241417056, 7946.602669866599, 7858.135605083322, 8113.472624279403, 8114.06688230861, 8116.594170291343, 8138.267555919759, 6034.034554471264, 6036.154938308729, 6129.049997250433, 6151.415615516519, 6161.106639537153, 7246.855062898818, 7263.346298832919, 7294.088238970129, 7315.341619254293, 7365.447591430557, 6619.569727967667, 6627.237867645283, 6699.5310328276555, 6743.718693448229, 6799.524033317621, 6478.578892371981, 6507.479401647762, 6554.049662798963, 6605.207375115017, 6685.76599819004, 6379.681015949277, 6394.90407643895, 6401.775937842153, 6409.198850143732, 6415.582987105823, 6001.489873360813, 6003.669798161143, 6016.909746353896, 6091.482224011009, 6097.359777223328, 5548.140038294071, 5595.664260144405, 5595.972020140036, 5605.691686957257, 5611.494965453129], "unit": "score", "name": "mootools.html", "value": 4251.525533673741}, {"lowerIsBetter": false, "replicates": [332.0453695625078, 338.5700160795789, 342.5717852684145, 346.1450217961634, 349.9807510586917, 6096.177016102824, 6146.969265153673, 6196.845078873025, 6239.251289845213, 6256.687165641711, 5966.642001479903, 6056.666883321415, 6058.666773327476, 6099.481544068748, 6162.784302549411, 4702.365180700596, 4711.269753188261, 4716.0, 4720.055988802236, 4720.858374248761, 4691.812327506913, 4725.527447255264, 4732.219183834681, 4741.288806679008, 4742.715437073769, 4518.774061296938, 4620.653450991173, 4624.838130665427, 4628.1669299526075, 4684.695494792844, 4570.748608826513, 4614.100250451163, 4623.144718227122, 4650.674452788307, 4663.230566956482, 3975.7018223633054, 4030.717849750519, 4054.9797251013556, 4061.390791381287, 4069.04377471294, 5864.970675146597, 5888.705564721747, 5894.056950887837, 5921.082232254007, 5931.525477961753, 5395.541378982791, 5398.190271459273, 5403.8108666196495, 5404.91892621611, 5412.269343638637, 6446.484281257535, 6450.741970321182, 6494.447971922392, 6505.154329937078, 6511.544191906569, 4353.847615333448, 4467.1735728889935, 4474.932876006863, 4476.395686582302, 4481.529439408848, 6724.630145342005, 6797.320267973163, 6865.485088618374, 6867.759628413031, 6886.10480637524, 4592.632589392841, 4594.218982772936, 4601.401817763669, 4608.5161058088415, 4610.40064791575, 4500.752458614807, 4654.604358629555, 4660.300954856731, 4665.486796452387, 4671.065786842577, 5389.056915039852, 5397.136458166753, 5403.5136837684795, 5409.161579955114, 5411.756470958816, 6552.770653027216, 6603.735850565923, 6606.636634985026, 6619.536632435779, 6630.1380820494, 1633.5752704296967, 1650.3646096253028, 1654.627708765518, 1658.6433916707965, 1660.593154677112, 1086.0, 1090.3294473898495, 1091.6670415523183, 1097.4622435006788, 1123.9775204495863, 6259.96870015647, 6303.369663033752, 6310.463610593151, 6315.052742088724, 6330.39861213184, 6790.219124800612, 6805.251422343538, 6813.965930170318, 6818.556793808387, 6822.829429264308, 6159.907601385893, 6294.276158241861, 6310.0, 6312.179416675803, 6318.115463835067, 3701.3522633539023, 3711.4432835074413, 3728.5898551159353, 3732.4401339799247, 3738.962610373916, 7011.0, 7108.68010939509, 7140.143182818095, 7227.927720722831, 7235.6020418876415, 6930.584164950018, 6973.476989225828, 7020.438364930793, 7035.1909530404655, 7037.753678621224, 7039.26087760788, 7147.928520714726, 7177.461690373243, 7203.243659415791, 7213.098362704557, 6886.10480637514, 6894.517383783288, 6896.241413444617, 6901.309869013058, 6904.896426553706, 6776.864462710817, 6805.8638827222185, 6808.319168083152, 6817.079694241413, 6818.829529261808, 7069.575825450489, 7169.964150179216, 7194.460415468756, 7201.2438693938475, 7204.387627051759, 4686.671932964796, 4738.691985020962, 4741.715497070187, 4745.715257084586, 4757.928631070606, 6783.694733736895, 6788.7284508619105, 6799.946008368711, 6873.725050997904, 6895.862082758417, 6513.772017979348, 6616.0, 6627.436667883284, 6631.171103612049, 6635.971424429221, 6605.1083103780375, 6609.603423794588, 6618.841702702104, 6619.205695316593, 6627.4698024158915, 6492.188476440445, 6510.641914694737, 6529.640869752209, 6530.575512591667, 6544.770933017512, 6561.0, 6660.700268487833, 6676.866462670622, 6678.732850686112, 6687.531872768859, 6375.5537112403545, 6434.227892652912, 6507.28419873823, 6512.5766825156215, 6519.7392104315295], "unit": "score", "name": "prototype.html", "value": 4951.5609240722}, {"lowerIsBetter": false, "replicates": [809.5668817182808, 819.3854609043213, 819.6680344460495, 820.7291593774052, 834.1325021977144, 12848.871511284884, 13015.804762928552, 13030.869691303084, 13043.739125217502, 13080.73838523229, 12175.0, 12182.0, 12182.147249692525, 12187.390630468486, 12201.450934707937, 635.4408120853642, 645.5416654175541, 763.7403282883819, 770.5261264322457, 772.0272456704564, 767.3209209849296, 769.5959621198888, 769.9153093159748, 771.3405038691893, 772.3357912195507, 799.5962039170211, 800.1638287989066, 802.1777677880191, 808.4946908182386, 808.5916612110905, 786.6027656033724, 791.1020991175029, 794.6900708723603, 800.6877317846045, 806.9435139540235, 593.0481577068786, 593.5637306579661, 594.0257976917858, 594.9881002379972, 596.3827438601071, 12336.198147120498, 12418.192817466836, 12479.31363774992, 12483.063770217179, 12504.0, 11308.151888608305, 11331.603393881174, 11477.426128693533, 11692.824607630891, 11845.763084738343, 9417.952910235404, 9664.420134791935, 9700.223982081487, 9715.805683886354, 9852.556634951445, 3515.753897227195, 3544.5746510418917, 3595.6404359564094, 3629.2197177606677, 3681.834317455721, 45483.31775023377, 45618.31572526415, 45647.0, 45814.54185458136, 45853.54146458559, 9002.819943601091, 9243.0, 9335.533223338942, 9586.0, 9621.663241786504, 18307.176177072062, 18454.446366609023, 18456.538586535444, 18465.35371262026, 18466.169022394024, 7234.421246300283, 7334.229905859915, 7382.5570465772225, 7443.7692838936855, 7645.694172233159, 4044.393340998815, 4090.897727556835, 4095.2628526864855, 4103.281925662997, 4150.91698166035, 2928.5167947288883, 2953.2321596385095, 2959.215807810932, 2959.8372089535283, 2963.688812674638, 502.5125628140698, 526.4630077321187, 527.9841604751864, 528.1997773373342, 532.3957308454932, 514.1490832671934, 587.5270407322147, 602.5053103845363, 657.1274003265826, 688.1535711075405, 7584.08990921093, 7747.30274275318, 7945.165757595485, 8227.177282271725, 8310.0, 485.3665965914515, 542.5036091975797, 591.4499515450672, 675.4056430341268, 693.7329128285646, 3600.891973240807, 3686.5576130863933, 3805.238952209569, 3946.4474973503734, 4014.7189696720948, 52929.735351323, 58389.12416313759, 58404.0, 58481.70759146177, 58657.70671146702, 49144.0, 50215.24677129846, 50257.24614130791, 50317.74841125771, 50531.49468505341, 43686.7815660926, 44169.55830441719, 44310.55689443064, 44595.55404445979, 44812.55187448149, 8029.799255018671, 8033.19668033192, 8182.22268884455, 8233.135520770233, 8316.293115085286, 31026.53460198144, 31970.0, 32714.836425817717, 33265.83367083149, 33334.0, 38464.80767596144, 39266.214675705756, 39771.0, 45398.31902521531, 45662.77168614136, 10730.051609756285, 10764.515596798006, 10813.24307298513, 10817.350958942488, 10897.346159230472, 10628.627998020032, 10728.088112510211, 10793.352398856094, 10814.783704326026, 11162.72093197644, 10178.796424071625, 10217.284790064625, 10478.528466219192, 10592.89407105919, 10607.840882386925, 10045.949770251102, 10341.193452841948, 10354.223433242387, 10434.843477347998, 10688.305260158066, 10026.348287361297, 10390.792184156426, 10428.113610343205, 10569.524371403459, 10722.678319650424, 8843.91156088431, 8861.955690221508, 9037.665253180345, 9079.773005674911, 9316.955368056908, 8408.537530435884, 8634.697785577726, 9173.266138709021, 9232.538373081454, 9261.978111671384], "unit": "score", "name": "yui.html", "value": 5966.366688517392}], "name": "dromaeo_css", "value": 6765.774264624373}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 106.0, 111.0, 111.0, 112.0, 121.0, 110.0, 110.0, 114.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [160.0, 129.0, 153.0, 166.0, 128.0, 157.0, 149.0, 127.0, 165.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 146.3}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 162.0, 163.0, 161.0, 164.0, 162.0, 169.0, 169.0, 163.0], "unit": "score", "name": "audio-dft", "value": 164.1}, {"lowerIsBetter": true, "replicates": [75.0, 72.0, 72.0, 73.0, 75.0, 73.0, 74.0, 130.0, 73.0, 71.0], "unit": "score", "name": "audio-fft", "value": 78.8}, {"lowerIsBetter": true, "replicates": [96.0, 98.0, 142.0, 96.0, 96.0, 95.0, 143.0, 96.0, 97.0, 144.0], "unit": "score", "name": "audio-oscillator", "value": 110.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 148.0, 147.0, 149.0, 147.0, 148.0, 147.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.5}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [60.0, 59.0, 60.0, 58.0, 60.0, 60.0, 60.0, 61.0, 58.0, 58.0], "unit": "score", "name": "json-parse-financial", "value": 59.4}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 68.0, 68.0, 70.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.0}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 87.0, 87.0, 86.0, 85.0, 87.0, 85.0, 90.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.4}, {"lowerIsBetter": true, "replicates": [121.0, 117.0, 127.0, 130.0, 139.0, 124.0, 131.0, 121.0, 128.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.4}, {"lowerIsBetter": true, "replicates": [145.0, 157.0, 156.0, 156.0, 158.0, 148.0, 154.0, 153.0, 164.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 153.7}, {"lowerIsBetter": true, "replicates": [72.0, 69.0, 68.0, 71.0, 72.0, 70.0, 72.0, 69.0, 68.0, 71.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken", "value": 1529.1000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.44809109176154], "unit": "score", "name": "Richards", "value": 210.82354399999997}, {"lowerIsBetter": false, "replicates": [190.36740909956217], "unit": "score", "name": "DeltaBlue", "value": 347.31785399999995}, {"lowerIsBetter": false, "replicates": [281.37310073157005], "unit": "score", "name": "Encrypt", "value": 946.0072739999998}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [1077.5862068965516], "unit": "score", "name": "RayTrace", "value": 686.7097920000001}, {"lowerIsBetter": false, "replicates": [717.3601147776184], "unit": "score", "name": "Earley", "value": 929.0494220000003}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [25600.0], "unit": "score", "name": "RegExp", "value": 35.58535156250002}, {"lowerIsBetter": false, "replicates": [689.6787423103212], "unit": "score", "name": "Splay", "value": 118.15791179385532}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 20947.857285023438}]} 22:15:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "20947.86"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1529.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6765.78"}}} 22:15:42 INFO - Return code: 0 22:15:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:15:42 INFO - # TBPL SUCCESS # 22:15:42 INFO - Running post-action listener: _resource_record_post_action 22:15:42 INFO - Running post-run listener: _resource_record_post_run 22:15:43 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 32768; Write bytes: 554217472; Read time: 408; Write time: 676996 22:15:43 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 4096; Write bytes: 235438080; Read time: 96; Write time: 442528 22:15:43 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 28672; Write bytes: 318779392; Read time: 312; Write time: 234468 22:15:43 INFO - Running post-run listener: _upload_blobber_files 22:15:43 INFO - Blob upload gear active. 22:15:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:15:43 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 22:15:43 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', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 22:15:43 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 Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 22:15:44 INFO - (blobuploader) - INFO - Open directory for files ... 22:15:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:15:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:15:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:44 INFO - (blobuploader) - INFO - Done attempting. 22:15:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:15:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:15:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:45 INFO - (blobuploader) - INFO - Done attempting. 22:15:45 INFO - (blobuploader) - INFO - Iteration through files over. 22:15:45 INFO - Return code: 0 22:15:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:15:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:15:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24756bbbcd02a33c2c0d69324555d2973eaedd39b2593277ea197976363664f54e3d8e874ddc5ce70574b22bea841e912ae8f5124e09e5d69c6cc768060a7fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623c80a6e7c934dff8c1d1fff9394d8aed152dd3870222eb7101feea2b17fccae67639ff7d0bd548b6a5038e5eaaf683fb810c2059a621a8ae0a7fe1722c69e2"} 22:15:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:15:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:15:45 INFO - Contents: 22:15:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24756bbbcd02a33c2c0d69324555d2973eaedd39b2593277ea197976363664f54e3d8e874ddc5ce70574b22bea841e912ae8f5124e09e5d69c6cc768060a7fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623c80a6e7c934dff8c1d1fff9394d8aed152dd3870222eb7101feea2b17fccae67639ff7d0bd548b6a5038e5eaaf683fb810c2059a621a8ae0a7fe1722c69e2"} 22:15:45 INFO - Copying logs to upload dir... 22:15:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.341688 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-02 22:15:45.608970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-02 22:15:45.613162) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24756bbbcd02a33c2c0d69324555d2973eaedd39b2593277ea197976363664f54e3d8e874ddc5ce70574b22bea841e912ae8f5124e09e5d69c6cc768060a7fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623c80a6e7c934dff8c1d1fff9394d8aed152dd3870222eb7101feea2b17fccae67639ff7d0bd548b6a5038e5eaaf683fb810c2059a621a8ae0a7fe1722c69e2"} build_url:https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011303 build_url: 'https://queue.taskcluster.net/v1/task/Ouzwny2jSkuKZKBx3yyDJg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24756bbbcd02a33c2c0d69324555d2973eaedd39b2593277ea197976363664f54e3d8e874ddc5ce70574b22bea841e912ae8f5124e09e5d69c6cc768060a7fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623c80a6e7c934dff8c1d1fff9394d8aed152dd3870222eb7101feea2b17fccae67639ff7d0bd548b6a5038e5eaaf683fb810c2059a621a8ae0a7fe1722c69e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-02 22:15:45.642635) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:15:45.642972) ========= 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=70a720fcaf18edea6683c2dc000001ad-1451796084.461742-861580980 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:15:45.725417) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-02 22:15:45.725746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-02 22:15:45.726101) ========= ========= Total master_lag: 0.28 =========