builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1451714124.53 results: success (0) buildid: 20160101193056 builduid: 2611955f65d34c7d99d3cb912d646f9f revision: ce643acfab14d95bea2fb6c4f56477413514b686 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.530257) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.530722) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.531073) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006386 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.557356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.557708) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.571818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.572184) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004648 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.621046) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.621395) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.621852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.622228) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-01 21:55:25-- 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% 60.4M=0s 2016-01-01 21:55:25 (60.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.177752 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.812451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.812785) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015467 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.862903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:24.863353) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ce643acfab14d95bea2fb6c4f56477413514b686 --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 ce643acfab14d95bea2fb6c4f56477413514b686 --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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-01 21:55:25,853 truncating revision to first 12 chars 2016-01-01 21:55:25,853 Setting DEBUG logging. 2016-01-01 21:55:25,854 attempt 1/10 2016-01-01 21:55:25,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ce643acfab14?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-01 21:55:26,393 unpacking tar archive at: mozilla-central-ce643acfab14/testing/mozharness/ program finished with exit code 0 elapsedTime=0.819745 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:25.696435) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:25.696752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:25.713697) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:25.713994) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-01 21:55:25.717194) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-01 21:55:25.717528) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:55:26 INFO - MultiFileLogger online at 20160101 21:55:26 in /builds/slave/test-pgo 21:55:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:55:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:55:26 INFO - {'append_to_log': False, 21:55:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:55:26 INFO - 'blob_upload_branch': 'Firefox', 21:55:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:55:26 INFO - 'branch': 'Firefox', 21:55:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:55:26 INFO - 'config_files': ('talos/linux_config.py',), 21:55:26 INFO - 'default_actions': ('clobber', 21:55:26 INFO - 'read-buildbot-config', 21:55:26 INFO - 'download-and-extract', 21:55:26 INFO - 'populate-webroot', 21:55:26 INFO - 'create-virtualenv', 21:55:26 INFO - 'install', 21:55:26 INFO - 'run-tests'), 21:55:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:55:26 INFO - 'download_minidump_stackwalk': True, 21:55:26 INFO - 'download_symbols': 'ondemand', 21:55:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:55:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:55:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:55:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:55:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:55:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:55:26 INFO - 'installer_path': 'installer.exe', 21:55:26 INFO - 'log_level': 'info', 21:55:26 INFO - 'log_name': 'talos', 21:55:26 INFO - 'log_to_console': True, 21:55:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:55:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:55:26 INFO - 'opt_config_files': (), 21:55:26 INFO - 'pip_index': False, 21:55:26 INFO - 'sps_profile': False, 21:55:26 INFO - 'sps_profile_interval': 0, 21:55:26 INFO - 'suite': 'dromaeojs', 21:55:26 INFO - 'system_bits': '32', 21:55:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:55:26 INFO - 'title': 'talos-linux64-ix-023', 21:55:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:55:26 INFO - 'use_talos_json': True, 21:55:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:55:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:55:26 INFO - 'work_dir': 'build'} 21:55:26 INFO - ##### 21:55:26 INFO - ##### Running clobber step. 21:55:26 INFO - ##### 21:55:26 INFO - Running pre-action listener: _resource_record_pre_action 21:55:26 INFO - Running main action method: clobber 21:55:26 INFO - rmtree: /builds/slave/test-pgo/build 21:55:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:55:27 INFO - Running post-action listener: _resource_record_post_action 21:55:27 INFO - ##### 21:55:27 INFO - ##### Running read-buildbot-config step. 21:55:27 INFO - ##### 21:55:27 INFO - Running pre-action listener: _resource_record_pre_action 21:55:27 INFO - Running main action method: read_buildbot_config 21:55:27 INFO - Using buildbot properties: 21:55:27 INFO - { 21:55:27 INFO - "project": "", 21:55:27 INFO - "product": "firefox", 21:55:27 INFO - "script_repo_revision": "production", 21:55:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:55:27 INFO - "repository": "", 21:55:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:55:27 INFO - "buildid": "20160101193056", 21:55:27 INFO - "pgo_build": "True", 21:55:27 INFO - "basedir": "/builds/slave/test-pgo", 21:55:27 INFO - "buildnumber": 627, 21:55:27 INFO - "slavename": "talos-linux64-ix-023", 21:55:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:55:27 INFO - "platform": "ubuntu64_hw", 21:55:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:55:27 INFO - "branch": "mozilla-central", 21:55:27 INFO - "repo_path": "mozilla-central", 21:55:27 INFO - "revision": "ce643acfab14d95bea2fb6c4f56477413514b686", 21:55:27 INFO - "stage_platform": "linux64-pgo", 21:55:27 INFO - "builduid": "2611955f65d34c7d99d3cb912d646f9f", 21:55:27 INFO - "slavebuilddir": "test" 21:55:27 INFO - } 21:55:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:55:27 INFO - Running post-action listener: _resource_record_post_action 21:55:27 INFO - ##### 21:55:27 INFO - ##### Running download-and-extract step. 21:55:27 INFO - ##### 21:55:27 INFO - Running pre-action listener: _resource_record_pre_action 21:55:27 INFO - Running main action method: download_and_extract 21:55:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:55:27 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:27 INFO - https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/test_packages.json 21:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/test_packages.json 21:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:55:29 INFO - Downloaded 1302 bytes. 21:55:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:55:29 INFO - Using the following test package requirements: 21:55:29 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:55:29 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:55:29 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'jsshell-linux-x86_64.zip'], 21:55:29 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:55:29 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:55:29 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:55:29 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:55:29 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:55:29 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:55:29 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:29 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:55:29 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 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 - https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:55:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:31 INFO - Downloaded 23197960 bytes. 21:55:31 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:55:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:31 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:55:32 INFO - Return code: 0 21:55:32 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:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:32 INFO - https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:33 INFO - Downloaded 23197960 bytes. 21:55:33 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:55:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:55:33 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:55:35 INFO - Return code: 0 21:55: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:55:35 INFO - https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:55:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:36 INFO - Downloaded 11132285 bytes. 21:55:36 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:55:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:36 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:55:37 INFO - Return code: 0 21:55:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:37 INFO - https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:55:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b6piR98XTxe6Y0HTdTeyBw/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:55:39 INFO - Downloaded 54985999 bytes. 21:55:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:39 INFO - mkdir: /builds/slave/test-pgo/properties 21:55:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:55:39 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:55:39 INFO - Contents: 21:55:39 INFO - build_url:https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:39 INFO - Running post-action listener: _resource_record_post_action 21:55:39 INFO - Running post-action listener: set_extra_try_arguments 21:55:39 INFO - ##### 21:55:39 INFO - ##### Running populate-webroot step. 21:55:39 INFO - ##### 21:55:39 INFO - Running pre-action listener: _resource_record_pre_action 21:55:39 INFO - Running main action method: populate_webroot 21:55:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:39 INFO - '--tppagecycles', 21:55:39 INFO - '7'], 21:55:39 INFO - 'tests': ['tsvgm']}}, 21:55:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:55:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:55:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:55:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'tests': ['damp', 'tps']}, 21:55:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['damp', 'tps']}, 21:55:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:55:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:39 INFO - 'other': {'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'other_l64': {'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'other_nol64': {'tests': ['a11yr', 21:55:39 INFO - 'ts_paint', 21:55:39 INFO - 'tpaint', 21:55:39 INFO - 'sessionrestore', 21:55:39 INFO - 'sessionrestore_no_auto_restore']}, 21:55:39 INFO - 'svgr': {'tests': ['tsvgx', 21:55:39 INFO - 'tsvgr_opacity', 21:55:39 INFO - 'tart', 21:55:39 INFO - 'tscrollx', 21:55:39 INFO - 'cart']}, 21:55:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['tsvgx', 21:55:39 INFO - 'tsvgr_opacity', 21:55:39 INFO - 'tart', 21:55:39 INFO - 'tscrollx', 21:55:39 INFO - 'cart']}, 21:55:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'tests': ['tp5o']}, 21:55:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'talos_options': ['--e10s'], 21:55:39 INFO - 'tests': ['tp5o']}, 21:55:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'talos_options': ['--xperf_path', 21:55:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:39 INFO - 'tests': ['tp5n']}, 21:55:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:39 INFO - 'talos_options': ['--e10s', 21:55:39 INFO - '--xperf_path', 21:55:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:39 INFO - 'tests': ['tp5n']}}, 21:55:39 INFO - 'talos.zip': {'path': '', 21:55:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:39 INFO - Running post-action listener: _resource_record_post_action 21:55:39 INFO - ##### 21:55:39 INFO - ##### Running create-virtualenv step. 21:55:39 INFO - ##### 21:55:39 INFO - Running pre-action listener: _resource_record_pre_action 21:55:39 INFO - Running main action method: create_virtualenv 21:55:39 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:55:39 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:55:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:55:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:39 INFO - Using real prefix '/usr' 21:55:39 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:55:40 INFO - Installing distribute.............................................................................................................................................................................................done. 21:55:41 INFO - Installing pip.................done. 21:55:41 INFO - Return code: 0 21:55:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:41 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:41 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:55:41 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:55:41 INFO - Using env: {'DISPLAY': ':0', 21:55:41 INFO - 'HOME': '/home/cltbld', 21:55:41 INFO - 'LANG': 'en_US.UTF-8', 21:55:41 INFO - 'LANGUAGE': 'en_US:en', 21:55:41 INFO - 'LOGNAME': 'cltbld', 21:55:41 INFO - 'MAIL': '/var/mail/cltbld', 21:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:41 INFO - 'MOZ_NO_REMOTE': '1', 21:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:41 INFO - 'NO_EM_RESTART': '1', 21:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:41 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:41 INFO - 'SHELL': '/bin/bash', 21:55:41 INFO - 'SHLVL': '1', 21:55:41 INFO - 'TERM': 'linux', 21:55:41 INFO - 'TMOUT': '86400', 21:55:41 INFO - 'USER': 'cltbld', 21:55:41 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:41 INFO - '_': '/tools/buildbot/bin/python'} 21:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:42 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:55:42 INFO - Cleaning up... 21:55:42 INFO - Return code: 0 21:55:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:55: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:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:42 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:55:42 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:55:42 INFO - Using env: {'DISPLAY': ':0', 21:55:42 INFO - 'HOME': '/home/cltbld', 21:55:42 INFO - 'LANG': 'en_US.UTF-8', 21:55:42 INFO - 'LANGUAGE': 'en_US:en', 21:55:42 INFO - 'LOGNAME': 'cltbld', 21:55:42 INFO - 'MAIL': '/var/mail/cltbld', 21:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:42 INFO - 'MOZ_NO_REMOTE': '1', 21:55:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:42 INFO - 'NO_EM_RESTART': '1', 21:55:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:42 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:42 INFO - 'SHELL': '/bin/bash', 21:55:42 INFO - 'SHLVL': '1', 21:55:42 INFO - 'TERM': 'linux', 21:55:42 INFO - 'TMOUT': '86400', 21:55:42 INFO - 'USER': 'cltbld', 21:55:42 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:42 INFO - '_': '/tools/buildbot/bin/python'} 21:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:42 INFO - Downloading/unpacking psutil>=0.7.1 21:55:42 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:42 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:42 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:42 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:42 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:42 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:44 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:55:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:55:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:55:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:44 INFO - Installing collected packages: psutil 21:55:44 INFO - Running setup.py install for psutil 21:55:44 INFO - building 'psutil._psutil_linux' extension 21:55:44 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:55:44 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:55:44 INFO - building 'psutil._psutil_posix' extension 21:55:44 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:55:44 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:55:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:44 INFO - Successfully installed psutil 21:55:44 INFO - Cleaning up... 21:55:44 INFO - Return code: 0 21:55:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:44 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:44 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:55:44 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:55:44 INFO - Using env: {'DISPLAY': ':0', 21:55:44 INFO - 'HOME': '/home/cltbld', 21:55:44 INFO - 'LANG': 'en_US.UTF-8', 21:55:44 INFO - 'LANGUAGE': 'en_US:en', 21:55:44 INFO - 'LOGNAME': 'cltbld', 21:55:44 INFO - 'MAIL': '/var/mail/cltbld', 21:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:44 INFO - 'MOZ_NO_REMOTE': '1', 21:55:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:44 INFO - 'NO_EM_RESTART': '1', 21:55:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:44 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:44 INFO - 'SHELL': '/bin/bash', 21:55:44 INFO - 'SHLVL': '1', 21:55:44 INFO - 'TERM': 'linux', 21:55:44 INFO - 'TMOUT': '86400', 21:55:44 INFO - 'USER': 'cltbld', 21:55:44 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:44 INFO - '_': '/tools/buildbot/bin/python'} 21:55:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:55:44 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:44 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:44 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:44 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:44 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:44 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:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:55:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:55:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:55:46 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:55:46 INFO - Installing collected packages: mozsystemmonitor 21:55:46 INFO - Running setup.py install for mozsystemmonitor 21:55:46 INFO - Successfully installed mozsystemmonitor 21:55:46 INFO - Cleaning up... 21:55:46 INFO - Return code: 0 21:55:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:55:46 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:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:46 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:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:46 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:46 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:55:46 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:55:46 INFO - Using env: {'DISPLAY': ':0', 21:55:46 INFO - 'HOME': '/home/cltbld', 21:55:46 INFO - 'LANG': 'en_US.UTF-8', 21:55:46 INFO - 'LANGUAGE': 'en_US:en', 21:55:46 INFO - 'LOGNAME': 'cltbld', 21:55:46 INFO - 'MAIL': '/var/mail/cltbld', 21:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:46 INFO - 'MOZ_NO_REMOTE': '1', 21:55:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:46 INFO - 'NO_EM_RESTART': '1', 21:55:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:46 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:46 INFO - 'SHELL': '/bin/bash', 21:55:46 INFO - 'SHLVL': '1', 21:55:46 INFO - 'TERM': 'linux', 21:55:46 INFO - 'TMOUT': '86400', 21:55:46 INFO - 'USER': 'cltbld', 21:55:46 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:46 INFO - '_': '/tools/buildbot/bin/python'} 21:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:47 INFO - Downloading/unpacking blobuploader==1.2.4 21:55:47 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:47 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:47 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:47 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:47 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:47 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:48 INFO - Downloading blobuploader-1.2.4.tar.gz 21:55:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:55:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:55:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:55:48 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:48 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:48 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:48 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:48 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:48 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:49 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:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:55:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:49 INFO - Downloading docopt-0.6.1.tar.gz 21:55:49 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:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:49 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:49 INFO - Running setup.py install for blobuploader 21:55:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:49 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:55:49 INFO - Running setup.py install for requests 21:55:49 INFO - Running setup.py install for docopt 21:55:49 INFO - Successfully installed blobuploader requests docopt 21:55:49 INFO - Cleaning up... 21:55:50 INFO - Return code: 0 21:55:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:50 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:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:50 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:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:50 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:50 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:50 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:50 INFO - Using env: {'DISPLAY': ':0', 21:55:50 INFO - 'HOME': '/home/cltbld', 21:55:50 INFO - 'LANG': 'en_US.UTF-8', 21:55:50 INFO - 'LANGUAGE': 'en_US:en', 21:55:50 INFO - 'LOGNAME': 'cltbld', 21:55:50 INFO - 'MAIL': '/var/mail/cltbld', 21:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:50 INFO - 'MOZ_NO_REMOTE': '1', 21:55:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:50 INFO - 'NO_EM_RESTART': '1', 21:55:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:50 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:50 INFO - 'SHELL': '/bin/bash', 21:55:50 INFO - 'SHLVL': '1', 21:55:50 INFO - 'TERM': 'linux', 21:55:50 INFO - 'TMOUT': '86400', 21:55:50 INFO - 'USER': 'cltbld', 21:55:50 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:50 INFO - '_': '/tools/buildbot/bin/python'} 21:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:50 INFO - Running setup.py (path:/tmp/pip-JbYThI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:50 INFO - Running setup.py (path:/tmp/pip-fkV2LN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:50 INFO - Running setup.py (path:/tmp/pip-iTSc_K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:50 INFO - Running setup.py (path:/tmp/pip-YQOnpe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:50 INFO - Running setup.py (path:/tmp/pip-_xOflj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:50 INFO - Running setup.py (path:/tmp/pip-iD8mef-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:50 INFO - Running setup.py (path:/tmp/pip-ri2UK0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:50 INFO - Running setup.py (path:/tmp/pip-m57oJE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:50 INFO - Running setup.py (path:/tmp/pip-1bLPzU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:50 INFO - Running setup.py (path:/tmp/pip-5NJl42-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:50 INFO - Running setup.py (path:/tmp/pip-lTnRmG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:51 INFO - Running setup.py (path:/tmp/pip-D0WNWz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:51 INFO - Running setup.py (path:/tmp/pip-Wod2Wc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:51 INFO - Running setup.py (path:/tmp/pip-RqN9dZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:51 INFO - Running setup.py (path:/tmp/pip-0FSEVG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:51 INFO - Running setup.py (path:/tmp/pip-BR6vmR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:51 INFO - Running setup.py (path:/tmp/pip-C_WvEr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:55:51 INFO - Running setup.py install for manifestparser 21:55:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:55:51 INFO - Running setup.py install for mozcrash 21:55:51 INFO - Running setup.py install for mozdebug 21:55:51 INFO - Running setup.py install for mozdevice 21:55:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:55:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:55:51 INFO - Running setup.py install for mozfile 21:55:51 INFO - Running setup.py install for mozhttpd 21:55:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozinfo 21:55:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozInstall 21:55:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozleak 21:55:52 INFO - Running setup.py install for mozlog 21:55:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for moznetwork 21:55:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozprocess 21:55:52 INFO - Running setup.py install for mozprofile 21:55:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozrunner 21:55:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:55:52 INFO - Running setup.py install for mozscreenshot 21:55:53 INFO - Running setup.py install for moztest 21:55:53 INFO - Running setup.py install for mozversion 21:55:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:55:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:55:53 INFO - Cleaning up... 21:55:53 INFO - Return code: 0 21:55:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55: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:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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', '-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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:53 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:53 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:53 INFO - Using env: {'DISPLAY': ':0', 21:55:53 INFO - 'HOME': '/home/cltbld', 21:55:53 INFO - 'LANG': 'en_US.UTF-8', 21:55:53 INFO - 'LANGUAGE': 'en_US:en', 21:55:53 INFO - 'LOGNAME': 'cltbld', 21:55:53 INFO - 'MAIL': '/var/mail/cltbld', 21:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:53 INFO - 'MOZ_NO_REMOTE': '1', 21:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:53 INFO - 'NO_EM_RESTART': '1', 21:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:53 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:53 INFO - 'SHELL': '/bin/bash', 21:55:53 INFO - 'SHLVL': '1', 21:55:53 INFO - 'TERM': 'linux', 21:55:53 INFO - 'TMOUT': '86400', 21:55:53 INFO - 'USER': 'cltbld', 21:55:53 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:53 INFO - '_': '/tools/buildbot/bin/python'} 21:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:53 INFO - Running setup.py (path:/tmp/pip-SmjTmH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:53 INFO - Running setup.py (path:/tmp/pip-FHYQHl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:53 INFO - Running setup.py (path:/tmp/pip-CpHjV8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:53 INFO - Running setup.py (path:/tmp/pip-_Eq1ZR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:53 INFO - Running setup.py (path:/tmp/pip-AYRqZV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:53 INFO - Running setup.py (path:/tmp/pip-zXl6Rk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:53 INFO - Running setup.py (path:/tmp/pip-K0FNU1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:53 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:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:53 INFO - Running setup.py (path:/tmp/pip-uaq_MK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:54 INFO - Running setup.py (path:/tmp/pip-8cU514-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:54 INFO - Running setup.py (path:/tmp/pip-seiV2z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:54 INFO - Running setup.py (path:/tmp/pip-krlGtB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:54 INFO - Running setup.py (path:/tmp/pip-7lpprG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:54 INFO - Running setup.py (path:/tmp/pip-G3NKwd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:54 INFO - Running setup.py (path:/tmp/pip-STH7Xg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:54 INFO - Running setup.py (path:/tmp/pip-P9LQ10-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:54 INFO - Running setup.py (path:/tmp/pip-hH8Zhg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:54 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:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:54 INFO - Running setup.py (path:/tmp/pip-XQpoVc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:54 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:54 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:54 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:54 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:54 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:54 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:54 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:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:56 INFO - Downloading blessings-1.5.1.tar.gz 21:55:56 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:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:55:56 INFO - Installing collected packages: blessings 21:55:56 INFO - Running setup.py install for blessings 21:55:56 INFO - Successfully installed blessings 21:55:56 INFO - Cleaning up... 21:55:56 INFO - Return code: 0 21:55:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:55:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:55:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:55:56 INFO - Reading from file tmpfile_stdout 21:55:56 INFO - Current package versions: 21:55:56 INFO - argparse == 1.2.1 21:55:56 INFO - blessings == 1.5.1 21:55:56 INFO - blobuploader == 1.2.4 21:55:56 INFO - docopt == 0.6.1 21:55:56 INFO - manifestparser == 1.1 21:55:56 INFO - mozInstall == 1.12 21:55:56 INFO - mozcrash == 0.16 21:55:56 INFO - mozdebug == 0.1 21:55:56 INFO - mozdevice == 0.47 21:55:56 INFO - mozfile == 1.2 21:55:56 INFO - mozhttpd == 0.7 21:55:56 INFO - mozinfo == 0.9 21:55:56 INFO - mozleak == 0.1 21:55:56 INFO - mozlog == 3.1 21:55:56 INFO - moznetwork == 0.27 21:55:56 INFO - mozprocess == 0.22 21:55:56 INFO - mozprofile == 0.28 21:55:56 INFO - mozrunner == 6.11 21:55:56 INFO - mozscreenshot == 0.1 21:55:56 INFO - mozsystemmonitor == 0.0 21:55:56 INFO - moztest == 0.7 21:55:56 INFO - mozversion == 1.4 21:55:56 INFO - psutil == 3.1.1 21:55:56 INFO - requests == 1.2.3 21:55:56 INFO - wsgiref == 0.1.2 21:55:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:56 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:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:56 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:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:56 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:56 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:56 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:56 INFO - Using env: {'DISPLAY': ':0', 21:55:56 INFO - 'HOME': '/home/cltbld', 21:55:56 INFO - 'LANG': 'en_US.UTF-8', 21:55:56 INFO - 'LANGUAGE': 'en_US:en', 21:55:56 INFO - 'LOGNAME': 'cltbld', 21:55:56 INFO - 'MAIL': '/var/mail/cltbld', 21:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:56 INFO - 'MOZ_NO_REMOTE': '1', 21:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:56 INFO - 'NO_EM_RESTART': '1', 21:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:56 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:56 INFO - 'SHELL': '/bin/bash', 21:55:56 INFO - 'SHLVL': '1', 21:55:56 INFO - 'TERM': 'linux', 21:55:56 INFO - 'TMOUT': '86400', 21:55:56 INFO - 'USER': 'cltbld', 21:55:56 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:56 INFO - '_': '/tools/buildbot/bin/python'} 21:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 INFO - Cleaning up... 21:55:57 INFO - Return code: 0 21:55:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:55: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:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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: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', 'jsonschema'] in /builds/slave/test-pgo/build 21:55: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 jsonschema 21:55:57 INFO - Using env: {'DISPLAY': ':0', 21:55:57 INFO - 'HOME': '/home/cltbld', 21:55:57 INFO - 'LANG': 'en_US.UTF-8', 21:55:57 INFO - 'LANGUAGE': 'en_US:en', 21:55:57 INFO - 'LOGNAME': 'cltbld', 21:55:57 INFO - 'MAIL': '/var/mail/cltbld', 21:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:57 INFO - 'MOZ_NO_REMOTE': '1', 21:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:57 INFO - 'NO_EM_RESTART': '1', 21:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:57 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:57 INFO - 'SHELL': '/bin/bash', 21:55:57 INFO - 'SHLVL': '1', 21:55:57 INFO - 'TERM': 'linux', 21:55:57 INFO - 'TMOUT': '86400', 21:55:57 INFO - 'USER': 'cltbld', 21:55:57 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:57 INFO - '_': '/tools/buildbot/bin/python'} 21:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:57 INFO - Downloading/unpacking jsonschema 21:55:57 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:57 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:57 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:57 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:57 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:57 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:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:58 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:58 INFO - Downloading/unpacking functools32 (from jsonschema) 21:55: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:55: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:55: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:55: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:55: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:55: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:55:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:59 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:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:59 INFO - warning: no files found matching '*.txt' 21:55:59 INFO - no previously-included directories found matching 'build' 21:55:59 INFO - no previously-included directories found matching 'dist' 21:55:59 INFO - no previously-included directories found matching '.git*' 21:55:59 INFO - Installing collected packages: jsonschema, functools32 21:55:59 INFO - Running setup.py install for functools32 21:55:59 INFO - warning: no files found matching '*.txt' 21:55:59 INFO - no previously-included directories found matching 'build' 21:55:59 INFO - no previously-included directories found matching 'dist' 21:55:59 INFO - no previously-included directories found matching '.git*' 21:55:59 INFO - Successfully installed jsonschema functools32 21:55:59 INFO - Cleaning up... 21:55:59 INFO - Return code: 0 21:55:59 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:55:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:59 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 0x7fa70b066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, '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': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', '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:59 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:59 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:59 INFO - Using env: {'DISPLAY': ':0', 21:55:59 INFO - 'HOME': '/home/cltbld', 21:55:59 INFO - 'LANG': 'en_US.UTF-8', 21:55:59 INFO - 'LANGUAGE': 'en_US:en', 21:55:59 INFO - 'LOGNAME': 'cltbld', 21:55:59 INFO - 'MAIL': '/var/mail/cltbld', 21:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:59 INFO - 'MOZ_NO_REMOTE': '1', 21:55:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:59 INFO - 'NO_EM_RESTART': '1', 21:55:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:59 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:59 INFO - 'SHELL': '/bin/bash', 21:55:59 INFO - 'SHLVL': '1', 21:55:59 INFO - 'TERM': 'linux', 21:55:59 INFO - 'TMOUT': '86400', 21:55:59 INFO - 'USER': 'cltbld', 21:55:59 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:59 INFO - '_': '/tools/buildbot/bin/python'} 21:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:59 INFO - Downloading/unpacking flake8 21:55: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:55: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: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:55: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:55: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:55: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:56:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:56:01 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:56:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:56:01 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:56:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 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:56:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:56:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:56:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:56:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:56:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:56:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:56:01 INFO - no previously-included directories found matching 'docs/_build' 21:56:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:56:01 INFO - Running setup.py install for pep8 21:56:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:56:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:56:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:56:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:56:02 INFO - no previously-included directories found matching 'docs/_build' 21:56:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:56:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:56:02 INFO - Cleaning up... 21:56:02 INFO - Return code: 0 21:56:02 INFO - Running post-action listener: _resource_record_post_action 21:56:02 INFO - Running post-action listener: _start_resource_monitoring 21:56:02 INFO - Starting resource monitoring. 21:56:02 INFO - ##### 21:56:02 INFO - ##### Running install step. 21:56:02 INFO - ##### 21:56:02 INFO - Running pre-action listener: _resource_record_pre_action 21:56:02 INFO - Running main action method: install 21:56:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:56:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:56:02 INFO - Reading from file tmpfile_stdout 21:56:02 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:56:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:56:02 INFO - Reading from file tmpfile_stdout 21:56:02 INFO - Output received: 21:56:02 INFO - Usage: mozinstall [options] installer 21:56:02 INFO - Options: 21:56:02 INFO - -h, --help show this help message and exit 21:56:02 INFO - -d DEST, --destination=DEST 21:56:02 INFO - Directory to install application into. [default: 21:56:02 INFO - "/builds/slave/test-pgo"] 21:56:02 INFO - --app=APP Application being installed. [default: firefox] 21:56:02 INFO - mkdir: /builds/slave/test-pgo/build/application 21:56:02 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:56:02 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:56:18 INFO - Reading from file tmpfile_stdout 21:56:18 INFO - Output received: 21:56:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:56:18 INFO - Running post-action listener: _resource_record_post_action 21:56:18 INFO - ##### 21:56:18 INFO - ##### Running run-tests step. 21:56:18 INFO - ##### 21:56:18 INFO - Running pre-action listener: _resource_record_pre_action 21:56:18 INFO - Running main action method: run_tests 21:56:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:56:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:56:18 INFO - Python 2.7.3 21:56:18 INFO - Return code: 0 21:56:18 INFO - grabbing minidump binary from tooltool 21:56:18 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:56:18 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 0x2aab840>, '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 0x2a186b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a18b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:56:18 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:56:18 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:56:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:56:18 INFO - Return code: 0 21:56:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:56:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:56:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:56:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:56:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:56:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:56:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:56:19 INFO - Return code: 0 21:56:19 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 21:56:19 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 --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 21:56:19 INFO - Using env: {'DISPLAY': ':0', 21:56:19 INFO - 'HOME': '/home/cltbld', 21:56:19 INFO - 'LANG': 'en_US.UTF-8', 21:56:19 INFO - 'LANGUAGE': 'en_US:en', 21:56:19 INFO - 'LOGNAME': 'cltbld', 21:56:19 INFO - 'MAIL': '/var/mail/cltbld', 21:56:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:56:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:19 INFO - 'MOZ_NO_REMOTE': '1', 21:56:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:19 INFO - 'NO_EM_RESTART': '1', 21:56:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:56:19 INFO - 'PWD': '/builds/slave/test-pgo', 21:56:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:56:19 INFO - 'SHELL': '/bin/bash', 21:56:19 INFO - 'SHLVL': '1', 21:56:19 INFO - 'TERM': 'linux', 21:56:19 INFO - 'TMOUT': '86400', 21:56:19 INFO - 'USER': 'cltbld', 21:56:19 INFO - 'XDG_SESSION_COOKIE': 'e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936', 21:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:19 INFO - '_': '/tools/buildbot/bin/python'} 21:56:19 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', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:56:19 INFO - mozversion application_buildid: 20160101193056 21:56:19 INFO - mozversion application_changeset: ce643acfab14d95bea2fb6c4f56477413514b686 21:56:19 INFO - mozversion application_display_name: Nightly 21:56:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:56:19 INFO - mozversion application_name: Firefox 21:56:19 INFO - mozversion application_remotingname: firefox 21:56:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:56:19 INFO - mozversion application_vendor: Mozilla 21:56:19 INFO - mozversion application_version: 46.0a1 21:56:19 INFO - mozversion platform_buildid: 20160101193056 21:56:19 INFO - mozversion platform_changeset: ce643acfab14d95bea2fb6c4f56477413514b686 21:56:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:56:19 INFO - mozversion platform_version: 46.0a1 21:56:19 INFO - using testdate: 1451714179 21:56:19 INFO - actual date: 1451714179 21:56:19 INFO - starting webserver on 'localhost:33280' 21:56:19 INFO - SUITE-START | Running 3 tests 21:56:19 INFO - TEST-START | dromaeo_css 21:56:19 INFO - operating with platform_type : linux_ 21:56:19 INFO - Initialising browser for dromaeo_css test... 21:56:19 INFO - TEST-INFO | started process 28614 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmKGxh7/profile http://localhost:33280/getInfo.html) 21:56:23 INFO - PROCESS | 28614 | __metrics Screen width/height:1600/1200 21:56:23 INFO - PROCESS | 28614 | colorDepth:24 21:56:23 INFO - PROCESS | 28614 | Browser inner width/height: 1024/697 21:56:23 INFO - PROCESS | 28614 | __metrics 21:56:23 INFO - PROCESS | 28614 | 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:56:24 INFO - PROCESS | 28614 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:56:25 INFO - TEST-INFO | 28614: exit 0 21:56:25 INFO - Browser initialized. 21:56:25 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:25 INFO - TEST-INFO | started process 28708 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmKGxh7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:36 INFO - PROCESS | 28708 | RSS: Main: 149839872 21:56:36 INFO - PROCESS | 28708 | 21:57:28 INFO - PROCESS | 28708 | 1451714248406 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:59:36 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33280/tests/dromaeo/cssquery-ext.html) 21:59:37 INFO - PROCESS | 28708 | RSS: Main: 247132160 21:59:37 INFO - PROCESS | 28708 | 22:02:40 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-ext.html (next: http://localhost:33280/tests/dromaeo/cssquery-jquery.html) 22:02:40 INFO - PROCESS | 28708 | RSS: Main: 235937792 22:02:40 INFO - PROCESS | 28708 | 22:05:42 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33280/tests/dromaeo/cssquery-mootools.html) 22:05:43 INFO - PROCESS | 28708 | RSS: Main: 228229120 22:05:43 INFO - PROCESS | 28708 | 22:08:45 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33280/tests/dromaeo/cssquery-prototype.html) 22:08:45 INFO - PROCESS | 28708 | RSS: Main: 210149376 22:08:45 INFO - PROCESS | 28708 | 22:11:48 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33280/tests/dromaeo/cssquery-yui.html) 22:11:48 INFO - PROCESS | 28708 | RSS: Main: 215240704 22:11:48 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | Cycle 1(1): loaded http://localhost:33280/tests/dromaeo/cssquery-yui.html (next: http://localhost:33280/tests/dromaeo/cssquery-dojo.html) 22:14:51 INFO - PROCESS | 28708 | RSS: Main: 229249024 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | __start_tp_report 22:14:51 INFO - PROCESS | 28708 | _x_x_mozilla_page_load 22:14:51 INFO - PROCESS | 28708 | _x_x_mozilla_page_load_details 22:14:51 INFO - PROCESS | 28708 | |i|pagename|runs| 22:14:51 INFO - PROCESS | 28708 | |0;dojo.html;3042.574039634451;3107.95883379068;3154.400663873864;3159.9368012639748;3164.3038531523066;4996.825111121102;5098.26075219093;5208.296879921209;5225.451327610604;5259.710715910624;5054.469280725526;5102.336696229494;5110.156824124015;5196.012757576058;5237.3453318335205;4662.720236785787;4677.649176311773;4682.6956247843955;4737.620990320766;4797.616190704735;4600.930986035212;4687.718736875781;4788.329633851264;4843.806247750086;4852.611791056707;4650.162970665279;4678.1813182692895;4726.976365118169;4740.360051393052;4785.234362501983;4713.222318317474;4716.292556116576;4718.103560323562;4723.929141062887;4758.119747846659;4663.27719203524;4671.85984420468;4690.788914498856;4697.694649847783;4711.693739906895;4883.34074899891;4991.401031876162;5016.423111342206;5021.548060674557;5033.421156566968;5420.376656684492;5454.236406903036;5557.749901254453;5588.329400471929;5591.3569939457075;5577.9163312550345;5580.079286917694;5591.2731344925305;5594.160875868612;5607.775688972436;5213.791448342062;5214.139666954948;5222.112240919053;5223.3209682741;5269.841904742864;5513.7794488220825;5525.0883604204855;5576.665400076008;5613.354464236583;5623.184638227516;5443.673379597237;5448.673079615236;5459.372172200247;5464.644798088191;5519.089350257163;5453.127499600045;5522.171674248895;5530.198121272393;5542.473465020817;5559.916601250903;5311.49540793624;5423.240746295522;5487.122060470386;5561.138023606348;5578.832635020955;5468.72656367188;5471.7264136793;5474.945250547444;5475;5477.972610136924;4262.6376757975295;4263.5736426357735;4270.530241673413;4307.655387568987;4308.80610372534;3720.776753394751;3768.2840260350445;3802.8478860845808;3809.3714537101623;3854.4218367244575;5095.159298715745;5120;5126.7180305083575;5128.00003999224;5167.457416971164;5454.754536045887;5458.344998600193;5459.3994660587305;5460.235567020621;5464.961657285103;4932.654714170046;5041.495850415003;5054.721990290569;5107.412647545506;5235.476452354734;4642.675012749142;4671.416072990876;4677.18149323867;4677.766111694402;4723.574878260904;5720.3421606515785;5724.427557244326;5727.656340619576;5742.7702891883855;5745.482906538432;5329.973350133225;5405.405405405324;5452.263944367618;5453.345598528043;5601.60788744784;5741.45456181662;5768.192453056659;5783.537317014545;5803.071508558611;5841.065429531254;5517.448255174611;5519.503244707996;5543.1408131739645;5544.473275038865;5554.944450555443;5296.470352964673;5416.864578385572;5424.321959755031;5594.552435805207;5615;5396.7571459283645;5403.864903377447;5451.046066938349;5474.0967740323995;5519.365272993497;5389.946100539101;5416.268803711606;5426.565874729933;5444.8366549003595;5498.945010549844;5112.105381558138;5225.895482090413;5237.214417837355;5285.233641122093;5399.190121481809;5354.54486368663;5436.673799572039;5438;5470.206820011155;5477.726113694379;5250.97374513125;5257.395399529103;5259.710715910509;5266.210068489757;5267.947320526746 22:14:51 INFO - PROCESS | 28708 | |1;ext.html;505.2194359714241;536.4983740202908;549.2447884159282;565.3385538919464;578.9276340457443;27568.586471202947;28208.576871346948;28230.576541351897;28254.57618135727;28265.717342826567;36573.451398229045;36896;36900;36948.81525592375;36973;8417.116202798694;8589.742307730778;8590.226879580836;8591.097934716858;8595.871061934045;7540.622968851563;7653.15815260321;8248.587570621476;8383.993920729521;9996.95001524995;9869.259805514615;9974.451405172713;9974.900250997505;9976;9983.251256155818;9832.852507212361;9927.851082233772;9930.950345248264;9943.651972180938;9969.202463802878;7599;7783.805404864867;9985.550650220757;10228.64199753005;10233.334833235816;35319.82340088283;35655.28689426197;35712.64287357119;35751.4637280441;35775.105622359646;3237.7895436796766;3310.3213841162506;3433.399155147864;3531.993381886176;3897.5907529709257;27348.45303093927;27905.581416278772;28075.298117547223;28514.287142821595;28517.857410712815;3757.981210093932;3895.7662540247675;3974.8012599370204;3978.920421591552;4086.0806318578084;263100;265403;265444;265835;266573.66713166505;59436;59638.40361596328;60001;60028.39971600316;60115;56268.718656406454;57044.714776425855;63549;63828.36171638317;65225.02162467567;15258.465953691568;15310.540683779505;15472.767908481383;15477.380904763684;15479.922600386926;11118.61084862026;11236.775264494665;11238.269512481662;11248.887511124947;11254;19437.22014506264;19447.513812154808;19924.402267931986;20190.293339733333;20296.579239453098;7730.806729831799;7889.9211007890335;7958.960205198937;7968.362530997506;8007;925.8796356473615;969.7187815533557;976.9902300977042;1010.5907107621425;1046.9528871200814;28087.719122808918;28253;28442.00452984177;28593.42813143767;28674.713252867623;998.1216529454034;1002.2633364477103;1009.3439264478001;1037.1184493180735;1132.750794825137;14824.851751482563;15284.617884552976;15323.23383830804;15487.148206848506;15748.448804291795;314779;317910;318653;318967;319642;52595.47404525982;52913;52948.205776913375;53050.734746326794;53054.7347263269;1335.152178366744;1354.2754626274896;1366.2485632901864;1373.5810577773675;1392.2273138408282;22325.665115023614;27779;27931.581026284217;28349.716502834708;28398;42146;42489;42609.14781704287;42647.360289596305;42660;194319;194884;196033;196721;196808;27189.320266993484;27236.591451127853;30068.398632026798;31085;31816.68183318137;36799.632003680694;37048.07379815418;37532.31104600245;37814.054648632904;40665.39001914914;33686.663133368354;34512.82743586266;34641;34874.651253488155;35937.46093808647;34171.65828341685;34274.48588271228;34442;34855;35145.121371965906;33756.32487350288;34222.65777342294;34295.828520857234;34648.653513464546;34717.47923781196;29707.702922970493;31260;31814.681853181173;31851.6814831858;31919.202019949687;24087.638685420083;24128.276151715476;24139.879300603385;24408.389790254674;24468.877655611606 22:14:51 INFO - PROCESS | 28708 | |2;jquery.html;360.35496461334196;361.9402798538242;362.7787049899562;365.8554870826024;369.5325413352112;13847.515336963208;13867.876450191341;16280.26738796754;16446;17123.315067397303;15114.924425377885;16287.267072981715;16293.266802993865;16304;17020.234089465972;5384.273123128386;5507.917381239285;5510.366307874586;5511.311086114236;5512.80705175319;4115.238680844039;4229.027323715547;5487.259220005288;5519.39286678465;5805.970970145143;5933.821985340425;5952.732127054272;5971.164037034819;5979.7907073252445;5981.043033114703;5398;5540.196671482653;5546.833594992117;5547.3343198816;5952.047672372421;4913.877153071202;4948.035133149037;4961.05739909416;4969.77636006377;5134.84595462133;11875.52497900083;12137.757244855053;12161.391930403533;12163.695907602292;18077.819221807877;2059.4542446251753;2092.278164033406;2133.6479480885637;2197.518097207859;2226.087304205268;9242.168204861528;9492.098250666175;9502.382345147544;10511.947440262826;12388.690282742913;1836.4949638849423;1862.748528948588;1867.449102514748;1882.425860112665;1893.9053047347716;58569.12146317809;58845.411545884424;59902.70048649773;61990.38009619936;62875.685621572055;9604.66383676568;9770;9792.951035244778;9841.360311579725;9867.309288349747;12936.09447338696;12984.350782461028;13024.218546887218;13026;13042.608721738363;7482.428689975166;7542;7547.433942454338;7561.054868141482;7580.810479738051;7506.099268087864;7535.773926782205;7539.962300188574;7585.62071896403;7593.316601505891;3961.801909904493;3967.4048892665755;3983.4423180754716;3988.3020471417385;4002.519697636244;1575.2121342276598;1625.910574918391;1632.6000129968234;1646.7365221564733;1662.2270644150528;797.3342259213622;879.8944126704708;899.1862364560084;1000.2797985450464;1000.3747657713928;16502.2573984171;16503.17484125789;16961.830381696273;17023.93132533132;17150.914245428692;817.2930415190935;862.3833958719546;902.9548522573845;909.5634095634133;1015.7359086637528;8916.128452189983;9015.323850711091;9039.322050846213;9078.727638170865;9195.540222988824;544197;546179;567466;567807;570457;81421;81976;82999;83903.16096839076;84002.57998709967;1156.6356597671706;1158.7276989907366;1161.6282789507277;1162.2329262686587;1173.647905628308;13930;13959.162450253018;14123.929380353444;14198.57404277832;14234.786478202628;31690.524642129927;32133.1966700825;32737.836310818293;33731.49402758911;36380;46502;46684;48563.514364857314;49743;51922.221166681775;9190.31072669539;9372.484513351832;9554.140127388568;9555.18780903631;9562.474063926551;11195.384253866116;11211.943940280247;12501.624951251477;14819;15011.624709381917;13931.373088211258;13971.301434928417;14726.63183420423;14907.776383354041;15075.849241507884;13740.381682824496;14004.36980335867;14094.929525352307;14306.785398218826;14475.85524144745;13942.512012079529;13964.2319672419;14062.789058163931;14560.85439145595;15212.543623691306;8982.595783189641;9274.814503710022;9395.718128456158;9414.95292523533;9490.857637135587;13596.728065438834;13745.656358590715;13903.721925561635;14069.859301406856;14194.148351098967 22:14:51 INFO - PROCESS | 28708 | |3;mootools.html;1307.973840523189;1470.2060887120956;1471.551176891048;1631.8286579909109;1803.765510483637;6715.093462382583;7236.348728614424;7342.743003994855;7349.338559529641;7349.632518374086;7237.819054523639;7339.082614673166;7406.814829629262;7417.295356941082;7418.814529636762;1978.584497255578;1988.353785019867;1996.8302694271003;2230.553889222154;2273.545290941818;2007.5683727998478;2013.8791672499697;2259.3560835161984;2299.1952816514254;2304.0783686525356;2207.933761987143;2218.134927378324;2222.4999375140546;2225.7774222577773;2286.2341115726254;2266.1615202375147;2272.5909336319455;2288.359259407377;2288.9198878039188;2290.5533420982915;2298.850574712655;2323.9651405228933;2325.976740232593;2326.139328448468;2326.9650955235684;7661.463697541174;7695.653695583712;7704.537727736354;7734;7750.883736743897;4597.287420449836;4652.441706995182;4708.976455117702;4712.434507859045;4713.57577817998;3058.709422604849;3079.3533357994847;3159.304952910356;3181.840907954593;3206.951895721566;1003.9949800251024;1010.4038617215878;1012.918966482687;1032.9173666106694;1080.9189310801642;10452.111570516516;10454.581816727321;10454.947725261402;10470.947645261802;10475.371477711362;4263.232618128766;4311;4348.499922508889;4370.300751879748;4375.4968178659865;9062;9062.093790620886;9068.54657267134;9079.591418386188;9084.273258139465;4725.598324142417;4730.597899178609;4736.95263047372;4738.123447162286;4741.407324084489;4314.331278651814;4318.460192475941;4324.697271190987;4326.242907491176;4329.718568293114;3324.5013248012506;3634.945475817865;3650.251698401821;3702.4631428442726;3707.073231692077;1078.4823284823328;1133.7165708572857;1151.5202964190973;1166.836642869999;1167.7022359298494;1063.229158859836;1063.4788953412926;1091.770728147082;1202.2846406388185;1316.8485624153154;7145.499815013003;7164.605946672878;7210.783676489714;7220.855582888313;7304.452166087564;1102.3826657072066;1117.5473933057124;1136.0797753819434;1137.2039572299423;1140.5437824869953;5655.604107712503;5718.085106382959;5804.100364443518;5804.593678442468;5806.622569532967;8754.21212090915;8969.058248883774;8998.775030624287;9038.412503187272;9061.093890611019;8817.16236957488;8979.10208979097;9083.137101975302;9086.909130908607;9097;7155.034070400429;7246.601436920914;7307.926920730831;7323.2676732326345;7332.743353982585;7131.500794944304;7308.926910730825;7320.304571065741;7325.340719335285;7328.450366222448;8216.479951209045;8253.587320633824;8332.416730829027;8422.957885210535;8430.325573954191;8417.158284171534;8592.957035214784;8607.311415086904;8747.343949203963;8768.167024132697;6664.5334826561675;6929.68816403253;7014.403775679124;7022.859542809218;7023.578585284899;6694.263631000488;6759.391654751096;6936.410405115622;7036.19083805369;7038.155421349471;6909.412699920563;6918.654067296716;6943.062686537455;7042.823929401806;7061.082059332254;6785.59286442815;6940.167179938439;6959.478039147186;7070.151581810215;7079.079719636415;6979.825504362431;7025.332593403618;7078;7079.893801592877;7086.858262834818;6391.201099862517;6409.551331406757;6449.6130232186215;6455.838604034937;6481.643509607017;6601.636909969806;6649.767258145942;6650.20197576294;6651.866962660816;6658.267590565131 22:14:51 INFO - PROCESS | 28708 | |4;prototype.html;339.60436091952874;344.82069323951544;345.2232476926914;346.18300810088186;354.22248165277216;5822.825315240544;5863.941360586393;5900.055991041434;5915.733791979361;5941.970290148549;5527.55779537637;5600.775968961237;5685.687287199203;5813.767449302023;5869.178315035899;4486.753228572427;4504.324351347308;4505.617022553079;4507.549245075483;4519.66102542309;4250.404943307939;4512.187806194884;4521.547845215485;4521.728696278217;4525.117602067598;4297.119090586422;4390.187815254172;4464.6651501137385;4467.687261891669;4470.575295346936;4417.337399390085;4463.4866990296205;4471.731696098228;4493.123840851035;4493.236149854532;3971.940420893689;3980.0646847990697;3981.3231750602176;3984.2828290907623;3986.1629057897876;5446.373667028302;5478.534324582415;5484.177373393983;5490.752916118784;5499.587530935156;5218.399884013349;5218.921716174261;5222.373315202162;5222.686638801684;5226.8693282667855;6124.2957059938235;6131.816045518642;6135.987562052256;6137.84655383619;6157.692115394258;4354.129174165148;4354.216241076605;4377.802998865059;4381.255186618282;4393.725819512338;6331.525135614847;6452.257990331125;6464.159659244268;6475.125858009155;6476.028595710634;4490.7754612269255;4499.932501012488;4500.482444518922;4500.774961251925;4504.414426124583;4541.409616749801;4547.636189104864;4554.476235232924;4556.384888040138;4559.703619264737;5217.58259339252;5228.398734145546;5236.397814251333;5238.266642670029;5242.895142097137;6109.175261339662;6227.813165605039;6229.875402491924;6230.532710046674;6238.688065596702;1592.2516417283857;1611.3876726843725;1611.5890447935935;1613.677264547072;1615.071333982941;1108.8357224913968;1109.5728144664363;1115.3085087245959;1126.8760436351995;1137.9317240965568;5896.616719913192;5908.822735317947;5909.970450147808;5913.201717768132;5913.9112913306335;6351.936480635227;6353.491720662336;6358;6362.491000719931;6368.363163683687;5700.201971723962;5766.3368712598585;5814.098814683731;5889.558283128783;5900.852478688067;3400.3879301725433;3423.17843717506;3427.708644765173;3429.53701250333;3430;6621.5364924454825;6627.801165965029;6630.071789949411;6683.164604424447;6722.9663851680425;6386.8722625547225;6568.376004279586;6571;6574.3754343337305;6578.375054369827;6687.164104486939;6699.095622091053;6699.866002679919;6701.061851340816;6701.530892837454;6381.968090159705;6382.521310901794;6388.456981156468;6393.1688880445245;6394.4564712;6126;6347.5556711029785;6353.745850165941;6368.713407896563;6378.425941665272;6702.597844129173;6711.295313991961;6714.127163468718;6718.731250750111;6720.1599800025;4527.253003254426;4549.499555049008;4578.65660075489;4579.7252164870215;4581.221192397234;6275.592086514362;6380.872382552416;6392.968035159794;6431.099646049651;6449.355064493513;6279.874402512016;6288.339724329063;6291.521492449362;6291.559590828781;6294.402031806971;6249.125122482947;6264.404881536247;6268.467180289544;6268.965620672538;6275.560710750204;6034.064719968412;6101.169649105339;6151.6924153793025;6156.26124865019;6162.630242185483;6297.748090076529;6309.022101574264;6313;6371.936280637135;6380.649064301508;6128.50971922254;6144.324124346408;6150.523874270053;6156.630602163885;6160 22:14:51 INFO - PROCESS | 28708 | |5;yui.html;842.414521907274;850.9149085091491;864.0495454999501;864.6800683747012;879.5821984557336;13456.798148027776;13620.114692544978;13665;13680.863191368084;13685.973551983603;12931.41808618612;12938.805917911239;12991.74016519669;13002.024848136403;13002.089853710244;670.5105273150591;681.7750142452978;682.8736683713504;683.6684208159039;683.7094234950151;807.2169995104739;827.7144385187131;827.93790465715;829.8589239829213;830.7881490219985;860.1484530315006;865.3293697384546;870.3907264914585;874.0822136756381;874.6807415293404;700.4676445901101;710.098175317344;859.1365677494109;862.4307956748547;868.2055918834259;628.1614045249597;638.5976834594222;639.2584601861864;639.6130341143635;640.0910706796313;13141.211527308296;13259.933700331532;13273.402696878567;13284.07011509185;13294.933525332408;11920.523179072827;11988.460519276654;12023.895818231862;12560.874391256062;12635.1787133836;9723.222142228604;9873.308868379216;9979.201663866874;10054.899451005469;10112.848307275397;3687.8893633191046;3701.648343407399;3709.0912726382207;3788.924221515554;3848.461215429842;43218.56781432209;44009.77995110036;44261.557384426065;44455.55544444547;45431.77284113591;9750.658726944523;9861.802763944681;9892.455914924605;10170.898291017143;10307.536160872778;18857.151428185764;19090.618187636446;19175;19179.32872349461;19208.423747287605;7591.3926885849;7781.455298129076;7856;7863.252990965849;7884.250996155356;4384.69307148503;4512.390827238347;4546.113507866001;4562.703424277412;4571.885702857388;3141.874325026973;3173.4129186100645;3175.666555011737;3176.587043684306;3179.9364012719616;562.6230425614848;567.7104676614956;568.8890666320028;571.4913726783083;575.1085816983658;618.653554009756;656.4321861589691;706.2231545300128;713.2974020589714;716.1155972374114;8170;8474.279686226617;8594.097619749837;8616.913830861737;8840.83524843499;489.42003725585295;576.5819780658989;586.6626689653381;656.5929123943185;693.0470602920983;4030.1939612077117;4056.918861622751;4114.403411505315;4124.154548317648;4136.276151673445;50254;51276;52691.73654131781;52723.20915186275;53181.46818531842;45467.31799023017;45588;46072;46427.30359044617;48419;43049.569504304556;43593;44184.3372349415;44186.33720494195;44677.55322446799;8436.70471533518;8691.695790647296;9020.14308640678;9028.683996060106;9049.827506842827;32346.353072937938;33020;33407.49888751622;33565;33922.15194620154;38082;40896;41443.37834932539;41484.377734334616;42156.57843421526;10703.919084715682;11038.613648522263;11169.72075698114;11203.99164075237;11406;11976.041971137247;12097.186320087663;12245.877541224474;12260.38698065111;12272.877271227173;11468.19722619442;11469.197156198987;11476.885231147582;11660.416979151178;11871.940640296743;9750.951245243728;9890.851637225303;10031.74920626961;10084.344517606332;10094.89905100969;9646.9035309646;10442.53008614599;10708.839367409651;11306.60426885088;11756.70608234801;8830.779230519289;9146.496942668216;9166.495842728715;9187.494687792236;9779.804403912025;8640.740777776677;8722.345824063348;8996.955015224881;9077.727668169966;9173.770655733393 22:14:51 INFO - PROCESS | 28708 | __end_tp_report 22:14:51 INFO - PROCESS | 28708 | __start_cc_report 22:14:51 INFO - PROCESS | 28708 | _x_x_mozilla_cycle_collect,804 22:14:51 INFO - PROCESS | 28708 | __end_cc_report 22:14:51 INFO - PROCESS | 28708 | __startTimestamp1451715291494__endTimestamp 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | ------- Summary: start ------- 22:14:51 INFO - PROCESS | 28708 | Number of tests: 6 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#0] dojo.html Cycles:165 Average:5123.46 Median:5269.84 stddev:562.57 (10.7%) stddev-sans-first:540.09 22:14:51 INFO - PROCESS | 28708 | Values: 3042.6 3108.0 3154.4 3159.9 3164.3 4996.8 5098.3 5208.3 5225.5 5259.7 5054.5 5102.3 5110.2 5196.0 5237.3 4662.7 4677.6 4682.7 4737.6 4797.6 4600.9 4687.7 4788.3 4843.8 4852.6 4650.2 4678.2 4727.0 4740.4 4785.2 4713.2 4716.3 4718.1 4723.9 4758.1 4663.3 4671.9 4690.8 4697.7 4711.7 4883.3 4991.4 5016.4 5021.5 5033.4 5420.4 5454.2 5557.7 5588.3 5591.4 5577.9 5580.1 5591.3 5594.2 5607.8 5213.8 5214.1 5222.1 5223.3 5269.8 5513.8 5525.1 5576.7 5613.4 5623.2 5443.7 5448.7 5459.4 5464.6 5519.1 5453.1 5522.2 5530.2 5542.5 5559.9 5311.5 5423.2 5487.1 5561.1 5578.8 5468.7 5471.7 5474.9 5475.0 5478.0 4262.6 4263.6 4270.5 4307.7 4308.8 3720.8 3768.3 3802.8 3809.4 3854.4 5095.2 5120.0 5126.7 5128.0 5167.5 5454.8 5458.3 5459.4 5460.2 5465.0 4932.7 5041.5 5054.7 5107.4 5235.5 4642.7 4671.4 4677.2 4677.8 4723.6 5720.3 5724.4 5727.7 5742.8 5745.5 5330.0 5405.4 5452.3 5453.3 5601.6 5741.5 5768.2 5783.5 5803.1 5841.1 5517.4 5519.5 5543.1 5544.5 5554.9 5296.5 5416.9 5424.3 5594.6 5615.0 5396.8 5403.9 5451.0 5474.1 5519.4 5389.9 5416.3 5426.6 5444.8 5498.9 5112.1 5225.9 5237.2 5285.2 5399.2 5354.5 5436.7 5438.0 5470.2 5477.7 5251.0 5257.4 5259.7 5266.2 5267.9 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#1] ext.html Cycles:180 Average:42588.78 Median:18864.61 stddev:69150.29 (366.6%) stddev-sans-first:69272.08 22:14:51 INFO - PROCESS | 28708 | Values: 505.2 536.5 549.2 565.3 578.9 27568.6 28208.6 28230.6 28254.6 28265.7 36573.5 36896.0 36900.0 36948.8 36973.0 8417.1 8589.7 8590.2 8591.1 8595.9 7540.6 7653.2 8248.6 8384.0 9997.0 9869.3 9974.5 9974.9 9976.0 9983.3 9832.9 9927.9 9931.0 9943.7 9969.2 7599.0 7783.8 9985.6 10228.6 10233.3 35319.8 35655.3 35712.6 35751.5 35775.1 3237.8 3310.3 3433.4 3532.0 3897.6 27348.5 27905.6 28075.3 28514.3 28517.9 3758.0 3895.8 3974.8 3978.9 4086.1 263100.0 265403.0 265444.0 265835.0 266573.7 59436.0 59638.4 60001.0 60028.4 60115.0 56268.7 57044.7 63549.0 63828.4 65225.0 15258.5 15310.5 15472.8 15477.4 15479.9 11118.6 11236.8 11238.3 11248.9 11254.0 19437.2 19447.5 19924.4 20190.3 20296.6 7730.8 7889.9 7959.0 7968.4 8007.0 925.9 969.7 977.0 1010.6 1047.0 28087.7 28253.0 28442.0 28593.4 28674.7 998.1 1002.3 1009.3 1037.1 1132.8 14824.9 15284.6 15323.2 15487.1 15748.4 314779.0 317910.0 318653.0 318967.0 319642.0 52595.5 52913.0 52948.2 53050.7 53054.7 1335.2 1354.3 1366.2 1373.6 1392.2 22325.7 27779.0 27931.6 28349.7 28398.0 42146.0 42489.0 42609.1 42647.4 42660.0 194319.0 194884.0 196033.0 196721.0 196808.0 27189.3 27236.6 30068.4 31085.0 31816.7 36799.6 37048.1 37532.3 37814.1 40665.4 33686.7 34512.8 34641.0 34874.7 35937.5 34171.7 34274.5 34442.0 34855.0 35145.1 33756.3 34222.7 34295.8 34648.7 34717.5 29707.7 31260.0 31814.7 31851.7 31919.2 24087.6 24128.3 24139.9 24408.4 24468.9 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#2] jquery.html Cycles:180 Average:29062.04 Median:3964.60 stddev:91470.89 (2307.2%) stddev-sans-first:91702.10 22:14:51 INFO - PROCESS | 28708 | Values: 360.4 361.9 362.8 365.9 369.5 13847.5 13867.9 16280.3 16446.0 17123.3 15114.9 16287.3 16293.3 16304.0 17020.2 5384.3 5507.9 5510.4 5511.3 5512.8 4115.2 4229.0 5487.3 5519.4 5806.0 5933.8 5952.7 5971.2 5979.8 5981.0 5398.0 5540.2 5546.8 5547.3 5952.0 4913.9 4948.0 4961.1 4969.8 5134.8 11875.5 12137.8 12161.4 12163.7 18077.8 2059.5 2092.3 2133.6 2197.5 2226.1 9242.2 9492.1 9502.4 10511.9 12388.7 1836.5 1862.7 1867.4 1882.4 1893.9 58569.1 58845.4 59902.7 61990.4 62875.7 9604.7 9770.0 9793.0 9841.4 9867.3 12936.1 12984.4 13024.2 13026.0 13042.6 7482.4 7542.0 7547.4 7561.1 7580.8 7506.1 7535.8 7540.0 7585.6 7593.3 3961.8 3967.4 3983.4 3988.3 4002.5 1575.2 1625.9 1632.6 1646.7 1662.2 797.3 879.9 899.2 1000.3 1000.4 16502.3 16503.2 16961.8 17023.9 17150.9 817.3 862.4 903.0 909.6 1015.7 8916.1 9015.3 9039.3 9078.7 9195.5 544197.0 546179.0 567466.0 567807.0 570457.0 81421.0 81976.0 82999.0 83903.2 84002.6 1156.6 1158.7 1161.6 1162.2 1173.6 13930.0 13959.2 14123.9 14198.6 14234.8 31690.5 32133.2 32737.8 33731.5 36380.0 46502.0 46684.0 48563.5 49743.0 51922.2 9190.3 9372.5 9554.1 9555.2 9562.5 11195.4 11211.9 12501.6 14819.0 15011.6 13931.4 13971.3 14726.6 14907.8 15075.8 13740.4 14004.4 14094.9 14306.8 14475.9 13942.5 13964.2 14062.8 14560.9 15212.5 8982.6 9274.8 9395.7 9415.0 9490.9 13596.7 13745.7 13903.7 14069.9 14194.1 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#3] mootools.html Cycles:180 Average:5390.30 Median:6400.38 stddev:2759.36 (43.1%) stddev-sans-first:2750.03 22:14:51 INFO - PROCESS | 28708 | Values: 1308.0 1470.2 1471.6 1631.8 1803.8 6715.1 7236.3 7342.7 7349.3 7349.6 7237.8 7339.1 7406.8 7417.3 7418.8 1978.6 1988.4 1996.8 2230.6 2273.5 2007.6 2013.9 2259.4 2299.2 2304.1 2207.9 2218.1 2222.5 2225.8 2286.2 2266.2 2272.6 2288.4 2288.9 2290.6 2298.9 2324.0 2326.0 2326.1 2327.0 7661.5 7695.7 7704.5 7734.0 7750.9 4597.3 4652.4 4709.0 4712.4 4713.6 3058.7 3079.4 3159.3 3181.8 3207.0 1004.0 1010.4 1012.9 1032.9 1080.9 10452.1 10454.6 10454.9 10470.9 10475.4 4263.2 4311.0 4348.5 4370.3 4375.5 9062.0 9062.1 9068.5 9079.6 9084.3 4725.6 4730.6 4737.0 4738.1 4741.4 4314.3 4318.5 4324.7 4326.2 4329.7 3324.5 3634.9 3650.3 3702.5 3707.1 1078.5 1133.7 1151.5 1166.8 1167.7 1063.2 1063.5 1091.8 1202.3 1316.8 7145.5 7164.6 7210.8 7220.9 7304.5 1102.4 1117.5 1136.1 1137.2 1140.5 5655.6 5718.1 5804.1 5804.6 5806.6 8754.2 8969.1 8998.8 9038.4 9061.1 8817.2 8979.1 9083.1 9086.9 9097.0 7155.0 7246.6 7307.9 7323.3 7332.7 7131.5 7308.9 7320.3 7325.3 7328.5 8216.5 8253.6 8332.4 8423.0 8430.3 8417.2 8593.0 8607.3 8747.3 8768.2 6664.5 6929.7 7014.4 7022.9 7023.6 6694.3 6759.4 6936.4 7036.2 7038.2 6909.4 6918.7 6943.1 7042.8 7061.1 6785.6 6940.2 6959.5 7070.2 7079.1 6979.8 7025.3 7078.0 7079.9 7086.9 6391.2 6409.6 6449.6 6455.8 6481.6 6601.6 6649.8 6650.2 6651.9 6658.3 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#4] prototype.html Cycles:180 Average:5210.02 Median:5893.09 stddev:1552.68 (26.3%) stddev-sans-first:1513.39 22:14:51 INFO - PROCESS | 28708 | Values: 339.6 344.8 345.2 346.2 354.2 5822.8 5863.9 5900.1 5915.7 5942.0 5527.6 5600.8 5685.7 5813.8 5869.2 4486.8 4504.3 4505.6 4507.5 4519.7 4250.4 4512.2 4521.5 4521.7 4525.1 4297.1 4390.2 4464.7 4467.7 4470.6 4417.3 4463.5 4471.7 4493.1 4493.2 3971.9 3980.1 3981.3 3984.3 3986.2 5446.4 5478.5 5484.2 5490.8 5499.6 5218.4 5218.9 5222.4 5222.7 5226.9 6124.3 6131.8 6136.0 6137.8 6157.7 4354.1 4354.2 4377.8 4381.3 4393.7 6331.5 6452.3 6464.2 6475.1 6476.0 4490.8 4499.9 4500.5 4500.8 4504.4 4541.4 4547.6 4554.5 4556.4 4559.7 5217.6 5228.4 5236.4 5238.3 5242.9 6109.2 6227.8 6229.9 6230.5 6238.7 1592.3 1611.4 1611.6 1613.7 1615.1 1108.8 1109.6 1115.3 1126.9 1137.9 5896.6 5908.8 5910.0 5913.2 5913.9 6351.9 6353.5 6358.0 6362.5 6368.4 5700.2 5766.3 5814.1 5889.6 5900.9 3400.4 3423.2 3427.7 3429.5 3430.0 6621.5 6627.8 6630.1 6683.2 6723.0 6386.9 6568.4 6571.0 6574.4 6578.4 6687.2 6699.1 6699.9 6701.1 6701.5 6382.0 6382.5 6388.5 6393.2 6394.5 6126.0 6347.6 6353.7 6368.7 6378.4 6702.6 6711.3 6714.1 6718.7 6720.2 4527.3 4549.5 4578.7 4579.7 4581.2 6275.6 6380.9 6393.0 6431.1 6449.4 6279.9 6288.3 6291.5 6291.6 6294.4 6249.1 6264.4 6268.5 6269.0 6275.6 6034.1 6101.2 6151.7 6156.3 6162.6 6297.7 6309.0 6313.0 6371.9 6380.6 6128.5 6144.3 6150.5 6156.6 6160.0 22:14:51 INFO - PROCESS | 28708 | 22:14:51 INFO - PROCESS | 28708 | [#5] yui.html Cycles:180 Average:13133.25 Median:25075.35 stddev:14594.24 (58.2%) stddev-sans-first:14605.99 22:14:51 INFO - PROCESS | 28708 | Values: 842.4 850.9 864.0 864.7 879.6 13456.8 13620.1 13665.0 13680.9 13686.0 12931.4 12938.8 12991.7 13002.0 13002.1 670.5 681.8 682.9 683.7 683.7 807.2 827.7 827.9 829.9 830.8 860.1 865.3 870.4 874.1 874.7 700.5 710.1 859.1 862.4 868.2 628.2 638.6 639.3 639.6 640.1 13141.2 13259.9 13273.4 13284.1 13294.9 11920.5 11988.5 12023.9 12560.9 12635.2 9723.2 9873.3 9979.2 10054.9 10112.8 3687.9 3701.6 3709.1 3788.9 3848.5 43218.6 44009.8 44261.6 44455.6 45431.8 9750.7 9861.8 9892.5 10170.9 10307.5 18857.2 19090.6 19175.0 19179.3 19208.4 7591.4 7781.5 7856.0 7863.3 7884.3 4384.7 4512.4 4546.1 4562.7 4571.9 3141.9 3173.4 3175.7 3176.6 3179.9 562.6 567.7 568.9 571.5 575.1 618.7 656.4 706.2 713.3 716.1 8170.0 8474.3 8594.1 8616.9 8840.8 489.4 576.6 586.7 656.6 693.0 4030.2 4056.9 4114.4 4124.2 4136.3 50254.0 51276.0 52691.7 52723.2 53181.5 45467.3 45588.0 46072.0 46427.3 48419.0 43049.6 43593.0 44184.3 44186.3 44677.6 8436.7 8691.7 9020.1 9028.7 9049.8 32346.4 33020.0 33407.5 33565.0 33922.2 38082.0 40896.0 41443.4 41484.4 42156.6 10703.9 11038.6 11169.7 11204.0 11406.0 11976.0 12097.2 12245.9 12260.4 12272.9 11468.2 11469.2 11476.9 11660.4 11871.9 9751.0 9890.9 10031.7 10084.3 10094.9 9646.9 10442.5 10708.8 11306.6 11756.7 8830.8 9146.5 9166.5 9187.5 9779.8 8640.7 8722.3 8997.0 9077.7 9173.8 22:14:51 INFO - PROCESS | 28708 | -------- Summary: end -------- 22:14:51 INFO - PROCESS | 28708 | 22:14:52 INFO - TEST-INFO | 28708: exit 0 22:14:52 INFO - TEST-OK | dromaeo_css | took 1112655ms 22:14:52 INFO - TEST-START | kraken 22:14:52 INFO - operating with platform_type : linux_ 22:14:52 INFO - Initialising browser for kraken test... 22:14:52 INFO - TEST-INFO | started process 28910 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiKDWLG/profile http://localhost:33280/getInfo.html) 22:14:56 INFO - PROCESS | 28910 | __metrics Screen width/height:1600/1200 22:14:56 INFO - PROCESS | 28910 | colorDepth:24 22:14:56 INFO - PROCESS | 28910 | Browser inner width/height: 1024/697 22:14:56 INFO - PROCESS | 28910 | __metrics 22:14:56 INFO - PROCESS | 28910 | 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:56 INFO - PROCESS | 28910 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:14:57 INFO - TEST-INFO | 28910: exit 0 22:14:57 INFO - Browser initialized. 22:14:57 INFO - Running cycle 1/1 for kraken test... 22:14:57 INFO - TEST-INFO | started process 28999 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiKDWLG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:09 INFO - PROCESS | 28999 | RSS: Main: 150327296 22:15:09 INFO - PROCESS | 28999 | 22:15:11 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33280/tests/kraken/driver.html?testName=audio-beat-detection) 22:15:12 INFO - PROCESS | 28999 | RSS: Main: 185315328 22:15:12 INFO - PROCESS | 28999 | 22:15:15 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33280/tests/kraken/driver.html?testName=audio-dft) 22:15:15 INFO - PROCESS | 28999 | RSS: Main: 175276032 22:15:15 INFO - PROCESS | 28999 | 22:15:18 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33280/tests/kraken/driver.html?testName=audio-fft) 22:15:19 INFO - PROCESS | 28999 | RSS: Main: 159752192 22:15:19 INFO - PROCESS | 28999 | 22:15:21 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33280/tests/kraken/driver.html?testName=audio-oscillator) 22:15:21 INFO - PROCESS | 28999 | RSS: Main: 163487744 22:15:21 INFO - PROCESS | 28999 | 22:15:23 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:24 INFO - PROCESS | 28999 | RSS: Main: 165502976 22:15:24 INFO - PROCESS | 28999 | 22:15:27 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33280/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:27 INFO - PROCESS | 28999 | RSS: Main: 165027840 22:15:27 INFO - PROCESS | 28999 | 22:15:31 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33280/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:31 INFO - PROCESS | 28999 | RSS: Main: 164651008 22:15:31 INFO - PROCESS | 28999 | 22:15:34 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33280/tests/kraken/driver.html?testName=json-parse-financial) 22:15:34 INFO - PROCESS | 28999 | RSS: Main: 166285312 22:15:35 INFO - PROCESS | 28999 | 22:15:36 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:37 INFO - PROCESS | 28999 | RSS: Main: 175222784 22:15:37 INFO - PROCESS | 28999 | 22:15:38 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:39 INFO - PROCESS | 28999 | RSS: Main: 172642304 22:15:39 INFO - PROCESS | 28999 | 22:15:41 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:42 INFO - PROCESS | 28999 | RSS: Main: 179392512 22:15:42 INFO - PROCESS | 28999 | 22:15:44 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:15:45 INFO - PROCESS | 28999 | RSS: Main: 187416576 22:15:45 INFO - PROCESS | 28999 | 22:15:47 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:15:48 INFO - PROCESS | 28999 | RSS: Main: 186097664 22:15:48 INFO - PROCESS | 28999 | 22:15:49 INFO - PROCESS | 28999 | Cycle 1(1): loaded http://localhost:33280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33280/tests/kraken/driver.html?testName=ai-astar) 22:15:50 INFO - PROCESS | 28999 | RSS: Main: 242900992 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | __start_tp_report 22:15:50 INFO - PROCESS | 28999 | _x_x_mozilla_page_load 22:15:50 INFO - PROCESS | 28999 | _x_x_mozilla_page_load_details 22:15:50 INFO - PROCESS | 28999 | |i|pagename|runs| 22:15:50 INFO - PROCESS | 28999 | |0;ai-astar;110;110;104;111;109;110;102;110;110;111 22:15:50 INFO - PROCESS | 28999 | |1;audio-beat-detection;157;126;151;165;132;149;152;134;170;124 22:15:50 INFO - PROCESS | 28999 | |2;audio-dft;161;161;160;162;159;160;160;162;161;161 22:15:50 INFO - PROCESS | 28999 | |3;audio-fft;74;72;90;105;71;71;71;96;87;72 22:15:50 INFO - PROCESS | 28999 | |4;audio-oscillator;96;97;141;97;98;97;136;95;97;138 22:15:50 INFO - PROCESS | 28999 | |5;imaging-gaussian-blur;109;109;109;110;109;109;109;109;109;109 22:15:50 INFO - PROCESS | 28999 | |6;imaging-darkroom;145;146;146;146;145;145;145;145;145;145 22:15:50 INFO - PROCESS | 28999 | |7;imaging-desaturate;96;96;96;96;95;95;96;96;96;97 22:15:50 INFO - PROCESS | 28999 | |8;json-parse-financial;59;57;56;57;57;59;56;65;54;55 22:15:50 INFO - PROCESS | 28999 | |9;json-stringify-tinderbox;67;68;68;67;68;68;68;68;68;68 22:15:50 INFO - PROCESS | 28999 | |10;stanford-crypto-aes;84;85;84;85;85;86;85;86;98;85 22:15:50 INFO - PROCESS | 28999 | |11;stanford-crypto-ccm;116;117;116;125;123;116;114;124;115;118 22:15:50 INFO - PROCESS | 28999 | |12;stanford-crypto-pbkdf2;150;150;158;156;150;142;151;151;153;142 22:15:50 INFO - PROCESS | 28999 | |13;stanford-crypto-sha256-iterative;68;68;70;68;69;67;69;69;68;68 22:15:50 INFO - PROCESS | 28999 | __end_tp_report 22:15:50 INFO - PROCESS | 28999 | __start_cc_report 22:15:50 INFO - PROCESS | 28999 | _x_x_mozilla_cycle_collect,930 22:15:50 INFO - PROCESS | 28999 | __end_cc_report 22:15:50 INFO - PROCESS | 28999 | __startTimestamp1451715350230__endTimestamp 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | ------- Summary: start ------- 22:15:50 INFO - PROCESS | 28999 | Number of tests: 14 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:3.09 (2.8%) stddev-sans-first:3.24 22:15:50 INFO - PROCESS | 28999 | Values: 110.0 110.0 104.0 111.0 109.0 110.0 102.0 110.0 110.0 111.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:151.50 stddev:16.17 (10.7%) stddev-sans-first:16.65 22:15:50 INFO - PROCESS | 28999 | Values: 157.0 126.0 151.0 165.0 132.0 149.0 152.0 134.0 170.0 124.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 22:15:50 INFO - PROCESS | 28999 | Values: 161.0 161.0 160.0 162.0 159.0 160.0 160.0 162.0 161.0 161.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#3] audio-fft Cycles:10 Average:80.90 Median:73.00 stddev:12.60 (17.3%) stddev-sans-first:13.11 22:15:50 INFO - PROCESS | 28999 | Values: 74.0 72.0 90.0 105.0 71.0 71.0 71.0 96.0 87.0 72.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#4] audio-oscillator Cycles:10 Average:109.20 Median:97.00 stddev:20.15 (20.8%) stddev-sans-first:20.80 22:15:50 INFO - PROCESS | 28999 | Values: 96.0 97.0 141.0 97.0 98.0 97.0 136.0 95.0 97.0 138.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:15:50 INFO - PROCESS | 28999 | Values: 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:15:50 INFO - PROCESS | 28999 | Values: 145.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:15:50 INFO - PROCESS | 28999 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 96.0 97.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:57.00 stddev:3.06 (5.4%) stddev-sans-first:3.20 22:15:50 INFO - PROCESS | 28999 | Values: 59.0 57.0 56.0 57.0 57.0 59.0 56.0 65.0 54.0 55.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 22:15:50 INFO - PROCESS | 28999 | Values: 67.0 68.0 68.0 67.0 68.0 68.0 68.0 68.0 68.0 68.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.00 stddev:4.16 (4.9%) stddev-sans-first:4.33 22:15:50 INFO - PROCESS | 28999 | Values: 84.0 85.0 84.0 85.0 85.0 86.0 85.0 86.0 98.0 85.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:117.50 stddev:4.03 (3.4%) stddev-sans-first:4.18 22:15:50 INFO - PROCESS | 28999 | Values: 116.0 117.0 116.0 125.0 123.0 116.0 114.0 124.0 115.0 118.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.30 Median:151.00 stddev:5.14 (3.4%) stddev-sans-first:5.45 22:15:50 INFO - PROCESS | 28999 | Values: 150.0 150.0 158.0 156.0 150.0 142.0 151.0 151.0 153.0 142.0 22:15:50 INFO - PROCESS | 28999 | 22:15:50 INFO - PROCESS | 28999 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 22:15:50 INFO - PROCESS | 28999 | Values: 68.0 68.0 70.0 68.0 69.0 67.0 69.0 69.0 68.0 68.0 22:15:50 INFO - PROCESS | 28999 | -------- Summary: end -------- 22:15:50 INFO - PROCESS | 28999 | 22:15:51 INFO - TEST-INFO | 28999: exit 0 22:15:51 INFO - TEST-OK | kraken | took 58906ms 22:15:51 INFO - TEST-START | v8_7 22:15:51 INFO - operating with platform_type : linux_ 22:15:51 INFO - Initialising browser for v8_7 test... 22:15:51 INFO - TEST-INFO | started process 29246 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj8941I/profile http://localhost:33280/getInfo.html) 22:15:55 INFO - PROCESS | 29246 | __metrics Screen width/height:1600/1200 22:15:55 INFO - PROCESS | 29246 | colorDepth:24 22:15:55 INFO - PROCESS | 29246 | Browser inner width/height: 1024/697 22:15:55 INFO - PROCESS | 29246 | __metrics 22:15:55 INFO - PROCESS | 29246 | 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:55 INFO - PROCESS | 29246 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:15:56 INFO - TEST-INFO | 29246: exit 0 22:15:56 INFO - Browser initialized. 22:15:56 INFO - Running cycle 1/1 for v8_7 test... 22:15:56 INFO - TEST-INFO | started process 29333 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj8941I/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:08 INFO - PROCESS | 29333 | RSS: Main: 150085632 22:16:08 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | Cycle 1(1): loaded http://localhost:33280/tests/v8_7/run.html (next: http://localhost:33280/tests/v8_7/run.html) 22:16:29 INFO - PROCESS | 29333 | RSS: Main: 562765824 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | __start_tp_report 22:16:29 INFO - PROCESS | 29333 | _x_x_mozilla_page_load 22:16:29 INFO - PROCESS | 29333 | _x_x_mozilla_page_load_details 22:16:29 INFO - PROCESS | 29333 | |i|pagename|runs| 22:16:29 INFO - PROCESS | 29333 | |0;Richards;167.5603217158177 22:16:29 INFO - PROCESS | 29333 | |1;DeltaBlue;190.43991620643686 22:16:29 INFO - PROCESS | 29333 | |2;Encrypt;280.97780275358247 22:16:29 INFO - PROCESS | 29333 | |3;Decrypt;5175.257731958763 22:16:29 INFO - PROCESS | 29333 | |4;RayTrace;1976.2845849802372 22:16:29 INFO - PROCESS | 29333 | |5;Earley;774.1686001546791 22:16:29 INFO - PROCESS | 29333 | |6;Boyer;12180.722891566265 22:16:29 INFO - PROCESS | 29333 | |7;RegExp;25820.51282051282 22:16:29 INFO - PROCESS | 29333 | |8;Splay;614.6281499692686 22:16:29 INFO - PROCESS | 29333 | |9;NavierStokes;4830.917874396136 22:16:29 INFO - PROCESS | 29333 | __end_tp_report 22:16:29 INFO - PROCESS | 29333 | __startTimestamp1451715389989__endTimestamp 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | ------- Summary: start ------- 22:16:29 INFO - PROCESS | 29333 | Number of tests: 10 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | [#0] Richards Cycles:1 Average:167.56 Median:167.56 stddev:0.00 (0.0%) 22:16:29 INFO - PROCESS | 29333 | Values: 167.6 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | [#1] DeltaBlue Cycles:1 Average:190.44 Median:190.44 stddev:0.00 (0.0%) 22:16:29 INFO - PROCESS | 29333 | Values: 190.4 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | [#2] Encrypt Cycles:1 Average:280.98 Median:280.98 stddev:0.00 (0.0%) 22:16:29 INFO - PROCESS | 29333 | Values: 281.0 22:16:29 INFO - PROCESS | 29333 | 22:16:29 INFO - PROCESS | 29333 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 22:16:29 INFO - PROCESS | 29333 | Values: 5175.3 22:16:29 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#4] RayTrace Cycles:1 Average:1976.28 Median:1976.28 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 1976.3 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#5] Earley Cycles:1 Average:774.17 Median:774.17 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 774.2 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#6] Boyer Cycles:1 Average:12180.72 Median:12180.72 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 12180.7 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#7] RegExp Cycles:1 Average:25820.51 Median:25820.51 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 25820.5 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#8] Splay Cycles:1 Average:614.63 Median:614.63 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 614.6 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - PROCESS | 29333 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 22:16:30 INFO - PROCESS | 29333 | Values: 4830.9 22:16:30 INFO - PROCESS | 29333 | -------- Summary: end -------- 22:16:30 INFO - PROCESS | 29333 | 22:16:30 INFO - TEST-INFO | 29333: exit 0 22:16:30 INFO - TEST-OK | v8_7 | took 39697ms 22:16:30 INFO - SUITE-END | took 1211s 22:16:30 INFO - Completed test suite (00:20:11) 22:16:30 INFO - Working with test: dromaeo_css 22:16:30 INFO - Generating results file: dromaeo_css 22:16:30 INFO - Working with test: kraken 22:16:30 INFO - Generating results file: kraken 22:16:30 INFO - javascript benchmark 22:16:30 INFO - Working with test: v8_7 22:16:30 INFO - Generating results file: v8_7 22:16:30 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:30 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 22:16:30 INFO - process_Request line: dromaeo_css 6826.68 graph.html#tests=[[72,1,35]] 22:16:30 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:30 INFO - process_Request line: kraken 1504.50 graph.html#tests=[[232,1,35]] 22:16:30 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:31 INFO - process_Request line: v8_7 19826.34 graph.html#tests=[[230,1,35]] 22:16:31 INFO - RETURN: dromaeo_css: 6826.68 22:16:31 INFO - RETURN: kraken: 1504.50 22:16:31 INFO - RETURN: v8_7: 19826.34 22:16:31 INFO - javascript benchmark 22:16:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3042.574039634451, 3107.95883379068, 3154.400663873864, 3159.9368012639748, 3164.3038531523066, 4996.825111121102, 5098.26075219093, 5208.296879921209, 5225.451327610604, 5259.710715910624, 5054.469280725526, 5102.336696229494, 5110.156824124015, 5196.012757576058, 5237.3453318335205, 4662.720236785787, 4677.649176311773, 4682.6956247843955, 4737.620990320766, 4797.616190704735, 4600.930986035212, 4687.718736875781, 4788.329633851264, 4843.806247750086, 4852.611791056707, 4650.162970665279, 4678.1813182692895, 4726.976365118169, 4740.360051393052, 4785.234362501983, 4713.222318317474, 4716.292556116576, 4718.103560323562, 4723.929141062887, 4758.119747846659, 4663.27719203524, 4671.85984420468, 4690.788914498856, 4697.694649847783, 4711.693739906895, 4883.34074899891, 4991.401031876162, 5016.423111342206, 5021.548060674557, 5033.421156566968, 5420.376656684492, 5454.236406903036, 5557.749901254453, 5588.329400471929, 5591.3569939457075, 5577.9163312550345, 5580.079286917694, 5591.2731344925305, 5594.160875868612, 5607.775688972436, 5213.791448342062, 5214.139666954948, 5222.112240919053, 5223.3209682741, 5269.841904742864, 5513.7794488220825, 5525.0883604204855, 5576.665400076008, 5613.354464236583, 5623.184638227516, 5443.673379597237, 5448.673079615236, 5459.372172200247, 5464.644798088191, 5519.089350257163, 5453.127499600045, 5522.171674248895, 5530.198121272393, 5542.473465020817, 5559.916601250903, 5311.49540793624, 5423.240746295522, 5487.122060470386, 5561.138023606348, 5578.832635020955, 5468.72656367188, 5471.7264136793, 5474.945250547444, 5475.0, 5477.972610136924, 4262.6376757975295, 4263.5736426357735, 4270.530241673413, 4307.655387568987, 4308.80610372534, 3720.776753394751, 3768.2840260350445, 3802.8478860845808, 3809.3714537101623, 3854.4218367244575, 5095.159298715745, 5120.0, 5126.7180305083575, 5128.00003999224, 5167.457416971164, 5454.754536045887, 5458.344998600193, 5459.3994660587305, 5460.235567020621, 5464.961657285103, 4932.654714170046, 5041.495850415003, 5054.721990290569, 5107.412647545506, 5235.476452354734, 4642.675012749142, 4671.416072990876, 4677.18149323867, 4677.766111694402, 4723.574878260904, 5720.3421606515785, 5724.427557244326, 5727.656340619576, 5742.7702891883855, 5745.482906538432, 5329.973350133225, 5405.405405405324, 5452.263944367618, 5453.345598528043, 5601.60788744784, 5741.45456181662, 5768.192453056659, 5783.537317014545, 5803.071508558611, 5841.065429531254, 5517.448255174611, 5519.503244707996, 5543.1408131739645, 5544.473275038865, 5554.944450555443, 5296.470352964673, 5416.864578385572, 5424.321959755031, 5594.552435805207, 5615.0, 5396.7571459283645, 5403.864903377447, 5451.046066938349, 5474.0967740323995, 5519.365272993497, 5389.946100539101, 5416.268803711606, 5426.565874729933, 5444.8366549003595, 5498.945010549844, 5112.105381558138, 5225.895482090413, 5237.214417837355, 5285.233641122093, 5399.190121481809, 5354.54486368663, 5436.673799572039, 5438.0, 5470.206820011155, 5477.726113694379, 5250.97374513125, 5257.395399529103, 5259.710715910509, 5266.210068489757, 5267.947320526746], "unit": "score", "name": "dojo.html", "value": 5087.694876080205}, {"lowerIsBetter": false, "replicates": [505.2194359714241, 536.4983740202908, 549.2447884159282, 565.3385538919464, 578.9276340457443, 27568.586471202947, 28208.576871346948, 28230.576541351897, 28254.57618135727, 28265.717342826567, 36573.451398229045, 36896.0, 36900.0, 36948.81525592375, 36973.0, 8417.116202798694, 8589.742307730778, 8590.226879580836, 8591.097934716858, 8595.871061934045, 7540.622968851563, 7653.15815260321, 8248.587570621476, 8383.993920729521, 9996.95001524995, 9869.259805514615, 9974.451405172713, 9974.900250997505, 9976.0, 9983.251256155818, 9832.852507212361, 9927.851082233772, 9930.950345248264, 9943.651972180938, 9969.202463802878, 7599.0, 7783.805404864867, 9985.550650220757, 10228.64199753005, 10233.334833235816, 35319.82340088283, 35655.28689426197, 35712.64287357119, 35751.4637280441, 35775.105622359646, 3237.7895436796766, 3310.3213841162506, 3433.399155147864, 3531.993381886176, 3897.5907529709257, 27348.45303093927, 27905.581416278772, 28075.298117547223, 28514.287142821595, 28517.857410712815, 3757.981210093932, 3895.7662540247675, 3974.8012599370204, 3978.920421591552, 4086.0806318578084, 263100.0, 265403.0, 265444.0, 265835.0, 266573.66713166505, 59436.0, 59638.40361596328, 60001.0, 60028.39971600316, 60115.0, 56268.718656406454, 57044.714776425855, 63549.0, 63828.36171638317, 65225.02162467567, 15258.465953691568, 15310.540683779505, 15472.767908481383, 15477.380904763684, 15479.922600386926, 11118.61084862026, 11236.775264494665, 11238.269512481662, 11248.887511124947, 11254.0, 19437.22014506264, 19447.513812154808, 19924.402267931986, 20190.293339733333, 20296.579239453098, 7730.806729831799, 7889.9211007890335, 7958.960205198937, 7968.362530997506, 8007.0, 925.8796356473615, 969.7187815533557, 976.9902300977042, 1010.5907107621425, 1046.9528871200814, 28087.719122808918, 28253.0, 28442.00452984177, 28593.42813143767, 28674.713252867623, 998.1216529454034, 1002.2633364477103, 1009.3439264478001, 1037.1184493180735, 1132.750794825137, 14824.851751482563, 15284.617884552976, 15323.23383830804, 15487.148206848506, 15748.448804291795, 314779.0, 317910.0, 318653.0, 318967.0, 319642.0, 52595.47404525982, 52913.0, 52948.205776913375, 53050.734746326794, 53054.7347263269, 1335.152178366744, 1354.2754626274896, 1366.2485632901864, 1373.5810577773675, 1392.2273138408282, 22325.665115023614, 27779.0, 27931.581026284217, 28349.716502834708, 28398.0, 42146.0, 42489.0, 42609.14781704287, 42647.360289596305, 42660.0, 194319.0, 194884.0, 196033.0, 196721.0, 196808.0, 27189.320266993484, 27236.591451127853, 30068.398632026798, 31085.0, 31816.68183318137, 36799.632003680694, 37048.07379815418, 37532.31104600245, 37814.054648632904, 40665.39001914914, 33686.663133368354, 34512.82743586266, 34641.0, 34874.651253488155, 35937.46093808647, 34171.65828341685, 34274.48588271228, 34442.0, 34855.0, 35145.121371965906, 33756.32487350288, 34222.65777342294, 34295.828520857234, 34648.653513464546, 34717.47923781196, 29707.702922970493, 31260.0, 31814.681853181173, 31851.6814831858, 31919.202019949687, 24087.638685420083, 24128.276151715476, 24139.879300603385, 24408.389790254674, 24468.877655611606], "unit": "score", "name": "ext.html", "value": 17816.847026993793}, {"lowerIsBetter": false, "replicates": [360.35496461334196, 361.9402798538242, 362.7787049899562, 365.8554870826024, 369.5325413352112, 13847.515336963208, 13867.876450191341, 16280.26738796754, 16446.0, 17123.315067397303, 15114.924425377885, 16287.267072981715, 16293.266802993865, 16304.0, 17020.234089465972, 5384.273123128386, 5507.917381239285, 5510.366307874586, 5511.311086114236, 5512.80705175319, 4115.238680844039, 4229.027323715547, 5487.259220005288, 5519.39286678465, 5805.970970145143, 5933.821985340425, 5952.732127054272, 5971.164037034819, 5979.7907073252445, 5981.043033114703, 5398.0, 5540.196671482653, 5546.833594992117, 5547.3343198816, 5952.047672372421, 4913.877153071202, 4948.035133149037, 4961.05739909416, 4969.77636006377, 5134.84595462133, 11875.52497900083, 12137.757244855053, 12161.391930403533, 12163.695907602292, 18077.819221807877, 2059.4542446251753, 2092.278164033406, 2133.6479480885637, 2197.518097207859, 2226.087304205268, 9242.168204861528, 9492.098250666175, 9502.382345147544, 10511.947440262826, 12388.690282742913, 1836.4949638849423, 1862.748528948588, 1867.449102514748, 1882.425860112665, 1893.9053047347716, 58569.12146317809, 58845.411545884424, 59902.70048649773, 61990.38009619936, 62875.685621572055, 9604.66383676568, 9770.0, 9792.951035244778, 9841.360311579725, 9867.309288349747, 12936.09447338696, 12984.350782461028, 13024.218546887218, 13026.0, 13042.608721738363, 7482.428689975166, 7542.0, 7547.433942454338, 7561.054868141482, 7580.810479738051, 7506.099268087864, 7535.773926782205, 7539.962300188574, 7585.62071896403, 7593.316601505891, 3961.801909904493, 3967.4048892665755, 3983.4423180754716, 3988.3020471417385, 4002.519697636244, 1575.2121342276598, 1625.910574918391, 1632.6000129968234, 1646.7365221564733, 1662.2270644150528, 797.3342259213622, 879.8944126704708, 899.1862364560084, 1000.2797985450464, 1000.3747657713928, 16502.2573984171, 16503.17484125789, 16961.830381696273, 17023.93132533132, 17150.914245428692, 817.2930415190935, 862.3833958719546, 902.9548522573845, 909.5634095634133, 1015.7359086637528, 8916.128452189983, 9015.323850711091, 9039.322050846213, 9078.727638170865, 9195.540222988824, 544197.0, 546179.0, 567466.0, 567807.0, 570457.0, 81421.0, 81976.0, 82999.0, 83903.16096839076, 84002.57998709967, 1156.6356597671706, 1158.7276989907366, 1161.6282789507277, 1162.2329262686587, 1173.647905628308, 13930.0, 13959.162450253018, 14123.929380353444, 14198.57404277832, 14234.786478202628, 31690.524642129927, 32133.1966700825, 32737.836310818293, 33731.49402758911, 36380.0, 46502.0, 46684.0, 48563.514364857314, 49743.0, 51922.221166681775, 9190.31072669539, 9372.484513351832, 9554.140127388568, 9555.18780903631, 9562.474063926551, 11195.384253866116, 11211.943940280247, 12501.624951251477, 14819.0, 15011.624709381917, 13931.373088211258, 13971.301434928417, 14726.63183420423, 14907.776383354041, 15075.849241507884, 13740.381682824496, 14004.36980335867, 14094.929525352307, 14306.785398218826, 14475.85524144745, 13942.512012079529, 13964.2319672419, 14062.789058163931, 14560.85439145595, 15212.543623691306, 8982.595783189641, 9274.814503710022, 9395.718128456158, 9414.95292523533, 9490.857637135587, 13596.728065438834, 13745.656358590715, 13903.721925561635, 14069.859301406856, 14194.148351098967], "unit": "score", "name": "jquery.html", "value": 8616.88697194128}, {"lowerIsBetter": false, "replicates": [1307.973840523189, 1470.2060887120956, 1471.551176891048, 1631.8286579909109, 1803.765510483637, 6715.093462382583, 7236.348728614424, 7342.743003994855, 7349.338559529641, 7349.632518374086, 7237.819054523639, 7339.082614673166, 7406.814829629262, 7417.295356941082, 7418.814529636762, 1978.584497255578, 1988.353785019867, 1996.8302694271003, 2230.553889222154, 2273.545290941818, 2007.5683727998478, 2013.8791672499697, 2259.3560835161984, 2299.1952816514254, 2304.0783686525356, 2207.933761987143, 2218.134927378324, 2222.4999375140546, 2225.7774222577773, 2286.2341115726254, 2266.1615202375147, 2272.5909336319455, 2288.359259407377, 2288.9198878039188, 2290.5533420982915, 2298.850574712655, 2323.9651405228933, 2325.976740232593, 2326.139328448468, 2326.9650955235684, 7661.463697541174, 7695.653695583712, 7704.537727736354, 7734.0, 7750.883736743897, 4597.287420449836, 4652.441706995182, 4708.976455117702, 4712.434507859045, 4713.57577817998, 3058.709422604849, 3079.3533357994847, 3159.304952910356, 3181.840907954593, 3206.951895721566, 1003.9949800251024, 1010.4038617215878, 1012.918966482687, 1032.9173666106694, 1080.9189310801642, 10452.111570516516, 10454.581816727321, 10454.947725261402, 10470.947645261802, 10475.371477711362, 4263.232618128766, 4311.0, 4348.499922508889, 4370.300751879748, 4375.4968178659865, 9062.0, 9062.093790620886, 9068.54657267134, 9079.591418386188, 9084.273258139465, 4725.598324142417, 4730.597899178609, 4736.95263047372, 4738.123447162286, 4741.407324084489, 4314.331278651814, 4318.460192475941, 4324.697271190987, 4326.242907491176, 4329.718568293114, 3324.5013248012506, 3634.945475817865, 3650.251698401821, 3702.4631428442726, 3707.073231692077, 1078.4823284823328, 1133.7165708572857, 1151.5202964190973, 1166.836642869999, 1167.7022359298494, 1063.229158859836, 1063.4788953412926, 1091.770728147082, 1202.2846406388185, 1316.8485624153154, 7145.499815013003, 7164.605946672878, 7210.783676489714, 7220.855582888313, 7304.452166087564, 1102.3826657072066, 1117.5473933057124, 1136.0797753819434, 1137.2039572299423, 1140.5437824869953, 5655.604107712503, 5718.085106382959, 5804.100364443518, 5804.593678442468, 5806.622569532967, 8754.21212090915, 8969.058248883774, 8998.775030624287, 9038.412503187272, 9061.093890611019, 8817.16236957488, 8979.10208979097, 9083.137101975302, 9086.909130908607, 9097.0, 7155.034070400429, 7246.601436920914, 7307.926920730831, 7323.2676732326345, 7332.743353982585, 7131.500794944304, 7308.926910730825, 7320.304571065741, 7325.340719335285, 7328.450366222448, 8216.479951209045, 8253.587320633824, 8332.416730829027, 8422.957885210535, 8430.325573954191, 8417.158284171534, 8592.957035214784, 8607.311415086904, 8747.343949203963, 8768.167024132697, 6664.5334826561675, 6929.68816403253, 7014.403775679124, 7022.859542809218, 7023.578585284899, 6694.263631000488, 6759.391654751096, 6936.410405115622, 7036.19083805369, 7038.155421349471, 6909.412699920563, 6918.654067296716, 6943.062686537455, 7042.823929401806, 7061.082059332254, 6785.59286442815, 6940.167179938439, 6959.478039147186, 7070.151581810215, 7079.079719636415, 6979.825504362431, 7025.332593403618, 7078.0, 7079.893801592877, 7086.858262834818, 6391.201099862517, 6409.551331406757, 6449.6130232186215, 6455.838604034937, 6481.643509607017, 6601.636909969806, 6649.767258145942, 6650.20197576294, 6651.866962660816, 6658.267590565131], "unit": "score", "name": "mootools.html", "value": 4441.293264329143}, {"lowerIsBetter": false, "replicates": [339.60436091952874, 344.82069323951544, 345.2232476926914, 346.18300810088186, 354.22248165277216, 5822.825315240544, 5863.941360586393, 5900.055991041434, 5915.733791979361, 5941.970290148549, 5527.55779537637, 5600.775968961237, 5685.687287199203, 5813.767449302023, 5869.178315035899, 4486.753228572427, 4504.324351347308, 4505.617022553079, 4507.549245075483, 4519.66102542309, 4250.404943307939, 4512.187806194884, 4521.547845215485, 4521.728696278217, 4525.117602067598, 4297.119090586422, 4390.187815254172, 4464.6651501137385, 4467.687261891669, 4470.575295346936, 4417.337399390085, 4463.4866990296205, 4471.731696098228, 4493.123840851035, 4493.236149854532, 3971.940420893689, 3980.0646847990697, 3981.3231750602176, 3984.2828290907623, 3986.1629057897876, 5446.373667028302, 5478.534324582415, 5484.177373393983, 5490.752916118784, 5499.587530935156, 5218.399884013349, 5218.921716174261, 5222.373315202162, 5222.686638801684, 5226.8693282667855, 6124.2957059938235, 6131.816045518642, 6135.987562052256, 6137.84655383619, 6157.692115394258, 4354.129174165148, 4354.216241076605, 4377.802998865059, 4381.255186618282, 4393.725819512338, 6331.525135614847, 6452.257990331125, 6464.159659244268, 6475.125858009155, 6476.028595710634, 4490.7754612269255, 4499.932501012488, 4500.482444518922, 4500.774961251925, 4504.414426124583, 4541.409616749801, 4547.636189104864, 4554.476235232924, 4556.384888040138, 4559.703619264737, 5217.58259339252, 5228.398734145546, 5236.397814251333, 5238.266642670029, 5242.895142097137, 6109.175261339662, 6227.813165605039, 6229.875402491924, 6230.532710046674, 6238.688065596702, 1592.2516417283857, 1611.3876726843725, 1611.5890447935935, 1613.677264547072, 1615.071333982941, 1108.8357224913968, 1109.5728144664363, 1115.3085087245959, 1126.8760436351995, 1137.9317240965568, 5896.616719913192, 5908.822735317947, 5909.970450147808, 5913.201717768132, 5913.9112913306335, 6351.936480635227, 6353.491720662336, 6358.0, 6362.491000719931, 6368.363163683687, 5700.201971723962, 5766.3368712598585, 5814.098814683731, 5889.558283128783, 5900.852478688067, 3400.3879301725433, 3423.17843717506, 3427.708644765173, 3429.53701250333, 3430.0, 6621.5364924454825, 6627.801165965029, 6630.071789949411, 6683.164604424447, 6722.9663851680425, 6386.8722625547225, 6568.376004279586, 6571.0, 6574.3754343337305, 6578.375054369827, 6687.164104486939, 6699.095622091053, 6699.866002679919, 6701.061851340816, 6701.530892837454, 6381.968090159705, 6382.521310901794, 6388.456981156468, 6393.1688880445245, 6394.4564712, 6126.0, 6347.5556711029785, 6353.745850165941, 6368.713407896563, 6378.425941665272, 6702.597844129173, 6711.295313991961, 6714.127163468718, 6718.731250750111, 6720.1599800025, 4527.253003254426, 4549.499555049008, 4578.65660075489, 4579.7252164870215, 4581.221192397234, 6275.592086514362, 6380.872382552416, 6392.968035159794, 6431.099646049651, 6449.355064493513, 6279.874402512016, 6288.339724329063, 6291.521492449362, 6291.559590828781, 6294.402031806971, 6249.125122482947, 6264.404881536247, 6268.467180289544, 6268.965620672538, 6275.560710750204, 6034.064719968412, 6101.169649105339, 6151.6924153793025, 6156.26124865019, 6162.630242185483, 6297.748090076529, 6309.022101574264, 6313.0, 6371.936280637135, 6380.649064301508, 6128.50971922254, 6144.324124346408, 6150.523874270053, 6156.630602163885, 6160.0], "unit": "score", "name": "prototype.html", "value": 4715.192712178771}, {"lowerIsBetter": false, "replicates": [842.414521907274, 850.9149085091491, 864.0495454999501, 864.6800683747012, 879.5821984557336, 13456.798148027776, 13620.114692544978, 13665.0, 13680.863191368084, 13685.973551983603, 12931.41808618612, 12938.805917911239, 12991.74016519669, 13002.024848136403, 13002.089853710244, 670.5105273150591, 681.7750142452978, 682.8736683713504, 683.6684208159039, 683.7094234950151, 807.2169995104739, 827.7144385187131, 827.93790465715, 829.8589239829213, 830.7881490219985, 860.1484530315006, 865.3293697384546, 870.3907264914585, 874.0822136756381, 874.6807415293404, 700.4676445901101, 710.098175317344, 859.1365677494109, 862.4307956748547, 868.2055918834259, 628.1614045249597, 638.5976834594222, 639.2584601861864, 639.6130341143635, 640.0910706796313, 13141.211527308296, 13259.933700331532, 13273.402696878567, 13284.07011509185, 13294.933525332408, 11920.523179072827, 11988.460519276654, 12023.895818231862, 12560.874391256062, 12635.1787133836, 9723.222142228604, 9873.308868379216, 9979.201663866874, 10054.899451005469, 10112.848307275397, 3687.8893633191046, 3701.648343407399, 3709.0912726382207, 3788.924221515554, 3848.461215429842, 43218.56781432209, 44009.77995110036, 44261.557384426065, 44455.55544444547, 45431.77284113591, 9750.658726944523, 9861.802763944681, 9892.455914924605, 10170.898291017143, 10307.536160872778, 18857.151428185764, 19090.618187636446, 19175.0, 19179.32872349461, 19208.423747287605, 7591.3926885849, 7781.455298129076, 7856.0, 7863.252990965849, 7884.250996155356, 4384.69307148503, 4512.390827238347, 4546.113507866001, 4562.703424277412, 4571.885702857388, 3141.874325026973, 3173.4129186100645, 3175.666555011737, 3176.587043684306, 3179.9364012719616, 562.6230425614848, 567.7104676614956, 568.8890666320028, 571.4913726783083, 575.1085816983658, 618.653554009756, 656.4321861589691, 706.2231545300128, 713.2974020589714, 716.1155972374114, 8170.0, 8474.279686226617, 8594.097619749837, 8616.913830861737, 8840.83524843499, 489.42003725585295, 576.5819780658989, 586.6626689653381, 656.5929123943185, 693.0470602920983, 4030.1939612077117, 4056.918861622751, 4114.403411505315, 4124.154548317648, 4136.276151673445, 50254.0, 51276.0, 52691.73654131781, 52723.20915186275, 53181.46818531842, 45467.31799023017, 45588.0, 46072.0, 46427.30359044617, 48419.0, 43049.569504304556, 43593.0, 44184.3372349415, 44186.33720494195, 44677.55322446799, 8436.70471533518, 8691.695790647296, 9020.14308640678, 9028.683996060106, 9049.827506842827, 32346.353072937938, 33020.0, 33407.49888751622, 33565.0, 33922.15194620154, 38082.0, 40896.0, 41443.37834932539, 41484.377734334616, 42156.57843421526, 10703.919084715682, 11038.613648522263, 11169.72075698114, 11203.99164075237, 11406.0, 11976.041971137247, 12097.186320087663, 12245.877541224474, 12260.38698065111, 12272.877271227173, 11468.19722619442, 11469.197156198987, 11476.885231147582, 11660.416979151178, 11871.940640296743, 9750.951245243728, 9890.851637225303, 10031.74920626961, 10084.344517606332, 10094.89905100969, 9646.9035309646, 10442.53008614599, 10708.839367409651, 11306.60426885088, 11756.70608234801, 8830.779230519289, 9146.496942668216, 9166.495842728715, 9187.494687792236, 9779.804403912025, 8640.740777776677, 8722.345824063348, 8996.955015224881, 9077.727668169966, 9173.770655733393], "unit": "score", "name": "yui.html", "value": 6187.39888759276}], "name": "dromaeo_css", "value": 6826.681436732892}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 111.0, 109.0, 110.0, 102.0, 110.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [157.0, 126.0, 151.0, 165.0, 132.0, 149.0, 152.0, 134.0, 170.0, 124.0], "unit": "score", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 162.0, 159.0, 160.0, 160.0, 162.0, 161.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [74.0, 72.0, 90.0, 105.0, 71.0, 71.0, 71.0, 96.0, 87.0, 72.0], "unit": "score", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 141.0, 97.0, 98.0, 97.0, 136.0, 95.0, 97.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 109.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 56.0, 57.0, 57.0, 59.0, 56.0, 65.0, 54.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 84.0, 85.0, 85.0, 86.0, 85.0, 86.0, 98.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.3}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 116.0, 125.0, 123.0, 116.0, 114.0, 124.0, 115.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.4}, {"lowerIsBetter": true, "replicates": [150.0, 150.0, 158.0, 156.0, 150.0, 142.0, 151.0, 151.0, 153.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.3}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 70.0, 68.0, 69.0, 67.0, 69.0, 69.0, 68.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1504.5000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.5603217158177], "unit": "score", "name": "Richards", "value": 210.68233600000002}, {"lowerIsBetter": false, "replicates": [190.43991620643686], "unit": "score", "name": "DeltaBlue", "value": 347.1856180000001}, {"lowerIsBetter": false, "replicates": [280.97780275358247], "unit": "score", "name": "Encrypt", "value": 947.3381789999996}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1976.2845849802372], "unit": "score", "name": "RayTrace", "value": 374.434434}, {"lowerIsBetter": false, "replicates": [774.1686001546791], "unit": "score", "name": "Earley", "value": 860.8757832167834}, {"lowerIsBetter": false, "replicates": [12180.722891566265], "unit": "score", "name": "Boyer", "value": 54.714568743818035}, {"lowerIsBetter": false, "replicates": [25820.51282051282], "unit": "score", "name": "RegExp", "value": 35.28144488579943}, {"lowerIsBetter": false, "replicates": [614.6281499692686], "unit": "score", "name": "Splay", "value": 132.585857}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19826.337759204598}]} 22:16:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19826.34"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1504.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6826.68"}}} 22:16:31 INFO - Return code: 0 22:16:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:16:31 INFO - # TBPL SUCCESS # 22:16:31 INFO - Running post-action listener: _resource_record_post_action 22:16:31 INFO - Running post-run listener: _resource_record_post_run 22:16:32 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 4096; Write bytes: 550866944; Read time: 12; Write time: 827280 22:16:32 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 247447552; Read time: 0; Write time: 489528 22:16:32 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 302555136; Read time: 12; Write time: 337716 22:16:32 INFO - Running post-run listener: _upload_blobber_files 22:16:32 INFO - Blob upload gear active. 22:16:32 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:16:32 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:16:32 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:16:32 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:16:32 INFO - (blobuploader) - INFO - Open directory for files ... 22:16:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:16:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:16:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:33 INFO - (blobuploader) - INFO - Done attempting. 22:16:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:16:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:16:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:34 INFO - (blobuploader) - INFO - Done attempting. 22:16:34 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:34 INFO - Return code: 0 22:16:34 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:16:34 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9c6bd738cee718c3bb022449bb7b5096070849c36f8cdacf22086fc62e1bf2c349f3d3e5eda5bc22445f8a8d800d4793c4bbb4cb5f82f96ffe0154848b3c0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb8e29cc81c9739bfb4be36ea771ac36b71295c8d632309cb471be840fc451b86569299b80f99852ba902e2ad73e4d10c0ee11ea2e9fef1c83016809b3f9f072"} 22:16:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:16:34 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:16:34 INFO - Contents: 22:16:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9c6bd738cee718c3bb022449bb7b5096070849c36f8cdacf22086fc62e1bf2c349f3d3e5eda5bc22445f8a8d800d4793c4bbb4cb5f82f96ffe0154848b3c0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb8e29cc81c9739bfb4be36ea771ac36b71295c8d632309cb471be840fc451b86569299b80f99852ba902e2ad73e4d10c0ee11ea2e9fef1c83016809b3f9f072"} 22:16:34 INFO - Copying logs to upload dir... 22:16:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.622607 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-01 22:16:33.352808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-01 22:16:33.356634) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9c6bd738cee718c3bb022449bb7b5096070849c36f8cdacf22086fc62e1bf2c349f3d3e5eda5bc22445f8a8d800d4793c4bbb4cb5f82f96ffe0154848b3c0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb8e29cc81c9739bfb4be36ea771ac36b71295c8d632309cb471be840fc451b86569299b80f99852ba902e2ad73e4d10c0ee11ea2e9fef1c83016809b3f9f072"} build_url:https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/b6piR98XTxe6Y0HTdTeyBw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9c6bd738cee718c3bb022449bb7b5096070849c36f8cdacf22086fc62e1bf2c349f3d3e5eda5bc22445f8a8d800d4793c4bbb4cb5f82f96ffe0154848b3c0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb8e29cc81c9739bfb4be36ea771ac36b71295c8d632309cb471be840fc451b86569299b80f99852ba902e2ad73e4d10c0ee11ea2e9fef1c83016809b3f9f072"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-01 22:16:33.382291) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 22:16:33.382687) ========= 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=e10134013339c31d8d0c4708000001d6-1451713795.134678-1438703936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 22:16:33.465373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-01 22:16:33.465724) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-01 22:16:33.466059) ========= ========= Total master_lag: 0.23 =========