builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1469223378.89 results: success (0) buildid: 20160722113020 builduid: 2bbdc45cfc9e41a29f3a19d0fdcd2d02 revision: 48cee0fccdb3369df7af58c63329a25fb088832b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:18.886284) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:18.886718) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:18.886991) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:18.901375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:18.901686) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 14:36:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 7.86M=0.001s 2016-07-22 14:36:19 (7.86 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163845 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.078877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.079197) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022208 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.129248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.129590) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 48cee0fccdb3369df7af58c63329a25fb088832b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 48cee0fccdb3369df7af58c63329a25fb088832b --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 14:36:19,295 truncating revision to first 12 chars 2016-07-22 14:36:19,295 Setting DEBUG logging. 2016-07-22 14:36:19,295 attempt 1/10 2016-07-22 14:36:19,295 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/48cee0fccdb3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 14:36:19,668 unpacking tar archive at: fx-team-48cee0fccdb3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.850774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.851305) ========= script_repo_revision: 48cee0fccdb3369df7af58c63329a25fb088832b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.851935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.852389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 14:36:19.863977) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-22 14:36:19.864519) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:36:20 INFO - MultiFileLogger online at 20160722 14:36:20 in /builds/slave/test 14:36:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:36:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:36:20 INFO - {'append_to_log': False, 14:36:20 INFO - 'base_work_dir': '/builds/slave/test', 14:36:20 INFO - 'blob_upload_branch': 'Fx-Team', 14:36:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:36:20 INFO - 'branch': 'Fx-Team', 14:36:20 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:20 INFO - 'config_files': ('talos/linux_config.py',), 14:36:20 INFO - 'default_actions': ('clobber', 14:36:20 INFO - 'read-buildbot-config', 14:36:20 INFO - 'download-and-extract', 14:36:20 INFO - 'populate-webroot', 14:36:20 INFO - 'create-virtualenv', 14:36:20 INFO - 'install', 14:36:20 INFO - 'run-tests'), 14:36:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:20 INFO - 'download_minidump_stackwalk': True, 14:36:20 INFO - 'download_symbols': 'ondemand', 14:36:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:20 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:20 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:20 INFO - 'installer_path': 'installer.exe', 14:36:20 INFO - 'log_level': 'info', 14:36:20 INFO - 'log_name': 'talos', 14:36:20 INFO - 'log_to_console': True, 14:36:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:36:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:36:20 INFO - 'opt_config_files': (), 14:36:20 INFO - 'pip_index': False, 14:36:20 INFO - 'sps_profile': False, 14:36:20 INFO - 'sps_profile_interval': 0, 14:36:20 INFO - 'suite': 'dromaeojs', 14:36:20 INFO - 'system_bits': '32', 14:36:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:20 INFO - 'title': 'talos-linux64-ix-006', 14:36:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:20 INFO - 'use_talos_json': True, 14:36:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:36:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:20 INFO - 'work_dir': 'build'} 14:36:20 INFO - [mozharness: 2016-07-22 21:36:20.125560Z] Running clobber step. 14:36:20 INFO - Running pre-action listener: _resource_record_pre_action 14:36:20 INFO - Running main action method: clobber 14:36:20 INFO - rmtree: /builds/slave/test/build 14:36:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:36:20 INFO - Running post-action listener: _resource_record_post_action 14:36:20 INFO - [mozharness: 2016-07-22 21:36:20.593473Z] Finished clobber step (success) 14:36:20 INFO - [mozharness: 2016-07-22 21:36:20.593575Z] Running read-buildbot-config step. 14:36:20 INFO - Running pre-action listener: _resource_record_pre_action 14:36:20 INFO - Running main action method: read_buildbot_config 14:36:20 INFO - Using buildbot properties: 14:36:20 INFO - { 14:36:20 INFO - "project": "", 14:36:20 INFO - "product": "firefox", 14:36:20 INFO - "script_repo_revision": "production", 14:36:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:36:20 INFO - "repository": "", 14:36:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:36:20 INFO - "buildid": "20160722113020", 14:36:20 INFO - "pgo_build": "True", 14:36:20 INFO - "basedir": "/builds/slave/test", 14:36:20 INFO - "buildnumber": 312, 14:36:20 INFO - "slavename": "talos-linux64-ix-006", 14:36:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:36:20 INFO - "platform": "ubuntu64_hw", 14:36:20 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:36:20 INFO - "branch": "fx-team", 14:36:20 INFO - "repo_path": "integration/fx-team", 14:36:20 INFO - "revision": "48cee0fccdb3369df7af58c63329a25fb088832b", 14:36:20 INFO - "stage_platform": "linux64-pgo", 14:36:20 INFO - "builduid": "2bbdc45cfc9e41a29f3a19d0fdcd2d02", 14:36:20 INFO - "slavebuilddir": "test" 14:36:20 INFO - } 14:36:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 14:36:20 INFO - Running post-action listener: _resource_record_post_action 14:36:20 INFO - [mozharness: 2016-07-22 21:36:20.596021Z] Finished read-buildbot-config step (success) 14:36:20 INFO - [mozharness: 2016-07-22 21:36:20.596110Z] Running download-and-extract step. 14:36:20 INFO - Running pre-action listener: _resource_record_pre_action 14:36:20 INFO - Running main action method: download_and_extract 14:36:20 INFO - mkdir: /builds/slave/test/build/tests 14:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:20 INFO - https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:36:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:36:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:36:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:36:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:36:21 INFO - Downloaded 1361 bytes. 14:36:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:36:21 INFO - Using the following test package requirements: 14:36:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:36:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:36:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:36:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'jsshell-linux-x86_64.zip'], 14:36:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:36:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:36:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:36:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:36:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:36:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:36:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:36: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')]} 14:36:21 INFO - https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:36:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:36:22 INFO - Downloaded 24308968 bytes. 14:36:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:36:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:36:23 INFO - Return code: 0 14:36:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:36:23 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')]} 14:36:23 INFO - https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:36:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:36:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:36:24 INFO - Downloaded 24308968 bytes. 14:36:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:36:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:36:26 INFO - Return code: 0 14:36: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')]} 14:36:26 INFO - https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:36:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:36:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:36:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:36:26 INFO - Downloaded 14403289 bytes. 14:36:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:36:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:36:27 INFO - Return code: 0 14:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:27 INFO - https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:36:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:36:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:36:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:36:28 INFO - Downloaded 60860140 bytes. 14:36:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:36:28 INFO - mkdir: /builds/slave/test/properties 14:36:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:36:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:36:28 INFO - Contents: 14:36:28 INFO - build_url:https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:36:29 INFO - Running post-action listener: _resource_record_post_action 14:36:29 INFO - Running post-action listener: set_extra_try_arguments 14:36:29 INFO - [mozharness: 2016-07-22 21:36:29.203103Z] Finished download-and-extract step (success) 14:36:29 INFO - [mozharness: 2016-07-22 21:36:29.203419Z] Running populate-webroot step. 14:36:29 INFO - Running pre-action listener: _resource_record_pre_action 14:36:29 INFO - Running main action method: populate_webroot 14:36:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:36:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:36:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:36:29 INFO - '--tppagecycles', 14:36:29 INFO - '7'], 14:36:29 INFO - 'tests': ['tsvgm']}}, 14:36:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:36:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:36:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:36:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:36:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['damp', 'tps']}, 14:36:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'tests': ['damp', 'tps']}, 14:36:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['dromaeo_dom']}, 14:36:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:36:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['basic_compositor_video']}, 14:36:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:36:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['a11yr', 14:36:29 INFO - 'ts_paint', 14:36:29 INFO - 'tpaint', 14:36:29 INFO - 'sessionrestore', 14:36:29 INFO - 'sessionrestore_no_auto_restore', 14:36:29 INFO - 'tabpaint']}, 14:36:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['tsvgx', 14:36:29 INFO - 'tsvgr_opacity', 14:36:29 INFO - 'tart', 14:36:29 INFO - 'tscrollx', 14:36:29 INFO - 'cart']}, 14:36:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:36:29 INFO - 'tsvgr_opacity', 14:36:29 INFO - 'tart', 14:36:29 INFO - 'tscrollx', 14:36:29 INFO - 'cart']}, 14:36:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'talos_options': ['--disable-e10s'], 14:36:29 INFO - 'tests': ['tp5o']}, 14:36:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'tests': ['tp5o']}, 14:36:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'talos_options': ['--disable-e10s', 14:36:29 INFO - '--xperf_path', 14:36:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:29 INFO - 'tests': ['tp5n']}, 14:36:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:36:29 INFO - 'talos_options': ['--xperf_path', 14:36:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:29 INFO - 'tests': ['tp5n']}}, 14:36:29 INFO - 'talos.zip': {'path': '', 14:36:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:36:29 INFO - Running post-action listener: _resource_record_post_action 14:36:29 INFO - [mozharness: 2016-07-22 21:36:29.221684Z] Finished populate-webroot step (success) 14:36:29 INFO - [mozharness: 2016-07-22 21:36:29.221772Z] Running create-virtualenv step. 14:36:29 INFO - Running pre-action listener: _resource_record_pre_action 14:36:29 INFO - Running main action method: create_virtualenv 14:36:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:36:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:36:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:36:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:36:29 INFO - Using real prefix '/usr' 14:36:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:36:29 INFO - Installing distribute.............................................................................................................................................................................................done. 14:36:31 INFO - Installing pip.................done. 14:36:31 INFO - Return code: 0 14:36:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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/build 14:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 14:36:31 INFO - Using env: {'DISPLAY': ':0', 14:36:31 INFO - 'HOME': '/home/cltbld', 14:36:31 INFO - 'LANG': 'en_US.UTF-8', 14:36:31 INFO - 'LANGUAGE': 'en_US:en', 14:36:31 INFO - 'LOGNAME': 'cltbld', 14:36:31 INFO - 'MAIL': '/var/mail/cltbld', 14:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:31 INFO - 'MOZ_NO_REMOTE': '1', 14:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:31 INFO - 'NO_EM_RESTART': '1', 14:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:31 INFO - 'PWD': '/builds/slave/test', 14:36:31 INFO - 'SHELL': '/bin/bash', 14:36:31 INFO - 'SHLVL': '1', 14:36:31 INFO - 'TERM': 'linux', 14:36:31 INFO - 'TMOUT': '86400', 14:36:31 INFO - 'USER': 'cltbld', 14:36:31 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:31 INFO - '_': '/tools/buildbot/bin/python'} 14:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:32 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 14:36:32 INFO - Cleaning up... 14:36:32 INFO - Return code: 0 14:36:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 14:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 14:36:32 INFO - Using env: {'DISPLAY': ':0', 14:36:32 INFO - 'HOME': '/home/cltbld', 14:36:32 INFO - 'LANG': 'en_US.UTF-8', 14:36:32 INFO - 'LANGUAGE': 'en_US:en', 14:36:32 INFO - 'LOGNAME': 'cltbld', 14:36:32 INFO - 'MAIL': '/var/mail/cltbld', 14:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:32 INFO - 'MOZ_NO_REMOTE': '1', 14:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:32 INFO - 'NO_EM_RESTART': '1', 14:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:32 INFO - 'PWD': '/builds/slave/test', 14:36:32 INFO - 'SHELL': '/bin/bash', 14:36:32 INFO - 'SHLVL': '1', 14:36:32 INFO - 'TERM': 'linux', 14:36:32 INFO - 'TMOUT': '86400', 14:36:32 INFO - 'USER': 'cltbld', 14:36:32 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:32 INFO - '_': '/tools/buildbot/bin/python'} 14:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:32 INFO - Downloading/unpacking psutil>=3.1.1 14:36:32 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 14:36:32 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 14:36:32 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 14:36:32 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 14:36:32 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 14:36:32 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 14:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:36:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:34 INFO - Installing collected packages: psutil 14:36:34 INFO - Running setup.py install for psutil 14:36:34 INFO - building 'psutil._psutil_linux' extension 14:36:34 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 14:36:34 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 14:36:34 INFO - building 'psutil._psutil_posix' extension 14:36:34 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 14:36:34 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 14:36:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:34 INFO - Successfully installed psutil 14:36:34 INFO - Cleaning up... 14:36:34 INFO - Return code: 0 14:36:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:36:34 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')]} 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:34 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')]} 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 14:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 14:36:34 INFO - Using env: {'DISPLAY': ':0', 14:36:34 INFO - 'HOME': '/home/cltbld', 14:36:34 INFO - 'LANG': 'en_US.UTF-8', 14:36:34 INFO - 'LANGUAGE': 'en_US:en', 14:36:34 INFO - 'LOGNAME': 'cltbld', 14:36:34 INFO - 'MAIL': '/var/mail/cltbld', 14:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:34 INFO - 'MOZ_NO_REMOTE': '1', 14:36:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:34 INFO - 'NO_EM_RESTART': '1', 14:36:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:34 INFO - 'PWD': '/builds/slave/test', 14:36:34 INFO - 'SHELL': '/bin/bash', 14:36:34 INFO - 'SHLVL': '1', 14:36:34 INFO - 'TERM': 'linux', 14:36:34 INFO - 'TMOUT': '86400', 14:36:34 INFO - 'USER': 'cltbld', 14:36:34 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:34 INFO - '_': '/tools/buildbot/bin/python'} 14:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:36:35 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 14:36:35 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 14:36:35 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 14:36:35 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 14:36:35 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 14:36:35 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 14:36:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:36:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:36:37 INFO - Installing collected packages: mozsystemmonitor 14:36:37 INFO - Running setup.py install for mozsystemmonitor 14:36:37 INFO - Successfully installed mozsystemmonitor 14:36:37 INFO - Cleaning up... 14:36:37 INFO - Return code: 0 14:36:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1'] in /builds/slave/test/build 14:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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==2.5.1 14:36:37 INFO - Using env: {'DISPLAY': ':0', 14:36:37 INFO - 'HOME': '/home/cltbld', 14:36:37 INFO - 'LANG': 'en_US.UTF-8', 14:36:37 INFO - 'LANGUAGE': 'en_US:en', 14:36:37 INFO - 'LOGNAME': 'cltbld', 14:36:37 INFO - 'MAIL': '/var/mail/cltbld', 14:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:37 INFO - 'MOZ_NO_REMOTE': '1', 14:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:37 INFO - 'NO_EM_RESTART': '1', 14:36:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:37 INFO - 'PWD': '/builds/slave/test', 14:36:37 INFO - 'SHELL': '/bin/bash', 14:36:37 INFO - 'SHLVL': '1', 14:36:37 INFO - 'TERM': 'linux', 14:36:37 INFO - 'TMOUT': '86400', 14:36:37 INFO - 'USER': 'cltbld', 14:36:37 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:37 INFO - '_': '/tools/buildbot/bin/python'} 14:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:37 INFO - Downloading/unpacking jsonschema==2.5.1 14:36:37 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 14:36:37 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 14:36:37 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 14:36:37 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 14:36:37 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 14:36:37 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 14:36:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:36:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:39 INFO - Downloading functools32-3.2.3-2.tar.gz 14:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:36:39 INFO - warning: no files found matching '*.txt' 14:36:39 INFO - no previously-included directories found matching 'build' 14:36:39 INFO - no previously-included directories found matching 'dist' 14:36:39 INFO - no previously-included directories found matching '.git*' 14:36:39 INFO - Installing collected packages: jsonschema, functools32 14:36:39 INFO - Running setup.py install for functools32 14:36:39 INFO - warning: no files found matching '*.txt' 14:36:39 INFO - no previously-included directories found matching 'build' 14:36:39 INFO - no previously-included directories found matching 'dist' 14:36:39 INFO - no previously-included directories found matching '.git*' 14:36:39 INFO - Successfully installed jsonschema functools32 14:36:39 INFO - Cleaning up... 14:36:39 INFO - Return code: 0 14:36:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:36: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')]} 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36: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')]} 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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/build 14:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 14:36:39 INFO - Using env: {'DISPLAY': ':0', 14:36:39 INFO - 'HOME': '/home/cltbld', 14:36:39 INFO - 'LANG': 'en_US.UTF-8', 14:36:39 INFO - 'LANGUAGE': 'en_US:en', 14:36:39 INFO - 'LOGNAME': 'cltbld', 14:36:39 INFO - 'MAIL': '/var/mail/cltbld', 14:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:39 INFO - 'MOZ_NO_REMOTE': '1', 14:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:39 INFO - 'NO_EM_RESTART': '1', 14:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:39 INFO - 'PWD': '/builds/slave/test', 14:36:39 INFO - 'SHELL': '/bin/bash', 14:36:39 INFO - 'SHLVL': '1', 14:36:39 INFO - 'TERM': 'linux', 14:36:39 INFO - 'TMOUT': '86400', 14:36:39 INFO - 'USER': 'cltbld', 14:36:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:39 INFO - '_': '/tools/buildbot/bin/python'} 14:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:39 INFO - Downloading/unpacking blobuploader==1.2.4 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:41 INFO - Downloading blobuploader-1.2.4.tar.gz 14:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:36:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:36:41 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 14:36:41 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 14:36:41 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 14:36:41 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 14:36:41 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 14:36:41 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 14:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:36:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:42 INFO - Downloading docopt-0.6.1.tar.gz 14:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:36:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:36:42 INFO - Running setup.py install for blobuploader 14:36:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:36:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:36:42 INFO - Running setup.py install for requests 14:36:43 INFO - Running setup.py install for docopt 14:36:43 INFO - Successfully installed blobuploader requests docopt 14:36:43 INFO - Cleaning up... 14:36:43 INFO - Return code: 0 14:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:43 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')]} 14:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 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')]} 14:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 14:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 14:36:43 INFO - Using env: {'DISPLAY': ':0', 14:36:43 INFO - 'HOME': '/home/cltbld', 14:36:43 INFO - 'LANG': 'en_US.UTF-8', 14:36:43 INFO - 'LANGUAGE': 'en_US:en', 14:36:43 INFO - 'LOGNAME': 'cltbld', 14:36:43 INFO - 'MAIL': '/var/mail/cltbld', 14:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:43 INFO - 'MOZ_NO_REMOTE': '1', 14:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:43 INFO - 'NO_EM_RESTART': '1', 14:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:43 INFO - 'PWD': '/builds/slave/test', 14:36:43 INFO - 'SHELL': '/bin/bash', 14:36:43 INFO - 'SHLVL': '1', 14:36:43 INFO - 'TERM': 'linux', 14:36:43 INFO - 'TMOUT': '86400', 14:36:43 INFO - 'USER': 'cltbld', 14:36:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:43 INFO - '_': '/tools/buildbot/bin/python'} 14:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:43 INFO - Running setup.py (path:/tmp/pip-pr3D_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:43 INFO - Running setup.py (path:/tmp/pip-7rqv4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:43 INFO - Running setup.py (path:/tmp/pip-V2AzPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:43 INFO - Running setup.py (path:/tmp/pip-gbIFwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:43 INFO - Running setup.py (path:/tmp/pip-9xwZ_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:43 INFO - Running setup.py (path:/tmp/pip-AuPpNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:43 INFO - Running setup.py (path:/tmp/pip-AtcVIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:43 INFO - Running setup.py (path:/tmp/pip-fiMcDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:44 INFO - Running setup.py (path:/tmp/pip-bDnmBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:44 INFO - Running setup.py (path:/tmp/pip-XZJK5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:44 INFO - Running setup.py (path:/tmp/pip-GxQac9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:44 INFO - Running setup.py (path:/tmp/pip-UCZd48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:44 INFO - Running setup.py (path:/tmp/pip-4ixRus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:44 INFO - Running setup.py (path:/tmp/pip-Fanv92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:44 INFO - Running setup.py (path:/tmp/pip-Sdz1cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:44 INFO - Running setup.py (path:/tmp/pip-xcZCNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:44 INFO - Running setup.py (path:/tmp/pip-Oy1MAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:36:44 INFO - Running setup.py install for manifestparser 14:36:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:36:44 INFO - Running setup.py install for mozcrash 14:36:44 INFO - Running setup.py install for mozdebug 14:36:45 INFO - Running setup.py install for mozdevice 14:36:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for mozfile 14:36:45 INFO - Running setup.py install for mozhttpd 14:36:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for mozinfo 14:36:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for mozInstall 14:36:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for mozleak 14:36:45 INFO - Running setup.py install for mozlog 14:36:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for moznetwork 14:36:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:36:45 INFO - Running setup.py install for mozprocess 14:36:45 INFO - Running setup.py install for mozprofile 14:36:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:36:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:36:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:36:46 INFO - Running setup.py install for mozrunner 14:36:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:36:46 INFO - Running setup.py install for mozscreenshot 14:36:46 INFO - Running setup.py install for moztest 14:36:46 INFO - Running setup.py install for mozversion 14:36:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:36:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:36:46 INFO - Cleaning up... 14:36:46 INFO - Return code: 0 14:36:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 14:36:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 14:36:46 INFO - Using env: {'DISPLAY': ':0', 14:36:46 INFO - 'HOME': '/home/cltbld', 14:36:46 INFO - 'LANG': 'en_US.UTF-8', 14:36:46 INFO - 'LANGUAGE': 'en_US:en', 14:36:46 INFO - 'LOGNAME': 'cltbld', 14:36:46 INFO - 'MAIL': '/var/mail/cltbld', 14:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:46 INFO - 'MOZ_NO_REMOTE': '1', 14:36:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:46 INFO - 'NO_EM_RESTART': '1', 14:36:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:46 INFO - 'PWD': '/builds/slave/test', 14:36:46 INFO - 'SHELL': '/bin/bash', 14:36:46 INFO - 'SHLVL': '1', 14:36:46 INFO - 'TERM': 'linux', 14:36:46 INFO - 'TMOUT': '86400', 14:36:46 INFO - 'USER': 'cltbld', 14:36:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:46 INFO - '_': '/tools/buildbot/bin/python'} 14:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:46 INFO - Running setup.py (path:/tmp/pip-5d77Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:46 INFO - Running setup.py (path:/tmp/pip-riRASz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:46 INFO - Running setup.py (path:/tmp/pip-3E5rUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:46 INFO - Running setup.py (path:/tmp/pip-ZDHLfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:47 INFO - Running setup.py (path:/tmp/pip-jkhrFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:47 INFO - Running setup.py (path:/tmp/pip-_MG5GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:47 INFO - Running setup.py (path:/tmp/pip-pNwLJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:47 INFO - Running setup.py (path:/tmp/pip-qMWku_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:47 INFO - Running setup.py (path:/tmp/pip-p9hEuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:47 INFO - Running setup.py (path:/tmp/pip-Zz1mG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:47 INFO - Running setup.py (path:/tmp/pip-sEenJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:47 INFO - Running setup.py (path:/tmp/pip-0n9DSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:47 INFO - Running setup.py (path:/tmp/pip-SrMge1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:47 INFO - Running setup.py (path:/tmp/pip-sqHF_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:47 INFO - Running setup.py (path:/tmp/pip-MgW1Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:47 INFO - Running setup.py (path:/tmp/pip-ZDCdL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:47 INFO - Running setup.py (path:/tmp/pip-hAGIWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:50 INFO - Downloading blessings-1.6.tar.gz 14:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:36:50 INFO - Installing collected packages: blessings 14:36:50 INFO - Running setup.py install for blessings 14:36:50 INFO - Successfully installed blessings 14:36:50 INFO - Cleaning up... 14:36:50 INFO - Return code: 0 14:36:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:36:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:50 INFO - Reading from file tmpfile_stdout 14:36:50 INFO - Current package versions: 14:36:50 INFO - argparse == 1.2.1 14:36:50 INFO - blessings == 1.6 14:36:50 INFO - blobuploader == 1.2.4 14:36:50 INFO - docopt == 0.6.1 14:36:50 INFO - functools32 == 3.2.3-2 14:36:50 INFO - jsonschema == 2.5.1 14:36:50 INFO - manifestparser == 1.1 14:36:50 INFO - mozInstall == 1.12 14:36:50 INFO - mozcrash == 0.17 14:36:50 INFO - mozdebug == 0.1 14:36:50 INFO - mozdevice == 0.48 14:36:50 INFO - mozfile == 1.2 14:36:50 INFO - mozhttpd == 0.7 14:36:50 INFO - mozinfo == 0.9 14:36:50 INFO - mozleak == 0.1 14:36:50 INFO - mozlog == 3.3 14:36:50 INFO - moznetwork == 0.27 14:36:50 INFO - mozprocess == 0.23 14:36:50 INFO - mozprofile == 0.28 14:36:50 INFO - mozrunner == 6.12 14:36:50 INFO - mozscreenshot == 0.1 14:36:50 INFO - mozsystemmonitor == 0.3 14:36:50 INFO - moztest == 0.7 14:36:50 INFO - mozversion == 1.4 14:36:50 INFO - psutil == 3.1.1 14:36:50 INFO - requests == 1.2.3 14:36:50 INFO - wsgiref == 0.1.2 14:36:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 14:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 14:36:50 INFO - Using env: {'DISPLAY': ':0', 14:36:50 INFO - 'HOME': '/home/cltbld', 14:36:50 INFO - 'LANG': 'en_US.UTF-8', 14:36:50 INFO - 'LANGUAGE': 'en_US:en', 14:36:50 INFO - 'LOGNAME': 'cltbld', 14:36:50 INFO - 'MAIL': '/var/mail/cltbld', 14:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:50 INFO - 'MOZ_NO_REMOTE': '1', 14:36:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:50 INFO - 'NO_EM_RESTART': '1', 14:36:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:50 INFO - 'PWD': '/builds/slave/test', 14:36:50 INFO - 'SHELL': '/bin/bash', 14:36:50 INFO - 'SHLVL': '1', 14:36:50 INFO - 'TERM': 'linux', 14:36:50 INFO - 'TMOUT': '86400', 14:36:50 INFO - 'USER': 'cltbld', 14:36:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:50 INFO - '_': '/tools/buildbot/bin/python'} 14:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:36:50 INFO - Cleaning up... 14:36:50 INFO - Return code: 0 14:36:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x1456030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1717180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 14:36:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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/build 14:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 14:36:50 INFO - Using env: {'DISPLAY': ':0', 14:36:50 INFO - 'HOME': '/home/cltbld', 14:36:50 INFO - 'LANG': 'en_US.UTF-8', 14:36:50 INFO - 'LANGUAGE': 'en_US:en', 14:36:50 INFO - 'LOGNAME': 'cltbld', 14:36:50 INFO - 'MAIL': '/var/mail/cltbld', 14:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:50 INFO - 'MOZ_NO_REMOTE': '1', 14:36:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:50 INFO - 'NO_EM_RESTART': '1', 14:36:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:50 INFO - 'PWD': '/builds/slave/test', 14:36:50 INFO - 'SHELL': '/bin/bash', 14:36:50 INFO - 'SHLVL': '1', 14:36:50 INFO - 'TERM': 'linux', 14:36:50 INFO - 'TMOUT': '86400', 14:36:50 INFO - 'USER': 'cltbld', 14:36:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:50 INFO - '_': '/tools/buildbot/bin/python'} 14:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:36:50 INFO - Cleaning up... 14:36:50 INFO - Return code: 0 14:36:50 INFO - Running post-action listener: _resource_record_post_action 14:36:50 INFO - Running post-action listener: _start_resource_monitoring 14:36:51 INFO - Starting resource monitoring. 14:36:51 INFO - [mozharness: 2016-07-22 21:36:51.026129Z] Finished create-virtualenv step (success) 14:36:51 INFO - [mozharness: 2016-07-22 21:36:51.026604Z] Running install step. 14:36:51 INFO - Running pre-action listener: _resource_record_pre_action 14:36:51 INFO - Running main action method: install 14:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:51 INFO - Reading from file tmpfile_stdout 14:36:51 INFO - Detecting whether we're running mozinstall >=1.0... 14:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:36:51 INFO - Reading from file tmpfile_stdout 14:36:51 INFO - Output received: 14:36:51 INFO - Usage: mozinstall [options] installer 14:36:51 INFO - Options: 14:36:51 INFO - -h, --help show this help message and exit 14:36:51 INFO - -d DEST, --destination=DEST 14:36:51 INFO - Directory to install application into. [default: 14:36:51 INFO - "/builds/slave/test"] 14:36:51 INFO - --app=APP Application being installed. [default: firefox] 14:36:51 INFO - mkdir: /builds/slave/test/build/application 14:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:37:08 INFO - Reading from file tmpfile_stdout 14:37:08 INFO - Output received: 14:37:08 INFO - /builds/slave/test/build/application/firefox/firefox 14:37:08 INFO - Running post-action listener: _resource_record_post_action 14:37:08 INFO - [mozharness: 2016-07-22 21:37:08.540172Z] Finished install step (success) 14:37:08 INFO - [mozharness: 2016-07-22 21:37:08.540444Z] Running run-tests step. 14:37:08 INFO - Running pre-action listener: _resource_record_pre_action 14:37:08 INFO - Running main action method: run_tests 14:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:37:08 INFO - Python 2.7.3 14:37:08 INFO - Return code: 0 14:37:08 INFO - grabbing minidump binary from tooltool 14:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:37:08 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17153b0>, '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 0x17162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1716740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:37:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:37:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:37:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:37:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:37:08 INFO - Return code: 0 14:37:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:37:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:37:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:37:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:37:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:37:08 INFO - Using env: {'DISPLAY': ':0', 14:37:08 INFO - 'HOME': '/home/cltbld', 14:37:08 INFO - 'LANG': 'en_US.UTF-8', 14:37:08 INFO - 'LANGUAGE': 'en_US:en', 14:37:08 INFO - 'LOGNAME': 'cltbld', 14:37:08 INFO - 'MAIL': '/var/mail/cltbld', 14:37:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:37:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:08 INFO - 'MOZ_NO_REMOTE': '1', 14:37:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:37:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:08 INFO - 'NO_EM_RESTART': '1', 14:37:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:08 INFO - 'PWD': '/builds/slave/test', 14:37:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:37:08 INFO - 'SHELL': '/bin/bash', 14:37:08 INFO - 'SHLVL': '1', 14:37:08 INFO - 'TERM': 'linux', 14:37:08 INFO - 'TMOUT': '86400', 14:37:08 INFO - 'USER': 'cltbld', 14:37:08 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734', 14:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:08 INFO - '_': '/tools/buildbot/bin/python'} 14:37:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:37:08 INFO - mozversion application_buildid: 20160722113020 14:37:08 INFO - mozversion application_changeset: 48cee0fccdb3369df7af58c63329a25fb088832b 14:37:08 INFO - mozversion application_display_name: Nightly 14:37:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:37:08 INFO - mozversion application_name: Firefox 14:37:08 INFO - mozversion application_remotingname: firefox 14:37:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:37:08 INFO - mozversion application_vendor: Mozilla 14:37:08 INFO - mozversion application_version: 50.0a1 14:37:08 INFO - mozversion platform_buildid: 20160722113020 14:37:08 INFO - mozversion platform_changeset: 48cee0fccdb3369df7af58c63329a25fb088832b 14:37:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:37:08 INFO - mozversion platform_version: 50.0a1 14:37:08 INFO - using testdate: 1469223428 14:37:08 INFO - actual date: 1469223428 14:37:08 INFO - starting webserver on 'localhost:46570' 14:37:08 INFO - SUITE-START | Running 2 tests 14:37:08 INFO - TEST-START | dromaeo_css 14:37:08 INFO - operating with platform_type : linux_ 14:37:08 INFO - Initialising browser for dromaeo_css test... 14:37:08 INFO - TEST-INFO | started process 30489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWu8vU7/profile http://localhost:46570/getInfo.html) 14:37:11 INFO - PROCESS | 30489 | JavaScript error: , line 0: uncaught exception: undefined 14:37:12 INFO - PROCESS | 30489 | __metrics Screen width/height:1600/1200 14:37:12 INFO - PROCESS | 30489 | colorDepth:24 14:37:12 INFO - PROCESS | 30489 | Browser inner width/height: 1024/697 14:37:12 INFO - PROCESS | 30489 | __metrics 14:37:13 INFO - PROCESS | 30489 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:37:13 INFO - PROCESS | 30489 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:37:13 INFO - PROCESS | 30489 | console.error: PushService: 14:37:13 INFO - PROCESS | 30489 | stateChangeProcessEnqueue: Error transitioning state 14:37:13 INFO - PROCESS | 30489 | UnknownError 14:37:14 INFO - TEST-INFO | 30489: exit 0 14:37:14 INFO - Browser initialized. 14:37:14 INFO - Running cycle 1/1 for dromaeo_css test... 14:37:14 INFO - TEST-INFO | started process 30572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWu8vU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:26 INFO - PROCESS | 30572 | RSS: Main: 157671424 14:37:26 INFO - PROCESS | 30572 | 14:38:17 INFO - PROCESS | 30572 | 1469223497753 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] 14:40:25 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46570/tests/dromaeo/cssquery-ext.html) 14:40:26 INFO - PROCESS | 30572 | RSS: Main: 272257024 14:40:26 INFO - PROCESS | 30572 | 14:43:28 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-ext.html (next: http://localhost:46570/tests/dromaeo/cssquery-jquery.html) 14:43:29 INFO - PROCESS | 30572 | RSS: Main: 246067200 14:43:29 INFO - PROCESS | 30572 | 14:46:31 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46570/tests/dromaeo/cssquery-mootools.html) 14:46:31 INFO - PROCESS | 30572 | RSS: Main: 186568704 14:46:31 INFO - PROCESS | 30572 | 14:49:33 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46570/tests/dromaeo/cssquery-prototype.html) 14:49:34 INFO - PROCESS | 30572 | RSS: Main: 224002048 14:49:34 INFO - PROCESS | 30572 | 14:52:37 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46570/tests/dromaeo/cssquery-yui.html) 14:52:37 INFO - PROCESS | 30572 | RSS: Main: 183230464 14:52:37 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | Cycle 1(1): loaded http://localhost:46570/tests/dromaeo/cssquery-yui.html (next: http://localhost:46570/tests/dromaeo/cssquery-dojo.html) 14:55:40 INFO - PROCESS | 30572 | RSS: Main: 276938752 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | __start_tp_report 14:55:40 INFO - PROCESS | 30572 | _x_x_mozilla_page_load 14:55:40 INFO - PROCESS | 30572 | _x_x_mozilla_page_load_details 14:55:40 INFO - PROCESS | 30572 | |i|pagename|runs| 14:55:40 INFO - PROCESS | 30572 | |0;dojo.html;3053;3062.9693703062962;3091.227193201701;3094.76789240807;3100.7984481008734;5166.250893620423;5231.921521177176;5332.946670533293;5369.91945120823;5393.676379417233;5260.263563101169;5271.920921186185;5291.947080529194;5363.758630861611;5397.919031214525;4848.321235027099;4864.221724524085;4886.902261954759;4898.51014898509;5004;4925.82759603414;4959.330490383806;4968.40379154502;4968.70187788732;4992.925106123411;4766.142094422985;4806.711597304138;4817.710937343771;4884.902301953959;4908.460069392382;4768.61851051915;4769.976150119227;4799.11216424959;4806.879828004293;4869.610431165498;4767.404074490689;4768.976155119202;4815.66290359675;4824.975875120637;4859;5178.0679477694;5252.107141785906;5342.198670199501;5344.198370244456;5377.0052540279785;5664.546836253131;5719.14212868069;5722.942770572283;5729.828105156852;5749.568782341299;5710.172025056385;5743.224664670258;5789.507891829199;5789.652620842763;5789.739461724233;5173.430922598511;5285.207218917193;5341.5459685927135;5393.676379417208;5401.135818269058;5848.853778655568;5890.175375447441;5893.675847828325;5893.852653683693;5897;5626.578006649518;5630.042892708249;5633.6619802811965;5635.690037048002;5637.49262566371;5558.249636299129;5583.720813959367;5636.915446268309;5709.714514274353;5711.428857114339;5643.887122257532;5646.632968857011;5652.095664693629;5693.857653558694;5706.857328566819;5601.159826026129;5639.548836093103;5640.605157638925;5646.20953066571;5649.209110724501;4181.60274773896;4295.269804133305;4330.242207613655;4351.608355248043;4391.6486681065435;3896.376579747227;3904.0630248740667;3907.531096268466;3934.409838524244;3939.763614183158;5400.1899715043055;5408.621396502286;5410.675359478444;5412.702301373462;5460.235567020621;5470.617056806065;5703.258576385127;5728.684922329228;5760.481556659921;5772.249607550992;5414.7834086636885;5461.563074953994;5467.316585426822;5481.643693159932;5482.068048431776;4986.301917731448;4993.7503124843615;4997.475265097112;4999.42506611737;5046.47012063728;5661.2923384576925;5737.627054241461;5759.596828222068;5802.79690210849;5881.441263080001;5679.034564124191;5688.260526131577;5689.800856969986;5694.914576281442;5728.484436400744;5807.796727114531;5809.128630705428;5813.94186058134;5817.680027598522;5818.970905145447;5413.539849112712;5554.333479982428;5576.163575463551;5584.134459158674;5614.410486898817;5644.435556444487;5748.683822389641;5751.482366587027;5751.683657398883;5788.623739456922;5598.664080155204;5601.103823388238;5606.103023516218;5608.775648974159;5610.579206559606;5393.595480339069;5554.750036248298;5594.692291923983;5603.187537807076;5618.634788738882;5578.77684892616;5579.302587176603;5580.107182850725;5580.609357344947;5615.410381909841;5658.971705141448;5678.3753787084215;5713.8285851424525;5719.513841323368;5727.627704199213;5322.121849894724;5412.052890744183;5474.534664553398;5485.945140548703;5550.611457197957 14:55:40 INFO - PROCESS | 30572 | |1;ext.html;594.2838879150623;601.38958956659;616.4144063140018;626.3392121312015;649.1366482578165;43276.78361608191;44795.77602111989;44856.55143448564;44944;48009.51990480094;45693.31460028094;45799;46006;46043.76978115113;46143.30785038219;8960.8655870162;9066.229370503515;9095.045020272843;9107.180353768159;9118.17936385725;8945.239654629364;9033.367664263504;9041.321900857429;9046.411983221102;9052.502112383816;9077.13767192119;9110.45337279762;9122.589483473259;9123.133302336268;9124.45253284805;9005.369624126313;9027.4132181408;9031.864522032109;9046.411983221069;9053.728388148333;8985.101489851028;9067.68263110788;9070.909290907073;9071.727848164499;9078.636854525812;42712.57287427117;42764;43036;43193;43199.35200971988;3251;3412.334594754024;3441.1913200397885;3478.4086705260165;3566.5006899034156;32669.346613067606;32888;33353.49969750456;33998.32003359943;34929;4355.150745604608;4386.517483076891;4406.162829062468;4430.113977204572;4439.200943830109;357591;358546;360486;362526;363421;56972.430275696715;57360;57361.71319143377;57374.7131264341;57424.42575574274;54257.72871135619;59338.70330648319;60040;60954;61325;16829;17070.743938840926;17092.40176593813;17112.914435427992;17118.40085596998;13240.867591324157;13335;13335.399907004208;13342.799858002138;13345;14593.19737414431;14849.480268190562;16052.27764750589;16486.75269870953;16609.501714948572;5749.338826034976;5967.761289548456;6063.272407311151;6070.3019152797115;6088.452039316394;997.2570435025807;1064.0523657231602;1073.7423018475665;1102.922795404314;1113.7939481196004;32391;32484.512732309035;32616.673833261364;32630.673693262765;32714;1114.0586204657052;1125.9528638366235;1171.654361963223;1177.1524502358288;1224.4245204753752;16479.752803707954;17069.31722731077;17196.764420625237;17350.39273625417;17628.20673069715;530214;533848;534441;534585;535591;58152.70923645355;58437.4156258432;58582;58591.414085858596;58592;1362.3801170467414;1385.8267716535433;1401.866822651826;1422.4523558429871;1425.4583258361756;30829.845850770602;31929;32329.51505727463;32336.35327293394;32965.34069318648;51124.488755111975;51613.483865160866;51675.483245167066;51713;51758.74120629373;468746;471749;472656;474197;474292;35745.1063723409;41440.79279603583;41866.37200442057;42627.57372426236;42628.08173632256;42412;45613.77193114013;48829;49021;49410.258846116616;37280.62719372771;38736.6126338733;39686.40470393004;40020.09810261944;40075.599244007186;39178.80410597929;39297;39965.60034399735;40956.59043409647;41068;40562;41048;41695;41881;41931.37102943519;32964.83517582493;35539.28921421505;35801.64198358087;43989.34015989827;45675.77162114301;32241.51637725389;35115.473267901514;35374.46938295876;35387.82306088556;35440.82279588689 14:55:40 INFO - PROCESS | 30572 | |2;jquery.html;365.66541614422806;378.39456869009587;379.609002727191;390.8045977011494;393.38238964825234;17830.019348935803;19436.70844937327;19787.50531236718;20087.497812554677;20124.899375503137;19123.948182849937;19182.61634767304;19263.229470821152;19429;19700.901495492504;5044.915343201211;5390.595705322106;5575.526080283161;5577.749001294951;5641.576881733866;5434.673919564839;5522.392536820947;5523.337199536061;5525.502704756571;5526.58550608706;5428.782848686048;5446.210299506589;5493.313335833021;5500.80747173849;5522.144067669517;5427.050266203419;5496.560275177976;5528.06022976095;5537.889242215153;5552.139418390156;4744.739039352834;4953.975230123826;4961.70229786214;4968.776405061781;5004.699718016895;12822.294773787438;12921.935390322988;12958.222506649538;13003.414846331938;13006.284654344103;2253.9436514087115;2299.8735069571167;2330.557194133109;2389.6773935518654;2429.6477010833332;9561.426314421087;9574.521273936345;9585.233181345475;9785.11933925943;10056.145227655657;1834.8073452287451;1906.9427917162507;1915.4856662136808;1941.5825597496605;1991.9701204481944;75151.62424187844;78071;78946.6052669733;80112.59943700302;83013.16986830115;10334.948325258325;10347.534360953625;10514.158867290598;10584.206184536191;10635.255532112678;14464.566063018126;14514.564563063124;14518.346674399678;14522.128672279698;14526.85473145255;7830;7909.169537198626;8086.474379165453;8100.918990810134;8317.667293308321;7732.604885064561;7746;7769.922300777033;7931;8412.369072319601;4420.248557745191;4428.579284967923;4455.866324010285;4610.05493873755;4618.607418369409;1788.7227479740661;1791.4446521578093;1835.5319393554557;1892.7350170976074;1904.64764018657;1012.4128005756644;1032.5405194688292;1047.219821233171;1082.9495389472356;1100.4937728644913;19501.219951202078;19570.412887613395;19605;19622.31321903755;19643.901780491007;972.5380444288907;998.1964518562481;1027.1679939249232;1033.937963722164;1069.3316677076828;10292.382457052601;10733.624323148808;10735.78528429427;10787.730306742395;10819.188560857967;478657;486735;514107;514793;525778;86674.13325866661;88241;90504;92372.53813730889;94469;971.0774763974252;996.2030375699557;1009.9596016159418;1012.2003617142375;1012.9797404052025;16299.674006520041;16491.83508164903;16549.91725041367;17493.03788291656;17873;41032.58967410288;41816.2819416737;42126.36810447907;42292.78853605712;43014.78492607517;58523.70738146282;60083.69958150356;60193;60406;61786.69106654618;8112.351011918914;8278.254957053894;8326.25063744266;8345.581251187434;8415.200555947175;11395.601153959571;12908.806367904677;12918.935405322913;12947.805782913452;13113.08208425401;11011.61459348919;11228.66314010581;11409.714757131138;11493.712657183638;11544.076473881902;10227.181825454094;11226.607068752555;11315.71710707206;11321.094312455149;11425.771484570429;11311.830322545004;11518.136139789382;11746.765064698488;11820.822687659864;11848.466818992994;8190.180981901762;8205.343572514304;8218.260356567698;8284.337253019865;8602.827943441222;8278.751637450887;10465.738356541147;11000.119990400588;11357.0914326852;11418 14:55:40 INFO - PROCESS | 30572 | |3;mootools.html;1328.2495390104584;1452.644102194962;1520.429838810446;1608.5898095985522;1792.399546152039;8372.120927302629;8773.605187766558;8779.2537634301;8793.692220772266;8799.56002199889;8636.697715579956;8711.08533603972;8721.956390218056;8727.476351418904;8728.869066964;2328.8020518255967;2357.422431504284;2359.9174028908906;2359.9882000589973;2365.006697187177;2341.8126549876056;2352.9058837646476;2402.039184326266;2402.6396040593963;2403.5553422616786;2336.9649455258186;2343.95312093758;2355.9293221203393;2362.1024010875844;2372.4306166519996;2310.5378924215056;2313.3060081975423;2332.428555004035;2338.275134708229;2343.238447504559;2263.1400067974228;2273.4430064634107;2277.9976810203457;2279.111146652807;2279.41874821921;8706;8710.21608055278;8765.254953328975;8834.690785822466;8842;5028.371453568304;5182.32629758129;5184.585233181337;5188.299579556787;5192.428832828386;3076.353908762349;3199.40810949975;3218.5976752905885;3249.252671885456;3273.623533293678;1057.6456886942883;1061.43743815777;1062.6705721226367;1067.151614466501;1090.1551297744231;11852.940735296355;11854.762904741943;11876;11884.524619015316;11934.283942963362;4234.809433575497;4350.0647360817575;4444.688871779009;4488.910221795546;4489.057297967398;9548.236141108695;9572.32993690435;9580.904190958141;9596.18432433251;9609.039096090335;5140.151874940668;5169.198774190007;5172.250023746536;5173.922391164136;5181.948180518222;4186.3092589722355;4690.577847993697;4825.734584597881;4835.202191638411;4865.148598995162;3696.482492451059;3797.373433383461;3836.5204349456317;3854.9807250963568;3905.804709764557;1118.429600903545;1128.5485805677795;1129.4239937631867;1130.4347826086957;1138.6697857621364;891.5765011619559;998.6604554451521;1011.5650270383659;1013.4932533733133;1018.6027449294781;6017.247844019497;6035.426634469718;6082.60463069899;6329.082283068929;6383.521235907326;433.3456480714144;435.6166573415374;440.8581773207408;445.58337954013103;455.9110973360229;4162.542120366757;4320.1143765527995;4369.257226271541;4374.496932852699;4511.187986162522;10226.488675566192;10487.213458990605;10515.106215971575;10519.474026298654;10538.63114790994;10463;10470.581176752845;10471;10479.790404192026;10482.528286227138;7826.843463130819;8004.599770011476;8093.433459657886;8110.432269741062;8160.510369377975;6096.375572625671;6181.690915454119;6195.659238741912;6199.597026193283;6319.873602528016;10035.19718422539;10191.694249172537;10194.286399951932;10221.437820919624;10241.23190760681;9669.371490853073;10034.397936123856;10059.949700251453;10170.949145254226;10246.282760207012;6960.547564408297;7708.884366734392;7791.883121753292;7912.881306780518;7989.7203597873795;8600.612972416131;8729.51987640686;8731.956340218258;8734.082921293173;8762.912370876209;7597.392208623186;7661.923380766121;7793.649285782267;7829.217078292125;7873.724419645285;7483.812904677427;7628.466007379652;7645.311921927053;7657.731979380917;7690.2694244046725;6975.79072627822;7102.893456598052;7103.2541583133025;7103.573785572882;7120.2167761547225;5193.480651934777;5303.522682958553;5336.493033161843;5370.704611246419;5562.137868630369;5138.588912886886;5242.239875217996;5309.2301616266195;5340.572754179734;5573.832785016456 14:55:40 INFO - PROCESS | 30572 | |4;prototype.html;296.589223924864;311.98440077996094;312.92020534763634;313.5985937999361;319.068320504128;5302.284191634129;5350.973245133774;5354.625176237665;5384.788422604912;5407.297051383314;4984.651074424783;5106.08090543702;5110.105731496992;5130.794768209276;5265.526102650771;3980.0099950024987;4016.9397459038064;4080.326746086892;4089.4683691120113;4124.072083781155;4106;4112.259793237216;4116.670666346685;4132.483439570054;4135.8966025849295;3872.7482713623667;4053.6351728344443;4054.209429161315;4094.9385759213637;4103;4030.7380020298588;4053.97973010136;4061.939070913939;4063.9187216255805;4069.9186016279655;3580.5703315602036;3583.122135076897;3584.4085725855202;3585.4621806728937;3587.766795158306;4905.007945153454;5135.281060651511;5153.098207813618;5166.664166829144;5174.146265866128;4627.953720462786;4659.603933665609;4660.5339466053465;4660.673752837302;4671.626269898434;5446.502211891722;5500.779968801243;5507.504324610764;5507.7246137692955;5511.807086751945;3818.885433437001;3919.196564704229;3935.3113205189265;3942.7437216581116;3943.7830919299427;5637.689927054011;5674.858128546778;5686.317641883001;5702.372738998707;5713.342965558931;3992.840286388512;3993.4009898514873;4006.0585762345827;4006.6193711597352;4008.7995600219874;4045.150518391171;4052.6352628263007;4060.147369052532;4061.878143655695;4063.6342729154517;4529.527903431398;4624.815007399734;4631.444226692818;4641.419822522184;4648.581627653527;5512.641678290898;5517.889642207133;5521.254630624814;5528.668279903219;5540.085885828874;1321.14125818217;1389.1248513436672;1394.9790753138711;1395.0931894268604;1400.0969374753226;919.3748251189255;926.2867591954158;939.8919124300793;942.6465075596651;946.3659348236698;5264.868378290574;5275.366955965309;5288.92066619001;5298.655587386807;5312.9734351328;5484.2596249506605;5610.943890561124;5614.989301925611;5616.326040875039;5620.522255608237;5218.712970786567;5242.640811634703;5252.79185787271;5266.4470230626;5271.235670827708;2949.439606474763;2977.1366303772097;2978.195887110468;2983.1796256029766;2996.8801247950273;5540.418256083134;5663.1505274208375;5668.51817595509;5725.484706376446;5761.567882408836;5686.744096515667;5703.885922281531;5705.5720820938595;5707.315122185364;5716.628419152825;5693.202951586781;5721.799737009268;5723.255976723;5727.885442291131;5728.312602487728;5353.892922141457;5479.863003424787;5508.586855985897;5574.442555744556;5586.190002449704;5507.026205848797;5519.972400137974;5523.696196709219;5528.944710552843;5539.41836107219;5543.311493925362;5700.686462244616;5720.2849643794525;5722.884037612625;5727.341355744143;3934.901627459222;3976.542697589814;3977.8607748729814;3979.840806367713;3982.3429134192534;5549.947528859192;5571.665700057847;5579.46995035471;5586.888262234814;5591.860203494945;5236.293100431545;5413.729313534386;5449.754761035683;5453.454654534515;5466.34403871538;5410.810621628224;5415.729213539228;5426.782928682808;5452.318460192476;5453.563714902719;5223.555997740083;5301.416844147064;5349.331333583302;5358.169483730028;5402.729863506887;5473.425290344457;5507.284053073075;5515.200295957144;5523.558115350682;5612.607117501736;5404.270423492778;5446.782128714807;5477.20580515831;5480.671159730429;5493.285872836505 14:55:40 INFO - PROCESS | 30572 | |5;yui.html;742.8031571633518;748.9737859174927;770.8150043989444;819.4714409206061;887.2768693514782;15298.69402611947;16328.83671163288;16345.75481367779;16353.591160221;16413.425530106448;13894.930525347385;13940.651483712887;13980.93009534951;14024.929875350634;14086.225257610828;646.1793522226781;785.7956931197901;811.2536466450879;815.8368326334727;819.4427789103407;815.2214635023538;815.5269943432795;815.6655771133836;817.0154963269247;817.6157206113118;698.2110215456527;867.1501928110456;871.777696687347;872.5462759365126;873.4802792338548;838.2748922182299;854.8717692346136;861.9181177788131;863.1368631368631;863.8445079885618;632.3581564711822;632.4624069540899;632.8196464007782;633.0472638678805;633.1168020611268;15084.17037062961;15085.849141508665;15136.243187840673;15158.166300853449;15174.24128793556;14197.503087391995;14285.21431321277;14309.499167529191;14379.424823007068;14445.638859028504;13180.209187448689;13204.801927971088;13298.667533311647;13305.800412993813;13409.865901341056;4429.291313389875;4443.155800397914;4491.146682130385;4494.662900282492;4571.565701258375;72960.63519682421;73042.63478682573;73297.63351183264;73343;75747.62126189389;11026.558937642565;11060.557577696964;11117.388543630017;11270.323780573028;11380.146489013192;19968.30110946139;20081;20123.899380503295;20126.597468050557;20131.19475221004;7788.221177882167;8095.323633315272;8126.10612832588;8166.4283500154415;8322.334213263046;4390.846320378771;4498.820047198075;4532.546745325507;4578.656600754956;4694.155052090657;3400.0819778659625;3488.0582242794303;3544.078539579728;3547.609762926076;3550.538430004083;587.1955421073072;592.2981267198297;595.9106134079923;609.9969500152471;617.3240301869446;554.6838302167806;578.1934201788481;588.1736160694231;591.4381337729175;608.1091201389985;8592.613332400057;8951.462912225288;9018.23345015681;9124.908750912538;9143.359964802534;613.9508839292846;622.7384498510636;646.7994921574327;657.6054367379534;662.1590579963515;4245.724027938174;4477.865664030084;4515.796789144496;4521.276595744665;4642.65180111493;113725;114784;115668.42165789117;117359.41320293515;120657.39671301587;102756;105291;106592.46703766432;107044;107701;87535.56232218798;89319.55340223257;90367.54816225877;91138;93866;8450;8490.235878770693;8810.339224558056;9861.260405469475;10174.898251017396;50503.747481262355;51526.22710659418;51734.482655172964;52355;53207.46792532025;72437.63781181061;74946.62526687332;75666.62166689131;76247;77216;12745.553905613258;13133.802992954923;13251.602451926457;13350.866491334962;13498.52755153565;15091.773623395877;15160.696786064438;15212.46982743549;15868.920655396649;16184.190790460665;14038.384109515777;14289.71420571562;14464.27678616086;14638.780418293947;14710.338034788247;13703.862961370258;13705.588832335046;13804.654883627989;13895.791563126359;14284.214368209849;13370.933145334211;13919.164850109026;14096.718065638835;14149.151050936976;14582.562523124323;11464.484098215768;11645.534178632759;11928.224665396721;12000.579979701019;12038.398080095785;11131.220814542905;12055;12073.917090851486;12090.274583525017;12375.504979800708 14:55:40 INFO - PROCESS | 30572 | __end_tp_report 14:55:40 INFO - PROCESS | 30572 | __start_cc_report 14:55:40 INFO - PROCESS | 30572 | _x_x_mozilla_cycle_collect,989 14:55:40 INFO - PROCESS | 30572 | __end_cc_report 14:55:40 INFO - PROCESS | 30572 | __startTimestamp1469224540938__endTimestamp 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | ------- Summary: start ------- 14:55:40 INFO - PROCESS | 30572 | Number of tests: 6 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#0] dojo.html Cycles:165 Average:5297.67 Median:5550.61 stddev:596.76 (10.8%) stddev-sans-first:572.02 14:55:40 INFO - PROCESS | 30572 | Values: 3053.0 3063.0 3091.2 3094.8 3100.8 5166.3 5231.9 5332.9 5369.9 5393.7 5260.3 5271.9 5291.9 5363.8 5397.9 4848.3 4864.2 4886.9 4898.5 5004.0 4925.8 4959.3 4968.4 4968.7 4992.9 4766.1 4806.7 4817.7 4884.9 4908.5 4768.6 4770.0 4799.1 4806.9 4869.6 4767.4 4769.0 4815.7 4825.0 4859.0 5178.1 5252.1 5342.2 5344.2 5377.0 5664.5 5719.1 5722.9 5729.8 5749.6 5710.2 5743.2 5789.5 5789.7 5789.7 5173.4 5285.2 5341.5 5393.7 5401.1 5848.9 5890.2 5893.7 5893.9 5897.0 5626.6 5630.0 5633.7 5635.7 5637.5 5558.2 5583.7 5636.9 5709.7 5711.4 5643.9 5646.6 5652.1 5693.9 5706.9 5601.2 5639.5 5640.6 5646.2 5649.2 4181.6 4295.3 4330.2 4351.6 4391.6 3896.4 3904.1 3907.5 3934.4 3939.8 5400.2 5408.6 5410.7 5412.7 5460.2 5470.6 5703.3 5728.7 5760.5 5772.2 5414.8 5461.6 5467.3 5481.6 5482.1 4986.3 4993.8 4997.5 4999.4 5046.5 5661.3 5737.6 5759.6 5802.8 5881.4 5679.0 5688.3 5689.8 5694.9 5728.5 5807.8 5809.1 5813.9 5817.7 5819.0 5413.5 5554.3 5576.2 5584.1 5614.4 5644.4 5748.7 5751.5 5751.7 5788.6 5598.7 5601.1 5606.1 5608.8 5610.6 5393.6 5554.8 5594.7 5603.2 5618.6 5578.8 5579.3 5580.1 5580.6 5615.4 5659.0 5678.4 5713.8 5719.5 5727.6 5322.1 5412.1 5474.5 5485.9 5550.6 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#1] ext.html Cycles:180 Average:62058.56 Median:43114.50 stddev:122175.46 (283.4%) stddev-sans-first:122431.04 14:55:40 INFO - PROCESS | 30572 | Values: 594.3 601.4 616.4 626.3 649.1 43276.8 44795.8 44856.6 44944.0 48009.5 45693.3 45799.0 46006.0 46043.8 46143.3 8960.9 9066.2 9095.0 9107.2 9118.2 8945.2 9033.4 9041.3 9046.4 9052.5 9077.1 9110.5 9122.6 9123.1 9124.5 9005.4 9027.4 9031.9 9046.4 9053.7 8985.1 9067.7 9070.9 9071.7 9078.6 42712.6 42764.0 43036.0 43193.0 43199.4 3251.0 3412.3 3441.2 3478.4 3566.5 32669.3 32888.0 33353.5 33998.3 34929.0 4355.2 4386.5 4406.2 4430.1 4439.2 357591.0 358546.0 360486.0 362526.0 363421.0 56972.4 57360.0 57361.7 57374.7 57424.4 54257.7 59338.7 60040.0 60954.0 61325.0 16829.0 17070.7 17092.4 17112.9 17118.4 13240.9 13335.0 13335.4 13342.8 13345.0 14593.2 14849.5 16052.3 16486.8 16609.5 5749.3 5967.8 6063.3 6070.3 6088.5 997.3 1064.1 1073.7 1102.9 1113.8 32391.0 32484.5 32616.7 32630.7 32714.0 1114.1 1126.0 1171.7 1177.2 1224.4 16479.8 17069.3 17196.8 17350.4 17628.2 530214.0 533848.0 534441.0 534585.0 535591.0 58152.7 58437.4 58582.0 58591.4 58592.0 1362.4 1385.8 1401.9 1422.5 1425.5 30829.8 31929.0 32329.5 32336.4 32965.3 51124.5 51613.5 51675.5 51713.0 51758.7 468746.0 471749.0 472656.0 474197.0 474292.0 35745.1 41440.8 41866.4 42627.6 42628.1 42412.0 45613.8 48829.0 49021.0 49410.3 37280.6 38736.6 39686.4 40020.1 40075.6 39178.8 39297.0 39965.6 40956.6 41068.0 40562.0 41048.0 41695.0 41881.0 41931.4 32964.8 35539.3 35801.6 43989.3 45675.8 32241.5 35115.5 35374.5 35387.8 35440.8 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#2] jquery.html Cycles:180 Average:28893.61 Median:1397.66 stddev:83125.03 (5947.5%) stddev-sans-first:83330.62 14:55:40 INFO - PROCESS | 30572 | Values: 365.7 378.4 379.6 390.8 393.4 17830.0 19436.7 19787.5 20087.5 20124.9 19123.9 19182.6 19263.2 19429.0 19700.9 5044.9 5390.6 5575.5 5577.7 5641.6 5434.7 5522.4 5523.3 5525.5 5526.6 5428.8 5446.2 5493.3 5500.8 5522.1 5427.1 5496.6 5528.1 5537.9 5552.1 4744.7 4954.0 4961.7 4968.8 5004.7 12822.3 12921.9 12958.2 13003.4 13006.3 2253.9 2299.9 2330.6 2389.7 2429.6 9561.4 9574.5 9585.2 9785.1 10056.1 1834.8 1906.9 1915.5 1941.6 1992.0 75151.6 78071.0 78946.6 80112.6 83013.2 10334.9 10347.5 10514.2 10584.2 10635.3 14464.6 14514.6 14518.3 14522.1 14526.9 7830.0 7909.2 8086.5 8100.9 8317.7 7732.6 7746.0 7769.9 7931.0 8412.4 4420.2 4428.6 4455.9 4610.1 4618.6 1788.7 1791.4 1835.5 1892.7 1904.6 1012.4 1032.5 1047.2 1082.9 1100.5 19501.2 19570.4 19605.0 19622.3 19643.9 972.5 998.2 1027.2 1033.9 1069.3 10292.4 10733.6 10735.8 10787.7 10819.2 478657.0 486735.0 514107.0 514793.0 525778.0 86674.1 88241.0 90504.0 92372.5 94469.0 971.1 996.2 1010.0 1012.2 1013.0 16299.7 16491.8 16549.9 17493.0 17873.0 41032.6 41816.3 42126.4 42292.8 43014.8 58523.7 60083.7 60193.0 60406.0 61786.7 8112.4 8278.3 8326.3 8345.6 8415.2 11395.6 12908.8 12918.9 12947.8 13113.1 11011.6 11228.7 11409.7 11493.7 11544.1 10227.2 11226.6 11315.7 11321.1 11425.8 11311.8 11518.1 11746.8 11820.8 11848.5 8190.2 8205.3 8218.3 8284.3 8602.8 8278.8 10465.7 11000.1 11357.1 11418.0 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#3] mootools.html Cycles:180 Average:5731.59 Median:4500.12 stddev:3237.96 (72.0%) stddev-sans-first:3230.13 14:55:40 INFO - PROCESS | 30572 | Values: 1328.2 1452.6 1520.4 1608.6 1792.4 8372.1 8773.6 8779.3 8793.7 8799.6 8636.7 8711.1 8722.0 8727.5 8728.9 2328.8 2357.4 2359.9 2360.0 2365.0 2341.8 2352.9 2402.0 2402.6 2403.6 2337.0 2344.0 2355.9 2362.1 2372.4 2310.5 2313.3 2332.4 2338.3 2343.2 2263.1 2273.4 2278.0 2279.1 2279.4 8706.0 8710.2 8765.3 8834.7 8842.0 5028.4 5182.3 5184.6 5188.3 5192.4 3076.4 3199.4 3218.6 3249.3 3273.6 1057.6 1061.4 1062.7 1067.2 1090.2 11852.9 11854.8 11876.0 11884.5 11934.3 4234.8 4350.1 4444.7 4488.9 4489.1 9548.2 9572.3 9580.9 9596.2 9609.0 5140.2 5169.2 5172.3 5173.9 5181.9 4186.3 4690.6 4825.7 4835.2 4865.1 3696.5 3797.4 3836.5 3855.0 3905.8 1118.4 1128.5 1129.4 1130.4 1138.7 891.6 998.7 1011.6 1013.5 1018.6 6017.2 6035.4 6082.6 6329.1 6383.5 433.3 435.6 440.9 445.6 455.9 4162.5 4320.1 4369.3 4374.5 4511.2 10226.5 10487.2 10515.1 10519.5 10538.6 10463.0 10470.6 10471.0 10479.8 10482.5 7826.8 8004.6 8093.4 8110.4 8160.5 6096.4 6181.7 6195.7 6199.6 6319.9 10035.2 10191.7 10194.3 10221.4 10241.2 9669.4 10034.4 10059.9 10170.9 10246.3 6960.5 7708.9 7791.9 7912.9 7989.7 8600.6 8729.5 8732.0 8734.1 8762.9 7597.4 7661.9 7793.6 7829.2 7873.7 7483.8 7628.5 7645.3 7657.7 7690.3 6975.8 7102.9 7103.3 7103.6 7120.2 5193.5 5303.5 5336.5 5370.7 5562.1 5138.6 5242.2 5309.2 5340.6 5573.8 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#4] prototype.html Cycles:180 Average:4600.37 Median:5300.04 stddev:1350.39 (25.5%) stddev-sans-first:1314.97 14:55:40 INFO - PROCESS | 30572 | Values: 296.6 312.0 312.9 313.6 319.1 5302.3 5351.0 5354.6 5384.8 5407.3 4984.7 5106.1 5110.1 5130.8 5265.5 3980.0 4016.9 4080.3 4089.5 4124.1 4106.0 4112.3 4116.7 4132.5 4135.9 3872.7 4053.6 4054.2 4094.9 4103.0 4030.7 4054.0 4061.9 4063.9 4069.9 3580.6 3583.1 3584.4 3585.5 3587.8 4905.0 5135.3 5153.1 5166.7 5174.1 4628.0 4659.6 4660.5 4660.7 4671.6 5446.5 5500.8 5507.5 5507.7 5511.8 3818.9 3919.2 3935.3 3942.7 3943.8 5637.7 5674.9 5686.3 5702.4 5713.3 3992.8 3993.4 4006.1 4006.6 4008.8 4045.2 4052.6 4060.1 4061.9 4063.6 4529.5 4624.8 4631.4 4641.4 4648.6 5512.6 5517.9 5521.3 5528.7 5540.1 1321.1 1389.1 1395.0 1395.1 1400.1 919.4 926.3 939.9 942.6 946.4 5264.9 5275.4 5288.9 5298.7 5313.0 5484.3 5610.9 5615.0 5616.3 5620.5 5218.7 5242.6 5252.8 5266.4 5271.2 2949.4 2977.1 2978.2 2983.2 2996.9 5540.4 5663.2 5668.5 5725.5 5761.6 5686.7 5703.9 5705.6 5707.3 5716.6 5693.2 5721.8 5723.3 5727.9 5728.3 5353.9 5479.9 5508.6 5574.4 5586.2 5507.0 5520.0 5523.7 5528.9 5539.4 5543.3 5700.7 5720.3 5722.9 5727.3 3934.9 3976.5 3977.9 3979.8 3982.3 5549.9 5571.7 5579.5 5586.9 5591.9 5236.3 5413.7 5449.8 5453.5 5466.3 5410.8 5415.7 5426.8 5452.3 5453.6 5223.6 5301.4 5349.3 5358.2 5402.7 5473.4 5507.3 5515.2 5523.6 5612.6 5404.3 5446.8 5477.2 5480.7 5493.3 14:55:40 INFO - PROCESS | 30572 | 14:55:40 INFO - PROCESS | 30572 | [#5] yui.html Cycles:180 Average:21174.79 Median:4484.51 stddev:30732.38 (685.3%) stddev-sans-first:30780.30 14:55:40 INFO - PROCESS | 30572 | Values: 742.8 749.0 770.8 819.5 887.3 15298.7 16328.8 16345.8 16353.6 16413.4 13894.9 13940.7 13980.9 14024.9 14086.2 646.2 785.8 811.3 815.8 819.4 815.2 815.5 815.7 817.0 817.6 698.2 867.2 871.8 872.5 873.5 838.3 854.9 861.9 863.1 863.8 632.4 632.5 632.8 633.0 633.1 15084.2 15085.8 15136.2 15158.2 15174.2 14197.5 14285.2 14309.5 14379.4 14445.6 13180.2 13204.8 13298.7 13305.8 13409.9 4429.3 4443.2 4491.1 4494.7 4571.6 72960.6 73042.6 73297.6 73343.0 75747.6 11026.6 11060.6 11117.4 11270.3 11380.1 19968.3 20081.0 20123.9 20126.6 20131.2 7788.2 8095.3 8126.1 8166.4 8322.3 4390.8 4498.8 4532.5 4578.7 4694.2 3400.1 3488.1 3544.1 3547.6 3550.5 587.2 592.3 595.9 610.0 617.3 554.7 578.2 588.2 591.4 608.1 8592.6 8951.5 9018.2 9124.9 9143.4 614.0 622.7 646.8 657.6 662.2 4245.7 4477.9 4515.8 4521.3 4642.7 113725.0 114784.0 115668.4 117359.4 120657.4 102756.0 105291.0 106592.5 107044.0 107701.0 87535.6 89319.6 90367.5 91138.0 93866.0 8450.0 8490.2 8810.3 9861.3 10174.9 50503.7 51526.2 51734.5 52355.0 53207.5 72437.6 74946.6 75666.6 76247.0 77216.0 12745.6 13133.8 13251.6 13350.9 13498.5 15091.8 15160.7 15212.5 15868.9 16184.2 14038.4 14289.7 14464.3 14638.8 14710.3 13703.9 13705.6 13804.7 13895.8 14284.2 13370.9 13919.2 14096.7 14149.2 14582.6 11464.5 11645.5 11928.2 12000.6 12038.4 11131.2 12055.0 12073.9 12090.3 12375.5 14:55:40 INFO - PROCESS | 30572 | -------- Summary: end -------- 14:55:40 INFO - PROCESS | 30572 | 14:55:41 INFO - TEST-INFO | 30572: exit 0 14:55:41 INFO - TEST-OK | dromaeo_css | took 1112947ms 14:55:41 INFO - TEST-START | kraken 14:55:41 INFO - operating with platform_type : linux_ 14:55:41 INFO - Initialising browser for kraken test... 14:55:41 INFO - TEST-INFO | started process 30760 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_5WPD7/profile http://localhost:46570/getInfo.html) 14:55:44 INFO - PROCESS | 30760 | JavaScript error: , line 0: uncaught exception: undefined 14:55:45 INFO - PROCESS | 30760 | __metrics Screen width/height:1600/1200 14:55:45 INFO - PROCESS | 30760 | colorDepth:24 14:55:45 INFO - PROCESS | 30760 | Browser inner width/height: 1024/697 14:55:45 INFO - PROCESS | 30760 | __metrics 14:55:46 INFO - PROCESS | 30760 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:55:46 INFO - PROCESS | 30760 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:55:47 INFO - TEST-INFO | 30760: exit 0 14:55:47 INFO - Browser initialized. 14:55:47 INFO - Running cycle 1/1 for kraken test... 14:55:47 INFO - TEST-INFO | started process 30844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_5WPD7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:59 INFO - PROCESS | 30844 | RSS: Main: 157958144 14:55:59 INFO - PROCESS | 30844 | 14:56:01 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46570/tests/kraken/driver.html?testName=audio-beat-detection) 14:56:02 INFO - PROCESS | 30844 | RSS: Main: 214110208 14:56:02 INFO - PROCESS | 30844 | 14:56:05 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46570/tests/kraken/driver.html?testName=audio-dft) 14:56:05 INFO - PROCESS | 30844 | RSS: Main: 192729088 14:56:05 INFO - PROCESS | 30844 | 14:56:08 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46570/tests/kraken/driver.html?testName=audio-fft) 14:56:09 INFO - PROCESS | 30844 | RSS: Main: 269885440 14:56:09 INFO - PROCESS | 30844 | 14:56:11 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46570/tests/kraken/driver.html?testName=audio-oscillator) 14:56:12 INFO - PROCESS | 30844 | RSS: Main: 181129216 14:56:12 INFO - PROCESS | 30844 | 14:56:14 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46570/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:56:14 INFO - PROCESS | 30844 | RSS: Main: 185364480 14:56:14 INFO - PROCESS | 30844 | 14:56:17 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46570/tests/kraken/driver.html?testName=imaging-darkroom) 14:56:18 INFO - PROCESS | 30844 | RSS: Main: 220246016 14:56:18 INFO - PROCESS | 30844 | 14:56:21 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46570/tests/kraken/driver.html?testName=imaging-desaturate) 14:56:22 INFO - PROCESS | 30844 | RSS: Main: 220913664 14:56:22 INFO - PROCESS | 30844 | 14:56:25 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46570/tests/kraken/driver.html?testName=json-parse-financial) 14:56:25 INFO - PROCESS | 30844 | RSS: Main: 221155328 14:56:25 INFO - PROCESS | 30844 | 14:56:27 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46570/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:56:27 INFO - PROCESS | 30844 | RSS: Main: 184946688 14:56:27 INFO - PROCESS | 30844 | 14:56:29 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:56:30 INFO - PROCESS | 30844 | RSS: Main: 190529536 14:56:30 INFO - PROCESS | 30844 | 14:56:32 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:56:32 INFO - PROCESS | 30844 | RSS: Main: 194908160 14:56:32 INFO - PROCESS | 30844 | 14:56:35 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:56:36 INFO - PROCESS | 30844 | RSS: Main: 190427136 14:56:36 INFO - PROCESS | 30844 | 14:56:38 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:56:39 INFO - PROCESS | 30844 | RSS: Main: 194015232 14:56:39 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | Cycle 1(1): loaded http://localhost:46570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46570/tests/kraken/driver.html?testName=ai-astar) 14:56:41 INFO - PROCESS | 30844 | RSS: Main: 245293056 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | __start_tp_report 14:56:41 INFO - PROCESS | 30844 | _x_x_mozilla_page_load 14:56:41 INFO - PROCESS | 30844 | _x_x_mozilla_page_load_details 14:56:41 INFO - PROCESS | 30844 | |i|pagename|runs| 14:56:41 INFO - PROCESS | 30844 | |0;ai-astar;101;110;109;110;111;112;110;110;110;110 14:56:41 INFO - PROCESS | 30844 | |1;audio-beat-detection;146;115;118;177;117;119;146;121;163;118 14:56:41 INFO - PROCESS | 30844 | |2;audio-dft;161;160;159;159;159;159;160;161;161;160 14:56:41 INFO - PROCESS | 30844 | |3;audio-fft;74;74;75;120;74;76;75;75;99;74 14:56:41 INFO - PROCESS | 30844 | |4;audio-oscillator;84;84;116;84;84;84;122;83;85;134 14:56:41 INFO - PROCESS | 30844 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;118;116;116 14:56:41 INFO - PROCESS | 30844 | |6;imaging-darkroom;146;146;146;145;146;145;146;145;146;145 14:56:41 INFO - PROCESS | 30844 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 14:56:41 INFO - PROCESS | 30844 | |8;json-parse-financial;54;54;52;53;53;53;54;53;53;60 14:56:41 INFO - PROCESS | 30844 | |9;json-stringify-tinderbox;63;64;63;63;64;63;63;63;64;63 14:56:41 INFO - PROCESS | 30844 | |10;stanford-crypto-aes;78;82;75;76;79;81;75;75;84;78 14:56:41 INFO - PROCESS | 30844 | |11;stanford-crypto-ccm;123;141;119;120;117;135;119;128;170;125 14:56:41 INFO - PROCESS | 30844 | |12;stanford-crypto-pbkdf2;160;163;158;164;156;163;157;159;161;157 14:56:41 INFO - PROCESS | 30844 | |13;stanford-crypto-sha256-iterative;64;63;63;63;63;62;62;61;61;61 14:56:41 INFO - PROCESS | 30844 | __end_tp_report 14:56:41 INFO - PROCESS | 30844 | __start_cc_report 14:56:41 INFO - PROCESS | 30844 | _x_x_mozilla_cycle_collect,1647 14:56:41 INFO - PROCESS | 30844 | __end_cc_report 14:56:41 INFO - PROCESS | 30844 | __startTimestamp1469224601309__endTimestamp 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | ------- Summary: start ------- 14:56:41 INFO - PROCESS | 30844 | Number of tests: 14 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:3.02 (2.7%) stddev-sans-first:0.83 14:56:41 INFO - PROCESS | 30844 | Values: 101.0 110.0 109.0 110.0 111.0 112.0 110.0 110.0 110.0 110.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:133.50 stddev:22.44 (16.8%) stddev-sans-first:23.38 14:56:41 INFO - PROCESS | 30844 | Values: 146.0 115.0 118.0 177.0 117.0 119.0 146.0 121.0 163.0 118.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 14:56:41 INFO - PROCESS | 30844 | Values: 161.0 160.0 159.0 159.0 159.0 159.0 160.0 161.0 161.0 160.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:15.53 (20.7%) stddev-sans-first:16.23 14:56:41 INFO - PROCESS | 30844 | Values: 74.0 74.0 75.0 120.0 74.0 76.0 75.0 75.0 99.0 74.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:84.00 stddev:19.80 (23.6%) stddev-sans-first:20.52 14:56:41 INFO - PROCESS | 30844 | Values: 84.0 84.0 116.0 84.0 84.0 84.0 122.0 83.0 85.0 134.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 14:56:41 INFO - PROCESS | 30844 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 116.0 116.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 14:56:41 INFO - PROCESS | 30844 | Values: 146.0 146.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:56:41 INFO - PROCESS | 30844 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:2.23 (4.2%) stddev-sans-first:2.37 14:56:41 INFO - PROCESS | 30844 | Values: 54.0 54.0 52.0 53.0 53.0 53.0 54.0 53.0 53.0 60.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 14:56:41 INFO - PROCESS | 30844 | Values: 63.0 64.0 63.0 63.0 64.0 63.0 63.0 63.0 64.0 63.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.50 stddev:3.20 (4.1%) stddev-sans-first:3.39 14:56:41 INFO - PROCESS | 30844 | Values: 78.0 82.0 75.0 76.0 79.0 81.0 75.0 75.0 84.0 78.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:126.50 stddev:16.10 (12.7%) stddev-sans-first:16.90 14:56:41 INFO - PROCESS | 30844 | Values: 123.0 141.0 119.0 120.0 117.0 135.0 119.0 128.0 170.0 125.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:160.50 stddev:2.86 (1.8%) stddev-sans-first:3.03 14:56:41 INFO - PROCESS | 30844 | Values: 160.0 163.0 158.0 164.0 156.0 163.0 157.0 159.0 161.0 157.0 14:56:41 INFO - PROCESS | 30844 | 14:56:41 INFO - PROCESS | 30844 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:63.00 stddev:1.06 (1.7%) stddev-sans-first:0.93 14:56:41 INFO - PROCESS | 30844 | Values: 64.0 63.0 63.0 63.0 63.0 62.0 62.0 61.0 61.0 61.0 14:56:41 INFO - PROCESS | 30844 | -------- Summary: end -------- 14:56:41 INFO - PROCESS | 30844 | 14:56:42 INFO - TEST-INFO | 30844: exit 0 14:56:42 INFO - TEST-OK | kraken | took 60553ms 14:56:42 INFO - SUITE-END | took 1173s 14:56:42 INFO - Completed test suite (00:19:33) 14:56:42 INFO - javascript benchmark 14:56:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3053.0, 3062.9693703062962, 3091.227193201701, 3094.76789240807, 3100.7984481008734, 5166.250893620423, 5231.921521177176, 5332.946670533293, 5369.91945120823, 5393.676379417233, 5260.263563101169, 5271.920921186185, 5291.947080529194, 5363.758630861611, 5397.919031214525, 4848.321235027099, 4864.221724524085, 4886.902261954759, 4898.51014898509, 5004.0, 4925.82759603414, 4959.330490383806, 4968.40379154502, 4968.70187788732, 4992.925106123411, 4766.142094422985, 4806.711597304138, 4817.710937343771, 4884.902301953959, 4908.460069392382, 4768.61851051915, 4769.976150119227, 4799.11216424959, 4806.879828004293, 4869.610431165498, 4767.404074490689, 4768.976155119202, 4815.66290359675, 4824.975875120637, 4859.0, 5178.0679477694, 5252.107141785906, 5342.198670199501, 5344.198370244456, 5377.0052540279785, 5664.546836253131, 5719.14212868069, 5722.942770572283, 5729.828105156852, 5749.568782341299, 5710.172025056385, 5743.224664670258, 5789.507891829199, 5789.652620842763, 5789.739461724233, 5173.430922598511, 5285.207218917193, 5341.5459685927135, 5393.676379417208, 5401.135818269058, 5848.853778655568, 5890.175375447441, 5893.675847828325, 5893.852653683693, 5897.0, 5626.578006649518, 5630.042892708249, 5633.6619802811965, 5635.690037048002, 5637.49262566371, 5558.249636299129, 5583.720813959367, 5636.915446268309, 5709.714514274353, 5711.428857114339, 5643.887122257532, 5646.632968857011, 5652.095664693629, 5693.857653558694, 5706.857328566819, 5601.159826026129, 5639.548836093103, 5640.605157638925, 5646.20953066571, 5649.209110724501, 4181.60274773896, 4295.269804133305, 4330.242207613655, 4351.608355248043, 4391.6486681065435, 3896.376579747227, 3904.0630248740667, 3907.531096268466, 3934.409838524244, 3939.763614183158, 5400.1899715043055, 5408.621396502286, 5410.675359478444, 5412.702301373462, 5460.235567020621, 5470.617056806065, 5703.258576385127, 5728.684922329228, 5760.481556659921, 5772.249607550992, 5414.7834086636885, 5461.563074953994, 5467.316585426822, 5481.643693159932, 5482.068048431776, 4986.301917731448, 4993.7503124843615, 4997.475265097112, 4999.42506611737, 5046.47012063728, 5661.2923384576925, 5737.627054241461, 5759.596828222068, 5802.79690210849, 5881.441263080001, 5679.034564124191, 5688.260526131577, 5689.800856969986, 5694.914576281442, 5728.484436400744, 5807.796727114531, 5809.128630705428, 5813.94186058134, 5817.680027598522, 5818.970905145447, 5413.539849112712, 5554.333479982428, 5576.163575463551, 5584.134459158674, 5614.410486898817, 5644.435556444487, 5748.683822389641, 5751.482366587027, 5751.683657398883, 5788.623739456922, 5598.664080155204, 5601.103823388238, 5606.103023516218, 5608.775648974159, 5610.579206559606, 5393.595480339069, 5554.750036248298, 5594.692291923983, 5603.187537807076, 5618.634788738882, 5578.77684892616, 5579.302587176603, 5580.107182850725, 5580.609357344947, 5615.410381909841, 5658.971705141448, 5678.3753787084215, 5713.8285851424525, 5719.513841323368, 5727.627704199213, 5322.121849894724, 5412.052890744183, 5474.534664553398, 5485.945140548703, 5550.611457197957], "value": 5257.50339428307, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.2838879150623, 601.38958956659, 616.4144063140018, 626.3392121312015, 649.1366482578165, 43276.78361608191, 44795.77602111989, 44856.55143448564, 44944.0, 48009.51990480094, 45693.31460028094, 45799.0, 46006.0, 46043.76978115113, 46143.30785038219, 8960.8655870162, 9066.229370503515, 9095.045020272843, 9107.180353768159, 9118.17936385725, 8945.239654629364, 9033.367664263504, 9041.321900857429, 9046.411983221102, 9052.502112383816, 9077.13767192119, 9110.45337279762, 9122.589483473259, 9123.133302336268, 9124.45253284805, 9005.369624126313, 9027.4132181408, 9031.864522032109, 9046.411983221069, 9053.728388148333, 8985.101489851028, 9067.68263110788, 9070.909290907073, 9071.727848164499, 9078.636854525812, 42712.57287427117, 42764.0, 43036.0, 43193.0, 43199.35200971988, 3251.0, 3412.334594754024, 3441.1913200397885, 3478.4086705260165, 3566.5006899034156, 32669.346613067606, 32888.0, 33353.49969750456, 33998.32003359943, 34929.0, 4355.150745604608, 4386.517483076891, 4406.162829062468, 4430.113977204572, 4439.200943830109, 357591.0, 358546.0, 360486.0, 362526.0, 363421.0, 56972.430275696715, 57360.0, 57361.71319143377, 57374.7131264341, 57424.42575574274, 54257.72871135619, 59338.70330648319, 60040.0, 60954.0, 61325.0, 16829.0, 17070.743938840926, 17092.40176593813, 17112.914435427992, 17118.40085596998, 13240.867591324157, 13335.0, 13335.399907004208, 13342.799858002138, 13345.0, 14593.19737414431, 14849.480268190562, 16052.27764750589, 16486.75269870953, 16609.501714948572, 5749.338826034976, 5967.761289548456, 6063.272407311151, 6070.3019152797115, 6088.452039316394, 997.2570435025807, 1064.0523657231602, 1073.7423018475665, 1102.922795404314, 1113.7939481196004, 32391.0, 32484.512732309035, 32616.673833261364, 32630.673693262765, 32714.0, 1114.0586204657052, 1125.9528638366235, 1171.654361963223, 1177.1524502358288, 1224.4245204753752, 16479.752803707954, 17069.31722731077, 17196.764420625237, 17350.39273625417, 17628.20673069715, 530214.0, 533848.0, 534441.0, 534585.0, 535591.0, 58152.70923645355, 58437.4156258432, 58582.0, 58591.414085858596, 58592.0, 1362.3801170467414, 1385.8267716535433, 1401.866822651826, 1422.4523558429871, 1425.4583258361756, 30829.845850770602, 31929.0, 32329.51505727463, 32336.35327293394, 32965.34069318648, 51124.488755111975, 51613.483865160866, 51675.483245167066, 51713.0, 51758.74120629373, 468746.0, 471749.0, 472656.0, 474197.0, 474292.0, 35745.1063723409, 41440.79279603583, 41866.37200442057, 42627.57372426236, 42628.08173632256, 42412.0, 45613.77193114013, 48829.0, 49021.0, 49410.258846116616, 37280.62719372771, 38736.6126338733, 39686.40470393004, 40020.09810261944, 40075.599244007186, 39178.80410597929, 39297.0, 39965.60034399735, 40956.59043409647, 41068.0, 40562.0, 41048.0, 41695.0, 41881.0, 41931.37102943519, 32964.83517582493, 35539.28921421505, 35801.64198358087, 43989.34015989827, 45675.77162114301, 32241.51637725389, 35115.473267901514, 35374.46938295876, 35387.82306088556, 35440.82279588689], "value": 20537.30324559578, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.66541614422806, 378.39456869009587, 379.609002727191, 390.8045977011494, 393.38238964825234, 17830.019348935803, 19436.70844937327, 19787.50531236718, 20087.497812554677, 20124.899375503137, 19123.948182849937, 19182.61634767304, 19263.229470821152, 19429.0, 19700.901495492504, 5044.915343201211, 5390.595705322106, 5575.526080283161, 5577.749001294951, 5641.576881733866, 5434.673919564839, 5522.392536820947, 5523.337199536061, 5525.502704756571, 5526.58550608706, 5428.782848686048, 5446.210299506589, 5493.313335833021, 5500.80747173849, 5522.144067669517, 5427.050266203419, 5496.560275177976, 5528.06022976095, 5537.889242215153, 5552.139418390156, 4744.739039352834, 4953.975230123826, 4961.70229786214, 4968.776405061781, 5004.699718016895, 12822.294773787438, 12921.935390322988, 12958.222506649538, 13003.414846331938, 13006.284654344103, 2253.9436514087115, 2299.8735069571167, 2330.557194133109, 2389.6773935518654, 2429.6477010833332, 9561.426314421087, 9574.521273936345, 9585.233181345475, 9785.11933925943, 10056.145227655657, 1834.8073452287451, 1906.9427917162507, 1915.4856662136808, 1941.5825597496605, 1991.9701204481944, 75151.62424187844, 78071.0, 78946.6052669733, 80112.59943700302, 83013.16986830115, 10334.948325258325, 10347.534360953625, 10514.158867290598, 10584.206184536191, 10635.255532112678, 14464.566063018126, 14514.564563063124, 14518.346674399678, 14522.128672279698, 14526.85473145255, 7830.0, 7909.169537198626, 8086.474379165453, 8100.918990810134, 8317.667293308321, 7732.604885064561, 7746.0, 7769.922300777033, 7931.0, 8412.369072319601, 4420.248557745191, 4428.579284967923, 4455.866324010285, 4610.05493873755, 4618.607418369409, 1788.7227479740661, 1791.4446521578093, 1835.5319393554557, 1892.7350170976074, 1904.64764018657, 1012.4128005756644, 1032.5405194688292, 1047.219821233171, 1082.9495389472356, 1100.4937728644913, 19501.219951202078, 19570.412887613395, 19605.0, 19622.31321903755, 19643.901780491007, 972.5380444288907, 998.1964518562481, 1027.1679939249232, 1033.937963722164, 1069.3316677076828, 10292.382457052601, 10733.624323148808, 10735.78528429427, 10787.730306742395, 10819.188560857967, 478657.0, 486735.0, 514107.0, 514793.0, 525778.0, 86674.13325866661, 88241.0, 90504.0, 92372.53813730889, 94469.0, 971.0774763974252, 996.2030375699557, 1009.9596016159418, 1012.2003617142375, 1012.9797404052025, 16299.674006520041, 16491.83508164903, 16549.91725041367, 17493.03788291656, 17873.0, 41032.58967410288, 41816.2819416737, 42126.36810447907, 42292.78853605712, 43014.78492607517, 58523.70738146282, 60083.69958150356, 60193.0, 60406.0, 61786.69106654618, 8112.351011918914, 8278.254957053894, 8326.25063744266, 8345.581251187434, 8415.200555947175, 11395.601153959571, 12908.806367904677, 12918.935405322913, 12947.805782913452, 13113.08208425401, 11011.61459348919, 11228.66314010581, 11409.714757131138, 11493.712657183638, 11544.076473881902, 10227.181825454094, 11226.607068752555, 11315.71710707206, 11321.094312455149, 11425.771484570429, 11311.830322545004, 11518.136139789382, 11746.765064698488, 11820.822687659864, 11848.466818992994, 8190.180981901762, 8205.343572514304, 8218.260356567698, 8284.337253019865, 8602.827943441222, 8278.751637450887, 10465.738356541147, 11000.119990400588, 11357.0914326852, 11418.0], "value": 8875.929861865367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.2495390104584, 1452.644102194962, 1520.429838810446, 1608.5898095985522, 1792.399546152039, 8372.120927302629, 8773.605187766558, 8779.2537634301, 8793.692220772266, 8799.56002199889, 8636.697715579956, 8711.08533603972, 8721.956390218056, 8727.476351418904, 8728.869066964, 2328.8020518255967, 2357.422431504284, 2359.9174028908906, 2359.9882000589973, 2365.006697187177, 2341.8126549876056, 2352.9058837646476, 2402.039184326266, 2402.6396040593963, 2403.5553422616786, 2336.9649455258186, 2343.95312093758, 2355.9293221203393, 2362.1024010875844, 2372.4306166519996, 2310.5378924215056, 2313.3060081975423, 2332.428555004035, 2338.275134708229, 2343.238447504559, 2263.1400067974228, 2273.4430064634107, 2277.9976810203457, 2279.111146652807, 2279.41874821921, 8706.0, 8710.21608055278, 8765.254953328975, 8834.690785822466, 8842.0, 5028.371453568304, 5182.32629758129, 5184.585233181337, 5188.299579556787, 5192.428832828386, 3076.353908762349, 3199.40810949975, 3218.5976752905885, 3249.252671885456, 3273.623533293678, 1057.6456886942883, 1061.43743815777, 1062.6705721226367, 1067.151614466501, 1090.1551297744231, 11852.940735296355, 11854.762904741943, 11876.0, 11884.524619015316, 11934.283942963362, 4234.809433575497, 4350.0647360817575, 4444.688871779009, 4488.910221795546, 4489.057297967398, 9548.236141108695, 9572.32993690435, 9580.904190958141, 9596.18432433251, 9609.039096090335, 5140.151874940668, 5169.198774190007, 5172.250023746536, 5173.922391164136, 5181.948180518222, 4186.3092589722355, 4690.577847993697, 4825.734584597881, 4835.202191638411, 4865.148598995162, 3696.482492451059, 3797.373433383461, 3836.5204349456317, 3854.9807250963568, 3905.804709764557, 1118.429600903545, 1128.5485805677795, 1129.4239937631867, 1130.4347826086957, 1138.6697857621364, 891.5765011619559, 998.6604554451521, 1011.5650270383659, 1013.4932533733133, 1018.6027449294781, 6017.247844019497, 6035.426634469718, 6082.60463069899, 6329.082283068929, 6383.521235907326, 433.3456480714144, 435.6166573415374, 440.8581773207408, 445.58337954013103, 455.9110973360229, 4162.542120366757, 4320.1143765527995, 4369.257226271541, 4374.496932852699, 4511.187986162522, 10226.488675566192, 10487.213458990605, 10515.106215971575, 10519.474026298654, 10538.63114790994, 10463.0, 10470.581176752845, 10471.0, 10479.790404192026, 10482.528286227138, 7826.843463130819, 8004.599770011476, 8093.433459657886, 8110.432269741062, 8160.510369377975, 6096.375572625671, 6181.690915454119, 6195.659238741912, 6199.597026193283, 6319.873602528016, 10035.19718422539, 10191.694249172537, 10194.286399951932, 10221.437820919624, 10241.23190760681, 9669.371490853073, 10034.397936123856, 10059.949700251453, 10170.949145254226, 10246.282760207012, 6960.547564408297, 7708.884366734392, 7791.883121753292, 7912.881306780518, 7989.7203597873795, 8600.612972416131, 8729.51987640686, 8731.956340218258, 8734.082921293173, 8762.912370876209, 7597.392208623186, 7661.923380766121, 7793.649285782267, 7829.217078292125, 7873.724419645285, 7483.812904677427, 7628.466007379652, 7645.311921927053, 7657.731979380917, 7690.2694244046725, 6975.79072627822, 7102.893456598052, 7103.2541583133025, 7103.573785572882, 7120.2167761547225, 5193.480651934777, 5303.522682958553, 5336.493033161843, 5370.704611246419, 5562.137868630369, 5138.588912886886, 5242.239875217996, 5309.2301616266195, 5340.572754179734, 5573.832785016456], "value": 4505.9352250734955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.589223924864, 311.98440077996094, 312.92020534763634, 313.5985937999361, 319.068320504128, 5302.284191634129, 5350.973245133774, 5354.625176237665, 5384.788422604912, 5407.297051383314, 4984.651074424783, 5106.08090543702, 5110.105731496992, 5130.794768209276, 5265.526102650771, 3980.0099950024987, 4016.9397459038064, 4080.326746086892, 4089.4683691120113, 4124.072083781155, 4106.0, 4112.259793237216, 4116.670666346685, 4132.483439570054, 4135.8966025849295, 3872.7482713623667, 4053.6351728344443, 4054.209429161315, 4094.9385759213637, 4103.0, 4030.7380020298588, 4053.97973010136, 4061.939070913939, 4063.9187216255805, 4069.9186016279655, 3580.5703315602036, 3583.122135076897, 3584.4085725855202, 3585.4621806728937, 3587.766795158306, 4905.007945153454, 5135.281060651511, 5153.098207813618, 5166.664166829144, 5174.146265866128, 4627.953720462786, 4659.603933665609, 4660.5339466053465, 4660.673752837302, 4671.626269898434, 5446.502211891722, 5500.779968801243, 5507.504324610764, 5507.7246137692955, 5511.807086751945, 3818.885433437001, 3919.196564704229, 3935.3113205189265, 3942.7437216581116, 3943.7830919299427, 5637.689927054011, 5674.858128546778, 5686.317641883001, 5702.372738998707, 5713.342965558931, 3992.840286388512, 3993.4009898514873, 4006.0585762345827, 4006.6193711597352, 4008.7995600219874, 4045.150518391171, 4052.6352628263007, 4060.147369052532, 4061.878143655695, 4063.6342729154517, 4529.527903431398, 4624.815007399734, 4631.444226692818, 4641.419822522184, 4648.581627653527, 5512.641678290898, 5517.889642207133, 5521.254630624814, 5528.668279903219, 5540.085885828874, 1321.14125818217, 1389.1248513436672, 1394.9790753138711, 1395.0931894268604, 1400.0969374753226, 919.3748251189255, 926.2867591954158, 939.8919124300793, 942.6465075596651, 946.3659348236698, 5264.868378290574, 5275.366955965309, 5288.92066619001, 5298.655587386807, 5312.9734351328, 5484.2596249506605, 5610.943890561124, 5614.989301925611, 5616.326040875039, 5620.522255608237, 5218.712970786567, 5242.640811634703, 5252.79185787271, 5266.4470230626, 5271.235670827708, 2949.439606474763, 2977.1366303772097, 2978.195887110468, 2983.1796256029766, 2996.8801247950273, 5540.418256083134, 5663.1505274208375, 5668.51817595509, 5725.484706376446, 5761.567882408836, 5686.744096515667, 5703.885922281531, 5705.5720820938595, 5707.315122185364, 5716.628419152825, 5693.202951586781, 5721.799737009268, 5723.255976723, 5727.885442291131, 5728.312602487728, 5353.892922141457, 5479.863003424787, 5508.586855985897, 5574.442555744556, 5586.190002449704, 5507.026205848797, 5519.972400137974, 5523.696196709219, 5528.944710552843, 5539.41836107219, 5543.311493925362, 5700.686462244616, 5720.2849643794525, 5722.884037612625, 5727.341355744143, 3934.901627459222, 3976.542697589814, 3977.8607748729814, 3979.840806367713, 3982.3429134192534, 5549.947528859192, 5571.665700057847, 5579.46995035471, 5586.888262234814, 5591.860203494945, 5236.293100431545, 5413.729313534386, 5449.754761035683, 5453.454654534515, 5466.34403871538, 5410.810621628224, 5415.729213539228, 5426.782928682808, 5452.318460192476, 5453.563714902719, 5223.555997740083, 5301.416844147064, 5349.331333583302, 5358.169483730028, 5402.729863506887, 5473.425290344457, 5507.284053073075, 5515.200295957144, 5523.558115350682, 5612.607117501736, 5404.270423492778, 5446.782128714807, 5477.20580515831, 5480.671159730429, 5493.285872836505], "value": 4164.081056117339, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.8031571633518, 748.9737859174927, 770.8150043989444, 819.4714409206061, 887.2768693514782, 15298.69402611947, 16328.83671163288, 16345.75481367779, 16353.591160221, 16413.425530106448, 13894.930525347385, 13940.651483712887, 13980.93009534951, 14024.929875350634, 14086.225257610828, 646.1793522226781, 785.7956931197901, 811.2536466450879, 815.8368326334727, 819.4427789103407, 815.2214635023538, 815.5269943432795, 815.6655771133836, 817.0154963269247, 817.6157206113118, 698.2110215456527, 867.1501928110456, 871.777696687347, 872.5462759365126, 873.4802792338548, 838.2748922182299, 854.8717692346136, 861.9181177788131, 863.1368631368631, 863.8445079885618, 632.3581564711822, 632.4624069540899, 632.8196464007782, 633.0472638678805, 633.1168020611268, 15084.17037062961, 15085.849141508665, 15136.243187840673, 15158.166300853449, 15174.24128793556, 14197.503087391995, 14285.21431321277, 14309.499167529191, 14379.424823007068, 14445.638859028504, 13180.209187448689, 13204.801927971088, 13298.667533311647, 13305.800412993813, 13409.865901341056, 4429.291313389875, 4443.155800397914, 4491.146682130385, 4494.662900282492, 4571.565701258375, 72960.63519682421, 73042.63478682573, 73297.63351183264, 73343.0, 75747.62126189389, 11026.558937642565, 11060.557577696964, 11117.388543630017, 11270.323780573028, 11380.146489013192, 19968.30110946139, 20081.0, 20123.899380503295, 20126.597468050557, 20131.19475221004, 7788.221177882167, 8095.323633315272, 8126.10612832588, 8166.4283500154415, 8322.334213263046, 4390.846320378771, 4498.820047198075, 4532.546745325507, 4578.656600754956, 4694.155052090657, 3400.0819778659625, 3488.0582242794303, 3544.078539579728, 3547.609762926076, 3550.538430004083, 587.1955421073072, 592.2981267198297, 595.9106134079923, 609.9969500152471, 617.3240301869446, 554.6838302167806, 578.1934201788481, 588.1736160694231, 591.4381337729175, 608.1091201389985, 8592.613332400057, 8951.462912225288, 9018.23345015681, 9124.908750912538, 9143.359964802534, 613.9508839292846, 622.7384498510636, 646.7994921574327, 657.6054367379534, 662.1590579963515, 4245.724027938174, 4477.865664030084, 4515.796789144496, 4521.276595744665, 4642.65180111493, 113725.0, 114784.0, 115668.42165789117, 117359.41320293515, 120657.39671301587, 102756.0, 105291.0, 106592.46703766432, 107044.0, 107701.0, 87535.56232218798, 89319.55340223257, 90367.54816225877, 91138.0, 93866.0, 8450.0, 8490.235878770693, 8810.339224558056, 9861.260405469475, 10174.898251017396, 50503.747481262355, 51526.22710659418, 51734.482655172964, 52355.0, 53207.46792532025, 72437.63781181061, 74946.62526687332, 75666.62166689131, 76247.0, 77216.0, 12745.553905613258, 13133.802992954923, 13251.602451926457, 13350.866491334962, 13498.52755153565, 15091.773623395877, 15160.696786064438, 15212.46982743549, 15868.920655396649, 16184.190790460665, 14038.384109515777, 14289.71420571562, 14464.27678616086, 14638.780418293947, 14710.338034788247, 13703.862961370258, 13705.588832335046, 13804.654883627989, 13895.791563126359, 14284.214368209849, 13370.933145334211, 13919.164850109026, 14096.718065638835, 14149.151050936976, 14582.562523124323, 11464.484098215768, 11645.534178632759, 11928.224665396721, 12000.579979701019, 12038.398080095785, 11131.220814542905, 12055.0, 12073.917090851486, 12090.274583525017, 12375.504979800708], "value": 7557.065715495547, "unit": "score"}], "name": "dromaeo_css", "value": 7170.348439830727, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 109.0, 110.0, 111.0, 112.0, 110.0, 110.0, 110.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 118.0, 177.0, 117.0, 119.0, 146.0, 121.0, 163.0, 118.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 159.0, 159.0, 159.0, 160.0, 161.0, 161.0, 160.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 120.0, 74.0, 76.0, 75.0, 75.0, 99.0, 74.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 84.0, 84.0, 84.0, 122.0, 83.0, 85.0, 134.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 116.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 53.0, 53.0, 53.0, 54.0, 53.0, 53.0, 60.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 75.0, 76.0, 79.0, 81.0, 75.0, 75.0, 84.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 141.0, 119.0, 120.0, 117.0, 135.0, 119.0, 128.0, 170.0, 125.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 158.0, 164.0, 156.0, 163.0, 157.0, 159.0, 161.0, 157.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 63.0, 62.0, 62.0, 61.0, 61.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1485.7, "alertThreshold": 2.0}]} 14:56:42 INFO - Return code: 0 14:56:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:56:42 INFO - # TBPL SUCCESS # 14:56:42 INFO - Running post-action listener: _resource_record_post_action 14:56:42 INFO - [mozharness: 2016-07-22 21:56:42.638488Z] Finished run-tests step (success) 14:56:42 INFO - Running post-run listener: _resource_record_post_run 14:56:42 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555012096; Read time: 40; Write time: 469468 14:56:42 INFO - TinderboxPrint: CPU usage
12.9% 14:56:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 40 14:56:42 INFO - TinderboxPrint: I/O write bytes / time
555,012,096 / 469,468 14:56:42 INFO - TinderboxPrint: CPU idle
8,275.2 (87.1%) 14:56:42 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 14:56:42 INFO - TinderboxPrint: Swap in / out
0 / 0 14:56:42 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257441792; Read time: 0; Write time: 261500 14:56:42 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 297570304; Read time: 40; Write time: 207968 14:56:43 INFO - Running post-run listener: _upload_blobber_files 14:56:43 INFO - Blob upload gear active. 14:56:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:56:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:56:43 INFO - (blobuploader) - INFO - Open directory for files ... 14:56:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:49 INFO - (blobuploader) - INFO - Done attempting. 14:56:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:56:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:50 INFO - (blobuploader) - INFO - Done attempting. 14:56:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:56:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:56:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:51 INFO - (blobuploader) - INFO - Done attempting. 14:56:51 INFO - (blobuploader) - INFO - Iteration through files over. 14:56:51 INFO - Return code: 0 14:56:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:56:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:56:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/daca9d29c58c1acbc35aa6afae7ee0e28b8359554372a37efdd8ef2ee952cf4326503d3613615de15c4b2afef2892c6f73a14e3c49c82ec0c9943c355f036b83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e30fafa8ef3f5345f4e9e6288df92cf0106a8121ae58c77c7d21cd204593f9f83a80add7fafbae8b5ca5a9ee26812879e88a1d784aaea6c92ad25efcc3b1eed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b88618b52a2b13a19a5353c5d4f1e7e80966bd46ce2eba4af64f732445157263bf244afb164014ad247dcb595273007874fdf5dd7f092193e649f98e7db0664"} 14:56:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:56:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:56:51 INFO - Contents: 14:56:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/daca9d29c58c1acbc35aa6afae7ee0e28b8359554372a37efdd8ef2ee952cf4326503d3613615de15c4b2afef2892c6f73a14e3c49c82ec0c9943c355f036b83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e30fafa8ef3f5345f4e9e6288df92cf0106a8121ae58c77c7d21cd204593f9f83a80add7fafbae8b5ca5a9ee26812879e88a1d784aaea6c92ad25efcc3b1eed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b88618b52a2b13a19a5353c5d4f1e7e80966bd46ce2eba4af64f732445157263bf244afb164014ad247dcb595273007874fdf5dd7f092193e649f98e7db0664"} 14:56:51 INFO - Running post-run listener: copy_logs_to_upload_dir 14:56:51 INFO - Copying logs to upload dir... 14:56:51 INFO - mkdir: /builds/slave/test/build/upload/logs 14:56:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.586631 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-22 14:56:51.464825) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 14:56:51.468889) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/daca9d29c58c1acbc35aa6afae7ee0e28b8359554372a37efdd8ef2ee952cf4326503d3613615de15c4b2afef2892c6f73a14e3c49c82ec0c9943c355f036b83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e30fafa8ef3f5345f4e9e6288df92cf0106a8121ae58c77c7d21cd204593f9f83a80add7fafbae8b5ca5a9ee26812879e88a1d784aaea6c92ad25efcc3b1eed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b88618b52a2b13a19a5353c5d4f1e7e80966bd46ce2eba4af64f732445157263bf244afb164014ad247dcb595273007874fdf5dd7f092193e649f98e7db0664"} build_url:https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011303 build_url: 'https://queue.taskcluster.net/v1/task/EIQQ5LOYSbKJiW8SZWepdA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/daca9d29c58c1acbc35aa6afae7ee0e28b8359554372a37efdd8ef2ee952cf4326503d3613615de15c4b2afef2892c6f73a14e3c49c82ec0c9943c355f036b83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e30fafa8ef3f5345f4e9e6288df92cf0106a8121ae58c77c7d21cd204593f9f83a80add7fafbae8b5ca5a9ee26812879e88a1d784aaea6c92ad25efcc3b1eed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b88618b52a2b13a19a5353c5d4f1e7e80966bd46ce2eba4af64f732445157263bf244afb164014ad247dcb595273007874fdf5dd7f092193e649f98e7db0664"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 14:56:51.494670) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:56:51.495003) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1469223378.169491-586789734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:56:51.580038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 14:56:51.580366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 14:56:51.580672) ========= ========= Total master_lag: 0.17 =========