builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-031 starttime: 1451223084.59 results: success (0) buildid: 20151227030239 builduid: 3baf4a9ffa2a44acbcc891c006153c42 revision: c39a71cf41460924d2988d2d63be2f5d267db82a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.592056) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.592561) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.592919) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006268 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.649981) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.650423) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.770158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.770452) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004655 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.842555) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.842834) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.843238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.843528) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-27 05:31:24-- 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% 72.1M=0s 2015-12-27 05:31:25 (72.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.965392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.965742) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019927 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.020180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.020569) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c39a71cf41460924d2988d2d63be2f5d267db82a --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 c39a71cf41460924d2988d2d63be2f5d267db82a --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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-27 05:31:25,164 truncating revision to first 12 chars 2015-12-27 05:31:25,165 Setting DEBUG logging. 2015-12-27 05:31:25,165 attempt 1/10 2015-12-27 05:31:25,165 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c39a71cf4146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-27 05:31:25,521 unpacking tar archive at: mozilla-central-c39a71cf4146/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.673653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.673953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.683283) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.683648) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.684126) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-27 05:31:25.684423) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:25 INFO - MultiFileLogger online at 20151227 05:31:25 in /builds/slave/test-pgo 05:31:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:31:25 INFO - {'append_to_log': False, 05:31:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:31:25 INFO - 'blob_upload_branch': 'Firefox', 05:31:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:31:25 INFO - 'branch': 'Firefox', 05:31:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:25 INFO - 'config_files': ('talos/linux_config.py',), 05:31:25 INFO - 'default_actions': ('clobber', 05:31:25 INFO - 'read-buildbot-config', 05:31:25 INFO - 'download-and-extract', 05:31:25 INFO - 'populate-webroot', 05:31:25 INFO - 'create-virtualenv', 05:31:25 INFO - 'install', 05:31:25 INFO - 'run-tests'), 05:31:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:25 INFO - 'download_minidump_stackwalk': True, 05:31:25 INFO - 'download_symbols': 'ondemand', 05:31:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:25 INFO - 'installer_path': 'installer.exe', 05:31:25 INFO - 'log_level': 'info', 05:31:25 INFO - 'log_name': 'talos', 05:31:25 INFO - 'log_to_console': True, 05:31:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:25 INFO - 'opt_config_files': (), 05:31:25 INFO - 'pip_index': False, 05:31:25 INFO - 'sps_profile': False, 05:31:25 INFO - 'sps_profile_interval': 0, 05:31:25 INFO - 'suite': 'dromaeojs-e10s', 05:31:25 INFO - 'system_bits': '64', 05:31:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:25 INFO - 'title': 'talos-linux64-ix-031', 05:31:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:25 INFO - 'use_talos_json': True, 05:31:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:31:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:25 INFO - 'work_dir': 'build'} 05:31:25 INFO - ##### 05:31:25 INFO - ##### Running clobber step. 05:31:25 INFO - ##### 05:31:25 INFO - Running pre-action listener: _resource_record_pre_action 05:31:25 INFO - Running main action method: clobber 05:31:25 INFO - rmtree: /builds/slave/test-pgo/build 05:31:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:31:26 INFO - Running post-action listener: _resource_record_post_action 05:31:26 INFO - ##### 05:31:26 INFO - ##### Running read-buildbot-config step. 05:31:26 INFO - ##### 05:31:26 INFO - Running pre-action listener: _resource_record_pre_action 05:31:26 INFO - Running main action method: read_buildbot_config 05:31:26 INFO - Using buildbot properties: 05:31:26 INFO - { 05:31:26 INFO - "project": "", 05:31:26 INFO - "product": "firefox", 05:31:26 INFO - "script_repo_revision": "production", 05:31:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:31:26 INFO - "repository": "", 05:31:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:31:26 INFO - "buildid": "20151227030239", 05:31:26 INFO - "stage_platform": "linux64-pgo", 05:31:26 INFO - "basedir": "/builds/slave/test-pgo", 05:31:26 INFO - "nightly_build": "True", 05:31:26 INFO - "buildnumber": 598, 05:31:26 INFO - "slavename": "talos-linux64-ix-031", 05:31:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:31:26 INFO - "platform": "ubuntu64_hw", 05:31:26 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:31:26 INFO - "branch": "mozilla-central", 05:31:26 INFO - "repo_path": "mozilla-central", 05:31:26 INFO - "revision": "c39a71cf41460924d2988d2d63be2f5d267db82a", 05:31:26 INFO - "pgo_build": "True", 05:31:26 INFO - "builduid": "3baf4a9ffa2a44acbcc891c006153c42", 05:31:26 INFO - "slavebuilddir": "test" 05:31:26 INFO - } 05:31:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:31:26 INFO - Running post-action listener: _resource_record_post_action 05:31:26 INFO - ##### 05:31:26 INFO - ##### Running download-and-extract step. 05:31:26 INFO - ##### 05:31:26 INFO - Running pre-action listener: _resource_record_pre_action 05:31:26 INFO - Running main action method: download_and_extract 05:31:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:26 INFO - https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:31:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/test_packages.json 05:31:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/test_packages.json 05:31:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:31:27 INFO - Downloaded 1302 bytes. 05:31:27 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:31:27 INFO - Using the following test package requirements: 05:31:27 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:27 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:27 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'jsshell-linux-x86_64.zip'], 05:31:27 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:27 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:27 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:27 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:27 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:27 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:27 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:27 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31: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')]} 05:31:27 INFO - https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:29 INFO - Downloaded 23192124 bytes. 05:31:29 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 05:31:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:29 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 05:31:30 INFO - Return code: 0 05:31:30 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 05:31:30 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')]} 05:31:30 INFO - https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:31 INFO - Downloaded 23192124 bytes. 05:31: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 05:31:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31: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 05:31:32 INFO - Return code: 0 05:31: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')]} 05:31:32 INFO - https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:31:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:35 INFO - Downloaded 11132270 bytes. 05:31:35 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 05:31:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:35 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 05:31:35 INFO - Return code: 0 05:31: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')]} 05:31:35 INFO - https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:31:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/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 05:31:36 INFO - Downloaded 54916794 bytes. 05:31:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:36 INFO - mkdir: /builds/slave/test-pgo/properties 05:31:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:31:36 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:31:36 INFO - Contents: 05:31:36 INFO - build_url:https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:36 INFO - Running post-action listener: _resource_record_post_action 05:31:36 INFO - Running post-action listener: set_extra_try_arguments 05:31:36 INFO - ##### 05:31:36 INFO - ##### Running populate-webroot step. 05:31:36 INFO - ##### 05:31:36 INFO - Running pre-action listener: _resource_record_pre_action 05:31:36 INFO - Running main action method: populate_webroot 05:31:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:36 INFO - '--tppagecycles', 05:31:36 INFO - '7'], 05:31:36 INFO - 'tests': ['tsvgm']}}, 05:31:36 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:31:36 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:36 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:36 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'tests': ['damp', 'tps']}, 05:31:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['damp', 'tps']}, 05:31:36 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:31:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:36 INFO - 'other': {'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'other_l64': {'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'other_nol64': {'tests': ['a11yr', 05:31:36 INFO - 'ts_paint', 05:31:36 INFO - 'tpaint', 05:31:36 INFO - 'sessionrestore', 05:31:36 INFO - 'sessionrestore_no_auto_restore']}, 05:31:36 INFO - 'svgr': {'tests': ['tsvgx', 05:31:36 INFO - 'tsvgr_opacity', 05:31:36 INFO - 'tart', 05:31:36 INFO - 'tscrollx', 05:31:36 INFO - 'cart']}, 05:31:36 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['tsvgx', 05:31:36 INFO - 'tsvgr_opacity', 05:31:36 INFO - 'tart', 05:31:36 INFO - 'tscrollx', 05:31:36 INFO - 'cart']}, 05:31:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'tests': ['tp5o']}, 05:31:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'talos_options': ['--e10s'], 05:31:36 INFO - 'tests': ['tp5o']}, 05:31:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'talos_options': ['--xperf_path', 05:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:36 INFO - 'tests': ['tp5n']}, 05:31:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:36 INFO - 'talos_options': ['--e10s', 05:31:36 INFO - '--xperf_path', 05:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:36 INFO - 'tests': ['tp5n']}}, 05:31:36 INFO - 'talos.zip': {'path': '', 05:31:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:36 INFO - Running post-action listener: _resource_record_post_action 05:31:36 INFO - ##### 05:31:36 INFO - ##### Running create-virtualenv step. 05:31:36 INFO - ##### 05:31:36 INFO - Running pre-action listener: _resource_record_pre_action 05:31:36 INFO - Running main action method: create_virtualenv 05:31:36 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:31:36 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 05:31:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:31:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:36 INFO - Using real prefix '/usr' 05:31:36 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:31:37 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:39 INFO - Installing pip.................done. 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:39 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 05:31:39 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 05:31:39 INFO - Using env: {'DISPLAY': ':0', 05:31:39 INFO - 'HOME': '/home/cltbld', 05:31:39 INFO - 'LANG': 'en_US.UTF-8', 05:31:39 INFO - 'LANGUAGE': 'en_US:en', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MAIL': '/var/mail/cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SHLVL': '1', 05:31:39 INFO - 'TERM': 'linux', 05:31:39 INFO - 'TMOUT': '86400', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - '_': '/tools/buildbot/bin/python'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 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 05:31:39 INFO - Cleaning up... 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:39 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 05:31:39 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 05:31:39 INFO - Using env: {'DISPLAY': ':0', 05:31:39 INFO - 'HOME': '/home/cltbld', 05:31:39 INFO - 'LANG': 'en_US.UTF-8', 05:31:39 INFO - 'LANGUAGE': 'en_US:en', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MAIL': '/var/mail/cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SHLVL': '1', 05:31:39 INFO - 'TERM': 'linux', 05:31:39 INFO - 'TMOUT': '86400', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - '_': '/tools/buildbot/bin/python'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 INFO - Downloading/unpacking psutil>=0.7.1 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:41 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:31:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:31:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:41 INFO - Installing collected packages: psutil 05:31:41 INFO - Running setup.py install for psutil 05:31:41 INFO - building 'psutil._psutil_linux' extension 05:31:41 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 05:31:41 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 05:31:42 INFO - building 'psutil._psutil_posix' extension 05:31:42 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 05:31:42 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 05:31:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:42 INFO - Successfully installed psutil 05:31:42 INFO - Cleaning up... 05:31:42 INFO - Return code: 0 05:31:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', '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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:31: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 mozsystemmonitor==0.0.0 05:31:42 INFO - Using env: {'DISPLAY': ':0', 05:31:42 INFO - 'HOME': '/home/cltbld', 05:31:42 INFO - 'LANG': 'en_US.UTF-8', 05:31:42 INFO - 'LANGUAGE': 'en_US:en', 05:31:42 INFO - 'LOGNAME': 'cltbld', 05:31:42 INFO - 'MAIL': '/var/mail/cltbld', 05:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:42 INFO - 'MOZ_NO_REMOTE': '1', 05:31:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:42 INFO - 'NO_EM_RESTART': '1', 05:31:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:42 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:42 INFO - 'SHELL': '/bin/bash', 05:31:42 INFO - 'SHLVL': '1', 05:31:42 INFO - 'TERM': 'linux', 05:31:42 INFO - 'TMOUT': '86400', 05:31:42 INFO - 'USER': 'cltbld', 05:31:42 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:42 INFO - '_': '/tools/buildbot/bin/python'} 05:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:31:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:31:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:31:44 INFO - Installing collected packages: mozsystemmonitor 05:31:44 INFO - Running setup.py install for mozsystemmonitor 05:31:44 INFO - Successfully installed mozsystemmonitor 05:31:44 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', '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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:31: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 blobuploader==1.2.4 05:31:44 INFO - Using env: {'DISPLAY': ':0', 05:31:44 INFO - 'HOME': '/home/cltbld', 05:31:44 INFO - 'LANG': 'en_US.UTF-8', 05:31:44 INFO - 'LANGUAGE': 'en_US:en', 05:31:44 INFO - 'LOGNAME': 'cltbld', 05:31:44 INFO - 'MAIL': '/var/mail/cltbld', 05:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:44 INFO - 'MOZ_NO_REMOTE': '1', 05:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:44 INFO - 'NO_EM_RESTART': '1', 05:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:44 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:44 INFO - 'SHELL': '/bin/bash', 05:31:44 INFO - 'SHLVL': '1', 05:31:44 INFO - 'TERM': 'linux', 05:31:44 INFO - 'TMOUT': '86400', 05:31:44 INFO - 'USER': 'cltbld', 05:31:44 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:44 INFO - '_': '/tools/buildbot/bin/python'} 05:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:44 INFO - Downloading/unpacking blobuploader==1.2.4 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:46 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:31:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:31:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:31:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 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 05:31:46 INFO - Downloading docopt-0.6.1.tar.gz 05:31:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:31:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:47 INFO - Running setup.py install for blobuploader 05:31:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:47 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:31:47 INFO - Running setup.py install for requests 05:31:47 INFO - Running setup.py install for docopt 05:31:47 INFO - Successfully installed blobuploader requests docopt 05:31:47 INFO - Cleaning up... 05:31:47 INFO - Return code: 0 05:31:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:47 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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:47 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 05:31:47 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 05:31:47 INFO - Using env: {'DISPLAY': ':0', 05:31:47 INFO - 'HOME': '/home/cltbld', 05:31:47 INFO - 'LANG': 'en_US.UTF-8', 05:31:47 INFO - 'LANGUAGE': 'en_US:en', 05:31:47 INFO - 'LOGNAME': 'cltbld', 05:31:47 INFO - 'MAIL': '/var/mail/cltbld', 05:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:47 INFO - 'MOZ_NO_REMOTE': '1', 05:31:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:47 INFO - 'NO_EM_RESTART': '1', 05:31:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:47 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:47 INFO - 'SHELL': '/bin/bash', 05:31:47 INFO - 'SHLVL': '1', 05:31:47 INFO - 'TERM': 'linux', 05:31:47 INFO - 'TMOUT': '86400', 05:31:47 INFO - 'USER': 'cltbld', 05:31:47 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:47 INFO - '_': '/tools/buildbot/bin/python'} 05:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:47 INFO - Running setup.py (path:/tmp/pip-e8lAdS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:48 INFO - Running setup.py (path:/tmp/pip-dN_mz1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:48 INFO - Running setup.py (path:/tmp/pip-qAUdkf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:48 INFO - Running setup.py (path:/tmp/pip-Miluxq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:48 INFO - Running setup.py (path:/tmp/pip-g9KST7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:48 INFO - Running setup.py (path:/tmp/pip-rAsPXs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:48 INFO - Running setup.py (path:/tmp/pip-X0xhfv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:48 INFO - Running setup.py (path:/tmp/pip-CPsQtg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:48 INFO - Running setup.py (path:/tmp/pip-1Jc2WI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:48 INFO - Running setup.py (path:/tmp/pip-5d2mYg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:48 INFO - Running setup.py (path:/tmp/pip-1pGQYD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:48 INFO - Running setup.py (path:/tmp/pip-0SweBW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:48 INFO - Running setup.py (path:/tmp/pip-_AYVtd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:48 INFO - Running setup.py (path:/tmp/pip-3IZW7G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:48 INFO - Running setup.py (path:/tmp/pip-VfGkd8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:49 INFO - Running setup.py (path:/tmp/pip-iGSGnp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:49 INFO - Running setup.py (path:/tmp/pip-JnElqV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:49 INFO - Running setup.py install for manifestparser 05:31:49 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Running setup.py install for mozcrash 05:31:49 INFO - Running setup.py install for mozdebug 05:31:49 INFO - Running setup.py install for mozdevice 05:31:49 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Running setup.py install for mozfile 05:31:49 INFO - Running setup.py install for mozhttpd 05:31:49 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Running setup.py install for mozinfo 05:31:49 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Running setup.py install for mozInstall 05:31:49 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:31:49 INFO - Running setup.py install for mozleak 05:31:50 INFO - Running setup.py install for mozlog 05:31:50 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Running setup.py install for moznetwork 05:31:50 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Running setup.py install for mozprocess 05:31:50 INFO - Running setup.py install for mozprofile 05:31:50 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Running setup.py install for mozrunner 05:31:50 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Running setup.py install for mozscreenshot 05:31:50 INFO - Running setup.py install for moztest 05:31:50 INFO - Running setup.py install for mozversion 05:31:50 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:31:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:50 INFO - Cleaning up... 05:31:50 INFO - Return code: 0 05:31:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:50 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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:50 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 05:31:50 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 05:31:50 INFO - Using env: {'DISPLAY': ':0', 05:31:50 INFO - 'HOME': '/home/cltbld', 05:31:50 INFO - 'LANG': 'en_US.UTF-8', 05:31:50 INFO - 'LANGUAGE': 'en_US:en', 05:31:50 INFO - 'LOGNAME': 'cltbld', 05:31:51 INFO - 'MAIL': '/var/mail/cltbld', 05:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:51 INFO - 'MOZ_NO_REMOTE': '1', 05:31:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:51 INFO - 'NO_EM_RESTART': '1', 05:31:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:51 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:51 INFO - 'SHELL': '/bin/bash', 05:31:51 INFO - 'SHLVL': '1', 05:31:51 INFO - 'TERM': 'linux', 05:31:51 INFO - 'TMOUT': '86400', 05:31:51 INFO - 'USER': 'cltbld', 05:31:51 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:51 INFO - '_': '/tools/buildbot/bin/python'} 05:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:51 INFO - Running setup.py (path:/tmp/pip-RXgb9Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:51 INFO - Running setup.py (path:/tmp/pip-lHW_Ex-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:51 INFO - Running setup.py (path:/tmp/pip-UsPWxh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:51 INFO - Running setup.py (path:/tmp/pip-lNp5Ys-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:51 INFO - Running setup.py (path:/tmp/pip-Y_ht0e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:51 INFO - Running setup.py (path:/tmp/pip-S_e9r7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:51 INFO - Running setup.py (path:/tmp/pip-OC9skl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:51 INFO - Running setup.py (path:/tmp/pip-HDRr9O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:51 INFO - Running setup.py (path:/tmp/pip-1pLz2e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:51 INFO - Running setup.py (path:/tmp/pip-8nVk8I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:51 INFO - Running setup.py (path:/tmp/pip-wZQuyT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:51 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)) 05:31:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:52 INFO - Running setup.py (path:/tmp/pip-8vZYTa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:52 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)) 05:31:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:52 INFO - Running setup.py (path:/tmp/pip-i7lLjb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:52 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)) 05:31:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:52 INFO - Running setup.py (path:/tmp/pip-HxtJUK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:52 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)) 05:31:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:52 INFO - Running setup.py (path:/tmp/pip-qrgGm5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:52 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)) 05:31:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:52 INFO - Running setup.py (path:/tmp/pip-ksX4uG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:52 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)) 05:31:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:52 INFO - Running setup.py (path:/tmp/pip-wc06lS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - Downloading blessings-1.5.1.tar.gz 05:31:54 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 05:31:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:54 INFO - Installing collected packages: blessings 05:31:54 INFO - Running setup.py install for blessings 05:31:54 INFO - Successfully installed blessings 05:31:54 INFO - Cleaning up... 05:31:54 INFO - Return code: 0 05:31:54 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:31:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:31:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:31:54 INFO - Reading from file tmpfile_stdout 05:31:54 INFO - Current package versions: 05:31:54 INFO - argparse == 1.2.1 05:31:54 INFO - blessings == 1.5.1 05:31:54 INFO - blobuploader == 1.2.4 05:31:54 INFO - docopt == 0.6.1 05:31:54 INFO - manifestparser == 1.1 05:31:54 INFO - mozInstall == 1.12 05:31:54 INFO - mozcrash == 0.16 05:31:54 INFO - mozdebug == 0.1 05:31:54 INFO - mozdevice == 0.47 05:31:54 INFO - mozfile == 1.2 05:31:54 INFO - mozhttpd == 0.7 05:31:54 INFO - mozinfo == 0.9 05:31:54 INFO - mozleak == 0.1 05:31:54 INFO - mozlog == 3.1 05:31:54 INFO - moznetwork == 0.27 05:31:54 INFO - mozprocess == 0.22 05:31:54 INFO - mozprofile == 0.28 05:31:54 INFO - mozrunner == 6.11 05:31:54 INFO - mozscreenshot == 0.1 05:31:54 INFO - mozsystemmonitor == 0.0 05:31:54 INFO - moztest == 0.7 05:31:54 INFO - mozversion == 1.4 05:31:54 INFO - psutil == 3.1.1 05:31:54 INFO - requests == 1.2.3 05:31:54 INFO - wsgiref == 0.1.2 05:31:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:54 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 05:31:54 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 05:31:54 INFO - Using env: {'DISPLAY': ':0', 05:31:54 INFO - 'HOME': '/home/cltbld', 05:31:54 INFO - 'LANG': 'en_US.UTF-8', 05:31:54 INFO - 'LANGUAGE': 'en_US:en', 05:31:54 INFO - 'LOGNAME': 'cltbld', 05:31:54 INFO - 'MAIL': '/var/mail/cltbld', 05:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:54 INFO - 'MOZ_NO_REMOTE': '1', 05:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:54 INFO - 'NO_EM_RESTART': '1', 05:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:54 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:54 INFO - 'SHELL': '/bin/bash', 05:31:54 INFO - 'SHLVL': '1', 05:31:54 INFO - 'TERM': 'linux', 05:31:54 INFO - 'TMOUT': '86400', 05:31:54 INFO - 'USER': 'cltbld', 05:31:54 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:54 INFO - '_': '/tools/buildbot/bin/python'} 05:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31: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 mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:31:54 INFO - Cleaning up... 05:31:54 INFO - Return code: 0 05:31:54 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:31:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:31:54 INFO - Using env: {'DISPLAY': ':0', 05:31:54 INFO - 'HOME': '/home/cltbld', 05:31:54 INFO - 'LANG': 'en_US.UTF-8', 05:31:54 INFO - 'LANGUAGE': 'en_US:en', 05:31:54 INFO - 'LOGNAME': 'cltbld', 05:31:54 INFO - 'MAIL': '/var/mail/cltbld', 05:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:54 INFO - 'MOZ_NO_REMOTE': '1', 05:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:54 INFO - 'NO_EM_RESTART': '1', 05:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:54 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:54 INFO - 'SHELL': '/bin/bash', 05:31:54 INFO - 'SHLVL': '1', 05:31:54 INFO - 'TERM': 'linux', 05:31:54 INFO - 'TMOUT': '86400', 05:31:54 INFO - 'USER': 'cltbld', 05:31:54 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:54 INFO - '_': '/tools/buildbot/bin/python'} 05:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:55 INFO - Downloading/unpacking jsonschema 05:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:56 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 05:31:56 INFO - Downloading/unpacking functools32 (from jsonschema) 05:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:56 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:31:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:56 INFO - warning: no files found matching '*.txt' 05:31:56 INFO - no previously-included directories found matching 'build' 05:31:56 INFO - no previously-included directories found matching 'dist' 05:31:56 INFO - no previously-included directories found matching '.git*' 05:31:56 INFO - Installing collected packages: jsonschema, functools32 05:31:56 INFO - Running setup.py install for functools32 05:31:57 INFO - warning: no files found matching '*.txt' 05:31:57 INFO - no previously-included directories found matching 'build' 05:31:57 INFO - no previously-included directories found matching 'dist' 05:31:57 INFO - no previously-included directories found matching '.git*' 05:31:57 INFO - Successfully installed jsonschema functools32 05:31:57 INFO - Cleaning up... 05:31:57 INFO - Return code: 0 05:31:57 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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')]} 05:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3a6d18ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2015630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', '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 05:31: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', 'flake8'] in /builds/slave/test-pgo/build 05:31: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 flake8 05:31:57 INFO - Using env: {'DISPLAY': ':0', 05:31:57 INFO - 'HOME': '/home/cltbld', 05:31:57 INFO - 'LANG': 'en_US.UTF-8', 05:31:57 INFO - 'LANGUAGE': 'en_US:en', 05:31:57 INFO - 'LOGNAME': 'cltbld', 05:31:57 INFO - 'MAIL': '/var/mail/cltbld', 05:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:57 INFO - 'MOZ_NO_REMOTE': '1', 05:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:57 INFO - 'NO_EM_RESTART': '1', 05:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:57 INFO - 'SHELL': '/bin/bash', 05:31:57 INFO - 'SHLVL': '1', 05:31:57 INFO - 'TERM': 'linux', 05:31:57 INFO - 'TMOUT': '86400', 05:31:57 INFO - 'USER': 'cltbld', 05:31:57 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:57 INFO - '_': '/tools/buildbot/bin/python'} 05:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:57 INFO - Downloading/unpacking flake8 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:31:59 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 05:31:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:31:59 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 05:31:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:59 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 05:31:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:31:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:59 INFO - no previously-included directories found matching 'docs/_build' 05:31:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:31:59 INFO - Running setup.py install for pep8 05:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:59 INFO - no previously-included directories found matching 'docs/_build' 05:31:59 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:31:59 INFO - Cleaning up... 05:31:59 INFO - Return code: 0 05:31:59 INFO - Running post-action listener: _resource_record_post_action 05:31:59 INFO - Running post-action listener: _start_resource_monitoring 05:31:59 INFO - Starting resource monitoring. 05:31:59 INFO - ##### 05:31:59 INFO - ##### Running install step. 05:31:59 INFO - ##### 05:31:59 INFO - Running pre-action listener: _resource_record_pre_action 05:31:59 INFO - Running main action method: install 05:31:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:31:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:00 INFO - Reading from file tmpfile_stdout 05:32:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:32:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:32:00 INFO - Reading from file tmpfile_stdout 05:32:00 INFO - Output received: 05:32:00 INFO - Usage: mozinstall [options] installer 05:32:00 INFO - Options: 05:32:00 INFO - -h, --help show this help message and exit 05:32:00 INFO - -d DEST, --destination=DEST 05:32:00 INFO - Directory to install application into. [default: 05:32:00 INFO - "/builds/slave/test-pgo"] 05:32:00 INFO - --app=APP Application being installed. [default: firefox] 05:32:00 INFO - mkdir: /builds/slave/test-pgo/build/application 05:32:00 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'] 05:32:00 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 05:32:16 INFO - Reading from file tmpfile_stdout 05:32:16 INFO - Output received: 05:32:16 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:32:16 INFO - Running post-action listener: _resource_record_post_action 05:32:16 INFO - ##### 05:32:16 INFO - ##### Running run-tests step. 05:32:16 INFO - ##### 05:32:16 INFO - Running pre-action listener: _resource_record_pre_action 05:32:16 INFO - Running main action method: run_tests 05:32:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:32:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:32:16 INFO - Python 2.7.3 05:32:16 INFO - Return code: 0 05:32:16 INFO - grabbing minidump binary from tooltool 05:32:16 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')]} 05:32:16 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 0x20a6840>, '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 0x20136b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:32:16 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 05:32:16 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 05:32:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:16 INFO - Return code: 0 05:32:16 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:32:16 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:32:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:32:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:32:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:32:17 INFO - Return code: 0 05:32:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 05:32:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:32:17 INFO - Using env: {'DISPLAY': ':0', 05:32:17 INFO - 'HOME': '/home/cltbld', 05:32:17 INFO - 'LANG': 'en_US.UTF-8', 05:32:17 INFO - 'LANGUAGE': 'en_US:en', 05:32:17 INFO - 'LOGNAME': 'cltbld', 05:32:17 INFO - 'MAIL': '/var/mail/cltbld', 05:32:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:17 INFO - 'MOZ_NO_REMOTE': '1', 05:32:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:17 INFO - 'NO_EM_RESTART': '1', 05:32:17 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:17 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:17 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:32:17 INFO - 'SHELL': '/bin/bash', 05:32:17 INFO - 'SHLVL': '1', 05:32:17 INFO - 'TERM': 'linux', 05:32:17 INFO - 'TMOUT': '86400', 05:32:17 INFO - 'USER': 'cltbld', 05:32:17 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101', 05:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:17 INFO - '_': '/tools/buildbot/bin/python'} 05:32:17 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:32:17 INFO - mozversion application_buildid: 20151227030239 05:32:17 INFO - mozversion application_changeset: c39a71cf41460924d2988d2d63be2f5d267db82a 05:32:17 INFO - mozversion application_display_name: Nightly 05:32:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:17 INFO - mozversion application_name: Firefox 05:32:17 INFO - mozversion application_remotingname: firefox 05:32:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:17 INFO - mozversion application_vendor: Mozilla 05:32:17 INFO - mozversion application_version: 46.0a1 05:32:17 INFO - mozversion platform_buildid: 20151227030239 05:32:17 INFO - mozversion platform_changeset: c39a71cf41460924d2988d2d63be2f5d267db82a 05:32:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:17 INFO - mozversion platform_version: 46.0a1 05:32:17 INFO - using testdate: 1451223137 05:32:17 INFO - actual date: 1451223137 05:32:17 INFO - starting webserver on 'localhost:40232' 05:32:17 INFO - SUITE-START | Running 3 tests 05:32:17 INFO - TEST-START | dromaeo_css 05:32:17 INFO - operating with platform_type : linux_ 05:32:17 INFO - Initialising browser for dromaeo_css test... 05:32:17 INFO - TEST-INFO | started process 6605 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVv3Sfz/profile http://localhost:40232/getInfo.html) 05:32:22 INFO - PROCESS | 6605 | __metrics Screen width/height:1600/1200 05:32:22 INFO - PROCESS | 6605 | colorDepth:24 05:32:22 INFO - PROCESS | 6605 | Browser inner width/height: 1024/670 05:32:22 INFO - PROCESS | 6605 | __metrics 05:32:22 INFO - PROCESS | 6605 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:32:22 INFO - PROCESS | 6605 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 05:32:23 INFO - TEST-INFO | 6605: exit 0 05:32:23 INFO - Browser initialized. 05:32:23 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:23 INFO - TEST-INFO | started process 6739 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVv3Sfz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:35 INFO - PROCESS | 6739 | RSS: Main: 148885504 05:32:35 INFO - PROCESS | 6739 | 05:33:25 INFO - PROCESS | 6739 | 1451223205074 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] 05:35:33 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40232/tests/dromaeo/cssquery-ext.html) 05:35:34 INFO - PROCESS | 6739 | RSS: Main: 156946432 05:35:34 INFO - PROCESS | 6739 | 05:38:36 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-ext.html (next: http://localhost:40232/tests/dromaeo/cssquery-jquery.html) 05:38:37 INFO - PROCESS | 6739 | RSS: Main: 153055232 05:38:37 INFO - PROCESS | 6739 | 05:41:39 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40232/tests/dromaeo/cssquery-mootools.html) 05:41:39 INFO - PROCESS | 6739 | RSS: Main: 152969216 05:41:39 INFO - PROCESS | 6739 | 05:44:41 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40232/tests/dromaeo/cssquery-prototype.html) 05:44:42 INFO - PROCESS | 6739 | RSS: Main: 151568384 05:44:42 INFO - PROCESS | 6739 | 05:47:44 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40232/tests/dromaeo/cssquery-yui.html) 05:47:45 INFO - PROCESS | 6739 | RSS: Main: 152559616 05:47:45 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-yui.html (next: http://localhost:40232/tests/dromaeo/cssquery-dojo.html) 05:50:47 INFO - PROCESS | 6739 | RSS: Main: 155025408 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | __start_tp_report 05:50:47 INFO - PROCESS | 6739 | _x_x_mozilla_page_load 05:50:47 INFO - PROCESS | 6739 | _x_x_mozilla_page_load_details 05:50:47 INFO - PROCESS | 6739 | |i|pagename|runs| 05:50:47 INFO - PROCESS | 6739 | |0;dojo.html;3131.8434078296086;3163.1459505933385;3186.856591453385;3198.056573310873;3216.4371235033877;5122.667026643266;5185.740712964355;5197.506236907498;5213.973930130349;5254.842354729364;5170.224466330053;5196.844094677166;5219.765110570024;5304.416514183436;5321.068812957727;4579.427571553556;4836.46798852126;4964.379452568429;4971.602271818246;4982.950170498303;4713;4871.756412179395;4926.605871530287;4928.186849169882;4940.061388336223;4780.665353425262;4781.593564547018;4889.071076495455;4906.190478571031;4917.754112294371;4775.856724298276;4780.4741478437345;4799.976000120012;4803.567678908915;4830.8792280193275;4770.618350531984;4799.976000120012;4805.183118869801;4842.055799119174;4843.24929635907;5119.820806271762;5142.434332223453;5143.6399452037995;5159;5181.248718935771;5758.596898217084;5768.74040668171;5773.1051686988585;5776.422357764232;5782.421757824226;5569.248151499577;5667.291588551431;5751.539876809845;5796.362400135981;5839.649621022752;5422.53908417781;5423.945760542383;5432.538234250093;5437.157240627709;5440.966216418868;5919.289685237799;5938.287405511366;5960.165576819249;5964.403559644001;5980.5215582754145;5643.463870932255;5677.545796336366;5714.942850571441;5763.740631671585;5768.077107662838;5878.617889837147;5882.499987501024;5889.352171261158;5894.351621321651;5928.110783382441;5665.915011274834;5741.626794258444;5791.681457519877;5806.4193580641595;5819.330776960704;5472.288602481652;5619.662820230717;5620.241267428844;5620.775168993277;5687.544996400278;4371.125774845043;4380.364847097153;4380.540043295472;4384.978075109604;4387.254166791653;3896.6687831534637;3914.4911161548816;3915.7063220258597;3969.0672691917375;3972.7219094663096;5250.474952504719;5320.946790532123;5336.7865285388925;5356.8660783479945;5396.91904621431;5639.8590035249445;5669.1496275558175;5681.289838770153;5702.144678298288;5728.541716662657;5180.145276029489;5300.893982120413;5302.072137375943;5322.946770532245;5349.598780091509;4908.9754551227015;4920.040592084509;4954.851354459372;4964.975175124101;5001.924971125436;5947.316058653224;5971.0744834550715;5980.491658209014;5987.64074155552;5995.760169593254;5541.861453463695;5629.324481062299;5782.942170578409;5817.563682723728;5834.15404766315;5806.535477161901;5842.970785146047;5884.499817515559;6015.157877897185;6091.786787462417;5705.657660540381;5749.281339832521;5750.8849823002465;5759.481646651821;5771.826845194651;5821.79623713168;5826.41735826414;5826.504747096545;5830.271216097988;5838.94161058384;5525.889482210253;5631.915521267102;5647.858803529944;5730.541556675373;5752.28096487939;5534.889302213853;5587.664740115606;5589.021921163699;5594.272744543184;5596.468335508121;5442.891142177214;5483.725813709379;5483.917741233805;5504.834854954198;5551.389347171727;5579.832605021856;5590.077637189981;5652.039153343859;5653.462921022497;5695.088785794419;5409.513143817076;5431.239626452379;5447.782088716407;5457;5468.207109968953 05:50:47 INFO - PROCESS | 6739 | |1;ext.html;576.5186069631857;590.3771521045295;592.7658574862928;599.1521996375129;601.1013534765526;33536.329273414514;34473.482897756556;34473.827630861844;34559.30881382371;34677.30645387091;36289.63710362902;37226.813865930635;37483.62516374835;37758;37772;8462.95768521158;8475.533845638487;8476.576171191447;8560.272376847975;8625.870611940825;8380.371472139583;8445.704400345989;8450.915490845075;8636.913630863704;8640.870386944169;8573.09982451843;8575.185357391068;8586.699465518708;8603.354748393864;8650.87023694645;8510.319174466027;8585.871211931826;8637.3521985851;8710.303175745925;8723.38936274461;8481.236688697985;8644.35167362444;8674.95900491945;8687.739367818913;8723.302135829119;37532.81233593842;37936.24127517434;37990.81004594987;38387;38690.226195476214;3127.202563346332;3234.66036066212;3339.081752518053;3376.358491886558;3423.0415483664615;27939.44121117566;27971.860140699166;28332.29169270764;28440.715592844015;28625;3784.6972242220554;3929.489166408377;3968.4840970673918;3977.2244412339605;4021.8190181441905;207752;208591;208669;208885;209260.9999999985;51388.48611513837;52322;52339.73830130825;52721.73639131856;52863.20705189425;63409.36590634127;63635.68182159152;63638.68180659067;63665.36334636594;63899.68050159719;13397.397117129753;13694.931525342508;13716.245606491539;13731;13755.587332380044;10079.49602519871;10313.484325783831;10387.636432724817;10388.948055259676;10420.947895260475;19146.329878454188;19511.294515660564;19567.608647826964;19677.606447870963;19817.207311707658;7856.607169641495;8162.591870406456;8169.099969510857;8196.098429172785;8267.801168830485;938.7746940734311;956.2827879090682;961.9230461563059;1069.2461814420815;1074.7313171707074;25833;26056.86971565168;26259.606105908428;26424.8678756605;29262;1008.6433746610796;1015.6800607808517;1083.0523292119397;1084.1868598551087;1097.7036200225893;13955.441782328593;16235.107069111076;16362.754558681629;16566;16875.320192388917;363534;364789;369048;369600;371701;52861.7356913213;53346.73326633342;53515.464845351045;53578.124765633795;53676.46323536715;1331.5605850069453;1356.9525066622818;1360.3130419138272;1366.938487768053;1370.6299299189163;28612;28713.569296460988;29176.854115729286;29651.40697186001;29750.55374169346;44129.77935110304;44371.33442998293;44455.55544444514;44655;44761.55238447574;290512;290649;291585;293621;295769;31053.534196987515;40593;41737.79131104325;41791.79104104581;41926.790366049194;36397;37158.25683486261;37486.43770343393;37487.2502549942;37673;35888.64111358853;37009.81495092507;37075;37573.62426375811;38347.2330553393;35456.46815297721;35700;35807.104822378606;35921.64078359183;36053.81973090118;34892;34914.65085349216;35226.64773352234;35233.11917201988;35471.82264088663;29985.700142998292;31625.36749264956;32507.18732031718;32507.862224822016;32530.674693252764;23770.40573985664;24516.14193503219;24622.384440388418;24741.87629061843;24818.50362992766 05:50:47 INFO - PROCESS | 6739 | |2;jquery.html;360.7745159275453;366.7744337232603;367.3644594850907;368.35170100622906;369.990750231244;16383.508494745147;16770.41303554376;16831.74752378715;17094.230759615817;17829.37597184099;12978.610641680742;12987.350632468386;13022.54421095262;13719.382627781748;15934.282957266922;4869.4887036861155;4873.244647079709;4944.357233559632;4954.851354459354;4975.975120124394;4653.9069218615605;5002.1746411842005;5022.598192144638;5025.271335656327;5035.1188542005;5232.529072383485;5413.566914646838;5420.105682562373;5430.619856610039;5438.53772429344;5245.554127899133;5281.788728450857;5348.5453736432255;5361.437049109826;5402.810901618444;4841.080194763019;4845.685030473008;4848.951510484921;4852.830150944736;4861.173600487926;12899.742005159844;13004.089713720043;13124.934375328063;15795.921020394939;20340.69488957667;2034.9796502034937;2113.2392338758036;2169.3383518026994;2238.742544607375;2293.3578597992587;9555.378900371454;9906.257030722725;10372.688819335432;10810.72973175669;13376.933115334361;1766.9469915902544;1792.937247196355;1793.6861049316449;1804.7022241330164;1819.7998220195768;59369.40630593682;60332.49168770772;60550;61064.69467652678;61326.69336653333;9701.981291964383;9971.900280997243;10174.4912754362;10272.743181420525;10296.639617613497;12654.683632909067;12676.176048556814;12815.423305951255;13039.282839443922;13355.131916425402;6936.687849046805;7065.1521817382245;7354;7413.443991700644;7487.28870757288;7123.786286411416;7130.750423735144;7139.92860071403;7158.8210294742;7192.748253811092;4367.58507941745;4461.174682683649;4462.9107417851465;4504.954950450519;4531.909361812811;1774.8668849836313;1780.1366337326385;1797.5775692712202;1806.096951524238;1845.4463660901677;415.7817145998375;416.11575402270176;435.34697953070395;437.62145743931904;989.3717489394287;10125.392476451436;10201.591936322464;10296.433696146634;10395.792084158275;10506.73733156662;445.1830890316241;447.33793984901877;448.1105006561972;909.9499527525917;1003.8544411060356;5671.234383358194;5983.431574000463;6037.909431358533;6056.636601803906;8767.561621918878;394639;394879;440851;575408;578410;38947.80526097352;38974.80512597419;51234;64992.35007649863;78853;507.36832643358514;512.126823765479;512.5028722139593;513.5762995528704;514.041312951343;10544.103751181236;10656.733581660521;10679.305845120043;10770.407627580558;14667.339969701603;32043.67956320407;32707.528161233276;33184.336313274085;34349.484757728154;35015.47476787799;38863.80568097141;40301.596984030955;40340.79829600833;40681.79659101686;40786.38820417756;9284.164425201765;9365.11031452011;9464.432134071978;9506.714798556055;9570.665026724031;10634.680959571226;15912.681746364777;16816.411425600047;17173.398931037354;17433.47699569015;14269.072510287213;14736;14866.256687165382;15134.924325378302;15161.317740701474;13586.728265434438;14372.49696260626;14542.345594448487;14584;14840.40638374453;13670.453181872614;15186;15192.772108418603;15223.086614803147;15360.155191464577;9450.905490945277;9587.328886978125;9637.518124093627;9868.654597089067;9937.403755774676;10769.569217231536;14393.208373539137;14435.639109022359;14934.402623895357;14935.850641493445 05:50:47 INFO - PROCESS | 6739 | |3;mootools.html;1429.0711037825413;1433.4266293482608;1596.177968346302;1731.6450127723817;1746.4236801855388;7419.443541734365;7489.213632568577;7543.283388078124;7585.279398457144;7617.999999999993;7476.252374762535;7563.205863384348;7573.0912290525075;7589.279018493248;7597.544147351163;2173.826093912487;2242.069541140425;2250.291158285143;2250.5948929192737;2253.864768113918;2249.3364457485;2263.343630347197;2269;2307.7230732312146;2322.616768233248;2229.1863469833556;2261.163369553268;2268.410213344534;2269.183094086136;2305.8616483011074;2234.497238121418;2234.854734442256;2262.739784924738;2271.8750468724297;2275.6131457652073;2269.217120093565;2310.3992961830045;2310.838241323108;2331.8834058297016;2336.707911511061;7611.353034992032;7678.155402905676;7682.731104411375;7691.807704807368;7702.306792388656;4599.264117741145;4601.28680054592;4640.628749700016;4689.554492323224;4690.742009189493;2952.158634789096;2953.350262942149;3032.120685001347;3045.1473587395567;3057.6330840299083;986.5856340337076;1001.5843424978625;1021.1065317846884;1025.2310766924807;1028.7942411517727;10424.113950314157;10445;10453.84319235212;10509;10567.894321056769;4112.773797441169;4153.646940010072;4175.8956026099595;4208.326667733209;4223.493180818322;8567.957160214284;8571.614277357534;8585.399022068395;8588.957055214809;8593.269572086443;4591.242444996605;4601.263797792404;4628.629709623222;4630.86107416778;4663.696859704109;4192.601702838226;4197.034682023152;4231.196072746168;4239.364095385655;4261.2542805009;3447.276072024903;3461.2731326421736;3475.4786781982907;3479.4780782882262;3530.3292374448774;1074.0548317480723;1075.8171110911164;1083.1659622090947;1083.5015892689294;1088.8856670049688;1303.0552849184455;1308.2869835939437;1317.4730107956893;1324.278268343755;1330.5875178694635;7188.101487314086;7220.3140701633265;7250.4199664026755;7267.055282813306;7299.671514781848;465.2277219815091;465.74616833825644;487.7000644603543;489.0902920567741;494.9851504454872;5557.722113894289;5583.776648934078;5586.91619625706;5614.185943038318;5615.887682246333;8571.314294856396;8679.913200868037;8685.956570217108;8715.259202967693;8791.296696264284;8573.957130214309;8636.654533818577;8665.220130188314;8681.435706679045;8739.956300218586;6848.760293389708;6979.860402792017;7024.648767561602;7055;7175.246599107123;7058.611776352144;7064.576125432491;7113.964430177816;7238.131424229126;7258;7773.41699372538;7940.920590794018;8153.592320383838;8155.510669359857;8156.551389673625;8230.259276665129;8319.750407487785;8417.789555261168;8436.493810371398;8477.067522572388;5810.215620891295;5928.822135335947;6409.647469389229;6619.437347825488;6636.701348439428;7228.277172282729;7234.6744396501235;7239.746608868664;7245.7826265212125;7311.305425984523;6708.362705542965;6830.077939478107;6835.282295358916;6881.862362752817;7006.439484841302;6706.429953453816;6767.357101075587;6799.796006119825;6902.758403455855;6907.861842763217;6466.159399278064;6725.932740672531;6795.932040679728;6867.519273650796;6914.965425172842;6299.338569450142;6302.180716506825;6354.93645063562;6357.586756860789;6363.109164717036;6243.032329988859;6254.749810007549;6256.343083976299;6313.1792866928845;6343.809685709436 05:50:47 INFO - PROCESS | 6739 | |4;prototype.html;333.38324100414235;342.6778827901771;343.62201578263904;344.7311097344071;345.98616055357786;6261.561690681654;6262.091996660487;6310.589811662238;6354.396332348431;6403.807885763423;6212.689365531728;6320.4943604511645;6365.809025729224;6369.6496692681885;6386.521010924176;4714.387129673138;4719.905601887977;4735.337052812609;4741.5495527924795;4744.715317080978;4732.43210814701;4745.525447455261;4746.833860814872;4754.90490190196;4755.120302744003;4610.354550362952;4658.440987081555;4692.389989301403;4693.037927224927;4698.953010469902;4625.745583992879;4626.791794369245;4628.398308219927;4699.177643912336;4702.929456058162;4056.4118202860714;4067.1662309226535;4083.367078102869;4084.1014976705374;4087.632113109805;5930.021546444831;5989.460948514655;6006.78976235834;6025.8192254232445;6028.306744724369;5390.245365648812;5394.460553944574;5425.484578964992;5471.987682278751;5504.119340905436;6655.168103987002;6663.067170596072;6664.133662623876;6664.700088496029;6683.465322774166;4452.243118669834;4453.51011388747;4454.420925279725;4460.6208472279895;4470.508244093147;6811.318868113248;6844.5208835381545;6880.965595171992;6927.515073944826;6968.303169683042;4576.725396476222;4577.450705915311;4620.2607582786595;4626.144163329727;4630.305454181832;4650.813967441264;4669.789859456333;4695.577398034194;4703.082898834763;4705.058988202373;5467.097928841697;5470.343558772893;5477.260569823103;5478.424765399655;5481.369642491085;6375.521835862236;6598.73605055802;6644.501662375341;6670.199576050905;6709.932900671029;1634.3054201964117;1652.8099268584026;1659.6514731906434;1661.6925868714327;1664.7835781348347;1082.4533610526635;1094.2996482251365;1107.374333501569;1108.562117963416;1119.9496022679;6192.659403732838;6270.905936410957;6275.780347687901;6277.52918531112;6286.842828929313;6796.048553202556;6817.0796942413135;6818.693158807866;6821.556598821061;6832.555883867532;6076.422739839708;6228.5951413158;6231.065340199007;6244.313125556185;6249.531285153632;3717.8326975286177;3736.8131593420762;3742.6257374262354;3743.494628225155;3759.981200093982;7014.7022800782015;7043.225245223021;7049.718011279594;7073.964630176816;7091.361777440055;6771.119754431892;6831.2144103428745;6845.281245469254;6845.349691779273;6921.273266307066;7042.366187043088;7045.295470453017;7056.223815380304;7062.223155452896;7112.933060041035;6764.2221144569;6794.252632210551;6801.2178599461695;6802.251752307143;6817.386435220854;6603.735850566116;6731.024001519851;6734.191896972395;6740.022696708852;6758.493113016445;6961.547499412522;7036.612986285599;7070.39901608369;7078.893816592652;7089.574625522485;4643.141018911512;4660;4664.253719404879;4665.603423708887;4670.953290467051;6634.933650663432;6642.269350371552;6725.361090696376;6756.256811750802;6885.483588730765;6685.866282674417;6688.3646053624825;6699.665016749045;6721.395074443323;6748.763793267409;6583.275839657538;6603.966980165068;6631.834204144935;6638.800835974928;6697.062411262525;6471.935280647133;6588.538802283794;6592.24189218246;6593.967030164818;6595.076689263411;6728.495362847904;6732.845489443331;6765.188177418544;6773.729050838108;6781.525293229427;6515.087887695631;6525;6540.869182616226;6559.934400655932;6563.901541476786 05:50:47 INFO - PROCESS | 6739 | |5;yui.html;749.6326799868066;774.1252384805173;800.8918795962547;860.2086080805658;880.7489865388363;13688;13874.028817982746;13896.444142234312;13899.444022239111;13899.722005559883;12813;12824.743505129893;12849.807252891214;12854.614361569167;12998.93500532496;652.9053287273342;661.2759028863387;775.6819703921379;789.5499565247811;792.7661339904713;795.4988357334871;799.2007992007992;802.1457148137245;804.1235053791366;805.8025783682976;831.5177197225624;832.9167083291653;835.3526017336552;835.3943391041507;836.4981011393146;817.6279792694339;821.8150916043902;822.2599660305773;832.3757182113415;832.9250367466926;604.8336707405455;607.0317843040343;610.0239616613427;610.2493932463051;612.2958597612736;13072.823445889915;13101.34493275342;13160.6709832254;13230.86769132306;13274;11874.821877671842;12024.579139730067;12312.322822244772;12476.81284780729;13387.330633468287;10023.59905603775;10204.132648724868;10427.79144417115;10493.160547156209;10675.733106672318;3525.11872031992;3553.289342131584;3562.09166662502;3636.709063274932;3705.2589482103685;45954.770226148656;46230;46541.53458465406;46695.53304466946;47425;9081.897474981402;9182.586783594754;9372.62509499626;9486.193673537826;9558.235341172689;17102.230399632048;17174.31302747901;17200.483985480456;17247.310107595807;17258.568535786704;7425.554466732014;7486.401087913063;7559.848803023909;7584.772456826304;7683.462157648911;4125.154343359664;4159.396887451303;4167;4224.619784219435;4301.892452688645;3006.744426723753;3010.71398217169;3030.4848175809725;3031.4088752693456;3048.4665183592783;563.2115038945427;568.7497501099502;580.3876909860138;590.8877313310458;592.8221533539921;562.7938481153886;629.5089829932659;664.7440735316937;690.3343247356553;713.0730050933765;7388.44586656003;7661.463697541119;8059.234372734581;8115.305969777045;8425.325973922072;592.9703514824328;677.8203775999364;682.3944219950923;685.2017399729259;708.1714394158846;3719.683826874746;3831.3678243090135;3853.4797802296894;4025.4163146343617;4090.8159132839096;48993.26510102351;49097.263541046916;52775.47224527706;52983;54955.175672364945;49551.50448495541;49631.75184124056;49817.75091124521;49915.50084499108;50844;45463.09073818505;46112.308315375296;46247;46413.5358646416;47330.29004565003;8202.425830192067;8353.373496987871;8417.537035463109;8448.535330556879;8675.86986195195;33819.32361352808;34642.133946651535;34958.30083398267;35318.82340588281;35579.64420355763;40086.599134008284;40099.1980160401;40300;42987.57012429961;43040.35439468473;10373.118284945864;10569.63006294776;10715.142788576739;11263.718407039561;11323.810999844898;11135.88864111381;11215.271007384494;11272.380019099028;11312.94343528277;11704.297742135159;10316.57429927016;10667.679969600924;10871.619493317696;11004;11130.499127539118;10245.897541024495;10415.895841041493;10559.419231942009;10666.466676665981;10794.784104318027;9690.85463718059;10042.949785251027;10377.636782712569;10493.213009024506;10544;9386.202172815387;9609.231261498924;9655.806883862424;9712.271579631415;10000;8577.35669824778;8692.304615630861;8776.56117194125;8858.768722434157;8952.373333866566 05:50:47 INFO - PROCESS | 6739 | __end_tp_report 05:50:47 INFO - PROCESS | 6739 | __start_cc_report 05:50:47 INFO - PROCESS | 6739 | _x_x_mozilla_cycle_collect,316 05:50:47 INFO - PROCESS | 6739 | __end_cc_report 05:50:47 INFO - PROCESS | 6739 | __startTimestamp1451224247871__endTimestamp 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | ------- Summary: start ------- 05:50:47 INFO - PROCESS | 6739 | Number of tests: 6 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#0] dojo.html Cycles:165 Average:5315.60 Median:5483.73 stddev:610.81 (11.1%) stddev-sans-first:588.16 05:50:47 INFO - PROCESS | 6739 | Values: 3131.8 3163.1 3186.9 3198.1 3216.4 5122.7 5185.7 5197.5 5214.0 5254.8 5170.2 5196.8 5219.8 5304.4 5321.1 4579.4 4836.5 4964.4 4971.6 4983.0 4713.0 4871.8 4926.6 4928.2 4940.1 4780.7 4781.6 4889.1 4906.2 4917.8 4775.9 4780.5 4800.0 4803.6 4830.9 4770.6 4800.0 4805.2 4842.1 4843.2 5119.8 5142.4 5143.6 5159.0 5181.2 5758.6 5768.7 5773.1 5776.4 5782.4 5569.2 5667.3 5751.5 5796.4 5839.6 5422.5 5423.9 5432.5 5437.2 5441.0 5919.3 5938.3 5960.2 5964.4 5980.5 5643.5 5677.5 5714.9 5763.7 5768.1 5878.6 5882.5 5889.4 5894.4 5928.1 5665.9 5741.6 5791.7 5806.4 5819.3 5472.3 5619.7 5620.2 5620.8 5687.5 4371.1 4380.4 4380.5 4385.0 4387.3 3896.7 3914.5 3915.7 3969.1 3972.7 5250.5 5320.9 5336.8 5356.9 5396.9 5639.9 5669.1 5681.3 5702.1 5728.5 5180.1 5300.9 5302.1 5322.9 5349.6 4909.0 4920.0 4954.9 4965.0 5001.9 5947.3 5971.1 5980.5 5987.6 5995.8 5541.9 5629.3 5782.9 5817.6 5834.2 5806.5 5843.0 5884.5 6015.2 6091.8 5705.7 5749.3 5750.9 5759.5 5771.8 5821.8 5826.4 5826.5 5830.3 5838.9 5525.9 5631.9 5647.9 5730.5 5752.3 5534.9 5587.7 5589.0 5594.3 5596.5 5442.9 5483.7 5483.9 5504.8 5551.4 5579.8 5590.1 5652.0 5653.5 5695.1 5409.5 5431.2 5447.8 5457.0 5468.2 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#1] ext.html Cycles:180 Average:45541.87 Median:199965.50 stddev:77944.59 (39.0%) stddev-sans-first:78090.13 05:50:47 INFO - PROCESS | 6739 | Values: 576.5 590.4 592.8 599.2 601.1 33536.3 34473.5 34473.8 34559.3 34677.3 36289.6 37226.8 37483.6 37758.0 37772.0 8463.0 8475.5 8476.6 8560.3 8625.9 8380.4 8445.7 8450.9 8636.9 8640.9 8573.1 8575.2 8586.7 8603.4 8650.9 8510.3 8585.9 8637.4 8710.3 8723.4 8481.2 8644.4 8675.0 8687.7 8723.3 37532.8 37936.2 37990.8 38387.0 38690.2 3127.2 3234.7 3339.1 3376.4 3423.0 27939.4 27971.9 28332.3 28440.7 28625.0 3784.7 3929.5 3968.5 3977.2 4021.8 207752.0 208591.0 208669.0 208885.0 209261.0 51388.5 52322.0 52339.7 52721.7 52863.2 63409.4 63635.7 63638.7 63665.4 63899.7 13397.4 13694.9 13716.2 13731.0 13755.6 10079.5 10313.5 10387.6 10388.9 10420.9 19146.3 19511.3 19567.6 19677.6 19817.2 7856.6 8162.6 8169.1 8196.1 8267.8 938.8 956.3 961.9 1069.2 1074.7 25833.0 26056.9 26259.6 26424.9 29262.0 1008.6 1015.7 1083.1 1084.2 1097.7 13955.4 16235.1 16362.8 16566.0 16875.3 363534.0 364789.0 369048.0 369600.0 371701.0 52861.7 53346.7 53515.5 53578.1 53676.5 1331.6 1357.0 1360.3 1366.9 1370.6 28612.0 28713.6 29176.9 29651.4 29750.6 44129.8 44371.3 44455.6 44655.0 44761.6 290512.0 290649.0 291585.0 293621.0 295769.0 31053.5 40593.0 41737.8 41791.8 41926.8 36397.0 37158.3 37486.4 37487.3 37673.0 35888.6 37009.8 37075.0 37573.6 38347.2 35456.5 35700.0 35807.1 35921.6 36053.8 34892.0 34914.7 35226.6 35233.1 35471.8 29985.7 31625.4 32507.2 32507.9 32530.7 23770.4 24516.1 24622.4 24741.9 24818.5 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#2] jquery.html Cycles:180 Average:25509.39 Median:394759.00 stddev:79005.25 (20.0%) stddev-sans-first:79204.31 05:50:47 INFO - PROCESS | 6739 | Values: 360.8 366.8 367.4 368.4 370.0 16383.5 16770.4 16831.7 17094.2 17829.4 12978.6 12987.4 13022.5 13719.4 15934.3 4869.5 4873.2 4944.4 4954.9 4976.0 4653.9 5002.2 5022.6 5025.3 5035.1 5232.5 5413.6 5420.1 5430.6 5438.5 5245.6 5281.8 5348.5 5361.4 5402.8 4841.1 4845.7 4849.0 4852.8 4861.2 12899.7 13004.1 13124.9 15795.9 20340.7 2035.0 2113.2 2169.3 2238.7 2293.4 9555.4 9906.3 10372.7 10810.7 13376.9 1766.9 1792.9 1793.7 1804.7 1819.8 59369.4 60332.5 60550.0 61064.7 61326.7 9702.0 9971.9 10174.5 10272.7 10296.6 12654.7 12676.2 12815.4 13039.3 13355.1 6936.7 7065.2 7354.0 7413.4 7487.3 7123.8 7130.8 7139.9 7158.8 7192.7 4367.6 4461.2 4462.9 4505.0 4531.9 1774.9 1780.1 1797.6 1806.1 1845.4 415.8 416.1 435.3 437.6 989.4 10125.4 10201.6 10296.4 10395.8 10506.7 445.2 447.3 448.1 909.9 1003.9 5671.2 5983.4 6037.9 6056.6 8767.6 394639.0 394879.0 440851.0 575408.0 578410.0 38947.8 38974.8 51234.0 64992.4 78853.0 507.4 512.1 512.5 513.6 514.0 10544.1 10656.7 10679.3 10770.4 14667.3 32043.7 32707.5 33184.3 34349.5 35015.5 38863.8 40301.6 40340.8 40681.8 40786.4 9284.2 9365.1 9464.4 9506.7 9570.7 10634.7 15912.7 16816.4 17173.4 17433.5 14269.1 14736.0 14866.3 15134.9 15161.3 13586.7 14372.5 14542.3 14584.0 14840.4 13670.5 15186.0 15192.8 15223.1 15360.2 9450.9 9587.3 9637.5 9868.7 9937.4 10769.6 14393.2 14435.6 14934.4 14935.9 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#3] mootools.html Cycles:180 Average:5281.75 Median:6085.93 stddev:2715.51 (44.6%) stddev-sans-first:2707.69 05:50:47 INFO - PROCESS | 6739 | Values: 1429.1 1433.4 1596.2 1731.6 1746.4 7419.4 7489.2 7543.3 7585.3 7618.0 7476.3 7563.2 7573.1 7589.3 7597.5 2173.8 2242.1 2250.3 2250.6 2253.9 2249.3 2263.3 2269.0 2307.7 2322.6 2229.2 2261.2 2268.4 2269.2 2305.9 2234.5 2234.9 2262.7 2271.9 2275.6 2269.2 2310.4 2310.8 2331.9 2336.7 7611.4 7678.2 7682.7 7691.8 7702.3 4599.3 4601.3 4640.6 4689.6 4690.7 2952.2 2953.4 3032.1 3045.1 3057.6 986.6 1001.6 1021.1 1025.2 1028.8 10424.1 10445.0 10453.8 10509.0 10567.9 4112.8 4153.6 4175.9 4208.3 4223.5 8568.0 8571.6 8585.4 8589.0 8593.3 4591.2 4601.3 4628.6 4630.9 4663.7 4192.6 4197.0 4231.2 4239.4 4261.3 3447.3 3461.3 3475.5 3479.5 3530.3 1074.1 1075.8 1083.2 1083.5 1088.9 1303.1 1308.3 1317.5 1324.3 1330.6 7188.1 7220.3 7250.4 7267.1 7299.7 465.2 465.7 487.7 489.1 495.0 5557.7 5583.8 5586.9 5614.2 5615.9 8571.3 8679.9 8686.0 8715.3 8791.3 8574.0 8636.7 8665.2 8681.4 8740.0 6848.8 6979.9 7024.6 7055.0 7175.2 7058.6 7064.6 7114.0 7238.1 7258.0 7773.4 7940.9 8153.6 8155.5 8156.6 8230.3 8319.8 8417.8 8436.5 8477.1 5810.2 5928.8 6409.6 6619.4 6636.7 7228.3 7234.7 7239.7 7245.8 7311.3 6708.4 6830.1 6835.3 6881.9 7006.4 6706.4 6767.4 6799.8 6902.8 6907.9 6466.2 6725.9 6795.9 6867.5 6915.0 6299.3 6302.2 6354.9 6357.6 6363.1 6243.0 6254.7 6256.3 6313.2 6343.8 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#4] prototype.html Cycles:180 Average:5516.03 Median:6276.65 stddev:1678.79 (26.7%) stddev-sans-first:1637.81 05:50:47 INFO - PROCESS | 6739 | Values: 333.4 342.7 343.6 344.7 346.0 6261.6 6262.1 6310.6 6354.4 6403.8 6212.7 6320.5 6365.8 6369.6 6386.5 4714.4 4719.9 4735.3 4741.5 4744.7 4732.4 4745.5 4746.8 4754.9 4755.1 4610.4 4658.4 4692.4 4693.0 4699.0 4625.7 4626.8 4628.4 4699.2 4702.9 4056.4 4067.2 4083.4 4084.1 4087.6 5930.0 5989.5 6006.8 6025.8 6028.3 5390.2 5394.5 5425.5 5472.0 5504.1 6655.2 6663.1 6664.1 6664.7 6683.5 4452.2 4453.5 4454.4 4460.6 4470.5 6811.3 6844.5 6881.0 6927.5 6968.3 4576.7 4577.5 4620.3 4626.1 4630.3 4650.8 4669.8 4695.6 4703.1 4705.1 5467.1 5470.3 5477.3 5478.4 5481.4 6375.5 6598.7 6644.5 6670.2 6709.9 1634.3 1652.8 1659.7 1661.7 1664.8 1082.5 1094.3 1107.4 1108.6 1119.9 6192.7 6270.9 6275.8 6277.5 6286.8 6796.0 6817.1 6818.7 6821.6 6832.6 6076.4 6228.6 6231.1 6244.3 6249.5 3717.8 3736.8 3742.6 3743.5 3760.0 7014.7 7043.2 7049.7 7074.0 7091.4 6771.1 6831.2 6845.3 6845.3 6921.3 7042.4 7045.3 7056.2 7062.2 7112.9 6764.2 6794.3 6801.2 6802.3 6817.4 6603.7 6731.0 6734.2 6740.0 6758.5 6961.5 7036.6 7070.4 7078.9 7089.6 4643.1 4660.0 4664.3 4665.6 4671.0 6634.9 6642.3 6725.4 6756.3 6885.5 6685.9 6688.4 6699.7 6721.4 6748.8 6583.3 6604.0 6631.8 6638.8 6697.1 6471.9 6588.5 6592.2 6594.0 6595.1 6728.5 6732.8 6765.2 6773.7 6781.5 6515.1 6525.0 6540.9 6559.9 6563.9 05:50:47 INFO - PROCESS | 6739 | 05:50:47 INFO - PROCESS | 6739 | [#5] yui.html Cycles:180 Average:13257.94 Median:46477.54 stddev:15195.07 (32.7%) stddev-sans-first:15208.66 05:50:47 INFO - PROCESS | 6739 | Values: 749.6 774.1 800.9 860.2 880.7 13688.0 13874.0 13896.4 13899.4 13899.7 12813.0 12824.7 12849.8 12854.6 12998.9 652.9 661.3 775.7 789.5 792.8 795.5 799.2 802.1 804.1 805.8 831.5 832.9 835.4 835.4 836.5 817.6 821.8 822.3 832.4 832.9 604.8 607.0 610.0 610.2 612.3 13072.8 13101.3 13160.7 13230.9 13274.0 11874.8 12024.6 12312.3 12476.8 13387.3 10023.6 10204.1 10427.8 10493.2 10675.7 3525.1 3553.3 3562.1 3636.7 3705.3 45954.8 46230.0 46541.5 46695.5 47425.0 9081.9 9182.6 9372.6 9486.2 9558.2 17102.2 17174.3 17200.5 17247.3 17258.6 7425.6 7486.4 7559.8 7584.8 7683.5 4125.2 4159.4 4167.0 4224.6 4301.9 3006.7 3010.7 3030.5 3031.4 3048.5 563.2 568.7 580.4 590.9 592.8 562.8 629.5 664.7 690.3 713.1 7388.4 7661.5 8059.2 8115.3 8425.3 593.0 677.8 682.4 685.2 708.2 3719.7 3831.4 3853.5 4025.4 4090.8 48993.3 49097.3 52775.5 52983.0 54955.2 49551.5 49631.8 49817.8 49915.5 50844.0 45463.1 46112.3 46247.0 46413.5 47330.3 8202.4 8353.4 8417.5 8448.5 8675.9 33819.3 34642.1 34958.3 35318.8 35579.6 40086.6 40099.2 40300.0 42987.6 43040.4 10373.1 10569.6 10715.1 11263.7 11323.8 11135.9 11215.3 11272.4 11312.9 11704.3 10316.6 10667.7 10871.6 11004.0 11130.5 10245.9 10415.9 10559.4 10666.5 10794.8 9690.9 10042.9 10377.6 10493.2 10544.0 9386.2 9609.2 9655.8 9712.3 10000.0 8577.4 8692.3 8776.6 8858.8 8952.4 05:50:47 INFO - PROCESS | 6739 | -------- Summary: end -------- 05:50:47 INFO - PROCESS | 6739 | 05:50:48 INFO - TEST-INFO | 6739: exit 0 05:50:48 INFO - TEST-OK | dromaeo_css | took 1111308ms 05:50:48 INFO - TEST-START | kraken 05:50:48 INFO - operating with platform_type : linux_ 05:50:48 INFO - Initialising browser for kraken test... 05:50:48 INFO - TEST-INFO | started process 6980 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmEfU53/profile http://localhost:40232/getInfo.html) 05:50:52 INFO - PROCESS | 6980 | __metrics Screen width/height:1600/1200 05:50:52 INFO - PROCESS | 6980 | colorDepth:24 05:50:52 INFO - PROCESS | 6980 | Browser inner width/height: 1024/670 05:50:52 INFO - PROCESS | 6980 | __metrics 05:50:52 INFO - PROCESS | 6980 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:50:53 INFO - TEST-INFO | 6980: exit 0 05:50:53 INFO - Browser initialized. 05:50:53 INFO - Running cycle 1/1 for kraken test... 05:50:53 INFO - TEST-INFO | started process 7103 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmEfU53/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:05 INFO - PROCESS | 7103 | RSS: Main: 149315584 05:51:05 INFO - PROCESS | 7103 | 05:51:08 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:08 INFO - PROCESS | 7103 | RSS: Main: 150913024 05:51:08 INFO - PROCESS | 7103 | 05:51:11 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-dft) 05:51:11 INFO - PROCESS | 7103 | RSS: Main: 151879680 05:51:11 INFO - PROCESS | 7103 | 05:51:14 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-fft) 05:51:15 INFO - PROCESS | 7103 | RSS: Main: 145600512 05:51:15 INFO - PROCESS | 7103 | 05:51:17 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-oscillator) 05:51:17 INFO - PROCESS | 7103 | RSS: Main: 146649088 05:51:17 INFO - PROCESS | 7103 | 05:51:20 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:20 INFO - PROCESS | 7103 | RSS: Main: 147468288 05:51:20 INFO - PROCESS | 7103 | 05:51:23 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:24 INFO - PROCESS | 7103 | RSS: Main: 147054592 05:51:24 INFO - PROCESS | 7103 | 05:51:27 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:28 INFO - PROCESS | 7103 | RSS: Main: 148254720 05:51:28 INFO - PROCESS | 7103 | 05:51:30 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40232/tests/kraken/driver.html?testName=json-parse-financial) 05:51:31 INFO - PROCESS | 7103 | RSS: Main: 148828160 05:51:31 INFO - PROCESS | 7103 | 05:51:33 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:33 INFO - PROCESS | 7103 | RSS: Main: 147849216 05:51:33 INFO - PROCESS | 7103 | 05:51:35 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:36 INFO - PROCESS | 7103 | RSS: Main: 149057536 05:51:36 INFO - PROCESS | 7103 | 05:51:38 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:38 INFO - PROCESS | 7103 | RSS: Main: 149217280 05:51:38 INFO - PROCESS | 7103 | 05:51:41 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:41 INFO - PROCESS | 7103 | RSS: Main: 149516288 05:51:41 INFO - PROCESS | 7103 | 05:51:44 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:45 INFO - PROCESS | 7103 | RSS: Main: 148598784 05:51:45 INFO - PROCESS | 7103 | 05:51:46 INFO - PROCESS | 7103 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40232/tests/kraken/driver.html?testName=ai-astar) 05:51:47 INFO - PROCESS | 7103 | RSS: Main: 148615168 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | __start_tp_report 05:51:47 INFO - PROCESS | 7103 | _x_x_mozilla_page_load 05:51:47 INFO - PROCESS | 7103 | _x_x_mozilla_page_load_details 05:51:47 INFO - PROCESS | 7103 | |i|pagename|runs| 05:51:47 INFO - PROCESS | 7103 | |0;ai-astar;113;108;114;113;113;121;111;110;112;111 05:51:47 INFO - PROCESS | 7103 | |1;audio-beat-detection;157;123;150;164;132;150;155;124;168;123 05:51:47 INFO - PROCESS | 7103 | |2;audio-dft;163;163;161;161;161;161;161;162;166;162 05:51:47 INFO - PROCESS | 7103 | |3;audio-fft;71;71;73;75;73;129;71;71;75;74 05:51:47 INFO - PROCESS | 7103 | |4;audio-oscillator;96;96;140;95;96;95;150;96;98;135 05:51:47 INFO - PROCESS | 7103 | |5;imaging-gaussian-blur;110;110;110;110;110;111;110;110;110;110 05:51:47 INFO - PROCESS | 7103 | |6;imaging-darkroom;149;146;147;148;147;146;147;146;146;147 05:51:47 INFO - PROCESS | 7103 | |7;imaging-desaturate;96;95;95;96;97;97;96;97;97;97 05:51:47 INFO - PROCESS | 7103 | |8;json-parse-financial;61;60;59;60;63;63;60;58;61;57 05:51:47 INFO - PROCESS | 7103 | |9;json-stringify-tinderbox;69;69;67;68;68;69;67;67;68;67 05:51:47 INFO - PROCESS | 7103 | |10;stanford-crypto-aes;84;87;85;85;87;92;90;85;87;87 05:51:47 INFO - PROCESS | 7103 | |11;stanford-crypto-ccm;120;119;125;134;121;128;124;122;118;117 05:51:47 INFO - PROCESS | 7103 | |12;stanford-crypto-pbkdf2;146;153;155;154;149;143;151;152;154;161 05:51:47 INFO - PROCESS | 7103 | |13;stanford-crypto-sha256-iterative;69;69;68;70;67;71;67;67;68;74 05:51:47 INFO - PROCESS | 7103 | __end_tp_report 05:51:47 INFO - PROCESS | 7103 | __start_cc_report 05:51:47 INFO - PROCESS | 7103 | _x_x_mozilla_cycle_collect,732 05:51:47 INFO - PROCESS | 7103 | __end_cc_report 05:51:47 INFO - PROCESS | 7103 | __startTimestamp1451224307028__endTimestamp 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | ------- Summary: start ------- 05:51:47 INFO - PROCESS | 7103 | Number of tests: 14 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:3.44 (3.0%) stddev-sans-first:3.64 05:51:47 INFO - PROCESS | 7103 | Values: 113.0 108.0 114.0 113.0 113.0 121.0 111.0 110.0 112.0 111.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:152.50 stddev:17.51 (11.5%) stddev-sans-first:17.99 05:51:47 INFO - PROCESS | 7103 | Values: 157.0 123.0 150.0 164.0 132.0 150.0 155.0 124.0 168.0 123.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.60 (1.0%) stddev-sans-first:1.66 05:51:47 INFO - PROCESS | 7103 | Values: 163.0 163.0 161.0 161.0 161.0 161.0 161.0 162.0 166.0 162.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#3] audio-fft Cycles:10 Average:78.30 Median:73.00 stddev:17.89 (24.5%) stddev-sans-first:18.78 05:51:47 INFO - PROCESS | 7103 | Values: 71.0 71.0 73.0 75.0 73.0 129.0 71.0 71.0 75.0 74.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:96.00 stddev:22.37 (23.3%) stddev-sans-first:23.17 05:51:47 INFO - PROCESS | 7103 | Values: 96.0 96.0 140.0 95.0 96.0 95.0 150.0 96.0 98.0 135.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:51:47 INFO - PROCESS | 7103 | Values: 110.0 110.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 110.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.99 (0.7%) stddev-sans-first:0.71 05:51:47 INFO - PROCESS | 7103 | Values: 149.0 146.0 147.0 148.0 147.0 146.0 147.0 146.0 146.0 147.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:97.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 05:51:47 INFO - PROCESS | 7103 | Values: 96.0 95.0 95.0 96.0 97.0 97.0 96.0 97.0 97.0 97.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:60.50 stddev:1.93 (3.2%) stddev-sans-first:2.03 05:51:47 INFO - PROCESS | 7103 | Values: 61.0 60.0 59.0 60.0 63.0 63.0 60.0 58.0 61.0 57.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 05:51:47 INFO - PROCESS | 7103 | Values: 69.0 69.0 67.0 68.0 68.0 69.0 67.0 67.0 68.0 67.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:2.47 (2.8%) stddev-sans-first:2.39 05:51:47 INFO - PROCESS | 7103 | Values: 84.0 87.0 85.0 85.0 87.0 92.0 90.0 85.0 87.0 87.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#11] stanford-crypto-ccm Cycles:10 Average:122.80 Median:123.00 stddev:5.18 (4.2%) stddev-sans-first:5.40 05:51:47 INFO - PROCESS | 7103 | Values: 120.0 119.0 125.0 134.0 121.0 128.0 124.0 122.0 118.0 117.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.80 Median:153.50 stddev:5.01 (3.3%) stddev-sans-first:4.85 05:51:47 INFO - PROCESS | 7103 | Values: 146.0 153.0 155.0 154.0 149.0 143.0 151.0 152.0 154.0 161.0 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - PROCESS | 7103 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:2.21 (3.2%) stddev-sans-first:2.35 05:51:47 INFO - PROCESS | 7103 | Values: 69.0 69.0 68.0 70.0 67.0 71.0 67.0 67.0 68.0 74.0 05:51:47 INFO - PROCESS | 7103 | -------- Summary: end -------- 05:51:47 INFO - PROCESS | 7103 | 05:51:47 INFO - TEST-INFO | 7103: exit 0 05:51:47 INFO - TEST-OK | kraken | took 59339ms 05:51:47 INFO - TEST-START | v8_7 05:51:47 INFO - operating with platform_type : linux_ 05:51:47 INFO - Initialising browser for v8_7 test... 05:51:47 INFO - TEST-INFO | started process 7388 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpw0DQsS/profile http://localhost:40232/getInfo.html) 05:51:52 INFO - PROCESS | 7388 | __metrics Screen width/height:1600/1200 05:51:52 INFO - PROCESS | 7388 | colorDepth:24 05:51:52 INFO - PROCESS | 7388 | Browser inner width/height: 1024/670 05:51:52 INFO - PROCESS | 7388 | __metrics 05:51:52 INFO - PROCESS | 7388 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:51:53 INFO - TEST-INFO | 7388: exit 0 05:51:53 INFO - Browser initialized. 05:51:53 INFO - Running cycle 1/1 for v8_7 test... 05:51:53 INFO - TEST-INFO | started process 7507 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpw0DQsS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:04 INFO - PROCESS | 7507 | RSS: Main: 149262336 05:52:04 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | Cycle 1(1): loaded http://localhost:40232/tests/v8_7/run.html (next: http://localhost:40232/tests/v8_7/run.html) 05:52:26 INFO - PROCESS | 7507 | RSS: Main: 146055168 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | __start_tp_report 05:52:26 INFO - PROCESS | 7507 | _x_x_mozilla_page_load 05:52:26 INFO - PROCESS | 7507 | _x_x_mozilla_page_load_details 05:52:26 INFO - PROCESS | 7507 | |i|pagename|runs| 05:52:26 INFO - PROCESS | 7507 | |0;Richards;166.16816218012627 05:52:26 INFO - PROCESS | 7507 | |1;DeltaBlue;188.71485185884129 05:52:26 INFO - PROCESS | 7507 | |2;Encrypt;279.9552071668533 05:52:26 INFO - PROCESS | 7507 | |3;Decrypt;5175.257731958763 05:52:26 INFO - PROCESS | 7507 | |4;RayTrace;1072.961373390558 05:52:26 INFO - PROCESS | 7507 | |5;Earley;727.2727272727273 05:52:26 INFO - PROCESS | 7507 | |6;Boyer;12481.481481481482 05:52:26 INFO - PROCESS | 7507 | |7;RegExp;25350 05:52:26 INFO - PROCESS | 7507 | |8;Splay;597.7286312014345 05:52:26 INFO - PROCESS | 7507 | |9;NavierStokes;4830.917874396136 05:52:26 INFO - PROCESS | 7507 | __end_tp_report 05:52:26 INFO - PROCESS | 7507 | __startTimestamp1451224346637__endTimestamp 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | ------- Summary: start ------- 05:52:26 INFO - PROCESS | 7507 | Number of tests: 10 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#0] Richards Cycles:1 Average:166.17 Median:166.17 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 166.2 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#1] DeltaBlue Cycles:1 Average:188.71 Median:188.71 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 188.7 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#2] Encrypt Cycles:1 Average:279.96 Median:279.96 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 280.0 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 5175.3 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#4] RayTrace Cycles:1 Average:1072.96 Median:1072.96 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 1073.0 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#5] Earley Cycles:1 Average:727.27 Median:727.27 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 727.3 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#6] Boyer Cycles:1 Average:12481.48 Median:12481.48 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 12481.5 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#7] RegExp Cycles:1 Average:25350.00 Median:25350.00 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 25350.0 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#8] Splay Cycles:1 Average:597.73 Median:597.73 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 597.7 05:52:26 INFO - PROCESS | 7507 | 05:52:26 INFO - PROCESS | 7507 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:52:26 INFO - PROCESS | 7507 | Values: 4830.9 05:52:26 INFO - PROCESS | 7507 | -------- Summary: end -------- 05:52:26 INFO - PROCESS | 7507 | 05:52:27 INFO - TEST-INFO | 7507: exit 0 05:52:27 INFO - TEST-OK | v8_7 | took 39608ms 05:52:27 INFO - SUITE-END | took 1210s 05:52:27 INFO - Completed test suite (00:20:10) 05:52:27 INFO - Working with test: dromaeo_css 05:52:27 INFO - Generating results file: dromaeo_css 05:52:27 INFO - Working with test: kraken 05:52:27 INFO - Generating results file: kraken 05:52:27 INFO - javascript benchmark 05:52:27 INFO - Working with test: v8_7 05:52:27 INFO - Generating results file: v8_7 05:52:27 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:27 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 05:52:27 INFO - process_Request line: dromaeo_css 6793.02 graph.html#tests=[[72,1,43]] 05:52:27 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:27 INFO - process_Request line: kraken 1519.20 graph.html#tests=[[232,1,43]] 05:52:27 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:27 INFO - process_Request line: v8_7 21302.45 graph.html#tests=[[230,1,43]] 05:52:27 INFO - RETURN: dromaeo_css: 6793.02 05:52:27 INFO - RETURN: kraken: 1519.20 05:52:27 INFO - RETURN: v8_7: 21302.45 05:52:27 INFO - javascript benchmark 05:52:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3131.8434078296086, 3163.1459505933385, 3186.856591453385, 3198.056573310873, 3216.4371235033877, 5122.667026643266, 5185.740712964355, 5197.506236907498, 5213.973930130349, 5254.842354729364, 5170.224466330053, 5196.844094677166, 5219.765110570024, 5304.416514183436, 5321.068812957727, 4579.427571553556, 4836.46798852126, 4964.379452568429, 4971.602271818246, 4982.950170498303, 4713.0, 4871.756412179395, 4926.605871530287, 4928.186849169882, 4940.061388336223, 4780.665353425262, 4781.593564547018, 4889.071076495455, 4906.190478571031, 4917.754112294371, 4775.856724298276, 4780.4741478437345, 4799.976000120012, 4803.567678908915, 4830.8792280193275, 4770.618350531984, 4799.976000120012, 4805.183118869801, 4842.055799119174, 4843.24929635907, 5119.820806271762, 5142.434332223453, 5143.6399452037995, 5159.0, 5181.248718935771, 5758.596898217084, 5768.74040668171, 5773.1051686988585, 5776.422357764232, 5782.421757824226, 5569.248151499577, 5667.291588551431, 5751.539876809845, 5796.362400135981, 5839.649621022752, 5422.53908417781, 5423.945760542383, 5432.538234250093, 5437.157240627709, 5440.966216418868, 5919.289685237799, 5938.287405511366, 5960.165576819249, 5964.403559644001, 5980.5215582754145, 5643.463870932255, 5677.545796336366, 5714.942850571441, 5763.740631671585, 5768.077107662838, 5878.617889837147, 5882.499987501024, 5889.352171261158, 5894.351621321651, 5928.110783382441, 5665.915011274834, 5741.626794258444, 5791.681457519877, 5806.4193580641595, 5819.330776960704, 5472.288602481652, 5619.662820230717, 5620.241267428844, 5620.775168993277, 5687.544996400278, 4371.125774845043, 4380.364847097153, 4380.540043295472, 4384.978075109604, 4387.254166791653, 3896.6687831534637, 3914.4911161548816, 3915.7063220258597, 3969.0672691917375, 3972.7219094663096, 5250.474952504719, 5320.946790532123, 5336.7865285388925, 5356.8660783479945, 5396.91904621431, 5639.8590035249445, 5669.1496275558175, 5681.289838770153, 5702.144678298288, 5728.541716662657, 5180.145276029489, 5300.893982120413, 5302.072137375943, 5322.946770532245, 5349.598780091509, 4908.9754551227015, 4920.040592084509, 4954.851354459372, 4964.975175124101, 5001.924971125436, 5947.316058653224, 5971.0744834550715, 5980.491658209014, 5987.64074155552, 5995.760169593254, 5541.861453463695, 5629.324481062299, 5782.942170578409, 5817.563682723728, 5834.15404766315, 5806.535477161901, 5842.970785146047, 5884.499817515559, 6015.157877897185, 6091.786787462417, 5705.657660540381, 5749.281339832521, 5750.8849823002465, 5759.481646651821, 5771.826845194651, 5821.79623713168, 5826.41735826414, 5826.504747096545, 5830.271216097988, 5838.94161058384, 5525.889482210253, 5631.915521267102, 5647.858803529944, 5730.541556675373, 5752.28096487939, 5534.889302213853, 5587.664740115606, 5589.021921163699, 5594.272744543184, 5596.468335508121, 5442.891142177214, 5483.725813709379, 5483.917741233805, 5504.834854954198, 5551.389347171727, 5579.832605021856, 5590.077637189981, 5652.039153343859, 5653.462921022497, 5695.088785794419, 5409.513143817076, 5431.239626452379, 5447.782088716407, 5457.0, 5468.207109968953], "unit": "score", "name": "dojo.html", "value": 5274.989140813033}, {"lowerIsBetter": false, "replicates": [576.5186069631857, 590.3771521045295, 592.7658574862928, 599.1521996375129, 601.1013534765526, 33536.329273414514, 34473.482897756556, 34473.827630861844, 34559.30881382371, 34677.30645387091, 36289.63710362902, 37226.813865930635, 37483.62516374835, 37758.0, 37772.0, 8462.95768521158, 8475.533845638487, 8476.576171191447, 8560.272376847975, 8625.870611940825, 8380.371472139583, 8445.704400345989, 8450.915490845075, 8636.913630863704, 8640.870386944169, 8573.09982451843, 8575.185357391068, 8586.699465518708, 8603.354748393864, 8650.87023694645, 8510.319174466027, 8585.871211931826, 8637.3521985851, 8710.303175745925, 8723.38936274461, 8481.236688697985, 8644.35167362444, 8674.95900491945, 8687.739367818913, 8723.302135829119, 37532.81233593842, 37936.24127517434, 37990.81004594987, 38387.0, 38690.226195476214, 3127.202563346332, 3234.66036066212, 3339.081752518053, 3376.358491886558, 3423.0415483664615, 27939.44121117566, 27971.860140699166, 28332.29169270764, 28440.715592844015, 28625.0, 3784.6972242220554, 3929.489166408377, 3968.4840970673918, 3977.2244412339605, 4021.8190181441905, 207752.0, 208591.0, 208669.0, 208885.0, 209260.9999999985, 51388.48611513837, 52322.0, 52339.73830130825, 52721.73639131856, 52863.20705189425, 63409.36590634127, 63635.68182159152, 63638.68180659067, 63665.36334636594, 63899.68050159719, 13397.397117129753, 13694.931525342508, 13716.245606491539, 13731.0, 13755.587332380044, 10079.49602519871, 10313.484325783831, 10387.636432724817, 10388.948055259676, 10420.947895260475, 19146.329878454188, 19511.294515660564, 19567.608647826964, 19677.606447870963, 19817.207311707658, 7856.607169641495, 8162.591870406456, 8169.099969510857, 8196.098429172785, 8267.801168830485, 938.7746940734311, 956.2827879090682, 961.9230461563059, 1069.2461814420815, 1074.7313171707074, 25833.0, 26056.86971565168, 26259.606105908428, 26424.8678756605, 29262.0, 1008.6433746610796, 1015.6800607808517, 1083.0523292119397, 1084.1868598551087, 1097.7036200225893, 13955.441782328593, 16235.107069111076, 16362.754558681629, 16566.0, 16875.320192388917, 363534.0, 364789.0, 369048.0, 369600.0, 371701.0, 52861.7356913213, 53346.73326633342, 53515.464845351045, 53578.124765633795, 53676.46323536715, 1331.5605850069453, 1356.9525066622818, 1360.3130419138272, 1366.938487768053, 1370.6299299189163, 28612.0, 28713.569296460988, 29176.854115729286, 29651.40697186001, 29750.55374169346, 44129.77935110304, 44371.33442998293, 44455.55544444514, 44655.0, 44761.55238447574, 290512.0, 290649.0, 291585.0, 293621.0, 295769.0, 31053.534196987515, 40593.0, 41737.79131104325, 41791.79104104581, 41926.790366049194, 36397.0, 37158.25683486261, 37486.43770343393, 37487.2502549942, 37673.0, 35888.64111358853, 37009.81495092507, 37075.0, 37573.62426375811, 38347.2330553393, 35456.46815297721, 35700.0, 35807.104822378606, 35921.64078359183, 36053.81973090118, 34892.0, 34914.65085349216, 35226.64773352234, 35233.11917201988, 35471.82264088663, 29985.700142998292, 31625.36749264956, 32507.18732031718, 32507.862224822016, 32530.674693252764, 23770.40573985664, 24516.14193503219, 24622.384440388418, 24741.87629061843, 24818.50362992766], "unit": "score", "name": "ext.html", "value": 18100.75927550271}, {"lowerIsBetter": false, "replicates": [360.7745159275453, 366.7744337232603, 367.3644594850907, 368.35170100622906, 369.990750231244, 16383.508494745147, 16770.41303554376, 16831.74752378715, 17094.230759615817, 17829.37597184099, 12978.610641680742, 12987.350632468386, 13022.54421095262, 13719.382627781748, 15934.282957266922, 4869.4887036861155, 4873.244647079709, 4944.357233559632, 4954.851354459354, 4975.975120124394, 4653.9069218615605, 5002.1746411842005, 5022.598192144638, 5025.271335656327, 5035.1188542005, 5232.529072383485, 5413.566914646838, 5420.105682562373, 5430.619856610039, 5438.53772429344, 5245.554127899133, 5281.788728450857, 5348.5453736432255, 5361.437049109826, 5402.810901618444, 4841.080194763019, 4845.685030473008, 4848.951510484921, 4852.830150944736, 4861.173600487926, 12899.742005159844, 13004.089713720043, 13124.934375328063, 15795.921020394939, 20340.69488957667, 2034.9796502034937, 2113.2392338758036, 2169.3383518026994, 2238.742544607375, 2293.3578597992587, 9555.378900371454, 9906.257030722725, 10372.688819335432, 10810.72973175669, 13376.933115334361, 1766.9469915902544, 1792.937247196355, 1793.6861049316449, 1804.7022241330164, 1819.7998220195768, 59369.40630593682, 60332.49168770772, 60550.0, 61064.69467652678, 61326.69336653333, 9701.981291964383, 9971.900280997243, 10174.4912754362, 10272.743181420525, 10296.639617613497, 12654.683632909067, 12676.176048556814, 12815.423305951255, 13039.282839443922, 13355.131916425402, 6936.687849046805, 7065.1521817382245, 7354.0, 7413.443991700644, 7487.28870757288, 7123.786286411416, 7130.750423735144, 7139.92860071403, 7158.8210294742, 7192.748253811092, 4367.58507941745, 4461.174682683649, 4462.9107417851465, 4504.954950450519, 4531.909361812811, 1774.8668849836313, 1780.1366337326385, 1797.5775692712202, 1806.096951524238, 1845.4463660901677, 415.7817145998375, 416.11575402270176, 435.34697953070395, 437.62145743931904, 989.3717489394287, 10125.392476451436, 10201.591936322464, 10296.433696146634, 10395.792084158275, 10506.73733156662, 445.1830890316241, 447.33793984901877, 448.1105006561972, 909.9499527525917, 1003.8544411060356, 5671.234383358194, 5983.431574000463, 6037.909431358533, 6056.636601803906, 8767.561621918878, 394639.0, 394879.0, 440851.0, 575408.0, 578410.0, 38947.80526097352, 38974.80512597419, 51234.0, 64992.35007649863, 78853.0, 507.36832643358514, 512.126823765479, 512.5028722139593, 513.5762995528704, 514.041312951343, 10544.103751181236, 10656.733581660521, 10679.305845120043, 10770.407627580558, 14667.339969701603, 32043.67956320407, 32707.528161233276, 33184.336313274085, 34349.484757728154, 35015.47476787799, 38863.80568097141, 40301.596984030955, 40340.79829600833, 40681.79659101686, 40786.38820417756, 9284.164425201765, 9365.11031452011, 9464.432134071978, 9506.714798556055, 9570.665026724031, 10634.680959571226, 15912.681746364777, 16816.411425600047, 17173.398931037354, 17433.47699569015, 14269.072510287213, 14736.0, 14866.256687165382, 15134.924325378302, 15161.317740701474, 13586.728265434438, 14372.49696260626, 14542.345594448487, 14584.0, 14840.40638374453, 13670.453181872614, 15186.0, 15192.772108418603, 15223.086614803147, 15360.155191464577, 9450.905490945277, 9587.328886978125, 9637.518124093627, 9868.654597089067, 9937.403755774676, 10769.569217231536, 14393.208373539137, 14435.639109022359, 14934.402623895357, 14935.850641493445], "unit": "score", "name": "jquery.html", "value": 7876.408565605747}, {"lowerIsBetter": false, "replicates": [1429.0711037825413, 1433.4266293482608, 1596.177968346302, 1731.6450127723817, 1746.4236801855388, 7419.443541734365, 7489.213632568577, 7543.283388078124, 7585.279398457144, 7617.999999999993, 7476.252374762535, 7563.205863384348, 7573.0912290525075, 7589.279018493248, 7597.544147351163, 2173.826093912487, 2242.069541140425, 2250.291158285143, 2250.5948929192737, 2253.864768113918, 2249.3364457485, 2263.343630347197, 2269.0, 2307.7230732312146, 2322.616768233248, 2229.1863469833556, 2261.163369553268, 2268.410213344534, 2269.183094086136, 2305.8616483011074, 2234.497238121418, 2234.854734442256, 2262.739784924738, 2271.8750468724297, 2275.6131457652073, 2269.217120093565, 2310.3992961830045, 2310.838241323108, 2331.8834058297016, 2336.707911511061, 7611.353034992032, 7678.155402905676, 7682.731104411375, 7691.807704807368, 7702.306792388656, 4599.264117741145, 4601.28680054592, 4640.628749700016, 4689.554492323224, 4690.742009189493, 2952.158634789096, 2953.350262942149, 3032.120685001347, 3045.1473587395567, 3057.6330840299083, 986.5856340337076, 1001.5843424978625, 1021.1065317846884, 1025.2310766924807, 1028.7942411517727, 10424.113950314157, 10445.0, 10453.84319235212, 10509.0, 10567.894321056769, 4112.773797441169, 4153.646940010072, 4175.8956026099595, 4208.326667733209, 4223.493180818322, 8567.957160214284, 8571.614277357534, 8585.399022068395, 8588.957055214809, 8593.269572086443, 4591.242444996605, 4601.263797792404, 4628.629709623222, 4630.86107416778, 4663.696859704109, 4192.601702838226, 4197.034682023152, 4231.196072746168, 4239.364095385655, 4261.2542805009, 3447.276072024903, 3461.2731326421736, 3475.4786781982907, 3479.4780782882262, 3530.3292374448774, 1074.0548317480723, 1075.8171110911164, 1083.1659622090947, 1083.5015892689294, 1088.8856670049688, 1303.0552849184455, 1308.2869835939437, 1317.4730107956893, 1324.278268343755, 1330.5875178694635, 7188.101487314086, 7220.3140701633265, 7250.4199664026755, 7267.055282813306, 7299.671514781848, 465.2277219815091, 465.74616833825644, 487.7000644603543, 489.0902920567741, 494.9851504454872, 5557.722113894289, 5583.776648934078, 5586.91619625706, 5614.185943038318, 5615.887682246333, 8571.314294856396, 8679.913200868037, 8685.956570217108, 8715.259202967693, 8791.296696264284, 8573.957130214309, 8636.654533818577, 8665.220130188314, 8681.435706679045, 8739.956300218586, 6848.760293389708, 6979.860402792017, 7024.648767561602, 7055.0, 7175.246599107123, 7058.611776352144, 7064.576125432491, 7113.964430177816, 7238.131424229126, 7258.0, 7773.41699372538, 7940.920590794018, 8153.592320383838, 8155.510669359857, 8156.551389673625, 8230.259276665129, 8319.750407487785, 8417.789555261168, 8436.493810371398, 8477.067522572388, 5810.215620891295, 5928.822135335947, 6409.647469389229, 6619.437347825488, 6636.701348439428, 7228.277172282729, 7234.6744396501235, 7239.746608868664, 7245.7826265212125, 7311.305425984523, 6708.362705542965, 6830.077939478107, 6835.282295358916, 6881.862362752817, 7006.439484841302, 6706.429953453816, 6767.357101075587, 6799.796006119825, 6902.758403455855, 6907.861842763217, 6466.159399278064, 6725.932740672531, 6795.932040679728, 6867.519273650796, 6914.965425172842, 6299.338569450142, 6302.180716506825, 6354.93645063562, 6357.586756860789, 6363.109164717036, 6243.032329988859, 6254.749810007549, 6256.343083976299, 6313.1792866928845, 6343.809685709436], "unit": "score", "name": "mootools.html", "value": 4294.5073861459905}, {"lowerIsBetter": false, "replicates": [333.38324100414235, 342.6778827901771, 343.62201578263904, 344.7311097344071, 345.98616055357786, 6261.561690681654, 6262.091996660487, 6310.589811662238, 6354.396332348431, 6403.807885763423, 6212.689365531728, 6320.4943604511645, 6365.809025729224, 6369.6496692681885, 6386.521010924176, 4714.387129673138, 4719.905601887977, 4735.337052812609, 4741.5495527924795, 4744.715317080978, 4732.43210814701, 4745.525447455261, 4746.833860814872, 4754.90490190196, 4755.120302744003, 4610.354550362952, 4658.440987081555, 4692.389989301403, 4693.037927224927, 4698.953010469902, 4625.745583992879, 4626.791794369245, 4628.398308219927, 4699.177643912336, 4702.929456058162, 4056.4118202860714, 4067.1662309226535, 4083.367078102869, 4084.1014976705374, 4087.632113109805, 5930.021546444831, 5989.460948514655, 6006.78976235834, 6025.8192254232445, 6028.306744724369, 5390.245365648812, 5394.460553944574, 5425.484578964992, 5471.987682278751, 5504.119340905436, 6655.168103987002, 6663.067170596072, 6664.133662623876, 6664.700088496029, 6683.465322774166, 4452.243118669834, 4453.51011388747, 4454.420925279725, 4460.6208472279895, 4470.508244093147, 6811.318868113248, 6844.5208835381545, 6880.965595171992, 6927.515073944826, 6968.303169683042, 4576.725396476222, 4577.450705915311, 4620.2607582786595, 4626.144163329727, 4630.305454181832, 4650.813967441264, 4669.789859456333, 4695.577398034194, 4703.082898834763, 4705.058988202373, 5467.097928841697, 5470.343558772893, 5477.260569823103, 5478.424765399655, 5481.369642491085, 6375.521835862236, 6598.73605055802, 6644.501662375341, 6670.199576050905, 6709.932900671029, 1634.3054201964117, 1652.8099268584026, 1659.6514731906434, 1661.6925868714327, 1664.7835781348347, 1082.4533610526635, 1094.2996482251365, 1107.374333501569, 1108.562117963416, 1119.9496022679, 6192.659403732838, 6270.905936410957, 6275.780347687901, 6277.52918531112, 6286.842828929313, 6796.048553202556, 6817.0796942413135, 6818.693158807866, 6821.556598821061, 6832.555883867532, 6076.422739839708, 6228.5951413158, 6231.065340199007, 6244.313125556185, 6249.531285153632, 3717.8326975286177, 3736.8131593420762, 3742.6257374262354, 3743.494628225155, 3759.981200093982, 7014.7022800782015, 7043.225245223021, 7049.718011279594, 7073.964630176816, 7091.361777440055, 6771.119754431892, 6831.2144103428745, 6845.281245469254, 6845.349691779273, 6921.273266307066, 7042.366187043088, 7045.295470453017, 7056.223815380304, 7062.223155452896, 7112.933060041035, 6764.2221144569, 6794.252632210551, 6801.2178599461695, 6802.251752307143, 6817.386435220854, 6603.735850566116, 6731.024001519851, 6734.191896972395, 6740.022696708852, 6758.493113016445, 6961.547499412522, 7036.612986285599, 7070.39901608369, 7078.893816592652, 7089.574625522485, 4643.141018911512, 4660.0, 4664.253719404879, 4665.603423708887, 4670.953290467051, 6634.933650663432, 6642.269350371552, 6725.361090696376, 6756.256811750802, 6885.483588730765, 6685.866282674417, 6688.3646053624825, 6699.665016749045, 6721.395074443323, 6748.763793267409, 6583.275839657538, 6603.966980165068, 6631.834204144935, 6638.800835974928, 6697.062411262525, 6471.935280647133, 6588.538802283794, 6592.24189218246, 6593.967030164818, 6595.076689263411, 6728.495362847904, 6732.845489443331, 6765.188177418544, 6773.729050838108, 6781.525293229427, 6515.087887695631, 6525.0, 6540.869182616226, 6559.934400655932, 6563.901541476786], "unit": "score", "name": "prototype.html", "value": 4966.302118636206}, {"lowerIsBetter": false, "replicates": [749.6326799868066, 774.1252384805173, 800.8918795962547, 860.2086080805658, 880.7489865388363, 13688.0, 13874.028817982746, 13896.444142234312, 13899.444022239111, 13899.722005559883, 12813.0, 12824.743505129893, 12849.807252891214, 12854.614361569167, 12998.93500532496, 652.9053287273342, 661.2759028863387, 775.6819703921379, 789.5499565247811, 792.7661339904713, 795.4988357334871, 799.2007992007992, 802.1457148137245, 804.1235053791366, 805.8025783682976, 831.5177197225624, 832.9167083291653, 835.3526017336552, 835.3943391041507, 836.4981011393146, 817.6279792694339, 821.8150916043902, 822.2599660305773, 832.3757182113415, 832.9250367466926, 604.8336707405455, 607.0317843040343, 610.0239616613427, 610.2493932463051, 612.2958597612736, 13072.823445889915, 13101.34493275342, 13160.6709832254, 13230.86769132306, 13274.0, 11874.821877671842, 12024.579139730067, 12312.322822244772, 12476.81284780729, 13387.330633468287, 10023.59905603775, 10204.132648724868, 10427.79144417115, 10493.160547156209, 10675.733106672318, 3525.11872031992, 3553.289342131584, 3562.09166662502, 3636.709063274932, 3705.2589482103685, 45954.770226148656, 46230.0, 46541.53458465406, 46695.53304466946, 47425.0, 9081.897474981402, 9182.586783594754, 9372.62509499626, 9486.193673537826, 9558.235341172689, 17102.230399632048, 17174.31302747901, 17200.483985480456, 17247.310107595807, 17258.568535786704, 7425.554466732014, 7486.401087913063, 7559.848803023909, 7584.772456826304, 7683.462157648911, 4125.154343359664, 4159.396887451303, 4167.0, 4224.619784219435, 4301.892452688645, 3006.744426723753, 3010.71398217169, 3030.4848175809725, 3031.4088752693456, 3048.4665183592783, 563.2115038945427, 568.7497501099502, 580.3876909860138, 590.8877313310458, 592.8221533539921, 562.7938481153886, 629.5089829932659, 664.7440735316937, 690.3343247356553, 713.0730050933765, 7388.44586656003, 7661.463697541119, 8059.234372734581, 8115.305969777045, 8425.325973922072, 592.9703514824328, 677.8203775999364, 682.3944219950923, 685.2017399729259, 708.1714394158846, 3719.683826874746, 3831.3678243090135, 3853.4797802296894, 4025.4163146343617, 4090.8159132839096, 48993.26510102351, 49097.263541046916, 52775.47224527706, 52983.0, 54955.175672364945, 49551.50448495541, 49631.75184124056, 49817.75091124521, 49915.50084499108, 50844.0, 45463.09073818505, 46112.308315375296, 46247.0, 46413.5358646416, 47330.29004565003, 8202.425830192067, 8353.373496987871, 8417.537035463109, 8448.535330556879, 8675.86986195195, 33819.32361352808, 34642.133946651535, 34958.30083398267, 35318.82340588281, 35579.64420355763, 40086.599134008284, 40099.1980160401, 40300.0, 42987.57012429961, 43040.35439468473, 10373.118284945864, 10569.63006294776, 10715.142788576739, 11263.718407039561, 11323.810999844898, 11135.88864111381, 11215.271007384494, 11272.380019099028, 11312.94343528277, 11704.297742135159, 10316.57429927016, 10667.679969600924, 10871.619493317696, 11004.0, 11130.499127539118, 10245.897541024495, 10415.895841041493, 10559.419231942009, 10666.466676665981, 10794.784104318027, 9690.85463718059, 10042.949785251027, 10377.636782712569, 10493.213009024506, 10544.0, 9386.202172815387, 9609.231261498924, 9655.806883862424, 9712.271579631415, 10000.0, 8577.35669824778, 8692.304615630861, 8776.56117194125, 8858.768722434157, 8952.373333866566], "unit": "score", "name": "yui.html", "value": 6125.5765024911225}], "name": "dromaeo_css", "value": 6793.020076712094}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 114.0, 113.0, 113.0, 121.0, 111.0, 110.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.6}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 150.0, 164.0, 132.0, 150.0, 155.0, 124.0, 168.0, 123.0], "unit": "score", "name": "audio-beat-detection", "value": 144.6}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 73.0, 75.0, 73.0, 129.0, 71.0, 71.0, 75.0, 74.0], "unit": "score", "name": "audio-fft", "value": 78.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 140.0, 95.0, 96.0, 95.0, 150.0, 96.0, 98.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 109.7}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [149.0, 146.0, 147.0, 148.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 97.0, 97.0, 96.0, 97.0, 97.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [61.0, 60.0, 59.0, 60.0, 63.0, 63.0, 60.0, 58.0, 61.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 60.2}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 67.0, 68.0, 68.0, 69.0, 67.0, 67.0, 68.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [84.0, 87.0, 85.0, 85.0, 87.0, 92.0, 90.0, 85.0, 87.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.9}, {"lowerIsBetter": true, "replicates": [120.0, 119.0, 125.0, 134.0, 121.0, 128.0, 124.0, 122.0, 118.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.8}, {"lowerIsBetter": true, "replicates": [146.0, 153.0, 155.0, 154.0, 149.0, 143.0, 151.0, 152.0, 154.0, 161.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 151.8}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 68.0, 70.0, 67.0, 71.0, 67.0, 67.0, 68.0, 74.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.0}], "name": "kraken", "value": 1519.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.16816218012627], "unit": "score", "name": "Richards", "value": 212.4474360000001}, {"lowerIsBetter": false, "replicates": [188.71485185884129], "unit": "score", "name": "DeltaBlue", "value": 350.35928200000006}, {"lowerIsBetter": false, "replicates": [279.9552071668533], "unit": "score", "name": "Encrypt", "value": 950.7985320000004}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1072.961373390558], "unit": "score", "name": "RayTrace", "value": 689.6697479999999}, {"lowerIsBetter": false, "replicates": [727.2727272727273], "unit": "score", "name": "Earley", "value": 916.3866250000003}, {"lowerIsBetter": false, "replicates": [12481.481481481482], "unit": "score", "name": "Boyer", "value": 53.39614540059344}, {"lowerIsBetter": false, "replicates": [25350.0], "unit": "score", "name": "RegExp", "value": 35.936291913214966}, {"lowerIsBetter": false, "replicates": [597.7286312014345], "unit": "score", "name": "Splay", "value": 136.33444300000005}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21302.44903233836}]} 05:52:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21302.45"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1519.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6793.02"}}} 05:52:28 INFO - Return code: 0 05:52:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:52:28 INFO - # TBPL SUCCESS # 05:52:28 INFO - Running post-action listener: _resource_record_post_action 05:52:28 INFO - Running post-run listener: _resource_record_post_run 05:52:29 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 3067904; Write bytes: 320094208; Read time: 1140; Write time: 325736 05:52:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 954368; Read time: 0; Write time: 72 05:52:29 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 3067904; Write bytes: 319139840; Read time: 1140; Write time: 325664 05:52:29 INFO - Running post-run listener: _upload_blobber_files 05:52:29 INFO - Blob upload gear active. 05:52:29 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:52:29 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 05:52:29 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'] 05:52:29 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 05:52:29 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:52:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:30 INFO - (blobuploader) - INFO - Done attempting. 05:52:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:52:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:52:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:31 INFO - (blobuploader) - INFO - Done attempting. 05:52:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:31 INFO - Return code: 0 05:52:31 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:52:31 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7d64fe77ecb8ec20151cab7df0f208a1175d8128fd040595f8bf6d6c5ff770b1ea0120809509a9cd38fc7c34b1e3da5083d829e5b4f2f5e094d046fb974a000", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931a8ff8271c3d4321159be5b117ff749f153f3e8a73022e7ace8d4720224272a63317505d01d55f98f68af236686950b05ef049853a733ae3954a1af8391f9"} 05:52:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:52:31 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:52:31 INFO - Contents: 05:52:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7d64fe77ecb8ec20151cab7df0f208a1175d8128fd040595f8bf6d6c5ff770b1ea0120809509a9cd38fc7c34b1e3da5083d829e5b4f2f5e094d046fb974a000", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931a8ff8271c3d4321159be5b117ff749f153f3e8a73022e7ace8d4720224272a63317505d01d55f98f68af236686950b05ef049853a733ae3954a1af8391f9"} 05:52:31 INFO - Copying logs to upload dir... 05:52:31 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.559478 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2015-12-27 05:52:31.274743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:31.278603) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7d64fe77ecb8ec20151cab7df0f208a1175d8128fd040595f8bf6d6c5ff770b1ea0120809509a9cd38fc7c34b1e3da5083d829e5b4f2f5e094d046fb974a000", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931a8ff8271c3d4321159be5b117ff749f153f3e8a73022e7ace8d4720224272a63317505d01d55f98f68af236686950b05ef049853a733ae3954a1af8391f9"} build_url:https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011444 build_url: 'https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7d64fe77ecb8ec20151cab7df0f208a1175d8128fd040595f8bf6d6c5ff770b1ea0120809509a9cd38fc7c34b1e3da5083d829e5b4f2f5e094d046fb974a000", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931a8ff8271c3d4321159be5b117ff749f153f3e8a73022e7ace8d4720224272a63317505d01d55f98f68af236686950b05ef049853a733ae3954a1af8391f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:31.305302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:31.305855) ========= 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=a9e534cca236dfa67d42a089000001c1-1451215997.648593-137182101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:31.388831) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-27 05:52:31.389151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-27 05:52:31.389496) ========= ========= Total master_lag: 0.31 =========