builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1451223084.72 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.719543) ========= 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.720046) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.720338) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006279 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.789171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.789546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.892856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.893208) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004566 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.944779) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.945072) ========= 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.945426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:24.945703) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-27 05:31:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2015-12-27 05:31:25 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.127972) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.128311) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019066 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.183037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.183407) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-27 05:31:25,312 truncating revision to first 12 chars 2015-12-27 05:31:25,312 Setting DEBUG logging. 2015-12-27 05:31:25,312 attempt 1/10 2015-12-27 05:31:25,312 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,638 unpacking tar archive at: mozilla-central-c39a71cf4146/testing/mozharness/ program finished with exit code 0 elapsedTime=0.603821 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.808160) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.808455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.827142) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-27 05:31:25.827387) ========= 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.827775) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-27 05:31:25.828063) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:26 INFO - MultiFileLogger online at 20151227 05:31:26 in /builds/slave/test-pgo 05:31:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:31:26 INFO - {'append_to_log': False, 05:31:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:31:26 INFO - 'blob_upload_branch': 'Firefox', 05:31:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:31:26 INFO - 'branch': 'Firefox', 05:31:26 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:26 INFO - 'config_files': ('talos/linux_config.py',), 05:31:26 INFO - 'default_actions': ('clobber', 05:31:26 INFO - 'read-buildbot-config', 05:31:26 INFO - 'download-and-extract', 05:31:26 INFO - 'populate-webroot', 05:31:26 INFO - 'create-virtualenv', 05:31:26 INFO - 'install', 05:31:26 INFO - 'run-tests'), 05:31:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:26 INFO - 'download_minidump_stackwalk': True, 05:31:26 INFO - 'download_symbols': 'ondemand', 05:31:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:26 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:26 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:26 INFO - 'installer_path': 'installer.exe', 05:31:26 INFO - 'log_level': 'info', 05:31:26 INFO - 'log_name': 'talos', 05:31:26 INFO - 'log_to_console': True, 05:31:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:26 INFO - 'opt_config_files': (), 05:31:26 INFO - 'pip_index': False, 05:31:26 INFO - 'sps_profile': False, 05:31:26 INFO - 'sps_profile_interval': 0, 05:31:26 INFO - 'suite': 'dromaeojs', 05:31:26 INFO - 'system_bits': '64', 05:31:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:26 INFO - 'title': 'talos-linux64-ix-018', 05:31:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:26 INFO - 'use_talos_json': True, 05:31:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:31:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:26 INFO - 'work_dir': 'build'} 05:31:26 INFO - ##### 05:31:26 INFO - ##### Running clobber 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: clobber 05:31:26 INFO - rmtree: /builds/slave/test-pgo/build 05:31:26 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", 05:31:26 INFO - "repository": "", 05:31:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 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": 618, 05:31:26 INFO - "slavename": "talos-linux64-ix-018", 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:33 INFO - Downloaded 1302 bytes. 05:31:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:31:33 INFO - Using the following test package requirements: 05:31:33 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:33 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'jsshell-linux-x86_64.zip'], 05:31:33 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:33 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:33 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:33 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:33 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:33 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31:33 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:33 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:33 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:33 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:33 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:33 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:35 INFO - Downloaded 23192124 bytes. 05:31:35 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:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:35 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:36 INFO - Return code: 0 05:31:36 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:36 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:36 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:36 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:36 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:36 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:36 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:38 INFO - Downloaded 23192124 bytes. 05:31:38 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:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:38 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:39 INFO - Return code: 0 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 - 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:39 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:39 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:39 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:39 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:40 INFO - Downloaded 11132270 bytes. 05:31:40 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:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:40 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:40 INFO - Return code: 0 05:31:40 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:40 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:40 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:40 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:40 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:40 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:42 INFO - Downloaded 54916794 bytes. 05:31:42 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:42 INFO - mkdir: /builds/slave/test-pgo/properties 05:31:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:31:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:31:42 INFO - Contents: 05:31:42 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:42 INFO - Running post-action listener: _resource_record_post_action 05:31:42 INFO - Running post-action listener: set_extra_try_arguments 05:31:42 INFO - ##### 05:31:42 INFO - ##### Running populate-webroot step. 05:31:42 INFO - ##### 05:31:42 INFO - Running pre-action listener: _resource_record_pre_action 05:31:42 INFO - Running main action method: populate_webroot 05:31:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:42 INFO - '--tppagecycles', 05:31:42 INFO - '7'], 05:31:42 INFO - 'tests': ['tsvgm']}}, 05:31:42 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:31:42 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:42 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:42 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'tests': ['damp', 'tps']}, 05:31:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['damp', 'tps']}, 05:31:42 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:31:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:42 INFO - 'other': {'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'other_l64': {'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'other_nol64': {'tests': ['a11yr', 05:31:42 INFO - 'ts_paint', 05:31:42 INFO - 'tpaint', 05:31:42 INFO - 'sessionrestore', 05:31:42 INFO - 'sessionrestore_no_auto_restore']}, 05:31:42 INFO - 'svgr': {'tests': ['tsvgx', 05:31:42 INFO - 'tsvgr_opacity', 05:31:42 INFO - 'tart', 05:31:42 INFO - 'tscrollx', 05:31:42 INFO - 'cart']}, 05:31:42 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['tsvgx', 05:31:42 INFO - 'tsvgr_opacity', 05:31:42 INFO - 'tart', 05:31:42 INFO - 'tscrollx', 05:31:42 INFO - 'cart']}, 05:31:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'tests': ['tp5o']}, 05:31:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'talos_options': ['--e10s'], 05:31:42 INFO - 'tests': ['tp5o']}, 05:31:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'talos_options': ['--xperf_path', 05:31:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:42 INFO - 'tests': ['tp5n']}, 05:31:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:42 INFO - 'talos_options': ['--e10s', 05:31:42 INFO - '--xperf_path', 05:31:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:42 INFO - 'tests': ['tp5n']}}, 05:31:42 INFO - 'talos.zip': {'path': '', 05:31:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:42 INFO - Running post-action listener: _resource_record_post_action 05:31:42 INFO - ##### 05:31:42 INFO - ##### Running create-virtualenv step. 05:31:42 INFO - ##### 05:31:42 INFO - Running pre-action listener: _resource_record_pre_action 05:31:42 INFO - Running main action method: create_virtualenv 05:31:42 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:31:42 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:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:31:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:42 INFO - Using real prefix '/usr' 05:31:42 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:31:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:44 INFO - Installing pip.................done. 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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', 'pip>=1.5'] 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 pip>=1.5 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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 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 - 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:44 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing psutil>=0.7.1 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', '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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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', 'psutil>=0.7.1'] 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 psutil>=0.7.1 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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:44 INFO - '_': '/tools/buildbot/bin/python'} 05:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:45 INFO - Downloading/unpacking psutil>=0.7.1 05:31:45 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:45 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:45 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:45 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:45 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:45 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 - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:31:46 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:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:46 INFO - Installing collected packages: psutil 05:31:46 INFO - Running setup.py install for psutil 05:31:47 INFO - building 'psutil._psutil_linux' extension 05:31:47 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:47 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:47 INFO - building 'psutil._psutil_posix' extension 05:31:47 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:47 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:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:47 INFO - Successfully installed psutil 05:31:47 INFO - Cleaning up... 05:31:47 INFO - Return code: 0 05:31:47 INFO - Installing mozsystemmonitor==0.0.0 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', '--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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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', '--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:47 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: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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 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 - Downloading/unpacking mozsystemmonitor==0.0.0 05:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:31:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:31:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:49 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:49 INFO - Installing collected packages: mozsystemmonitor 05:31:49 INFO - Running setup.py install for mozsystemmonitor 05:31:49 INFO - Successfully installed mozsystemmonitor 05:31:49 INFO - Cleaning up... 05:31:49 INFO - Return code: 0 05:31:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:49 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:49 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:49 INFO - Using env: {'DISPLAY': ':0', 05:31:49 INFO - 'HOME': '/home/cltbld', 05:31:49 INFO - 'LANG': 'en_US.UTF-8', 05:31:49 INFO - 'LANGUAGE': 'en_US:en', 05:31:49 INFO - 'LOGNAME': 'cltbld', 05:31:49 INFO - 'MAIL': '/var/mail/cltbld', 05:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:49 INFO - 'MOZ_NO_REMOTE': '1', 05:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:49 INFO - 'NO_EM_RESTART': '1', 05:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:49 INFO - 'SHELL': '/bin/bash', 05:31:49 INFO - 'SHLVL': '1', 05:31:49 INFO - 'TERM': 'linux', 05:31:49 INFO - 'TMOUT': '86400', 05:31:49 INFO - 'USER': 'cltbld', 05:31:49 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:49 INFO - '_': '/tools/buildbot/bin/python'} 05:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:51 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:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 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:52 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:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:31:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 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:52 INFO - Downloading docopt-0.6.1.tar.gz 05:31:52 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:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:52 INFO - Running setup.py install for blobuploader 05:31:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:52 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:31:52 INFO - Running setup.py install for requests 05:31:53 INFO - Running setup.py install for docopt 05:31:53 INFO - Successfully installed blobuploader requests docopt 05:31:53 INFO - Cleaning up... 05:31:53 INFO - Return code: 0 05:31:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:53 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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:53 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:53 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:53 INFO - Using env: {'DISPLAY': ':0', 05:31:53 INFO - 'HOME': '/home/cltbld', 05:31:53 INFO - 'LANG': 'en_US.UTF-8', 05:31:53 INFO - 'LANGUAGE': 'en_US:en', 05:31:53 INFO - 'LOGNAME': 'cltbld', 05:31:53 INFO - 'MAIL': '/var/mail/cltbld', 05:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:53 INFO - 'MOZ_NO_REMOTE': '1', 05:31:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:53 INFO - 'NO_EM_RESTART': '1', 05:31:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:53 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:53 INFO - 'SHELL': '/bin/bash', 05:31:53 INFO - 'SHLVL': '1', 05:31:53 INFO - 'TERM': 'linux', 05:31:53 INFO - 'TMOUT': '86400', 05:31:53 INFO - 'USER': 'cltbld', 05:31:53 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:53 INFO - '_': '/tools/buildbot/bin/python'} 05:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:53 INFO - Running setup.py (path:/tmp/pip-TyGb43-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:53 INFO - Running setup.py (path:/tmp/pip-_bFfUy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:53 INFO - Running setup.py (path:/tmp/pip-xq8G7S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:53 INFO - Running setup.py (path:/tmp/pip-7aFDjd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:53 INFO - Running setup.py (path:/tmp/pip-zeaPVe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:53 INFO - Running setup.py (path:/tmp/pip-17Kril-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:53 INFO - Running setup.py (path:/tmp/pip-mt0ztt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:53 INFO - Running setup.py (path:/tmp/pip-xVyCNe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:54 INFO - Running setup.py (path:/tmp/pip-nT0PzH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:54 INFO - Running setup.py (path:/tmp/pip-iNiAGq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:54 INFO - Running setup.py (path:/tmp/pip-2DQjnF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:54 INFO - Running setup.py (path:/tmp/pip-JRAC7i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:54 INFO - Running setup.py (path:/tmp/pip-l8YNSL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:54 INFO - Running setup.py (path:/tmp/pip-xAwscY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:54 INFO - Running setup.py (path:/tmp/pip-UGEvar-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:54 INFO - Running setup.py (path:/tmp/pip-DWE0X7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:54 INFO - Running setup.py (path:/tmp/pip-2TCU1R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:54 INFO - Running setup.py install for manifestparser 05:31:54 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:31:54 INFO - Running setup.py install for mozcrash 05:31:54 INFO - Running setup.py install for mozdebug 05:31:55 INFO - Running setup.py install for mozdevice 05:31:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for mozfile 05:31:55 INFO - Running setup.py install for mozhttpd 05:31:55 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for mozinfo 05:31:55 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for mozInstall 05:31:55 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for mozleak 05:31:55 INFO - Running setup.py install for mozlog 05:31:55 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for moznetwork 05:31:55 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:31:55 INFO - Running setup.py install for mozprocess 05:31:56 INFO - Running setup.py install for mozprofile 05:31:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:31:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:31:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:31:56 INFO - Running setup.py install for mozrunner 05:31:56 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:31:56 INFO - Running setup.py install for mozscreenshot 05:31:56 INFO - Running setup.py install for moztest 05:31:56 INFO - Running setup.py install for mozversion 05:31:56 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:31:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:56 INFO - Cleaning up... 05:31:56 INFO - Return code: 0 05:31:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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:56 INFO - Using env: {'DISPLAY': ':0', 05:31:56 INFO - 'HOME': '/home/cltbld', 05:31:56 INFO - 'LANG': 'en_US.UTF-8', 05:31:56 INFO - 'LANGUAGE': 'en_US:en', 05:31:56 INFO - 'LOGNAME': 'cltbld', 05:31:56 INFO - 'MAIL': '/var/mail/cltbld', 05:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:56 INFO - 'MOZ_NO_REMOTE': '1', 05:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:56 INFO - 'NO_EM_RESTART': '1', 05:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:56 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:56 INFO - 'SHELL': '/bin/bash', 05:31:56 INFO - 'SHLVL': '1', 05:31:56 INFO - 'TERM': 'linux', 05:31:56 INFO - 'TMOUT': '86400', 05:31:56 INFO - 'USER': 'cltbld', 05:31:56 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:56 INFO - '_': '/tools/buildbot/bin/python'} 05:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:56 INFO - Running setup.py (path:/tmp/pip-ICKms4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:56 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:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:56 INFO - Running setup.py (path:/tmp/pip-Vm6bez-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:56 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:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:56 INFO - Running setup.py (path:/tmp/pip-qfMT3H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:56 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:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:56 INFO - Running setup.py (path:/tmp/pip-FiwfS2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:57 INFO - Running setup.py (path:/tmp/pip-zl__LL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:57 INFO - Running setup.py (path:/tmp/pip-nkrWSe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:57 INFO - Running setup.py (path:/tmp/pip-7GX5dK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:57 INFO - Running setup.py (path:/tmp/pip-JyxRiC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:57 INFO - Running setup.py (path:/tmp/pip-3ZWzpn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:57 INFO - Running setup.py (path:/tmp/pip-YUkNCB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:57 INFO - Running setup.py (path:/tmp/pip-sPd5LR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:57 INFO - Running setup.py (path:/tmp/pip-KxVc7e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:57 INFO - Running setup.py (path:/tmp/pip-3a7iXC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:57 INFO - Running setup.py (path:/tmp/pip-4d5MGf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:57 INFO - Running setup.py (path:/tmp/pip-S9Naxv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:57 INFO - Running setup.py (path:/tmp/pip-i9m77h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:57 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:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:57 INFO - Running setup.py (path:/tmp/pip-YqQjYA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:58 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:58 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:58 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:58 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:58 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:58 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:58 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:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:59 INFO - Downloading blessings-1.5.1.tar.gz 05:31:59 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:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:59 INFO - Installing collected packages: blessings 05:31:59 INFO - Running setup.py install for blessings 05:31:59 INFO - Successfully installed blessings 05:31:59 INFO - Cleaning up... 05:31:59 INFO - Return code: 0 05:31:59 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 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 - Current package versions: 05:32:00 INFO - argparse == 1.2.1 05:32:00 INFO - blessings == 1.5.1 05:32:00 INFO - blobuploader == 1.2.4 05:32:00 INFO - docopt == 0.6.1 05:32:00 INFO - manifestparser == 1.1 05:32:00 INFO - mozInstall == 1.12 05:32:00 INFO - mozcrash == 0.16 05:32:00 INFO - mozdebug == 0.1 05:32:00 INFO - mozdevice == 0.47 05:32:00 INFO - mozfile == 1.2 05:32:00 INFO - mozhttpd == 0.7 05:32:00 INFO - mozinfo == 0.9 05:32:00 INFO - mozleak == 0.1 05:32:00 INFO - mozlog == 3.1 05:32:00 INFO - moznetwork == 0.27 05:32:00 INFO - mozprocess == 0.22 05:32:00 INFO - mozprofile == 0.28 05:32:00 INFO - mozrunner == 6.11 05:32:00 INFO - mozscreenshot == 0.1 05:32:00 INFO - mozsystemmonitor == 0.0 05:32:00 INFO - moztest == 0.7 05:32:00 INFO - mozversion == 1.4 05:32:00 INFO - psutil == 3.1.1 05:32:00 INFO - requests == 1.2.3 05:32:00 INFO - wsgiref == 0.1.2 05:32:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:32:00 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:32:00 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:32:00 INFO - Using env: {'DISPLAY': ':0', 05:32:00 INFO - 'HOME': '/home/cltbld', 05:32:00 INFO - 'LANG': 'en_US.UTF-8', 05:32:00 INFO - 'LANGUAGE': 'en_US:en', 05:32:00 INFO - 'LOGNAME': 'cltbld', 05:32:00 INFO - 'MAIL': '/var/mail/cltbld', 05:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:00 INFO - 'MOZ_NO_REMOTE': '1', 05:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:00 INFO - 'NO_EM_RESTART': '1', 05:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:00 INFO - 'SHELL': '/bin/bash', 05:32:00 INFO - 'SHLVL': '1', 05:32:00 INFO - 'TERM': 'linux', 05:32:00 INFO - 'TMOUT': '86400', 05:32:00 INFO - 'USER': 'cltbld', 05:32:00 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:00 INFO - '_': '/tools/buildbot/bin/python'} 05:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 INFO - Cleaning up... 05:32:00 INFO - Return code: 0 05:32:00 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:32:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:32:00 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:32:00 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:32:00 INFO - Using env: {'DISPLAY': ':0', 05:32:00 INFO - 'HOME': '/home/cltbld', 05:32:00 INFO - 'LANG': 'en_US.UTF-8', 05:32:00 INFO - 'LANGUAGE': 'en_US:en', 05:32:00 INFO - 'LOGNAME': 'cltbld', 05:32:00 INFO - 'MAIL': '/var/mail/cltbld', 05:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:00 INFO - 'MOZ_NO_REMOTE': '1', 05:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:00 INFO - 'NO_EM_RESTART': '1', 05:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:00 INFO - 'SHELL': '/bin/bash', 05:32:00 INFO - 'SHLVL': '1', 05:32:00 INFO - 'TERM': 'linux', 05:32:00 INFO - 'TMOUT': '86400', 05:32:00 INFO - 'USER': 'cltbld', 05:32:00 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:00 INFO - '_': '/tools/buildbot/bin/python'} 05:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:00 INFO - Downloading/unpacking jsonschema 05:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:02 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:32:02 INFO - Downloading/unpacking functools32 (from jsonschema) 05:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:02 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:32:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:02 INFO - warning: no files found matching '*.txt' 05:32:02 INFO - no previously-included directories found matching 'build' 05:32:02 INFO - no previously-included directories found matching 'dist' 05:32:02 INFO - no previously-included directories found matching '.git*' 05:32:02 INFO - Installing collected packages: jsonschema, functools32 05:32:02 INFO - Running setup.py install for functools32 05:32:02 INFO - warning: no files found matching '*.txt' 05:32:02 INFO - no previously-included directories found matching 'build' 05:32:02 INFO - no previously-included directories found matching 'dist' 05:32:02 INFO - no previously-included directories found matching '.git*' 05:32:02 INFO - Successfully installed jsonschema functools32 05:32:02 INFO - Cleaning up... 05:32:02 INFO - Return code: 0 05:32:02 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f499281af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b63630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, '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': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', '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:32:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 05:32:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:32:02 INFO - Using env: {'DISPLAY': ':0', 05:32:02 INFO - 'HOME': '/home/cltbld', 05:32:02 INFO - 'LANG': 'en_US.UTF-8', 05:32:02 INFO - 'LANGUAGE': 'en_US:en', 05:32:02 INFO - 'LOGNAME': 'cltbld', 05:32:02 INFO - 'MAIL': '/var/mail/cltbld', 05:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:02 INFO - 'MOZ_NO_REMOTE': '1', 05:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:02 INFO - 'NO_EM_RESTART': '1', 05:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:02 INFO - 'SHELL': '/bin/bash', 05:32:02 INFO - 'SHLVL': '1', 05:32:02 INFO - 'TERM': 'linux', 05:32:02 INFO - 'TMOUT': '86400', 05:32:02 INFO - 'USER': 'cltbld', 05:32:02 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:02 INFO - '_': '/tools/buildbot/bin/python'} 05:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:02 INFO - Downloading/unpacking flake8 05:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:32:04 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:32:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:32:04 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:32:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:32:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:32:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:05 INFO - no previously-included directories found matching 'docs/_build' 05:32:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:32:05 INFO - Running setup.py install for pep8 05:32:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:05 INFO - no previously-included directories found matching 'docs/_build' 05:32:05 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:32:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:32:05 INFO - Cleaning up... 05:32:05 INFO - Return code: 0 05:32:05 INFO - Running post-action listener: _resource_record_post_action 05:32:05 INFO - Running post-action listener: _start_resource_monitoring 05:32:05 INFO - Starting resource monitoring. 05:32:05 INFO - ##### 05:32:05 INFO - ##### Running install step. 05:32:05 INFO - ##### 05:32:05 INFO - Running pre-action listener: _resource_record_pre_action 05:32:05 INFO - Running main action method: install 05:32:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:32:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:05 INFO - Reading from file tmpfile_stdout 05:32:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:32:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:32:05 INFO - Reading from file tmpfile_stdout 05:32:05 INFO - Output received: 05:32:05 INFO - Usage: mozinstall [options] installer 05:32:05 INFO - Options: 05:32:05 INFO - -h, --help show this help message and exit 05:32:05 INFO - -d DEST, --destination=DEST 05:32:05 INFO - Directory to install application into. [default: 05:32:05 INFO - "/builds/slave/test-pgo"] 05:32:05 INFO - --app=APP Application being installed. [default: firefox] 05:32:05 INFO - mkdir: /builds/slave/test-pgo/build/application 05:32:05 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:05 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:21 INFO - Reading from file tmpfile_stdout 05:32:21 INFO - Output received: 05:32:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:32:21 INFO - Running post-action listener: _resource_record_post_action 05:32:21 INFO - ##### 05:32:21 INFO - ##### Running run-tests step. 05:32:21 INFO - ##### 05:32:21 INFO - Running pre-action listener: _resource_record_pre_action 05:32:21 INFO - Running main action method: run_tests 05:32:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:32:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:32:21 INFO - Python 2.7.3 05:32:21 INFO - Return code: 0 05:32:21 INFO - grabbing minidump binary from tooltool 05:32:21 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:21 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 0x1bf4840>, '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 0x1b616b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:32:21 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:21 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:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:21 INFO - Return code: 0 05:32:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:32:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:32:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:32:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:32:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:32:22 INFO - Return code: 0 05:32:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 05:32:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 05:32:22 INFO - Using env: {'DISPLAY': ':0', 05:32:22 INFO - 'HOME': '/home/cltbld', 05:32:22 INFO - 'LANG': 'en_US.UTF-8', 05:32:22 INFO - 'LANGUAGE': 'en_US:en', 05:32:22 INFO - 'LOGNAME': 'cltbld', 05:32:22 INFO - 'MAIL': '/var/mail/cltbld', 05:32:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:22 INFO - 'MOZ_NO_REMOTE': '1', 05:32:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:22 INFO - 'NO_EM_RESTART': '1', 05:32:22 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:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:22 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:32:22 INFO - 'SHELL': '/bin/bash', 05:32:22 INFO - 'SHLVL': '1', 05:32:22 INFO - 'TERM': 'linux', 05:32:22 INFO - 'TMOUT': '86400', 05:32:22 INFO - 'USER': 'cltbld', 05:32:22 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971', 05:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:22 INFO - '_': '/tools/buildbot/bin/python'} 05:32:22 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/kkDu6bzyS2-UmD5s_T5sCQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:32:22 INFO - mozversion application_buildid: 20151227030239 05:32:22 INFO - mozversion application_changeset: c39a71cf41460924d2988d2d63be2f5d267db82a 05:32:22 INFO - mozversion application_display_name: Nightly 05:32:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:22 INFO - mozversion application_name: Firefox 05:32:22 INFO - mozversion application_remotingname: firefox 05:32:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:22 INFO - mozversion application_vendor: Mozilla 05:32:22 INFO - mozversion application_version: 46.0a1 05:32:22 INFO - mozversion platform_buildid: 20151227030239 05:32:22 INFO - mozversion platform_changeset: c39a71cf41460924d2988d2d63be2f5d267db82a 05:32:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:22 INFO - mozversion platform_version: 46.0a1 05:32:22 INFO - using testdate: 1451223142 05:32:22 INFO - actual date: 1451223142 05:32:22 INFO - starting webserver on 'localhost:33546' 05:32:22 INFO - SUITE-START | Running 3 tests 05:32:22 INFO - TEST-START | dromaeo_css 05:32:22 INFO - operating with platform_type : linux_ 05:32:22 INFO - Initialising browser for dromaeo_css test... 05:32:22 INFO - TEST-INFO | started process 5025 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRbleju/profile http://localhost:33546/getInfo.html) 05:32:27 INFO - PROCESS | 5025 | __metrics Screen width/height:1600/1200 05:32:27 INFO - PROCESS | 5025 | colorDepth:24 05:32:27 INFO - PROCESS | 5025 | Browser inner width/height: 1024/697 05:32:27 INFO - PROCESS | 5025 | __metrics 05:32:27 INFO - PROCESS | 5025 | 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:27 INFO - PROCESS | 5025 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:28 INFO - PROCESS | 5025 | 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:28 INFO - TEST-INFO | 5025: exit 0 05:32:28 INFO - Browser initialized. 05:32:28 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:28 INFO - TEST-INFO | started process 5121 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRbleju/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:40 INFO - PROCESS | 5121 | RSS: Main: 146309120 05:32:40 INFO - PROCESS | 5121 | 05:33:31 INFO - PROCESS | 5121 | 1451223211894 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:40 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33546/tests/dromaeo/cssquery-ext.html) 05:35:41 INFO - PROCESS | 5121 | RSS: Main: 264839168 05:35:41 INFO - PROCESS | 5121 | 05:38:43 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-ext.html (next: http://localhost:33546/tests/dromaeo/cssquery-jquery.html) 05:38:44 INFO - PROCESS | 5121 | RSS: Main: 233234432 05:38:44 INFO - PROCESS | 5121 | 05:41:46 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33546/tests/dromaeo/cssquery-mootools.html) 05:41:47 INFO - PROCESS | 5121 | RSS: Main: 234135552 05:41:47 INFO - PROCESS | 5121 | 05:44:48 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33546/tests/dromaeo/cssquery-prototype.html) 05:44:49 INFO - PROCESS | 5121 | RSS: Main: 210112512 05:44:49 INFO - PROCESS | 5121 | 05:47:52 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33546/tests/dromaeo/cssquery-yui.html) 05:47:52 INFO - PROCESS | 5121 | RSS: Main: 212918272 05:47:52 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | Cycle 1(1): loaded http://localhost:33546/tests/dromaeo/cssquery-yui.html (next: http://localhost:33546/tests/dromaeo/cssquery-dojo.html) 05:50:55 INFO - PROCESS | 5121 | RSS: Main: 251949056 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | __start_tp_report 05:50:55 INFO - PROCESS | 5121 | _x_x_mozilla_page_load 05:50:55 INFO - PROCESS | 5121 | _x_x_mozilla_page_load_details 05:50:55 INFO - PROCESS | 5121 | |i|pagename|runs| 05:50:55 INFO - PROCESS | 5121 | |0;dojo.html;3087.274490494734;3157.242261857155;3168.2554599669074;3177.9841100794492;3202.6156861176664;5020.447750747415;5150.768215430306;5233.398159211692;5263.421023687391;5307.336582927134;5183.896322073556;5222.712750798704;5224.346956630421;5224.660397074187;5324.733763311829;4695.295705644146;4708.317293992369;4711.363965864599;4776.283557466373;4799.160146974283;4904.950950490503;4912.287718280847;4933.950660493403;4938.555530002299;4991.126552853254;4607.493175750665;4704.976475117637;4716.504766999467;4805.231163013901;4810.831620893252;4796.088743138793;4801.063792560417;4805.711657300573;4821;4839.322494850724;4768.427788665383;4783;4798;4803.015381846748;4814.157522433595;4960.2559616057515;5024.32171656829;5055.721935293557;5068.645194836363;5077.238414237894;5501.972490137564;5554.166874968747;5573.386927437979;5606.439356064402;5636.718164091779;5535.197396377543;5550.944490555084;5552.139418390156;5563.777448902039;5618.01684705179;5162.406323272797;5164.14791559396;5166.147585648364;5168.715720635363;5192.71440070796;5579.13523403873;5617.719114044281;5629.380768115504;5637.830865074055;5689.118186681071;5558.749856256478;5559.33288005442;5559.360673522516;5563.721813909288;5592.468715472024;5572.86067848307;5642.520385767256;5642.927843709764;5649.209110724501;5654.123610840326;5345.679259244458;5498.80754173602;5525.364583072998;5581.274434323594;5653.038983372837;5552.91670624941;5553.583481238923;5556.444355564411;5557.3331200256225;5569.749361278752;4329.76186309756;4337.414449049401;4346.760928148982;4355.216061109031;4370.912581748347;3817.5991520889906;3840.539135303781;3895.8052097395016;3908.785016824102;3932.410138479194;5248.798620463484;5256.894862102737;5267.051930652519;5269.1569348903995;5308.946910530845;5426.8371948841595;5631.493165615114;5633.126865335879;5637.041702910515;5652.519535839499;5126.615503837227;5267.657602255841;5287.920681189786;5334.653247538975;5369.758360873692;4803.759812009385;4806.9665022019735;4809.182438985381;4810.95189048112;4836.685615435056;5684.772609095672;5725.713714314267;5738.053221218453;5738.856528586735;5788.623739457006;5432.456754324536;5540.501354878081;5608;5637.633553818989;5659.773609055592;5755.194272801895;5774.220480235283;5778.104393818965;5782.913256301074;5784.797532086357;5389.973050134724;5492.148716948879;5496.285482887199;5536.335639723259;5536.557075433875;5598.188262701967;5656.632318899258;5663.405342438985;5670.943290567042;5710.771569137188;5488.094464413327;5523.751431185687;5524.751386187711;5526.447355264602;5527.50252477279;5313.707746073848;5406.810761623325;5423.728813559385;5504.311961004874;5530.391656917655;5436.347638283273;5444.8638784029135;5446.291982042309;5453.400125986218;5490.231367608458;5481.75332110048;5516.393196748435;5541.445855414427;5546.36216835053;5598.944010559842;5163.380394352702;5213.556847667991;5248.1340578804065;5333.599980001593;5378.838634840961 05:50:55 INFO - PROCESS | 5121 | |1;ext.html;550.7500533213263;578.4360248757462;588.6762280745589;589.1663296435544;610.5481943361914;30421;31410.528842067386;31447.05658830236;38305.425418618746;39208.215835683266;42488;43101.35347969783;43129.13741725164;43207.13585728283;43221.35167972483;8556.743297701063;8729.869051964226;8731.563421828914;8792.384533082655;8798.824023519526;7568.318851303382;7745.728899488519;10140.49297535124;10266.948665256663;10361.689149325532;10024;10112.89887101127;10113.646022389217;10119.241056920724;10120.645777397793;7590.3548198403205;7747.806304842367;10056.94971525145;10154.187664986783;10165.59337626494;7432.070991126109;10210.744731381776;10371.585136594527;10388.584456621726;10394.792104157874;35811;36309.273814523825;36310.45534316987;36319.273614527825;36346.63653363459;3194.520821876714;3204.1829333520036;3213.935721285561;3347.7321814255047;3573.962970130951;27423.314417139733;27481.725182748116;27609.171724848286;27660.170194894185;29163.270918227216;3915.647591716759;3934.2328245992044;3962.6631736302447;4001.279769641463;4009;186956;188062;188285;188497.99999999863;188931;54154.18768718473;55081;55448.72275638596;55484.445155547925;55527;50298.49701502938;60776.69611651913;61627.69186154041;61653;61667.38332616706;13838.446462141603;14056.71886562263;14061.648458788408;14061.859381406055;14080.507182248572;10500.842487362695;10550.525226364833;10553.261271710906;10553.84169237462;10567.788644227227;18428.907855460904;18612.720809187875;18632.16155273016;18652.253909843454;19217.61564768697;7179.712811487587;7379.963100184572;7388.076490438695;7477.925220747723;7523.059617547807;946.1153821177221;978.6476868327396;999.0608827701936;1094.5676457799125;1121.8373335866254;24647.137350192657;26590.202293930826;26754.19737407881;26761.598576021373;26899.462010759675;1014.0923873133504;1062.3148069495287;1074.511097450658;1089.54239219528;1138.823482360219;13235.801462978063;13312.26782526951;13428.93285533566;13471.595852124452;13496.932515337361;316209;318890;320249;321270;322236;50259.24611130836;50546.241806372935;50547.494525055015;50573;50606.49393506018;1304.1914013311618;1323.6161513161076;1335.9465621375036;1336.7326534692907;1336.739335829504;24127.276181714577;25063.373415664755;26083.347916301485;28194.2951426216;29782.70217297799;45185;45536.77231613821;45547.544524555655;45553;45608.77195614001;241086;242648;242736;243171;243784;27778.583321249793;28196.295092622848;30908.53637195399;31348;31434.842825785723;35446;38073;38828.6117138825;39495;39507.802460987514;35113.297734045686;36895.41732078729;38861.61138388693;39370.212595747354;40310.39534406928;33673.550912794875;33865.322693546485;33906.66093339035;34222.65777342294;34330.31339373248;33465.98408191033;33831.15422114368;33904.83047584845;34344.65655343414;34453.82773086119;29539;31149.844250778602;31666.25006249081;31672.683273167895;31680.683193168694;23806;23809.642855356837;23818.761812381654;23918.402039949142;23970.161044363365 05:50:55 INFO - PROCESS | 5121 | |2;jquery.html;358.14224931289544;359.89563026722254;363.10675737685295;364.2950890027796;366.0555766123402;14014.229217393065;14248.175182481751;15695.686086278267;15950.920245398771;16591.087490188034;15544.84455155448;17001.404950826694;17081.658366832657;17093.572660683458;17503.299868005295;5185.637005409632;5272.472752724716;5326.387465441466;5327.627066105355;5336.786528538873;3904.707146963975;4031.093004074089;5260.447652996438;5288.471152884719;5599.41206173352;5667.688277144776;5686.744096515667;5719.227904232917;5732;5736.053551164053;5688.943110568904;6284.088807122965;6523.5107366947195;6535.934640653581;6537.967310163466;6146.6004709693725;6205;6249.875002499924;6252.468540174091;6269.185005949243;22856.885715571483;22931.656025159635;23075.76924230753;23309.88345058281;23408.297751067494;2206.735191776981;2247.6844458639453;2280.1107568048474;2288.6338185890345;2416.818738594595;13880.4447822087;14071.788923166161;14264.35810388535;14270.05035846293;14526.273686315642;1778.6264884374298;1796.3263776083968;1815.1105958080577;1918.3094086129122;1959;64598.67700661514;71980.28019719788;72027.2797272031;72329.63835180843;74040.62979685067;15885.523434296989;15905.204739762965;15917.283722232527;15932.601684957737;15963;24535.509289814105;24769.5046099077;24880.751192488206;24898.253052408458;24961;8517.148285171557;8637.956810215908;8646.52444115567;8840.292776577988;8956.328275379372;7681.116671582728;7687.192844751333;7689.961550192325;7711.614419279013;7746.0317460317465;7934.857588451086;7956;7980.3615710743;8040.35677145827;8089.110197878229;1823.5076529337005;1865.4123950955407;1913.6364090822979;2263.4907145892043;3069.6009518762867;427.31629392970996;437.2370213976631;446.2547545598793;744.6760659113303;1000.5997600959529;10113.29206955506;10497.632582859564;10600.0989915858;10602.310849794738;11025;462.4889497105717;468.4051254906318;475.3535192138689;480.0950208856331;497.1176162311925;5901;5958.582899197101;6006.639601623917;6083.695815209222;6104.786332478431;483066;524905;527031;552809;554292;65985;72601.2739872605;73062.26937730661;73302.63348683223;74418.6279068612;480.7115730561635;481.8747125747331;485.5096352683815;486.9440014398389;488.2091012559676;10199.796004079728;14425.422983080978;14949.626259343604;15263;15304.464343747915;35377.11557210988;35591.28817423689;35724.821375892956;35881.282374352886;36307.092322692144;41117.383239250834;44515.554844451144;45327;45788.771056144506;49137.50862491472;9193.080691930754;9427.15155635996;9517.619295228113;9518.333716639769;9519.095685909828;11427.428628568705;16625.334986600883;16962.49112526626;17232.483025509257;17244.913775431043;11448.942755286504;11651.53393864236;11940.164188506722;14370.353334099782;14977.925110374814;13446.193228406328;13497.86502134966;13823.51617693376;14631;14775.261236938326;14872;14875.256237187881;15192.772108418603;15203.847961520243;15550.455734049254;9398.530073496435;9814.165795907427;9854.802903942025;9929.659495967951;9934.503274836085;10457.92017539507;10491.580336786443;14013.719725605635;14140.292985350898;14297.28513574338 05:50:55 INFO - PROCESS | 5121 | |3;mootools.html;1338.975683602044;1452.927353632318;1453.5639308207537;1601.7517284820856;1774.104077440892;6784.660766961651;7498.625068746568;7559.130699969505;7566.697332106723;7569.167391586921;7258.927410725892;7516.661750221239;7519.7368092116785;7526;7578.393728501706;1965.9705104423444;2003.5692326149874;2177.1617927098023;2179.4442417183595;2229.9331020069344;2224.443889027743;2225.121077174515;2226;2226.1318085946496;2228.4985878177363;2141.5930973115137;2227.3652009177385;2234.7988681018705;2236.630955892276;2237.944051398712;1921.625283069802;1947.3281717807404;2164.3290579920194;2219.9889000555054;2238.753737088919;2217.4345541886714;2221.4002219400672;2251.4483951432003;2252.3017864462067;2267.716535433071;7446.888296675555;7505.024346834931;7515.436342274296;7515.661795219174;7534.472586918918;4567.314902764579;4711.623070154379;4711.7879695413785;4718.669693121483;4719.4100737407825;2973.152651494314;2987.9551806722916;3027.6518200407013;3028.9091327260217;3126.515390114536;946.3848498475977;952.1383148250844;964.7925695975399;984.1880448629909;998.3859926145441;10512.57949682004;10517.894821051768;10521.78956420875;10528.157747380268;10530.789384212272;4182.163567286555;4238.639715624145;4316.589923957219;4322.848700295537;4332.436783218224;8898.288136949028;8914.108589141035;8925.821483570293;8930.106989301017;8939.642414303486;4691.483936766953;4705.6470764692785;4711.95288047122;4715.127701375257;4726.361941137971;4288.978555107267;4294.978525107417;4313.568643135724;4315.395844581761;4316.330968699856;3611.0972256935765;3646.052026473083;3654.2874139543064;3672.6694597486353;3679.1537946271974;1115.2137742891243;1131.4851742457163;1134.82977553366;1139.6979800352913;1144.1590431033185;959.7312752429191;1296.371259938929;1301.6810881334134;1301.7136230029378;1326.038621999043;6286.119943207955;7046.964765176142;7140.964295178491;7149.89275160873;7161.033260509765;614.3733391940285;622.6762083716442;622.9501639868799;629.8866204083309;633.5216911232083;5554.555635549146;5634.915476267859;5657.9434205658235;5667.943320566824;5670.518005969457;9001.234895033995;9016.23362014223;9155.359124861196;9156.359054866096;9211.907880921239;9091.090890910857;9170.082991700776;9191.77020574491;9227.90772092284;9230.169284764403;6978.79063628092;7095.716171353191;7121.715131394686;7130.2869713028285;7135.4648401370105;6940.236573976757;7006.159260888726;7046.260142684944;7052.188998265266;7057.576545407292;7872.803179920319;8002.239787220205;8042.034955805107;8047;8060.758177254692;8218.301444377055;8226.30076443509;8342.416030878023;8372.497650141011;8410;7133;7256.7097316106765;7262.31008054234;7264.055672762718;7265.891011634724;7501.249875012455;7522.548647081193;7527.3601743850195;7528.698852045857;7531.962340188483;6849.24658287598;6907.75822846218;6920.929305015496;7022.262662420577;7260.854782904418;5858.062709966385;6644.634545100066;6711.865762684816;6815.216250131299;6924.792256232321;5980.282366116094;6066.757329706762;6611.768588099393;6753.425958793558;6921.169459664872;6222.81331560054;6261.843453913689;6322.146510221245;6324.525660575383;6332.050192471166;6151.630902145886;6180.505559555135;6180.721867515883;6191.81424557264;6196.039613859859 05:50:55 INFO - PROCESS | 5121 | |4;prototype.html;335.32432149219323;344.936186805441;345.38004282313244;345.3903859687652;349.5229012398076;5914.260717410324;6023.15675805387;6068.514518838493;6145.9999999999945;6152.26172859257;5832.212651292074;5970.73131709073;5995.310539287983;6037.849053773658;6063.363346848583;4641.071785642868;4644.187267228222;4645.558671926162;4651.558101980307;4651.86044418668;4447.799849006802;4527.388802511652;4547.431571053618;4643.489216186217;4643.651726120537;4599.471060828014;4603.49361570227;4631.328457373679;4632.791524381411;4642.234031384834;4567.931481027771;4579.816807327719;4590.655700822452;4633.6061434778085;4645.140648979949;4059.6955228357697;4064.065264989069;4069.0844559974066;4086.1827634473225;4094.856680016215;5603.467670571289;5685.090385538294;5698.8290351289525;5709.571782116316;5767.307923049261;5398.163284690879;5398.757055932453;5399.62202645815;5400.21696853954;5403.297571315743;6055.212822333113;6329.651869147195;6342.39747224013;6351.587146835441;6364.172657554534;4393.450818647669;4409.735415875058;4468.486124095738;4490.483594386655;4511.6165125964335;6674.766383176614;6682.665866706645;6685.465162786917;6694.799156025327;6704.296048914843;4650.86047418578;4657.254839225708;4667.533246675305;4668.043051174501;4675.906481870344;4435.667324950641;4541.205289074399;4566.931496027562;4727.030958653468;4737.8341758038305;5263.105272103751;5267.920981185285;5320.228566857862;5324.148136298174;5325.09473389517;6403.423691867662;6425.710843012076;6429.260635027032;6432.581882177644;6445.484361251089;1594.3861613278739;1610.307567745857;1619.044763589488;1624.2041399714055;1626.0975158786987;1063.0698139128262;1069.256866477806;1071.089573862211;1072.7103682005816;1094.4691824465128;6018.668973206428;6037.78867739627;6037.879242415127;6039.396060394013;6066.48434883031;6623.7350505980185;6628.502862285348;6647.304937240972;6659.800205993828;6660.56706314088;5972.193753843176;6081.665508397081;6117.724702388403;6125.295591007002;6130.509559235251;3565.625609310985;3591.8742844000335;3612.2956023575152;3618.6743193112748;3621.0947263184203;6898.620575868375;6904.551204171713;6906.758263460855;6913.3777959983845;6921.446284297244;6623.569467984566;6747.831304217434;6752.797416077526;6754.729810807513;6759.493038022168;6740.29226931175;6758;6762.459003279726;6766.7969960901255;6911.7926462205205;6399.8080057598345;6557;6562.639054852029;6599.208095028627;6626.635535045619;6604.273529911802;6614.173228346457;6614.173228346457;6616.503762217757;6631.502637302126;6917.377436030782;6919.238883722887;6923.238443771282;6927.792166235021;6933.341332573397;4456.086502267092;4525.366448697251;4533.727976321299;4549.886252843706;4560.156371071363;6692.899606506004;6699.263081060982;6707.0945422369305;6709.429698475684;6711.4966377520905;6498.415142637185;6500.057491663777;6507.869842603216;6515.381038801306;6518.054882041983;6361.777337793155;6476.5466417350335;6490.253620833476;6490.286068532552;6491.837704057436;6361.268454127835;6363.936360636334;6370.6814659267775;6371.362863713777;6373.362663733589;6532.673366331569;6535;6540.869182616416;6541.574797638138;6545.247296560766;6139.631622102688;6311.116443697978;6353.491720662429;6354.6822658867795;6358.078078678658 05:50:55 INFO - PROCESS | 5121 | |5;yui.html;756.1833220122269;771.2172145272548;787.9803004924872;814.1492140712954;839.4417712221373;12500.56248031319;12679.61961141165;12702.17435866668;12754.936225318883;12820.80768788469;12809.807852882192;13093.934530327359;13305.401256943436;13340.132891362055;13396.933015334935;680.1837794646419;688.5868478912637;813.1746277528314;817.9018517777845;818.7748369198488;814.8981377327834;828.5318794880873;829.867221244601;833.2792134803382;838.0697425857323;851.6891334662865;855.700504823313;855.8117214212895;858.1847245117133;865.2861389353808;706.7208452661196;715.4491041897761;834.0408530190269;847.1359213602153;854.7435769269194;631.643121636274;633.6103296472683;636.6371168433994;636.7580319478615;637.111229834383;12988.675283117998;13076.08467407282;13085.88769954555;13090.86909130906;13097.672558186026;10889;11300;11639.709007274801;11920.642380728592;12230.205036672587;9836.852447213298;9977.950110249474;10030.749231269278;10062.195024398105;10237.539310731036;3611.855525778966;3619.7104231661406;3621.2395396966667;3629.67332940034;3743.8502459901842;45121.32318015232;45536;45785.54214457846;45858.54141458576;46915;9137.588808503633;9177.128172823572;9638.855417168748;9897.35667181631;9930.2055835533;17778.288868445117;17965.640687186184;17968.281268749364;17984;17999.290067443573;7258.927410725931;7271.454640901953;7346.11846578414;7485.737999170003;7488.700451981859;4127.277726397869;4149.294619914622;4151.647109995623;4204.831806727758;4205.201011807747;3061.663217046123;3073.9702199762883;3096.9845150774104;3114.5639610454555;3144.74842012643;563;568.6786965364556;573.4638113364018;577.4081566394395;594.7472324262171;620.6369273974685;644.5906849150823;652.9347065293433;654.738104758091;663.4990582110476;7786.688532458751;7849.803754906173;7909.960450197827;7949.562774047367;8162.102168761431;632.8133200705803;640.5324113397246;657.4674513643964;668.0492960555191;682.8668409660069;3534.681878630884;3689.7232707546505;3776.490173826553;3868.4777555030273;3886.3198940185353;51173.97652046938;52513.47486525162;55016.44983550193;55190.44809551853;55608.16587751187;45157.322640160426;46373.536264636925;46619;46852.297215541796;47077.764611176724;42421.15157696829;42596.78701606472;44356.556434435886;44386;44672.3299150513;8277.668893244203;8391.244787969112;8710.520921349387;8749.256313213447;8838.337124715801;33685.49471757877;33861.153471163416;33949.49075763816;34361.82819085888;34409.82795086008;39630.009249768984;40663.39004914988;42799.3580096305;43395.56604433915;43704.78147609369;9706.563204655666;9789.31474796786;10270.743231419274;10315.381077135396;10706.089982351587;10289.74275643085;10305.175585953255;10310.793784124426;10441.269111162146;10451.895481045092;9473.194778443909;9719.805603887742;9735;9947.253955953478;10021.09810117093;9820.705378838646;10150.03574660406;10319.845202321821;10344.224183186441;10361.430121343097;10565.788684226118;10669.89330106689;10757.46212689378;10758.569657213937;11023.253031269891;8315.501069935823;8593.140685931357;8683.913160868564;8744.125587441205;8956.955215223883;8023.4784738991775;8088.433809633269;8115.594220288844;9516.429014259167;9576.569054392708 05:50:55 INFO - PROCESS | 5121 | __end_tp_report 05:50:55 INFO - PROCESS | 5121 | __start_cc_report 05:50:55 INFO - PROCESS | 5121 | _x_x_mozilla_cycle_collect,871 05:50:55 INFO - PROCESS | 5121 | __end_cc_report 05:50:55 INFO - PROCESS | 5121 | __startTimestamp1451224255534__endTimestamp 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | ------- Summary: start ------- 05:50:55 INFO - PROCESS | 5121 | Number of tests: 6 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#0] dojo.html Cycles:165 Average:5196.58 Median:5389.97 stddev:560.15 (10.4%) stddev-sans-first:536.87 05:50:55 INFO - PROCESS | 5121 | Values: 3087.3 3157.2 3168.3 3178.0 3202.6 5020.4 5150.8 5233.4 5263.4 5307.3 5183.9 5222.7 5224.3 5224.7 5324.7 4695.3 4708.3 4711.4 4776.3 4799.2 4905.0 4912.3 4934.0 4938.6 4991.1 4607.5 4705.0 4716.5 4805.2 4810.8 4796.1 4801.1 4805.7 4821.0 4839.3 4768.4 4783.0 4798.0 4803.0 4814.2 4960.3 5024.3 5055.7 5068.6 5077.2 5502.0 5554.2 5573.4 5606.4 5636.7 5535.2 5550.9 5552.1 5563.8 5618.0 5162.4 5164.1 5166.1 5168.7 5192.7 5579.1 5617.7 5629.4 5637.8 5689.1 5558.7 5559.3 5559.4 5563.7 5592.5 5572.9 5642.5 5642.9 5649.2 5654.1 5345.7 5498.8 5525.4 5581.3 5653.0 5552.9 5553.6 5556.4 5557.3 5569.7 4329.8 4337.4 4346.8 4355.2 4370.9 3817.6 3840.5 3895.8 3908.8 3932.4 5248.8 5256.9 5267.1 5269.2 5308.9 5426.8 5631.5 5633.1 5637.0 5652.5 5126.6 5267.7 5287.9 5334.7 5369.8 4803.8 4807.0 4809.2 4811.0 4836.7 5684.8 5725.7 5738.1 5738.9 5788.6 5432.5 5540.5 5608.0 5637.6 5659.8 5755.2 5774.2 5778.1 5782.9 5784.8 5390.0 5492.1 5496.3 5536.3 5536.6 5598.2 5656.6 5663.4 5670.9 5710.8 5488.1 5523.8 5524.8 5526.4 5527.5 5313.7 5406.8 5423.7 5504.3 5530.4 5436.3 5444.9 5446.3 5453.4 5490.2 5481.8 5516.4 5541.4 5546.4 5598.9 5163.4 5213.6 5248.1 5333.6 5378.8 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#1] ext.html Cycles:180 Average:41855.33 Median:175285.34 stddev:66903.83 (38.2%) stddev-sans-first:67019.63 05:50:55 INFO - PROCESS | 5121 | Values: 550.8 578.4 588.7 589.2 610.5 30421.0 31410.5 31447.1 38305.4 39208.2 42488.0 43101.4 43129.1 43207.1 43221.4 8556.7 8729.9 8731.6 8792.4 8798.8 7568.3 7745.7 10140.5 10266.9 10361.7 10024.0 10112.9 10113.6 10119.2 10120.6 7590.4 7747.8 10056.9 10154.2 10165.6 7432.1 10210.7 10371.6 10388.6 10394.8 35811.0 36309.3 36310.5 36319.3 36346.6 3194.5 3204.2 3213.9 3347.7 3574.0 27423.3 27481.7 27609.2 27660.2 29163.3 3915.6 3934.2 3962.7 4001.3 4009.0 186956.0 188062.0 188285.0 188498.0 188931.0 54154.2 55081.0 55448.7 55484.4 55527.0 50298.5 60776.7 61627.7 61653.0 61667.4 13838.4 14056.7 14061.6 14061.9 14080.5 10500.8 10550.5 10553.3 10553.8 10567.8 18428.9 18612.7 18632.2 18652.3 19217.6 7179.7 7380.0 7388.1 7477.9 7523.1 946.1 978.6 999.1 1094.6 1121.8 24647.1 26590.2 26754.2 26761.6 26899.5 1014.1 1062.3 1074.5 1089.5 1138.8 13235.8 13312.3 13428.9 13471.6 13496.9 316209.0 318890.0 320249.0 321270.0 322236.0 50259.2 50546.2 50547.5 50573.0 50606.5 1304.2 1323.6 1335.9 1336.7 1336.7 24127.3 25063.4 26083.3 28194.3 29782.7 45185.0 45536.8 45547.5 45553.0 45608.8 241086.0 242648.0 242736.0 243171.0 243784.0 27778.6 28196.3 30908.5 31348.0 31434.8 35446.0 38073.0 38828.6 39495.0 39507.8 35113.3 36895.4 38861.6 39370.2 40310.4 33673.6 33865.3 33906.7 34222.7 34330.3 33466.0 33831.2 33904.8 34344.7 34453.8 29539.0 31149.8 31666.3 31672.7 31680.7 23806.0 23809.6 23818.8 23918.4 23970.2 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#2] jquery.html Cycles:180 Average:28998.22 Median:2135.38 stddev:86397.17 (4046.0%) stddev-sans-first:86612.78 05:50:55 INFO - PROCESS | 5121 | Values: 358.1 359.9 363.1 364.3 366.1 14014.2 14248.2 15695.7 15950.9 16591.1 15544.8 17001.4 17081.7 17093.6 17503.3 5185.6 5272.5 5326.4 5327.6 5336.8 3904.7 4031.1 5260.4 5288.5 5599.4 5667.7 5686.7 5719.2 5732.0 5736.1 5688.9 6284.1 6523.5 6535.9 6538.0 6146.6 6205.0 6249.9 6252.5 6269.2 22856.9 22931.7 23075.8 23309.9 23408.3 2206.7 2247.7 2280.1 2288.6 2416.8 13880.4 14071.8 14264.4 14270.1 14526.3 1778.6 1796.3 1815.1 1918.3 1959.0 64598.7 71980.3 72027.3 72329.6 74040.6 15885.5 15905.2 15917.3 15932.6 15963.0 24535.5 24769.5 24880.8 24898.3 24961.0 8517.1 8638.0 8646.5 8840.3 8956.3 7681.1 7687.2 7690.0 7711.6 7746.0 7934.9 7956.0 7980.4 8040.4 8089.1 1823.5 1865.4 1913.6 2263.5 3069.6 427.3 437.2 446.3 744.7 1000.6 10113.3 10497.6 10600.1 10602.3 11025.0 462.5 468.4 475.4 480.1 497.1 5901.0 5958.6 6006.6 6083.7 6104.8 483066.0 524905.0 527031.0 552809.0 554292.0 65985.0 72601.3 73062.3 73302.6 74418.6 480.7 481.9 485.5 486.9 488.2 10199.8 14425.4 14949.6 15263.0 15304.5 35377.1 35591.3 35724.8 35881.3 36307.1 41117.4 44515.6 45327.0 45788.8 49137.5 9193.1 9427.2 9517.6 9518.3 9519.1 11427.4 16625.3 16962.5 17232.5 17244.9 11448.9 11651.5 11940.2 14370.4 14977.9 13446.2 13497.9 13823.5 14631.0 14775.3 14872.0 14875.3 15192.8 15203.8 15550.5 9398.5 9814.2 9854.8 9929.7 9934.5 10457.9 10491.6 14013.7 14140.3 14297.3 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#3] mootools.html Cycles:180 Average:5318.45 Median:6242.33 stddev:2768.88 (44.4%) stddev-sans-first:2760.49 05:50:55 INFO - PROCESS | 5121 | Values: 1339.0 1452.9 1453.6 1601.8 1774.1 6784.7 7498.6 7559.1 7566.7 7569.2 7258.9 7516.7 7519.7 7526.0 7578.4 1966.0 2003.6 2177.2 2179.4 2229.9 2224.4 2225.1 2226.0 2226.1 2228.5 2141.6 2227.4 2234.8 2236.6 2237.9 1921.6 1947.3 2164.3 2220.0 2238.8 2217.4 2221.4 2251.4 2252.3 2267.7 7446.9 7505.0 7515.4 7515.7 7534.5 4567.3 4711.6 4711.8 4718.7 4719.4 2973.2 2988.0 3027.7 3028.9 3126.5 946.4 952.1 964.8 984.2 998.4 10512.6 10517.9 10521.8 10528.2 10530.8 4182.2 4238.6 4316.6 4322.8 4332.4 8898.3 8914.1 8925.8 8930.1 8939.6 4691.5 4705.6 4712.0 4715.1 4726.4 4289.0 4295.0 4313.6 4315.4 4316.3 3611.1 3646.1 3654.3 3672.7 3679.2 1115.2 1131.5 1134.8 1139.7 1144.2 959.7 1296.4 1301.7 1301.7 1326.0 6286.1 7047.0 7141.0 7149.9 7161.0 614.4 622.7 623.0 629.9 633.5 5554.6 5634.9 5657.9 5667.9 5670.5 9001.2 9016.2 9155.4 9156.4 9211.9 9091.1 9170.1 9191.8 9227.9 9230.2 6978.8 7095.7 7121.7 7130.3 7135.5 6940.2 7006.2 7046.3 7052.2 7057.6 7872.8 8002.2 8042.0 8047.0 8060.8 8218.3 8226.3 8342.4 8372.5 8410.0 7133.0 7256.7 7262.3 7264.1 7265.9 7501.2 7522.5 7527.4 7528.7 7532.0 6849.2 6907.8 6920.9 7022.3 7260.9 5858.1 6644.6 6711.9 6815.2 6924.8 5980.3 6066.8 6611.8 6753.4 6921.2 6222.8 6261.8 6322.1 6324.5 6332.1 6151.6 6180.5 6180.7 6191.8 6196.0 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#4] prototype.html Cycles:180 Average:5372.09 Median:6047.30 stddev:1620.32 (26.8%) stddev-sans-first:1580.15 05:50:55 INFO - PROCESS | 5121 | Values: 335.3 344.9 345.4 345.4 349.5 5914.3 6023.2 6068.5 6146.0 6152.3 5832.2 5970.7 5995.3 6037.8 6063.4 4641.1 4644.2 4645.6 4651.6 4651.9 4447.8 4527.4 4547.4 4643.5 4643.7 4599.5 4603.5 4631.3 4632.8 4642.2 4567.9 4579.8 4590.7 4633.6 4645.1 4059.7 4064.1 4069.1 4086.2 4094.9 5603.5 5685.1 5698.8 5709.6 5767.3 5398.2 5398.8 5399.6 5400.2 5403.3 6055.2 6329.7 6342.4 6351.6 6364.2 4393.5 4409.7 4468.5 4490.5 4511.6 6674.8 6682.7 6685.5 6694.8 6704.3 4650.9 4657.3 4667.5 4668.0 4675.9 4435.7 4541.2 4566.9 4727.0 4737.8 5263.1 5267.9 5320.2 5324.1 5325.1 6403.4 6425.7 6429.3 6432.6 6445.5 1594.4 1610.3 1619.0 1624.2 1626.1 1063.1 1069.3 1071.1 1072.7 1094.5 6018.7 6037.8 6037.9 6039.4 6066.5 6623.7 6628.5 6647.3 6659.8 6660.6 5972.2 6081.7 6117.7 6125.3 6130.5 3565.6 3591.9 3612.3 3618.7 3621.1 6898.6 6904.6 6906.8 6913.4 6921.4 6623.6 6747.8 6752.8 6754.7 6759.5 6740.3 6758.0 6762.5 6766.8 6911.8 6399.8 6557.0 6562.6 6599.2 6626.6 6604.3 6614.2 6614.2 6616.5 6631.5 6917.4 6919.2 6923.2 6927.8 6933.3 4456.1 4525.4 4533.7 4549.9 4560.2 6692.9 6699.3 6707.1 6709.4 6711.5 6498.4 6500.1 6507.9 6515.4 6518.1 6361.8 6476.5 6490.3 6490.3 6491.8 6361.3 6363.9 6370.7 6371.4 6373.4 6532.7 6535.0 6540.9 6541.6 6545.2 6139.6 6311.1 6353.5 6354.7 6358.1 05:50:55 INFO - PROCESS | 5121 | 05:50:55 INFO - PROCESS | 5121 | [#5] yui.html Cycles:180 Average:12991.35 Median:49125.87 stddev:14957.75 (30.4%) stddev-sans-first:14971.49 05:50:55 INFO - PROCESS | 5121 | Values: 756.2 771.2 788.0 814.1 839.4 12500.6 12679.6 12702.2 12754.9 12820.8 12809.8 13093.9 13305.4 13340.1 13396.9 680.2 688.6 813.2 817.9 818.8 814.9 828.5 829.9 833.3 838.1 851.7 855.7 855.8 858.2 865.3 706.7 715.4 834.0 847.1 854.7 631.6 633.6 636.6 636.8 637.1 12988.7 13076.1 13085.9 13090.9 13097.7 10889.0 11300.0 11639.7 11920.6 12230.2 9836.9 9978.0 10030.7 10062.2 10237.5 3611.9 3619.7 3621.2 3629.7 3743.9 45121.3 45536.0 45785.5 45858.5 46915.0 9137.6 9177.1 9638.9 9897.4 9930.2 17778.3 17965.6 17968.3 17984.0 17999.3 7258.9 7271.5 7346.1 7485.7 7488.7 4127.3 4149.3 4151.6 4204.8 4205.2 3061.7 3074.0 3097.0 3114.6 3144.7 563.0 568.7 573.5 577.4 594.7 620.6 644.6 652.9 654.7 663.5 7786.7 7849.8 7910.0 7949.6 8162.1 632.8 640.5 657.5 668.0 682.9 3534.7 3689.7 3776.5 3868.5 3886.3 51174.0 52513.5 55016.4 55190.4 55608.2 45157.3 46373.5 46619.0 46852.3 47077.8 42421.2 42596.8 44356.6 44386.0 44672.3 8277.7 8391.2 8710.5 8749.3 8838.3 33685.5 33861.2 33949.5 34361.8 34409.8 39630.0 40663.4 42799.4 43395.6 43704.8 9706.6 9789.3 10270.7 10315.4 10706.1 10289.7 10305.2 10310.8 10441.3 10451.9 9473.2 9719.8 9735.0 9947.3 10021.1 9820.7 10150.0 10319.8 10344.2 10361.4 10565.8 10669.9 10757.5 10758.6 11023.3 8315.5 8593.1 8683.9 8744.1 8957.0 8023.5 8088.4 8115.6 9516.4 9576.6 05:50:55 INFO - PROCESS | 5121 | -------- Summary: end -------- 05:50:55 INFO - PROCESS | 5121 | 05:50:56 INFO - TEST-INFO | 5121: exit 0 05:50:56 INFO - TEST-OK | dromaeo_css | took 1113425ms 05:50:56 INFO - TEST-START | kraken 05:50:56 INFO - operating with platform_type : linux_ 05:50:56 INFO - Initialising browser for kraken test... 05:50:56 INFO - TEST-INFO | started process 5313 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6nEiGP/profile http://localhost:33546/getInfo.html) 05:51:00 INFO - PROCESS | 5313 | __metrics Screen width/height:1600/1200 05:51:00 INFO - PROCESS | 5313 | colorDepth:24 05:51:00 INFO - PROCESS | 5313 | Browser inner width/height: 1024/697 05:51:00 INFO - PROCESS | 5313 | __metrics 05:51:00 INFO - PROCESS | 5313 | 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:00 INFO - PROCESS | 5313 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:00 INFO - TEST-INFO | 5313: exit 0 05:51:00 INFO - Browser initialized. 05:51:00 INFO - Running cycle 1/1 for kraken test... 05:51:00 INFO - TEST-INFO | started process 5395 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6nEiGP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:12 INFO - PROCESS | 5395 | RSS: Main: 148422656 05:51:12 INFO - PROCESS | 5395 | 05:51:15 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33546/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:15 INFO - PROCESS | 5395 | RSS: Main: 184516608 05:51:15 INFO - PROCESS | 5395 | 05:51:18 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33546/tests/kraken/driver.html?testName=audio-dft) 05:51:18 INFO - PROCESS | 5395 | RSS: Main: 174026752 05:51:18 INFO - PROCESS | 5395 | 05:51:21 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33546/tests/kraken/driver.html?testName=audio-fft) 05:51:22 INFO - PROCESS | 5395 | RSS: Main: 160280576 05:51:22 INFO - PROCESS | 5395 | 05:51:24 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33546/tests/kraken/driver.html?testName=audio-oscillator) 05:51:24 INFO - PROCESS | 5395 | RSS: Main: 161574912 05:51:24 INFO - PROCESS | 5395 | 05:51:27 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:27 INFO - PROCESS | 5395 | RSS: Main: 165175296 05:51:27 INFO - PROCESS | 5395 | 05:51:30 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33546/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:31 INFO - PROCESS | 5395 | RSS: Main: 164605952 05:51:31 INFO - PROCESS | 5395 | 05:51:34 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33546/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:35 INFO - PROCESS | 5395 | RSS: Main: 166383616 05:51:35 INFO - PROCESS | 5395 | 05:51:37 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33546/tests/kraken/driver.html?testName=json-parse-financial) 05:51:38 INFO - PROCESS | 5395 | RSS: Main: 165036032 05:51:38 INFO - PROCESS | 5395 | 05:51:39 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:40 INFO - PROCESS | 5395 | RSS: Main: 177668096 05:51:40 INFO - PROCESS | 5395 | 05:51:42 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:42 INFO - PROCESS | 5395 | RSS: Main: 176889856 05:51:42 INFO - PROCESS | 5395 | 05:51:44 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:45 INFO - PROCESS | 5395 | RSS: Main: 187293696 05:51:45 INFO - PROCESS | 5395 | 05:51:47 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:48 INFO - PROCESS | 5395 | RSS: Main: 192626688 05:51:48 INFO - PROCESS | 5395 | 05:51:51 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:51 INFO - PROCESS | 5395 | RSS: Main: 189116416 05:51:51 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | Cycle 1(1): loaded http://localhost:33546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33546/tests/kraken/driver.html?testName=ai-astar) 05:51:53 INFO - PROCESS | 5395 | RSS: Main: 248397824 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | __start_tp_report 05:51:53 INFO - PROCESS | 5395 | _x_x_mozilla_page_load 05:51:53 INFO - PROCESS | 5395 | _x_x_mozilla_page_load_details 05:51:53 INFO - PROCESS | 5395 | |i|pagename|runs| 05:51:53 INFO - PROCESS | 5395 | |0;ai-astar;109;111;105;113;112;112;100;111;110;101 05:51:53 INFO - PROCESS | 5395 | |1;audio-beat-detection;147;129;152;163;125;154;152;131;159;126 05:51:53 INFO - PROCESS | 5395 | |2;audio-dft;162;163;163;161;164;165;163;161;161;162 05:51:53 INFO - PROCESS | 5395 | |3;audio-fft;83;71;82;90;71;71;71;98;89;73 05:51:53 INFO - PROCESS | 5395 | |4;audio-oscillator;97;96;150;94;99;95;141;94;96;134 05:51:53 INFO - PROCESS | 5395 | |5;imaging-gaussian-blur;109;108;110;109;109;109;109;110;110;110 05:51:53 INFO - PROCESS | 5395 | |6;imaging-darkroom;145;144;144;145;145;145;145;145;144;144 05:51:53 INFO - PROCESS | 5395 | |7;imaging-desaturate;95;95;95;96;95;95;96;96;95;95 05:51:53 INFO - PROCESS | 5395 | |8;json-parse-financial;58;59;58;59;56;57;56;66;56;55 05:51:53 INFO - PROCESS | 5395 | |9;json-stringify-tinderbox;67;67;67;67;67;67;69;68;67;68 05:51:53 INFO - PROCESS | 5395 | |10;stanford-crypto-aes;86;85;86;86;84;85;85;86;86;85 05:51:53 INFO - PROCESS | 5395 | |11;stanford-crypto-ccm;116;123;123;115;125;114;120;120;115;126 05:51:53 INFO - PROCESS | 5395 | |12;stanford-crypto-pbkdf2;144;142;151;152;148;141;147;148;141;150 05:51:53 INFO - PROCESS | 5395 | |13;stanford-crypto-sha256-iterative;70;67;71;65;69;68;68;69;70;67 05:51:53 INFO - PROCESS | 5395 | __end_tp_report 05:51:53 INFO - PROCESS | 5395 | __start_cc_report 05:51:53 INFO - PROCESS | 5395 | _x_x_mozilla_cycle_collect,916 05:51:53 INFO - PROCESS | 5395 | __end_cc_report 05:51:53 INFO - PROCESS | 5395 | __startTimestamp1451224313551__endTimestamp 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | ------- Summary: start ------- 05:51:53 INFO - PROCESS | 5395 | Number of tests: 14 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#0] ai-astar Cycles:10 Average:108.40 Median:111.00 stddev:4.72 (4.3%) stddev-sans-first:5.00 05:51:53 INFO - PROCESS | 5395 | Values: 109.0 111.0 105.0 113.0 112.0 112.0 100.0 111.0 110.0 101.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:152.00 stddev:14.54 (9.6%) stddev-sans-first:15.37 05:51:53 INFO - PROCESS | 5395 | Values: 147.0 129.0 152.0 163.0 125.0 154.0 152.0 131.0 159.0 126.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 05:51:53 INFO - PROCESS | 5395 | Values: 162.0 163.0 163.0 161.0 164.0 165.0 163.0 161.0 161.0 162.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#3] audio-fft Cycles:10 Average:79.90 Median:82.50 stddev:9.95 (12.1%) stddev-sans-first:10.49 05:51:53 INFO - PROCESS | 5395 | Values: 83.0 71.0 82.0 90.0 71.0 71.0 71.0 98.0 89.0 73.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:95.50 stddev:22.50 (23.6%) stddev-sans-first:23.39 05:51:53 INFO - PROCESS | 5395 | Values: 97.0 96.0 150.0 94.0 99.0 95.0 141.0 94.0 96.0 134.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:51:53 INFO - PROCESS | 5395 | Values: 109.0 108.0 110.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#6] imaging-darkroom Cycles:10 Average:144.60 Median:145.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:51:53 INFO - PROCESS | 5395 | Values: 145.0 144.0 144.0 145.0 145.0 145.0 145.0 145.0 144.0 144.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:51:53 INFO - PROCESS | 5395 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:3.13 (5.4%) stddev-sans-first:3.32 05:51:53 INFO - PROCESS | 5395 | Values: 58.0 59.0 58.0 59.0 56.0 57.0 56.0 66.0 56.0 55.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 05:51:53 INFO - PROCESS | 5395 | Values: 67.0 67.0 67.0 67.0 67.0 67.0 69.0 68.0 67.0 68.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 05:51:53 INFO - PROCESS | 5395 | Values: 86.0 85.0 86.0 86.0 84.0 85.0 85.0 86.0 86.0 85.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#11] stanford-crypto-ccm Cycles:10 Average:119.70 Median:121.50 stddev:4.47 (3.7%) stddev-sans-first:4.54 05:51:53 INFO - PROCESS | 5395 | Values: 116.0 123.0 123.0 115.0 125.0 114.0 120.0 120.0 115.0 126.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.40 Median:148.00 stddev:4.14 (2.8%) stddev-sans-first:4.30 05:51:53 INFO - PROCESS | 5395 | Values: 144.0 142.0 151.0 152.0 148.0 141.0 147.0 148.0 141.0 150.0 05:51:53 INFO - PROCESS | 5395 | 05:51:53 INFO - PROCESS | 5395 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.78 (2.6%) stddev-sans-first:1.79 05:51:53 INFO - PROCESS | 5395 | Values: 70.0 67.0 71.0 65.0 69.0 68.0 68.0 69.0 70.0 67.0 05:51:53 INFO - PROCESS | 5395 | -------- Summary: end -------- 05:51:53 INFO - PROCESS | 5395 | 05:51:54 INFO - TEST-INFO | 5395: exit 0 05:51:54 INFO - TEST-OK | kraken | took 58209ms 05:51:54 INFO - TEST-START | v8_7 05:51:54 INFO - operating with platform_type : linux_ 05:51:54 INFO - Initialising browser for v8_7 test... 05:51:54 INFO - TEST-INFO | started process 5644 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu9rnIb/profile http://localhost:33546/getInfo.html) 05:51:57 INFO - PROCESS | 5644 | __metrics Screen width/height:1600/1200 05:51:57 INFO - PROCESS | 5644 | colorDepth:24 05:51:57 INFO - PROCESS | 5644 | Browser inner width/height: 1024/697 05:51:57 INFO - PROCESS | 5644 | __metrics 05:51:58 INFO - PROCESS | 5644 | 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:58 INFO - PROCESS | 5644 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:59 INFO - TEST-INFO | 5644: exit 0 05:51:59 INFO - Browser initialized. 05:51:59 INFO - Running cycle 1/1 for v8_7 test... 05:51:59 INFO - TEST-INFO | started process 5724 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpu9rnIb/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:10 INFO - PROCESS | 5724 | RSS: Main: 148303872 05:52:10 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | Cycle 1(1): loaded http://localhost:33546/tests/v8_7/run.html (next: http://localhost:33546/tests/v8_7/run.html) 05:52:32 INFO - PROCESS | 5724 | RSS: Main: 619307008 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | __start_tp_report 05:52:32 INFO - PROCESS | 5724 | _x_x_mozilla_page_load 05:52:32 INFO - PROCESS | 5724 | _x_x_mozilla_page_load_details 05:52:32 INFO - PROCESS | 5724 | |i|pagename|runs| 05:52:32 INFO - PROCESS | 5724 | |0;Richards;168.46361185983827 05:52:32 INFO - PROCESS | 5724 | |1;DeltaBlue;189.25056775170324 05:52:32 INFO - PROCESS | 5724 | |2;Encrypt;282.4060999717594 05:52:32 INFO - PROCESS | 5724 | |3;Decrypt;5159.79381443299 05:52:32 INFO - PROCESS | 5724 | |4;RayTrace;1107.4197120708748 05:52:32 INFO - PROCESS | 5724 | |5;Earley;791.7656373713381 05:52:32 INFO - PROCESS | 5724 | |6;Boyer;12365.853658536585 05:52:32 INFO - PROCESS | 5724 | |7;RegExp;25974.358974358973 05:52:32 INFO - PROCESS | 5724 | |8;Splay;701.6689847009736 05:52:32 INFO - PROCESS | 5724 | |9;NavierStokes;4830.917874396136 05:52:32 INFO - PROCESS | 5724 | __end_tp_report 05:52:32 INFO - PROCESS | 5724 | __startTimestamp1451224352551__endTimestamp 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | ------- Summary: start ------- 05:52:32 INFO - PROCESS | 5724 | Number of tests: 10 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#0] Richards Cycles:1 Average:168.46 Median:168.46 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 168.5 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#1] DeltaBlue Cycles:1 Average:189.25 Median:189.25 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 189.3 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#2] Encrypt Cycles:1 Average:282.41 Median:282.41 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 282.4 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#3] Decrypt Cycles:1 Average:5159.79 Median:5159.79 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 5159.8 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#4] RayTrace Cycles:1 Average:1107.42 Median:1107.42 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 1107.4 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#5] Earley Cycles:1 Average:791.77 Median:791.77 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 791.8 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#6] Boyer Cycles:1 Average:12365.85 Median:12365.85 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 12365.9 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#7] RegExp Cycles:1 Average:25974.36 Median:25974.36 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 25974.4 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#8] Splay Cycles:1 Average:701.67 Median:701.67 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 701.7 05:52:32 INFO - PROCESS | 5724 | 05:52:32 INFO - PROCESS | 5724 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:52:32 INFO - PROCESS | 5724 | Values: 4830.9 05:52:32 INFO - PROCESS | 5724 | -------- Summary: end -------- 05:52:32 INFO - PROCESS | 5724 | 05:52:33 INFO - TEST-INFO | 5724: exit 0 05:52:33 INFO - TEST-OK | v8_7 | took 38951ms 05:52:33 INFO - SUITE-END | took 1210s 05:52:33 INFO - Completed test suite (00:20:10) 05:52:33 INFO - Working with test: dromaeo_css 05:52:33 INFO - Generating results file: dromaeo_css 05:52:33 INFO - Working with test: kraken 05:52:33 INFO - Generating results file: kraken 05:52:33 INFO - javascript benchmark 05:52:33 INFO - Working with test: v8_7 05:52:33 INFO - Generating results file: v8_7 05:52:33 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:33 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 05:52:33 INFO - process_Request line: dromaeo_css 6833.40 graph.html#tests=[[72,1,35]] 05:52:33 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:33 INFO - process_Request line: kraken 1498.70 graph.html#tests=[[232,1,35]] 05:52:33 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:33 INFO - process_Request line: v8_7 20644.44 graph.html#tests=[[230,1,35]] 05:52:33 INFO - RETURN: dromaeo_css: 6833.40 05:52:33 INFO - RETURN: kraken: 1498.70 05:52:33 INFO - RETURN: v8_7: 20644.44 05:52:33 INFO - javascript benchmark 05:52:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3087.274490494734, 3157.242261857155, 3168.2554599669074, 3177.9841100794492, 3202.6156861176664, 5020.447750747415, 5150.768215430306, 5233.398159211692, 5263.421023687391, 5307.336582927134, 5183.896322073556, 5222.712750798704, 5224.346956630421, 5224.660397074187, 5324.733763311829, 4695.295705644146, 4708.317293992369, 4711.363965864599, 4776.283557466373, 4799.160146974283, 4904.950950490503, 4912.287718280847, 4933.950660493403, 4938.555530002299, 4991.126552853254, 4607.493175750665, 4704.976475117637, 4716.504766999467, 4805.231163013901, 4810.831620893252, 4796.088743138793, 4801.063792560417, 4805.711657300573, 4821.0, 4839.322494850724, 4768.427788665383, 4783.0, 4798.0, 4803.015381846748, 4814.157522433595, 4960.2559616057515, 5024.32171656829, 5055.721935293557, 5068.645194836363, 5077.238414237894, 5501.972490137564, 5554.166874968747, 5573.386927437979, 5606.439356064402, 5636.718164091779, 5535.197396377543, 5550.944490555084, 5552.139418390156, 5563.777448902039, 5618.01684705179, 5162.406323272797, 5164.14791559396, 5166.147585648364, 5168.715720635363, 5192.71440070796, 5579.13523403873, 5617.719114044281, 5629.380768115504, 5637.830865074055, 5689.118186681071, 5558.749856256478, 5559.33288005442, 5559.360673522516, 5563.721813909288, 5592.468715472024, 5572.86067848307, 5642.520385767256, 5642.927843709764, 5649.209110724501, 5654.123610840326, 5345.679259244458, 5498.80754173602, 5525.364583072998, 5581.274434323594, 5653.038983372837, 5552.91670624941, 5553.583481238923, 5556.444355564411, 5557.3331200256225, 5569.749361278752, 4329.76186309756, 4337.414449049401, 4346.760928148982, 4355.216061109031, 4370.912581748347, 3817.5991520889906, 3840.539135303781, 3895.8052097395016, 3908.785016824102, 3932.410138479194, 5248.798620463484, 5256.894862102737, 5267.051930652519, 5269.1569348903995, 5308.946910530845, 5426.8371948841595, 5631.493165615114, 5633.126865335879, 5637.041702910515, 5652.519535839499, 5126.615503837227, 5267.657602255841, 5287.920681189786, 5334.653247538975, 5369.758360873692, 4803.759812009385, 4806.9665022019735, 4809.182438985381, 4810.95189048112, 4836.685615435056, 5684.772609095672, 5725.713714314267, 5738.053221218453, 5738.856528586735, 5788.623739457006, 5432.456754324536, 5540.501354878081, 5608.0, 5637.633553818989, 5659.773609055592, 5755.194272801895, 5774.220480235283, 5778.104393818965, 5782.913256301074, 5784.797532086357, 5389.973050134724, 5492.148716948879, 5496.285482887199, 5536.335639723259, 5536.557075433875, 5598.188262701967, 5656.632318899258, 5663.405342438985, 5670.943290567042, 5710.771569137188, 5488.094464413327, 5523.751431185687, 5524.751386187711, 5526.447355264602, 5527.50252477279, 5313.707746073848, 5406.810761623325, 5423.728813559385, 5504.311961004874, 5530.391656917655, 5436.347638283273, 5444.8638784029135, 5446.291982042309, 5453.400125986218, 5490.231367608458, 5481.75332110048, 5516.393196748435, 5541.445855414427, 5546.36216835053, 5598.944010559842, 5163.380394352702, 5213.556847667991, 5248.1340578804065, 5333.599980001593, 5378.838634840961], "unit": "score", "name": "dojo.html", "value": 5161.320483666176}, {"lowerIsBetter": false, "replicates": [550.7500533213263, 578.4360248757462, 588.6762280745589, 589.1663296435544, 610.5481943361914, 30421.0, 31410.528842067386, 31447.05658830236, 38305.425418618746, 39208.215835683266, 42488.0, 43101.35347969783, 43129.13741725164, 43207.13585728283, 43221.35167972483, 8556.743297701063, 8729.869051964226, 8731.563421828914, 8792.384533082655, 8798.824023519526, 7568.318851303382, 7745.728899488519, 10140.49297535124, 10266.948665256663, 10361.689149325532, 10024.0, 10112.89887101127, 10113.646022389217, 10119.241056920724, 10120.645777397793, 7590.3548198403205, 7747.806304842367, 10056.94971525145, 10154.187664986783, 10165.59337626494, 7432.070991126109, 10210.744731381776, 10371.585136594527, 10388.584456621726, 10394.792104157874, 35811.0, 36309.273814523825, 36310.45534316987, 36319.273614527825, 36346.63653363459, 3194.520821876714, 3204.1829333520036, 3213.935721285561, 3347.7321814255047, 3573.962970130951, 27423.314417139733, 27481.725182748116, 27609.171724848286, 27660.170194894185, 29163.270918227216, 3915.647591716759, 3934.2328245992044, 3962.6631736302447, 4001.279769641463, 4009.0, 186956.0, 188062.0, 188285.0, 188497.99999999863, 188931.0, 54154.18768718473, 55081.0, 55448.72275638596, 55484.445155547925, 55527.0, 50298.49701502938, 60776.69611651913, 61627.69186154041, 61653.0, 61667.38332616706, 13838.446462141603, 14056.71886562263, 14061.648458788408, 14061.859381406055, 14080.507182248572, 10500.842487362695, 10550.525226364833, 10553.261271710906, 10553.84169237462, 10567.788644227227, 18428.907855460904, 18612.720809187875, 18632.16155273016, 18652.253909843454, 19217.61564768697, 7179.712811487587, 7379.963100184572, 7388.076490438695, 7477.925220747723, 7523.059617547807, 946.1153821177221, 978.6476868327396, 999.0608827701936, 1094.5676457799125, 1121.8373335866254, 24647.137350192657, 26590.202293930826, 26754.19737407881, 26761.598576021373, 26899.462010759675, 1014.0923873133504, 1062.3148069495287, 1074.511097450658, 1089.54239219528, 1138.823482360219, 13235.801462978063, 13312.26782526951, 13428.93285533566, 13471.595852124452, 13496.932515337361, 316209.0, 318890.0, 320249.0, 321270.0, 322236.0, 50259.24611130836, 50546.241806372935, 50547.494525055015, 50573.0, 50606.49393506018, 1304.1914013311618, 1323.6161513161076, 1335.9465621375036, 1336.7326534692907, 1336.739335829504, 24127.276181714577, 25063.373415664755, 26083.347916301485, 28194.2951426216, 29782.70217297799, 45185.0, 45536.77231613821, 45547.544524555655, 45553.0, 45608.77195614001, 241086.0, 242648.0, 242736.0, 243171.0, 243784.0, 27778.583321249793, 28196.295092622848, 30908.53637195399, 31348.0, 31434.842825785723, 35446.0, 38073.0, 38828.6117138825, 39495.0, 39507.802460987514, 35113.297734045686, 36895.41732078729, 38861.61138388693, 39370.212595747354, 40310.39534406928, 33673.550912794875, 33865.322693546485, 33906.66093339035, 34222.65777342294, 34330.31339373248, 33465.98408191033, 33831.15422114368, 33904.83047584845, 34344.65655343414, 34453.82773086119, 29539.0, 31149.844250778602, 31666.25006249081, 31672.683273167895, 31680.683193168694, 23806.0, 23809.642855356837, 23818.761812381654, 23918.402039949142, 23970.161044363365], "unit": "score", "name": "ext.html", "value": 17760.620629956386}, {"lowerIsBetter": false, "replicates": [358.14224931289544, 359.89563026722254, 363.10675737685295, 364.2950890027796, 366.0555766123402, 14014.229217393065, 14248.175182481751, 15695.686086278267, 15950.920245398771, 16591.087490188034, 15544.84455155448, 17001.404950826694, 17081.658366832657, 17093.572660683458, 17503.299868005295, 5185.637005409632, 5272.472752724716, 5326.387465441466, 5327.627066105355, 5336.786528538873, 3904.707146963975, 4031.093004074089, 5260.447652996438, 5288.471152884719, 5599.41206173352, 5667.688277144776, 5686.744096515667, 5719.227904232917, 5732.0, 5736.053551164053, 5688.943110568904, 6284.088807122965, 6523.5107366947195, 6535.934640653581, 6537.967310163466, 6146.6004709693725, 6205.0, 6249.875002499924, 6252.468540174091, 6269.185005949243, 22856.885715571483, 22931.656025159635, 23075.76924230753, 23309.88345058281, 23408.297751067494, 2206.735191776981, 2247.6844458639453, 2280.1107568048474, 2288.6338185890345, 2416.818738594595, 13880.4447822087, 14071.788923166161, 14264.35810388535, 14270.05035846293, 14526.273686315642, 1778.6264884374298, 1796.3263776083968, 1815.1105958080577, 1918.3094086129122, 1959.0, 64598.67700661514, 71980.28019719788, 72027.2797272031, 72329.63835180843, 74040.62979685067, 15885.523434296989, 15905.204739762965, 15917.283722232527, 15932.601684957737, 15963.0, 24535.509289814105, 24769.5046099077, 24880.751192488206, 24898.253052408458, 24961.0, 8517.148285171557, 8637.956810215908, 8646.52444115567, 8840.292776577988, 8956.328275379372, 7681.116671582728, 7687.192844751333, 7689.961550192325, 7711.614419279013, 7746.0317460317465, 7934.857588451086, 7956.0, 7980.3615710743, 8040.35677145827, 8089.110197878229, 1823.5076529337005, 1865.4123950955407, 1913.6364090822979, 2263.4907145892043, 3069.6009518762867, 427.31629392970996, 437.2370213976631, 446.2547545598793, 744.6760659113303, 1000.5997600959529, 10113.29206955506, 10497.632582859564, 10600.0989915858, 10602.310849794738, 11025.0, 462.4889497105717, 468.4051254906318, 475.3535192138689, 480.0950208856331, 497.1176162311925, 5901.0, 5958.582899197101, 6006.639601623917, 6083.695815209222, 6104.786332478431, 483066.0, 524905.0, 527031.0, 552809.0, 554292.0, 65985.0, 72601.2739872605, 73062.26937730661, 73302.63348683223, 74418.6279068612, 480.7115730561635, 481.8747125747331, 485.5096352683815, 486.9440014398389, 488.2091012559676, 10199.796004079728, 14425.422983080978, 14949.626259343604, 15263.0, 15304.464343747915, 35377.11557210988, 35591.28817423689, 35724.821375892956, 35881.282374352886, 36307.092322692144, 41117.383239250834, 44515.554844451144, 45327.0, 45788.771056144506, 49137.50862491472, 9193.080691930754, 9427.15155635996, 9517.619295228113, 9518.333716639769, 9519.095685909828, 11427.428628568705, 16625.334986600883, 16962.49112526626, 17232.483025509257, 17244.913775431043, 11448.942755286504, 11651.53393864236, 11940.164188506722, 14370.353334099782, 14977.925110374814, 13446.193228406328, 13497.86502134966, 13823.51617693376, 14631.0, 14775.261236938326, 14872.0, 14875.256237187881, 15192.772108418603, 15203.847961520243, 15550.455734049254, 9398.530073496435, 9814.165795907427, 9854.802903942025, 9929.659495967951, 9934.503274836085, 10457.92017539507, 10491.580336786443, 14013.719725605635, 14140.292985350898, 14297.28513574338], "unit": "score", "name": "jquery.html", "value": 8792.225983319262}, {"lowerIsBetter": false, "replicates": [1338.975683602044, 1452.927353632318, 1453.5639308207537, 1601.7517284820856, 1774.104077440892, 6784.660766961651, 7498.625068746568, 7559.130699969505, 7566.697332106723, 7569.167391586921, 7258.927410725892, 7516.661750221239, 7519.7368092116785, 7526.0, 7578.393728501706, 1965.9705104423444, 2003.5692326149874, 2177.1617927098023, 2179.4442417183595, 2229.9331020069344, 2224.443889027743, 2225.121077174515, 2226.0, 2226.1318085946496, 2228.4985878177363, 2141.5930973115137, 2227.3652009177385, 2234.7988681018705, 2236.630955892276, 2237.944051398712, 1921.625283069802, 1947.3281717807404, 2164.3290579920194, 2219.9889000555054, 2238.753737088919, 2217.4345541886714, 2221.4002219400672, 2251.4483951432003, 2252.3017864462067, 2267.716535433071, 7446.888296675555, 7505.024346834931, 7515.436342274296, 7515.661795219174, 7534.472586918918, 4567.314902764579, 4711.623070154379, 4711.7879695413785, 4718.669693121483, 4719.4100737407825, 2973.152651494314, 2987.9551806722916, 3027.6518200407013, 3028.9091327260217, 3126.515390114536, 946.3848498475977, 952.1383148250844, 964.7925695975399, 984.1880448629909, 998.3859926145441, 10512.57949682004, 10517.894821051768, 10521.78956420875, 10528.157747380268, 10530.789384212272, 4182.163567286555, 4238.639715624145, 4316.589923957219, 4322.848700295537, 4332.436783218224, 8898.288136949028, 8914.108589141035, 8925.821483570293, 8930.106989301017, 8939.642414303486, 4691.483936766953, 4705.6470764692785, 4711.95288047122, 4715.127701375257, 4726.361941137971, 4288.978555107267, 4294.978525107417, 4313.568643135724, 4315.395844581761, 4316.330968699856, 3611.0972256935765, 3646.052026473083, 3654.2874139543064, 3672.6694597486353, 3679.1537946271974, 1115.2137742891243, 1131.4851742457163, 1134.82977553366, 1139.6979800352913, 1144.1590431033185, 959.7312752429191, 1296.371259938929, 1301.6810881334134, 1301.7136230029378, 1326.038621999043, 6286.119943207955, 7046.964765176142, 7140.964295178491, 7149.89275160873, 7161.033260509765, 614.3733391940285, 622.6762083716442, 622.9501639868799, 629.8866204083309, 633.5216911232083, 5554.555635549146, 5634.915476267859, 5657.9434205658235, 5667.943320566824, 5670.518005969457, 9001.234895033995, 9016.23362014223, 9155.359124861196, 9156.359054866096, 9211.907880921239, 9091.090890910857, 9170.082991700776, 9191.77020574491, 9227.90772092284, 9230.169284764403, 6978.79063628092, 7095.716171353191, 7121.715131394686, 7130.2869713028285, 7135.4648401370105, 6940.236573976757, 7006.159260888726, 7046.260142684944, 7052.188998265266, 7057.576545407292, 7872.803179920319, 8002.239787220205, 8042.034955805107, 8047.0, 8060.758177254692, 8218.301444377055, 8226.30076443509, 8342.416030878023, 8372.497650141011, 8410.0, 7133.0, 7256.7097316106765, 7262.31008054234, 7264.055672762718, 7265.891011634724, 7501.249875012455, 7522.548647081193, 7527.3601743850195, 7528.698852045857, 7531.962340188483, 6849.24658287598, 6907.75822846218, 6920.929305015496, 7022.262662420577, 7260.854782904418, 5858.062709966385, 6644.634545100066, 6711.865762684816, 6815.216250131299, 6924.792256232321, 5980.282366116094, 6066.757329706762, 6611.768588099393, 6753.425958793558, 6921.169459664872, 6222.81331560054, 6261.843453913689, 6322.146510221245, 6324.525660575383, 6332.050192471166, 6151.630902145886, 6180.505559555135, 6180.721867515883, 6191.81424557264, 6196.039613859859], "unit": "score", "name": "mootools.html", "value": 4321.584059331861}, {"lowerIsBetter": false, "replicates": [335.32432149219323, 344.936186805441, 345.38004282313244, 345.3903859687652, 349.5229012398076, 5914.260717410324, 6023.15675805387, 6068.514518838493, 6145.9999999999945, 6152.26172859257, 5832.212651292074, 5970.73131709073, 5995.310539287983, 6037.849053773658, 6063.363346848583, 4641.071785642868, 4644.187267228222, 4645.558671926162, 4651.558101980307, 4651.86044418668, 4447.799849006802, 4527.388802511652, 4547.431571053618, 4643.489216186217, 4643.651726120537, 4599.471060828014, 4603.49361570227, 4631.328457373679, 4632.791524381411, 4642.234031384834, 4567.931481027771, 4579.816807327719, 4590.655700822452, 4633.6061434778085, 4645.140648979949, 4059.6955228357697, 4064.065264989069, 4069.0844559974066, 4086.1827634473225, 4094.856680016215, 5603.467670571289, 5685.090385538294, 5698.8290351289525, 5709.571782116316, 5767.307923049261, 5398.163284690879, 5398.757055932453, 5399.62202645815, 5400.21696853954, 5403.297571315743, 6055.212822333113, 6329.651869147195, 6342.39747224013, 6351.587146835441, 6364.172657554534, 4393.450818647669, 4409.735415875058, 4468.486124095738, 4490.483594386655, 4511.6165125964335, 6674.766383176614, 6682.665866706645, 6685.465162786917, 6694.799156025327, 6704.296048914843, 4650.86047418578, 4657.254839225708, 4667.533246675305, 4668.043051174501, 4675.906481870344, 4435.667324950641, 4541.205289074399, 4566.931496027562, 4727.030958653468, 4737.8341758038305, 5263.105272103751, 5267.920981185285, 5320.228566857862, 5324.148136298174, 5325.09473389517, 6403.423691867662, 6425.710843012076, 6429.260635027032, 6432.581882177644, 6445.484361251089, 1594.3861613278739, 1610.307567745857, 1619.044763589488, 1624.2041399714055, 1626.0975158786987, 1063.0698139128262, 1069.256866477806, 1071.089573862211, 1072.7103682005816, 1094.4691824465128, 6018.668973206428, 6037.78867739627, 6037.879242415127, 6039.396060394013, 6066.48434883031, 6623.7350505980185, 6628.502862285348, 6647.304937240972, 6659.800205993828, 6660.56706314088, 5972.193753843176, 6081.665508397081, 6117.724702388403, 6125.295591007002, 6130.509559235251, 3565.625609310985, 3591.8742844000335, 3612.2956023575152, 3618.6743193112748, 3621.0947263184203, 6898.620575868375, 6904.551204171713, 6906.758263460855, 6913.3777959983845, 6921.446284297244, 6623.569467984566, 6747.831304217434, 6752.797416077526, 6754.729810807513, 6759.493038022168, 6740.29226931175, 6758.0, 6762.459003279726, 6766.7969960901255, 6911.7926462205205, 6399.8080057598345, 6557.0, 6562.639054852029, 6599.208095028627, 6626.635535045619, 6604.273529911802, 6614.173228346457, 6614.173228346457, 6616.503762217757, 6631.502637302126, 6917.377436030782, 6919.238883722887, 6923.238443771282, 6927.792166235021, 6933.341332573397, 4456.086502267092, 4525.366448697251, 4533.727976321299, 4549.886252843706, 4560.156371071363, 6692.899606506004, 6699.263081060982, 6707.0945422369305, 6709.429698475684, 6711.4966377520905, 6498.415142637185, 6500.057491663777, 6507.869842603216, 6515.381038801306, 6518.054882041983, 6361.777337793155, 6476.5466417350335, 6490.253620833476, 6490.286068532552, 6491.837704057436, 6361.268454127835, 6363.936360636334, 6370.6814659267775, 6371.362863713777, 6373.362663733589, 6532.673366331569, 6535.0, 6540.869182616416, 6541.574797638138, 6545.247296560766, 6139.631622102688, 6311.116443697978, 6353.491720662429, 6354.6822658867795, 6358.078078678658], "unit": "score", "name": "prototype.html", "value": 4844.955965209705}, {"lowerIsBetter": false, "replicates": [756.1833220122269, 771.2172145272548, 787.9803004924872, 814.1492140712954, 839.4417712221373, 12500.56248031319, 12679.61961141165, 12702.17435866668, 12754.936225318883, 12820.80768788469, 12809.807852882192, 13093.934530327359, 13305.401256943436, 13340.132891362055, 13396.933015334935, 680.1837794646419, 688.5868478912637, 813.1746277528314, 817.9018517777845, 818.7748369198488, 814.8981377327834, 828.5318794880873, 829.867221244601, 833.2792134803382, 838.0697425857323, 851.6891334662865, 855.700504823313, 855.8117214212895, 858.1847245117133, 865.2861389353808, 706.7208452661196, 715.4491041897761, 834.0408530190269, 847.1359213602153, 854.7435769269194, 631.643121636274, 633.6103296472683, 636.6371168433994, 636.7580319478615, 637.111229834383, 12988.675283117998, 13076.08467407282, 13085.88769954555, 13090.86909130906, 13097.672558186026, 10889.0, 11300.0, 11639.709007274801, 11920.642380728592, 12230.205036672587, 9836.852447213298, 9977.950110249474, 10030.749231269278, 10062.195024398105, 10237.539310731036, 3611.855525778966, 3619.7104231661406, 3621.2395396966667, 3629.67332940034, 3743.8502459901842, 45121.32318015232, 45536.0, 45785.54214457846, 45858.54141458576, 46915.0, 9137.588808503633, 9177.128172823572, 9638.855417168748, 9897.35667181631, 9930.2055835533, 17778.288868445117, 17965.640687186184, 17968.281268749364, 17984.0, 17999.290067443573, 7258.927410725931, 7271.454640901953, 7346.11846578414, 7485.737999170003, 7488.700451981859, 4127.277726397869, 4149.294619914622, 4151.647109995623, 4204.831806727758, 4205.201011807747, 3061.663217046123, 3073.9702199762883, 3096.9845150774104, 3114.5639610454555, 3144.74842012643, 563.0, 568.6786965364556, 573.4638113364018, 577.4081566394395, 594.7472324262171, 620.6369273974685, 644.5906849150823, 652.9347065293433, 654.738104758091, 663.4990582110476, 7786.688532458751, 7849.803754906173, 7909.960450197827, 7949.562774047367, 8162.102168761431, 632.8133200705803, 640.5324113397246, 657.4674513643964, 668.0492960555191, 682.8668409660069, 3534.681878630884, 3689.7232707546505, 3776.490173826553, 3868.4777555030273, 3886.3198940185353, 51173.97652046938, 52513.47486525162, 55016.44983550193, 55190.44809551853, 55608.16587751187, 45157.322640160426, 46373.536264636925, 46619.0, 46852.297215541796, 47077.764611176724, 42421.15157696829, 42596.78701606472, 44356.556434435886, 44386.0, 44672.3299150513, 8277.668893244203, 8391.244787969112, 8710.520921349387, 8749.256313213447, 8838.337124715801, 33685.49471757877, 33861.153471163416, 33949.49075763816, 34361.82819085888, 34409.82795086008, 39630.009249768984, 40663.39004914988, 42799.3580096305, 43395.56604433915, 43704.78147609369, 9706.563204655666, 9789.31474796786, 10270.743231419274, 10315.381077135396, 10706.089982351587, 10289.74275643085, 10305.175585953255, 10310.793784124426, 10441.269111162146, 10451.895481045092, 9473.194778443909, 9719.805603887742, 9735.0, 9947.253955953478, 10021.09810117093, 9820.705378838646, 10150.03574660406, 10319.845202321821, 10344.224183186441, 10361.430121343097, 10565.788684226118, 10669.89330106689, 10757.46212689378, 10758.569657213937, 11023.253031269891, 8315.501069935823, 8593.140685931357, 8683.913160868564, 8744.125587441205, 8956.955215223883, 8023.4784738991775, 8088.433809633269, 8115.594220288844, 9516.429014259167, 9576.569054392708], "unit": "score", "name": "yui.html", "value": 6032.961508307264}], "name": "dromaeo_css", "value": 6833.397044516497}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 105.0, 113.0, 112.0, 112.0, 100.0, 111.0, 110.0, 101.0], "unit": "score", "name": "ai-astar", "value": 108.4}, {"lowerIsBetter": true, "replicates": [147.0, 129.0, 152.0, 163.0, 125.0, 154.0, 152.0, 131.0, 159.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 163.0, 161.0, 164.0, 165.0, 163.0, 161.0, 161.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.5}, {"lowerIsBetter": true, "replicates": [83.0, 71.0, 82.0, 90.0, 71.0, 71.0, 71.0, 98.0, 89.0, 73.0], "unit": "score", "name": "audio-fft", "value": 79.9}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 150.0, 94.0, 99.0, 95.0, 141.0, 94.0, 96.0, 134.0], "unit": "score", "name": "audio-oscillator", "value": 109.6}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 144.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 144.0], "unit": "score", "name": "imaging-darkroom", "value": 144.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 58.0, 59.0, 56.0, 57.0, 56.0, 66.0, 56.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 58.0}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 69.0, 68.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 86.0, 86.0, 84.0, 85.0, 85.0, 86.0, 86.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.4}, {"lowerIsBetter": true, "replicates": [116.0, 123.0, 123.0, 115.0, 125.0, 114.0, 120.0, 120.0, 115.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.7}, {"lowerIsBetter": true, "replicates": [144.0, 142.0, 151.0, 152.0, 148.0, 141.0, 147.0, 148.0, 141.0, 150.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.4}, {"lowerIsBetter": true, "replicates": [70.0, 67.0, 71.0, 65.0, 69.0, 68.0, 68.0, 69.0, 70.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1498.7000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.46361185983827], "unit": "score", "name": "Richards", "value": 209.55267200000006}, {"lowerIsBetter": false, "replicates": [189.25056775170324], "unit": "score", "name": "DeltaBlue", "value": 349.36751200000015}, {"lowerIsBetter": false, "replicates": [282.4060999717594], "unit": "score", "name": "Encrypt", "value": 942.5469209999999}, {"lowerIsBetter": false, "replicates": [5159.79381443299], "unit": "score", "name": "Decrypt", "value": 51.587526473526495}, {"lowerIsBetter": false, "replicates": [1107.4197120708748], "unit": "score", "name": "RayTrace", "value": 668.2100670000003}, {"lowerIsBetter": false, "replicates": [791.7656373713381], "unit": "score", "name": "Earley", "value": 841.7427690000002}, {"lowerIsBetter": false, "replicates": [12365.853658536585], "unit": "score", "name": "Boyer", "value": 53.89542998027611}, {"lowerIsBetter": false, "replicates": [25974.358974358973], "unit": "score", "name": "RegExp", "value": 35.072472852912156}, {"lowerIsBetter": false, "replicates": [701.6689847009736], "unit": "score", "name": "Splay", "value": 116.13880872150638}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20644.439098838466}]} 05:52:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20644.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1498.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6833.40"}}} 05:52:33 INFO - Return code: 0 05:52:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:52:34 INFO - # TBPL SUCCESS # 05:52:34 INFO - Running post-action listener: _resource_record_post_action 05:52:34 INFO - Running post-run listener: _resource_record_post_run 05:52:35 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 2768896; Write bytes: 309960704; Read time: 788; Write time: 214912 05:52:35 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 634880; Read time: 0; Write time: 60 05:52:35 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 2768896; Write bytes: 308461568; Read time: 788; Write time: 214800 05:52:35 INFO - Running post-run listener: _upload_blobber_files 05:52:35 INFO - Blob upload gear active. 05:52:35 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:52:35 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:35 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:35 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:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:52:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:36 INFO - (blobuploader) - INFO - Done attempting. 05:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:37 INFO - (blobuploader) - INFO - Done attempting. 05:52:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:37 INFO - Return code: 0 05:52:37 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:52:37 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f96aac5a21ca50bbe985e3f1bf82b1afab9a1238578d2c109257bd6e2b2244a9fc7a07d8686f1cf57a46804eb3230677f1cde33c265a6e8692506c54278ea30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4afa7071e07eff87b2a4192924d3d25c3a77f70c4b810658322af9dbfbdc4d7c9329fdc00229c00de120c45e955d202a0d9d55faaeb6a41979a543ed6e2619dc"} 05:52:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:52:37 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:52:37 INFO - Contents: 05:52:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f96aac5a21ca50bbe985e3f1bf82b1afab9a1238578d2c109257bd6e2b2244a9fc7a07d8686f1cf57a46804eb3230677f1cde33c265a6e8692506c54278ea30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4afa7071e07eff87b2a4192924d3d25c3a77f70c4b810658322af9dbfbdc4d7c9329fdc00229c00de120c45e955d202a0d9d55faaeb6a41979a543ed6e2619dc"} 05:52:37 INFO - Copying logs to upload dir... 05:52:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.333951 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-27 05:52:37.201416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:37.206426) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f96aac5a21ca50bbe985e3f1bf82b1afab9a1238578d2c109257bd6e2b2244a9fc7a07d8686f1cf57a46804eb3230677f1cde33c265a6e8692506c54278ea30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4afa7071e07eff87b2a4192924d3d25c3a77f70c4b810658322af9dbfbdc4d7c9329fdc00229c00de120c45e955d202a0d9d55faaeb6a41979a543ed6e2619dc"} 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.011419 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f96aac5a21ca50bbe985e3f1bf82b1afab9a1238578d2c109257bd6e2b2244a9fc7a07d8686f1cf57a46804eb3230677f1cde33c265a6e8692506c54278ea30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4afa7071e07eff87b2a4192924d3d25c3a77f70c4b810658322af9dbfbdc4d7c9329fdc00229c00de120c45e955d202a0d9d55faaeb6a41979a543ed6e2619dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:37.233764) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:37.234106) ========= 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=6d5287d839824eebc594b0c9000001d1-1451216573.487274-1638317971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-27 05:52:37.315714) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-27 05:52:37.319059) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-27 05:52:37.319361) ========= ========= Total master_lag: 0.32 =========