builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1469214610.45 results: success (0) buildid: 20160722112426 builduid: b25ecc2c786a42d8ae34c65bffbe6118 revision: 6df3913433336ceaa463d831fef7c0ee5a2efa21 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.451174) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.451939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.452447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.467045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.467332) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 12:10:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.0M=0.001s 2016-07-22 12:10:11 (20.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162347 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.643700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.644019) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016416 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.693391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:10.693767) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6df3913433336ceaa463d831fef7c0ee5a2efa21 --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 6df3913433336ceaa463d831fef7c0ee5a2efa21 --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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 12:10:11,699 truncating revision to first 12 chars 2016-07-22 12:10:11,699 Setting DEBUG logging. 2016-07-22 12:10:11,699 attempt 1/10 2016-07-22 12:10:11,699 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6df391343333?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 12:10:12,017 unpacking tar archive at: fx-team-6df391343333/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675107 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:11.382333) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:11.382677) ========= script_repo_revision: 6df3913433336ceaa463d831fef7c0ee5a2efa21 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:11.383061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:11.383330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:10:11.393403) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-22 12:10:11.393677) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:10:12 INFO - MultiFileLogger online at 20160722 12:10:12 in /builds/slave/test 12:10:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:10:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:12 INFO - {'append_to_log': False, 12:10:12 INFO - 'base_work_dir': '/builds/slave/test', 12:10:12 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:10:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:12 INFO - 'branch': 'Fx-Team-Non-PGO', 12:10:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:12 INFO - 'config_files': ('talos/linux_config.py',), 12:10:12 INFO - 'default_actions': ('clobber', 12:10:12 INFO - 'read-buildbot-config', 12:10:12 INFO - 'download-and-extract', 12:10:12 INFO - 'populate-webroot', 12:10:12 INFO - 'create-virtualenv', 12:10:12 INFO - 'install', 12:10:12 INFO - 'run-tests'), 12:10:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:12 INFO - 'download_minidump_stackwalk': True, 12:10:12 INFO - 'download_symbols': 'ondemand', 12:10:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:10:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:10:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:10:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:10:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:12 INFO - 'installer_path': 'installer.exe', 12:10:12 INFO - 'log_level': 'info', 12:10:12 INFO - 'log_name': 'talos', 12:10:12 INFO - 'log_to_console': True, 12:10:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:10:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:10:12 INFO - 'opt_config_files': (), 12:10:12 INFO - 'pip_index': False, 12:10:12 INFO - 'sps_profile': False, 12:10:12 INFO - 'sps_profile_interval': 0, 12:10:12 INFO - 'suite': 'dromaeojs', 12:10:12 INFO - 'system_bits': '32', 12:10:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:12 INFO - 'title': 'talos-linux64-ix-059', 12:10:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:12 INFO - 'use_talos_json': True, 12:10:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:12 INFO - 'work_dir': 'build'} 12:10:12 INFO - [mozharness: 2016-07-22 19:10:12.494790Z] Running clobber step. 12:10:12 INFO - Running pre-action listener: _resource_record_pre_action 12:10:12 INFO - Running main action method: clobber 12:10:12 INFO - rmtree: /builds/slave/test/build 12:10:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:12 INFO - Running post-action listener: _resource_record_post_action 12:10:12 INFO - [mozharness: 2016-07-22 19:10:12.963525Z] Finished clobber step (success) 12:10:12 INFO - [mozharness: 2016-07-22 19:10:12.963626Z] Running read-buildbot-config step. 12:10:12 INFO - Running pre-action listener: _resource_record_pre_action 12:10:12 INFO - Running main action method: read_buildbot_config 12:10:12 INFO - Using buildbot properties: 12:10:12 INFO - { 12:10:12 INFO - "project": "", 12:10:12 INFO - "product": "firefox", 12:10:12 INFO - "script_repo_revision": "production", 12:10:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:10:12 INFO - "repository": "", 12:10:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:10:12 INFO - "buildid": "20160722112426", 12:10:12 INFO - "pgo_build": "False", 12:10:12 INFO - "basedir": "/builds/slave/test", 12:10:12 INFO - "buildnumber": 3193, 12:10:12 INFO - "slavename": "talos-linux64-ix-059", 12:10:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:10:12 INFO - "platform": "ubuntu64_hw", 12:10:12 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:10:12 INFO - "branch": "fx-team", 12:10:12 INFO - "repo_path": "integration/fx-team", 12:10:12 INFO - "revision": "6df3913433336ceaa463d831fef7c0ee5a2efa21", 12:10:12 INFO - "stage_platform": "linux64", 12:10:12 INFO - "builduid": "b25ecc2c786a42d8ae34c65bffbe6118", 12:10:12 INFO - "slavebuilddir": "test" 12:10:12 INFO - } 12:10:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 12:10:12 INFO - Running post-action listener: _resource_record_post_action 12:10:12 INFO - [mozharness: 2016-07-22 19:10:12.966070Z] Finished read-buildbot-config step (success) 12:10:12 INFO - [mozharness: 2016-07-22 19:10:12.966160Z] Running download-and-extract step. 12:10:12 INFO - Running pre-action listener: _resource_record_pre_action 12:10:12 INFO - Running main action method: download_and_extract 12:10:12 INFO - mkdir: /builds/slave/test/build/tests 12:10:12 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')]} 12:10:12 INFO - https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:10:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:10:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:10:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:13 INFO - Downloaded 1361 bytes. 12:10:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:10:13 INFO - Using the following test package requirements: 12:10:13 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:10:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:10:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:10:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'jsshell-linux-x86_64.zip'], 12:10:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:10:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:10:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:10:13 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:10:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:10:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:10:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:10:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:10:13 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')]} 12:10:13 INFO - https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:10:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:10:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:10:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:14 INFO - Downloaded 22737593 bytes. 12:10:14 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'] 12:10:14 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 12:10:16 INFO - Return code: 0 12:10:16 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 12:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:16 INFO - https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:17 INFO - Downloaded 22737593 bytes. 12:10:17 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'] 12:10:17 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 12:10:18 INFO - Return code: 0 12:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:18 INFO - https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:10:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:10:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:10:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:19 INFO - Downloaded 14403289 bytes. 12:10:19 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'] 12:10:19 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 12:10:19 INFO - Return code: 0 12:10:19 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')]} 12:10:19 INFO - https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:10:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:10:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:10:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TVkrAuPSQruH5pLy35hoFQ/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 12:10:20 INFO - Downloaded 57159310 bytes. 12:10:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:10:20 INFO - mkdir: /builds/slave/test/properties 12:10:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:10:20 INFO - Writing to file /builds/slave/test/properties/build_url 12:10:20 INFO - Contents: 12:10:20 INFO - build_url:https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:10:21 INFO - Running post-action listener: _resource_record_post_action 12:10:21 INFO - Running post-action listener: set_extra_try_arguments 12:10:21 INFO - [mozharness: 2016-07-22 19:10:21.590101Z] Finished download-and-extract step (success) 12:10:21 INFO - [mozharness: 2016-07-22 19:10:21.590337Z] Running populate-webroot step. 12:10:21 INFO - Running pre-action listener: _resource_record_pre_action 12:10:21 INFO - Running main action method: populate_webroot 12:10:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:10:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:10:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:10:21 INFO - '--tppagecycles', 12:10:21 INFO - '7'], 12:10:21 INFO - 'tests': ['tsvgm']}}, 12:10:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:10:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:10:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:10:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:10:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['damp', 'tps']}, 12:10:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'tests': ['damp', 'tps']}, 12:10:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['dromaeo_dom']}, 12:10:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:10:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['basic_compositor_video']}, 12:10:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:10:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'other-e10s': {'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['a11yr', 12:10:21 INFO - 'ts_paint', 12:10:21 INFO - 'tpaint', 12:10:21 INFO - 'sessionrestore', 12:10:21 INFO - 'sessionrestore_no_auto_restore', 12:10:21 INFO - 'tabpaint']}, 12:10:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['tsvgx', 12:10:21 INFO - 'tsvgr_opacity', 12:10:21 INFO - 'tart', 12:10:21 INFO - 'tscrollx', 12:10:21 INFO - 'cart']}, 12:10:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:10:21 INFO - 'tsvgr_opacity', 12:10:21 INFO - 'tart', 12:10:21 INFO - 'tscrollx', 12:10:21 INFO - 'cart']}, 12:10:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'talos_options': ['--disable-e10s'], 12:10:21 INFO - 'tests': ['tp5o']}, 12:10:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'tests': ['tp5o']}, 12:10:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'talos_options': ['--disable-e10s', 12:10:21 INFO - '--xperf_path', 12:10:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:21 INFO - 'tests': ['tp5n']}, 12:10:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:10:21 INFO - 'talos_options': ['--xperf_path', 12:10:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:21 INFO - 'tests': ['tp5n']}}, 12:10:21 INFO - 'talos.zip': {'path': '', 12:10:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:10:21 INFO - Running post-action listener: _resource_record_post_action 12:10:21 INFO - [mozharness: 2016-07-22 19:10:21.608592Z] Finished populate-webroot step (success) 12:10:21 INFO - [mozharness: 2016-07-22 19:10:21.608681Z] Running create-virtualenv step. 12:10:21 INFO - Running pre-action listener: _resource_record_pre_action 12:10:21 INFO - Running main action method: create_virtualenv 12:10:21 INFO - Creating virtualenv /builds/slave/test/build/venv 12:10:21 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 12:10:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:10:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:10:21 INFO - Using real prefix '/usr' 12:10:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:10:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:10:24 INFO - Installing pip.................done. 12:10:24 INFO - Return code: 0 12:10:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:10:24 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')]} 12:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:24 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')]} 12:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:24 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:24 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 12:10:24 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 12:10:24 INFO - Using env: {'DISPLAY': ':0', 12:10:24 INFO - 'HOME': '/home/cltbld', 12:10:24 INFO - 'LANG': 'en_US.UTF-8', 12:10:24 INFO - 'LANGUAGE': 'en_US:en', 12:10:24 INFO - 'LOGNAME': 'cltbld', 12:10:24 INFO - 'MAIL': '/var/mail/cltbld', 12:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:24 INFO - 'MOZ_NO_REMOTE': '1', 12:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:24 INFO - 'NO_EM_RESTART': '1', 12:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:24 INFO - 'PWD': '/builds/slave/test', 12:10:24 INFO - 'SHELL': '/bin/bash', 12:10:24 INFO - 'SHLVL': '1', 12:10:24 INFO - 'TERM': 'linux', 12:10:24 INFO - 'TMOUT': '86400', 12:10:24 INFO - 'USER': 'cltbld', 12:10:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:24 INFO - '_': '/tools/buildbot/bin/python'} 12:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:24 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 12:10:24 INFO - Cleaning up... 12:10:24 INFO - Return code: 0 12:10:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:10:24 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')]} 12:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:24 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')]} 12:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:24 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:24 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 12:10:24 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 12:10:24 INFO - Using env: {'DISPLAY': ':0', 12:10:24 INFO - 'HOME': '/home/cltbld', 12:10:24 INFO - 'LANG': 'en_US.UTF-8', 12:10:24 INFO - 'LANGUAGE': 'en_US:en', 12:10:24 INFO - 'LOGNAME': 'cltbld', 12:10:24 INFO - 'MAIL': '/var/mail/cltbld', 12:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:24 INFO - 'MOZ_NO_REMOTE': '1', 12:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:24 INFO - 'NO_EM_RESTART': '1', 12:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:24 INFO - 'PWD': '/builds/slave/test', 12:10:24 INFO - 'SHELL': '/bin/bash', 12:10:24 INFO - 'SHLVL': '1', 12:10:24 INFO - 'TERM': 'linux', 12:10:24 INFO - 'TMOUT': '86400', 12:10:24 INFO - 'USER': 'cltbld', 12:10:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:24 INFO - '_': '/tools/buildbot/bin/python'} 12:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:24 INFO - Downloading/unpacking psutil>=3.1.1 12:10:24 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 12:10:24 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 12:10:24 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 12:10:24 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 12:10:24 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 12:10:24 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 12:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:10:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:26 INFO - Installing collected packages: psutil 12:10:26 INFO - Running setup.py install for psutil 12:10:26 INFO - building 'psutil._psutil_linux' extension 12:10:26 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 12:10:26 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 12:10:26 INFO - building 'psutil._psutil_posix' extension 12:10:26 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 12:10:27 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 12:10:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:27 INFO - Successfully installed psutil 12:10:27 INFO - Cleaning up... 12:10:27 INFO - Return code: 0 12:10:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:10: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')]} 12:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10: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')]} 12:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:27 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:27 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 12:10:27 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 12:10:27 INFO - Using env: {'DISPLAY': ':0', 12:10:27 INFO - 'HOME': '/home/cltbld', 12:10:27 INFO - 'LANG': 'en_US.UTF-8', 12:10:27 INFO - 'LANGUAGE': 'en_US:en', 12:10:27 INFO - 'LOGNAME': 'cltbld', 12:10:27 INFO - 'MAIL': '/var/mail/cltbld', 12:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:27 INFO - 'MOZ_NO_REMOTE': '1', 12:10:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:27 INFO - 'NO_EM_RESTART': '1', 12:10:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:27 INFO - 'PWD': '/builds/slave/test', 12:10:27 INFO - 'SHELL': '/bin/bash', 12:10:27 INFO - 'SHLVL': '1', 12:10:27 INFO - 'TERM': 'linux', 12:10:27 INFO - 'TMOUT': '86400', 12:10:27 INFO - 'USER': 'cltbld', 12:10:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:27 INFO - '_': '/tools/buildbot/bin/python'} 12:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:10:27 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 12:10:27 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 12:10:27 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 12:10:27 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 12:10:27 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 12:10:27 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 12:10:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:10:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:10:29 INFO - Installing collected packages: mozsystemmonitor 12:10:29 INFO - Running setup.py install for mozsystemmonitor 12:10:29 INFO - Successfully installed mozsystemmonitor 12:10:29 INFO - Cleaning up... 12:10:29 INFO - Return code: 0 12:10:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:29 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:29 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 12:10:29 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 12:10:29 INFO - Using env: {'DISPLAY': ':0', 12:10:29 INFO - 'HOME': '/home/cltbld', 12:10:29 INFO - 'LANG': 'en_US.UTF-8', 12:10:29 INFO - 'LANGUAGE': 'en_US:en', 12:10:29 INFO - 'LOGNAME': 'cltbld', 12:10:29 INFO - 'MAIL': '/var/mail/cltbld', 12:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:29 INFO - 'MOZ_NO_REMOTE': '1', 12:10:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:29 INFO - 'NO_EM_RESTART': '1', 12:10:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:29 INFO - 'PWD': '/builds/slave/test', 12:10:29 INFO - 'SHELL': '/bin/bash', 12:10:29 INFO - 'SHLVL': '1', 12:10:29 INFO - 'TERM': 'linux', 12:10:29 INFO - 'TMOUT': '86400', 12:10:29 INFO - 'USER': 'cltbld', 12:10:29 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:29 INFO - '_': '/tools/buildbot/bin/python'} 12:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:29 INFO - Downloading/unpacking jsonschema==2.5.1 12:10:29 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 12:10:29 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 12:10:29 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 12:10:29 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 12:10:29 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 12:10:29 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 12:10:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:10:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:10:31 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 12:10:31 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 12:10:31 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 12:10:31 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 12:10:31 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 12:10:31 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 12:10:31 INFO - Downloading functools32-3.2.3-2.tar.gz 12:10:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:10:31 INFO - warning: no files found matching '*.txt' 12:10:31 INFO - no previously-included directories found matching 'build' 12:10:31 INFO - no previously-included directories found matching 'dist' 12:10:31 INFO - no previously-included directories found matching '.git*' 12:10:31 INFO - Installing collected packages: jsonschema, functools32 12:10:31 INFO - Running setup.py install for functools32 12:10:32 INFO - warning: no files found matching '*.txt' 12:10:32 INFO - no previously-included directories found matching 'build' 12:10:32 INFO - no previously-included directories found matching 'dist' 12:10:32 INFO - no previously-included directories found matching '.git*' 12:10:32 INFO - Successfully installed jsonschema functools32 12:10:32 INFO - Cleaning up... 12:10:32 INFO - Return code: 0 12:10:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:10: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')]} 12:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10: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')]} 12:10:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10: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', '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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:10: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 blobuploader==1.2.4 12:10:32 INFO - Using env: {'DISPLAY': ':0', 12:10:32 INFO - 'HOME': '/home/cltbld', 12:10:32 INFO - 'LANG': 'en_US.UTF-8', 12:10:32 INFO - 'LANGUAGE': 'en_US:en', 12:10:32 INFO - 'LOGNAME': 'cltbld', 12:10:32 INFO - 'MAIL': '/var/mail/cltbld', 12:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:32 INFO - 'MOZ_NO_REMOTE': '1', 12:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:32 INFO - 'NO_EM_RESTART': '1', 12:10:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:32 INFO - 'PWD': '/builds/slave/test', 12:10:32 INFO - 'SHELL': '/bin/bash', 12:10:32 INFO - 'SHLVL': '1', 12:10:32 INFO - 'TERM': 'linux', 12:10:32 INFO - 'TMOUT': '86400', 12:10:32 INFO - 'USER': 'cltbld', 12:10:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:32 INFO - '_': '/tools/buildbot/bin/python'} 12:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:32 INFO - Downloading/unpacking blobuploader==1.2.4 12:10: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 12:10: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 12:10: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 12:10: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 12:10: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 12:10: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 12:10:34 INFO - Downloading blobuploader-1.2.4.tar.gz 12:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:10:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:10:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 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 12:10:34 INFO - Downloading docopt-0.6.1.tar.gz 12:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:10:35 INFO - Installing collected packages: blobuploader, requests, docopt 12:10:35 INFO - Running setup.py install for blobuploader 12:10:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:10:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:10:35 INFO - Running setup.py install for requests 12:10:35 INFO - Running setup.py install for docopt 12:10:35 INFO - Successfully installed blobuploader requests docopt 12:10:35 INFO - Cleaning up... 12:10:35 INFO - Return code: 0 12:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:35 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:35 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 12:10:35 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 12:10:35 INFO - Using env: {'DISPLAY': ':0', 12:10:35 INFO - 'HOME': '/home/cltbld', 12:10:35 INFO - 'LANG': 'en_US.UTF-8', 12:10:35 INFO - 'LANGUAGE': 'en_US:en', 12:10:35 INFO - 'LOGNAME': 'cltbld', 12:10:35 INFO - 'MAIL': '/var/mail/cltbld', 12:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:35 INFO - 'MOZ_NO_REMOTE': '1', 12:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:35 INFO - 'NO_EM_RESTART': '1', 12:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:35 INFO - 'PWD': '/builds/slave/test', 12:10:35 INFO - 'SHELL': '/bin/bash', 12:10:35 INFO - 'SHLVL': '1', 12:10:35 INFO - 'TERM': 'linux', 12:10:35 INFO - 'TMOUT': '86400', 12:10:35 INFO - 'USER': 'cltbld', 12:10:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:35 INFO - '_': '/tools/buildbot/bin/python'} 12:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:35 INFO - Running setup.py (path:/tmp/pip-G8BIFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:35 INFO - Running setup.py (path:/tmp/pip-Ij_Vjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:35 INFO - Running setup.py (path:/tmp/pip-z7k3oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:36 INFO - Running setup.py (path:/tmp/pip-R9a3yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:36 INFO - Running setup.py (path:/tmp/pip-VkAmjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:36 INFO - Running setup.py (path:/tmp/pip-vI0jmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:36 INFO - Running setup.py (path:/tmp/pip-eQDC4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:36 INFO - Running setup.py (path:/tmp/pip-sKy4LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:36 INFO - Running setup.py (path:/tmp/pip-jryXU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:36 INFO - Running setup.py (path:/tmp/pip-sziew7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:36 INFO - Running setup.py (path:/tmp/pip-pKTFsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:36 INFO - Running setup.py (path:/tmp/pip-D9qfzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:36 INFO - Running setup.py (path:/tmp/pip-wvMQDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:10:36 INFO - Running setup.py (path:/tmp/pip-Hhj93G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:36 INFO - Running setup.py (path:/tmp/pip-I2Su0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:10:36 INFO - Running setup.py (path:/tmp/pip-Nlodmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:10:37 INFO - Running setup.py (path:/tmp/pip-e0pT0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:10:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:10:37 INFO - Running setup.py install for manifestparser 12:10:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Running setup.py install for mozcrash 12:10:37 INFO - Running setup.py install for mozdebug 12:10:37 INFO - Running setup.py install for mozdevice 12:10:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Running setup.py install for mozfile 12:10:37 INFO - Running setup.py install for mozhttpd 12:10:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Running setup.py install for mozinfo 12:10:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Running setup.py install for mozInstall 12:10:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:10:37 INFO - Running setup.py install for mozleak 12:10:37 INFO - Running setup.py install for mozlog 12:10:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Running setup.py install for moznetwork 12:10:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Running setup.py install for mozprocess 12:10:38 INFO - Running setup.py install for mozprofile 12:10:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Running setup.py install for mozrunner 12:10:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Running setup.py install for mozscreenshot 12:10:38 INFO - Running setup.py install for moztest 12:10:38 INFO - Running setup.py install for mozversion 12:10:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:10:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:10:38 INFO - Cleaning up... 12:10:38 INFO - Return code: 0 12:10:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:38 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')]} 12:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:38 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')]} 12:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:38 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:38 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 12:10:38 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 12:10:38 INFO - Using env: {'DISPLAY': ':0', 12:10:38 INFO - 'HOME': '/home/cltbld', 12:10:38 INFO - 'LANG': 'en_US.UTF-8', 12:10:38 INFO - 'LANGUAGE': 'en_US:en', 12:10:38 INFO - 'LOGNAME': 'cltbld', 12:10:38 INFO - 'MAIL': '/var/mail/cltbld', 12:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:38 INFO - 'MOZ_NO_REMOTE': '1', 12:10:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:38 INFO - 'NO_EM_RESTART': '1', 12:10:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:38 INFO - 'PWD': '/builds/slave/test', 12:10:38 INFO - 'SHELL': '/bin/bash', 12:10:38 INFO - 'SHLVL': '1', 12:10:38 INFO - 'TERM': 'linux', 12:10:38 INFO - 'TMOUT': '86400', 12:10:38 INFO - 'USER': 'cltbld', 12:10:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:38 INFO - '_': '/tools/buildbot/bin/python'} 12:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:39 INFO - Running setup.py (path:/tmp/pip-FwF5R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:39 INFO - Running setup.py (path:/tmp/pip-OqE8T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:39 INFO - Running setup.py (path:/tmp/pip-jMd6VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:39 INFO - Running setup.py (path:/tmp/pip-u0wfzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:39 INFO - Running setup.py (path:/tmp/pip-53W6_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:39 INFO - Running setup.py (path:/tmp/pip-HJZZWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:39 INFO - Running setup.py (path:/tmp/pip-d5w4hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:39 INFO - Running setup.py (path:/tmp/pip-LS8qRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:39 INFO - Running setup.py (path:/tmp/pip-ceuRlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:39 INFO - Running setup.py (path:/tmp/pip-rmhy1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:39 INFO - Running setup.py (path:/tmp/pip-v9a0KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:39 INFO - Running setup.py (path:/tmp/pip-r51Dho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:39 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)) 12:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:39 INFO - Running setup.py (path:/tmp/pip-Z_AcRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:10:40 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)) 12:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:10:40 INFO - Running setup.py (path:/tmp/pip-P5hrFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:10:40 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)) 12:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:40 INFO - Running setup.py (path:/tmp/pip-NCG9l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:40 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)) 12:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:10:40 INFO - Running setup.py (path:/tmp/pip-fLWJQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:10:40 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)) 12:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:10:40 INFO - Running setup.py (path:/tmp/pip-jxt0Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:10:40 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)) 12:10:40 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)) 12:10:40 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)) 12:10:40 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)) 12:10:40 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)) 12:10:40 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)) 12:10:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:10:40 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 12:10:40 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 12:10:40 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 12:10:40 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 12:10:40 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 12:10:40 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 12:10:42 INFO - Downloading blessings-1.6.tar.gz 12:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:10:42 INFO - Installing collected packages: blessings 12:10:42 INFO - Running setup.py install for blessings 12:10:42 INFO - Successfully installed blessings 12:10:42 INFO - Cleaning up... 12:10:42 INFO - Return code: 0 12:10:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:10:42 INFO - Reading from file tmpfile_stdout 12:10:42 INFO - Current package versions: 12:10:42 INFO - argparse == 1.2.1 12:10:42 INFO - blessings == 1.6 12:10:42 INFO - blobuploader == 1.2.4 12:10:42 INFO - docopt == 0.6.1 12:10:42 INFO - functools32 == 3.2.3-2 12:10:42 INFO - jsonschema == 2.5.1 12:10:42 INFO - manifestparser == 1.1 12:10:42 INFO - mozInstall == 1.12 12:10:42 INFO - mozcrash == 0.17 12:10:42 INFO - mozdebug == 0.1 12:10:42 INFO - mozdevice == 0.48 12:10:42 INFO - mozfile == 1.2 12:10:42 INFO - mozhttpd == 0.7 12:10:42 INFO - mozinfo == 0.9 12:10:42 INFO - mozleak == 0.1 12:10:42 INFO - mozlog == 3.3 12:10:42 INFO - moznetwork == 0.27 12:10:42 INFO - mozprocess == 0.23 12:10:42 INFO - mozprofile == 0.28 12:10:42 INFO - mozrunner == 6.12 12:10:42 INFO - mozscreenshot == 0.1 12:10:42 INFO - mozsystemmonitor == 0.3 12:10:42 INFO - moztest == 0.7 12:10:42 INFO - mozversion == 1.4 12:10:42 INFO - psutil == 3.1.1 12:10:42 INFO - requests == 1.2.3 12:10:42 INFO - wsgiref == 0.1.2 12:10:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:42 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:42 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 12:10:42 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 12:10:42 INFO - Using env: {'DISPLAY': ':0', 12:10:42 INFO - 'HOME': '/home/cltbld', 12:10:42 INFO - 'LANG': 'en_US.UTF-8', 12:10:42 INFO - 'LANGUAGE': 'en_US:en', 12:10:42 INFO - 'LOGNAME': 'cltbld', 12:10:42 INFO - 'MAIL': '/var/mail/cltbld', 12:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:42 INFO - 'MOZ_NO_REMOTE': '1', 12:10:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:42 INFO - 'NO_EM_RESTART': '1', 12:10:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:42 INFO - 'PWD': '/builds/slave/test', 12:10:42 INFO - 'SHELL': '/bin/bash', 12:10:42 INFO - 'SHLVL': '1', 12:10:42 INFO - 'TERM': 'linux', 12:10:42 INFO - 'TMOUT': '86400', 12:10:42 INFO - 'USER': 'cltbld', 12:10:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:42 INFO - '_': '/tools/buildbot/bin/python'} 12:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 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)) 12:10:42 INFO - Cleaning up... 12:10:42 INFO - Return code: 0 12:10:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:10:42 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 0x2910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', '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 12:10:42 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 12:10:42 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 12:10:42 INFO - Using env: {'DISPLAY': ':0', 12:10:42 INFO - 'HOME': '/home/cltbld', 12:10:42 INFO - 'LANG': 'en_US.UTF-8', 12:10:42 INFO - 'LANGUAGE': 'en_US:en', 12:10:42 INFO - 'LOGNAME': 'cltbld', 12:10:42 INFO - 'MAIL': '/var/mail/cltbld', 12:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:42 INFO - 'MOZ_NO_REMOTE': '1', 12:10:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:42 INFO - 'NO_EM_RESTART': '1', 12:10:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:42 INFO - 'PWD': '/builds/slave/test', 12:10:42 INFO - 'SHELL': '/bin/bash', 12:10:42 INFO - 'SHLVL': '1', 12:10:42 INFO - 'TERM': 'linux', 12:10:42 INFO - 'TMOUT': '86400', 12:10:42 INFO - 'USER': 'cltbld', 12:10:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:42 INFO - '_': '/tools/buildbot/bin/python'} 12:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:10:43 INFO - Cleaning up... 12:10:43 INFO - Return code: 0 12:10:43 INFO - Running post-action listener: _resource_record_post_action 12:10:43 INFO - Running post-action listener: _start_resource_monitoring 12:10:43 INFO - Starting resource monitoring. 12:10:43 INFO - [mozharness: 2016-07-22 19:10:43.157929Z] Finished create-virtualenv step (success) 12:10:43 INFO - [mozharness: 2016-07-22 19:10:43.158516Z] Running install step. 12:10:43 INFO - Running pre-action listener: _resource_record_pre_action 12:10:43 INFO - Running main action method: install 12:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:10:43 INFO - Reading from file tmpfile_stdout 12:10:43 INFO - Detecting whether we're running mozinstall >=1.0... 12:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:10:43 INFO - Reading from file tmpfile_stdout 12:10:43 INFO - Output received: 12:10:43 INFO - Usage: mozinstall [options] installer 12:10:43 INFO - Options: 12:10:43 INFO - -h, --help show this help message and exit 12:10:43 INFO - -d DEST, --destination=DEST 12:10:43 INFO - Directory to install application into. [default: 12:10:43 INFO - "/builds/slave/test"] 12:10:43 INFO - --app=APP Application being installed. [default: firefox] 12:10:43 INFO - mkdir: /builds/slave/test/build/application 12:10:43 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'] 12:10:43 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 12:10:59 INFO - Reading from file tmpfile_stdout 12:10:59 INFO - Output received: 12:10:59 INFO - /builds/slave/test/build/application/firefox/firefox 12:10:59 INFO - Running post-action listener: _resource_record_post_action 12:10:59 INFO - [mozharness: 2016-07-22 19:10:59.969248Z] Finished install step (success) 12:10:59 INFO - [mozharness: 2016-07-22 19:10:59.969530Z] Running run-tests step. 12:10:59 INFO - Running pre-action listener: _resource_record_pre_action 12:10:59 INFO - Running main action method: run_tests 12:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:59 INFO - Python 2.7.3 12:10:59 INFO - Return code: 0 12:10:59 INFO - grabbing minidump binary from tooltool 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:59 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 0x2bcf3b0>, '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 0x2bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:59 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 12:10:59 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 12:10:59 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 12:11:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:11:00 INFO - Return code: 0 12:11:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:11:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:11:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:11:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:11:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 12:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 12:11:00 INFO - Using env: {'DISPLAY': ':0', 12:11:00 INFO - 'HOME': '/home/cltbld', 12:11:00 INFO - 'LANG': 'en_US.UTF-8', 12:11:00 INFO - 'LANGUAGE': 'en_US:en', 12:11:00 INFO - 'LOGNAME': 'cltbld', 12:11:00 INFO - 'MAIL': '/var/mail/cltbld', 12:11:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:00 INFO - 'MOZ_NO_REMOTE': '1', 12:11:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:00 INFO - 'NO_EM_RESTART': '1', 12:11:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:00 INFO - 'PWD': '/builds/slave/test', 12:11:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:00 INFO - 'SHELL': '/bin/bash', 12:11:00 INFO - 'SHLVL': '1', 12:11:00 INFO - 'TERM': 'linux', 12:11:00 INFO - 'TMOUT': '86400', 12:11:00 INFO - 'USER': 'cltbld', 12:11:00 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967', 12:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:00 INFO - '_': '/tools/buildbot/bin/python'} 12:11:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 12:11:00 INFO - mozversion application_buildid: 20160722112426 12:11:00 INFO - mozversion application_changeset: 6df3913433336ceaa463d831fef7c0ee5a2efa21 12:11:00 INFO - mozversion application_display_name: Nightly 12:11:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:11:00 INFO - mozversion application_name: Firefox 12:11:00 INFO - mozversion application_remotingname: firefox 12:11:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:11:00 INFO - mozversion application_vendor: Mozilla 12:11:00 INFO - mozversion application_version: 50.0a1 12:11:00 INFO - mozversion platform_buildid: 20160722112426 12:11:00 INFO - mozversion platform_changeset: 6df3913433336ceaa463d831fef7c0ee5a2efa21 12:11:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:11:00 INFO - mozversion platform_version: 50.0a1 12:11:00 INFO - using testdate: 1469214660 12:11:00 INFO - actual date: 1469214660 12:11:00 INFO - starting webserver on 'localhost:39099' 12:11:00 INFO - SUITE-START | Running 2 tests 12:11:00 INFO - TEST-START | dromaeo_css 12:11:00 INFO - operating with platform_type : linux_ 12:11:00 INFO - Initialising browser for dromaeo_css test... 12:11:00 INFO - TEST-INFO | started process 23159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqe9j50/profile http://localhost:39099/getInfo.html) 12:11:03 INFO - PROCESS | 23159 | JavaScript error: , line 0: uncaught exception: undefined 12:11:04 INFO - PROCESS | 23159 | __metrics Screen width/height:1600/1200 12:11:04 INFO - PROCESS | 23159 | colorDepth:24 12:11:04 INFO - PROCESS | 23159 | Browser inner width/height: 1024/697 12:11:04 INFO - PROCESS | 23159 | __metrics 12:11:05 INFO - PROCESS | 23159 | 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" 12:11:05 INFO - PROCESS | 23159 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:11:06 INFO - TEST-INFO | 23159: exit 0 12:11:06 INFO - Browser initialized. 12:11:06 INFO - Running cycle 1/1 for dromaeo_css test... 12:11:06 INFO - TEST-INFO | started process 23241 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqe9j50/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:18 INFO - PROCESS | 23241 | RSS: Main: 159764480 12:11:18 INFO - PROCESS | 23241 | 12:12:08 INFO - PROCESS | 23241 | 1469214728925 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] 12:14:21 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39099/tests/dromaeo/cssquery-ext.html) 12:14:22 INFO - PROCESS | 23241 | RSS: Main: 235548672 12:14:22 INFO - PROCESS | 23241 | 12:17:24 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-ext.html (next: http://localhost:39099/tests/dromaeo/cssquery-jquery.html) 12:17:25 INFO - PROCESS | 23241 | RSS: Main: 235941888 12:17:25 INFO - PROCESS | 23241 | 12:20:27 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39099/tests/dromaeo/cssquery-mootools.html) 12:20:28 INFO - PROCESS | 23241 | RSS: Main: 184094720 12:20:28 INFO - PROCESS | 23241 | 12:23:30 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39099/tests/dromaeo/cssquery-prototype.html) 12:23:31 INFO - PROCESS | 23241 | RSS: Main: 189911040 12:23:31 INFO - PROCESS | 23241 | 12:26:34 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39099/tests/dromaeo/cssquery-yui.html) 12:26:34 INFO - PROCESS | 23241 | RSS: Main: 186249216 12:26:34 INFO - PROCESS | 23241 | 12:29:37 INFO - PROCESS | 23241 | Cycle 1(1): loaded http://localhost:39099/tests/dromaeo/cssquery-yui.html (next: http://localhost:39099/tests/dromaeo/cssquery-dojo.html) 12:29:38 INFO - PROCESS | 23241 | RSS: Main: 279539712 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | __start_tp_report 12:29:38 INFO - PROCESS | 23241 | _x_x_mozilla_page_load 12:29:38 INFO - PROCESS | 23241 | _x_x_mozilla_page_load_details 12:29:38 INFO - PROCESS | 23241 | |i|pagename|runs| 12:29:38 INFO - PROCESS | 23241 | |0;dojo.html;2562.3337932137642;2585.405356767942;2614.516314481821;2629.4478159586474;2632.8946842126315;4608.308753686949;4657.0453057123295;4667.276572131316;4701.177293973554;4765.166095933211;4631.258998560231;4667.906641867161;4681.0871879983415;4696.154692155411;4709.387779588648;4274;4305.461817272841;4324.84863029794;4374.803133858984;4485.753283569403;4394.62645675118;4429.069895321986;4438.60052595268;4449.1101779644205;4461.1523810476065;4226.028013556899;4277.144571085764;4288.614024737757;4299.849505267301;4350.738955662655;4280.700350975433;4282.957170428318;4285.485741710983;4322.546132656089;4337.674674399401;4296.849610263657;4318.352247162919;4325.52419233884;4332.826686932519;4376.387305777193;4565.452145742499;4638.930416043762;4699.624030077585;4702.365180700596;4719.032598317371;5048.974755126237;5097.872553186163;5102.132637451642;5112.821051263188;5118.155504341817;5108.4125325587665;5125.282460455539;5129.820456284012;5131.897362052738;5139.691618502864;4581.74800385982;4665.603423708955;4697.201475749062;4710.505396933273;4764.761761911891;5151.304573882478;5184.637075404686;5188.792448302026;5234.162533994619;5234.790608375699;4962.379702537183;4969.801207951641;5017.272495488134;5019.47295533971;5019.799208031711;4897.534734200245;4984.40187177541;5032.0690672225755;5035.974820125949;5088.465711100281;4979.05397974391;5002.624803139779;5006.173981293119;5008.449070602231;5054.974725126424;4976.776045077981;4989.201727723619;4995.550400463903;4998.150314446553;5044.621653376012;4200.180964711852;4234.343676730112;4237.385579091015;4266.637335826489;4287.314029755286;3733.2346868892355;3750.587435382106;3832.6358995895343;3846.7499612525658;3849.653531182207;4308.526062133163;4311.568843115726;4318.935215971763;4344.23975804233;4354.1944740222525;4839.225723884232;5030.446650868402;5031.270465782441;5035.219540971156;5077.060743762416;4236.195122926634;4274.038341373166;4286.507051689034;4295.033617436115;4347.195768782785;4577.359169716243;4578.908421831545;4583.129205450954;4613.0543238636;4621.884452888705;4748.145333839942;4756.524347565285;4758.809647614126;4764.047190561971;4807.326974223682;4829.7585120744525;4863.562279394871;4918.286848407032;4969.850904472872;5029.06962211992;4712.363830882787;4717.3159891816185;4731.787069581808;4755.5719985201495;4759.714417134983;4917.58200552957;4920.72935988524;4929.309896614405;4938.950610493849;4940.802367905244;4932.975335123301;4943.950560494349;4962.45413004562;4977.303177555073;5014.924776128434;4832.685875418087;4833.951660483491;4835.8065677372515;4869.634777391639;4881.243407271879;4840.128776820206;4844.588210002048;4848.8302909398;4849.903001940012;4901.583365413837;4683.8360657376825;4700.083483720641;4703.05938812232;4780.235162374003;4781.9043619128115;4826.493218212177;4846.466888642317;4847.297141914332;4864.489228630943;4894.486078961801;4741.810327586996;4743.549362810528;4746.145693775154;4747.501512341154;4782.808687652455 12:29:38 INFO - PROCESS | 23241 | |1;ext.html;559.5239785847167;579.4466284698112;586.1764221269116;591.9644821310725;596.25468164794;27234.727652723468;30367.54448683266;30398.848005759966;30399.240018999535;30432.84783576082;39671.80164099183;40549.59450405495;40695.38956915641;44709.55290447095;44738.32892506607;7709.961450192741;7868.645910933993;7869.016372953381;7871.094824095217;7871.685132594718;7798.688052477894;7857.960710196469;7882.093559240675;7882.881756773653;7883.172266911978;7883.014623172104;7912.604369781517;7914.40641951853;7917.841643167133;7925.128235894047;7816.218378162138;7835.686572537092;7875.645595948196;7880.0937892142565;7880.36957043438;7732.922670773277;7798.2201779822135;7801.414893882981;7801.531908085533;7803.531788092732;38647.227055459014;38919.805400972815;38954.41568376477;39032;39056.80471597652;3138.152698771342;3155.7002084801907;3230.1601583588435;3381.881634142818;3725.1990821973404;28855.711442885724;29077.41845163106;29636.70363296361;29677.258068548243;29719;3422;3451.913702157441;3479.2693534357813;3506.6843984041557;3515.402381595135;254659;256292;256934;256957;257332;52524;52910;52971.205431918555;52978.470215298126;53013.204801928005;54552.45447545474;54730;54904;54956;55177.72411137919;15519.922400387926;15790;15792.526224213292;15801.209939502978;15802.60493487672;11177.83233251502;11187.216894817284;11190.160737944687;11201.495932683049;11231.663050108345;14806.407743690348;15256.160911149993;15317.6170595736;15332.310046047955;15540.378384864478;4885.374707842203;5268.762905669254;5440.401555828856;5600.803971860965;5682.943170568324;797.9281864632095;872.3500991761179;891.3092353426147;936.4615346175976;952.1383148250844;27152.963527735497;27941.161765146673;28128.253175098765;28810.27974300659;29140.417191656048;925.560358829564;945.177695405002;986.5264672957021;999.1906555689915;1058.7300238439302;14602.269886505632;14819.851801481847;15009.924950375396;15433.614159646098;15459.227038648247;377986;381543.7382504669;381942;382164;383408;50593.24110138351;50838.74580627073;50847.745761270955;50858.237126443135;50942.74528627333;1076.155218153761;1158.9826152607718;1196.443653701033;1214.969625759363;1228.588422878328;28089.43821123607;29253.268668282613;29641.851790740908;29659.555106672986;30119.849400752857;42832.57167428286;43425.13149736924;43441.56558434375;43513.78243108764;43532;308650;310441;310656;310828;311058;24775;25414.745852541237;25521.872390637927;26387.340316491474;26612.733872661025;42230.78884605557;42802;43079;43349.13301734011;43530.34704479372;36292.278079143645;36420.63579364278;36624.81687591545;36630;37425.06437339088;36176;37070.44394334141;37153.442698359;37565.81217093897;37677.62322376741;35593.64406355903;36159.096022599646;36209.818950906134;36265;36885.631143689294;31931.36137277288;33162.403414599896;33774;33893.49159762655;34094.82952585221;25223.864926078004;25277.241682749547;25388;25483;25871 12:29:38 INFO - PROCESS | 23241 | |2;jquery.html;336.04395494817237;357.27116681968783;366.85875937763956;370.35927844828444;382.18022342076245;12590.874091259084;17805.643887122267;18203.18085686144;18479.90760046198;18827.43517694468;16414.917925410387;17185.74221386677;18300.542486437847;18436.907815460905;18466.815331846676;4923.507649235084;4994.175960966436;5131.794728210867;5132.358455193101;5161.30322406476;3713.4244192150127;5113.463086375914;5149.201873709581;5741.799037033705;6035.668038257894;6052.636841789485;6076.453119219269;6077.604955677867;6086.269647642289;6090.056041313604;6082.270127584696;6391.840203994914;6672.566283191577;6701.463882889358;6829.317068293131;6525.869482610321;6544.541882068257;6577.276499585042;6577.967110164419;6608.900866487006;22750.317490475147;23239.651405228935;23639.763602363928;24207.515849683085;24285.150019749224;1891.640588288221;2119.7562280337806;2300.896459659319;2614.816962812604;2665.720099389575;13588.184708917397;13953.232572208524;14534;14760.778588321184;15129.319180636898;1791.9193636286398;1833.3399976008627;1842.8986405747678;1867.3650958674116;1880.7178923161498;78699.21300787033;87741;90197.54901225583;91024.54487727518;91309;17046.74429883553;17137.657246854993;17341.913290433466;17467.825321746874;17474.91262543679;30505.847470762503;30524;30589.388212236077;30642.693573063985;30856.228594285323;9439.103285187895;9515.619375224913;9577.569009394594;9603.183729383081;9835.508224588742;7934.2859142677435;7950.165232650604;8156.755297341089;8226.341892648694;8297.95851020753;8138.755837324771;8419.663213471515;8633.223009929137;8635.525046122524;8773.824523509495;3051.8474076296097;3125.359301343219;3315.701586857146;3387.356402283558;3388.7119594834717;365.47189311445237;792.4809249941297;834.7412302186342;915.7207051849117;917.9718715039198;10436.425996570262;10576.52405641748;10801.83797243042;10823.83764243537;10842.620508282173;403.31235243909066;408.6424378668665;409.5801803151734;410.0896010855895;411.7899871065718;5713.714314284269;5964.522838172936;9519.571619277149;10164.33931794431;10451.372917624967;370445.9066503903;372623;385913;390740;391614;74179.03283868692;82414.58792705998;84519;86600;87286;912.3841407050214;958.3818437108166;967.4582233949152;970.4177493503842;992.7270000749852;11403.600873969372;15352.923235383752;15890.219211637686;16288.104154271628;16469.176541173132;26241.606375903993;27196.592051118852;38359.84920452391;38818.80590597029;38983.41524877186;51890.74054629703;51962.74018630034;52204.47795522148;53538.196927046905;53838.73080634572;7863.174366691415;7988.081370642451;8096.190380961857;8099.676012959651;8204.507729536246;10011.799764004825;11618.128640351839;12262.938685306517;12748.235105893677;12796.552120675731;5015;8259.83480330402;10425.426601536989;10426.113780328757;11293.43532823339;10507.527161277609;10616.416097114732;10787;10821.404822734825;10933;10297.53661085238;10327.32872363324;10672.199585031309;10676.679699608714;11268.943180454866;6822.829429264109;6963.721451141898;6966.268541803241;7951.165127661512;7959.641816118405;9672.564734587102;9977.650782222587;10111.090001899864;10426.739331516772;10633.574657013942 12:29:38 INFO - PROCESS | 23241 | |3;mootools.html;1397.6925076483174;1414.8868090552758;1483.517856696574;1658.8507034366905;1681.2350380576831;7286.52637578557;7815.0231221097365;7815.882761758578;7824.687012519499;7870.134285228621;6458.225012998447;6718.529702920798;7189.89215161773;7494.625268736569;7733.072031356245;2136.305700647288;2170.6092903277404;2171.381156370435;2173.978260217393;2175.3147758456034;2122.745270567534;2145.184829764687;2180.7819218078143;2185.49733561281;2186.6173419651577;2141.3468891987936;2149.7746284617774;2161.502854343502;2163.134746101556;2174.9782502174935;2077.5221699009235;2087.09211493001;2090.3833369156137;2090.905909234088;2094.036743098177;2062.8143467087884;2109.44099813548;2110.7150534677776;2110.799474049963;2113.260358874397;7628.427867909929;7753.844923101563;7756.069271687434;7759.922400775977;7771.067471903408;4443.133588950156;4555.157295900269;4555.954440455619;4556.931646025312;4560.065186636732;2724.4142509360386;2800.2999250187454;2853.785966052554;2858.5426331786816;2860.184847318504;852.8658365044344;905.6241659711212;905.7101727447154;906.4380084347747;1707.607250332418;9793.069658382443;10187.083162515335;10204.387736735745;10215.744606384826;10219.080282774587;3746.138388170736;3969.801509924492;4003.7597744135446;4018.819153138116;4032.6169013943627;8134.877976830352;8145.185481451926;8145.551994640233;8148.633311500997;8148.796280093045;4493.865184044484;4512.954870451319;4513.571210734975;4514.052049069732;4515.1195516873895;3607.9458808117897;3997.6801855851463;4124.876253712393;4231.11146659205;4232.640225580799;3237.190702324419;3274.705276525124;3330.5670262865674;3343.8996830095134;3346.130006198406;1000.2897942460934;1028.9639862604772;1037.6108959140322;1043.4052590023766;1046.0428707732492;784.9175836537081;790.5454363740872;858.7895965488495;874.4840544079024;891.9598618062203;5091.465396133352;5247.291615631917;5251.264822924793;5280.841574752763;5467.043267428184;371.3260432315339;372.4562139276632;380.8895420328129;381.4373798647029;383.78316251317915;3486.546748922624;3668.834902429397;3834.1181528248103;3839;3855.286771947143;8978.640854365753;9295.72112836616;9414.058594140532;9522.285828562886;9560.043995600385;9452.763680907894;9458.432494050241;9478.194353480032;9479.905200947902;9496.097870702271;7209.2069872314005;7209.423246140296;7262.673179706926;7291.197968223597;7308.451866110063;5302.602304827076;5317.1492561190025;5324.494173053554;5333.439988801275;5526.447355264602;8771.298296136167;8774.868376974222;8790.120987901159;8806.251468625238;8807.735767926972;8666.78333041679;8870.15733505316;9129;9151.3136514763;9155.542222888696;5377.542908852792;6355.841103972438;6418.358164183544;6422.454091402096;6427.839304017437;6509.7396104155305;6650.467962562887;6671.532992690465;6700.128983232149;7129.39400150993;6147.569670123048;6685.197776266879;6825.624590647562;6952.687129079102;7071;6547.476201903741;6696.5647232929705;6743.561668491532;6841.794746157623;6906.447484201352;5843.065109582446;5922.585419020627;5926.051831707078;5939.613925094855;5942.58401911862;4668.463126740333;4729.645276604338;4755.1916174251155;4766.928496072631;4836.081144582518;4514.074614703912;4588.311753237042;4603.907921841612;4683.578477937002;4751.717036400221 12:29:38 INFO - PROCESS | 23241 | |4;prototype.html;286.60734793333125;291.49280252360893;299.0430622009569;299.29068108582663;299.6075141564549;4812.614990800736;4812.9518704812945;4839.637027222959;4840.44334901486;4846.20037693781;4498.5501449855;4652.860414187579;4691.4987204094705;4761.69049011814;4777.808887644499;3808.5239345081864;3812.275667623143;3812.542494900616;3824.1395685970574;3852.6339997700175;3811.5997820228754;3828.3874580067195;3832.13776900198;3862.377801323451;3871.341871881787;3572.8749493767723;3630.455431685242;3746.1571146492092;3765.171662234304;3788.223414200096;3756.906077348061;3787.394016957274;3795.35478968576;3800.923981520382;3802.6007269236748;3374.1227280906896;3383.8477268522975;3384.8660698665976;3390.627031026585;3391.965450537585;4466.263066594008;4749;4751.809927602892;4758.571728544413;4763.118823017753;4231.344141658048;4236.237477254093;4265.698961816644;4268.935965960513;4272.829086836523;4978.303037574742;4993.151164302078;5017.071841709296;5020.548150666457;5025.693319736858;3533.2756784859043;3546.556680414948;3563.875264365735;3575.106223444139;3579.427291633352;4988.800447982113;5173.870653233662;5241.117385438032;5252.65857719247;5253.264542963988;3770;3789.2610940866266;3798.3352913240074;3818.6825545186866;3820.675242604354;3864.091938394475;3869.129445874712;3870.2839974604226;3874.767513949172;3876.127871228951;4203.600657937491;4339;4372.213001659732;4384.56154384559;4384.9123017540105;5015.6489045767175;5027;5034.572061374824;5034.748262586856;5058.266551350033;1296.786030305008;1313.3761463304854;1317.0451422718452;1317.9406926688323;1318.7955866840655;836.1220718245818;890.7149712092069;904.9728508144766;922.3589605224439;933.3839665820526;4392.648588112943;4395.824167033346;4396.846110386121;4399.5380485049245;4403.075354175595;5091.516305950929;5096.210087436453;5115.283860259567;5127.461616530285;5156.92264616031;4276.87169384919;4359.12817436514;4359.891002724958;4408.779561021936;4424.955750442518;2693.1920423873153;2703.324168957761;2706.0799328228495;2712.8779204935827;2713.280980540119;4811.061842940593;4827.8551643450755;4831.371921650163;4833.661643684908;4834.154023045953;4911.729854857946;5149.2791009258735;5175.430702622709;5186.455422180692;5198.740062996835;4835.178019736653;4842.15262329091;4843.128236917389;4847.660663753503;4847.927281090716;5087.430847422028;5092.949070509248;5093.668911520887;5103.209002604603;5105.28526006352;4793.18515852299;4975.253711943237;4991.176455884738;5023.915075243879;5081.517255860687;4851.223804191312;4854.69880481262;4877.487863774394;4878.243872199815;4889.877753056202;3587.7847329160336;3624.746267761337;3630.183208778004;3640.2355505344176;3646.234290798856;5030.4718004608985;5120.975804838972;5155.9742201288755;5273.182656688219;5273.367195936359;4928.644622728778;4933.9013219736125;4968.925466117938;4976.377952755905;4977.850664480072;4957.429895562056;4972.353594032753;5004.5746111579465;5005.724685142352;5016.297718319365;4729.787159577896;4907.386576677915;4948.505149485167;4953.3065370848835;4968.155413579628;4767.380240568704;4840.83057093;4860.513948605111;4992.301077849032;5002.224655178453;4827.8551643450755;4881.780319885543;4897.657163998486;4898.828541001048;4903.33804936329 12:29:38 INFO - PROCESS | 23241 | |5;yui.html;630.4798541203506;695.3290075077553;737.2001378504323;737.8677513953269;748.973785917493;11741.471633776478;12848.614541563744;12941.482340706372;13028.60914172574;13066.085374023822;12146.574869879556;12155.635330940064;12188.512459501631;12308.630741077783;12320.753584928318;776.4370831147429;797.6330887791623;799.7560743973086;799.9400044996648;800.6677228949978;638.4349850382422;755.176857225624;776.5379599138504;783.1189911349732;794.5629903553051;836.5440834745048;837.8659414493683;838.5220424358118;839.2488722593306;840.1430540848331;830.9667613295492;831.8502669519484;832.0888626953476;832.5504227717025;832.9541875196832;484.5711545282415;487.9243717223836;498.59862810437585;503.83625321770205;590.9290885093774;12556.434960426803;13085.411156497885;13105.934470327682;13112.213267203902;13116.344182790823;12552.882302469387;12557.434915428828;13075.934620326932;13372.86627133726;13559.728805423934;11456.4844581994;11881.287122772575;12208.572699955459;12310.076744244217;12752.171108877987;3963.9009024774323;4009.2613339308273;4045.02919299369;4047.1703300823556;4138.234426631053;66738;66988.3301166987;67100.6644966772;67213.66393168052;67714.3228567713;10527.315724478038;10531.368117912949;10548.261621686563;10963.122950163968;11048.779024419466;18171.818281817013;18283.72574411385;18294.17676204574;18303.90848045778;18362.81637183638;6609.778414289719;6703;6984.406325462291;7016.228214896358;7035.437165026786;3536;3617.2584620152807;3658.7804731716183;3672.1186915140174;3685.9078523037138;2711.891524339044;2761.8757155928033;2779.9444011119663;2781.4715204111153;2804.7615952644255;442.45136031321573;445.59005714742517;449.6357950060461;451.19011374582703;459.83905633028843;544.7548603128529;557.5316733943425;569.8262030080782;574.133059080791;578.1587789765881;7037.19072306681;7348.70605175787;7417.109946806418;7463.477556570988;7550.433717471212;537.2854104041693;546.8578169675833;552.9557635389159;557.6904817826146;558.9133684278943;3276.8689252429635;3393.7497196792556;3427.2288735034417;3475.7740746851373;3493.807840568793;97782;102996.48501757444;103304;107333;107953.46023269833;66443;73498.63250683712;78200.6089969558;79865.99999999884;89263.55368223117;45343.773281133384;71326.64336678387;73346;73883.26116738871;74799.25200747923;7849.646765895663;8184.672613095648;8323;8331.708390206313;8398.622062007102;42969.570304297806;43715.78142109269;43825;45057.324140137265;45409.545904541854;55785.721071394386;62173;63186;65311.67344163439;65458.672706636164;11126.833097503706;11377.488013039267;11512.366819824654;11590.879763358329;11745.47145378477;11755.412229388667;11867.58463453775;12075.27548347102;12221.144519883523;12634.115611907078;10973.177011724312;11410.543578256777;12272.570460033856;12282.140250182401;12291.262524248574;10952.507137179005;11376;12532.248065116122;12564.874351256736;12676;11592.594259200887;11765.294082355085;11947.342896140795;12692.301923394301;12694.492220311084;9794.412335259907;9878.16035636979;10014.899851001397;10096;10576;8735.475871447734;9008.59461324255;9234.1689247968;9322.300827437834;10199.847002294822 12:29:38 INFO - PROCESS | 23241 | __end_tp_report 12:29:38 INFO - PROCESS | 23241 | __start_cc_report 12:29:38 INFO - PROCESS | 23241 | _x_x_mozilla_cycle_collect,895 12:29:38 INFO - PROCESS | 23241 | __end_cc_report 12:29:38 INFO - PROCESS | 23241 | __startTimestamp1469215778004__endTimestamp 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | ------- Summary: start ------- 12:29:38 INFO - PROCESS | 23241 | Number of tests: 6 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#0] dojo.html Cycles:165 Average:4638.89 Median:4747.50 stddev:481.67 (10.1%) stddev-sans-first:454.77 12:29:38 INFO - PROCESS | 23241 | Values: 2562.3 2585.4 2614.5 2629.4 2632.9 4608.3 4657.0 4667.3 4701.2 4765.2 4631.3 4667.9 4681.1 4696.2 4709.4 4274.0 4305.5 4324.8 4374.8 4485.8 4394.6 4429.1 4438.6 4449.1 4461.2 4226.0 4277.1 4288.6 4299.8 4350.7 4280.7 4283.0 4285.5 4322.5 4337.7 4296.8 4318.4 4325.5 4332.8 4376.4 4565.5 4638.9 4699.6 4702.4 4719.0 5049.0 5097.9 5102.1 5112.8 5118.2 5108.4 5125.3 5129.8 5131.9 5139.7 4581.7 4665.6 4697.2 4710.5 4764.8 5151.3 5184.6 5188.8 5234.2 5234.8 4962.4 4969.8 5017.3 5019.5 5019.8 4897.5 4984.4 5032.1 5036.0 5088.5 4979.1 5002.6 5006.2 5008.4 5055.0 4976.8 4989.2 4995.6 4998.2 5044.6 4200.2 4234.3 4237.4 4266.6 4287.3 3733.2 3750.6 3832.6 3846.7 3849.7 4308.5 4311.6 4318.9 4344.2 4354.2 4839.2 5030.4 5031.3 5035.2 5077.1 4236.2 4274.0 4286.5 4295.0 4347.2 4577.4 4578.9 4583.1 4613.1 4621.9 4748.1 4756.5 4758.8 4764.0 4807.3 4829.8 4863.6 4918.3 4969.9 5029.1 4712.4 4717.3 4731.8 4755.6 4759.7 4917.6 4920.7 4929.3 4939.0 4940.8 4933.0 4944.0 4962.5 4977.3 5014.9 4832.7 4834.0 4835.8 4869.6 4881.2 4840.1 4844.6 4848.8 4849.9 4901.6 4683.8 4700.1 4703.1 4780.2 4781.9 4826.5 4846.5 4847.3 4864.5 4894.5 4741.8 4743.5 4746.1 4747.5 4782.8 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#1] ext.html Cycles:180 Average:47159.12 Median:37111.94 stddev:84223.74 (226.9%) stddev-sans-first:84387.34 12:29:38 INFO - PROCESS | 23241 | Values: 559.5 579.4 586.2 592.0 596.3 27234.7 30367.5 30398.8 30399.2 30432.8 39671.8 40549.6 40695.4 44709.6 44738.3 7710.0 7868.6 7869.0 7871.1 7871.7 7798.7 7858.0 7882.1 7882.9 7883.2 7883.0 7912.6 7914.4 7917.8 7925.1 7816.2 7835.7 7875.6 7880.1 7880.4 7732.9 7798.2 7801.4 7801.5 7803.5 38647.2 38919.8 38954.4 39032.0 39056.8 3138.2 3155.7 3230.2 3381.9 3725.2 28855.7 29077.4 29636.7 29677.3 29719.0 3422.0 3451.9 3479.3 3506.7 3515.4 254659.0 256292.0 256934.0 256957.0 257332.0 52524.0 52910.0 52971.2 52978.5 53013.2 54552.5 54730.0 54904.0 54956.0 55177.7 15519.9 15790.0 15792.5 15801.2 15802.6 11177.8 11187.2 11190.2 11201.5 11231.7 14806.4 15256.2 15317.6 15332.3 15540.4 4885.4 5268.8 5440.4 5600.8 5682.9 797.9 872.4 891.3 936.5 952.1 27153.0 27941.2 28128.3 28810.3 29140.4 925.6 945.2 986.5 999.2 1058.7 14602.3 14819.9 15009.9 15433.6 15459.2 377986.0 381543.7 381942.0 382164.0 383408.0 50593.2 50838.7 50847.7 50858.2 50942.7 1076.2 1159.0 1196.4 1215.0 1228.6 28089.4 29253.3 29641.9 29659.6 30119.8 42832.6 43425.1 43441.6 43513.8 43532.0 308650.0 310441.0 310656.0 310828.0 311058.0 24775.0 25414.7 25521.9 26387.3 26612.7 42230.8 42802.0 43079.0 43349.1 43530.3 36292.3 36420.6 36624.8 36630.0 37425.1 36176.0 37070.4 37153.4 37565.8 37677.6 35593.6 36159.1 36209.8 36265.0 36885.6 31931.4 33162.4 33774.0 33893.5 34094.8 25223.9 25277.2 25388.0 25483.0 25871.0 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#2] jquery.html Cycles:180 Average:25781.22 Median:212365.90 stddev:63648.87 (30.0%) stddev-sans-first:63798.75 12:29:38 INFO - PROCESS | 23241 | Values: 336.0 357.3 366.9 370.4 382.2 12590.9 17805.6 18203.2 18479.9 18827.4 16414.9 17185.7 18300.5 18436.9 18466.8 4923.5 4994.2 5131.8 5132.4 5161.3 3713.4 5113.5 5149.2 5741.8 6035.7 6052.6 6076.5 6077.6 6086.3 6090.1 6082.3 6391.8 6672.6 6701.5 6829.3 6525.9 6544.5 6577.3 6578.0 6608.9 22750.3 23239.7 23639.8 24207.5 24285.2 1891.6 2119.8 2300.9 2614.8 2665.7 13588.2 13953.2 14534.0 14760.8 15129.3 1791.9 1833.3 1842.9 1867.4 1880.7 78699.2 87741.0 90197.5 91024.5 91309.0 17046.7 17137.7 17341.9 17467.8 17474.9 30505.8 30524.0 30589.4 30642.7 30856.2 9439.1 9515.6 9577.6 9603.2 9835.5 7934.3 7950.2 8156.8 8226.3 8298.0 8138.8 8419.7 8633.2 8635.5 8773.8 3051.8 3125.4 3315.7 3387.4 3388.7 365.5 792.5 834.7 915.7 918.0 10436.4 10576.5 10801.8 10823.8 10842.6 403.3 408.6 409.6 410.1 411.8 5713.7 5964.5 9519.6 10164.3 10451.4 370445.9 372623.0 385913.0 390740.0 391614.0 74179.0 82414.6 84519.0 86600.0 87286.0 912.4 958.4 967.5 970.4 992.7 11403.6 15352.9 15890.2 16288.1 16469.2 26241.6 27196.6 38359.8 38818.8 38983.4 51890.7 51962.7 52204.5 53538.2 53838.7 7863.2 7988.1 8096.2 8099.7 8204.5 10011.8 11618.1 12262.9 12748.2 12796.6 5015.0 8259.8 10425.4 10426.1 11293.4 10507.5 10616.4 10787.0 10821.4 10933.0 10297.5 10327.3 10672.2 10676.7 11268.9 6822.8 6963.7 6966.3 7951.2 7959.6 9672.6 9977.7 10111.1 10426.7 10633.6 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#3] mootools.html Cycles:180 Average:4985.00 Median:5169.38 stddev:2783.47 (53.8%) stddev-sans-first:2778.22 12:29:38 INFO - PROCESS | 23241 | Values: 1397.7 1414.9 1483.5 1658.9 1681.2 7286.5 7815.0 7815.9 7824.7 7870.1 6458.2 6718.5 7189.9 7494.6 7733.1 2136.3 2170.6 2171.4 2174.0 2175.3 2122.7 2145.2 2180.8 2185.5 2186.6 2141.3 2149.8 2161.5 2163.1 2175.0 2077.5 2087.1 2090.4 2090.9 2094.0 2062.8 2109.4 2110.7 2110.8 2113.3 7628.4 7753.8 7756.1 7759.9 7771.1 4443.1 4555.2 4556.0 4556.9 4560.1 2724.4 2800.3 2853.8 2858.5 2860.2 852.9 905.6 905.7 906.4 1707.6 9793.1 10187.1 10204.4 10215.7 10219.1 3746.1 3969.8 4003.8 4018.8 4032.6 8134.9 8145.2 8145.6 8148.6 8148.8 4493.9 4513.0 4513.6 4514.1 4515.1 3607.9 3997.7 4124.9 4231.1 4232.6 3237.2 3274.7 3330.6 3343.9 3346.1 1000.3 1029.0 1037.6 1043.4 1046.0 784.9 790.5 858.8 874.5 892.0 5091.5 5247.3 5251.3 5280.8 5467.0 371.3 372.5 380.9 381.4 383.8 3486.5 3668.8 3834.1 3839.0 3855.3 8978.6 9295.7 9414.1 9522.3 9560.0 9452.8 9458.4 9478.2 9479.9 9496.1 7209.2 7209.4 7262.7 7291.2 7308.5 5302.6 5317.1 5324.5 5333.4 5526.4 8771.3 8774.9 8790.1 8806.3 8807.7 8666.8 8870.2 9129.0 9151.3 9155.5 5377.5 6355.8 6418.4 6422.5 6427.8 6509.7 6650.5 6671.5 6700.1 7129.4 6147.6 6685.2 6825.6 6952.7 7071.0 6547.5 6696.6 6743.6 6841.8 6906.4 5843.1 5922.6 5926.1 5939.6 5942.6 4668.5 4729.6 4755.2 4766.9 4836.1 4514.1 4588.3 4603.9 4683.6 4751.7 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#4] prototype.html Cycles:180 Average:4167.64 Median:4772.59 stddev:1185.96 (24.8%) stddev-sans-first:1152.96 12:29:38 INFO - PROCESS | 23241 | Values: 286.6 291.5 299.0 299.3 299.6 4812.6 4813.0 4839.6 4840.4 4846.2 4498.6 4652.9 4691.5 4761.7 4777.8 3808.5 3812.3 3812.5 3824.1 3852.6 3811.6 3828.4 3832.1 3862.4 3871.3 3572.9 3630.5 3746.2 3765.2 3788.2 3756.9 3787.4 3795.4 3800.9 3802.6 3374.1 3383.8 3384.9 3390.6 3392.0 4466.3 4749.0 4751.8 4758.6 4763.1 4231.3 4236.2 4265.7 4268.9 4272.8 4978.3 4993.2 5017.1 5020.5 5025.7 3533.3 3546.6 3563.9 3575.1 3579.4 4988.8 5173.9 5241.1 5252.7 5253.3 3770.0 3789.3 3798.3 3818.7 3820.7 3864.1 3869.1 3870.3 3874.8 3876.1 4203.6 4339.0 4372.2 4384.6 4384.9 5015.6 5027.0 5034.6 5034.7 5058.3 1296.8 1313.4 1317.0 1317.9 1318.8 836.1 890.7 905.0 922.4 933.4 4392.6 4395.8 4396.8 4399.5 4403.1 5091.5 5096.2 5115.3 5127.5 5156.9 4276.9 4359.1 4359.9 4408.8 4425.0 2693.2 2703.3 2706.1 2712.9 2713.3 4811.1 4827.9 4831.4 4833.7 4834.2 4911.7 5149.3 5175.4 5186.5 5198.7 4835.2 4842.2 4843.1 4847.7 4847.9 5087.4 5092.9 5093.7 5103.2 5105.3 4793.2 4975.3 4991.2 5023.9 5081.5 4851.2 4854.7 4877.5 4878.2 4889.9 3587.8 3624.7 3630.2 3640.2 3646.2 5030.5 5121.0 5156.0 5273.2 5273.4 4928.6 4933.9 4968.9 4976.4 4977.9 4957.4 4972.4 5004.6 5005.7 5016.3 4729.8 4907.4 4948.5 4953.3 4968.2 4767.4 4840.8 4860.5 4992.3 5002.2 4827.9 4881.8 4897.7 4898.8 4903.3 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - PROCESS | 23241 | [#5] yui.html Cycles:180 Average:17671.19 Median:22133.73 stddev:25384.57 (114.7%) stddev-sans-first:25423.53 12:29:38 INFO - PROCESS | 23241 | Values: 630.5 695.3 737.2 737.9 749.0 11741.5 12848.6 12941.5 13028.6 13066.1 12146.6 12155.6 12188.5 12308.6 12320.8 776.4 797.6 799.8 799.9 800.7 638.4 755.2 776.5 783.1 794.6 836.5 837.9 838.5 839.2 840.1 831.0 831.9 832.1 832.6 833.0 484.6 487.9 498.6 503.8 590.9 12556.4 13085.4 13105.9 13112.2 13116.3 12552.9 12557.4 13075.9 13372.9 13559.7 11456.5 11881.3 12208.6 12310.1 12752.2 3963.9 4009.3 4045.0 4047.2 4138.2 66738.0 66988.3 67100.7 67213.7 67714.3 10527.3 10531.4 10548.3 10963.1 11048.8 18171.8 18283.7 18294.2 18303.9 18362.8 6609.8 6703.0 6984.4 7016.2 7035.4 3536.0 3617.3 3658.8 3672.1 3685.9 2711.9 2761.9 2779.9 2781.5 2804.8 442.5 445.6 449.6 451.2 459.8 544.8 557.5 569.8 574.1 578.2 7037.2 7348.7 7417.1 7463.5 7550.4 537.3 546.9 553.0 557.7 558.9 3276.9 3393.7 3427.2 3475.8 3493.8 97782.0 102996.5 103304.0 107333.0 107953.5 66443.0 73498.6 78200.6 79866.0 89263.6 45343.8 71326.6 73346.0 73883.3 74799.3 7849.6 8184.7 8323.0 8331.7 8398.6 42969.6 43715.8 43825.0 45057.3 45409.5 55785.7 62173.0 63186.0 65311.7 65458.7 11126.8 11377.5 11512.4 11590.9 11745.5 11755.4 11867.6 12075.3 12221.1 12634.1 10973.2 11410.5 12272.6 12282.1 12291.3 10952.5 11376.0 12532.2 12564.9 12676.0 11592.6 11765.3 11947.3 12692.3 12694.5 9794.4 9878.2 10014.9 10096.0 10576.0 8735.5 9008.6 9234.2 9322.3 10199.8 12:29:38 INFO - PROCESS | 23241 | -------- Summary: end -------- 12:29:38 INFO - PROCESS | 23241 | 12:29:38 INFO - TEST-INFO | 23241: exit 0 12:29:38 INFO - TEST-OK | dromaeo_css | took 1118602ms 12:29:38 INFO - TEST-START | kraken 12:29:38 INFO - operating with platform_type : linux_ 12:29:38 INFO - Initialising browser for kraken test... 12:29:38 INFO - TEST-INFO | started process 23433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8M53yg/profile http://localhost:39099/getInfo.html) 12:29:42 INFO - PROCESS | 23433 | JavaScript error: , line 0: uncaught exception: undefined 12:29:42 INFO - PROCESS | 23433 | __metrics Screen width/height:1600/1200 12:29:42 INFO - PROCESS | 23433 | colorDepth:24 12:29:42 INFO - PROCESS | 23433 | Browser inner width/height: 1024/697 12:29:42 INFO - PROCESS | 23433 | __metrics 12:29:43 INFO - PROCESS | 23433 | 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" 12:29:43 INFO - PROCESS | 23433 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:29:44 INFO - TEST-INFO | 23433: exit 0 12:29:44 INFO - Browser initialized. 12:29:44 INFO - Running cycle 1/1 for kraken test... 12:29:44 INFO - TEST-INFO | started process 23516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8M53yg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:56 INFO - PROCESS | 23516 | RSS: Main: 159019008 12:29:56 INFO - PROCESS | 23516 | 12:29:59 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39099/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:00 INFO - PROCESS | 23516 | RSS: Main: 215769088 12:30:00 INFO - PROCESS | 23516 | 12:30:02 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39099/tests/kraken/driver.html?testName=audio-dft) 12:30:03 INFO - PROCESS | 23516 | RSS: Main: 192847872 12:30:03 INFO - PROCESS | 23516 | 12:30:06 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39099/tests/kraken/driver.html?testName=audio-fft) 12:30:07 INFO - PROCESS | 23516 | RSS: Main: 273268736 12:30:07 INFO - PROCESS | 23516 | 12:30:09 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39099/tests/kraken/driver.html?testName=audio-oscillator) 12:30:10 INFO - PROCESS | 23516 | RSS: Main: 182210560 12:30:10 INFO - PROCESS | 23516 | 12:30:12 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39099/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:30:13 INFO - PROCESS | 23516 | RSS: Main: 186220544 12:30:13 INFO - PROCESS | 23516 | 12:30:16 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39099/tests/kraken/driver.html?testName=imaging-darkroom) 12:30:16 INFO - PROCESS | 23516 | RSS: Main: 223350784 12:30:16 INFO - PROCESS | 23516 | 12:30:20 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39099/tests/kraken/driver.html?testName=imaging-desaturate) 12:30:21 INFO - PROCESS | 23516 | RSS: Main: 217018368 12:30:21 INFO - PROCESS | 23516 | 12:30:23 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39099/tests/kraken/driver.html?testName=json-parse-financial) 12:30:24 INFO - PROCESS | 23516 | RSS: Main: 220155904 12:30:24 INFO - PROCESS | 23516 | 12:30:26 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39099/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:30:26 INFO - PROCESS | 23516 | RSS: Main: 185929728 12:30:26 INFO - PROCESS | 23516 | 12:30:28 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:30:29 INFO - PROCESS | 23516 | RSS: Main: 191438848 12:30:29 INFO - PROCESS | 23516 | 12:30:31 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:30:32 INFO - PROCESS | 23516 | RSS: Main: 196014080 12:30:32 INFO - PROCESS | 23516 | 12:30:34 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:35 INFO - PROCESS | 23516 | RSS: Main: 191500288 12:30:35 INFO - PROCESS | 23516 | 12:30:38 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:39 INFO - PROCESS | 23516 | RSS: Main: 193241088 12:30:39 INFO - PROCESS | 23516 | 12:30:40 INFO - PROCESS | 23516 | Cycle 1(1): loaded http://localhost:39099/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39099/tests/kraken/driver.html?testName=ai-astar) 12:30:41 INFO - PROCESS | 23516 | RSS: Main: 244576256 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | __start_tp_report 12:30:41 INFO - PROCESS | 23516 | _x_x_mozilla_page_load 12:30:41 INFO - PROCESS | 23516 | _x_x_mozilla_page_load_details 12:30:41 INFO - PROCESS | 23516 | |i|pagename|runs| 12:30:41 INFO - PROCESS | 23516 | |0;ai-astar;106;116;115;113;117;113;114;114;114;114 12:30:41 INFO - PROCESS | 23516 | |1;audio-beat-detection;150;125;124;181;123;125;153;122;168;128 12:30:41 INFO - PROCESS | 23516 | |2;audio-dft;163;163;160;163;162;161;163;161;163;162 12:30:41 INFO - PROCESS | 23516 | |3;audio-fft;76;77;76;119;75;76;76;78;112;74 12:30:41 INFO - PROCESS | 23516 | |4;audio-oscillator;86;85;130;85;86;85;115;86;85;125 12:30:41 INFO - PROCESS | 23516 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;119;118 12:30:41 INFO - PROCESS | 23516 | |6;imaging-darkroom;147;146;146;146;147;146;146;147;147;146 12:30:41 INFO - PROCESS | 23516 | |7;imaging-desaturate;95;95;95;95;95;94;96;96;95;95 12:30:41 INFO - PROCESS | 23516 | |8;json-parse-financial;63;61;62;63;62;63;61;62;61;67 12:30:41 INFO - PROCESS | 23516 | |9;json-stringify-tinderbox;66;66;65;65;67;65;66;66;66;67 12:30:41 INFO - PROCESS | 23516 | |10;stanford-crypto-aes;80;78;79;79;80;82;85;79;79;85 12:30:41 INFO - PROCESS | 23516 | |11;stanford-crypto-ccm;141;125;147;131;129;140;128;130;174;132 12:30:41 INFO - PROCESS | 23516 | |12;stanford-crypto-pbkdf2;166;161;162;160;163;164;164;161;161;163 12:30:41 INFO - PROCESS | 23516 | |13;stanford-crypto-sha256-iterative;68;66;70;66;68;67;69;67;69;67 12:30:41 INFO - PROCESS | 23516 | __end_tp_report 12:30:41 INFO - PROCESS | 23516 | __start_cc_report 12:30:41 INFO - PROCESS | 23516 | _x_x_mozilla_cycle_collect,1959 12:30:41 INFO - PROCESS | 23516 | __end_cc_report 12:30:41 INFO - PROCESS | 23516 | __startTimestamp1469215841011__endTimestamp 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | ------- Summary: start ------- 12:30:41 INFO - PROCESS | 23516 | Number of tests: 14 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:2.95 (2.6%) stddev-sans-first:1.33 12:30:41 INFO - PROCESS | 23516 | Values: 106.0 116.0 115.0 113.0 117.0 113.0 114.0 114.0 114.0 114.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:139.00 stddev:21.59 (15.5%) stddev-sans-first:22.59 12:30:41 INFO - PROCESS | 23516 | Values: 150.0 125.0 124.0 181.0 123.0 125.0 153.0 122.0 168.0 128.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#2] audio-dft Cycles:10 Average:162.10 Median:163.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 12:30:41 INFO - PROCESS | 23516 | Values: 163.0 163.0 160.0 163.0 162.0 161.0 163.0 161.0 163.0 162.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#3] audio-fft Cycles:10 Average:83.90 Median:76.00 stddev:16.77 (22.1%) stddev-sans-first:17.54 12:30:41 INFO - PROCESS | 23516 | Values: 76.0 77.0 76.0 119.0 75.0 76.0 76.0 78.0 112.0 74.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:85.00 stddev:18.67 (22.0%) stddev-sans-first:19.38 12:30:41 INFO - PROCESS | 23516 | Values: 86.0 85.0 130.0 85.0 86.0 85.0 115.0 86.0 85.0 125.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 12:30:41 INFO - PROCESS | 23516 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 119.0 118.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 12:30:41 INFO - PROCESS | 23516 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:30:41 INFO - PROCESS | 23516 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 96.0 96.0 95.0 95.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.50 stddev:1.78 (2.8%) stddev-sans-first:1.88 12:30:41 INFO - PROCESS | 23516 | Values: 63.0 61.0 62.0 63.0 62.0 63.0 61.0 62.0 61.0 67.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 12:30:41 INFO - PROCESS | 23516 | Values: 66.0 66.0 65.0 65.0 67.0 65.0 66.0 66.0 66.0 67.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.00 stddev:2.55 (3.2%) stddev-sans-first:2.69 12:30:41 INFO - PROCESS | 23516 | Values: 80.0 78.0 79.0 79.0 80.0 82.0 85.0 79.0 79.0 85.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:136.00 stddev:14.48 (10.7%) stddev-sans-first:15.31 12:30:41 INFO - PROCESS | 23516 | Values: 141.0 125.0 147.0 131.0 129.0 140.0 128.0 130.0 174.0 132.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:163.00 stddev:1.84 (1.1%) stddev-sans-first:1.45 12:30:41 INFO - PROCESS | 23516 | Values: 166.0 161.0 162.0 160.0 163.0 164.0 164.0 161.0 161.0 163.0 12:30:41 INFO - PROCESS | 23516 | 12:30:41 INFO - PROCESS | 23516 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.41 12:30:41 INFO - PROCESS | 23516 | Values: 68.0 66.0 70.0 66.0 68.0 67.0 69.0 67.0 69.0 67.0 12:30:41 INFO - PROCESS | 23516 | -------- Summary: end -------- 12:30:41 INFO - PROCESS | 23516 | 12:30:42 INFO - TEST-INFO | 23516: exit 0 12:30:42 INFO - TEST-OK | kraken | took 63286ms 12:30:42 INFO - SUITE-END | took 1181s 12:30:42 INFO - Completed test suite (00:19:41) 12:30:42 INFO - javascript benchmark 12:30:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2562.3337932137642, 2585.405356767942, 2614.516314481821, 2629.4478159586474, 2632.8946842126315, 4608.308753686949, 4657.0453057123295, 4667.276572131316, 4701.177293973554, 4765.166095933211, 4631.258998560231, 4667.906641867161, 4681.0871879983415, 4696.154692155411, 4709.387779588648, 4274.0, 4305.461817272841, 4324.84863029794, 4374.803133858984, 4485.753283569403, 4394.62645675118, 4429.069895321986, 4438.60052595268, 4449.1101779644205, 4461.1523810476065, 4226.028013556899, 4277.144571085764, 4288.614024737757, 4299.849505267301, 4350.738955662655, 4280.700350975433, 4282.957170428318, 4285.485741710983, 4322.546132656089, 4337.674674399401, 4296.849610263657, 4318.352247162919, 4325.52419233884, 4332.826686932519, 4376.387305777193, 4565.452145742499, 4638.930416043762, 4699.624030077585, 4702.365180700596, 4719.032598317371, 5048.974755126237, 5097.872553186163, 5102.132637451642, 5112.821051263188, 5118.155504341817, 5108.4125325587665, 5125.282460455539, 5129.820456284012, 5131.897362052738, 5139.691618502864, 4581.74800385982, 4665.603423708955, 4697.201475749062, 4710.505396933273, 4764.761761911891, 5151.304573882478, 5184.637075404686, 5188.792448302026, 5234.162533994619, 5234.790608375699, 4962.379702537183, 4969.801207951641, 5017.272495488134, 5019.47295533971, 5019.799208031711, 4897.534734200245, 4984.40187177541, 5032.0690672225755, 5035.974820125949, 5088.465711100281, 4979.05397974391, 5002.624803139779, 5006.173981293119, 5008.449070602231, 5054.974725126424, 4976.776045077981, 4989.201727723619, 4995.550400463903, 4998.150314446553, 5044.621653376012, 4200.180964711852, 4234.343676730112, 4237.385579091015, 4266.637335826489, 4287.314029755286, 3733.2346868892355, 3750.587435382106, 3832.6358995895343, 3846.7499612525658, 3849.653531182207, 4308.526062133163, 4311.568843115726, 4318.935215971763, 4344.23975804233, 4354.1944740222525, 4839.225723884232, 5030.446650868402, 5031.270465782441, 5035.219540971156, 5077.060743762416, 4236.195122926634, 4274.038341373166, 4286.507051689034, 4295.033617436115, 4347.195768782785, 4577.359169716243, 4578.908421831545, 4583.129205450954, 4613.0543238636, 4621.884452888705, 4748.145333839942, 4756.524347565285, 4758.809647614126, 4764.047190561971, 4807.326974223682, 4829.7585120744525, 4863.562279394871, 4918.286848407032, 4969.850904472872, 5029.06962211992, 4712.363830882787, 4717.3159891816185, 4731.787069581808, 4755.5719985201495, 4759.714417134983, 4917.58200552957, 4920.72935988524, 4929.309896614405, 4938.950610493849, 4940.802367905244, 4932.975335123301, 4943.950560494349, 4962.45413004562, 4977.303177555073, 5014.924776128434, 4832.685875418087, 4833.951660483491, 4835.8065677372515, 4869.634777391639, 4881.243407271879, 4840.128776820206, 4844.588210002048, 4848.8302909398, 4849.903001940012, 4901.583365413837, 4683.8360657376825, 4700.083483720641, 4703.05938812232, 4780.235162374003, 4781.9043619128115, 4826.493218212177, 4846.466888642317, 4847.297141914332, 4864.489228630943, 4894.486078961801, 4741.810327586996, 4743.549362810528, 4746.145693775154, 4747.501512341154, 4782.808687652455], "value": 4608.213495013761, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.5239785847167, 579.4466284698112, 586.1764221269116, 591.9644821310725, 596.25468164794, 27234.727652723468, 30367.54448683266, 30398.848005759966, 30399.240018999535, 30432.84783576082, 39671.80164099183, 40549.59450405495, 40695.38956915641, 44709.55290447095, 44738.32892506607, 7709.961450192741, 7868.645910933993, 7869.016372953381, 7871.094824095217, 7871.685132594718, 7798.688052477894, 7857.960710196469, 7882.093559240675, 7882.881756773653, 7883.172266911978, 7883.014623172104, 7912.604369781517, 7914.40641951853, 7917.841643167133, 7925.128235894047, 7816.218378162138, 7835.686572537092, 7875.645595948196, 7880.0937892142565, 7880.36957043438, 7732.922670773277, 7798.2201779822135, 7801.414893882981, 7801.531908085533, 7803.531788092732, 38647.227055459014, 38919.805400972815, 38954.41568376477, 39032.0, 39056.80471597652, 3138.152698771342, 3155.7002084801907, 3230.1601583588435, 3381.881634142818, 3725.1990821973404, 28855.711442885724, 29077.41845163106, 29636.70363296361, 29677.258068548243, 29719.0, 3422.0, 3451.913702157441, 3479.2693534357813, 3506.6843984041557, 3515.402381595135, 254659.0, 256292.0, 256934.0, 256957.0, 257332.0, 52524.0, 52910.0, 52971.205431918555, 52978.470215298126, 53013.204801928005, 54552.45447545474, 54730.0, 54904.0, 54956.0, 55177.72411137919, 15519.922400387926, 15790.0, 15792.526224213292, 15801.209939502978, 15802.60493487672, 11177.83233251502, 11187.216894817284, 11190.160737944687, 11201.495932683049, 11231.663050108345, 14806.407743690348, 15256.160911149993, 15317.6170595736, 15332.310046047955, 15540.378384864478, 4885.374707842203, 5268.762905669254, 5440.401555828856, 5600.803971860965, 5682.943170568324, 797.9281864632095, 872.3500991761179, 891.3092353426147, 936.4615346175976, 952.1383148250844, 27152.963527735497, 27941.161765146673, 28128.253175098765, 28810.27974300659, 29140.417191656048, 925.560358829564, 945.177695405002, 986.5264672957021, 999.1906555689915, 1058.7300238439302, 14602.269886505632, 14819.851801481847, 15009.924950375396, 15433.614159646098, 15459.227038648247, 377986.0, 381543.7382504669, 381942.0, 382164.0, 383408.0, 50593.24110138351, 50838.74580627073, 50847.745761270955, 50858.237126443135, 50942.74528627333, 1076.155218153761, 1158.9826152607718, 1196.443653701033, 1214.969625759363, 1228.588422878328, 28089.43821123607, 29253.268668282613, 29641.851790740908, 29659.555106672986, 30119.849400752857, 42832.57167428286, 43425.13149736924, 43441.56558434375, 43513.78243108764, 43532.0, 308650.0, 310441.0, 310656.0, 310828.0, 311058.0, 24775.0, 25414.745852541237, 25521.872390637927, 26387.340316491474, 26612.733872661025, 42230.78884605557, 42802.0, 43079.0, 43349.13301734011, 43530.34704479372, 36292.278079143645, 36420.63579364278, 36624.81687591545, 36630.0, 37425.06437339088, 36176.0, 37070.44394334141, 37153.442698359, 37565.81217093897, 37677.62322376741, 35593.64406355903, 36159.096022599646, 36209.818950906134, 36265.0, 36885.631143689294, 31931.36137277288, 33162.403414599896, 33774.0, 33893.49159762655, 34094.82952585221, 25223.864926078004, 25277.241682749547, 25388.0, 25483.0, 25871.0], "value": 17426.405938387656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.04395494817237, 357.27116681968783, 366.85875937763956, 370.35927844828444, 382.18022342076245, 12590.874091259084, 17805.643887122267, 18203.18085686144, 18479.90760046198, 18827.43517694468, 16414.917925410387, 17185.74221386677, 18300.542486437847, 18436.907815460905, 18466.815331846676, 4923.507649235084, 4994.175960966436, 5131.794728210867, 5132.358455193101, 5161.30322406476, 3713.4244192150127, 5113.463086375914, 5149.201873709581, 5741.799037033705, 6035.668038257894, 6052.636841789485, 6076.453119219269, 6077.604955677867, 6086.269647642289, 6090.056041313604, 6082.270127584696, 6391.840203994914, 6672.566283191577, 6701.463882889358, 6829.317068293131, 6525.869482610321, 6544.541882068257, 6577.276499585042, 6577.967110164419, 6608.900866487006, 22750.317490475147, 23239.651405228935, 23639.763602363928, 24207.515849683085, 24285.150019749224, 1891.640588288221, 2119.7562280337806, 2300.896459659319, 2614.816962812604, 2665.720099389575, 13588.184708917397, 13953.232572208524, 14534.0, 14760.778588321184, 15129.319180636898, 1791.9193636286398, 1833.3399976008627, 1842.8986405747678, 1867.3650958674116, 1880.7178923161498, 78699.21300787033, 87741.0, 90197.54901225583, 91024.54487727518, 91309.0, 17046.74429883553, 17137.657246854993, 17341.913290433466, 17467.825321746874, 17474.91262543679, 30505.847470762503, 30524.0, 30589.388212236077, 30642.693573063985, 30856.228594285323, 9439.103285187895, 9515.619375224913, 9577.569009394594, 9603.183729383081, 9835.508224588742, 7934.2859142677435, 7950.165232650604, 8156.755297341089, 8226.341892648694, 8297.95851020753, 8138.755837324771, 8419.663213471515, 8633.223009929137, 8635.525046122524, 8773.824523509495, 3051.8474076296097, 3125.359301343219, 3315.701586857146, 3387.356402283558, 3388.7119594834717, 365.47189311445237, 792.4809249941297, 834.7412302186342, 915.7207051849117, 917.9718715039198, 10436.425996570262, 10576.52405641748, 10801.83797243042, 10823.83764243537, 10842.620508282173, 403.31235243909066, 408.6424378668665, 409.5801803151734, 410.0896010855895, 411.7899871065718, 5713.714314284269, 5964.522838172936, 9519.571619277149, 10164.33931794431, 10451.372917624967, 370445.9066503903, 372623.0, 385913.0, 390740.0, 391614.0, 74179.03283868692, 82414.58792705998, 84519.0, 86600.0, 87286.0, 912.3841407050214, 958.3818437108166, 967.4582233949152, 970.4177493503842, 992.7270000749852, 11403.600873969372, 15352.923235383752, 15890.219211637686, 16288.104154271628, 16469.176541173132, 26241.606375903993, 27196.592051118852, 38359.84920452391, 38818.80590597029, 38983.41524877186, 51890.74054629703, 51962.74018630034, 52204.47795522148, 53538.196927046905, 53838.73080634572, 7863.174366691415, 7988.081370642451, 8096.190380961857, 8099.676012959651, 8204.507729536246, 10011.799764004825, 11618.128640351839, 12262.938685306517, 12748.235105893677, 12796.552120675731, 5015.0, 8259.83480330402, 10425.426601536989, 10426.113780328757, 11293.43532823339, 10507.527161277609, 10616.416097114732, 10787.0, 10821.404822734825, 10933.0, 10297.53661085238, 10327.32872363324, 10672.199585031309, 10676.679699608714, 11268.943180454866, 6822.829429264109, 6963.721451141898, 6966.268541803241, 7951.165127661512, 7959.641816118405, 9672.564734587102, 9977.650782222587, 10111.090001899864, 10426.739331516772, 10633.574657013942], "value": 8955.182909528156, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.6925076483174, 1414.8868090552758, 1483.517856696574, 1658.8507034366905, 1681.2350380576831, 7286.52637578557, 7815.0231221097365, 7815.882761758578, 7824.687012519499, 7870.134285228621, 6458.225012998447, 6718.529702920798, 7189.89215161773, 7494.625268736569, 7733.072031356245, 2136.305700647288, 2170.6092903277404, 2171.381156370435, 2173.978260217393, 2175.3147758456034, 2122.745270567534, 2145.184829764687, 2180.7819218078143, 2185.49733561281, 2186.6173419651577, 2141.3468891987936, 2149.7746284617774, 2161.502854343502, 2163.134746101556, 2174.9782502174935, 2077.5221699009235, 2087.09211493001, 2090.3833369156137, 2090.905909234088, 2094.036743098177, 2062.8143467087884, 2109.44099813548, 2110.7150534677776, 2110.799474049963, 2113.260358874397, 7628.427867909929, 7753.844923101563, 7756.069271687434, 7759.922400775977, 7771.067471903408, 4443.133588950156, 4555.157295900269, 4555.954440455619, 4556.931646025312, 4560.065186636732, 2724.4142509360386, 2800.2999250187454, 2853.785966052554, 2858.5426331786816, 2860.184847318504, 852.8658365044344, 905.6241659711212, 905.7101727447154, 906.4380084347747, 1707.607250332418, 9793.069658382443, 10187.083162515335, 10204.387736735745, 10215.744606384826, 10219.080282774587, 3746.138388170736, 3969.801509924492, 4003.7597744135446, 4018.819153138116, 4032.6169013943627, 8134.877976830352, 8145.185481451926, 8145.551994640233, 8148.633311500997, 8148.796280093045, 4493.865184044484, 4512.954870451319, 4513.571210734975, 4514.052049069732, 4515.1195516873895, 3607.9458808117897, 3997.6801855851463, 4124.876253712393, 4231.11146659205, 4232.640225580799, 3237.190702324419, 3274.705276525124, 3330.5670262865674, 3343.8996830095134, 3346.130006198406, 1000.2897942460934, 1028.9639862604772, 1037.6108959140322, 1043.4052590023766, 1046.0428707732492, 784.9175836537081, 790.5454363740872, 858.7895965488495, 874.4840544079024, 891.9598618062203, 5091.465396133352, 5247.291615631917, 5251.264822924793, 5280.841574752763, 5467.043267428184, 371.3260432315339, 372.4562139276632, 380.8895420328129, 381.4373798647029, 383.78316251317915, 3486.546748922624, 3668.834902429397, 3834.1181528248103, 3839.0, 3855.286771947143, 8978.640854365753, 9295.72112836616, 9414.058594140532, 9522.285828562886, 9560.043995600385, 9452.763680907894, 9458.432494050241, 9478.194353480032, 9479.905200947902, 9496.097870702271, 7209.2069872314005, 7209.423246140296, 7262.673179706926, 7291.197968223597, 7308.451866110063, 5302.602304827076, 5317.1492561190025, 5324.494173053554, 5333.439988801275, 5526.447355264602, 8771.298296136167, 8774.868376974222, 8790.120987901159, 8806.251468625238, 8807.735767926972, 8666.78333041679, 8870.15733505316, 9129.0, 9151.3136514763, 9155.542222888696, 5377.542908852792, 6355.841103972438, 6418.358164183544, 6422.454091402096, 6427.839304017437, 6509.7396104155305, 6650.467962562887, 6671.532992690465, 6700.128983232149, 7129.39400150993, 6147.569670123048, 6685.197776266879, 6825.624590647562, 6952.687129079102, 7071.0, 6547.476201903741, 6696.5647232929705, 6743.561668491532, 6841.794746157623, 6906.447484201352, 5843.065109582446, 5922.585419020627, 5926.051831707078, 5939.613925094855, 5942.58401911862, 4668.463126740333, 4729.645276604338, 4755.1916174251155, 4766.928496072631, 4836.081144582518, 4514.074614703912, 4588.311753237042, 4603.907921841612, 4683.578477937002, 4751.717036400221], "value": 3961.201992287291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.60734793333125, 291.49280252360893, 299.0430622009569, 299.29068108582663, 299.6075141564549, 4812.614990800736, 4812.9518704812945, 4839.637027222959, 4840.44334901486, 4846.20037693781, 4498.5501449855, 4652.860414187579, 4691.4987204094705, 4761.69049011814, 4777.808887644499, 3808.5239345081864, 3812.275667623143, 3812.542494900616, 3824.1395685970574, 3852.6339997700175, 3811.5997820228754, 3828.3874580067195, 3832.13776900198, 3862.377801323451, 3871.341871881787, 3572.8749493767723, 3630.455431685242, 3746.1571146492092, 3765.171662234304, 3788.223414200096, 3756.906077348061, 3787.394016957274, 3795.35478968576, 3800.923981520382, 3802.6007269236748, 3374.1227280906896, 3383.8477268522975, 3384.8660698665976, 3390.627031026585, 3391.965450537585, 4466.263066594008, 4749.0, 4751.809927602892, 4758.571728544413, 4763.118823017753, 4231.344141658048, 4236.237477254093, 4265.698961816644, 4268.935965960513, 4272.829086836523, 4978.303037574742, 4993.151164302078, 5017.071841709296, 5020.548150666457, 5025.693319736858, 3533.2756784859043, 3546.556680414948, 3563.875264365735, 3575.106223444139, 3579.427291633352, 4988.800447982113, 5173.870653233662, 5241.117385438032, 5252.65857719247, 5253.264542963988, 3770.0, 3789.2610940866266, 3798.3352913240074, 3818.6825545186866, 3820.675242604354, 3864.091938394475, 3869.129445874712, 3870.2839974604226, 3874.767513949172, 3876.127871228951, 4203.600657937491, 4339.0, 4372.213001659732, 4384.56154384559, 4384.9123017540105, 5015.6489045767175, 5027.0, 5034.572061374824, 5034.748262586856, 5058.266551350033, 1296.786030305008, 1313.3761463304854, 1317.0451422718452, 1317.9406926688323, 1318.7955866840655, 836.1220718245818, 890.7149712092069, 904.9728508144766, 922.3589605224439, 933.3839665820526, 4392.648588112943, 4395.824167033346, 4396.846110386121, 4399.5380485049245, 4403.075354175595, 5091.516305950929, 5096.210087436453, 5115.283860259567, 5127.461616530285, 5156.92264616031, 4276.87169384919, 4359.12817436514, 4359.891002724958, 4408.779561021936, 4424.955750442518, 2693.1920423873153, 2703.324168957761, 2706.0799328228495, 2712.8779204935827, 2713.280980540119, 4811.061842940593, 4827.8551643450755, 4831.371921650163, 4833.661643684908, 4834.154023045953, 4911.729854857946, 5149.2791009258735, 5175.430702622709, 5186.455422180692, 5198.740062996835, 4835.178019736653, 4842.15262329091, 4843.128236917389, 4847.660663753503, 4847.927281090716, 5087.430847422028, 5092.949070509248, 5093.668911520887, 5103.209002604603, 5105.28526006352, 4793.18515852299, 4975.253711943237, 4991.176455884738, 5023.915075243879, 5081.517255860687, 4851.223804191312, 4854.69880481262, 4877.487863774394, 4878.243872199815, 4889.877753056202, 3587.7847329160336, 3624.746267761337, 3630.183208778004, 3640.2355505344176, 3646.234290798856, 5030.4718004608985, 5120.975804838972, 5155.9742201288755, 5273.182656688219, 5273.367195936359, 4928.644622728778, 4933.9013219736125, 4968.925466117938, 4976.377952755905, 4977.850664480072, 4957.429895562056, 4972.353594032753, 5004.5746111579465, 5005.724685142352, 5016.297718319365, 4729.787159577896, 4907.386576677915, 4948.505149485167, 4953.3065370848835, 4968.155413579628, 4767.380240568704, 4840.83057093, 4860.513948605111, 4992.301077849032, 5002.224655178453, 4827.8551643450755, 4881.780319885543, 4897.657163998486, 4898.828541001048, 4903.33804936329], "value": 3792.6926945772193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.4798541203506, 695.3290075077553, 737.2001378504323, 737.8677513953269, 748.973785917493, 11741.471633776478, 12848.614541563744, 12941.482340706372, 13028.60914172574, 13066.085374023822, 12146.574869879556, 12155.635330940064, 12188.512459501631, 12308.630741077783, 12320.753584928318, 776.4370831147429, 797.6330887791623, 799.7560743973086, 799.9400044996648, 800.6677228949978, 638.4349850382422, 755.176857225624, 776.5379599138504, 783.1189911349732, 794.5629903553051, 836.5440834745048, 837.8659414493683, 838.5220424358118, 839.2488722593306, 840.1430540848331, 830.9667613295492, 831.8502669519484, 832.0888626953476, 832.5504227717025, 832.9541875196832, 484.5711545282415, 487.9243717223836, 498.59862810437585, 503.83625321770205, 590.9290885093774, 12556.434960426803, 13085.411156497885, 13105.934470327682, 13112.213267203902, 13116.344182790823, 12552.882302469387, 12557.434915428828, 13075.934620326932, 13372.86627133726, 13559.728805423934, 11456.4844581994, 11881.287122772575, 12208.572699955459, 12310.076744244217, 12752.171108877987, 3963.9009024774323, 4009.2613339308273, 4045.02919299369, 4047.1703300823556, 4138.234426631053, 66738.0, 66988.3301166987, 67100.6644966772, 67213.66393168052, 67714.3228567713, 10527.315724478038, 10531.368117912949, 10548.261621686563, 10963.122950163968, 11048.779024419466, 18171.818281817013, 18283.72574411385, 18294.17676204574, 18303.90848045778, 18362.81637183638, 6609.778414289719, 6703.0, 6984.406325462291, 7016.228214896358, 7035.437165026786, 3536.0, 3617.2584620152807, 3658.7804731716183, 3672.1186915140174, 3685.9078523037138, 2711.891524339044, 2761.8757155928033, 2779.9444011119663, 2781.4715204111153, 2804.7615952644255, 442.45136031321573, 445.59005714742517, 449.6357950060461, 451.19011374582703, 459.83905633028843, 544.7548603128529, 557.5316733943425, 569.8262030080782, 574.133059080791, 578.1587789765881, 7037.19072306681, 7348.70605175787, 7417.109946806418, 7463.477556570988, 7550.433717471212, 537.2854104041693, 546.8578169675833, 552.9557635389159, 557.6904817826146, 558.9133684278943, 3276.8689252429635, 3393.7497196792556, 3427.2288735034417, 3475.7740746851373, 3493.807840568793, 97782.0, 102996.48501757444, 103304.0, 107333.0, 107953.46023269833, 66443.0, 73498.63250683712, 78200.6089969558, 79865.99999999884, 89263.55368223117, 45343.773281133384, 71326.64336678387, 73346.0, 73883.26116738871, 74799.25200747923, 7849.646765895663, 8184.672613095648, 8323.0, 8331.708390206313, 8398.622062007102, 42969.570304297806, 43715.78142109269, 43825.0, 45057.324140137265, 45409.545904541854, 55785.721071394386, 62173.0, 63186.0, 65311.67344163439, 65458.672706636164, 11126.833097503706, 11377.488013039267, 11512.366819824654, 11590.879763358329, 11745.47145378477, 11755.412229388667, 11867.58463453775, 12075.27548347102, 12221.144519883523, 12634.115611907078, 10973.177011724312, 11410.543578256777, 12272.570460033856, 12282.140250182401, 12291.262524248574, 10952.507137179005, 11376.0, 12532.248065116122, 12564.874351256736, 12676.0, 11592.594259200887, 11765.294082355085, 11947.342896140795, 12692.301923394301, 12694.492220311084, 9794.412335259907, 9878.16035636979, 10014.899851001397, 10096.0, 10576.0, 8735.475871447734, 9008.59461324255, 9234.1689247968, 9322.300827437834, 10199.847002294822], "value": 6491.463135706375, "unit": "score"}], "name": "dromaeo_css", "value": 6421.909407953388, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 115.0, 113.0, 117.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 124.0, 181.0, 123.0, 125.0, 153.0, 122.0, 168.0, 128.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 160.0, 163.0, 162.0, 161.0, 163.0, 161.0, 163.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 119.0, 75.0, 76.0, 76.0, 78.0, 112.0, 74.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 130.0, 85.0, 86.0, 85.0, 115.0, 86.0, 85.0, 125.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 63.0, 62.0, 63.0, 61.0, 62.0, 61.0, 67.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 67.0, 65.0, 66.0, 66.0, 66.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 79.0, 80.0, 82.0, 85.0, 79.0, 79.0, 85.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 125.0, 147.0, 131.0, 129.0, 140.0, 128.0, 130.0, 174.0, 132.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 162.0, 160.0, 163.0, 164.0, 164.0, 161.0, 161.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 70.0, 66.0, 68.0, 67.0, 69.0, 67.0, 69.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1532.1, "alertThreshold": 2.0}]} 12:30:42 INFO - Return code: 0 12:30:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:30:42 INFO - # TBPL SUCCESS # 12:30:42 INFO - Running post-action listener: _resource_record_post_action 12:30:42 INFO - [mozharness: 2016-07-22 19:30:42.289295Z] Finished run-tests step (success) 12:30:42 INFO - Running post-run listener: _resource_record_post_run 12:30:42 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 540114944; Read time: 0; Write time: 371204 12:30:42 INFO - TinderboxPrint: CPU usage
13.0% 12:30:42 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:30:42 INFO - TinderboxPrint: I/O write bytes / time
540,114,944 / 371,204 12:30:42 INFO - TinderboxPrint: CPU idle
8,328.9 (87.0%) 12:30:42 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 12:30:42 INFO - TinderboxPrint: Swap in / out
0 / 0 12:30:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246738944; Read time: 0; Write time: 192740 12:30:42 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 288886784; Read time: 0; Write time: 177824 12:30:42 INFO - Running post-run listener: _upload_blobber_files 12:30:42 INFO - Blob upload gear active. 12:30:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:30:42 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 12:30:42 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:30:42 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:30:43 INFO - (blobuploader) - INFO - Open directory for files ... 12:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:57 INFO - (blobuploader) - INFO - Done attempting. 12:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:57 INFO - (blobuploader) - INFO - Done attempting. 12:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:58 INFO - (blobuploader) - INFO - Done attempting. 12:30:58 INFO - (blobuploader) - INFO - Iteration through files over. 12:30:58 INFO - Return code: 0 12:30:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:30:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:30:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b622ae6c3cfa7c1f4ef7a1f95230521db3b4e3e8791c2244ec532c0b7201c83ca4c81ded0fc2e9983649eb3c79a1b8accc0fbcea14f8dab8a9b86697adbf2ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3f2dc0aa0f83b43d8aa573b8a5578a1ac8e3df3fb444d21aa62bfc3081c2ec7b085b047c785528be0d786bff7d5aa7927b16226d898929a1151c092869b0d595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/37a7d1b618c756f798269421c8d0ecd587fb944e3c1ce0ed2826c272c1a4b82bcdc6aac4ef1d748f82eeba642f448268abc2c41c435043911272ebebb3d34c38"} 12:30:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:30:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:30:58 INFO - Contents: 12:30:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b622ae6c3cfa7c1f4ef7a1f95230521db3b4e3e8791c2244ec532c0b7201c83ca4c81ded0fc2e9983649eb3c79a1b8accc0fbcea14f8dab8a9b86697adbf2ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3f2dc0aa0f83b43d8aa573b8a5578a1ac8e3df3fb444d21aa62bfc3081c2ec7b085b047c785528be0d786bff7d5aa7927b16226d898929a1151c092869b0d595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/37a7d1b618c756f798269421c8d0ecd587fb944e3c1ce0ed2826c272c1a4b82bcdc6aac4ef1d748f82eeba642f448268abc2c41c435043911272ebebb3d34c38"} 12:30:58 INFO - Running post-run listener: copy_logs_to_upload_dir 12:30:58 INFO - Copying logs to upload dir... 12:30:58 INFO - mkdir: /builds/slave/test/build/upload/logs 12:30:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.375035 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-22 12:30:57.766465) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:30:57.769867) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 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-Non-PGO/sha512/b622ae6c3cfa7c1f4ef7a1f95230521db3b4e3e8791c2244ec532c0b7201c83ca4c81ded0fc2e9983649eb3c79a1b8accc0fbcea14f8dab8a9b86697adbf2ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3f2dc0aa0f83b43d8aa573b8a5578a1ac8e3df3fb444d21aa62bfc3081c2ec7b085b047c785528be0d786bff7d5aa7927b16226d898929a1151c092869b0d595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/37a7d1b618c756f798269421c8d0ecd587fb944e3c1ce0ed2826c272c1a4b82bcdc6aac4ef1d748f82eeba642f448268abc2c41c435043911272ebebb3d34c38"} build_url:https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/TVkrAuPSQruH5pLy35hoFQ/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-Non-PGO/sha512/b622ae6c3cfa7c1f4ef7a1f95230521db3b4e3e8791c2244ec532c0b7201c83ca4c81ded0fc2e9983649eb3c79a1b8accc0fbcea14f8dab8a9b86697adbf2ae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3f2dc0aa0f83b43d8aa573b8a5578a1ac8e3df3fb444d21aa62bfc3081c2ec7b085b047c785528be0d786bff7d5aa7927b16226d898929a1151c092869b0d595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/37a7d1b618c756f798269421c8d0ecd587fb944e3c1ce0ed2826c272c1a4b82bcdc6aac4ef1d748f82eeba642f448268abc2c41c435043911272ebebb3d34c38"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:30:57.795786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:30:57.796173) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469214610.474181-1711074967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:30:57.882847) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:30:57.883381) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:30:57.883874) ========= ========= Total master_lag: 0.16 =========