builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1468624860.56 results: success (0) buildid: 20160715150452 builduid: c7e79db236ed4e77839fcee238d932b6 revision: fbf164ef9e70c56d898f1c56d316cf4b88f2efac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.564799) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.565212) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.565491) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.603603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.603980) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 16:21:00-- 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% 32.7M=0s 2016-07-15 16:21:01 (32.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151180 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.769164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.769494) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016743 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.819104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:00.819471) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fbf164ef9e70c56d898f1c56d316cf4b88f2efac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev fbf164ef9e70c56d898f1c56d316cf4b88f2efac --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=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 16:21:01,225 truncating revision to first 12 chars 2016-07-15 16:21:01,225 Setting DEBUG logging. 2016-07-15 16:21:01,225 attempt 1/10 2016-07-15 16:21:01,225 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fbf164ef9e70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 16:21:01,561 unpacking tar archive at: autoland-fbf164ef9e70/testing/mozharness/ program finished with exit code 0 elapsedTime=0.678611 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:01.513404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:01.513972) ========= script_repo_revision: fbf164ef9e70c56d898f1c56d316cf4b88f2efac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:01.514639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:01.515100) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 16:21:01.527153) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-15 16:21:01.527532) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:21:02 INFO - MultiFileLogger online at 20160715 16:21:02 in /builds/slave/test 16:21:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 16:21:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:21:02 INFO - {'append_to_log': False, 16:21:02 INFO - 'base_work_dir': '/builds/slave/test', 16:21:02 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 16:21:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:21:02 INFO - 'branch': 'Autoland-Non-PGO', 16:21:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:21:02 INFO - 'config_files': ('talos/linux_config.py',), 16:21:02 INFO - 'default_actions': ('clobber', 16:21:02 INFO - 'read-buildbot-config', 16:21:02 INFO - 'download-and-extract', 16:21:02 INFO - 'populate-webroot', 16:21:02 INFO - 'create-virtualenv', 16:21:02 INFO - 'install', 16:21:02 INFO - 'run-tests'), 16:21:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:21:02 INFO - 'download_minidump_stackwalk': True, 16:21:02 INFO - 'download_symbols': 'ondemand', 16:21:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:21:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:21:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:21:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:21:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:21:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:21:02 INFO - 'installer_path': 'installer.exe', 16:21:02 INFO - 'log_level': 'info', 16:21:02 INFO - 'log_name': 'talos', 16:21:02 INFO - 'log_to_console': True, 16:21:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:21:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:21:02 INFO - 'opt_config_files': (), 16:21:02 INFO - 'pip_index': False, 16:21:02 INFO - 'sps_profile': False, 16:21:02 INFO - 'sps_profile_interval': 0, 16:21:02 INFO - 'suite': 'dromaeojs', 16:21:02 INFO - 'system_bits': '32', 16:21:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:21:02 INFO - 'title': 'talos-linux64-ix-068', 16:21:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:21:02 INFO - 'use_talos_json': True, 16:21:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:21:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:21:02 INFO - 'work_dir': 'build'} 16:21:02 INFO - ##### 16:21:02 INFO - ##### Running clobber step. 16:21:02 INFO - ##### 16:21:02 INFO - Running pre-action listener: _resource_record_pre_action 16:21:02 INFO - Running main action method: clobber 16:21:02 INFO - rmtree: /builds/slave/test/build 16:21:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:21:02 INFO - Running post-action listener: _resource_record_post_action 16:21:02 INFO - ##### 16:21:02 INFO - ##### Finished clobber step (success) 16:21:02 INFO - ##### 16:21:02 INFO - ##### 16:21:02 INFO - ##### Running read-buildbot-config step. 16:21:02 INFO - ##### 16:21:02 INFO - Running pre-action listener: _resource_record_pre_action 16:21:02 INFO - Running main action method: read_buildbot_config 16:21:02 INFO - Using buildbot properties: 16:21:02 INFO - { 16:21:02 INFO - "project": "", 16:21:02 INFO - "product": "firefox", 16:21:02 INFO - "script_repo_revision": "production", 16:21:02 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:21:02 INFO - "repository": "", 16:21:02 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:21:02 INFO - "buildid": "20160715150452", 16:21:02 INFO - "pgo_build": "False", 16:21:02 INFO - "basedir": "/builds/slave/test", 16:21:02 INFO - "buildnumber": 53, 16:21:02 INFO - "slavename": "talos-linux64-ix-068", 16:21:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:21:02 INFO - "platform": "ubuntu64_hw", 16:21:02 INFO - "scheduler": "tests-autoland-linux64-talos", 16:21:02 INFO - "branch": "autoland", 16:21:02 INFO - "repo_path": "integration/autoland", 16:21:02 INFO - "revision": "fbf164ef9e70c56d898f1c56d316cf4b88f2efac", 16:21:02 INFO - "stage_platform": "linux64", 16:21:02 INFO - "builduid": "c7e79db236ed4e77839fcee238d932b6", 16:21:02 INFO - "slavebuilddir": "test" 16:21:02 INFO - } 16:21:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:21:02 INFO - Running post-action listener: _resource_record_post_action 16:21:02 INFO - ##### 16:21:02 INFO - ##### Finished read-buildbot-config step (success) 16:21:02 INFO - ##### 16:21:02 INFO - ##### 16:21:02 INFO - ##### Running download-and-extract step. 16:21:02 INFO - ##### 16:21:02 INFO - Running pre-action listener: _resource_record_pre_action 16:21:02 INFO - Running main action method: download_and_extract 16:21:02 INFO - mkdir: /builds/slave/test/build/tests 16:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:02 INFO - https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:08 INFO - Downloaded 1361 bytes. 16:21:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:21:08 INFO - Using the following test package requirements: 16:21:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:21:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:21:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:21:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'jsshell-linux-x86_64.zip'], 16:21:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:21:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:21:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:21:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:21:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:21:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:21:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:21:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:08 INFO - https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:21:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:21:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:21:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:10 INFO - Downloaded 22757330 bytes. 16:21:10 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'] 16:21:10 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 16:21:11 INFO - Return code: 0 16:21:11 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 16:21:11 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')]} 16:21:11 INFO - https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:21:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:21:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:21:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:17 INFO - Downloaded 22757330 bytes. 16:21:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:21:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:21:18 INFO - Return code: 0 16:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:18 INFO - https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:21:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:21:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:21:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:20 INFO - Downloaded 14403317 bytes. 16:21:20 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'] 16:21:20 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 16:21:20 INFO - Return code: 0 16:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:20 INFO - https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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 16:21:22 INFO - Downloaded 57055657 bytes. 16:21:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:21:22 INFO - mkdir: /builds/slave/test/properties 16:21:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:21:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:21:22 INFO - Contents: 16:21:22 INFO - build_url:https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:21:23 INFO - Running post-action listener: _resource_record_post_action 16:21:23 INFO - Running post-action listener: set_extra_try_arguments 16:21:23 INFO - ##### 16:21:23 INFO - ##### Finished download-and-extract step (success) 16:21:23 INFO - ##### 16:21:23 INFO - ##### 16:21:23 INFO - ##### Running populate-webroot step. 16:21:23 INFO - ##### 16:21:23 INFO - Running pre-action listener: _resource_record_pre_action 16:21:23 INFO - Running main action method: populate_webroot 16:21:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:21:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:21:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:21:23 INFO - '--tppagecycles', 16:21:23 INFO - '7'], 16:21:23 INFO - 'tests': ['tsvgm']}}, 16:21:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:21:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:21:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:21:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:21:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['damp', 'tps']}, 16:21:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'tests': ['damp', 'tps']}, 16:21:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['dromaeo_dom']}, 16:21:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:21:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['basic_compositor_video']}, 16:21:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:21:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'other-e10s': {'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['a11yr', 16:21:23 INFO - 'ts_paint', 16:21:23 INFO - 'tpaint', 16:21:23 INFO - 'sessionrestore', 16:21:23 INFO - 'sessionrestore_no_auto_restore', 16:21:23 INFO - 'tabpaint']}, 16:21:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['tsvgx', 16:21:23 INFO - 'tsvgr_opacity', 16:21:23 INFO - 'tart', 16:21:23 INFO - 'tscrollx', 16:21:23 INFO - 'cart']}, 16:21:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:21:23 INFO - 'tsvgr_opacity', 16:21:23 INFO - 'tart', 16:21:23 INFO - 'tscrollx', 16:21:23 INFO - 'cart']}, 16:21:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'talos_options': ['--disable-e10s'], 16:21:23 INFO - 'tests': ['tp5o']}, 16:21:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'tests': ['tp5o']}, 16:21:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'talos_options': ['--disable-e10s', 16:21:23 INFO - '--xperf_path', 16:21:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:23 INFO - 'tests': ['tp5n']}, 16:21:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:23 INFO - 'talos_options': ['--xperf_path', 16:21:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:23 INFO - 'tests': ['tp5n']}}, 16:21:23 INFO - 'talos.zip': {'path': '', 16:21:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:21:23 INFO - Running post-action listener: _resource_record_post_action 16:21:23 INFO - ##### 16:21:23 INFO - ##### Finished populate-webroot step (success) 16:21:23 INFO - ##### 16:21:23 INFO - ##### 16:21:23 INFO - ##### Running create-virtualenv step. 16:21:23 INFO - ##### 16:21:23 INFO - Running pre-action listener: _resource_record_pre_action 16:21:23 INFO - Running main action method: create_virtualenv 16:21:23 INFO - Creating virtualenv /builds/slave/test/build/venv 16:21:23 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 16:21:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:21:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:21:23 INFO - Using real prefix '/usr' 16:21:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:21:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:21:25 INFO - Installing pip.................done. 16:21:25 INFO - Return code: 0 16:21:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:21:25 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')]} 16:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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')]} 16:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:25 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 16:21:25 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 16:21:25 INFO - Using env: {'DISPLAY': ':0', 16:21:25 INFO - 'HOME': '/home/cltbld', 16:21:25 INFO - 'LANG': 'en_US.UTF-8', 16:21:25 INFO - 'LANGUAGE': 'en_US:en', 16:21:25 INFO - 'LOGNAME': 'cltbld', 16:21:25 INFO - 'MAIL': '/var/mail/cltbld', 16:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:25 INFO - 'MOZ_NO_REMOTE': '1', 16:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:25 INFO - 'NO_EM_RESTART': '1', 16:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:25 INFO - 'PWD': '/builds/slave/test', 16:21:25 INFO - 'SHELL': '/bin/bash', 16:21:25 INFO - 'SHLVL': '1', 16:21:25 INFO - 'TERM': 'linux', 16:21:25 INFO - 'TMOUT': '86400', 16:21:25 INFO - 'USER': 'cltbld', 16:21:25 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:25 INFO - '_': '/tools/buildbot/bin/python'} 16:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:25 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 16:21:25 INFO - Cleaning up... 16:21:25 INFO - Return code: 0 16:21:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:21:25 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')]} 16:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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')]} 16:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:25 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 16:21:25 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 16:21:25 INFO - Using env: {'DISPLAY': ':0', 16:21:25 INFO - 'HOME': '/home/cltbld', 16:21:25 INFO - 'LANG': 'en_US.UTF-8', 16:21:25 INFO - 'LANGUAGE': 'en_US:en', 16:21:25 INFO - 'LOGNAME': 'cltbld', 16:21:25 INFO - 'MAIL': '/var/mail/cltbld', 16:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:25 INFO - 'MOZ_NO_REMOTE': '1', 16:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:25 INFO - 'NO_EM_RESTART': '1', 16:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:25 INFO - 'PWD': '/builds/slave/test', 16:21:25 INFO - 'SHELL': '/bin/bash', 16:21:25 INFO - 'SHLVL': '1', 16:21:25 INFO - 'TERM': 'linux', 16:21:25 INFO - 'TMOUT': '86400', 16:21:25 INFO - 'USER': 'cltbld', 16:21:25 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:25 INFO - '_': '/tools/buildbot/bin/python'} 16:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:26 INFO - Downloading/unpacking psutil>=3.1.1 16:21:26 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 16:21:26 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 16:21:26 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 16:21:26 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 16:21:26 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 16:21:26 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 16:21:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:28 INFO - Installing collected packages: psutil 16:21:28 INFO - Running setup.py install for psutil 16:21:28 INFO - building 'psutil._psutil_linux' extension 16:21:28 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 16:21:28 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 16:21:28 INFO - building 'psutil._psutil_posix' extension 16:21:28 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 16:21:28 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 16:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:28 INFO - Successfully installed psutil 16:21:28 INFO - Cleaning up... 16:21:28 INFO - Return code: 0 16:21:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:21:28 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')]} 16:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:28 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')]} 16:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:28 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:28 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 16:21:28 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 16:21:28 INFO - Using env: {'DISPLAY': ':0', 16:21:28 INFO - 'HOME': '/home/cltbld', 16:21:28 INFO - 'LANG': 'en_US.UTF-8', 16:21:28 INFO - 'LANGUAGE': 'en_US:en', 16:21:28 INFO - 'LOGNAME': 'cltbld', 16:21:28 INFO - 'MAIL': '/var/mail/cltbld', 16:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:28 INFO - 'MOZ_NO_REMOTE': '1', 16:21:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:28 INFO - 'NO_EM_RESTART': '1', 16:21:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:28 INFO - 'PWD': '/builds/slave/test', 16:21:28 INFO - 'SHELL': '/bin/bash', 16:21:28 INFO - 'SHLVL': '1', 16:21:28 INFO - 'TERM': 'linux', 16:21:28 INFO - 'TMOUT': '86400', 16:21:28 INFO - 'USER': 'cltbld', 16:21:28 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:28 INFO - '_': '/tools/buildbot/bin/python'} 16:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:21:28 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 16:21:28 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 16:21:28 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 16:21:28 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 16:21:28 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 16:21:28 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 16:21:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:21:30 INFO - Installing collected packages: mozsystemmonitor 16:21:30 INFO - Running setup.py install for mozsystemmonitor 16:21:31 INFO - Successfully installed mozsystemmonitor 16:21:31 INFO - Cleaning up... 16:21:31 INFO - Return code: 0 16:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:21:31 INFO - Using env: {'DISPLAY': ':0', 16:21:31 INFO - 'HOME': '/home/cltbld', 16:21:31 INFO - 'LANG': 'en_US.UTF-8', 16:21:31 INFO - 'LANGUAGE': 'en_US:en', 16:21:31 INFO - 'LOGNAME': 'cltbld', 16:21:31 INFO - 'MAIL': '/var/mail/cltbld', 16:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:31 INFO - 'MOZ_NO_REMOTE': '1', 16:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:31 INFO - 'NO_EM_RESTART': '1', 16:21:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:31 INFO - 'PWD': '/builds/slave/test', 16:21:31 INFO - 'SHELL': '/bin/bash', 16:21:31 INFO - 'SHLVL': '1', 16:21:31 INFO - 'TERM': 'linux', 16:21:31 INFO - 'TMOUT': '86400', 16:21:31 INFO - 'USER': 'cltbld', 16:21:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:31 INFO - '_': '/tools/buildbot/bin/python'} 16:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:31 INFO - Downloading/unpacking jsonschema==2.5.1 16:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:21:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:21:33 INFO - warning: no files found matching '*.txt' 16:21:33 INFO - no previously-included directories found matching 'build' 16:21:33 INFO - no previously-included directories found matching 'dist' 16:21:33 INFO - no previously-included directories found matching '.git*' 16:21:33 INFO - Installing collected packages: jsonschema, functools32 16:21:33 INFO - Running setup.py install for functools32 16:21:33 INFO - warning: no files found matching '*.txt' 16:21:33 INFO - no previously-included directories found matching 'build' 16:21:33 INFO - no previously-included directories found matching 'dist' 16:21:33 INFO - no previously-included directories found matching '.git*' 16:21:33 INFO - Successfully installed jsonschema functools32 16:21:33 INFO - Cleaning up... 16:21:33 INFO - Return code: 0 16:21:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:33 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:33 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 16:21:33 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 16:21:33 INFO - Using env: {'DISPLAY': ':0', 16:21:33 INFO - 'HOME': '/home/cltbld', 16:21:33 INFO - 'LANG': 'en_US.UTF-8', 16:21:33 INFO - 'LANGUAGE': 'en_US:en', 16:21:33 INFO - 'LOGNAME': 'cltbld', 16:21:33 INFO - 'MAIL': '/var/mail/cltbld', 16:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:33 INFO - 'MOZ_NO_REMOTE': '1', 16:21:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:33 INFO - 'NO_EM_RESTART': '1', 16:21:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:33 INFO - 'PWD': '/builds/slave/test', 16:21:33 INFO - 'SHELL': '/bin/bash', 16:21:33 INFO - 'SHLVL': '1', 16:21:33 INFO - 'TERM': 'linux', 16:21:33 INFO - 'TMOUT': '86400', 16:21:33 INFO - 'USER': 'cltbld', 16:21:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:33 INFO - '_': '/tools/buildbot/bin/python'} 16:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:33 INFO - Downloading/unpacking blobuploader==1.2.4 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:21:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:21:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:21:36 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 16:21:36 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 16:21:36 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 16:21:36 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 16:21:36 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 16:21:36 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 16:21:36 INFO - Downloading docopt-0.6.1.tar.gz 16:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:21:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:21:36 INFO - Running setup.py install for blobuploader 16:21:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:21:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:21:36 INFO - Running setup.py install for requests 16:21:37 INFO - Running setup.py install for docopt 16:21:37 INFO - Successfully installed blobuploader requests docopt 16:21:37 INFO - Cleaning up... 16:21:37 INFO - Return code: 0 16:21:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:37 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:37 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 16:21:37 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 16:21:37 INFO - Using env: {'DISPLAY': ':0', 16:21:37 INFO - 'HOME': '/home/cltbld', 16:21:37 INFO - 'LANG': 'en_US.UTF-8', 16:21:37 INFO - 'LANGUAGE': 'en_US:en', 16:21:37 INFO - 'LOGNAME': 'cltbld', 16:21:37 INFO - 'MAIL': '/var/mail/cltbld', 16:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:37 INFO - 'MOZ_NO_REMOTE': '1', 16:21:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:37 INFO - 'NO_EM_RESTART': '1', 16:21:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:37 INFO - 'PWD': '/builds/slave/test', 16:21:37 INFO - 'SHELL': '/bin/bash', 16:21:37 INFO - 'SHLVL': '1', 16:21:37 INFO - 'TERM': 'linux', 16:21:37 INFO - 'TMOUT': '86400', 16:21:37 INFO - 'USER': 'cltbld', 16:21:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:37 INFO - '_': '/tools/buildbot/bin/python'} 16:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:37 INFO - Running setup.py (path:/tmp/pip-ys8ydN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:37 INFO - Running setup.py (path:/tmp/pip-skWzSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:37 INFO - Running setup.py (path:/tmp/pip-9VOwwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:37 INFO - Running setup.py (path:/tmp/pip-guFhdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:37 INFO - Running setup.py (path:/tmp/pip-eMZrlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:37 INFO - Running setup.py (path:/tmp/pip-YwCJYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:37 INFO - Running setup.py (path:/tmp/pip-0Vogn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:37 INFO - Running setup.py (path:/tmp/pip-Imy3_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:38 INFO - Running setup.py (path:/tmp/pip-m4JCb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:38 INFO - Running setup.py (path:/tmp/pip-oGkw8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:38 INFO - Running setup.py (path:/tmp/pip-PykfGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:38 INFO - Running setup.py (path:/tmp/pip-in_5kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:38 INFO - Running setup.py (path:/tmp/pip-aVQEFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:38 INFO - Running setup.py (path:/tmp/pip-atLgGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:38 INFO - Running setup.py (path:/tmp/pip-O3L9em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:38 INFO - Running setup.py (path:/tmp/pip-Wgc8de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:38 INFO - Running setup.py (path:/tmp/pip-C75UUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:21:38 INFO - Running setup.py install for manifestparser 16:21:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozcrash 16:21:38 INFO - Running setup.py install for mozdebug 16:21:38 INFO - Running setup.py install for mozdevice 16:21:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozfile 16:21:39 INFO - Running setup.py install for mozhttpd 16:21:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozinfo 16:21:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozInstall 16:21:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozleak 16:21:39 INFO - Running setup.py install for mozlog 16:21:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for moznetwork 16:21:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozprocess 16:21:39 INFO - Running setup.py install for mozprofile 16:21:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:21:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:21:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:21:40 INFO - Running setup.py install for mozrunner 16:21:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:21:40 INFO - Running setup.py install for mozscreenshot 16:21:40 INFO - Running setup.py install for moztest 16:21:40 INFO - Running setup.py install for mozversion 16:21:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:21:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:21:40 INFO - Cleaning up... 16:21:40 INFO - Return code: 0 16:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:40 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:40 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 16:21:40 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 16:21:40 INFO - Using env: {'DISPLAY': ':0', 16:21:40 INFO - 'HOME': '/home/cltbld', 16:21:40 INFO - 'LANG': 'en_US.UTF-8', 16:21:40 INFO - 'LANGUAGE': 'en_US:en', 16:21:40 INFO - 'LOGNAME': 'cltbld', 16:21:40 INFO - 'MAIL': '/var/mail/cltbld', 16:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:40 INFO - 'MOZ_NO_REMOTE': '1', 16:21:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:40 INFO - 'NO_EM_RESTART': '1', 16:21:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:40 INFO - 'PWD': '/builds/slave/test', 16:21:40 INFO - 'SHELL': '/bin/bash', 16:21:40 INFO - 'SHLVL': '1', 16:21:40 INFO - 'TERM': 'linux', 16:21:40 INFO - 'TMOUT': '86400', 16:21:40 INFO - 'USER': 'cltbld', 16:21:40 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:40 INFO - '_': '/tools/buildbot/bin/python'} 16:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:40 INFO - Running setup.py (path:/tmp/pip-oqWdEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:40 INFO - Running setup.py (path:/tmp/pip-O7GqZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:40 INFO - Running setup.py (path:/tmp/pip-Dtux7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:40 INFO - Running setup.py (path:/tmp/pip-gnJUuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:41 INFO - Running setup.py (path:/tmp/pip-JDfeUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:41 INFO - Running setup.py (path:/tmp/pip-99ZLlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:41 INFO - Running setup.py (path:/tmp/pip-8YnNa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:41 INFO - Running setup.py (path:/tmp/pip-dFpVkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:41 INFO - Running setup.py (path:/tmp/pip-ugfF4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:41 INFO - Running setup.py (path:/tmp/pip-_JcsNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:41 INFO - Running setup.py (path:/tmp/pip-RiU8mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:41 INFO - Running setup.py (path:/tmp/pip-m8oFlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:41 INFO - Running setup.py (path:/tmp/pip-s0Leyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:41 INFO - Running setup.py (path:/tmp/pip-SBIgaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:41 INFO - Running setup.py (path:/tmp/pip-dqpHCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:41 INFO - Running setup.py (path:/tmp/pip-u4RK9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:41 INFO - Running setup.py (path:/tmp/pip-eWlrow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:43 INFO - Downloading blessings-1.6.tar.gz 16:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:21:43 INFO - Installing collected packages: blessings 16:21:43 INFO - Running setup.py install for blessings 16:21:44 INFO - Successfully installed blessings 16:21:44 INFO - Cleaning up... 16:21:44 INFO - Return code: 0 16:21:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:44 INFO - Reading from file tmpfile_stdout 16:21:44 INFO - Current package versions: 16:21:44 INFO - argparse == 1.2.1 16:21:44 INFO - blessings == 1.6 16:21:44 INFO - blobuploader == 1.2.4 16:21:44 INFO - docopt == 0.6.1 16:21:44 INFO - functools32 == 3.2.3-2 16:21:44 INFO - jsonschema == 2.5.1 16:21:44 INFO - manifestparser == 1.1 16:21:44 INFO - mozInstall == 1.12 16:21:44 INFO - mozcrash == 0.17 16:21:44 INFO - mozdebug == 0.1 16:21:44 INFO - mozdevice == 0.48 16:21:44 INFO - mozfile == 1.2 16:21:44 INFO - mozhttpd == 0.7 16:21:44 INFO - mozinfo == 0.9 16:21:44 INFO - mozleak == 0.1 16:21:44 INFO - mozlog == 3.2 16:21:44 INFO - moznetwork == 0.27 16:21:44 INFO - mozprocess == 0.23 16:21:44 INFO - mozprofile == 0.28 16:21:44 INFO - mozrunner == 6.12 16:21:44 INFO - mozscreenshot == 0.1 16:21:44 INFO - mozsystemmonitor == 0.3 16:21:44 INFO - moztest == 0.7 16:21:44 INFO - mozversion == 1.4 16:21:44 INFO - psutil == 3.1.1 16:21:44 INFO - requests == 1.2.3 16:21:44 INFO - wsgiref == 0.1.2 16:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21: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')]} 16:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21: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')]} 16:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:44 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:44 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 16:21:44 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 16:21:44 INFO - Using env: {'DISPLAY': ':0', 16:21:44 INFO - 'HOME': '/home/cltbld', 16:21:44 INFO - 'LANG': 'en_US.UTF-8', 16:21:44 INFO - 'LANGUAGE': 'en_US:en', 16:21:44 INFO - 'LOGNAME': 'cltbld', 16:21:44 INFO - 'MAIL': '/var/mail/cltbld', 16:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:44 INFO - 'MOZ_NO_REMOTE': '1', 16:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:44 INFO - 'NO_EM_RESTART': '1', 16:21:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:44 INFO - 'PWD': '/builds/slave/test', 16:21:44 INFO - 'SHELL': '/bin/bash', 16:21:44 INFO - 'SHLVL': '1', 16:21:44 INFO - 'TERM': 'linux', 16:21:44 INFO - 'TMOUT': '86400', 16:21:44 INFO - 'USER': 'cltbld', 16:21:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:44 INFO - '_': '/tools/buildbot/bin/python'} 16:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 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)) 16:21:44 INFO - Cleaning up... 16:21:44 INFO - Return code: 0 16:21:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:21: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')]} 16:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21: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')]} 16:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:44 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 0x2661030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2922180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', '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 16:21:44 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 16:21:44 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 16:21:44 INFO - Using env: {'DISPLAY': ':0', 16:21:44 INFO - 'HOME': '/home/cltbld', 16:21:44 INFO - 'LANG': 'en_US.UTF-8', 16:21:44 INFO - 'LANGUAGE': 'en_US:en', 16:21:44 INFO - 'LOGNAME': 'cltbld', 16:21:44 INFO - 'MAIL': '/var/mail/cltbld', 16:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:44 INFO - 'MOZ_NO_REMOTE': '1', 16:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:44 INFO - 'NO_EM_RESTART': '1', 16:21:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:44 INFO - 'PWD': '/builds/slave/test', 16:21:44 INFO - 'SHELL': '/bin/bash', 16:21:44 INFO - 'SHLVL': '1', 16:21:44 INFO - 'TERM': 'linux', 16:21:44 INFO - 'TMOUT': '86400', 16:21:44 INFO - 'USER': 'cltbld', 16:21:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:44 INFO - '_': '/tools/buildbot/bin/python'} 16:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:21:44 INFO - Cleaning up... 16:21:44 INFO - Return code: 0 16:21:44 INFO - Running post-action listener: _resource_record_post_action 16:21:44 INFO - Running post-action listener: _start_resource_monitoring 16:21:44 INFO - Starting resource monitoring. 16:21:44 INFO - ##### 16:21:44 INFO - ##### Finished create-virtualenv step (success) 16:21:44 INFO - ##### 16:21:44 INFO - ##### 16:21:44 INFO - ##### Running install step. 16:21:44 INFO - ##### 16:21:44 INFO - Running pre-action listener: _resource_record_pre_action 16:21:44 INFO - Running main action method: install 16:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:45 INFO - Reading from file tmpfile_stdout 16:21:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:21:45 INFO - Reading from file tmpfile_stdout 16:21:45 INFO - Output received: 16:21:45 INFO - Usage: mozinstall [options] installer 16:21:45 INFO - Options: 16:21:45 INFO - -h, --help show this help message and exit 16:21:45 INFO - -d DEST, --destination=DEST 16:21:45 INFO - Directory to install application into. [default: 16:21:45 INFO - "/builds/slave/test"] 16:21:45 INFO - --app=APP Application being installed. [default: firefox] 16:21:45 INFO - mkdir: /builds/slave/test/build/application 16:21:45 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'] 16:21:45 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 16:22:01 INFO - Reading from file tmpfile_stdout 16:22:01 INFO - Output received: 16:22:01 INFO - /builds/slave/test/build/application/firefox/firefox 16:22:01 INFO - Running post-action listener: _resource_record_post_action 16:22:01 INFO - ##### 16:22:01 INFO - ##### Finished install step (success) 16:22:01 INFO - ##### 16:22:01 INFO - ##### 16:22:01 INFO - ##### Running run-tests step. 16:22:01 INFO - ##### 16:22:01 INFO - Running pre-action listener: _resource_record_pre_action 16:22:01 INFO - Running main action method: run_tests 16:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:22:01 INFO - Python 2.7.3 16:22:01 INFO - Return code: 0 16:22:01 INFO - grabbing minidump binary from tooltool 16:22: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')]} 16:22:01 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 0x29203b0>, '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 0x29212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2921740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:22:01 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 16:22:01 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 16:22:01 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 16:22:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:22:01 INFO - Return code: 0 16:22:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:22:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:22:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:22:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:22:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 16:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 16:22:01 INFO - Using env: {'DISPLAY': ':0', 16:22:01 INFO - 'HOME': '/home/cltbld', 16:22:01 INFO - 'LANG': 'en_US.UTF-8', 16:22:01 INFO - 'LANGUAGE': 'en_US:en', 16:22:01 INFO - 'LOGNAME': 'cltbld', 16:22:01 INFO - 'MAIL': '/var/mail/cltbld', 16:22:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:01 INFO - 'MOZ_NO_REMOTE': '1', 16:22:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:01 INFO - 'NO_EM_RESTART': '1', 16:22:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:01 INFO - 'PWD': '/builds/slave/test', 16:22:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:01 INFO - 'SHELL': '/bin/bash', 16:22:01 INFO - 'SHLVL': '1', 16:22:01 INFO - 'TERM': 'linux', 16:22:01 INFO - 'TMOUT': '86400', 16:22:01 INFO - 'USER': 'cltbld', 16:22:01 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588', 16:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:01 INFO - '_': '/tools/buildbot/bin/python'} 16:22:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 16:22:01 INFO - mozversion application_buildid: 20160715150452 16:22:01 INFO - mozversion application_changeset: fbf164ef9e70c56d898f1c56d316cf4b88f2efac 16:22:01 INFO - mozversion application_display_name: Nightly 16:22:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:22:01 INFO - mozversion application_name: Firefox 16:22:01 INFO - mozversion application_remotingname: firefox 16:22:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:22:01 INFO - mozversion application_vendor: Mozilla 16:22:01 INFO - mozversion application_version: 50.0a1 16:22:01 INFO - mozversion platform_buildid: 20160715150452 16:22:01 INFO - mozversion platform_changeset: fbf164ef9e70c56d898f1c56d316cf4b88f2efac 16:22:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:22:01 INFO - mozversion platform_version: 50.0a1 16:22:01 INFO - using testdate: 1468624921 16:22:01 INFO - actual date: 1468624921 16:22:01 INFO - starting webserver on 'localhost:49446' 16:22:01 INFO - SUITE-START | Running 2 tests 16:22:01 INFO - TEST-START | dromaeo_css 16:22:01 INFO - operating with platform_type : linux_ 16:22:01 INFO - Initialising browser for dromaeo_css test... 16:22:01 INFO - TEST-INFO | started process 19299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZAPXPV/profile http://localhost:49446/getInfo.html) 16:22:06 INFO - PROCESS | 19299 | __metrics Screen width/height:1600/1200 16:22:06 INFO - PROCESS | 19299 | colorDepth:24 16:22:06 INFO - PROCESS | 19299 | Browser inner width/height: 1024/697 16:22:06 INFO - PROCESS | 19299 | __metrics 16:22:07 INFO - PROCESS | 19299 | 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" 16:22:07 INFO - PROCESS | 19299 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:22:08 INFO - TEST-INFO | 19299: exit 0 16:22:08 INFO - Browser initialized. 16:22:08 INFO - Running cycle 1/1 for dromaeo_css test... 16:22:08 INFO - TEST-INFO | started process 19379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZAPXPV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:20 INFO - PROCESS | 19379 | RSS: Main: 159506432 16:22:20 INFO - PROCESS | 19379 | 16:23:11 INFO - PROCESS | 19379 | 1468624991111 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] 16:25:23 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49446/tests/dromaeo/cssquery-ext.html) 16:25:24 INFO - PROCESS | 19379 | RSS: Main: 234106880 16:25:24 INFO - PROCESS | 19379 | 16:28:27 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-ext.html (next: http://localhost:49446/tests/dromaeo/cssquery-jquery.html) 16:28:28 INFO - PROCESS | 19379 | RSS: Main: 248602624 16:28:28 INFO - PROCESS | 19379 | 16:31:30 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49446/tests/dromaeo/cssquery-mootools.html) 16:31:31 INFO - PROCESS | 19379 | RSS: Main: 243396608 16:31:31 INFO - PROCESS | 19379 | 16:34:33 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49446/tests/dromaeo/cssquery-prototype.html) 16:34:33 INFO - PROCESS | 19379 | RSS: Main: 241971200 16:34:33 INFO - PROCESS | 19379 | 16:37:36 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49446/tests/dromaeo/cssquery-yui.html) 16:37:37 INFO - PROCESS | 19379 | RSS: Main: 215592960 16:37:37 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-yui.html (next: http://localhost:49446/tests/dromaeo/cssquery-dojo.html) 16:40:40 INFO - PROCESS | 19379 | RSS: Main: 292827136 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | __start_tp_report 16:40:40 INFO - PROCESS | 19379 | _x_x_mozilla_page_load 16:40:40 INFO - PROCESS | 19379 | _x_x_mozilla_page_load_details 16:40:40 INFO - PROCESS | 19379 | |i|pagename|runs| 16:40:40 INFO - PROCESS | 19379 | |0;dojo.html;2636.9736302636966;2645.9735402645974;2666.466706658669;2672.1182009936724;2691;4476.239039363308;4532.410786597738;4754.477007529169;4782.163121453749;4799.880002999922;4703.835365762199;4757.738324392157;4774.594159496447;4793.065352256312;4829.372181616393;4153.148604536078;4335.804888780004;4433.2241857674935;4456.041950980539;4459.0190158165315;4407.625351845097;4432.246518091933;4439.511653718088;4463.196624607569;4477.037436951055;4299.075698724773;4327.7619730914785;4349.956500434987;4371.256886329332;4399.38408622793;4325.891852703676;4337.50118736343;4378.6934914555995;4379.846705365297;4385.912281754379;4370.2570563004365;4378.9562104378865;4382.539833317488;4388.978055109736;4406.140802543506;4636.049609830011;4742.217534106902;4874.780634871439;4883.853484395474;4887.266909963534;5223.608229382773;5225.163973764217;5232.372115346145;5239.3712754469325;5251.159814429673;4958.504149585049;5262.552683021948;5262.710550919698;5264.973675131638;5272.103742363807;4749.121412538657;4793.808247670054;4794.976025119852;4845.733484658307;4860.635452341088;5339.41266460689;5351.866203344947;5369;5370.248165256867;5383.623146379716;4803.231482962779;4968.6025117990475;5037.319961805183;5055.115354812893;5107.668001579886;5106.565941894981;5129.281900533928;5147.176451767699;5155.407128180307;5202.453742357074;5076.390833100052;5091.821786237538;5095.668781529189;5102.260172275073;5144.562712169508;4876.878078048077;5006.674566153189;5044.344235249394;5046.470120637354;5049.899002020013;4414.227510185705;4420.646348292129;4442.044960333479;4453.999999999935;4463.464384273908;3844.7116466264583;3849.1339448623835;3862.8841134766003;3864.6521813036397;3881.86413475527;4205.516365617932;4274.957250427456;4431.601155895984;4489.618382437529;4496.437945256843;5002.474740152305;5035.093683137071;5061.645684802103;5094.745262736848;5105.846824595268;4318.546552611993;4325.740455572675;4327.740335579875;4332.241857674894;4367.170237654836;4505;4548.476925153584;4559.52125026874;4564.475085365159;4605.171069207575;4785.545373189541;4818.6626936114135;4847.878803029953;4856.854294371175;4875.098106850244;5155.046316431472;5175.560077393464;5176.171812509981;5181.222816577543;5224.085784987688;4601.792919318572;4737.431508219036;4865.1242776300605;4874.098291816025;4929.038837426668;4987.975060124676;5014.498550144956;5039.319691841554;5045.167547354646;5086.949130508796;5112.565431938178;5114.2840002400435;5120.283160357628;5124.333836601218;5166.819161329336;4882.6826256293225;4902.068606964665;4951.257311403318;4987.276844857548;4987.276844857548;4950.8762280943265;4958.033183529322;4979.3526841510375;4980.576650984707;5008.023435430056;4880.755962201804;4890.657653964188;4911.287863259736;4915.3855768029;4931.235658472942;4889.731064791328;4963.280324353021;5011.123053465556;5049;5060.645754797129;4938.234573641092;4952.430470495795;4955.0337684151245;4960.47914968923;5017.473165317735 16:40:40 INFO - PROCESS | 19379 | |1;ext.html;576.5877397660672;580.814139475368;587.944145306196;592.2715060475614;594.4174708785393;27011.864940675292;40080.79959600201;41185.17629647413;41210.58789412105;44414.555854441445;40787.59212407875;41255;41298.174036519325;41334.79332603333;41343.58656413435;7332.963335183316;7767.029121359844;7898.170692077335;7906.604669766518;7920;7502.849943001137;7589.620518974029;7591.544507329578;9944.40333579984;10224.386536807779;10093.89906100937;10122.949385253063;10136.797264054714;10145.44200068996;10155.543000564992;10153.796924061515;10198.592056317739;10213.948930255376;10215.591376344937;10219.029192226726;7344.596047217401;7541.170471248159;7555.659995300225;9751.90248097517;10147.289689721723;38930.22139557221;39343.803280983695;39391.8030409849;39400.80299598513;39441.40837887434;3018.5321275202377;3221.2269055426764;3382.746294027958;3684.4657524658774;3812.618738126193;29419.558706619417;29596.260093497618;30012.8499357504;30459.23851903698;31221.8438907804;3541.982290088533;3547.5742910850863;3569.232614987764;3590.874319398835;3604.8377822998027;189892;190074;190170;190593;190818;53597;54035.13683768401;54073.459265407626;54075;54080.459195407544;56795;56880.43119568751;56919.43080569224;56944.430555694744;56965.43034569684;15201.391944322324;15793.526194214191;15794.131322777357;15794.60513487149;15797.052176869425;10188.643397481053;11971.52113915451;12075.09436792244;12081.75836483283;12090.395480225954;15527.844721552865;15853.128077955824;15952.92023539898;16278.918605406898;16510.6697866042;5984.790532331434;6023.283364241258;6135.969320153371;6229.190205273286;6230.314665386803;805.7300804230531;862.5083702289635;886.7368085445622;901.5131828812367;907.4464576608264;27880.860595696893;28219.43561128807;28534.42931141407;28636.570451443244;28731.42537149287;944.0983860413289;965.9420434773936;972.1833659725864;1002.0330381182049;1047.3715770537617;14517.927410362881;14923.925380373028;14990.700185996218;15361.923190383977;15416.229188540752;391746;412359;415976;416326;641258;49844.25233621499;50074.24888626674;50286.748566257666;50329.74835125801;50394.244086338;1062.8458873463305;1209.4738788626976;1226.6871947653292;1227.4169769359662;1255.7802384582662;25654;28552.714472855838;29944.401111977204;30070.87529240433;30422.543661844647;44264;45168.32247516224;45180.774096130626;45259.09481810411;45265.773671131436;346713;354231;354958;355357;357141;33385.16537086592;39194.608053919095;39410.40884386679;39921.60078399179;40016;42241.57758422376;42784.5721542793;42960.57039429565;43419.65803419759;43455;36230.4565431524;36681;37073.814630926674;37758;38130.8093459542;36396.27207455889;37075.258494830494;37223.62776372202;37228.813855930544;37783.81108094442;36134.27731445409;36800.63199368079;36852.447213292355;36982.8150859244;37030.44454333133;33394.16514587155;33483.330333393686;33904.66095339015;34087;34204.48693269553;24708.87284919117;25177;25397.365065873502;25595.10417135466;25630.743692562835 16:40:40 INFO - PROCESS | 19379 | |2;jquery.html;380.9939576739525;383.3043026906165;383.6930455635493;396.59547261792954;400.83365403357607;15945.441909533169;17368.739468907977;17994.190261438234;18172.27363400478;18214.362497312595;17094.487165385028;17443.563910902234;18046.18792154353;18365;18389.172487238073;5129.615278854082;5191.818286359978;5300.443453437391;5338.813141540047;5338.839834804962;3717.5910649828497;3854.6338097880657;4712.481627021025;6160.969195154041;6163.876722465548;5997.820065398045;6098.298695650013;6100.176476175726;6123.081537769326;6138.017917133259;5862.472377486026;6088.512918966472;6093.146959425706;6105.053716673922;6110;5186.974065129651;5352;5356.625036247464;5363.115086010804;5462.481064298925;21937.670934935988;22057.779422205735;22188.88905555478;22199.11203551856;22208.444788880246;2021.191523390641;2039.7552293724848;2169.6962425260476;2187.6062308784412;2576.1498705427166;13756.65608359799;14369;14430.927845360811;14512.782308265385;14661.41354345825;1915.3870761356372;1923;1952.8730632508984;1991.32295019694;2009.4273132157412;58572;69680.6515967422;72139.2786072143;83795.58102209511;85088;16307.184640767915;16636.50090497287;16988.830111698724;17015.489535313714;17114.657706846043;29735.702642973727;29837.254068648457;30111.69888301089;30156;30191.849040754656;9278.350515463853;9590.42457452555;9659.565319560637;9692.806143877084;9784.651683595886;7975.680972761141;8011.799705007422;8159.347252219808;8192.221738934792;8197.344212463107;8500.404971651926;8614.612342444605;8704.303655707528;8784.341174411944;8906.910930890608;2640.240983083313;3034.734735234367;3209.5667084943234;3218.8873389431724;3405.540252065989;803.5058439059936;853.9743807685779;887.5296093070683;905.8550631898864;913.3515204205089;17223.827761722474;17837.197326120357;17921.641567168845;18040.368587099387;18362.724559131624;874.9343799215085;882.2809410330689;911.129870973219;911.1344222988188;915.7985243246475;9882.752931176778;10259.127974122282;10276.17790576767;10292.330998485075;10400;407723;408101;422420;423413;423888;48672.75663621659;49708.254376184384;58227.70886145542;59238;74748.87876681854;883.7525492862009;894.8478758610921;900.4552245891141;914.3599480363772;916.1617120334819;15879.206039697738;16134.515964521082;16151.384861514225;16275.918620406823;16277.674446510766;27335.72664273332;29964;39617.80191099142;40998.9750256246;41041.79479102585;49172.26241606451;52642.73678631582;52752;53173.20240196323;54625.45374546204;7909.9209007909185;7933.722319718782;7992.720254791055;8075.87886181696;8225.177482251727;6267.874642507033;8915;9442.716718498456;10143.340682855885;11830.231034982698;10132.34139780912;10914.945425273141;10960.232783705063;10976.725581860517;11169.273997190157;9969.35199212049;10366.792664146826;10581.153507719519;10766.03105720489;10841.295315804447;11102.167337449562;11129.387883666152;11225.71935701614;11243.10055195566;11524.59663911759;6987.545809522365;7022.754203602849;7246.96376518114;8129.75610731679;8380.916190838014;10821.350718956888;10829.29609575375;10844.240903136704;10868.94565527199;11052.502637381176 16:40:40 INFO - PROCESS | 19379 | |3;mootools.html;1333.3999700134937;1391.6242614494086;1449.9420023199073;1685.949421517354;1689.9915500422496;7787.338076263517;8202.097769245378;8242.381821363391;8318.625661845217;8328.083910769818;6925.722971081151;7170.533915295502;7807.063152421703;8032.277095061443;8149.225823546768;2211.7345918489805;2240.7535171131162;2241.0363543676212;2242.854214476054;2245.3264020793695;2164.9783502164933;2188.9343319700433;2280.9885950570224;2282.167009041696;2286.1655495744017;1907.122723547163;1980.7623085229793;1981.5937732764748;1992.023908284937;2083.322920050982;2216.390492614528;2234.474898398867;2261.5137745384823;2263.1739415113366;2264.5810525052916;2230.877301748403;2237.2057919438525;2246.7416247131628;2248.303026061926;2248.6177349850564;8034.678612855537;8040.316573091294;8049.436539442183;8095.3523718103;8106.554139522383;4715.24556071027;4747.976260118678;4755.286706993978;4759.78580963854;4920.975395123037;2872.9856350718323;2909.6944820793724;2957.8668959896854;2973.568832519294;2980.672126066131;895.2435192262528;916.0656130746668;924.6024209589874;924.6717415317532;931.2131249094572;11098.223124381297;11313.20807543464;11351.772964540745;11356.148288878367;11400.942995285053;4178.6657067434535;4189.350650649154;4357.673174511924;4357.694961352675;4441.68908176431;9604.855927161097;9611.807763844685;9627.470489123025;9629.807403852024;9652.034796520433;4873.756312184377;4876.439209490883;4882.6826256293225;4883.218685010382;4883.584895283941;4217.536071032184;4270.188664153801;4272.46594175728;4276.5723427656985;4285.7642829644665;3593.460980852893;3623.655752703489;3663.9084022899106;3698.093966978108;3728;1061.0105951901473;1075.3762817565794;1084.4631907205826;1086.1365214654368;1086.32104934416;820.8522465956066;840.2521755637488;847.7531056055293;862.3015357560271;868.0104680663921;5107.336046313955;5134.358205224347;5172.9741351293005;5290.418054014055;5412.161115027177;416.5917400947089;420.6130360068683;421.51104718526364;423.7881059470265;426.44775016353896;3505.8948231553095;3648.3250598638806;3681.5766186888313;3706.4069748840056;3754.718396120302;9756.317057806027;10118.696439106692;10256.743581410525;10288.074073333435;10298.845517317246;9420.811583768424;10049.799004019878;10069.899301007043;10089.899101008896;10155.847662285072;7252.528585642022;7565.205653406423;7627.771166865003;7631.046119235096;7646.502977306457;5357.410684824588;5660.264165658603;5666.688332141607;5681.829545113653;5904.202932604044;8935.597898094471;9257.028012058638;9265.305102117503;9341.813163736822;9518.095780900803;9311.76720581991;9514.286428518028;9646.855297170408;9723.56243969009;9801.362911410735;6655.334466553306;6963.6866341013765;6984.4063254623925;7045.11936007999;7199;7968.243016913384;7974.40191985615;8013.639386227517;8030.919690803017;8068.394870384862;6971.895421568579;7175.964120179366;7210.423166146591;7218.097737782778;7392.186859445349;6868.416184624363;6960.651967401711;6984.371406573229;7070.36366726997;7167.892481612885;6392.328805475544;6408.391202835724;6510.511711621552;6516.771912983023;6521.869562608626;4850.708957462563;4897.216445368724;4912.729799861042;4943.382077240345;4974.776135073858;4751.144793937125;4757.904841903073;4778.976105119452;4795.616350692006;4850.296706977398 16:40:40 INFO - PROCESS | 19379 | |4;prototype.html;308.1295340662628;313.6220853871084;316.1085738218226;316.14955768981446;317.04095112285336;4940.209566469365;4955.231939049444;4962.454130045692;4965.535167125698;4974.726390048546;4825.372701548802;4838.9032219355595;4922.704637721739;4931.926021109686;4938.592501137176;3644.908877278069;3750.831212595426;3770.905727356824;3867.845286188549;3921.745086569364;3855.5951625079383;3880.8641697540593;3885.9611403886024;3888.2612303662354;3895.3183192941265;3670.724695647824;3833.8083095845236;3839.9232015359676;3860.9227815443674;3867.0719027433356;3615.788710781865;3791.3365161096835;3800.866969656063;3806.505154329947;3844.519435070616;3402.387570237356;3406.938802024789;3408.454647256427;3409.511056928456;3457.2739724657854;4709.458412282563;4732.100900828831;4732.95267047332;4737.455192652855;4785.68893021956;4277.2663294589465;4417.779111044435;4422.3366495026;4454.643628509712;4464.419625448703;5045.8738531537;5076.111680455905;5097.980403919231;5101.107306221396;5110.335656364686;3745.700343972476;3757.651467832605;3763.34141525232;3772.5472943246987;3772.981135094307;5211.765470553834;5307.840764777063;5337.653052551611;5381.434949330303;5388.703621300827;3842.3275926712713;3852.114013776847;3861.575226725058;3866.149447121629;3872.2642697887313;3880.553736320303;3887.8833634990997;3910.354791459434;3921.039345360405;3922.3920292354733;4336.197235711617;4411.933820992687;4412;4423.977880110579;4424.867253982386;5021.14640511114;5069.822556210515;5075.670081444694;5088.185890257541;5109.6678715883345;1323.8874695650977;1343.147101590497;1345.7577636025414;1349.7705390083709;1356.5552686388965;847.9236868681849;883.9955800221086;887.5828360670474;892.9910700892908;896.3501461440403;4307.267764480046;4365.214261432972;4445.843765773598;4501.414816073955;4507.639388848885;5271.736413179326;5287.894242115136;5303.416624171338;5329.546988505943;5349.411564727877;4456.598906098467;4463.843765468193;4469.41897553316;4484.260097083945;4506.459224893034;2649.337665583604;2809.4828792452254;2818.210900947738;2821.8730157142977;2836.262571731365;4946.1344264753525;4948.8574755030095;4981.551660350513;4991.126552853236;5005.72468514228;5162.767675454614;5366.2755528004;5404.513593776501;5426.8371948841595;5450.400455949843;4988.977259661688;5012.691623528453;5018.1218286800395;5020.849374518771;5025.346704928336;5317.787288508571;5338.825458399301;5352.571794256528;5363.597730170175;5376.650517716336;5070.974645126898;5238.869028274324;5251.054810134213;5253.658512196695;5265.315508983961;4991.550760431579;4998;5004.249362595494;5024.296598476143;5029.64792464524;3750.568684601306;3782.9407765825613;3784.8615532224203;3795.089178597172;3801.2967600993907;5163.535281824654;5352.143657014859;5370.838874833761;5388.434214407431;5398.46015398457;5120.308758317579;5146.948530514647;5157.0975079361715;5162.457941916195;5173.379194496534;5138.4604616514725;5141.922871156861;5212.635115541876;5215.869603259949;5220.9738951305;4905;5094.243504839507;5098.444974282879;5128.897422051464;5158.535731784157;4964.553190212898;5036.269740887624;5226.843194704165;5228.607854410859;5238.371395432572;5096.617753668415;5101.285819985131;5113.4375218726655;5114.33513643224;5121.590272778094 16:40:40 INFO - PROCESS | 19379 | |5;yui.html;616.9167162433071;623.4264476681452;710.2400431538252;728.1153398620675;754.2796629219098;12440.626781196568;13279.535216267432;13303;13340.733185336287;13427.798583021251;13366.933165334183;13546.525871594496;13559;14049.719005619882;14119.882017697351;635.1140159477526;648.1023782061831;758.9279018493235;785.352084530262;804.2279411764713;791.6714563456187;796.1361922314296;799.2646764976207;802.2058162419193;802.4022103532876;837.6900546797693;840.3193413335218;841.7264389073575;841.7937605286713;841.9284360829306;657.464166704137;679.4021261290034;680.6936878404738;683.3883674111643;831.8544254755424;568.528121659022;589.8083122985004;594.9613275137145;595.1637948682109;595.8838026584817;12222.266664000099;12222.449989750483;12225.510979560808;12242.571509997108;12279.508819647204;11880.227785193934;12566.497340106385;12577.245365278113;12803.679908002281;13068.281244531547;11837.230580012272;11923;12001.759964800742;12143.332116733576;12442.440090195872;4142.668586513102;4181.351890456984;4196.202721482909;4238.237117318881;4315.029118448356;63383.36616633867;63560.36439635591;63793.68103159455;65559;66633.66683166602;9579.856302155473;9932.851007234898;9949.701508954742;10123.797524049478;10252;17376.478705638852;17469.825301747074;17496.300147994192;17508.5622859427;17539.29842806299;6487.740490380426;6594.340565943367;6728.293529179464;6781.321867813179;6827.863442731117;3669.8165091745304;3715.331240376704;3722.1253005543676;3752.7185461090526;3774.3960966245268;2796.874140663675;2859.5567687008547;2883.4377296427415;2895.739383455499;2953.8375389353364;483.3209340876075;488.27003629573306;492.70930151210956;495.07421122501154;496.7714851168503;515.9716215608103;518.9143791274473;535.6464733276019;536.085973415326;545.6835035679296;6944.930550694529;6959.4432445404245;7062.187848397397;7275.236100209508;7417.147028091838;397.8488174493674;430.35876543949917;458.74081144153445;499.91751361025024;507.2366089035977;3393.270446853938;3479.582450105953;3548.680618744274;3603.837827297778;3657.2868290683596;96599;96798.51600741952;97719;99581.50209249053;100633;81709.59145204235;85338;85437.57281213554;85763;86598;75036.62481687666;76485.61757191179;77306.61346693231;77416.61291693618;77860;6966.686499107451;7022.929770702432;7036.518388304484;7074.610896400747;7224.566526008456;45079.7746011281;46088.53911460842;47640.761796190796;48288.758556206994;48778.512214877395;67227.32772672211;67401;67513.66243168752;68856.311436885;69624.65187674029;9356.251499879858;11812.93683568483;11904.940475297568;11919.821202681793;12401.441935112762;11830.46762895689;12866.292353920624;13725.313734313444;14044;14483.058601190889;10788.136949043901;10809.513571889127;11333.320000799979;11346.319220846446;11656.06751459898;11730.76538469243;11766.999805016669;11861.5848445304;11983.460744266704;12447.377631118226;10920.2901811382;10967.616133435291;11414.714632134263;11425.428728563706;11773.117016223647;9736.513174341397;10030.398176109456;10203.38779673222;10473.31923424975;10581.62964296246;9238.769030774285;9620.13418792312;9770;9791.83266932271;9988.300818942604 16:40:40 INFO - PROCESS | 19379 | __end_tp_report 16:40:40 INFO - PROCESS | 19379 | __start_cc_report 16:40:40 INFO - PROCESS | 19379 | _x_x_mozilla_cycle_collect,1058 16:40:40 INFO - PROCESS | 19379 | __end_cc_report 16:40:40 INFO - PROCESS | 19379 | __startTimestamp1468626040451__endTimestamp 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | ------- Summary: start ------- 16:40:40 INFO - PROCESS | 19379 | Number of tests: 6 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#0] dojo.html Cycles:165 Average:4729.44 Median:4875.10 stddev:504.78 (10.4%) stddev-sans-first:478.89 16:40:40 INFO - PROCESS | 19379 | Values: 2637.0 2646.0 2666.5 2672.1 2691.0 4476.2 4532.4 4754.5 4782.2 4799.9 4703.8 4757.7 4774.6 4793.1 4829.4 4153.1 4335.8 4433.2 4456.0 4459.0 4407.6 4432.2 4439.5 4463.2 4477.0 4299.1 4327.8 4350.0 4371.3 4399.4 4325.9 4337.5 4378.7 4379.8 4385.9 4370.3 4379.0 4382.5 4389.0 4406.1 4636.0 4742.2 4874.8 4883.9 4887.3 5223.6 5225.2 5232.4 5239.4 5251.2 4958.5 5262.6 5262.7 5265.0 5272.1 4749.1 4793.8 4795.0 4845.7 4860.6 5339.4 5351.9 5369.0 5370.2 5383.6 4803.2 4968.6 5037.3 5055.1 5107.7 5106.6 5129.3 5147.2 5155.4 5202.5 5076.4 5091.8 5095.7 5102.3 5144.6 4876.9 5006.7 5044.3 5046.5 5049.9 4414.2 4420.6 4442.0 4454.0 4463.5 3844.7 3849.1 3862.9 3864.7 3881.9 4205.5 4275.0 4431.6 4489.6 4496.4 5002.5 5035.1 5061.6 5094.7 5105.8 4318.5 4325.7 4327.7 4332.2 4367.2 4505.0 4548.5 4559.5 4564.5 4605.2 4785.5 4818.7 4847.9 4856.9 4875.1 5155.0 5175.6 5176.2 5181.2 5224.1 4601.8 4737.4 4865.1 4874.1 4929.0 4988.0 5014.5 5039.3 5045.2 5086.9 5112.6 5114.3 5120.3 5124.3 5166.8 4882.7 4902.1 4951.3 4987.3 4987.3 4950.9 4958.0 4979.4 4980.6 5008.0 4880.8 4890.7 4911.3 4915.4 4931.2 4889.7 4963.3 5011.1 5049.0 5060.6 4938.2 4952.4 4955.0 4960.5 5017.5 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#1] ext.html Cycles:180 Average:49562.06 Median:36917.63 stddev:94205.40 (255.2%) stddev-sans-first:94397.87 16:40:40 INFO - PROCESS | 19379 | Values: 576.6 580.8 587.9 592.3 594.4 27011.9 40080.8 41185.2 41210.6 44414.6 40787.6 41255.0 41298.2 41334.8 41343.6 7333.0 7767.0 7898.2 7906.6 7920.0 7502.8 7589.6 7591.5 9944.4 10224.4 10093.9 10122.9 10136.8 10145.4 10155.5 10153.8 10198.6 10213.9 10215.6 10219.0 7344.6 7541.2 7555.7 9751.9 10147.3 38930.2 39343.8 39391.8 39400.8 39441.4 3018.5 3221.2 3382.7 3684.5 3812.6 29419.6 29596.3 30012.8 30459.2 31221.8 3542.0 3547.6 3569.2 3590.9 3604.8 189892.0 190074.0 190170.0 190593.0 190818.0 53597.0 54035.1 54073.5 54075.0 54080.5 56795.0 56880.4 56919.4 56944.4 56965.4 15201.4 15793.5 15794.1 15794.6 15797.1 10188.6 11971.5 12075.1 12081.8 12090.4 15527.8 15853.1 15952.9 16278.9 16510.7 5984.8 6023.3 6136.0 6229.2 6230.3 805.7 862.5 886.7 901.5 907.4 27880.9 28219.4 28534.4 28636.6 28731.4 944.1 965.9 972.2 1002.0 1047.4 14517.9 14923.9 14990.7 15361.9 15416.2 391746.0 412359.0 415976.0 416326.0 641258.0 49844.3 50074.2 50286.7 50329.7 50394.2 1062.8 1209.5 1226.7 1227.4 1255.8 25654.0 28552.7 29944.4 30070.9 30422.5 44264.0 45168.3 45180.8 45259.1 45265.8 346713.0 354231.0 354958.0 355357.0 357141.0 33385.2 39194.6 39410.4 39921.6 40016.0 42241.6 42784.6 42960.6 43419.7 43455.0 36230.5 36681.0 37073.8 37758.0 38130.8 36396.3 37075.3 37223.6 37228.8 37783.8 36134.3 36800.6 36852.4 36982.8 37030.4 33394.2 33483.3 33904.7 34087.0 34204.5 24708.9 25177.0 25397.4 25595.1 25630.7 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#2] jquery.html Cycles:180 Average:25918.00 Median:407912.00 stddev:68346.00 (16.8%) stddev-sans-first:68510.83 16:40:40 INFO - PROCESS | 19379 | Values: 381.0 383.3 383.7 396.6 400.8 15945.4 17368.7 17994.2 18172.3 18214.4 17094.5 17443.6 18046.2 18365.0 18389.2 5129.6 5191.8 5300.4 5338.8 5338.8 3717.6 3854.6 4712.5 6161.0 6163.9 5997.8 6098.3 6100.2 6123.1 6138.0 5862.5 6088.5 6093.1 6105.1 6110.0 5187.0 5352.0 5356.6 5363.1 5462.5 21937.7 22057.8 22188.9 22199.1 22208.4 2021.2 2039.8 2169.7 2187.6 2576.1 13756.7 14369.0 14430.9 14512.8 14661.4 1915.4 1923.0 1952.9 1991.3 2009.4 58572.0 69680.7 72139.3 83795.6 85088.0 16307.2 16636.5 16988.8 17015.5 17114.7 29735.7 29837.3 30111.7 30156.0 30191.8 9278.4 9590.4 9659.6 9692.8 9784.7 7975.7 8011.8 8159.3 8192.2 8197.3 8500.4 8614.6 8704.3 8784.3 8906.9 2640.2 3034.7 3209.6 3218.9 3405.5 803.5 854.0 887.5 905.9 913.4 17223.8 17837.2 17921.6 18040.4 18362.7 874.9 882.3 911.1 911.1 915.8 9882.8 10259.1 10276.2 10292.3 10400.0 407723.0 408101.0 422420.0 423413.0 423888.0 48672.8 49708.3 58227.7 59238.0 74748.9 883.8 894.8 900.5 914.4 916.2 15879.2 16134.5 16151.4 16275.9 16277.7 27335.7 29964.0 39617.8 40999.0 41041.8 49172.3 52642.7 52752.0 53173.2 54625.5 7909.9 7933.7 7992.7 8075.9 8225.2 6267.9 8915.0 9442.7 10143.3 11830.2 10132.3 10914.9 10960.2 10976.7 11169.3 9969.4 10366.8 10581.2 10766.0 10841.3 11102.2 11129.4 11225.7 11243.1 11524.6 6987.5 7022.8 7247.0 8129.8 8380.9 10821.4 10829.3 10844.2 10868.9 11052.5 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#3] mootools.html Cycles:180 Average:5326.47 Median:4882.95 stddev:3065.63 (62.8%) stddev-sans-first:3059.54 16:40:40 INFO - PROCESS | 19379 | Values: 1333.4 1391.6 1449.9 1685.9 1690.0 7787.3 8202.1 8242.4 8318.6 8328.1 6925.7 7170.5 7807.1 8032.3 8149.2 2211.7 2240.8 2241.0 2242.9 2245.3 2165.0 2188.9 2281.0 2282.2 2286.2 1907.1 1980.8 1981.6 1992.0 2083.3 2216.4 2234.5 2261.5 2263.2 2264.6 2230.9 2237.2 2246.7 2248.3 2248.6 8034.7 8040.3 8049.4 8095.4 8106.6 4715.2 4748.0 4755.3 4759.8 4921.0 2873.0 2909.7 2957.9 2973.6 2980.7 895.2 916.1 924.6 924.7 931.2 11098.2 11313.2 11351.8 11356.1 11400.9 4178.7 4189.4 4357.7 4357.7 4441.7 9604.9 9611.8 9627.5 9629.8 9652.0 4873.8 4876.4 4882.7 4883.2 4883.6 4217.5 4270.2 4272.5 4276.6 4285.8 3593.5 3623.7 3663.9 3698.1 3728.0 1061.0 1075.4 1084.5 1086.1 1086.3 820.9 840.3 847.8 862.3 868.0 5107.3 5134.4 5173.0 5290.4 5412.2 416.6 420.6 421.5 423.8 426.4 3505.9 3648.3 3681.6 3706.4 3754.7 9756.3 10118.7 10256.7 10288.1 10298.8 9420.8 10049.8 10069.9 10089.9 10155.8 7252.5 7565.2 7627.8 7631.0 7646.5 5357.4 5660.3 5666.7 5681.8 5904.2 8935.6 9257.0 9265.3 9341.8 9518.1 9311.8 9514.3 9646.9 9723.6 9801.4 6655.3 6963.7 6984.4 7045.1 7199.0 7968.2 7974.4 8013.6 8030.9 8068.4 6971.9 7176.0 7210.4 7218.1 7392.2 6868.4 6960.7 6984.4 7070.4 7167.9 6392.3 6408.4 6510.5 6516.8 6521.9 4850.7 4897.2 4912.7 4943.4 4974.8 4751.1 4757.9 4779.0 4795.6 4850.3 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#4] prototype.html Cycles:180 Average:4286.92 Median:4939.40 stddev:1236.52 (25.0%) stddev-sans-first:1203.39 16:40:40 INFO - PROCESS | 19379 | Values: 308.1 313.6 316.1 316.1 317.0 4940.2 4955.2 4962.5 4965.5 4974.7 4825.4 4838.9 4922.7 4931.9 4938.6 3644.9 3750.8 3770.9 3867.8 3921.7 3855.6 3880.9 3886.0 3888.3 3895.3 3670.7 3833.8 3839.9 3860.9 3867.1 3615.8 3791.3 3800.9 3806.5 3844.5 3402.4 3406.9 3408.5 3409.5 3457.3 4709.5 4732.1 4733.0 4737.5 4785.7 4277.3 4417.8 4422.3 4454.6 4464.4 5045.9 5076.1 5098.0 5101.1 5110.3 3745.7 3757.7 3763.3 3772.5 3773.0 5211.8 5307.8 5337.7 5381.4 5388.7 3842.3 3852.1 3861.6 3866.1 3872.3 3880.6 3887.9 3910.4 3921.0 3922.4 4336.2 4411.9 4412.0 4424.0 4424.9 5021.1 5069.8 5075.7 5088.2 5109.7 1323.9 1343.1 1345.8 1349.8 1356.6 847.9 884.0 887.6 893.0 896.4 4307.3 4365.2 4445.8 4501.4 4507.6 5271.7 5287.9 5303.4 5329.5 5349.4 4456.6 4463.8 4469.4 4484.3 4506.5 2649.3 2809.5 2818.2 2821.9 2836.3 4946.1 4948.9 4981.6 4991.1 5005.7 5162.8 5366.3 5404.5 5426.8 5450.4 4989.0 5012.7 5018.1 5020.8 5025.3 5317.8 5338.8 5352.6 5363.6 5376.7 5071.0 5238.9 5251.1 5253.7 5265.3 4991.6 4998.0 5004.2 5024.3 5029.6 3750.6 3782.9 3784.9 3795.1 3801.3 5163.5 5352.1 5370.8 5388.4 5398.5 5120.3 5146.9 5157.1 5162.5 5173.4 5138.5 5141.9 5212.6 5215.9 5221.0 4905.0 5094.2 5098.4 5128.9 5158.5 4964.6 5036.3 5226.8 5228.6 5238.4 5096.6 5101.3 5113.4 5114.3 5121.6 16:40:40 INFO - PROCESS | 19379 | 16:40:40 INFO - PROCESS | 19379 | [#5] yui.html Cycles:180 Average:18095.21 Median:490.49 stddev:26089.78 (5319.1%) stddev-sans-first:26129.96 16:40:40 INFO - PROCESS | 19379 | Values: 616.9 623.4 710.2 728.1 754.3 12440.6 13279.5 13303.0 13340.7 13427.8 13366.9 13546.5 13559.0 14049.7 14119.9 635.1 648.1 758.9 785.4 804.2 791.7 796.1 799.3 802.2 802.4 837.7 840.3 841.7 841.8 841.9 657.5 679.4 680.7 683.4 831.9 568.5 589.8 595.0 595.2 595.9 12222.3 12222.4 12225.5 12242.6 12279.5 11880.2 12566.5 12577.2 12803.7 13068.3 11837.2 11923.0 12001.8 12143.3 12442.4 4142.7 4181.4 4196.2 4238.2 4315.0 63383.4 63560.4 63793.7 65559.0 66633.7 9579.9 9932.9 9949.7 10123.8 10252.0 17376.5 17469.8 17496.3 17508.6 17539.3 6487.7 6594.3 6728.3 6781.3 6827.9 3669.8 3715.3 3722.1 3752.7 3774.4 2796.9 2859.6 2883.4 2895.7 2953.8 483.3 488.3 492.7 495.1 496.8 516.0 518.9 535.6 536.1 545.7 6944.9 6959.4 7062.2 7275.2 7417.1 397.8 430.4 458.7 499.9 507.2 3393.3 3479.6 3548.7 3603.8 3657.3 96599.0 96798.5 97719.0 99581.5 100633.0 81709.6 85338.0 85437.6 85763.0 86598.0 75036.6 76485.6 77306.6 77416.6 77860.0 6966.7 7022.9 7036.5 7074.6 7224.6 45079.8 46088.5 47640.8 48288.8 48778.5 67227.3 67401.0 67513.7 68856.3 69624.7 9356.3 11812.9 11904.9 11919.8 12401.4 11830.5 12866.3 13725.3 14044.0 14483.1 10788.1 10809.5 11333.3 11346.3 11656.1 11730.8 11767.0 11861.6 11983.5 12447.4 10920.3 10967.6 11414.7 11425.4 11773.1 9736.5 10030.4 10203.4 10473.3 10581.6 9238.8 9620.1 9770.0 9791.8 9988.3 16:40:40 INFO - PROCESS | 19379 | -------- Summary: end -------- 16:40:40 INFO - PROCESS | 19379 | 16:40:41 INFO - TEST-INFO | 19379: exit 0 16:40:41 INFO - TEST-OK | dromaeo_css | took 1119536ms 16:40:41 INFO - TEST-START | kraken 16:40:41 INFO - operating with platform_type : linux_ 16:40:41 INFO - Initialising browser for kraken test... 16:40:41 INFO - TEST-INFO | started process 19567 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH7qh6A/profile http://localhost:49446/getInfo.html) 16:40:45 INFO - PROCESS | 19567 | __metrics Screen width/height:1600/1200 16:40:45 INFO - PROCESS | 19567 | colorDepth:24 16:40:45 INFO - PROCESS | 19567 | Browser inner width/height: 1024/697 16:40:45 INFO - PROCESS | 19567 | __metrics 16:40:46 INFO - PROCESS | 19567 | 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" 16:40:46 INFO - PROCESS | 19567 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:40:47 INFO - TEST-INFO | 19567: exit 0 16:40:47 INFO - Browser initialized. 16:40:47 INFO - Running cycle 1/1 for kraken test... 16:40:47 INFO - TEST-INFO | started process 19650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH7qh6A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:59 INFO - PROCESS | 19650 | RSS: Main: 158896128 16:40:59 INFO - PROCESS | 19650 | 16:41:02 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection) 16:41:03 INFO - PROCESS | 19650 | RSS: Main: 218390528 16:41:03 INFO - PROCESS | 19650 | 16:41:06 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-dft) 16:41:06 INFO - PROCESS | 19650 | RSS: Main: 204607488 16:41:06 INFO - PROCESS | 19650 | 16:41:10 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-fft) 16:41:10 INFO - PROCESS | 19650 | RSS: Main: 271265792 16:41:10 INFO - PROCESS | 19650 | 16:41:12 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator) 16:41:13 INFO - PROCESS | 19650 | RSS: Main: 181387264 16:41:13 INFO - PROCESS | 19650 | 16:41:15 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:41:16 INFO - PROCESS | 19650 | RSS: Main: 185536512 16:41:16 INFO - PROCESS | 19650 | 16:41:19 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom) 16:41:20 INFO - PROCESS | 19650 | RSS: Main: 221872128 16:41:20 INFO - PROCESS | 19650 | 16:41:23 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate) 16:41:24 INFO - PROCESS | 19650 | RSS: Main: 222363648 16:41:24 INFO - PROCESS | 19650 | 16:41:27 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial) 16:41:27 INFO - PROCESS | 19650 | RSS: Main: 222457856 16:41:27 INFO - PROCESS | 19650 | 16:41:29 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:41:29 INFO - PROCESS | 19650 | RSS: Main: 211345408 16:41:29 INFO - PROCESS | 19650 | 16:41:31 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:41:32 INFO - PROCESS | 19650 | RSS: Main: 216563712 16:41:32 INFO - PROCESS | 19650 | 16:41:34 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:41:35 INFO - PROCESS | 19650 | RSS: Main: 211968000 16:41:35 INFO - PROCESS | 19650 | 16:41:38 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:41:38 INFO - PROCESS | 19650 | RSS: Main: 226947072 16:41:38 INFO - PROCESS | 19650 | 16:41:41 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:41:42 INFO - PROCESS | 19650 | RSS: Main: 225873920 16:41:42 INFO - PROCESS | 19650 | 16:41:43 INFO - PROCESS | 19650 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49446/tests/kraken/driver.html?testName=ai-astar) 16:41:44 INFO - PROCESS | 19650 | RSS: Main: 267706368 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | __start_tp_report 16:41:44 INFO - PROCESS | 19650 | _x_x_mozilla_page_load 16:41:44 INFO - PROCESS | 19650 | _x_x_mozilla_page_load_details 16:41:44 INFO - PROCESS | 19650 | |i|pagename|runs| 16:41:44 INFO - PROCESS | 19650 | |0;ai-astar;105;114;114;120;113;113;113;113;113;112 16:41:44 INFO - PROCESS | 19650 | |1;audio-beat-detection;149;121;124;168;122;126;159;128;194;125 16:41:44 INFO - PROCESS | 19650 | |2;audio-dft;161;160;162;160;162;163;162;160;162;162 16:41:44 INFO - PROCESS | 19650 | |3;audio-fft;75;75;78;114;76;76;75;75;105;75 16:41:44 INFO - PROCESS | 19650 | |4;audio-oscillator;85;84;122;86;86;86;108;84;85;130 16:41:44 INFO - PROCESS | 19650 | |5;imaging-gaussian-blur;116;116;117;117;117;116;117;117;117;117 16:41:44 INFO - PROCESS | 19650 | |6;imaging-darkroom;147;146;147;146;146;147;147;146;145;146 16:41:44 INFO - PROCESS | 19650 | |7;imaging-desaturate;94;95;94;95;95;94;95;95;95;95 16:41:44 INFO - PROCESS | 19650 | |8;json-parse-financial;65;63;64;63;65;71;65;64;64;64 16:41:44 INFO - PROCESS | 19650 | |9;json-stringify-tinderbox;66;66;66;65;68;66;65;67;66;65 16:41:44 INFO - PROCESS | 19650 | |10;stanford-crypto-aes;79;79;82;85;79;78;78;78;79;81 16:41:44 INFO - PROCESS | 19650 | |11;stanford-crypto-ccm;144;128;137;137;126;134;139;131;174;132 16:41:44 INFO - PROCESS | 19650 | |12;stanford-crypto-pbkdf2;171;171;169;164;165;165;162;165;165;164 16:41:44 INFO - PROCESS | 19650 | |13;stanford-crypto-sha256-iterative;70;67;69;66;70;65;65;65;65;65 16:41:44 INFO - PROCESS | 19650 | __end_tp_report 16:41:44 INFO - PROCESS | 19650 | __start_cc_report 16:41:44 INFO - PROCESS | 19650 | _x_x_mozilla_cycle_collect,1921 16:41:44 INFO - PROCESS | 19650 | __end_cc_report 16:41:44 INFO - PROCESS | 19650 | __startTimestamp1468626104091__endTimestamp 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | ------- Summary: start ------- 16:41:44 INFO - PROCESS | 19650 | Number of tests: 14 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:3.59 (3.2%) stddev-sans-first:2.37 16:41:44 INFO - PROCESS | 19650 | Values: 105.0 114.0 114.0 120.0 113.0 113.0 113.0 113.0 113.0 112.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:138.50 stddev:24.99 (18.0%) stddev-sans-first:26.37 16:41:44 INFO - PROCESS | 19650 | Values: 149.0 121.0 124.0 168.0 122.0 126.0 159.0 128.0 194.0 125.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 16:41:44 INFO - PROCESS | 19650 | Values: 161.0 160.0 162.0 160.0 162.0 163.0 162.0 160.0 162.0 162.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:14.47 (19.3%) stddev-sans-first:15.10 16:41:44 INFO - PROCESS | 19650 | Values: 75.0 75.0 78.0 114.0 76.0 76.0 75.0 75.0 105.0 75.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:85.00 stddev:17.65 (20.8%) stddev-sans-first:18.30 16:41:44 INFO - PROCESS | 19650 | Values: 85.0 84.0 122.0 86.0 86.0 86.0 108.0 84.0 85.0 130.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:41:44 INFO - PROCESS | 19650 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 16:41:44 INFO - PROCESS | 19650 | Values: 147.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 145.0 146.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 16:41:44 INFO - PROCESS | 19650 | Values: 94.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:64.50 stddev:2.30 (3.6%) stddev-sans-first:2.44 16:41:44 INFO - PROCESS | 19650 | Values: 65.0 63.0 64.0 63.0 65.0 71.0 65.0 64.0 64.0 64.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 16:41:44 INFO - PROCESS | 19650 | Values: 66.0 66.0 66.0 65.0 68.0 66.0 65.0 67.0 66.0 65.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:2.25 (2.8%) stddev-sans-first:2.37 16:41:44 INFO - PROCESS | 19650 | Values: 79.0 79.0 82.0 85.0 79.0 78.0 78.0 78.0 79.0 81.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:137.00 stddev:13.66 (10.0%) stddev-sans-first:14.33 16:41:44 INFO - PROCESS | 19650 | Values: 144.0 128.0 137.0 137.0 126.0 134.0 139.0 131.0 174.0 132.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:165.00 stddev:3.11 (1.9%) stddev-sans-first:2.74 16:41:44 INFO - PROCESS | 19650 | Values: 171.0 171.0 169.0 164.0 165.0 165.0 162.0 165.0 165.0 164.0 16:41:44 INFO - PROCESS | 19650 | 16:41:44 INFO - PROCESS | 19650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:2.16 (3.3%) stddev-sans-first:1.94 16:41:44 INFO - PROCESS | 19650 | Values: 70.0 67.0 69.0 66.0 70.0 65.0 65.0 65.0 65.0 65.0 16:41:44 INFO - PROCESS | 19650 | -------- Summary: end -------- 16:41:44 INFO - PROCESS | 19650 | 16:41:45 INFO - TEST-INFO | 19650: exit 0 16:41:45 INFO - TEST-OK | kraken | took 63842ms 16:41:45 INFO - SUITE-END | took 1183s 16:41:45 INFO - Completed test suite (00:19:43) 16:41:45 INFO - javascript benchmark 16:41:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2636.9736302636966, 2645.9735402645974, 2666.466706658669, 2672.1182009936724, 2691.0, 4476.239039363308, 4532.410786597738, 4754.477007529169, 4782.163121453749, 4799.880002999922, 4703.835365762199, 4757.738324392157, 4774.594159496447, 4793.065352256312, 4829.372181616393, 4153.148604536078, 4335.804888780004, 4433.2241857674935, 4456.041950980539, 4459.0190158165315, 4407.625351845097, 4432.246518091933, 4439.511653718088, 4463.196624607569, 4477.037436951055, 4299.075698724773, 4327.7619730914785, 4349.956500434987, 4371.256886329332, 4399.38408622793, 4325.891852703676, 4337.50118736343, 4378.6934914555995, 4379.846705365297, 4385.912281754379, 4370.2570563004365, 4378.9562104378865, 4382.539833317488, 4388.978055109736, 4406.140802543506, 4636.049609830011, 4742.217534106902, 4874.780634871439, 4883.853484395474, 4887.266909963534, 5223.608229382773, 5225.163973764217, 5232.372115346145, 5239.3712754469325, 5251.159814429673, 4958.504149585049, 5262.552683021948, 5262.710550919698, 5264.973675131638, 5272.103742363807, 4749.121412538657, 4793.808247670054, 4794.976025119852, 4845.733484658307, 4860.635452341088, 5339.41266460689, 5351.866203344947, 5369.0, 5370.248165256867, 5383.623146379716, 4803.231482962779, 4968.6025117990475, 5037.319961805183, 5055.115354812893, 5107.668001579886, 5106.565941894981, 5129.281900533928, 5147.176451767699, 5155.407128180307, 5202.453742357074, 5076.390833100052, 5091.821786237538, 5095.668781529189, 5102.260172275073, 5144.562712169508, 4876.878078048077, 5006.674566153189, 5044.344235249394, 5046.470120637354, 5049.899002020013, 4414.227510185705, 4420.646348292129, 4442.044960333479, 4453.999999999935, 4463.464384273908, 3844.7116466264583, 3849.1339448623835, 3862.8841134766003, 3864.6521813036397, 3881.86413475527, 4205.516365617932, 4274.957250427456, 4431.601155895984, 4489.618382437529, 4496.437945256843, 5002.474740152305, 5035.093683137071, 5061.645684802103, 5094.745262736848, 5105.846824595268, 4318.546552611993, 4325.740455572675, 4327.740335579875, 4332.241857674894, 4367.170237654836, 4505.0, 4548.476925153584, 4559.52125026874, 4564.475085365159, 4605.171069207575, 4785.545373189541, 4818.6626936114135, 4847.878803029953, 4856.854294371175, 4875.098106850244, 5155.046316431472, 5175.560077393464, 5176.171812509981, 5181.222816577543, 5224.085784987688, 4601.792919318572, 4737.431508219036, 4865.1242776300605, 4874.098291816025, 4929.038837426668, 4987.975060124676, 5014.498550144956, 5039.319691841554, 5045.167547354646, 5086.949130508796, 5112.565431938178, 5114.2840002400435, 5120.283160357628, 5124.333836601218, 5166.819161329336, 4882.6826256293225, 4902.068606964665, 4951.257311403318, 4987.276844857548, 4987.276844857548, 4950.8762280943265, 4958.033183529322, 4979.3526841510375, 4980.576650984707, 5008.023435430056, 4880.755962201804, 4890.657653964188, 4911.287863259736, 4915.3855768029, 4931.235658472942, 4889.731064791328, 4963.280324353021, 5011.123053465556, 5049.0, 5060.645754797129, 4938.234573641092, 4952.430470495795, 4955.0337684151245, 4960.47914968923, 5017.473165317735], "value": 4697.0426291341055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.5877397660672, 580.814139475368, 587.944145306196, 592.2715060475614, 594.4174708785393, 27011.864940675292, 40080.79959600201, 41185.17629647413, 41210.58789412105, 44414.555854441445, 40787.59212407875, 41255.0, 41298.174036519325, 41334.79332603333, 41343.58656413435, 7332.963335183316, 7767.029121359844, 7898.170692077335, 7906.604669766518, 7920.0, 7502.849943001137, 7589.620518974029, 7591.544507329578, 9944.40333579984, 10224.386536807779, 10093.89906100937, 10122.949385253063, 10136.797264054714, 10145.44200068996, 10155.543000564992, 10153.796924061515, 10198.592056317739, 10213.948930255376, 10215.591376344937, 10219.029192226726, 7344.596047217401, 7541.170471248159, 7555.659995300225, 9751.90248097517, 10147.289689721723, 38930.22139557221, 39343.803280983695, 39391.8030409849, 39400.80299598513, 39441.40837887434, 3018.5321275202377, 3221.2269055426764, 3382.746294027958, 3684.4657524658774, 3812.618738126193, 29419.558706619417, 29596.260093497618, 30012.8499357504, 30459.23851903698, 31221.8438907804, 3541.982290088533, 3547.5742910850863, 3569.232614987764, 3590.874319398835, 3604.8377822998027, 189892.0, 190074.0, 190170.0, 190593.0, 190818.0, 53597.0, 54035.13683768401, 54073.459265407626, 54075.0, 54080.459195407544, 56795.0, 56880.43119568751, 56919.43080569224, 56944.430555694744, 56965.43034569684, 15201.391944322324, 15793.526194214191, 15794.131322777357, 15794.60513487149, 15797.052176869425, 10188.643397481053, 11971.52113915451, 12075.09436792244, 12081.75836483283, 12090.395480225954, 15527.844721552865, 15853.128077955824, 15952.92023539898, 16278.918605406898, 16510.6697866042, 5984.790532331434, 6023.283364241258, 6135.969320153371, 6229.190205273286, 6230.314665386803, 805.7300804230531, 862.5083702289635, 886.7368085445622, 901.5131828812367, 907.4464576608264, 27880.860595696893, 28219.43561128807, 28534.42931141407, 28636.570451443244, 28731.42537149287, 944.0983860413289, 965.9420434773936, 972.1833659725864, 1002.0330381182049, 1047.3715770537617, 14517.927410362881, 14923.925380373028, 14990.700185996218, 15361.923190383977, 15416.229188540752, 391746.0, 412359.0, 415976.0, 416326.0, 641258.0, 49844.25233621499, 50074.24888626674, 50286.748566257666, 50329.74835125801, 50394.244086338, 1062.8458873463305, 1209.4738788626976, 1226.6871947653292, 1227.4169769359662, 1255.7802384582662, 25654.0, 28552.714472855838, 29944.401111977204, 30070.87529240433, 30422.543661844647, 44264.0, 45168.32247516224, 45180.774096130626, 45259.09481810411, 45265.773671131436, 346713.0, 354231.0, 354958.0, 355357.0, 357141.0, 33385.16537086592, 39194.608053919095, 39410.40884386679, 39921.60078399179, 40016.0, 42241.57758422376, 42784.5721542793, 42960.57039429565, 43419.65803419759, 43455.0, 36230.4565431524, 36681.0, 37073.814630926674, 37758.0, 38130.8093459542, 36396.27207455889, 37075.258494830494, 37223.62776372202, 37228.813855930544, 37783.81108094442, 36134.27731445409, 36800.63199368079, 36852.447213292355, 36982.8150859244, 37030.44454333133, 33394.16514587155, 33483.330333393686, 33904.66095339015, 34087.0, 34204.48693269553, 24708.87284919117, 25177.0, 25397.365065873502, 25595.10417135466, 25630.743692562835], "value": 18292.76321513115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.9939576739525, 383.3043026906165, 383.6930455635493, 396.59547261792954, 400.83365403357607, 15945.441909533169, 17368.739468907977, 17994.190261438234, 18172.27363400478, 18214.362497312595, 17094.487165385028, 17443.563910902234, 18046.18792154353, 18365.0, 18389.172487238073, 5129.615278854082, 5191.818286359978, 5300.443453437391, 5338.813141540047, 5338.839834804962, 3717.5910649828497, 3854.6338097880657, 4712.481627021025, 6160.969195154041, 6163.876722465548, 5997.820065398045, 6098.298695650013, 6100.176476175726, 6123.081537769326, 6138.017917133259, 5862.472377486026, 6088.512918966472, 6093.146959425706, 6105.053716673922, 6110.0, 5186.974065129651, 5352.0, 5356.625036247464, 5363.115086010804, 5462.481064298925, 21937.670934935988, 22057.779422205735, 22188.88905555478, 22199.11203551856, 22208.444788880246, 2021.191523390641, 2039.7552293724848, 2169.6962425260476, 2187.6062308784412, 2576.1498705427166, 13756.65608359799, 14369.0, 14430.927845360811, 14512.782308265385, 14661.41354345825, 1915.3870761356372, 1923.0, 1952.8730632508984, 1991.32295019694, 2009.4273132157412, 58572.0, 69680.6515967422, 72139.2786072143, 83795.58102209511, 85088.0, 16307.184640767915, 16636.50090497287, 16988.830111698724, 17015.489535313714, 17114.657706846043, 29735.702642973727, 29837.254068648457, 30111.69888301089, 30156.0, 30191.849040754656, 9278.350515463853, 9590.42457452555, 9659.565319560637, 9692.806143877084, 9784.651683595886, 7975.680972761141, 8011.799705007422, 8159.347252219808, 8192.221738934792, 8197.344212463107, 8500.404971651926, 8614.612342444605, 8704.303655707528, 8784.341174411944, 8906.910930890608, 2640.240983083313, 3034.734735234367, 3209.5667084943234, 3218.8873389431724, 3405.540252065989, 803.5058439059936, 853.9743807685779, 887.5296093070683, 905.8550631898864, 913.3515204205089, 17223.827761722474, 17837.197326120357, 17921.641567168845, 18040.368587099387, 18362.724559131624, 874.9343799215085, 882.2809410330689, 911.129870973219, 911.1344222988188, 915.7985243246475, 9882.752931176778, 10259.127974122282, 10276.17790576767, 10292.330998485075, 10400.0, 407723.0, 408101.0, 422420.0, 423413.0, 423888.0, 48672.75663621659, 49708.254376184384, 58227.70886145542, 59238.0, 74748.87876681854, 883.7525492862009, 894.8478758610921, 900.4552245891141, 914.3599480363772, 916.1617120334819, 15879.206039697738, 16134.515964521082, 16151.384861514225, 16275.918620406823, 16277.674446510766, 27335.72664273332, 29964.0, 39617.80191099142, 40998.9750256246, 41041.79479102585, 49172.26241606451, 52642.73678631582, 52752.0, 53173.20240196323, 54625.45374546204, 7909.9209007909185, 7933.722319718782, 7992.720254791055, 8075.87886181696, 8225.177482251727, 6267.874642507033, 8915.0, 9442.716718498456, 10143.340682855885, 11830.231034982698, 10132.34139780912, 10914.945425273141, 10960.232783705063, 10976.725581860517, 11169.273997190157, 9969.35199212049, 10366.792664146826, 10581.153507719519, 10766.03105720489, 10841.295315804447, 11102.167337449562, 11129.387883666152, 11225.71935701614, 11243.10055195566, 11524.59663911759, 6987.545809522365, 7022.754203602849, 7246.96376518114, 8129.75610731679, 8380.916190838014, 10821.350718956888, 10829.29609575375, 10844.240903136704, 10868.94565527199, 11052.502637381176], "value": 9201.831846818179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.3999700134937, 1391.6242614494086, 1449.9420023199073, 1685.949421517354, 1689.9915500422496, 7787.338076263517, 8202.097769245378, 8242.381821363391, 8318.625661845217, 8328.083910769818, 6925.722971081151, 7170.533915295502, 7807.063152421703, 8032.277095061443, 8149.225823546768, 2211.7345918489805, 2240.7535171131162, 2241.0363543676212, 2242.854214476054, 2245.3264020793695, 2164.9783502164933, 2188.9343319700433, 2280.9885950570224, 2282.167009041696, 2286.1655495744017, 1907.122723547163, 1980.7623085229793, 1981.5937732764748, 1992.023908284937, 2083.322920050982, 2216.390492614528, 2234.474898398867, 2261.5137745384823, 2263.1739415113366, 2264.5810525052916, 2230.877301748403, 2237.2057919438525, 2246.7416247131628, 2248.303026061926, 2248.6177349850564, 8034.678612855537, 8040.316573091294, 8049.436539442183, 8095.3523718103, 8106.554139522383, 4715.24556071027, 4747.976260118678, 4755.286706993978, 4759.78580963854, 4920.975395123037, 2872.9856350718323, 2909.6944820793724, 2957.8668959896854, 2973.568832519294, 2980.672126066131, 895.2435192262528, 916.0656130746668, 924.6024209589874, 924.6717415317532, 931.2131249094572, 11098.223124381297, 11313.20807543464, 11351.772964540745, 11356.148288878367, 11400.942995285053, 4178.6657067434535, 4189.350650649154, 4357.673174511924, 4357.694961352675, 4441.68908176431, 9604.855927161097, 9611.807763844685, 9627.470489123025, 9629.807403852024, 9652.034796520433, 4873.756312184377, 4876.439209490883, 4882.6826256293225, 4883.218685010382, 4883.584895283941, 4217.536071032184, 4270.188664153801, 4272.46594175728, 4276.5723427656985, 4285.7642829644665, 3593.460980852893, 3623.655752703489, 3663.9084022899106, 3698.093966978108, 3728.0, 1061.0105951901473, 1075.3762817565794, 1084.4631907205826, 1086.1365214654368, 1086.32104934416, 820.8522465956066, 840.2521755637488, 847.7531056055293, 862.3015357560271, 868.0104680663921, 5107.336046313955, 5134.358205224347, 5172.9741351293005, 5290.418054014055, 5412.161115027177, 416.5917400947089, 420.6130360068683, 421.51104718526364, 423.7881059470265, 426.44775016353896, 3505.8948231553095, 3648.3250598638806, 3681.5766186888313, 3706.4069748840056, 3754.718396120302, 9756.317057806027, 10118.696439106692, 10256.743581410525, 10288.074073333435, 10298.845517317246, 9420.811583768424, 10049.799004019878, 10069.899301007043, 10089.899101008896, 10155.847662285072, 7252.528585642022, 7565.205653406423, 7627.771166865003, 7631.046119235096, 7646.502977306457, 5357.410684824588, 5660.264165658603, 5666.688332141607, 5681.829545113653, 5904.202932604044, 8935.597898094471, 9257.028012058638, 9265.305102117503, 9341.813163736822, 9518.095780900803, 9311.76720581991, 9514.286428518028, 9646.855297170408, 9723.56243969009, 9801.362911410735, 6655.334466553306, 6963.6866341013765, 6984.4063254623925, 7045.11936007999, 7199.0, 7968.243016913384, 7974.40191985615, 8013.639386227517, 8030.919690803017, 8068.394870384862, 6971.895421568579, 7175.964120179366, 7210.423166146591, 7218.097737782778, 7392.186859445349, 6868.416184624363, 6960.651967401711, 6984.371406573229, 7070.36366726997, 7167.892481612885, 6392.328805475544, 6408.391202835724, 6510.511711621552, 6516.771912983023, 6521.869562608626, 4850.708957462563, 4897.216445368724, 4912.729799861042, 4943.382077240345, 4974.776135073858, 4751.144793937125, 4757.904841903073, 4778.976105119452, 4795.616350692006, 4850.296706977398], "value": 4171.13795478054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.1295340662628, 313.6220853871084, 316.1085738218226, 316.14955768981446, 317.04095112285336, 4940.209566469365, 4955.231939049444, 4962.454130045692, 4965.535167125698, 4974.726390048546, 4825.372701548802, 4838.9032219355595, 4922.704637721739, 4931.926021109686, 4938.592501137176, 3644.908877278069, 3750.831212595426, 3770.905727356824, 3867.845286188549, 3921.745086569364, 3855.5951625079383, 3880.8641697540593, 3885.9611403886024, 3888.2612303662354, 3895.3183192941265, 3670.724695647824, 3833.8083095845236, 3839.9232015359676, 3860.9227815443674, 3867.0719027433356, 3615.788710781865, 3791.3365161096835, 3800.866969656063, 3806.505154329947, 3844.519435070616, 3402.387570237356, 3406.938802024789, 3408.454647256427, 3409.511056928456, 3457.2739724657854, 4709.458412282563, 4732.100900828831, 4732.95267047332, 4737.455192652855, 4785.68893021956, 4277.2663294589465, 4417.779111044435, 4422.3366495026, 4454.643628509712, 4464.419625448703, 5045.8738531537, 5076.111680455905, 5097.980403919231, 5101.107306221396, 5110.335656364686, 3745.700343972476, 3757.651467832605, 3763.34141525232, 3772.5472943246987, 3772.981135094307, 5211.765470553834, 5307.840764777063, 5337.653052551611, 5381.434949330303, 5388.703621300827, 3842.3275926712713, 3852.114013776847, 3861.575226725058, 3866.149447121629, 3872.2642697887313, 3880.553736320303, 3887.8833634990997, 3910.354791459434, 3921.039345360405, 3922.3920292354733, 4336.197235711617, 4411.933820992687, 4412.0, 4423.977880110579, 4424.867253982386, 5021.14640511114, 5069.822556210515, 5075.670081444694, 5088.185890257541, 5109.6678715883345, 1323.8874695650977, 1343.147101590497, 1345.7577636025414, 1349.7705390083709, 1356.5552686388965, 847.9236868681849, 883.9955800221086, 887.5828360670474, 892.9910700892908, 896.3501461440403, 4307.267764480046, 4365.214261432972, 4445.843765773598, 4501.414816073955, 4507.639388848885, 5271.736413179326, 5287.894242115136, 5303.416624171338, 5329.546988505943, 5349.411564727877, 4456.598906098467, 4463.843765468193, 4469.41897553316, 4484.260097083945, 4506.459224893034, 2649.337665583604, 2809.4828792452254, 2818.210900947738, 2821.8730157142977, 2836.262571731365, 4946.1344264753525, 4948.8574755030095, 4981.551660350513, 4991.126552853236, 5005.72468514228, 5162.767675454614, 5366.2755528004, 5404.513593776501, 5426.8371948841595, 5450.400455949843, 4988.977259661688, 5012.691623528453, 5018.1218286800395, 5020.849374518771, 5025.346704928336, 5317.787288508571, 5338.825458399301, 5352.571794256528, 5363.597730170175, 5376.650517716336, 5070.974645126898, 5238.869028274324, 5251.054810134213, 5253.658512196695, 5265.315508983961, 4991.550760431579, 4998.0, 5004.249362595494, 5024.296598476143, 5029.64792464524, 3750.568684601306, 3782.9407765825613, 3784.8615532224203, 3795.089178597172, 3801.2967600993907, 5163.535281824654, 5352.143657014859, 5370.838874833761, 5388.434214407431, 5398.46015398457, 5120.308758317579, 5146.948530514647, 5157.0975079361715, 5162.457941916195, 5173.379194496534, 5138.4604616514725, 5141.922871156861, 5212.635115541876, 5215.869603259949, 5220.9738951305, 4905.0, 5094.243504839507, 5098.444974282879, 5128.897422051464, 5158.535731784157, 4964.553190212898, 5036.269740887624, 5226.843194704165, 5228.607854410859, 5238.371395432572, 5096.617753668415, 5101.285819985131, 5113.4375218726655, 5114.33513643224, 5121.590272778094], "value": 3897.1437292064415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.9167162433071, 623.4264476681452, 710.2400431538252, 728.1153398620675, 754.2796629219098, 12440.626781196568, 13279.535216267432, 13303.0, 13340.733185336287, 13427.798583021251, 13366.933165334183, 13546.525871594496, 13559.0, 14049.719005619882, 14119.882017697351, 635.1140159477526, 648.1023782061831, 758.9279018493235, 785.352084530262, 804.2279411764713, 791.6714563456187, 796.1361922314296, 799.2646764976207, 802.2058162419193, 802.4022103532876, 837.6900546797693, 840.3193413335218, 841.7264389073575, 841.7937605286713, 841.9284360829306, 657.464166704137, 679.4021261290034, 680.6936878404738, 683.3883674111643, 831.8544254755424, 568.528121659022, 589.8083122985004, 594.9613275137145, 595.1637948682109, 595.8838026584817, 12222.266664000099, 12222.449989750483, 12225.510979560808, 12242.571509997108, 12279.508819647204, 11880.227785193934, 12566.497340106385, 12577.245365278113, 12803.679908002281, 13068.281244531547, 11837.230580012272, 11923.0, 12001.759964800742, 12143.332116733576, 12442.440090195872, 4142.668586513102, 4181.351890456984, 4196.202721482909, 4238.237117318881, 4315.029118448356, 63383.36616633867, 63560.36439635591, 63793.68103159455, 65559.0, 66633.66683166602, 9579.856302155473, 9932.851007234898, 9949.701508954742, 10123.797524049478, 10252.0, 17376.478705638852, 17469.825301747074, 17496.300147994192, 17508.5622859427, 17539.29842806299, 6487.740490380426, 6594.340565943367, 6728.293529179464, 6781.321867813179, 6827.863442731117, 3669.8165091745304, 3715.331240376704, 3722.1253005543676, 3752.7185461090526, 3774.3960966245268, 2796.874140663675, 2859.5567687008547, 2883.4377296427415, 2895.739383455499, 2953.8375389353364, 483.3209340876075, 488.27003629573306, 492.70930151210956, 495.07421122501154, 496.7714851168503, 515.9716215608103, 518.9143791274473, 535.6464733276019, 536.085973415326, 545.6835035679296, 6944.930550694529, 6959.4432445404245, 7062.187848397397, 7275.236100209508, 7417.147028091838, 397.8488174493674, 430.35876543949917, 458.74081144153445, 499.91751361025024, 507.2366089035977, 3393.270446853938, 3479.582450105953, 3548.680618744274, 3603.837827297778, 3657.2868290683596, 96599.0, 96798.51600741952, 97719.0, 99581.50209249053, 100633.0, 81709.59145204235, 85338.0, 85437.57281213554, 85763.0, 86598.0, 75036.62481687666, 76485.61757191179, 77306.61346693231, 77416.61291693618, 77860.0, 6966.686499107451, 7022.929770702432, 7036.518388304484, 7074.610896400747, 7224.566526008456, 45079.7746011281, 46088.53911460842, 47640.761796190796, 48288.758556206994, 48778.512214877395, 67227.32772672211, 67401.0, 67513.66243168752, 68856.311436885, 69624.65187674029, 9356.251499879858, 11812.93683568483, 11904.940475297568, 11919.821202681793, 12401.441935112762, 11830.46762895689, 12866.292353920624, 13725.313734313444, 14044.0, 14483.058601190889, 10788.136949043901, 10809.513571889127, 11333.320000799979, 11346.319220846446, 11656.06751459898, 11730.76538469243, 11766.999805016669, 11861.5848445304, 11983.460744266704, 12447.377631118226, 10920.2901811382, 10967.616133435291, 11414.714632134263, 11425.428728563706, 11773.117016223647, 9736.513174341397, 10030.398176109456, 10203.38779673222, 10473.31923424975, 10581.62964296246, 9238.769030774285, 9620.13418792312, 9770.0, 9791.83266932271, 9988.300818942604], "value": 6477.144073182115, "unit": "score"}], "name": "dromaeo_css", "value": 6607.985017831659, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 114.0, 120.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 124.0, 168.0, 122.0, 126.0, 159.0, 128.0, 194.0, 125.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 160.0, 162.0, 163.0, 162.0, 160.0, 162.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 78.0, 114.0, 76.0, 76.0, 75.0, 75.0, 105.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 122.0, 86.0, 86.0, 86.0, 108.0, 84.0, 85.0, 130.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 65.0, 71.0, 65.0, 64.0, 64.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 68.0, 66.0, 65.0, 67.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 85.0, 79.0, 78.0, 78.0, 78.0, 79.0, 81.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 128.0, 137.0, 137.0, 126.0, 134.0, 139.0, 131.0, 174.0, 132.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 169.0, 164.0, 165.0, 165.0, 162.0, 165.0, 165.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 66.0, 70.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1533.3, "alertThreshold": 2.0}]} 16:41:45 INFO - Return code: 0 16:41:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:41:45 INFO - # TBPL SUCCESS # 16:41:45 INFO - Running post-action listener: _resource_record_post_action 16:41:45 INFO - ##### 16:41:45 INFO - ##### Finished run-tests step (success) 16:41:45 INFO - ##### 16:41:45 INFO - Running post-run listener: _resource_record_post_run 16:41:45 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 541687808; Read time: 8; Write time: 760276 16:41:45 INFO - TinderboxPrint: CPU usage
13.0% 16:41:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:41:45 INFO - TinderboxPrint: I/O write bytes / time
541,687,808 / 760,276 16:41:45 INFO - TinderboxPrint: CPU idle
8,332.3 (87.0%) 16:41:45 INFO - TinderboxPrint: CPU user
1,200.1 (12.5%) 16:41:45 INFO - TinderboxPrint: Swap in / out
0 / 0 16:41:45 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 257843200; Read time: 0; Write time: 422656 16:41:45 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283844608; Read time: 8; Write time: 337620 16:41:46 INFO - Running post-run listener: _upload_blobber_files 16:41:46 INFO - Blob upload gear active. 16:41:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:41:46 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 16:41:46 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:41:46 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:41:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:41:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:41:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:41:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:50 INFO - (blobuploader) - INFO - Done attempting. 16:41:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:41:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:41:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:51 INFO - (blobuploader) - INFO - Done attempting. 16:41:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:41:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:41:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:52 INFO - (blobuploader) - INFO - Done attempting. 16:41:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:41:52 INFO - Return code: 0 16:41:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:41:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:41:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a0e7c20fe8b0922e9fd78d8b77213903fb3bf98d6c7469cdabdc7bdc4c74e2afeaeda6e548366525bf728891026554b48cb1f74cfc34a044c3b71bfb6425cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7c5dbe9fa9506d1381da40954dba95f38ec81b95fa53aa63e15b2f9ee7502e50d7547a3715d18ca434c8d7efc75f6049a265b80dc9bfd2e8e7a3fc4633e0281b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/81617b851a8f48ec8cb3c6bcc587b38bfee248f6ee2a8bfbeb2c019a72e4446d589bae15112d837ee887b88126523a692653518cb53ee21cba34fd2431fab08f"} 16:41:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:41:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:41:52 INFO - Contents: 16:41:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a0e7c20fe8b0922e9fd78d8b77213903fb3bf98d6c7469cdabdc7bdc4c74e2afeaeda6e548366525bf728891026554b48cb1f74cfc34a044c3b71bfb6425cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7c5dbe9fa9506d1381da40954dba95f38ec81b95fa53aa63e15b2f9ee7502e50d7547a3715d18ca434c8d7efc75f6049a265b80dc9bfd2e8e7a3fc4633e0281b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/81617b851a8f48ec8cb3c6bcc587b38bfee248f6ee2a8bfbeb2c019a72e4446d589bae15112d837ee887b88126523a692653518cb53ee21cba34fd2431fab08f"} 16:41:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:41:52 INFO - Copying logs to upload dir... 16:41:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:41:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.230761 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-15 16:41:51.752502) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 16:41:51.755568) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a0e7c20fe8b0922e9fd78d8b77213903fb3bf98d6c7469cdabdc7bdc4c74e2afeaeda6e548366525bf728891026554b48cb1f74cfc34a044c3b71bfb6425cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7c5dbe9fa9506d1381da40954dba95f38ec81b95fa53aa63e15b2f9ee7502e50d7547a3715d18ca434c8d7efc75f6049a265b80dc9bfd2e8e7a3fc4633e0281b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/81617b851a8f48ec8cb3c6bcc587b38bfee248f6ee2a8bfbeb2c019a72e4446d589bae15112d837ee887b88126523a692653518cb53ee21cba34fd2431fab08f"} build_url:https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://queue.taskcluster.net/v1/task/PpQtgXvjQOm5JJN1vLVqYg/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/Autoland-Non-PGO/sha512/7a0e7c20fe8b0922e9fd78d8b77213903fb3bf98d6c7469cdabdc7bdc4c74e2afeaeda6e548366525bf728891026554b48cb1f74cfc34a044c3b71bfb6425cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7c5dbe9fa9506d1381da40954dba95f38ec81b95fa53aa63e15b2f9ee7502e50d7547a3715d18ca434c8d7efc75f6049a265b80dc9bfd2e8e7a3fc4633e0281b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/81617b851a8f48ec8cb3c6bcc587b38bfee248f6ee2a8bfbeb2c019a72e4446d589bae15112d837ee887b88126523a692653518cb53ee21cba34fd2431fab08f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 16:41:51.780057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:41:51.780362) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468624803.939038-1588701588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 16:41:51.865037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 16:41:51.865405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 16:41:51.865783) ========= ========= Total master_lag: 0.15 =========