builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1469217942.3 results: success (0) buildid: 20160722083025 builduid: 90f9e25df22548d1a017b0ededf34064 revision: ff29f08b72c466b65dea5efa6f82f99add8f17ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.295464) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.295896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.296170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.310844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.311133) ========= 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=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 13:05:42-- 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% 37.5M=0s 2016-07-22 13:05:42 (37.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109754 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.434944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.435375) ========= 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=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.485588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:42.485970) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ff29f08b72c466b65dea5efa6f82f99add8f17ef --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 ff29f08b72c466b65dea5efa6f82f99add8f17ef --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=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 13:05:42,922 truncating revision to first 12 chars 2016-07-22 13:05:42,922 Setting DEBUG logging. 2016-07-22 13:05:42,922 attempt 1/10 2016-07-22 13:05:42,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ff29f08b72c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 13:05:43,237 unpacking tar archive at: fx-team-ff29f08b72c4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652784 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:43.185611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:43.185960) ========= script_repo_revision: ff29f08b72c466b65dea5efa6f82f99add8f17ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:43.186419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:43.186712) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 13:05:43.196074) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-22 13:05:43.196378) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:05:43 INFO - MultiFileLogger online at 20160722 13:05:43 in /builds/slave/test 13:05:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:05:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:05:43 INFO - {'append_to_log': False, 13:05:43 INFO - 'base_work_dir': '/builds/slave/test', 13:05:43 INFO - 'blob_upload_branch': 'Fx-Team', 13:05:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:05:43 INFO - 'branch': 'Fx-Team', 13:05:43 INFO - 'buildbot_json_path': 'buildprops.json', 13:05:43 INFO - 'config_files': ('talos/linux_config.py',), 13:05:43 INFO - 'default_actions': ('clobber', 13:05:43 INFO - 'read-buildbot-config', 13:05:43 INFO - 'download-and-extract', 13:05:43 INFO - 'populate-webroot', 13:05:43 INFO - 'create-virtualenv', 13:05:43 INFO - 'install', 13:05:43 INFO - 'run-tests'), 13:05:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:05:43 INFO - 'download_minidump_stackwalk': True, 13:05:43 INFO - 'download_symbols': 'ondemand', 13:05:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:05:43 INFO - 'tooltool.py': '/tools/tooltool.py', 13:05:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:05:43 INFO - '/tools/misc-python/virtualenv.py')}, 13:05:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:05:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:05:43 INFO - 'installer_path': 'installer.exe', 13:05:43 INFO - 'log_level': 'info', 13:05:43 INFO - 'log_name': 'talos', 13:05:43 INFO - 'log_to_console': True, 13:05:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:05:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:05:43 INFO - 'opt_config_files': (), 13:05:43 INFO - 'pip_index': False, 13:05:43 INFO - 'sps_profile': False, 13:05:43 INFO - 'sps_profile_interval': 0, 13:05:43 INFO - 'suite': 'dromaeojs', 13:05:43 INFO - 'system_bits': '32', 13:05:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:05:43 INFO - 'title': 'talos-linux64-ix-025', 13:05:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:05:43 INFO - 'use_talos_json': True, 13:05:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:05:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:05:43 INFO - 'work_dir': 'build'} 13:05:43 INFO - [mozharness: 2016-07-22 20:05:43.690655Z] Running clobber step. 13:05:43 INFO - Running pre-action listener: _resource_record_pre_action 13:05:43 INFO - Running main action method: clobber 13:05:43 INFO - rmtree: /builds/slave/test/build 13:05:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:05:44 INFO - Running post-action listener: _resource_record_post_action 13:05:44 INFO - [mozharness: 2016-07-22 20:05:44.678132Z] Finished clobber step (success) 13:05:44 INFO - [mozharness: 2016-07-22 20:05:44.678232Z] Running read-buildbot-config step. 13:05:44 INFO - Running pre-action listener: _resource_record_pre_action 13:05:44 INFO - Running main action method: read_buildbot_config 13:05:44 INFO - Using buildbot properties: 13:05:44 INFO - { 13:05:44 INFO - "project": "", 13:05:44 INFO - "product": "firefox", 13:05:44 INFO - "script_repo_revision": "production", 13:05:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 13:05:44 INFO - "repository": "", 13:05:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 13:05:44 INFO - "buildid": "20160722083025", 13:05:44 INFO - "pgo_build": "True", 13:05:44 INFO - "basedir": "/builds/slave/test", 13:05:44 INFO - "buildnumber": 1348, 13:05:44 INFO - "slavename": "talos-linux64-ix-025", 13:05:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:05:44 INFO - "platform": "ubuntu64_hw", 13:05:44 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 13:05:44 INFO - "branch": "fx-team", 13:05:44 INFO - "repo_path": "integration/fx-team", 13:05:44 INFO - "revision": "ff29f08b72c466b65dea5efa6f82f99add8f17ef", 13:05:44 INFO - "stage_platform": "linux64-pgo", 13:05:44 INFO - "builduid": "90f9e25df22548d1a017b0ededf34064", 13:05:44 INFO - "slavebuilddir": "test" 13:05:44 INFO - } 13:05:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 13:05:44 INFO - Running post-action listener: _resource_record_post_action 13:05:44 INFO - [mozharness: 2016-07-22 20:05:44.680662Z] Finished read-buildbot-config step (success) 13:05:44 INFO - [mozharness: 2016-07-22 20:05:44.680752Z] Running download-and-extract step. 13:05:44 INFO - Running pre-action listener: _resource_record_pre_action 13:05:44 INFO - Running main action method: download_and_extract 13:05:44 INFO - mkdir: /builds/slave/test/build/tests 13:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:44 INFO - https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:05:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:05:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:05:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:45 INFO - Downloaded 1361 bytes. 13:05:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:05:45 INFO - Using the following test package requirements: 13:05:45 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:05:45 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:05:45 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:05:45 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'jsshell-linux-x86_64.zip'], 13:05:45 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:05:45 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:05:45 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:05:45 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:05:45 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:05:45 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:05:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:05:45 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:05:45 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')]} 13:05:45 INFO - https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:05:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:05:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:05:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:46 INFO - Downloaded 24308968 bytes. 13:05:46 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'] 13:05:46 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 13:05:47 INFO - Return code: 0 13:05:47 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 13:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:47 INFO - https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:05:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:05:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:05:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:48 INFO - Downloaded 24308968 bytes. 13:05:48 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'] 13:05:48 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 13:05:50 INFO - Return code: 0 13:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:50 INFO - https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:05:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:05:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:05:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:51 INFO - Downloaded 14403289 bytes. 13:05:51 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'] 13:05:51 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 13:05:51 INFO - Return code: 0 13:05:51 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')]} 13:05:51 INFO - https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:05:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:05:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:05:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/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 13:05:52 INFO - Downloaded 60849208 bytes. 13:05:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:05:52 INFO - mkdir: /builds/slave/test/properties 13:05:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:05:52 INFO - Writing to file /builds/slave/test/properties/build_url 13:05:52 INFO - Contents: 13:05:52 INFO - build_url:https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:05:53 INFO - Running post-action listener: _resource_record_post_action 13:05:53 INFO - Running post-action listener: set_extra_try_arguments 13:05:53 INFO - [mozharness: 2016-07-22 20:05:53.400055Z] Finished download-and-extract step (success) 13:05:53 INFO - [mozharness: 2016-07-22 20:05:53.400287Z] Running populate-webroot step. 13:05:53 INFO - Running pre-action listener: _resource_record_pre_action 13:05:53 INFO - Running main action method: populate_webroot 13:05:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:05:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:05:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:05:53 INFO - '--tppagecycles', 13:05:53 INFO - '7'], 13:05:53 INFO - 'tests': ['tsvgm']}}, 13:05:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:05:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:05:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:05:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:05:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['damp', 'tps']}, 13:05:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'tests': ['damp', 'tps']}, 13:05:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['dromaeo_dom']}, 13:05:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:05:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['basic_compositor_video']}, 13:05:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:05:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'other-e10s': {'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['a11yr', 13:05:53 INFO - 'ts_paint', 13:05:53 INFO - 'tpaint', 13:05:53 INFO - 'sessionrestore', 13:05:53 INFO - 'sessionrestore_no_auto_restore', 13:05:53 INFO - 'tabpaint']}, 13:05:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['tsvgx', 13:05:53 INFO - 'tsvgr_opacity', 13:05:53 INFO - 'tart', 13:05:53 INFO - 'tscrollx', 13:05:53 INFO - 'cart']}, 13:05:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:05:53 INFO - 'tsvgr_opacity', 13:05:53 INFO - 'tart', 13:05:53 INFO - 'tscrollx', 13:05:53 INFO - 'cart']}, 13:05:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'talos_options': ['--disable-e10s'], 13:05:53 INFO - 'tests': ['tp5o']}, 13:05:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'tests': ['tp5o']}, 13:05:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'talos_options': ['--disable-e10s', 13:05:53 INFO - '--xperf_path', 13:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:53 INFO - 'tests': ['tp5n']}, 13:05:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:53 INFO - 'talos_options': ['--xperf_path', 13:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:53 INFO - 'tests': ['tp5n']}}, 13:05:53 INFO - 'talos.zip': {'path': '', 13:05:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:05:53 INFO - Running post-action listener: _resource_record_post_action 13:05:53 INFO - [mozharness: 2016-07-22 20:05:53.419412Z] Finished populate-webroot step (success) 13:05:53 INFO - [mozharness: 2016-07-22 20:05:53.419501Z] Running create-virtualenv step. 13:05:53 INFO - Running pre-action listener: _resource_record_pre_action 13:05:53 INFO - Running main action method: create_virtualenv 13:05:53 INFO - Creating virtualenv /builds/slave/test/build/venv 13:05:53 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 13:05:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:05:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:05:53 INFO - Using real prefix '/usr' 13:05:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:05:54 INFO - Installing distribute.............................................................................................................................................................................................done. 13:05:55 INFO - Installing pip.................done. 13:05:55 INFO - Return code: 0 13:05:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:05:55 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')]} 13:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:55 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')]} 13:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:55 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:05:55 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 13:05:55 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 13:05:55 INFO - Using env: {'DISPLAY': ':0', 13:05:55 INFO - 'HOME': '/home/cltbld', 13:05:55 INFO - 'LANG': 'en_US.UTF-8', 13:05:55 INFO - 'LANGUAGE': 'en_US:en', 13:05:55 INFO - 'LOGNAME': 'cltbld', 13:05:55 INFO - 'MAIL': '/var/mail/cltbld', 13:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:55 INFO - 'MOZ_NO_REMOTE': '1', 13:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:05:55 INFO - 'NO_EM_RESTART': '1', 13:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:55 INFO - 'PWD': '/builds/slave/test', 13:05:55 INFO - 'SHELL': '/bin/bash', 13:05:55 INFO - 'SHLVL': '1', 13:05:55 INFO - 'TERM': 'linux', 13:05:55 INFO - 'TMOUT': '86400', 13:05:55 INFO - 'USER': 'cltbld', 13:05:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:55 INFO - '_': '/tools/buildbot/bin/python'} 13:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:56 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 13:05:56 INFO - Cleaning up... 13:05:56 INFO - Return code: 0 13:05:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:56 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:05:56 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 13:05:56 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 13:05:56 INFO - Using env: {'DISPLAY': ':0', 13:05:56 INFO - 'HOME': '/home/cltbld', 13:05:56 INFO - 'LANG': 'en_US.UTF-8', 13:05:56 INFO - 'LANGUAGE': 'en_US:en', 13:05:56 INFO - 'LOGNAME': 'cltbld', 13:05:56 INFO - 'MAIL': '/var/mail/cltbld', 13:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:56 INFO - 'MOZ_NO_REMOTE': '1', 13:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:05:56 INFO - 'NO_EM_RESTART': '1', 13:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:56 INFO - 'PWD': '/builds/slave/test', 13:05:56 INFO - 'SHELL': '/bin/bash', 13:05:56 INFO - 'SHLVL': '1', 13:05:56 INFO - 'TERM': 'linux', 13:05:56 INFO - 'TMOUT': '86400', 13:05:56 INFO - 'USER': 'cltbld', 13:05:56 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:56 INFO - '_': '/tools/buildbot/bin/python'} 13:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:56 INFO - Downloading/unpacking psutil>=3.1.1 13:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:05:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:58 INFO - Installing collected packages: psutil 13:05:58 INFO - Running setup.py install for psutil 13:05:58 INFO - building 'psutil._psutil_linux' extension 13:05:58 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 13:05:58 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 13:05:58 INFO - building 'psutil._psutil_posix' extension 13:05:58 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 13:05:58 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 13:05:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:58 INFO - Successfully installed psutil 13:05:58 INFO - Cleaning up... 13:05:58 INFO - Return code: 0 13:05:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:05:58 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')]} 13:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:58 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')]} 13:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:58 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:05:58 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 13:05:58 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 13:05:58 INFO - Using env: {'DISPLAY': ':0', 13:05:58 INFO - 'HOME': '/home/cltbld', 13:05:58 INFO - 'LANG': 'en_US.UTF-8', 13:05:58 INFO - 'LANGUAGE': 'en_US:en', 13:05:58 INFO - 'LOGNAME': 'cltbld', 13:05:58 INFO - 'MAIL': '/var/mail/cltbld', 13:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:59 INFO - 'MOZ_NO_REMOTE': '1', 13:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:05:59 INFO - 'NO_EM_RESTART': '1', 13:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:59 INFO - 'PWD': '/builds/slave/test', 13:05:59 INFO - 'SHELL': '/bin/bash', 13:05:59 INFO - 'SHLVL': '1', 13:05:59 INFO - 'TERM': 'linux', 13:05:59 INFO - 'TMOUT': '86400', 13:05:59 INFO - 'USER': 'cltbld', 13:05:59 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:59 INFO - '_': '/tools/buildbot/bin/python'} 13:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:06:01 INFO - Installing collected packages: mozsystemmonitor 13:06:01 INFO - Running setup.py install for mozsystemmonitor 13:06:01 INFO - Successfully installed mozsystemmonitor 13:06:01 INFO - Cleaning up... 13:06:01 INFO - Return code: 0 13:06:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:06:01 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')]} 13:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:01 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')]} 13:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:01 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:01 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 13:06:01 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 13:06:01 INFO - Using env: {'DISPLAY': ':0', 13:06:01 INFO - 'HOME': '/home/cltbld', 13:06:01 INFO - 'LANG': 'en_US.UTF-8', 13:06:01 INFO - 'LANGUAGE': 'en_US:en', 13:06:01 INFO - 'LOGNAME': 'cltbld', 13:06:01 INFO - 'MAIL': '/var/mail/cltbld', 13:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:01 INFO - 'MOZ_NO_REMOTE': '1', 13:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:01 INFO - 'NO_EM_RESTART': '1', 13:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:01 INFO - 'PWD': '/builds/slave/test', 13:06:01 INFO - 'SHELL': '/bin/bash', 13:06:01 INFO - 'SHLVL': '1', 13:06:01 INFO - 'TERM': 'linux', 13:06:01 INFO - 'TMOUT': '86400', 13:06:01 INFO - 'USER': 'cltbld', 13:06:01 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:01 INFO - '_': '/tools/buildbot/bin/python'} 13:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:01 INFO - Downloading/unpacking jsonschema==2.5.1 13:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:03 INFO - warning: no files found matching '*.txt' 13:06:03 INFO - no previously-included directories found matching 'build' 13:06:03 INFO - no previously-included directories found matching 'dist' 13:06:03 INFO - no previously-included directories found matching '.git*' 13:06:03 INFO - Installing collected packages: jsonschema, functools32 13:06:03 INFO - Running setup.py install for functools32 13:06:03 INFO - warning: no files found matching '*.txt' 13:06:03 INFO - no previously-included directories found matching 'build' 13:06:03 INFO - no previously-included directories found matching 'dist' 13:06:03 INFO - no previously-included directories found matching '.git*' 13:06:03 INFO - Successfully installed jsonschema functools32 13:06:03 INFO - Cleaning up... 13:06:03 INFO - Return code: 0 13:06:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:06:03 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')]} 13:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:03 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')]} 13:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:03 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:03 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 13:06:03 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 13:06:03 INFO - Using env: {'DISPLAY': ':0', 13:06:03 INFO - 'HOME': '/home/cltbld', 13:06:03 INFO - 'LANG': 'en_US.UTF-8', 13:06:03 INFO - 'LANGUAGE': 'en_US:en', 13:06:03 INFO - 'LOGNAME': 'cltbld', 13:06:03 INFO - 'MAIL': '/var/mail/cltbld', 13:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:03 INFO - 'MOZ_NO_REMOTE': '1', 13:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:03 INFO - 'NO_EM_RESTART': '1', 13:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:03 INFO - 'PWD': '/builds/slave/test', 13:06:03 INFO - 'SHELL': '/bin/bash', 13:06:03 INFO - 'SHLVL': '1', 13:06:03 INFO - 'TERM': 'linux', 13:06:03 INFO - 'TMOUT': '86400', 13:06:03 INFO - 'USER': 'cltbld', 13:06:03 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:03 INFO - '_': '/tools/buildbot/bin/python'} 13:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:04 INFO - Downloading/unpacking blobuploader==1.2.4 13:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:06 INFO - Downloading blobuploader-1.2.4.tar.gz 13:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:06:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:06:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 INFO - Downloading docopt-0.6.1.tar.gz 13:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:06:06 INFO - Installing collected packages: blobuploader, requests, docopt 13:06:06 INFO - Running setup.py install for blobuploader 13:06:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:06:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:06:06 INFO - Running setup.py install for requests 13:06:07 INFO - Running setup.py install for docopt 13:06:07 INFO - Successfully installed blobuploader requests docopt 13:06:07 INFO - Cleaning up... 13:06:07 INFO - Return code: 0 13:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:07 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')]} 13:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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')]} 13:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:07 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:07 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 13:06:07 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 13:06:07 INFO - Using env: {'DISPLAY': ':0', 13:06:07 INFO - 'HOME': '/home/cltbld', 13:06:07 INFO - 'LANG': 'en_US.UTF-8', 13:06:07 INFO - 'LANGUAGE': 'en_US:en', 13:06:07 INFO - 'LOGNAME': 'cltbld', 13:06:07 INFO - 'MAIL': '/var/mail/cltbld', 13:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:07 INFO - 'MOZ_NO_REMOTE': '1', 13:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:07 INFO - 'NO_EM_RESTART': '1', 13:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:07 INFO - 'PWD': '/builds/slave/test', 13:06:07 INFO - 'SHELL': '/bin/bash', 13:06:07 INFO - 'SHLVL': '1', 13:06:07 INFO - 'TERM': 'linux', 13:06:07 INFO - 'TMOUT': '86400', 13:06:07 INFO - 'USER': 'cltbld', 13:06:07 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:07 INFO - '_': '/tools/buildbot/bin/python'} 13:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:07 INFO - Running setup.py (path:/tmp/pip-WZa29T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:07 INFO - Running setup.py (path:/tmp/pip-25oO9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:07 INFO - Running setup.py (path:/tmp/pip-MDKWWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:07 INFO - Running setup.py (path:/tmp/pip-I9cQyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:07 INFO - Running setup.py (path:/tmp/pip-XnLWSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:08 INFO - Running setup.py (path:/tmp/pip-UQeKcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:08 INFO - Running setup.py (path:/tmp/pip-pNFT7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:08 INFO - Running setup.py (path:/tmp/pip-BGaqK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:08 INFO - Running setup.py (path:/tmp/pip-z1eHE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:08 INFO - Running setup.py (path:/tmp/pip-ylnPb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:08 INFO - Running setup.py (path:/tmp/pip-Ya0sZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:08 INFO - Running setup.py (path:/tmp/pip-YxOa5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:08 INFO - Running setup.py (path:/tmp/pip-J8ChXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:08 INFO - Running setup.py (path:/tmp/pip-MdzbuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:08 INFO - Running setup.py (path:/tmp/pip-qy5wWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:08 INFO - Running setup.py (path:/tmp/pip-PUmpBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:08 INFO - Running setup.py (path:/tmp/pip-hwZwKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:06:08 INFO - Running setup.py install for manifestparser 13:06:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:06:08 INFO - Running setup.py install for mozcrash 13:06:09 INFO - Running setup.py install for mozdebug 13:06:09 INFO - Running setup.py install for mozdevice 13:06:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Running setup.py install for mozfile 13:06:09 INFO - Running setup.py install for mozhttpd 13:06:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Running setup.py install for mozinfo 13:06:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Running setup.py install for mozInstall 13:06:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Running setup.py install for mozleak 13:06:09 INFO - Running setup.py install for mozlog 13:06:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:06:09 INFO - Running setup.py install for moznetwork 13:06:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Running setup.py install for mozprocess 13:06:10 INFO - Running setup.py install for mozprofile 13:06:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Running setup.py install for mozrunner 13:06:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Running setup.py install for mozscreenshot 13:06:10 INFO - Running setup.py install for moztest 13:06:10 INFO - Running setup.py install for mozversion 13:06:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:06:10 INFO - Cleaning up... 13:06:10 INFO - Return code: 0 13:06:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:10 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')]} 13:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:10 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')]} 13:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:10 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:10 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 13:06:10 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 13:06:10 INFO - Using env: {'DISPLAY': ':0', 13:06:10 INFO - 'HOME': '/home/cltbld', 13:06:10 INFO - 'LANG': 'en_US.UTF-8', 13:06:10 INFO - 'LANGUAGE': 'en_US:en', 13:06:10 INFO - 'LOGNAME': 'cltbld', 13:06:10 INFO - 'MAIL': '/var/mail/cltbld', 13:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:10 INFO - 'MOZ_NO_REMOTE': '1', 13:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:10 INFO - 'NO_EM_RESTART': '1', 13:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:10 INFO - 'PWD': '/builds/slave/test', 13:06:10 INFO - 'SHELL': '/bin/bash', 13:06:10 INFO - 'SHLVL': '1', 13:06:10 INFO - 'TERM': 'linux', 13:06:10 INFO - 'TMOUT': '86400', 13:06:10 INFO - 'USER': 'cltbld', 13:06:10 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:10 INFO - '_': '/tools/buildbot/bin/python'} 13:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:10 INFO - Running setup.py (path:/tmp/pip-G6KP9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:10 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)) 13:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:10 INFO - Running setup.py (path:/tmp/pip-EcP66E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:11 INFO - Running setup.py (path:/tmp/pip-YL59ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:11 INFO - Running setup.py (path:/tmp/pip-AxF6Fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:11 INFO - Running setup.py (path:/tmp/pip-Rq0e4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:11 INFO - Running setup.py (path:/tmp/pip-r7rx27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:11 INFO - Running setup.py (path:/tmp/pip-sFFT77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:11 INFO - Running setup.py (path:/tmp/pip-g1Veq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:11 INFO - Running setup.py (path:/tmp/pip-zYhoBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:11 INFO - Running setup.py (path:/tmp/pip-PxNAY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:11 INFO - Running setup.py (path:/tmp/pip-B49pnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:11 INFO - Running setup.py (path:/tmp/pip-pndLRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:11 INFO - Running setup.py (path:/tmp/pip-I81Mnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:11 INFO - Running setup.py (path:/tmp/pip-Jte7gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:11 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)) 13:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:11 INFO - Running setup.py (path:/tmp/pip-5tu5WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:12 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)) 13:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:12 INFO - Running setup.py (path:/tmp/pip-uYtlEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:12 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)) 13:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:12 INFO - Running setup.py (path:/tmp/pip-0DRDKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:12 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)) 13:06:12 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)) 13:06:12 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)) 13:06:12 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)) 13:06:12 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)) 13:06:12 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)) 13:06:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:06:12 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 13:06:12 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 13:06:12 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 13:06:12 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 13:06:12 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 13:06:12 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 13:06:14 INFO - Downloading blessings-1.6.tar.gz 13:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:14 INFO - Installing collected packages: blessings 13:06:14 INFO - Running setup.py install for blessings 13:06:14 INFO - Successfully installed blessings 13:06:14 INFO - Cleaning up... 13:06:14 INFO - Return code: 0 13:06:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:14 INFO - Reading from file tmpfile_stdout 13:06:14 INFO - Current package versions: 13:06:14 INFO - argparse == 1.2.1 13:06:14 INFO - blessings == 1.6 13:06:14 INFO - blobuploader == 1.2.4 13:06:14 INFO - docopt == 0.6.1 13:06:14 INFO - functools32 == 3.2.3-2 13:06:14 INFO - jsonschema == 2.5.1 13:06:14 INFO - manifestparser == 1.1 13:06:14 INFO - mozInstall == 1.12 13:06:14 INFO - mozcrash == 0.17 13:06:14 INFO - mozdebug == 0.1 13:06:14 INFO - mozdevice == 0.48 13:06:14 INFO - mozfile == 1.2 13:06:14 INFO - mozhttpd == 0.7 13:06:14 INFO - mozinfo == 0.9 13:06:14 INFO - mozleak == 0.1 13:06:14 INFO - mozlog == 3.3 13:06:14 INFO - moznetwork == 0.27 13:06:14 INFO - mozprocess == 0.23 13:06:14 INFO - mozprofile == 0.28 13:06:14 INFO - mozrunner == 6.12 13:06:14 INFO - mozscreenshot == 0.1 13:06:14 INFO - mozsystemmonitor == 0.3 13:06:14 INFO - moztest == 0.7 13:06:14 INFO - mozversion == 1.4 13:06:14 INFO - psutil == 3.1.1 13:06:14 INFO - requests == 1.2.3 13:06:14 INFO - wsgiref == 0.1.2 13:06:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:14 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')]} 13:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:14 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')]} 13:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:14 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:14 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 13:06:14 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 13:06:14 INFO - Using env: {'DISPLAY': ':0', 13:06:14 INFO - 'HOME': '/home/cltbld', 13:06:14 INFO - 'LANG': 'en_US.UTF-8', 13:06:14 INFO - 'LANGUAGE': 'en_US:en', 13:06:14 INFO - 'LOGNAME': 'cltbld', 13:06:14 INFO - 'MAIL': '/var/mail/cltbld', 13:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:14 INFO - 'MOZ_NO_REMOTE': '1', 13:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:14 INFO - 'NO_EM_RESTART': '1', 13:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:14 INFO - 'PWD': '/builds/slave/test', 13:06:14 INFO - 'SHELL': '/bin/bash', 13:06:14 INFO - 'SHLVL': '1', 13:06:14 INFO - 'TERM': 'linux', 13:06:14 INFO - 'TMOUT': '86400', 13:06:14 INFO - 'USER': 'cltbld', 13:06:14 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:14 INFO - '_': '/tools/buildbot/bin/python'} 13:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 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)) 13:06:14 INFO - Cleaning up... 13:06:14 INFO - Return code: 0 13:06:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06:14 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')]} 13:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:14 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')]} 13:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:14 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 0xee3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, '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': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', '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 13:06:14 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 13:06:14 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 13:06:14 INFO - Using env: {'DISPLAY': ':0', 13:06:14 INFO - 'HOME': '/home/cltbld', 13:06:14 INFO - 'LANG': 'en_US.UTF-8', 13:06:14 INFO - 'LANGUAGE': 'en_US:en', 13:06:14 INFO - 'LOGNAME': 'cltbld', 13:06:14 INFO - 'MAIL': '/var/mail/cltbld', 13:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:14 INFO - 'MOZ_NO_REMOTE': '1', 13:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:14 INFO - 'NO_EM_RESTART': '1', 13:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:14 INFO - 'PWD': '/builds/slave/test', 13:06:14 INFO - 'SHELL': '/bin/bash', 13:06:14 INFO - 'SHLVL': '1', 13:06:14 INFO - 'TERM': 'linux', 13:06:14 INFO - 'TMOUT': '86400', 13:06:14 INFO - 'USER': 'cltbld', 13:06:14 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:14 INFO - '_': '/tools/buildbot/bin/python'} 13:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:06:14 INFO - Cleaning up... 13:06:14 INFO - Return code: 0 13:06:14 INFO - Running post-action listener: _resource_record_post_action 13:06:14 INFO - Running post-action listener: _start_resource_monitoring 13:06:15 INFO - Starting resource monitoring. 13:06:15 INFO - [mozharness: 2016-07-22 20:06:15.024422Z] Finished create-virtualenv step (success) 13:06:15 INFO - [mozharness: 2016-07-22 20:06:15.024853Z] Running install step. 13:06:15 INFO - Running pre-action listener: _resource_record_pre_action 13:06:15 INFO - Running main action method: install 13:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:15 INFO - Reading from file tmpfile_stdout 13:06:15 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:15 INFO - Reading from file tmpfile_stdout 13:06:15 INFO - Output received: 13:06:15 INFO - Usage: mozinstall [options] installer 13:06:15 INFO - Options: 13:06:15 INFO - -h, --help show this help message and exit 13:06:15 INFO - -d DEST, --destination=DEST 13:06:15 INFO - Directory to install application into. [default: 13:06:15 INFO - "/builds/slave/test"] 13:06:15 INFO - --app=APP Application being installed. [default: firefox] 13:06:15 INFO - mkdir: /builds/slave/test/build/application 13:06:15 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'] 13:06:15 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 13:06:32 INFO - Reading from file tmpfile_stdout 13:06:32 INFO - Output received: 13:06:32 INFO - /builds/slave/test/build/application/firefox/firefox 13:06:32 INFO - Running post-action listener: _resource_record_post_action 13:06:32 INFO - [mozharness: 2016-07-22 20:06:32.557978Z] Finished install step (success) 13:06:32 INFO - [mozharness: 2016-07-22 20:06:32.558252Z] Running run-tests step. 13:06:32 INFO - Running pre-action listener: _resource_record_pre_action 13:06:32 INFO - Running main action method: run_tests 13:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:06:32 INFO - Python 2.7.3 13:06:32 INFO - Return code: 0 13:06:32 INFO - grabbing minidump binary from tooltool 13:06: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')]} 13:06:32 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 0x11a23b0>, '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 0x11a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:06:32 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 13:06:32 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 13:06:32 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 13:06:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:06:32 INFO - Return code: 0 13:06:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:06:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:06:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:06:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:06:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:06:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 13:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 13:06:32 INFO - Using env: {'DISPLAY': ':0', 13:06:32 INFO - 'HOME': '/home/cltbld', 13:06:32 INFO - 'LANG': 'en_US.UTF-8', 13:06:32 INFO - 'LANGUAGE': 'en_US:en', 13:06:32 INFO - 'LOGNAME': 'cltbld', 13:06:32 INFO - 'MAIL': '/var/mail/cltbld', 13:06:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:06:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:32 INFO - 'MOZ_NO_REMOTE': '1', 13:06:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:32 INFO - 'NO_EM_RESTART': '1', 13:06:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:32 INFO - 'PWD': '/builds/slave/test', 13:06:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:06:32 INFO - 'SHELL': '/bin/bash', 13:06:32 INFO - 'SHLVL': '1', 13:06:32 INFO - 'TERM': 'linux', 13:06:32 INFO - 'TMOUT': '86400', 13:06:32 INFO - 'USER': 'cltbld', 13:06:32 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1469217941.805801-621938265', 13:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:32 INFO - '_': '/tools/buildbot/bin/python'} 13:06:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 13:06:32 INFO - mozversion application_buildid: 20160722083025 13:06:32 INFO - mozversion application_changeset: ff29f08b72c466b65dea5efa6f82f99add8f17ef 13:06:32 INFO - mozversion application_display_name: Nightly 13:06:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:06:32 INFO - mozversion application_name: Firefox 13:06:32 INFO - mozversion application_remotingname: firefox 13:06:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:06:32 INFO - mozversion application_vendor: Mozilla 13:06:32 INFO - mozversion application_version: 50.0a1 13:06:32 INFO - mozversion platform_buildid: 20160722083025 13:06:32 INFO - mozversion platform_changeset: ff29f08b72c466b65dea5efa6f82f99add8f17ef 13:06:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:06:32 INFO - mozversion platform_version: 50.0a1 13:06:32 INFO - using testdate: 1469217992 13:06:32 INFO - actual date: 1469217992 13:06:32 INFO - starting webserver on 'localhost:42425' 13:06:32 INFO - SUITE-START | Running 2 tests 13:06:32 INFO - TEST-START | dromaeo_css 13:06:32 INFO - operating with platform_type : linux_ 13:06:32 INFO - Initialising browser for dromaeo_css test... 13:06:32 INFO - TEST-INFO | started process 9338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpifBxlx/profile http://localhost:42425/getInfo.html) 13:06:36 INFO - PROCESS | 9338 | JavaScript error: , line 0: uncaught exception: undefined 13:06:36 INFO - PROCESS | 9338 | __metrics Screen width/height:1600/1200 13:06:36 INFO - PROCESS | 9338 | colorDepth:24 13:06:36 INFO - PROCESS | 9338 | Browser inner width/height: 1024/697 13:06:36 INFO - PROCESS | 9338 | __metrics 13:06:37 INFO - PROCESS | 9338 | 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" 13:06:37 INFO - PROCESS | 9338 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:06:38 INFO - TEST-INFO | 9338: exit 0 13:06:38 INFO - Browser initialized. 13:06:38 INFO - Running cycle 1/1 for dromaeo_css test... 13:06:38 INFO - TEST-INFO | started process 9422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpifBxlx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:06:50 INFO - PROCESS | 9422 | RSS: Main: 158420992 13:06:50 INFO - PROCESS | 9422 | 13:07:42 INFO - PROCESS | 9422 | 1469218062130 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] 13:09:50 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42425/tests/dromaeo/cssquery-ext.html) 13:09:51 INFO - PROCESS | 9422 | RSS: Main: 265498624 13:09:51 INFO - PROCESS | 9422 | 13:12:53 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-ext.html (next: http://localhost:42425/tests/dromaeo/cssquery-jquery.html) 13:12:54 INFO - PROCESS | 9422 | RSS: Main: 250519552 13:12:54 INFO - PROCESS | 9422 | 13:15:56 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42425/tests/dromaeo/cssquery-mootools.html) 13:15:56 INFO - PROCESS | 9422 | RSS: Main: 187047936 13:15:56 INFO - PROCESS | 9422 | 13:18:58 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42425/tests/dromaeo/cssquery-prototype.html) 13:18:59 INFO - PROCESS | 9422 | RSS: Main: 228392960 13:18:59 INFO - PROCESS | 9422 | 13:22:01 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42425/tests/dromaeo/cssquery-yui.html) 13:22:02 INFO - PROCESS | 9422 | RSS: Main: 185954304 13:22:02 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | Cycle 1(1): loaded http://localhost:42425/tests/dromaeo/cssquery-yui.html (next: http://localhost:42425/tests/dromaeo/cssquery-dojo.html) 13:25:05 INFO - PROCESS | 9422 | RSS: Main: 273428480 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | __start_tp_report 13:25:05 INFO - PROCESS | 9422 | _x_x_mozilla_page_load 13:25:05 INFO - PROCESS | 9422 | _x_x_mozilla_page_load_details 13:25:05 INFO - PROCESS | 9422 | |i|pagename|runs| 13:25:05 INFO - PROCESS | 9422 | |0;dojo.html;3034.7420469260096;3037.498812695905;3050.8474576271187;3078.9846050769743;3095.8916437924677;5248.0553500369915;5275.973620131898;5312.787488500456;5368.409474957752;5389.676619402839;5311.840644780653;5351.117065684158;5380.865478363043;5417.404085550586;5418.2685337479425;4793.712377257359;4867.1482490564185;4870.975645121769;4877.170880950229;5062.367204099488;4966.5778408835295;5006.849794506171;5011.523905229015;5012.799488020475;5022.949770502303;4748.335233067373;4775.068861571977;4830.73430961297;4899.975500122512;4965.106280869442;4855.878603034917;4861.149298872683;4862.222044472903;4930.753462326869;4956;4863.076015557032;4885;4896.632752543538;4900.509949005107;4935.161022626162;5206.947930520684;5245.501677340647;5323.387810401815;5376.569874410077;5401.054815407328;5678.346990096151;5728.22668939692;5767.1637612546365;5779.566532510079;5780.682062486561;5835.387284335168;5841.970790146064;5844.561657875676;5850.151727999459;5850.912236316459;5252.1596544552685;5254.632175747699;5290.100682883919;5291.947080529222;5444.8094316698725;5796.188533605298;5825.300963884361;5833.358330583633;5848.064309710426;5848.356680765112;5607.551395888319;5613.410591887875;5652.519535839499;5653.717314134276;5659.122835960433;5614.831555053355;5675.886482270332;5714.885702285931;5717.599768016199;5758.712064396847;5749.338826035059;5764.654120752768;5765.135229715493;5768.596198266081;5810.041343178413;5688.260526131577;5753.683547404849;5765.8558536036935;5772.913406298909;5774.509166720792;4286.957130428718;4308.978455107767;4312.568743125725;4350.651947844165;4361.498427680794;3928.43037759529;3928.8035598219976;3968.722189446769;4002.5597184309704;4023.2355852387955;5517.951589198039;5518.2826232589505;5518.392976772552;5520.613557050968;5526.668399895939;5658.434156584309;5660.518855897212;5806.042003069446;5811.186433899257;5873.618214816023;5417.8103766367985;5458.699771512605;5465.808696695596;5501.174823776386;5507.311586051744;4944.159492886218;4951.034548263054;4957.4794646561595;4961.82633607822;5002.57478114357;5693.914591281134;5854.502367298742;5894.2926848778425;5907.084401917709;5907.586468947218;5757.9424205757405;5821.417858214145;5825.70871456434;5871.6183448077445;5877.882442351214;5938.2280303560265;5947.91078133837;5951.702414879325;5960.672163031075;5963.373845746134;5524.06090964545;5556.749946252509;5647.124695672177;5690.914636280542;5708.514776244066;5692.373838877637;5785.942140578709;5800.76796928135;5815.447532484407;5848.239728835394;5584.274044374205;5623.184638227516;5623.831285061455;5636.915446268391;5637.408072152365;5484.369297530835;5574.219609254788;5628.043232650377;5629.662220266797;5634.774609015757;5551.25058117149;5623.718814059363;5630.465105814941;5677.886442271214;5680.11958146488;5694.373618902;5717.799877004285;5746.827595172152;5752.395998420106;5763.913541296968;5378.784848606012;5476.91784623239;5531.502164805187;5538.833834984957;5546.7781288748 13:25:05 INFO - PROCESS | 9422 | |1;ext.html;590.7489317040257;612.185792895449;613.6164896939416;630.1776182082383;647.7409036385442;30128.548071778914;43558.78220608897;44431.33352999708;44639.77680111603;48538.51461485384;45481.77259113708;46178.538214617845;46226.768866155704;46341;46423.76788116063;8977.775555611113;9073.909260907372;9107;9108.18026377626;9129.95435022824;8907.73276801697;9004.144606262395;9006.909930900705;9008;9013.504257265848;9032.277417806592;9104.726858194264;9107.590158442852;9110.225630821387;9113.817723645523;9021.594028268712;9058.139476749699;9069.274458043341;9070.319726020542;9074.68238611652;8991;8996.370254082216;9042.954785226098;9057.547122643842;9068.637254509811;44006.55993440057;44361;44411;44415.22470590004;44425.33361999573;3537.0287364908713;3663.303972245265;3667.8349474273723;3864.207837393356;4057.177949642273;33076.17309567256;33160.33679326424;33242.3351532968;33247.6675233247;33303.83348083268;4433.069055498349;4488.349189367524;4488.618467430272;4491.573300536444;4510.797014134372;361391;362148;362284.9999999974;363682;364010;58747.41252587419;58795;58893.64425422935;59238.70380648156;59314;54181.72909135508;55576.44423555794;60550;60728;60744;15429.22853857325;15765.763513547307;16278.593035173979;17080.48758537221;17082.914585426992;13108.410121544553;13567.86432135666;13576.253306068063;13579;13586.728265434833;16013.67972640564;16251.431199908184;16287;16298.837011629732;16461.423850165185;5214.817481388133;5974.970125149346;6095.238095238095;6137.3735959970645;6263.812085637438;950.6530116507563;1061.8141825180562;1114.660028691257;1118.6396702956754;1127.2165844737829;33254;33314.3337133256;33330;34016.65983340184;36420.27159456849;1088.0914436445657;1128.8701799293021;1155.1278784517635;1160.4719852467108;1189.316143217636;16768.58078548046;16911.746323805153;16935.153242337834;17033.48899533016;17069.829301707072;522584;526144;526268;526876;528164;57789.13316300259;57907.131393029136;57936.4206357931;57942;57972.130418043766;1247.5134697467995;1346.4479563378968;1396.155326027759;1408.7605107131812;1415.9221242831536;30657.386852262385;32758.83620581977;33108.505861810925;33568;34157.48763768496;49375.50624493853;49933.25100123428;49970.75014625049;50001.74999124981;50051;315939;316689;317263;317295;317478;27014.864925675247;28302.716972830007;28304.15087547377;29585.112446626637;29767.10698679043;49434.505654942994;49456.50543494519;49672.50327496679;49739.75130124326;51321.486785131674;42092.15815683608;42631.786841065594;42832.57167428286;42866.35700464558;44524.33213501735;42419.363709543766;42725.786371067945;42869;44151.77924110359;44232.33651495166;41455.1708965825;41867.162656746084;43106.353404698326;43198.568014319455;43200.35199471947;35442.64557354394;36980.630193698795;37028.629713703594;37746.056348590406;38312.233755325295;28577.857110715144;28851.27871803138;29038;29102.70897291085;29103.563446547894 13:25:05 INFO - PROCESS | 9422 | |2;jquery.html;356.3087610036527;379.3475222617099;382.25651108593786;384.40609258695315;402.63762613647714;14722.484713035046;18519.166637501312;19086.522836929085;19841.60316793663;20091.799082009213;17068.914655426706;17483.300667973264;17828.732569011474;18005.81994180058;18014.639707205883;5609.551235901118;5834.358220595731;5871.324797648261;5871.941280587203;5879.088741245114;4154.335306350985;4221.493420789509;5765.913511297334;5853.970730146343;6598.67006649668;6474.514411419139;6545.639989800559;6555.93444065558;6556.311587283338;6571.244306904696;6242.469390101847;6392;6414.711337989802;6420.357964203589;6536.640484773336;5465.688234823653;5575.665460072409;5624.606277560572;5635.295588051494;5636.746346414421;22941.967611457523;23013;23322;23482.295531134092;23527.882360588086;2367.7130044843047;2390.581648211556;2550.2859199424192;2577.7808886244693;2614.4509652972897;14502;14698.26508674562;14819.18494482803;14902.254887255702;15377.538673839803;1873.9812601873944;1903.7620297462818;1918.8273055424938;2002.2691717523144;2034.8779073255505;84710.57644711799;84748.57625711831;85983;86514.56742716309;88329;10603.94698026505;10752.838707419238;10758;11026.960012397732;11121.610743623934;15082.396704131932;15084.472043478425;15098.018628789094;15102.622434439227;15109.622259443602;7815.374770018385;7999.120096789348;8036.9953755780525;8070.838583228303;8117.918820811835;7576.659050342748;7634.618269086634;7656.885146722803;7802.269205777932;7849.097353804272;4615.676902616785;4618.445786505641;4657.930131048037;4659.2312268476;4765;1871.8876867387728;1884.990575047116;1903.2767548331544;1944.8833070015844;1984.7915968823354;895.4895709445684;947.0576776107816;951.7287573041789;976.6630512473185;983.8229118758549;18786.436406907815;20186.495337616678;20266.594668106554;20298.95515672661;20488.590228195353;993.2500961773959;1000.6597756762735;1010.1514727628827;1021.7241344836852;1044.1177205261542;9073.13805188506;11020.944895275632;11212.495437705324;11215.831762523569;11260.887391126147;394180;397957;399327;495382;521732;58435.415645843845;73798;92093.61859572111;99991.50004250077;104003;1025.687165414541;1027.3938376357985;1070.9089727373262;1073.2487258918788;1077.3697387028676;17569.472915812545;18039.09804509743;18374.81625183731;18416.815831842046;18434.72347914809;40815.183696325315;41125.383119252634;42104.78947605242;42226;42705.78647106745;59751.701241493516;60081.69959150176;60755.69622151861;61135.694321528106;61784.38215617966;8144.185581441809;8172.509649421054;8332.50004999702;8383.95808020956;8394.328453723809;11629.76740465169;13219.471221151047;13222;13349.466021359036;13398.732025359634;8965.417247878868;10606.787864242518;11340.206185567262;11439.42802859837;11749.353785541878;10370.481475926324;10401.791964160826;10636.30863993838;10636.734081647712;10733.677989660322;11275.943620281847;11396.544138234378;11451.59919402817;12052.698682533006;12204.877951220375;8281.21328473794;8308.584570771558;8396.622152003296;8440.198181172778;8482.491050536988;10909.236353455182;11407.657770266906;11427.428628568372;11706.122040847142;11919.94040029829 13:25:05 INFO - PROCESS | 9422 | |3;mootools.html;1330.2816479101286;1447.1317209674194;1514.0158896717135;1601.4234875444838;1832.0656465202742;8516.190961858621;8921.464712117278;8949.68676096336;9165.404248723828;9172;8966.28269738421;9057.864132038025;9058.094190580938;9066.048064953184;9074.500902450363;2379.5121999990006;2412.1798588479915;2414.420539070628;2416.2751174647624;2416.4442178299;2368.561816064025;2386.677798497198;2399.8200134989947;2414.420539070628;2424.35754525051;2323.61660326046;2332.5801355755957;2336.8597884127007;2376.536575367804;2378.904843806254;2289.53064621753;2305.5504176685554;2316.073570571768;2317.3395582259145;2365.0421579260255;2290.1526435218907;2309.4688221708934;2324.4305145239523;2331.5453486570127;2332.8017118544776;8924.776880577972;9016.729498115066;9043.819123617557;9083.273338132933;9121;5080.187170052811;5308.6549374290935;5313.681179129226;5315.840524784263;5316.12283973144;3546.6453354664586;3552.6802587766965;3598.4062629666073;3616.674499295076;3634.8727794527063;1154.8094564396865;1176.5587904535798;1195.378403230324;1197.3175290084569;1224.6081253998634;12556;12808.359582020861;12840.1011929165;12855.035872309614;12859;4484.192845287879;4615;4789.856304310807;4807.831725889577;4811.807527698923;10116.291859569907;10120.240981926385;10127.341722787995;10134.189264858793;10142.087212150942;5340.038793017294;5351.384590772033;5351.785928562892;5358.410574836765;5362.195670649434;4295.957040429618;4793.352897358882;4934.679245849008;4961.90076198474;5012.122878496249;3822.808859557011;3874.88818593867;3973.384125460558;4013.979930100331;4020.557738648746;1156.670348950545;1191.3149938785234;1197.8263151842934;1202.1765090912752;1208.1542919956066;928.9489078100769;984.6061575369766;985.3693636072919;997.65082221222;998.7453199434098;5877.735501902424;5931.466168044896;6038.33578306386;6047.637141771508;6077.453029227302;453.2951260789504;463.6453113368276;466.0864705177822;469.67123013890415;473.6849994753519;4088.5298190707854;4163.063310755056;4163.354680024601;4179.561146079679;4214.978925105416;11262.099032077414;11518.88481115195;11554.133439992034;11571.363575003455;11576.24754390962;10799.40603266828;11498.655040348804;11516.827247591293;11538;11547;8261.463004904663;8591.828163436696;8596.957015214884;8598.140185981352;8603.913960860436;5862.208601838868;5983.34183239852;6175.752969881154;6181.258249010148;6329.810105696836;10254.128399086161;10576;10582.4708764563;10700.571977120828;10773.62292319765;10957.56169753201;10977.725556861142;10992.120630349713;11008.889911100787;11026.44867756625;7939.166387529457;8002.9599852000365;8229.341652667892;8355.498670079814;8356.707515236938;8843.115688431106;8900.5994730236;8907.331950103639;8912.064233255674;8912.643494260416;7907.406944479302;7986.800329991796;8050.83898322042;8052.355811534946;8214.219649133323;7792.961035194788;7879.621873878214;7936.047674279123;7988.480748751313;8124.634391452517;7179.497435179487;7217.566945983258;7268.527545709512;7272.527285726623;7335.339819416278;5177;5190.8183213587345;5312.62811603184;5335.306410166653;5423.538999184956;5160.948390516047;5193;5200.843974680765;5231;5318.095923692904 13:25:05 INFO - PROCESS | 9422 | |4;prototype.html;313.1576060397529;323.374270786029;324.96587858274864;327.6117800406517;332.28227029616033;5292.417834038252;5314.92027619585;5328.387235467918;5331.760070796814;5334.306540149785;4995.900081998367;5086.321513900413;5272.841814745554;5275.446078161795;5290.867728306785;4123.515534407611;4141.254574176647;4145.709800313979;4150.27763478157;4156.192056455437;4126.319157339036;4152.294110001292;4175.519815221258;4181.790910454465;4183.309753890604;3877.3602355611433;4041.3735870939904;4100.487439070116;4133.483314585676;4140.979295103521;4119.176164767028;4121.876343709679;4125.514814666718;4134.607212314825;4137.213929353413;3652.7625704329134;3665.8533658653623;3669.6880765134997;3686.225892562565;3688.3545379558736;5081.891015228675;5107.872303192413;5136.64043516954;5152.330197074394;5167.9224811627855;4700.670953033289;4715.834945776881;4721.386219791405;4729.574338309534;4738.099761045425;5463.486796073811;5464.371597266326;5473.233747275384;5479.643753186128;5496.148097044965;3859.749116307431;3977.6618987386105;4014.4781178446906;4024.4969378827645;4026.5973402659793;5653.671387223999;5667.999999999959;5669.404712505168;5673.744681489343;5677.659340439587;4081.5306239782212;4083.060895993938;4085.937656209407;4095.344744840811;4095.488063992001;4038.757674539478;4113.341865301538;4129.442525259112;4129.587041295907;4140.627343539036;4680.315086568832;4686.695364801345;4692.225782745877;4693.366395536559;4698.906021879611;5488.643238189505;5504.9449505504435;5508.476694713996;5515.7242137892945;5515.951969125853;1409.4150927365233;1425.0238586568028;1431.8568143185807;1436.7629341158593;1448.5799118255798;907.9591418386188;922.1700469577299;928.8838895138108;935.3546053223254;935.6257497001254;4794.2808578713475;4802.591779698695;4803.438882363246;4808.326834243208;4812.35033270511;5034.697918124924;5048.192289233779;5054.898902021939;5072.289879416885;5105.361829771278;4615.799892028097;4725.3384526166365;4783.282507623815;4802.519748025239;4812.398450193726;2625.829321094123;2666.879990400437;2675.705672376037;2685.181019788944;2694.272546412458;5130.538251557377;5137.201979307212;5150.845474635767;5178.378594568676;5181.3005244291535;4949.7772600233075;5091.3381260436645;5099.566536844335;5128.8461346159675;5137.563307118937;5151.845444636667;5186;5203.271541984125;5208.140656791663;5217.426083130853;5059.822906198413;5099.898002039864;5101.026499163434;5116.820911268088;5134.871628209325;4670.64970127235;4979.800807967786;4995.476379704225;5028.773705183203;5060.974695126501;5118.257852611425;5131.974340128425;5137.3817247568395;5198.636095473432;5217.2434996925995;3739.9813000934823;3766.246750649826;3773.094457330276;3773.3208022556205;3778.9055273617278;5391.595630327663;5421.83734487966;5642.322921249476;5659.660420374791;5678.949394361891;5514.61397702157;5518.365387980273;5529.281193444826;5536.612437129363;5541.362743284412;5301.602379821452;5528;5534.059209934401;5541.868844103069;5548.9722551386985;5524.14375771756;5524.75138618755;5535.536686174613;5535.861603459784;5545.168224766156;5613.607047506635;5625.987322282029;5626.38109807929;5629.746661400329;5636.830895073154;5187.870303242449;5450.700211488248;5475.342958844964;5484.14995675655;5484.808031718871 13:25:05 INFO - PROCESS | 9422 | |5;yui.html;779.4933293359319;786.0606575142704;808.5957021489255;840.0885039731891;854.991110489622;16086;16532.42136525222;16634.33462661492;16643.167841607916;16677.24952377143;16553.83446165538;16792.244349004293;16792.412265570707;16812.41156559521;16831.242594083265;787.7557957033338;806.2260230179036;806.9919300807005;807.6728924785472;808.1635507249997;652.1326635574675;758.4652819762055;796.7291121018852;798.0144521515923;803.7387848949086;861.4056301152216;862.7886167888876;865.0614083719172;866.8309679612478;868.1969178509885;863.1239292118505;864.1013346120028;864.3171894203572;864.576357584782;864.8054187807725;498.88525639102846;590.1236663554618;609.2080295615726;615.3938370704852;619.1053927075378;16034.35862565496;16054.678906421806;16060.919695401564;16073.91963040189;16074.5981350466;13180.406881690347;13387.531436399679;13548.322583870768;13770.24263665498;14000.369983350673;12509.687257818536;12807.359632018455;13027.348632568428;13074.411651475612;13216.801747973788;4545.818167273305;4558.202314594932;4606.838760643362;4642.141203877293;4668.509806470306;79960.60019699922;80347.59826200831;80792;81447;81564.59217703933;11324.830127548094;11782.646520604396;11855.741879015755;12051;12256.81614775779;22337;22485.32544023649;22580;22698.773012269667;22754.544909102056;8888.955555222183;9111.635534578676;9164.8625270621;9240.122188392092;9415.423624730005;4872.731999739977;4897.7795999180125;5050.217216331474;5056.9494305057215;5139.948600513947;3694.113412780913;3834.559025712023;3835.7890316032885;3838.27072856162;3838.3858582626644;657.2901266632026;658.111549408295;662.4038365471115;677.2786981864299;684.9931500685029;647.5726020826327;666.5101150654265;738;756.1455555222673;770.6609091999502;9904.405735655884;10060.051132647606;10325.122364599094;10408.427536485415;10655.176378259768;724.8912663100577;748.5845355827568;749.4978364495801;751.7970148059993;760.277736150659;4022.557518672944;4815;5276.234945932818;5434.673919564839;5451.482109199699;111816;120001.39999300122;120004;125750;130316.3484182592;102653.48673256735;103864.48067759613;104378;105664.18846963337;105823;74839.25160748432;75404.62297688477;76812.23187768162;90851;91268.08731912728;10089.596416143566;10138;10392.636257730943;10515.894841051491;10591.311564748266;49285;51895.481045189066;52375.47624523706;52585.21122183094;53590.1961470586;69506.30493694998;70048.64975675293;71807.64096179485;73414;73946.63026684833;13324.200547967153;13425.530106446227;13505.32473376347;13515.121517101357;13787.586372408843;14629.707405852036;15045.548633541011;15246.31391587359;15352.385904563693;16175.43385981485;13604.115732477358;13783;13831.654208644864;14274.218941328778;14452.494162704255;9805.803883922139;13987.23070231147;14005.159690418608;14064.226467544384;14844;14129.364178612193;14278.571642850731;14311.642208944444;14383;14777.187254701095;11152.832707509231;11552.768944621228;11677.824832627688;12287;12608.369581520703;12312.876871231172;12384.380780960735;12457.875421246034;12488;12586.748265034465 13:25:05 INFO - PROCESS | 9422 | __end_tp_report 13:25:05 INFO - PROCESS | 9422 | __start_cc_report 13:25:05 INFO - PROCESS | 9422 | _x_x_mozilla_cycle_collect,1003 13:25:05 INFO - PROCESS | 9422 | __end_cc_report 13:25:05 INFO - PROCESS | 9422 | __startTimestamp1469219105658__endTimestamp 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | ------- Summary: start ------- 13:25:05 INFO - PROCESS | 9422 | Number of tests: 6 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#0] dojo.html Cycles:165 Average:5342.85 Median:5551.25 stddev:612.65 (11.0%) stddev-sans-first:587.16 13:25:05 INFO - PROCESS | 9422 | Values: 3034.7 3037.5 3050.8 3079.0 3095.9 5248.1 5276.0 5312.8 5368.4 5389.7 5311.8 5351.1 5380.9 5417.4 5418.3 4793.7 4867.1 4871.0 4877.2 5062.4 4966.6 5006.8 5011.5 5012.8 5022.9 4748.3 4775.1 4830.7 4900.0 4965.1 4855.9 4861.1 4862.2 4930.8 4956.0 4863.1 4885.0 4896.6 4900.5 4935.2 5206.9 5245.5 5323.4 5376.6 5401.1 5678.3 5728.2 5767.2 5779.6 5780.7 5835.4 5842.0 5844.6 5850.2 5850.9 5252.2 5254.6 5290.1 5291.9 5444.8 5796.2 5825.3 5833.4 5848.1 5848.4 5607.6 5613.4 5652.5 5653.7 5659.1 5614.8 5675.9 5714.9 5717.6 5758.7 5749.3 5764.7 5765.1 5768.6 5810.0 5688.3 5753.7 5765.9 5772.9 5774.5 4287.0 4309.0 4312.6 4350.7 4361.5 3928.4 3928.8 3968.7 4002.6 4023.2 5518.0 5518.3 5518.4 5520.6 5526.7 5658.4 5660.5 5806.0 5811.2 5873.6 5417.8 5458.7 5465.8 5501.2 5507.3 4944.2 4951.0 4957.5 4961.8 5002.6 5693.9 5854.5 5894.3 5907.1 5907.6 5757.9 5821.4 5825.7 5871.6 5877.9 5938.2 5947.9 5951.7 5960.7 5963.4 5524.1 5556.7 5647.1 5690.9 5708.5 5692.4 5785.9 5800.8 5815.4 5848.2 5584.3 5623.2 5623.8 5636.9 5637.4 5484.4 5574.2 5628.0 5629.7 5634.8 5551.3 5623.7 5630.5 5677.9 5680.1 5694.4 5717.8 5746.8 5752.4 5763.9 5378.8 5476.9 5531.5 5538.8 5546.8 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#1] ext.html Cycles:180 Average:57323.55 Median:43199.46 stddev:108948.33 (252.2%) stddev-sans-first:109170.69 13:25:05 INFO - PROCESS | 9422 | Values: 590.7 612.2 613.6 630.2 647.7 30128.5 43558.8 44431.3 44639.8 48538.5 45481.8 46178.5 46226.8 46341.0 46423.8 8977.8 9073.9 9107.0 9108.2 9130.0 8907.7 9004.1 9006.9 9008.0 9013.5 9032.3 9104.7 9107.6 9110.2 9113.8 9021.6 9058.1 9069.3 9070.3 9074.7 8991.0 8996.4 9043.0 9057.5 9068.6 44006.6 44361.0 44411.0 44415.2 44425.3 3537.0 3663.3 3667.8 3864.2 4057.2 33076.2 33160.3 33242.3 33247.7 33303.8 4433.1 4488.3 4488.6 4491.6 4510.8 361391.0 362148.0 362285.0 363682.0 364010.0 58747.4 58795.0 58893.6 59238.7 59314.0 54181.7 55576.4 60550.0 60728.0 60744.0 15429.2 15765.8 16278.6 17080.5 17082.9 13108.4 13567.9 13576.3 13579.0 13586.7 16013.7 16251.4 16287.0 16298.8 16461.4 5214.8 5975.0 6095.2 6137.4 6263.8 950.7 1061.8 1114.7 1118.6 1127.2 33254.0 33314.3 33330.0 34016.7 36420.3 1088.1 1128.9 1155.1 1160.5 1189.3 16768.6 16911.7 16935.2 17033.5 17069.8 522584.0 526144.0 526268.0 526876.0 528164.0 57789.1 57907.1 57936.4 57942.0 57972.1 1247.5 1346.4 1396.2 1408.8 1415.9 30657.4 32758.8 33108.5 33568.0 34157.5 49375.5 49933.3 49970.8 50001.7 50051.0 315939.0 316689.0 317263.0 317295.0 317478.0 27014.9 28302.7 28304.2 29585.1 29767.1 49434.5 49456.5 49672.5 49739.8 51321.5 42092.2 42631.8 42832.6 42866.4 44524.3 42419.4 42725.8 42869.0 44151.8 44232.3 41455.2 41867.2 43106.4 43198.6 43200.4 35442.6 36980.6 37028.6 37746.1 38312.2 28577.9 28851.3 29038.0 29102.7 29103.6 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#2] jquery.html Cycles:180 Average:27750.60 Median:2564.03 stddev:73740.59 (2876.0%) stddev-sans-first:73918.77 13:25:05 INFO - PROCESS | 9422 | Values: 356.3 379.3 382.3 384.4 402.6 14722.5 18519.2 19086.5 19841.6 20091.8 17068.9 17483.3 17828.7 18005.8 18014.6 5609.6 5834.4 5871.3 5871.9 5879.1 4154.3 4221.5 5765.9 5854.0 6598.7 6474.5 6545.6 6555.9 6556.3 6571.2 6242.5 6392.0 6414.7 6420.4 6536.6 5465.7 5575.7 5624.6 5635.3 5636.7 22942.0 23013.0 23322.0 23482.3 23527.9 2367.7 2390.6 2550.3 2577.8 2614.5 14502.0 14698.3 14819.2 14902.3 15377.5 1874.0 1903.8 1918.8 2002.3 2034.9 84710.6 84748.6 85983.0 86514.6 88329.0 10603.9 10752.8 10758.0 11027.0 11121.6 15082.4 15084.5 15098.0 15102.6 15109.6 7815.4 7999.1 8037.0 8070.8 8117.9 7576.7 7634.6 7656.9 7802.3 7849.1 4615.7 4618.4 4657.9 4659.2 4765.0 1871.9 1885.0 1903.3 1944.9 1984.8 895.5 947.1 951.7 976.7 983.8 18786.4 20186.5 20266.6 20299.0 20488.6 993.3 1000.7 1010.2 1021.7 1044.1 9073.1 11020.9 11212.5 11215.8 11260.9 394180.0 397957.0 399327.0 495382.0 521732.0 58435.4 73798.0 92093.6 99991.5 104003.0 1025.7 1027.4 1070.9 1073.2 1077.4 17569.5 18039.1 18374.8 18416.8 18434.7 40815.2 41125.4 42104.8 42226.0 42705.8 59751.7 60081.7 60755.7 61135.7 61784.4 8144.2 8172.5 8332.5 8384.0 8394.3 11629.8 13219.5 13222.0 13349.5 13398.7 8965.4 10606.8 11340.2 11439.4 11749.4 10370.5 10401.8 10636.3 10636.7 10733.7 11275.9 11396.5 11451.6 12052.7 12204.9 8281.2 8308.6 8396.6 8440.2 8482.5 10909.2 11407.7 11427.4 11706.1 11919.9 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#3] mootools.html Cycles:180 Average:5967.10 Median:467.88 stddev:3472.29 (742.1%) stddev-sans-first:3464.55 13:25:05 INFO - PROCESS | 9422 | Values: 1330.3 1447.1 1514.0 1601.4 1832.1 8516.2 8921.5 8949.7 9165.4 9172.0 8966.3 9057.9 9058.1 9066.0 9074.5 2379.5 2412.2 2414.4 2416.3 2416.4 2368.6 2386.7 2399.8 2414.4 2424.4 2323.6 2332.6 2336.9 2376.5 2378.9 2289.5 2305.6 2316.1 2317.3 2365.0 2290.2 2309.5 2324.4 2331.5 2332.8 8924.8 9016.7 9043.8 9083.3 9121.0 5080.2 5308.7 5313.7 5315.8 5316.1 3546.6 3552.7 3598.4 3616.7 3634.9 1154.8 1176.6 1195.4 1197.3 1224.6 12556.0 12808.4 12840.1 12855.0 12859.0 4484.2 4615.0 4789.9 4807.8 4811.8 10116.3 10120.2 10127.3 10134.2 10142.1 5340.0 5351.4 5351.8 5358.4 5362.2 4296.0 4793.4 4934.7 4961.9 5012.1 3822.8 3874.9 3973.4 4014.0 4020.6 1156.7 1191.3 1197.8 1202.2 1208.2 928.9 984.6 985.4 997.7 998.7 5877.7 5931.5 6038.3 6047.6 6077.5 453.3 463.6 466.1 469.7 473.7 4088.5 4163.1 4163.4 4179.6 4215.0 11262.1 11518.9 11554.1 11571.4 11576.2 10799.4 11498.7 11516.8 11538.0 11547.0 8261.5 8591.8 8597.0 8598.1 8603.9 5862.2 5983.3 6175.8 6181.3 6329.8 10254.1 10576.0 10582.5 10700.6 10773.6 10957.6 10977.7 10992.1 11008.9 11026.4 7939.2 8003.0 8229.3 8355.5 8356.7 8843.1 8900.6 8907.3 8912.1 8912.6 7907.4 7986.8 8050.8 8052.4 8214.2 7793.0 7879.6 7936.0 7988.5 8124.6 7179.5 7217.6 7268.5 7272.5 7335.3 5177.0 5190.8 5312.6 5335.3 5423.5 5160.9 5193.0 5200.8 5231.0 5318.1 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#4] prototype.html Cycles:180 Average:4487.99 Median:5066.63 stddev:1288.96 (25.4%) stddev-sans-first:1253.91 13:25:05 INFO - PROCESS | 9422 | Values: 313.2 323.4 325.0 327.6 332.3 5292.4 5314.9 5328.4 5331.8 5334.3 4995.9 5086.3 5272.8 5275.4 5290.9 4123.5 4141.3 4145.7 4150.3 4156.2 4126.3 4152.3 4175.5 4181.8 4183.3 3877.4 4041.4 4100.5 4133.5 4141.0 4119.2 4121.9 4125.5 4134.6 4137.2 3652.8 3665.9 3669.7 3686.2 3688.4 5081.9 5107.9 5136.6 5152.3 5167.9 4700.7 4715.8 4721.4 4729.6 4738.1 5463.5 5464.4 5473.2 5479.6 5496.1 3859.7 3977.7 4014.5 4024.5 4026.6 5653.7 5668.0 5669.4 5673.7 5677.7 4081.5 4083.1 4085.9 4095.3 4095.5 4038.8 4113.3 4129.4 4129.6 4140.6 4680.3 4686.7 4692.2 4693.4 4698.9 5488.6 5504.9 5508.5 5515.7 5516.0 1409.4 1425.0 1431.9 1436.8 1448.6 908.0 922.2 928.9 935.4 935.6 4794.3 4802.6 4803.4 4808.3 4812.4 5034.7 5048.2 5054.9 5072.3 5105.4 4615.8 4725.3 4783.3 4802.5 4812.4 2625.8 2666.9 2675.7 2685.2 2694.3 5130.5 5137.2 5150.8 5178.4 5181.3 4949.8 5091.3 5099.6 5128.8 5137.6 5151.8 5186.0 5203.3 5208.1 5217.4 5059.8 5099.9 5101.0 5116.8 5134.9 4670.6 4979.8 4995.5 5028.8 5061.0 5118.3 5132.0 5137.4 5198.6 5217.2 3740.0 3766.2 3773.1 3773.3 3778.9 5391.6 5421.8 5642.3 5659.7 5678.9 5514.6 5518.4 5529.3 5536.6 5541.4 5301.6 5528.0 5534.1 5541.9 5549.0 5524.1 5524.8 5535.5 5535.9 5545.2 5613.6 5626.0 5626.4 5629.7 5636.8 5187.9 5450.7 5475.3 5484.1 5484.8 13:25:05 INFO - PROCESS | 9422 | 13:25:05 INFO - PROCESS | 9422 | [#5] yui.html Cycles:180 Average:21458.18 Median:3837.03 stddev:30724.59 (800.7%) stddev-sans-first:30771.54 13:25:05 INFO - PROCESS | 9422 | Values: 779.5 786.1 808.6 840.1 855.0 16086.0 16532.4 16634.3 16643.2 16677.2 16553.8 16792.2 16792.4 16812.4 16831.2 787.8 806.2 807.0 807.7 808.2 652.1 758.5 796.7 798.0 803.7 861.4 862.8 865.1 866.8 868.2 863.1 864.1 864.3 864.6 864.8 498.9 590.1 609.2 615.4 619.1 16034.4 16054.7 16060.9 16073.9 16074.6 13180.4 13387.5 13548.3 13770.2 14000.4 12509.7 12807.4 13027.3 13074.4 13216.8 4545.8 4558.2 4606.8 4642.1 4668.5 79960.6 80347.6 80792.0 81447.0 81564.6 11324.8 11782.6 11855.7 12051.0 12256.8 22337.0 22485.3 22580.0 22698.8 22754.5 8889.0 9111.6 9164.9 9240.1 9415.4 4872.7 4897.8 5050.2 5056.9 5139.9 3694.1 3834.6 3835.8 3838.3 3838.4 657.3 658.1 662.4 677.3 685.0 647.6 666.5 738.0 756.1 770.7 9904.4 10060.1 10325.1 10408.4 10655.2 724.9 748.6 749.5 751.8 760.3 4022.6 4815.0 5276.2 5434.7 5451.5 111816.0 120001.4 120004.0 125750.0 130316.3 102653.5 103864.5 104378.0 105664.2 105823.0 74839.3 75404.6 76812.2 90851.0 91268.1 10089.6 10138.0 10392.6 10515.9 10591.3 49285.0 51895.5 52375.5 52585.2 53590.2 69506.3 70048.6 71807.6 73414.0 73946.6 13324.2 13425.5 13505.3 13515.1 13787.6 14629.7 15045.5 15246.3 15352.4 16175.4 13604.1 13783.0 13831.7 14274.2 14452.5 9805.8 13987.2 14005.2 14064.2 14844.0 14129.4 14278.6 14311.6 14383.0 14777.2 11152.8 11552.8 11677.8 12287.0 12608.4 12312.9 12384.4 12457.9 12488.0 12586.7 13:25:05 INFO - PROCESS | 9422 | -------- Summary: end -------- 13:25:05 INFO - PROCESS | 9422 | 13:25:06 INFO - TEST-INFO | 9422: exit 0 13:25:06 INFO - TEST-OK | dromaeo_css | took 1113608ms 13:25:06 INFO - TEST-START | kraken 13:25:06 INFO - operating with platform_type : linux_ 13:25:06 INFO - Initialising browser for kraken test... 13:25:06 INFO - TEST-INFO | started process 9617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRH3Mqw/profile http://localhost:42425/getInfo.html) 13:25:09 INFO - PROCESS | 9617 | JavaScript error: , line 0: uncaught exception: undefined 13:25:10 INFO - PROCESS | 9617 | __metrics Screen width/height:1600/1200 13:25:10 INFO - PROCESS | 9617 | colorDepth:24 13:25:10 INFO - PROCESS | 9617 | Browser inner width/height: 1024/697 13:25:10 INFO - PROCESS | 9617 | __metrics 13:25:11 INFO - PROCESS | 9617 | 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" 13:25:11 INFO - PROCESS | 9617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:25:12 INFO - TEST-INFO | 9617: exit 0 13:25:12 INFO - Browser initialized. 13:25:12 INFO - Running cycle 1/1 for kraken test... 13:25:12 INFO - TEST-INFO | started process 9696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRH3Mqw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:25:24 INFO - PROCESS | 9696 | RSS: Main: 157908992 13:25:24 INFO - PROCESS | 9696 | 13:25:26 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42425/tests/kraken/driver.html?testName=audio-beat-detection) 13:25:27 INFO - PROCESS | 9696 | RSS: Main: 214286336 13:25:27 INFO - PROCESS | 9696 | 13:25:30 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42425/tests/kraken/driver.html?testName=audio-dft) 13:25:30 INFO - PROCESS | 9696 | RSS: Main: 192933888 13:25:30 INFO - PROCESS | 9696 | 13:25:33 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42425/tests/kraken/driver.html?testName=audio-fft) 13:25:34 INFO - PROCESS | 9696 | RSS: Main: 270553088 13:25:34 INFO - PROCESS | 9696 | 13:25:36 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42425/tests/kraken/driver.html?testName=audio-oscillator) 13:25:37 INFO - PROCESS | 9696 | RSS: Main: 180965376 13:25:37 INFO - PROCESS | 9696 | 13:25:39 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42425/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:25:39 INFO - PROCESS | 9696 | RSS: Main: 186003456 13:25:39 INFO - PROCESS | 9696 | 13:25:42 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42425/tests/kraken/driver.html?testName=imaging-darkroom) 13:25:43 INFO - PROCESS | 9696 | RSS: Main: 220872704 13:25:43 INFO - PROCESS | 9696 | 13:25:46 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42425/tests/kraken/driver.html?testName=imaging-desaturate) 13:25:47 INFO - PROCESS | 9696 | RSS: Main: 221048832 13:25:47 INFO - PROCESS | 9696 | 13:25:50 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42425/tests/kraken/driver.html?testName=json-parse-financial) 13:25:50 INFO - PROCESS | 9696 | RSS: Main: 220958720 13:25:50 INFO - PROCESS | 9696 | 13:25:52 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42425/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:25:52 INFO - PROCESS | 9696 | RSS: Main: 185352192 13:25:52 INFO - PROCESS | 9696 | 13:25:54 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:25:55 INFO - PROCESS | 9696 | RSS: Main: 190296064 13:25:55 INFO - PROCESS | 9696 | 13:25:57 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:25:57 INFO - PROCESS | 9696 | RSS: Main: 195276800 13:25:57 INFO - PROCESS | 9696 | 13:26:00 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:26:01 INFO - PROCESS | 9696 | RSS: Main: 188493824 13:26:01 INFO - PROCESS | 9696 | 13:26:03 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:26:04 INFO - PROCESS | 9696 | RSS: Main: 193912832 13:26:04 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:42425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42425/tests/kraken/driver.html?testName=ai-astar) 13:26:06 INFO - PROCESS | 9696 | RSS: Main: 243687424 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | __start_tp_report 13:26:06 INFO - PROCESS | 9696 | _x_x_mozilla_page_load 13:26:06 INFO - PROCESS | 9696 | _x_x_mozilla_page_load_details 13:26:06 INFO - PROCESS | 9696 | |i|pagename|runs| 13:26:06 INFO - PROCESS | 9696 | |0;ai-astar;105;112;111;110;110;112;113;111;111;111 13:26:06 INFO - PROCESS | 9696 | |1;audio-beat-detection;145;120;119;192;123;119;144;122;157;118 13:26:06 INFO - PROCESS | 9696 | |2;audio-dft;161;160;161;161;161;161;163;162;160;159 13:26:06 INFO - PROCESS | 9696 | |3;audio-fft;74;75;75;111;75;74;75;75;100;74 13:26:06 INFO - PROCESS | 9696 | |4;audio-oscillator;82;83;117;83;84;84;126;83;85;138 13:26:06 INFO - PROCESS | 9696 | |5;imaging-gaussian-blur;117;117;118;117;117;116;117;117;117;116 13:26:06 INFO - PROCESS | 9696 | |6;imaging-darkroom;146;146;147;146;146;147;145;145;146;146 13:26:06 INFO - PROCESS | 9696 | |7;imaging-desaturate;95;95;95;95;94;94;95;95;95;94 13:26:06 INFO - PROCESS | 9696 | |8;json-parse-financial;54;54;53;54;54;55;54;54;54;54 13:26:06 INFO - PROCESS | 9696 | |9;json-stringify-tinderbox;62;62;61;61;63;61;61;61;64;60 13:26:06 INFO - PROCESS | 9696 | |10;stanford-crypto-aes;76;76;76;75;75;86;78;82;76;78 13:26:06 INFO - PROCESS | 9696 | |11;stanford-crypto-ccm;121;127;130;125;118;120;125;128;206;130 13:26:06 INFO - PROCESS | 9696 | |12;stanford-crypto-pbkdf2;158;158;159;156;155;153;154;156;153;158 13:26:06 INFO - PROCESS | 9696 | |13;stanford-crypto-sha256-iterative;62;66;62;61;62;62;64;62;61;61 13:26:06 INFO - PROCESS | 9696 | __end_tp_report 13:26:06 INFO - PROCESS | 9696 | __start_cc_report 13:26:06 INFO - PROCESS | 9696 | _x_x_mozilla_cycle_collect,1673 13:26:06 INFO - PROCESS | 9696 | __end_cc_report 13:26:06 INFO - PROCESS | 9696 | __startTimestamp1469219166343__endTimestamp 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | ------- Summary: start ------- 13:26:06 INFO - PROCESS | 9696 | Number of tests: 14 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.17 (2.0%) stddev-sans-first:0.97 13:26:06 INFO - PROCESS | 9696 | Values: 105.0 112.0 111.0 110.0 110.0 112.0 113.0 111.0 111.0 111.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:133.50 stddev:24.14 (18.1%) stddev-sans-first:25.38 13:26:06 INFO - PROCESS | 9696 | Values: 145.0 120.0 119.0 192.0 123.0 119.0 144.0 122.0 157.0 118.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 13:26:06 INFO - PROCESS | 9696 | Values: 161.0 160.0 161.0 161.0 161.0 161.0 163.0 162.0 160.0 159.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:13.28 (17.7%) stddev-sans-first:13.86 13:26:06 INFO - PROCESS | 9696 | Values: 74.0 75.0 75.0 111.0 75.0 74.0 75.0 75.0 100.0 74.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:83.00 stddev:21.64 (26.1%) stddev-sans-first:22.31 13:26:06 INFO - PROCESS | 9696 | Values: 82.0 83.0 117.0 83.0 84.0 84.0 126.0 83.0 85.0 138.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 13:26:06 INFO - PROCESS | 9696 | Values: 117.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 13:26:06 INFO - PROCESS | 9696 | Values: 146.0 146.0 147.0 146.0 146.0 147.0 145.0 145.0 146.0 146.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 13:26:06 INFO - PROCESS | 9696 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 13:26:06 INFO - PROCESS | 9696 | Values: 54.0 54.0 53.0 54.0 54.0 55.0 54.0 54.0 54.0 54.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:61.50 stddev:1.17 (1.9%) stddev-sans-first:1.24 13:26:06 INFO - PROCESS | 9696 | Values: 62.0 62.0 61.0 61.0 63.0 61.0 61.0 61.0 64.0 60.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:77.00 stddev:3.55 (4.6%) stddev-sans-first:3.71 13:26:06 INFO - PROCESS | 9696 | Values: 76.0 76.0 76.0 75.0 75.0 86.0 78.0 82.0 76.0 78.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:127.50 stddev:25.98 (20.4%) stddev-sans-first:27.19 13:26:06 INFO - PROCESS | 9696 | Values: 121.0 127.0 130.0 125.0 118.0 120.0 125.0 128.0 206.0 130.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:157.00 stddev:2.21 (1.4%) stddev-sans-first:2.22 13:26:06 INFO - PROCESS | 9696 | Values: 158.0 158.0 159.0 156.0 155.0 153.0 154.0 156.0 153.0 158.0 13:26:06 INFO - PROCESS | 9696 | 13:26:06 INFO - PROCESS | 9696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.57 (2.5%) stddev-sans-first:1.66 13:26:06 INFO - PROCESS | 9696 | Values: 62.0 66.0 62.0 61.0 62.0 62.0 64.0 62.0 61.0 61.0 13:26:06 INFO - PROCESS | 9696 | -------- Summary: end -------- 13:26:06 INFO - PROCESS | 9696 | 13:26:07 INFO - TEST-INFO | 9696: exit 0 13:26:07 INFO - TEST-OK | kraken | took 60994ms 13:26:07 INFO - SUITE-END | took 1174s 13:26:07 INFO - Completed test suite (00:19:34) 13:26:07 INFO - javascript benchmark 13:26:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3034.7420469260096, 3037.498812695905, 3050.8474576271187, 3078.9846050769743, 3095.8916437924677, 5248.0553500369915, 5275.973620131898, 5312.787488500456, 5368.409474957752, 5389.676619402839, 5311.840644780653, 5351.117065684158, 5380.865478363043, 5417.404085550586, 5418.2685337479425, 4793.712377257359, 4867.1482490564185, 4870.975645121769, 4877.170880950229, 5062.367204099488, 4966.5778408835295, 5006.849794506171, 5011.523905229015, 5012.799488020475, 5022.949770502303, 4748.335233067373, 4775.068861571977, 4830.73430961297, 4899.975500122512, 4965.106280869442, 4855.878603034917, 4861.149298872683, 4862.222044472903, 4930.753462326869, 4956.0, 4863.076015557032, 4885.0, 4896.632752543538, 4900.509949005107, 4935.161022626162, 5206.947930520684, 5245.501677340647, 5323.387810401815, 5376.569874410077, 5401.054815407328, 5678.346990096151, 5728.22668939692, 5767.1637612546365, 5779.566532510079, 5780.682062486561, 5835.387284335168, 5841.970790146064, 5844.561657875676, 5850.151727999459, 5850.912236316459, 5252.1596544552685, 5254.632175747699, 5290.100682883919, 5291.947080529222, 5444.8094316698725, 5796.188533605298, 5825.300963884361, 5833.358330583633, 5848.064309710426, 5848.356680765112, 5607.551395888319, 5613.410591887875, 5652.519535839499, 5653.717314134276, 5659.122835960433, 5614.831555053355, 5675.886482270332, 5714.885702285931, 5717.599768016199, 5758.712064396847, 5749.338826035059, 5764.654120752768, 5765.135229715493, 5768.596198266081, 5810.041343178413, 5688.260526131577, 5753.683547404849, 5765.8558536036935, 5772.913406298909, 5774.509166720792, 4286.957130428718, 4308.978455107767, 4312.568743125725, 4350.651947844165, 4361.498427680794, 3928.43037759529, 3928.8035598219976, 3968.722189446769, 4002.5597184309704, 4023.2355852387955, 5517.951589198039, 5518.2826232589505, 5518.392976772552, 5520.613557050968, 5526.668399895939, 5658.434156584309, 5660.518855897212, 5806.042003069446, 5811.186433899257, 5873.618214816023, 5417.8103766367985, 5458.699771512605, 5465.808696695596, 5501.174823776386, 5507.311586051744, 4944.159492886218, 4951.034548263054, 4957.4794646561595, 4961.82633607822, 5002.57478114357, 5693.914591281134, 5854.502367298742, 5894.2926848778425, 5907.084401917709, 5907.586468947218, 5757.9424205757405, 5821.417858214145, 5825.70871456434, 5871.6183448077445, 5877.882442351214, 5938.2280303560265, 5947.91078133837, 5951.702414879325, 5960.672163031075, 5963.373845746134, 5524.06090964545, 5556.749946252509, 5647.124695672177, 5690.914636280542, 5708.514776244066, 5692.373838877637, 5785.942140578709, 5800.76796928135, 5815.447532484407, 5848.239728835394, 5584.274044374205, 5623.184638227516, 5623.831285061455, 5636.915446268391, 5637.408072152365, 5484.369297530835, 5574.219609254788, 5628.043232650377, 5629.662220266797, 5634.774609015757, 5551.25058117149, 5623.718814059363, 5630.465105814941, 5677.886442271214, 5680.11958146488, 5694.373618902, 5717.799877004285, 5746.827595172152, 5752.395998420106, 5763.913541296968, 5378.784848606012, 5476.91784623239, 5531.502164805187, 5538.833834984957, 5546.7781288748], "value": 5300.7309032293515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.7489317040257, 612.185792895449, 613.6164896939416, 630.1776182082383, 647.7409036385442, 30128.548071778914, 43558.78220608897, 44431.33352999708, 44639.77680111603, 48538.51461485384, 45481.77259113708, 46178.538214617845, 46226.768866155704, 46341.0, 46423.76788116063, 8977.775555611113, 9073.909260907372, 9107.0, 9108.18026377626, 9129.95435022824, 8907.73276801697, 9004.144606262395, 9006.909930900705, 9008.0, 9013.504257265848, 9032.277417806592, 9104.726858194264, 9107.590158442852, 9110.225630821387, 9113.817723645523, 9021.594028268712, 9058.139476749699, 9069.274458043341, 9070.319726020542, 9074.68238611652, 8991.0, 8996.370254082216, 9042.954785226098, 9057.547122643842, 9068.637254509811, 44006.55993440057, 44361.0, 44411.0, 44415.22470590004, 44425.33361999573, 3537.0287364908713, 3663.303972245265, 3667.8349474273723, 3864.207837393356, 4057.177949642273, 33076.17309567256, 33160.33679326424, 33242.3351532968, 33247.6675233247, 33303.83348083268, 4433.069055498349, 4488.349189367524, 4488.618467430272, 4491.573300536444, 4510.797014134372, 361391.0, 362148.0, 362284.9999999974, 363682.0, 364010.0, 58747.41252587419, 58795.0, 58893.64425422935, 59238.70380648156, 59314.0, 54181.72909135508, 55576.44423555794, 60550.0, 60728.0, 60744.0, 15429.22853857325, 15765.763513547307, 16278.593035173979, 17080.48758537221, 17082.914585426992, 13108.410121544553, 13567.86432135666, 13576.253306068063, 13579.0, 13586.728265434833, 16013.67972640564, 16251.431199908184, 16287.0, 16298.837011629732, 16461.423850165185, 5214.817481388133, 5974.970125149346, 6095.238095238095, 6137.3735959970645, 6263.812085637438, 950.6530116507563, 1061.8141825180562, 1114.660028691257, 1118.6396702956754, 1127.2165844737829, 33254.0, 33314.3337133256, 33330.0, 34016.65983340184, 36420.27159456849, 1088.0914436445657, 1128.8701799293021, 1155.1278784517635, 1160.4719852467108, 1189.316143217636, 16768.58078548046, 16911.746323805153, 16935.153242337834, 17033.48899533016, 17069.829301707072, 522584.0, 526144.0, 526268.0, 526876.0, 528164.0, 57789.13316300259, 57907.131393029136, 57936.4206357931, 57942.0, 57972.130418043766, 1247.5134697467995, 1346.4479563378968, 1396.155326027759, 1408.7605107131812, 1415.9221242831536, 30657.386852262385, 32758.83620581977, 33108.505861810925, 33568.0, 34157.48763768496, 49375.50624493853, 49933.25100123428, 49970.75014625049, 50001.74999124981, 50051.0, 315939.0, 316689.0, 317263.0, 317295.0, 317478.0, 27014.864925675247, 28302.716972830007, 28304.15087547377, 29585.112446626637, 29767.10698679043, 49434.505654942994, 49456.50543494519, 49672.50327496679, 49739.75130124326, 51321.486785131674, 42092.15815683608, 42631.786841065594, 42832.57167428286, 42866.35700464558, 44524.33213501735, 42419.363709543766, 42725.786371067945, 42869.0, 44151.77924110359, 44232.33651495166, 41455.1708965825, 41867.162656746084, 43106.353404698326, 43198.568014319455, 43200.35199471947, 35442.64557354394, 36980.630193698795, 37028.629713703594, 37746.056348590406, 38312.233755325295, 28577.857110715144, 28851.27871803138, 29038.0, 29102.70897291085, 29103.563446547894], "value": 20149.823214492717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.3087610036527, 379.3475222617099, 382.25651108593786, 384.40609258695315, 402.63762613647714, 14722.484713035046, 18519.166637501312, 19086.522836929085, 19841.60316793663, 20091.799082009213, 17068.914655426706, 17483.300667973264, 17828.732569011474, 18005.81994180058, 18014.639707205883, 5609.551235901118, 5834.358220595731, 5871.324797648261, 5871.941280587203, 5879.088741245114, 4154.335306350985, 4221.493420789509, 5765.913511297334, 5853.970730146343, 6598.67006649668, 6474.514411419139, 6545.639989800559, 6555.93444065558, 6556.311587283338, 6571.244306904696, 6242.469390101847, 6392.0, 6414.711337989802, 6420.357964203589, 6536.640484773336, 5465.688234823653, 5575.665460072409, 5624.606277560572, 5635.295588051494, 5636.746346414421, 22941.967611457523, 23013.0, 23322.0, 23482.295531134092, 23527.882360588086, 2367.7130044843047, 2390.581648211556, 2550.2859199424192, 2577.7808886244693, 2614.4509652972897, 14502.0, 14698.26508674562, 14819.18494482803, 14902.254887255702, 15377.538673839803, 1873.9812601873944, 1903.7620297462818, 1918.8273055424938, 2002.2691717523144, 2034.8779073255505, 84710.57644711799, 84748.57625711831, 85983.0, 86514.56742716309, 88329.0, 10603.94698026505, 10752.838707419238, 10758.0, 11026.960012397732, 11121.610743623934, 15082.396704131932, 15084.472043478425, 15098.018628789094, 15102.622434439227, 15109.622259443602, 7815.374770018385, 7999.120096789348, 8036.9953755780525, 8070.838583228303, 8117.918820811835, 7576.659050342748, 7634.618269086634, 7656.885146722803, 7802.269205777932, 7849.097353804272, 4615.676902616785, 4618.445786505641, 4657.930131048037, 4659.2312268476, 4765.0, 1871.8876867387728, 1884.990575047116, 1903.2767548331544, 1944.8833070015844, 1984.7915968823354, 895.4895709445684, 947.0576776107816, 951.7287573041789, 976.6630512473185, 983.8229118758549, 18786.436406907815, 20186.495337616678, 20266.594668106554, 20298.95515672661, 20488.590228195353, 993.2500961773959, 1000.6597756762735, 1010.1514727628827, 1021.7241344836852, 1044.1177205261542, 9073.13805188506, 11020.944895275632, 11212.495437705324, 11215.831762523569, 11260.887391126147, 394180.0, 397957.0, 399327.0, 495382.0, 521732.0, 58435.415645843845, 73798.0, 92093.61859572111, 99991.50004250077, 104003.0, 1025.687165414541, 1027.3938376357985, 1070.9089727373262, 1073.2487258918788, 1077.3697387028676, 17569.472915812545, 18039.09804509743, 18374.81625183731, 18416.815831842046, 18434.72347914809, 40815.183696325315, 41125.383119252634, 42104.78947605242, 42226.0, 42705.78647106745, 59751.701241493516, 60081.69959150176, 60755.69622151861, 61135.694321528106, 61784.38215617966, 8144.185581441809, 8172.509649421054, 8332.50004999702, 8383.95808020956, 8394.328453723809, 11629.76740465169, 13219.471221151047, 13222.0, 13349.466021359036, 13398.732025359634, 8965.417247878868, 10606.787864242518, 11340.206185567262, 11439.42802859837, 11749.353785541878, 10370.481475926324, 10401.791964160826, 10636.30863993838, 10636.734081647712, 10733.677989660322, 11275.943620281847, 11396.544138234378, 11451.59919402817, 12052.698682533006, 12204.877951220375, 8281.21328473794, 8308.584570771558, 8396.622152003296, 8440.198181172778, 8482.491050536988, 10909.236353455182, 11407.657770266906, 11427.428628568372, 11706.122040847142, 11919.94040029829], "value": 9257.662217080457, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.2816479101286, 1447.1317209674194, 1514.0158896717135, 1601.4234875444838, 1832.0656465202742, 8516.190961858621, 8921.464712117278, 8949.68676096336, 9165.404248723828, 9172.0, 8966.28269738421, 9057.864132038025, 9058.094190580938, 9066.048064953184, 9074.500902450363, 2379.5121999990006, 2412.1798588479915, 2414.420539070628, 2416.2751174647624, 2416.4442178299, 2368.561816064025, 2386.677798497198, 2399.8200134989947, 2414.420539070628, 2424.35754525051, 2323.61660326046, 2332.5801355755957, 2336.8597884127007, 2376.536575367804, 2378.904843806254, 2289.53064621753, 2305.5504176685554, 2316.073570571768, 2317.3395582259145, 2365.0421579260255, 2290.1526435218907, 2309.4688221708934, 2324.4305145239523, 2331.5453486570127, 2332.8017118544776, 8924.776880577972, 9016.729498115066, 9043.819123617557, 9083.273338132933, 9121.0, 5080.187170052811, 5308.6549374290935, 5313.681179129226, 5315.840524784263, 5316.12283973144, 3546.6453354664586, 3552.6802587766965, 3598.4062629666073, 3616.674499295076, 3634.8727794527063, 1154.8094564396865, 1176.5587904535798, 1195.378403230324, 1197.3175290084569, 1224.6081253998634, 12556.0, 12808.359582020861, 12840.1011929165, 12855.035872309614, 12859.0, 4484.192845287879, 4615.0, 4789.856304310807, 4807.831725889577, 4811.807527698923, 10116.291859569907, 10120.240981926385, 10127.341722787995, 10134.189264858793, 10142.087212150942, 5340.038793017294, 5351.384590772033, 5351.785928562892, 5358.410574836765, 5362.195670649434, 4295.957040429618, 4793.352897358882, 4934.679245849008, 4961.90076198474, 5012.122878496249, 3822.808859557011, 3874.88818593867, 3973.384125460558, 4013.979930100331, 4020.557738648746, 1156.670348950545, 1191.3149938785234, 1197.8263151842934, 1202.1765090912752, 1208.1542919956066, 928.9489078100769, 984.6061575369766, 985.3693636072919, 997.65082221222, 998.7453199434098, 5877.735501902424, 5931.466168044896, 6038.33578306386, 6047.637141771508, 6077.453029227302, 453.2951260789504, 463.6453113368276, 466.0864705177822, 469.67123013890415, 473.6849994753519, 4088.5298190707854, 4163.063310755056, 4163.354680024601, 4179.561146079679, 4214.978925105416, 11262.099032077414, 11518.88481115195, 11554.133439992034, 11571.363575003455, 11576.24754390962, 10799.40603266828, 11498.655040348804, 11516.827247591293, 11538.0, 11547.0, 8261.463004904663, 8591.828163436696, 8596.957015214884, 8598.140185981352, 8603.913960860436, 5862.208601838868, 5983.34183239852, 6175.752969881154, 6181.258249010148, 6329.810105696836, 10254.128399086161, 10576.0, 10582.4708764563, 10700.571977120828, 10773.62292319765, 10957.56169753201, 10977.725556861142, 10992.120630349713, 11008.889911100787, 11026.44867756625, 7939.166387529457, 8002.9599852000365, 8229.341652667892, 8355.498670079814, 8356.707515236938, 8843.115688431106, 8900.5994730236, 8907.331950103639, 8912.064233255674, 8912.643494260416, 7907.406944479302, 7986.800329991796, 8050.83898322042, 8052.355811534946, 8214.219649133323, 7792.961035194788, 7879.621873878214, 7936.047674279123, 7988.480748751313, 8124.634391452517, 7179.497435179487, 7217.566945983258, 7268.527545709512, 7272.527285726623, 7335.339819416278, 5177.0, 5190.8183213587345, 5312.62811603184, 5335.306410166653, 5423.538999184956, 5160.948390516047, 5193.0, 5200.843974680765, 5231.0, 5318.095923692904], "value": 4659.065316160213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.1576060397529, 323.374270786029, 324.96587858274864, 327.6117800406517, 332.28227029616033, 5292.417834038252, 5314.92027619585, 5328.387235467918, 5331.760070796814, 5334.306540149785, 4995.900081998367, 5086.321513900413, 5272.841814745554, 5275.446078161795, 5290.867728306785, 4123.515534407611, 4141.254574176647, 4145.709800313979, 4150.27763478157, 4156.192056455437, 4126.319157339036, 4152.294110001292, 4175.519815221258, 4181.790910454465, 4183.309753890604, 3877.3602355611433, 4041.3735870939904, 4100.487439070116, 4133.483314585676, 4140.979295103521, 4119.176164767028, 4121.876343709679, 4125.514814666718, 4134.607212314825, 4137.213929353413, 3652.7625704329134, 3665.8533658653623, 3669.6880765134997, 3686.225892562565, 3688.3545379558736, 5081.891015228675, 5107.872303192413, 5136.64043516954, 5152.330197074394, 5167.9224811627855, 4700.670953033289, 4715.834945776881, 4721.386219791405, 4729.574338309534, 4738.099761045425, 5463.486796073811, 5464.371597266326, 5473.233747275384, 5479.643753186128, 5496.148097044965, 3859.749116307431, 3977.6618987386105, 4014.4781178446906, 4024.4969378827645, 4026.5973402659793, 5653.671387223999, 5667.999999999959, 5669.404712505168, 5673.744681489343, 5677.659340439587, 4081.5306239782212, 4083.060895993938, 4085.937656209407, 4095.344744840811, 4095.488063992001, 4038.757674539478, 4113.341865301538, 4129.442525259112, 4129.587041295907, 4140.627343539036, 4680.315086568832, 4686.695364801345, 4692.225782745877, 4693.366395536559, 4698.906021879611, 5488.643238189505, 5504.9449505504435, 5508.476694713996, 5515.7242137892945, 5515.951969125853, 1409.4150927365233, 1425.0238586568028, 1431.8568143185807, 1436.7629341158593, 1448.5799118255798, 907.9591418386188, 922.1700469577299, 928.8838895138108, 935.3546053223254, 935.6257497001254, 4794.2808578713475, 4802.591779698695, 4803.438882363246, 4808.326834243208, 4812.35033270511, 5034.697918124924, 5048.192289233779, 5054.898902021939, 5072.289879416885, 5105.361829771278, 4615.799892028097, 4725.3384526166365, 4783.282507623815, 4802.519748025239, 4812.398450193726, 2625.829321094123, 2666.879990400437, 2675.705672376037, 2685.181019788944, 2694.272546412458, 5130.538251557377, 5137.201979307212, 5150.845474635767, 5178.378594568676, 5181.3005244291535, 4949.7772600233075, 5091.3381260436645, 5099.566536844335, 5128.8461346159675, 5137.563307118937, 5151.845444636667, 5186.0, 5203.271541984125, 5208.140656791663, 5217.426083130853, 5059.822906198413, 5099.898002039864, 5101.026499163434, 5116.820911268088, 5134.871628209325, 4670.64970127235, 4979.800807967786, 4995.476379704225, 5028.773705183203, 5060.974695126501, 5118.257852611425, 5131.974340128425, 5137.3817247568395, 5198.636095473432, 5217.2434996925995, 3739.9813000934823, 3766.246750649826, 3773.094457330276, 3773.3208022556205, 3778.9055273617278, 5391.595630327663, 5421.83734487966, 5642.322921249476, 5659.660420374791, 5678.949394361891, 5514.61397702157, 5518.365387980273, 5529.281193444826, 5536.612437129363, 5541.362743284412, 5301.602379821452, 5528.0, 5534.059209934401, 5541.868844103069, 5548.9722551386985, 5524.14375771756, 5524.75138618755, 5535.536686174613, 5535.861603459784, 5545.168224766156, 5613.607047506635, 5625.987322282029, 5626.38109807929, 5629.746661400329, 5636.830895073154, 5187.870303242449, 5450.700211488248, 5475.342958844964, 5484.14995675655, 5484.808031718871], "value": 4079.508390322255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.4933293359319, 786.0606575142704, 808.5957021489255, 840.0885039731891, 854.991110489622, 16086.0, 16532.42136525222, 16634.33462661492, 16643.167841607916, 16677.24952377143, 16553.83446165538, 16792.244349004293, 16792.412265570707, 16812.41156559521, 16831.242594083265, 787.7557957033338, 806.2260230179036, 806.9919300807005, 807.6728924785472, 808.1635507249997, 652.1326635574675, 758.4652819762055, 796.7291121018852, 798.0144521515923, 803.7387848949086, 861.4056301152216, 862.7886167888876, 865.0614083719172, 866.8309679612478, 868.1969178509885, 863.1239292118505, 864.1013346120028, 864.3171894203572, 864.576357584782, 864.8054187807725, 498.88525639102846, 590.1236663554618, 609.2080295615726, 615.3938370704852, 619.1053927075378, 16034.35862565496, 16054.678906421806, 16060.919695401564, 16073.91963040189, 16074.5981350466, 13180.406881690347, 13387.531436399679, 13548.322583870768, 13770.24263665498, 14000.369983350673, 12509.687257818536, 12807.359632018455, 13027.348632568428, 13074.411651475612, 13216.801747973788, 4545.818167273305, 4558.202314594932, 4606.838760643362, 4642.141203877293, 4668.509806470306, 79960.60019699922, 80347.59826200831, 80792.0, 81447.0, 81564.59217703933, 11324.830127548094, 11782.646520604396, 11855.741879015755, 12051.0, 12256.81614775779, 22337.0, 22485.32544023649, 22580.0, 22698.773012269667, 22754.544909102056, 8888.955555222183, 9111.635534578676, 9164.8625270621, 9240.122188392092, 9415.423624730005, 4872.731999739977, 4897.7795999180125, 5050.217216331474, 5056.9494305057215, 5139.948600513947, 3694.113412780913, 3834.559025712023, 3835.7890316032885, 3838.27072856162, 3838.3858582626644, 657.2901266632026, 658.111549408295, 662.4038365471115, 677.2786981864299, 684.9931500685029, 647.5726020826327, 666.5101150654265, 738.0, 756.1455555222673, 770.6609091999502, 9904.405735655884, 10060.051132647606, 10325.122364599094, 10408.427536485415, 10655.176378259768, 724.8912663100577, 748.5845355827568, 749.4978364495801, 751.7970148059993, 760.277736150659, 4022.557518672944, 4815.0, 5276.234945932818, 5434.673919564839, 5451.482109199699, 111816.0, 120001.39999300122, 120004.0, 125750.0, 130316.3484182592, 102653.48673256735, 103864.48067759613, 104378.0, 105664.18846963337, 105823.0, 74839.25160748432, 75404.62297688477, 76812.23187768162, 90851.0, 91268.08731912728, 10089.596416143566, 10138.0, 10392.636257730943, 10515.894841051491, 10591.311564748266, 49285.0, 51895.481045189066, 52375.47624523706, 52585.21122183094, 53590.1961470586, 69506.30493694998, 70048.64975675293, 71807.64096179485, 73414.0, 73946.63026684833, 13324.200547967153, 13425.530106446227, 13505.32473376347, 13515.121517101357, 13787.586372408843, 14629.707405852036, 15045.548633541011, 15246.31391587359, 15352.385904563693, 16175.43385981485, 13604.115732477358, 13783.0, 13831.654208644864, 14274.218941328778, 14452.494162704255, 9805.803883922139, 13987.23070231147, 14005.159690418608, 14064.226467544384, 14844.0, 14129.364178612193, 14278.571642850731, 14311.642208944444, 14383.0, 14777.187254701095, 11152.832707509231, 11552.768944621228, 11677.824832627688, 12287.0, 12608.369581520703, 12312.876871231172, 12384.380780960735, 12457.875421246034, 12488.0, 12586.748265034465], "value": 7869.823836610309, "unit": "score"}], "name": "dromaeo_css", "value": 7272.289331652552, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 111.0, 110.0, 110.0, 112.0, 113.0, 111.0, 111.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 119.0, 192.0, 123.0, 119.0, 144.0, 122.0, 157.0, 118.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 163.0, 162.0, 160.0, 159.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 111.0, 75.0, 74.0, 75.0, 75.0, 100.0, 74.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 117.0, 83.0, 84.0, 84.0, 126.0, 83.0, 85.0, 138.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 63.0, 61.0, 61.0, 61.0, 64.0, 60.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 75.0, 75.0, 86.0, 78.0, 82.0, 76.0, 78.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 127.0, 130.0, 125.0, 118.0, 120.0, 125.0, 128.0, 206.0, 130.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 159.0, 156.0, 155.0, 153.0, 154.0, 156.0, 153.0, 158.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 62.0, 61.0, 62.0, 62.0, 64.0, 62.0, 61.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1487.0, "alertThreshold": 2.0}]} 13:26:07 INFO - Return code: 0 13:26:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:26:07 INFO - # TBPL SUCCESS # 13:26:07 INFO - Running post-action listener: _resource_record_post_action 13:26:07 INFO - [mozharness: 2016-07-22 20:26:07.642504Z] Finished run-tests step (success) 13:26:07 INFO - Running post-run listener: _resource_record_post_run 13:26:07 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 558313472; Read time: 12; Write time: 621048 13:26:07 INFO - TinderboxPrint: CPU usage
13.1% 13:26:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:26:07 INFO - TinderboxPrint: I/O write bytes / time
558,313,472 / 621,048 13:26:07 INFO - TinderboxPrint: CPU idle
8,266.7 (86.9%) 13:26:07 INFO - TinderboxPrint: CPU user
1,199.1 (12.6%) 13:26:07 INFO - TinderboxPrint: Swap in / out
0 / 0 13:26:07 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 267399168; Read time: 0; Write time: 356020 13:26:07 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 290721792; Read time: 0; Write time: 265004 13:26:08 INFO - Running post-run listener: _upload_blobber_files 13:26:08 INFO - Blob upload gear active. 13:26:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:26:08 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 13:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:26:08 INFO - (blobuploader) - INFO - Open directory for files ... 13:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:09 INFO - (blobuploader) - INFO - Done attempting. 13:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:26:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:25 INFO - (blobuploader) - INFO - Done attempting. 13:26:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:26:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:26:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:26 INFO - (blobuploader) - INFO - Done attempting. 13:26:26 INFO - (blobuploader) - INFO - Iteration through files over. 13:26:26 INFO - Return code: 0 13:26:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:26:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:26:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/695dd8968873aa35f099eeaf29ff8ddf4dc911f23a6003ffbb7074a4597491459a4a23aa060979d85cf58f5a9afb239fb0b44e59f029269bdfa7bf2b0f14ad2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9371a9d8f63421306c8212c5341feea46f85988a1a337562cc00ca297616280b84f22ed7ef4a77d93b2d1ebf688f0c75abc650929ca45b2b814673d637bb0daa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbabeada006a827f8dc222d7375c8c26508e878fddd0561a6604952576bb04837b4af99a0ccee4b47c96f1103c05b90f0d5de83d97850c37b0ba40519fbd476f"} 13:26:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:26:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:26:26 INFO - Contents: 13:26:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/695dd8968873aa35f099eeaf29ff8ddf4dc911f23a6003ffbb7074a4597491459a4a23aa060979d85cf58f5a9afb239fb0b44e59f029269bdfa7bf2b0f14ad2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9371a9d8f63421306c8212c5341feea46f85988a1a337562cc00ca297616280b84f22ed7ef4a77d93b2d1ebf688f0c75abc650929ca45b2b814673d637bb0daa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbabeada006a827f8dc222d7375c8c26508e878fddd0561a6604952576bb04837b4af99a0ccee4b47c96f1103c05b90f0d5de83d97850c37b0ba40519fbd476f"} 13:26:26 INFO - Running post-run listener: copy_logs_to_upload_dir 13:26:26 INFO - Copying logs to upload dir... 13:26:26 INFO - mkdir: /builds/slave/test/build/upload/logs 13:26:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.573554 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-22 13:26:25.777539) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 13:26:25.780748) ========= 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=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/695dd8968873aa35f099eeaf29ff8ddf4dc911f23a6003ffbb7074a4597491459a4a23aa060979d85cf58f5a9afb239fb0b44e59f029269bdfa7bf2b0f14ad2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9371a9d8f63421306c8212c5341feea46f85988a1a337562cc00ca297616280b84f22ed7ef4a77d93b2d1ebf688f0c75abc650929ca45b2b814673d637bb0daa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbabeada006a827f8dc222d7375c8c26508e878fddd0561a6604952576bb04837b4af99a0ccee4b47c96f1103c05b90f0d5de83d97850c37b0ba40519fbd476f"} build_url:https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/BbRSFxU7RfKrg4iXB4Rgyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/695dd8968873aa35f099eeaf29ff8ddf4dc911f23a6003ffbb7074a4597491459a4a23aa060979d85cf58f5a9afb239fb0b44e59f029269bdfa7bf2b0f14ad2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9371a9d8f63421306c8212c5341feea46f85988a1a337562cc00ca297616280b84f22ed7ef4a77d93b2d1ebf688f0c75abc650929ca45b2b814673d637bb0daa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbabeada006a827f8dc222d7375c8c26508e878fddd0561a6604952576bb04837b4af99a0ccee4b47c96f1103c05b90f0d5de83d97850c37b0ba40519fbd476f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 13:26:25.808171) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:26:25.808490) ========= 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=232498196343f89f466a4a9a000001c8-1469217941.805801-621938265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:26:25.890265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 13:26:25.890581) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 13:26:25.890846) ========= ========= Total master_lag: 0.19 =========