builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1467649007.07 results: success (0) buildid: 20160704063826 builduid: e74b39d38e5449bdaf15971386cd8c8a revision: 128eff0a581ff318eb3629005a871d5095b4f430 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.068659) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.069291) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.069717) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.081281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.081548) ========= 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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-04 09:16:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 210M=0s 2016-07-04 09:16:47 (210 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114093 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.204043) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.204359) ========= 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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018803 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.253737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.254061) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 128eff0a581ff318eb3629005a871d5095b4f430 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 128eff0a581ff318eb3629005a871d5095b4f430 --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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-04 09:16:47,369 truncating revision to first 12 chars 2016-07-04 09:16:47,369 Setting DEBUG logging. 2016-07-04 09:16:47,369 attempt 1/10 2016-07-04 09:16:47,369 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/128eff0a581f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 09:16:47,669 unpacking tar archive at: mozilla-aurora-128eff0a581f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639406 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.907774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.908095) ========= script_repo_revision: 128eff0a581ff318eb3629005a871d5095b4f430 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.908501) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.908767) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 09:16:47.917683) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-04 09:16:47.917945) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:16:48 INFO - MultiFileLogger online at 20160704 09:16:48 in /builds/slave/test 09:16:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:16:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:48 INFO - {'append_to_log': False, 09:16:48 INFO - 'base_work_dir': '/builds/slave/test', 09:16:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:16:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:48 INFO - 'branch': 'Mozilla-Aurora', 09:16:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:48 INFO - 'config_files': ('talos/linux_config.py',), 09:16:48 INFO - 'default_actions': ('clobber', 09:16:48 INFO - 'read-buildbot-config', 09:16:48 INFO - 'download-and-extract', 09:16:48 INFO - 'populate-webroot', 09:16:48 INFO - 'create-virtualenv', 09:16:48 INFO - 'install', 09:16:48 INFO - 'run-tests'), 09:16:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:48 INFO - 'download_minidump_stackwalk': True, 09:16:48 INFO - 'download_symbols': 'ondemand', 09:16:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:48 INFO - 'installer_path': 'installer.exe', 09:16:48 INFO - 'log_level': 'info', 09:16:48 INFO - 'log_name': 'talos', 09:16:48 INFO - 'log_to_console': True, 09:16:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:16:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:16:48 INFO - 'opt_config_files': (), 09:16:48 INFO - 'pip_index': False, 09:16:48 INFO - 'sps_profile': False, 09:16:48 INFO - 'sps_profile_interval': 0, 09:16:48 INFO - 'suite': 'dromaeojs', 09:16:48 INFO - 'system_bits': '32', 09:16:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:48 INFO - 'title': 'talos-linux64-ix-001', 09:16:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:48 INFO - 'use_talos_json': True, 09:16:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:48 INFO - 'work_dir': 'build'} 09:16:48 INFO - ##### 09:16:48 INFO - ##### Running clobber step. 09:16:48 INFO - ##### 09:16:48 INFO - Running pre-action listener: _resource_record_pre_action 09:16:48 INFO - Running main action method: clobber 09:16:48 INFO - rmtree: /builds/slave/test/build 09:16:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:49 INFO - Running post-action listener: _resource_record_post_action 09:16:49 INFO - ##### 09:16:49 INFO - ##### Finished clobber step (success) 09:16:49 INFO - ##### 09:16:49 INFO - ##### 09:16:49 INFO - ##### Running read-buildbot-config step. 09:16:49 INFO - ##### 09:16:49 INFO - Running pre-action listener: _resource_record_pre_action 09:16:49 INFO - Running main action method: read_buildbot_config 09:16:49 INFO - Using buildbot properties: 09:16:49 INFO - { 09:16:49 INFO - "project": "", 09:16:49 INFO - "product": "firefox", 09:16:49 INFO - "script_repo_revision": "production", 09:16:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:16:49 INFO - "repository": "", 09:16:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:16:49 INFO - "buildid": "20160704063826", 09:16:49 INFO - "pgo_build": "True", 09:16:49 INFO - "basedir": "/builds/slave/test", 09:16:49 INFO - "buildnumber": 404, 09:16:49 INFO - "slavename": "talos-linux64-ix-001", 09:16:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:16:49 INFO - "platform": "ubuntu64_hw", 09:16:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:16:49 INFO - "branch": "mozilla-aurora", 09:16:49 INFO - "repo_path": "releases/mozilla-aurora", 09:16:49 INFO - "revision": "128eff0a581ff318eb3629005a871d5095b4f430", 09:16:49 INFO - "stage_platform": "linux64-pgo", 09:16:49 INFO - "builduid": "e74b39d38e5449bdaf15971386cd8c8a", 09:16:49 INFO - "slavebuilddir": "test" 09:16:49 INFO - } 09:16:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:16:49 INFO - Running post-action listener: _resource_record_post_action 09:16:49 INFO - ##### 09:16:49 INFO - ##### Finished read-buildbot-config step (success) 09:16:49 INFO - ##### 09:16:49 INFO - ##### 09:16:49 INFO - ##### Running download-and-extract step. 09:16:49 INFO - ##### 09:16:49 INFO - Running pre-action listener: _resource_record_pre_action 09:16:49 INFO - Running main action method: download_and_extract 09:16:49 INFO - mkdir: /builds/slave/test/build/tests 09:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:49 INFO - https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:16:49 INFO - Downloaded 1361 bytes. 09:16:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:16:49 INFO - Using the following test package requirements: 09:16:49 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:16:49 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:16:49 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:16:49 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'jsshell-linux-x86_64.zip'], 09:16:49 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:16:49 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:16:49 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:16:49 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:16:49 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:16:49 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:16:49 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:16:49 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:49 INFO - https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:50 INFO - Downloaded 24526414 bytes. 09:16:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:16:52 INFO - Return code: 0 09:16:52 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:16:52 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')]} 09:16:52 INFO - https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:16:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:53 INFO - Downloaded 24526414 bytes. 09:16:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:16:54 INFO - Return code: 0 09:16:54 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')]} 09:16:54 INFO - https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:16:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:16:55 INFO - Downloaded 10833369 bytes. 09:16:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:16:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:16:55 INFO - Return code: 0 09:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:55 INFO - https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:16:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:16:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:16:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:16:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:16:57 INFO - Downloaded 63623518 bytes. 09:16:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:16:57 INFO - mkdir: /builds/slave/test/properties 09:16:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:57 INFO - Contents: 09:16:57 INFO - build_url:https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:16:58 INFO - Running post-action listener: _resource_record_post_action 09:16:58 INFO - Running post-action listener: set_extra_try_arguments 09:16:58 INFO - ##### 09:16:58 INFO - ##### Finished download-and-extract step (success) 09:16:58 INFO - ##### 09:16:58 INFO - ##### 09:16:58 INFO - ##### Running populate-webroot step. 09:16:58 INFO - ##### 09:16:58 INFO - Running pre-action listener: _resource_record_pre_action 09:16:58 INFO - Running main action method: populate_webroot 09:16:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:58 INFO - '--tppagecycles', 09:16:58 INFO - '7'], 09:16:58 INFO - 'tests': ['tsvgm']}}, 09:16:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['damp', 'tps']}, 09:16:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'tests': ['damp', 'tps']}, 09:16:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['dromaeo_dom']}, 09:16:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['a11yr', 09:16:58 INFO - 'ts_paint', 09:16:58 INFO - 'tpaint', 09:16:58 INFO - 'sessionrestore', 09:16:58 INFO - 'sessionrestore_no_auto_restore', 09:16:58 INFO - 'tabpaint']}, 09:16:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['tsvgx', 09:16:58 INFO - 'tsvgr_opacity', 09:16:58 INFO - 'tart', 09:16:58 INFO - 'tscrollx', 09:16:58 INFO - 'cart']}, 09:16:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:58 INFO - 'tsvgr_opacity', 09:16:58 INFO - 'tart', 09:16:58 INFO - 'tscrollx', 09:16:58 INFO - 'cart']}, 09:16:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'talos_options': ['--disable-e10s'], 09:16:58 INFO - 'tests': ['tp5o']}, 09:16:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'tests': ['tp5o']}, 09:16:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'talos_options': ['--disable-e10s', 09:16:58 INFO - '--xperf_path', 09:16:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:58 INFO - 'tests': ['tp5n']}, 09:16:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:58 INFO - 'talos_options': ['--xperf_path', 09:16:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:58 INFO - 'tests': ['tp5n']}}, 09:16:58 INFO - 'talos.zip': {'path': '', 09:16:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:58 INFO - Running post-action listener: _resource_record_post_action 09:16:58 INFO - ##### 09:16:58 INFO - ##### Finished populate-webroot step (success) 09:16:58 INFO - ##### 09:16:58 INFO - ##### 09:16:58 INFO - ##### Running create-virtualenv step. 09:16:58 INFO - ##### 09:16:58 INFO - Running pre-action listener: _resource_record_pre_action 09:16:58 INFO - Running main action method: create_virtualenv 09:16:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:58 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 09:16:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:58 INFO - Using real prefix '/usr' 09:16:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:00 INFO - Installing pip.................done. 09:17:00 INFO - Return code: 0 09:17:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:00 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 09:17:00 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 09:17:00 INFO - Using env: {'DISPLAY': ':0', 09:17:00 INFO - 'HOME': '/home/cltbld', 09:17:00 INFO - 'LANG': 'en_US.UTF-8', 09:17:00 INFO - 'LANGUAGE': 'en_US:en', 09:17:00 INFO - 'LOGNAME': 'cltbld', 09:17:00 INFO - 'MAIL': '/var/mail/cltbld', 09:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:00 INFO - 'MOZ_NO_REMOTE': '1', 09:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:00 INFO - 'NO_EM_RESTART': '1', 09:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:00 INFO - 'PWD': '/builds/slave/test', 09:17:00 INFO - 'SHELL': '/bin/bash', 09:17:00 INFO - 'SHLVL': '1', 09:17:00 INFO - 'TERM': 'linux', 09:17:00 INFO - 'TMOUT': '86400', 09:17:00 INFO - 'USER': 'cltbld', 09:17:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:00 INFO - '_': '/tools/buildbot/bin/python'} 09:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:00 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 09:17:00 INFO - Cleaning up... 09:17:00 INFO - Return code: 0 09:17:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:00 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 09:17:00 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 09:17:00 INFO - Using env: {'DISPLAY': ':0', 09:17:00 INFO - 'HOME': '/home/cltbld', 09:17:00 INFO - 'LANG': 'en_US.UTF-8', 09:17:00 INFO - 'LANGUAGE': 'en_US:en', 09:17:00 INFO - 'LOGNAME': 'cltbld', 09:17:00 INFO - 'MAIL': '/var/mail/cltbld', 09:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:00 INFO - 'MOZ_NO_REMOTE': '1', 09:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:00 INFO - 'NO_EM_RESTART': '1', 09:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:00 INFO - 'PWD': '/builds/slave/test', 09:17:00 INFO - 'SHELL': '/bin/bash', 09:17:00 INFO - 'SHLVL': '1', 09:17:00 INFO - 'TERM': 'linux', 09:17:00 INFO - 'TMOUT': '86400', 09:17:00 INFO - 'USER': 'cltbld', 09:17:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:00 INFO - '_': '/tools/buildbot/bin/python'} 09:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:01 INFO - Downloading/unpacking psutil>=3.1.1 09:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:03 INFO - Installing collected packages: psutil 09:17:03 INFO - Running setup.py install for psutil 09:17:03 INFO - building 'psutil._psutil_linux' extension 09:17:03 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 09:17:03 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 09:17:03 INFO - building 'psutil._psutil_posix' extension 09:17:03 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 09:17:03 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 09:17:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:03 INFO - Successfully installed psutil 09:17:03 INFO - Cleaning up... 09:17:03 INFO - Return code: 0 09:17:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:17:03 INFO - Using env: {'DISPLAY': ':0', 09:17:03 INFO - 'HOME': '/home/cltbld', 09:17:03 INFO - 'LANG': 'en_US.UTF-8', 09:17:03 INFO - 'LANGUAGE': 'en_US:en', 09:17:03 INFO - 'LOGNAME': 'cltbld', 09:17:03 INFO - 'MAIL': '/var/mail/cltbld', 09:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:03 INFO - 'MOZ_NO_REMOTE': '1', 09:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:03 INFO - 'NO_EM_RESTART': '1', 09:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:03 INFO - 'PWD': '/builds/slave/test', 09:17:03 INFO - 'SHELL': '/bin/bash', 09:17:03 INFO - 'SHLVL': '1', 09:17:03 INFO - 'TERM': 'linux', 09:17:03 INFO - 'TMOUT': '86400', 09:17:03 INFO - 'USER': 'cltbld', 09:17:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:03 INFO - '_': '/tools/buildbot/bin/python'} 09:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:05 INFO - Installing collected packages: mozsystemmonitor 09:17:05 INFO - Running setup.py install for mozsystemmonitor 09:17:05 INFO - Successfully installed mozsystemmonitor 09:17:05 INFO - Cleaning up... 09:17:05 INFO - Return code: 0 09:17:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:05 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')]} 09:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:05 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')]} 09:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:05 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:05 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 09:17:05 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 09:17:05 INFO - Using env: {'DISPLAY': ':0', 09:17:05 INFO - 'HOME': '/home/cltbld', 09:17:05 INFO - 'LANG': 'en_US.UTF-8', 09:17:05 INFO - 'LANGUAGE': 'en_US:en', 09:17:05 INFO - 'LOGNAME': 'cltbld', 09:17:05 INFO - 'MAIL': '/var/mail/cltbld', 09:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:05 INFO - 'MOZ_NO_REMOTE': '1', 09:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:05 INFO - 'NO_EM_RESTART': '1', 09:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:05 INFO - 'PWD': '/builds/slave/test', 09:17:05 INFO - 'SHELL': '/bin/bash', 09:17:05 INFO - 'SHLVL': '1', 09:17:05 INFO - 'TERM': 'linux', 09:17:05 INFO - 'TMOUT': '86400', 09:17:05 INFO - 'USER': 'cltbld', 09:17:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:05 INFO - '_': '/tools/buildbot/bin/python'} 09:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:08 INFO - warning: no files found matching '*.txt' 09:17:08 INFO - no previously-included directories found matching 'build' 09:17:08 INFO - no previously-included directories found matching 'dist' 09:17:08 INFO - no previously-included directories found matching '.git*' 09:17:08 INFO - Installing collected packages: jsonschema, functools32 09:17:08 INFO - Running setup.py install for functools32 09:17:08 INFO - warning: no files found matching '*.txt' 09:17:08 INFO - no previously-included directories found matching 'build' 09:17:08 INFO - no previously-included directories found matching 'dist' 09:17:08 INFO - no previously-included directories found matching '.git*' 09:17:08 INFO - Successfully installed jsonschema functools32 09:17:08 INFO - Cleaning up... 09:17:08 INFO - Return code: 0 09:17:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:08 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:08 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 09:17:08 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 09:17:08 INFO - Using env: {'DISPLAY': ':0', 09:17:08 INFO - 'HOME': '/home/cltbld', 09:17:08 INFO - 'LANG': 'en_US.UTF-8', 09:17:08 INFO - 'LANGUAGE': 'en_US:en', 09:17:08 INFO - 'LOGNAME': 'cltbld', 09:17:08 INFO - 'MAIL': '/var/mail/cltbld', 09:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:08 INFO - 'MOZ_NO_REMOTE': '1', 09:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:08 INFO - 'NO_EM_RESTART': '1', 09:17:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:08 INFO - 'PWD': '/builds/slave/test', 09:17:08 INFO - 'SHELL': '/bin/bash', 09:17:08 INFO - 'SHLVL': '1', 09:17:08 INFO - 'TERM': 'linux', 09:17:08 INFO - 'TMOUT': '86400', 09:17:08 INFO - 'USER': 'cltbld', 09:17:08 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:08 INFO - '_': '/tools/buildbot/bin/python'} 09:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:08 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 09:17:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:10 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 09:17:10 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 09:17:10 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 09:17:10 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 09:17:10 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 09:17:10 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 09:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:11 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 09:17:11 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 09:17:11 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 09:17:11 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 09:17:11 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 09:17:11 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 09:17:11 INFO - Downloading docopt-0.6.1.tar.gz 09:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:11 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:11 INFO - Running setup.py install for blobuploader 09:17:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:11 INFO - Running setup.py install for requests 09:17:11 INFO - Running setup.py install for docopt 09:17:11 INFO - Successfully installed blobuploader requests docopt 09:17:11 INFO - Cleaning up... 09:17:12 INFO - Return code: 0 09:17:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:12 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:12 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 09:17:12 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 09:17:12 INFO - Using env: {'DISPLAY': ':0', 09:17:12 INFO - 'HOME': '/home/cltbld', 09:17:12 INFO - 'LANG': 'en_US.UTF-8', 09:17:12 INFO - 'LANGUAGE': 'en_US:en', 09:17:12 INFO - 'LOGNAME': 'cltbld', 09:17:12 INFO - 'MAIL': '/var/mail/cltbld', 09:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:12 INFO - 'MOZ_NO_REMOTE': '1', 09:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:12 INFO - 'NO_EM_RESTART': '1', 09:17:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:12 INFO - 'PWD': '/builds/slave/test', 09:17:12 INFO - 'SHELL': '/bin/bash', 09:17:12 INFO - 'SHLVL': '1', 09:17:12 INFO - 'TERM': 'linux', 09:17:12 INFO - 'TMOUT': '86400', 09:17:12 INFO - 'USER': 'cltbld', 09:17:12 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:12 INFO - '_': '/tools/buildbot/bin/python'} 09:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:12 INFO - Running setup.py (path:/tmp/pip-eEYIyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:12 INFO - Running setup.py (path:/tmp/pip-7HC2K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:12 INFO - Running setup.py (path:/tmp/pip-_HNSkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:12 INFO - Running setup.py (path:/tmp/pip-FFAb2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:12 INFO - Running setup.py (path:/tmp/pip-wsAdW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:12 INFO - Running setup.py (path:/tmp/pip-IRBRJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:12 INFO - Running setup.py (path:/tmp/pip-wxmEvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:12 INFO - Running setup.py (path:/tmp/pip-58kwwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:12 INFO - Running setup.py (path:/tmp/pip-Ti_x8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:12 INFO - Running setup.py (path:/tmp/pip-wJvYTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:12 INFO - Running setup.py (path:/tmp/pip-Sb404m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:13 INFO - Running setup.py (path:/tmp/pip-6s2Ezj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:13 INFO - Running setup.py (path:/tmp/pip-IBDvGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:13 INFO - Running setup.py (path:/tmp/pip-Zf1ozW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:13 INFO - Running setup.py (path:/tmp/pip-rDhYWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:13 INFO - Running setup.py (path:/tmp/pip-9a_Mns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:13 INFO - Running setup.py (path:/tmp/pip-8kcWZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:13 INFO - Running setup.py install for manifestparser 09:17:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:13 INFO - Running setup.py install for mozcrash 09:17:13 INFO - Running setup.py install for mozdebug 09:17:13 INFO - Running setup.py install for mozdevice 09:17:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:13 INFO - Running setup.py install for mozfile 09:17:13 INFO - Running setup.py install for mozhttpd 09:17:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozinfo 09:17:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozInstall 09:17:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozleak 09:17:14 INFO - Running setup.py install for mozlog 09:17:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for moznetwork 09:17:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozprocess 09:17:14 INFO - Running setup.py install for mozprofile 09:17:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozrunner 09:17:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:14 INFO - Running setup.py install for mozscreenshot 09:17:15 INFO - Running setup.py install for moztest 09:17:15 INFO - Running setup.py install for mozversion 09:17:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:15 INFO - Cleaning up... 09:17:15 INFO - Return code: 0 09:17:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:15 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')]} 09:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:15 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')]} 09:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:15 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:15 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 09:17:15 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 09:17:15 INFO - Using env: {'DISPLAY': ':0', 09:17:15 INFO - 'HOME': '/home/cltbld', 09:17:15 INFO - 'LANG': 'en_US.UTF-8', 09:17:15 INFO - 'LANGUAGE': 'en_US:en', 09:17:15 INFO - 'LOGNAME': 'cltbld', 09:17:15 INFO - 'MAIL': '/var/mail/cltbld', 09:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:15 INFO - 'MOZ_NO_REMOTE': '1', 09:17:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:15 INFO - 'NO_EM_RESTART': '1', 09:17:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:15 INFO - 'PWD': '/builds/slave/test', 09:17:15 INFO - 'SHELL': '/bin/bash', 09:17:15 INFO - 'SHLVL': '1', 09:17:15 INFO - 'TERM': 'linux', 09:17:15 INFO - 'TMOUT': '86400', 09:17:15 INFO - 'USER': 'cltbld', 09:17:15 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:15 INFO - '_': '/tools/buildbot/bin/python'} 09:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:15 INFO - Running setup.py (path:/tmp/pip-FVPUFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:15 INFO - Running setup.py (path:/tmp/pip-tHN5UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:15 INFO - Running setup.py (path:/tmp/pip-Ct6bv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:15 INFO - Running setup.py (path:/tmp/pip-uQj2g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:15 INFO - Running setup.py (path:/tmp/pip-iuPruh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:15 INFO - Running setup.py (path:/tmp/pip-MvRvFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:15 INFO - Running setup.py (path:/tmp/pip-9VMs3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:15 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)) 09:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:15 INFO - Running setup.py (path:/tmp/pip-rnZ6X5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:16 INFO - Running setup.py (path:/tmp/pip-FWwgH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:16 INFO - Running setup.py (path:/tmp/pip-6BwVvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:16 INFO - Running setup.py (path:/tmp/pip-8_WjKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:16 INFO - Running setup.py (path:/tmp/pip-bVpqnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:16 INFO - Running setup.py (path:/tmp/pip-Mfl8ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:16 INFO - Running setup.py (path:/tmp/pip-4XLSQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:16 INFO - Running setup.py (path:/tmp/pip-_vZyfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:16 INFO - Running setup.py (path:/tmp/pip-Mnwl_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:16 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)) 09:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:16 INFO - Running setup.py (path:/tmp/pip-AJGvj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:16 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)) 09:17:16 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)) 09:17:16 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)) 09:17:16 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)) 09:17:16 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)) 09:17:16 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)) 09:17:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:16 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 09:17:16 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 09:17:16 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 09:17:16 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 09:17:16 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 09:17:16 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 09:17:18 INFO - Downloading blessings-1.6.tar.gz 09:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:18 INFO - Installing collected packages: blessings 09:17:18 INFO - Running setup.py install for blessings 09:17:18 INFO - Successfully installed blessings 09:17:18 INFO - Cleaning up... 09:17:18 INFO - Return code: 0 09:17:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:19 INFO - Reading from file tmpfile_stdout 09:17:19 INFO - Current package versions: 09:17:19 INFO - argparse == 1.2.1 09:17:19 INFO - blessings == 1.6 09:17:19 INFO - blobuploader == 1.2.4 09:17:19 INFO - docopt == 0.6.1 09:17:19 INFO - functools32 == 3.2.3-2 09:17:19 INFO - jsonschema == 2.5.1 09:17:19 INFO - manifestparser == 1.1 09:17:19 INFO - mozInstall == 1.12 09:17:19 INFO - mozcrash == 0.17 09:17:19 INFO - mozdebug == 0.1 09:17:19 INFO - mozdevice == 0.48 09:17:19 INFO - mozfile == 1.2 09:17:19 INFO - mozhttpd == 0.7 09:17:19 INFO - mozinfo == 0.9 09:17:19 INFO - mozleak == 0.1 09:17:19 INFO - mozlog == 3.2 09:17:19 INFO - moznetwork == 0.27 09:17:19 INFO - mozprocess == 0.22 09:17:19 INFO - mozprofile == 0.28 09:17:19 INFO - mozrunner == 6.11 09:17:19 INFO - mozscreenshot == 0.1 09:17:19 INFO - mozsystemmonitor == 0.3 09:17:19 INFO - moztest == 0.7 09:17:19 INFO - mozversion == 1.4 09:17:19 INFO - psutil == 3.1.1 09:17:19 INFO - requests == 1.2.3 09:17:19 INFO - wsgiref == 0.1.2 09:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:19 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 09:17:19 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 09:17:19 INFO - Using env: {'DISPLAY': ':0', 09:17:19 INFO - 'HOME': '/home/cltbld', 09:17:19 INFO - 'LANG': 'en_US.UTF-8', 09:17:19 INFO - 'LANGUAGE': 'en_US:en', 09:17:19 INFO - 'LOGNAME': 'cltbld', 09:17:19 INFO - 'MAIL': '/var/mail/cltbld', 09:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:19 INFO - 'MOZ_NO_REMOTE': '1', 09:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:19 INFO - 'NO_EM_RESTART': '1', 09:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:19 INFO - 'PWD': '/builds/slave/test', 09:17:19 INFO - 'SHELL': '/bin/bash', 09:17:19 INFO - 'SHLVL': '1', 09:17:19 INFO - 'TERM': 'linux', 09:17:19 INFO - 'TMOUT': '86400', 09:17:19 INFO - 'USER': 'cltbld', 09:17:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:19 INFO - '_': '/tools/buildbot/bin/python'} 09:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 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)) 09:17:19 INFO - Cleaning up... 09:17:19 INFO - Return code: 0 09:17:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 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 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:19 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 09:17:19 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 09:17:19 INFO - Using env: {'DISPLAY': ':0', 09:17:19 INFO - 'HOME': '/home/cltbld', 09:17:19 INFO - 'LANG': 'en_US.UTF-8', 09:17:19 INFO - 'LANGUAGE': 'en_US:en', 09:17:19 INFO - 'LOGNAME': 'cltbld', 09:17:19 INFO - 'MAIL': '/var/mail/cltbld', 09:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:19 INFO - 'MOZ_NO_REMOTE': '1', 09:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:19 INFO - 'NO_EM_RESTART': '1', 09:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:19 INFO - 'PWD': '/builds/slave/test', 09:17:19 INFO - 'SHELL': '/bin/bash', 09:17:19 INFO - 'SHLVL': '1', 09:17:19 INFO - 'TERM': 'linux', 09:17:19 INFO - 'TMOUT': '86400', 09:17:19 INFO - 'USER': 'cltbld', 09:17:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:19 INFO - '_': '/tools/buildbot/bin/python'} 09:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:19 INFO - Cleaning up... 09:17:19 INFO - Return code: 0 09:17:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:19 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0fce49bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a60020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', '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 09:17:19 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', 'flake8'] in /builds/slave/test/build 09:17:19 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 flake8 09:17:19 INFO - Using env: {'DISPLAY': ':0', 09:17:19 INFO - 'HOME': '/home/cltbld', 09:17:19 INFO - 'LANG': 'en_US.UTF-8', 09:17:19 INFO - 'LANGUAGE': 'en_US:en', 09:17:19 INFO - 'LOGNAME': 'cltbld', 09:17:19 INFO - 'MAIL': '/var/mail/cltbld', 09:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:19 INFO - 'MOZ_NO_REMOTE': '1', 09:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:19 INFO - 'NO_EM_RESTART': '1', 09:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:19 INFO - 'PWD': '/builds/slave/test', 09:17:19 INFO - 'SHELL': '/bin/bash', 09:17:19 INFO - 'SHLVL': '1', 09:17:19 INFO - 'TERM': 'linux', 09:17:19 INFO - 'TMOUT': '86400', 09:17:19 INFO - 'USER': 'cltbld', 09:17:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:19 INFO - '_': '/tools/buildbot/bin/python'} 09:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:19 INFO - Downloading/unpacking flake8 09:17:19 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 09:17:19 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 09:17:19 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 09:17:19 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 09:17:19 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 09:17:19 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 09:17:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:17:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:17:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:21 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 09:17:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:17:22 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 09:17:22 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 09:17:22 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 09:17:22 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 09:17:22 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 09:17:22 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 09:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:17:22 INFO - no previously-included directories found matching 'docs/_build' 09:17:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:17:22 INFO - Running setup.py install for pep8 09:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:17:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:17:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:17:22 INFO - no previously-included directories found matching 'docs/_build' 09:17:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:17:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:17:22 INFO - Cleaning up... 09:17:22 INFO - Return code: 0 09:17:22 INFO - Running post-action listener: _resource_record_post_action 09:17:22 INFO - Running post-action listener: _start_resource_monitoring 09:17:22 INFO - Starting resource monitoring. 09:17:22 INFO - ##### 09:17:22 INFO - ##### Finished create-virtualenv step (success) 09:17:22 INFO - ##### 09:17:22 INFO - ##### 09:17:22 INFO - ##### Running install step. 09:17:22 INFO - ##### 09:17:22 INFO - Running pre-action listener: _resource_record_pre_action 09:17:22 INFO - Running main action method: install 09:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:22 INFO - Reading from file tmpfile_stdout 09:17:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:22 INFO - Reading from file tmpfile_stdout 09:17:22 INFO - Output received: 09:17:22 INFO - Usage: mozinstall [options] installer 09:17:22 INFO - Options: 09:17:22 INFO - -h, --help show this help message and exit 09:17:22 INFO - -d DEST, --destination=DEST 09:17:22 INFO - Directory to install application into. [default: 09:17:22 INFO - "/builds/slave/test"] 09:17:22 INFO - --app=APP Application being installed. [default: firefox] 09:17:22 INFO - mkdir: /builds/slave/test/build/application 09:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:17:38 INFO - Reading from file tmpfile_stdout 09:17:38 INFO - Output received: 09:17:38 INFO - /builds/slave/test/build/application/firefox/firefox 09:17:38 INFO - Running post-action listener: _resource_record_post_action 09:17:38 INFO - ##### 09:17:38 INFO - ##### Finished install step (success) 09:17:38 INFO - ##### 09:17:38 INFO - ##### 09:17:38 INFO - ##### Running run-tests step. 09:17:38 INFO - ##### 09:17:38 INFO - Running pre-action listener: _resource_record_pre_action 09:17:38 INFO - Running main action method: run_tests 09:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:38 INFO - Python 2.7.3 09:17:38 INFO - Return code: 0 09:17:38 INFO - grabbing minidump binary from tooltool 09:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:38 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 0x2af22e0>, '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 0x2a5e140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e5d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:38 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 09:17:38 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 09:17:38 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 09:17:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:38 INFO - Return code: 0 09:17:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:17:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:17:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:17:39 INFO - Return code: 0 09:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 09:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 09:17:39 INFO - Using env: {'DISPLAY': ':0', 09:17:39 INFO - 'HOME': '/home/cltbld', 09:17:39 INFO - 'LANG': 'en_US.UTF-8', 09:17:39 INFO - 'LANGUAGE': 'en_US:en', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MAIL': '/var/mail/cltbld', 09:17:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SHLVL': '1', 09:17:39 INFO - 'TERM': 'linux', 09:17:39 INFO - 'TMOUT': '86400', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - '_': '/tools/buildbot/bin/python'} 09:17:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 09:17:39 INFO - mozversion application_buildid: 20160704063826 09:17:39 INFO - mozversion application_changeset: 128eff0a581ff318eb3629005a871d5095b4f430 09:17:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:17:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:39 INFO - mozversion application_name: Firefox 09:17:39 INFO - mozversion application_remotingname: firefox-dev 09:17:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:17:39 INFO - mozversion application_vendor: Mozilla 09:17:39 INFO - mozversion application_version: 49.0a2 09:17:39 INFO - mozversion platform_buildid: 20160704063826 09:17:39 INFO - mozversion platform_changeset: 128eff0a581ff318eb3629005a871d5095b4f430 09:17:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:17:39 INFO - mozversion platform_version: 49.0a2 09:17:39 INFO - using testdate: 1467649059 09:17:39 INFO - actual date: 1467649059 09:17:39 INFO - starting webserver on 'localhost:52204' 09:17:39 INFO - SUITE-START | Running 2 tests 09:17:39 INFO - TEST-START | dromaeo_css 09:17:39 INFO - operating with platform_type : linux_ 09:17:39 INFO - Initialising browser for dromaeo_css test... 09:17:39 INFO - TEST-INFO | started process 19135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1wmn1c/profile http://localhost:52204/getInfo.html) 09:17:44 INFO - PROCESS | 19135 | __metrics Screen width/height:1600/1200 09:17:44 INFO - PROCESS | 19135 | colorDepth:24 09:17:44 INFO - PROCESS | 19135 | Browser inner width/height: 1024/697 09:17:44 INFO - PROCESS | 19135 | __metrics 09:17:45 ERROR - PROCESS | 19135 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:17:46 INFO - TEST-INFO | 19135: exit 0 09:17:46 INFO - Browser initialized. 09:17:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:46 INFO - TEST-INFO | started process 19225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1wmn1c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:58 INFO - PROCESS | 19225 | RSS: Main: 145502208 09:17:58 INFO - PROCESS | 19225 | 09:18:49 INFO - PROCESS | 19225 | 1467649129582 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] 09:20:57 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52204/tests/dromaeo/cssquery-ext.html) 09:20:58 INFO - PROCESS | 19225 | RSS: Main: 256307200 09:20:58 INFO - PROCESS | 19225 | 09:24:01 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-ext.html (next: http://localhost:52204/tests/dromaeo/cssquery-jquery.html) 09:24:01 INFO - PROCESS | 19225 | RSS: Main: 227143680 09:24:01 INFO - PROCESS | 19225 | 09:27:03 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52204/tests/dromaeo/cssquery-mootools.html) 09:27:04 INFO - PROCESS | 19225 | RSS: Main: 225550336 09:27:04 INFO - PROCESS | 19225 | 09:30:06 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52204/tests/dromaeo/cssquery-prototype.html) 09:30:06 INFO - PROCESS | 19225 | RSS: Main: 218542080 09:30:06 INFO - PROCESS | 19225 | 09:33:09 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52204/tests/dromaeo/cssquery-yui.html) 09:33:09 INFO - PROCESS | 19225 | RSS: Main: 214544384 09:33:09 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | Cycle 1(1): loaded http://localhost:52204/tests/dromaeo/cssquery-yui.html (next: http://localhost:52204/tests/dromaeo/cssquery-dojo.html) 09:36:12 INFO - PROCESS | 19225 | RSS: Main: 260124672 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | __start_tp_report 09:36:12 INFO - PROCESS | 19225 | _x_x_mozilla_page_load 09:36:12 INFO - PROCESS | 19225 | _x_x_mozilla_page_load_details 09:36:12 INFO - PROCESS | 19225 | |i|pagename|runs| 09:36:12 INFO - PROCESS | 19225 | |0;dojo.html;3402.557667503225;3409.948850767237;3415.4193787056206;3447.1382154461385;3469.2541103662707;5532.0318944184755;5618.662880227184;5629.802956896509;5637.182608521762;5639.436056394358;5558.44415558444;5594.384617692051;5597.048501754702;5601.971990140044;5605.775768969236;4912.852614421573;5048.7223202723835;5066.493350664941;5097.872553186163;5153.510416510426;4959;5032.672876263049;5085.720285384303;5095.79616815327;5104.132297509414;5111.309973153614;5129.410117836441;5150.227465880111;5184.066867963765;5185.455527169649;5288;5296.364436267634;5300.522952934216;5301.73491325436;5333.679979201222;4961.7022978621035;5022.573081288095;5057.924131138036;5186.118359878829;5196.714180720059;5439.945600544023;5446.564274858002;5459.426760190163;5517.200005999108;5530.751116199781;6059.757609695607;6067.820924070054;6076.4834989025985;6106.084087386883;6107.908381374283;5968.731407086691;6000;6017.06735456005;6107.4197951194565;6108.725107370179;5653.010723123437;5668.943310566924;5685.857853553653;5707.571932105067;5710.628809127435;5909.202257695156;6184.969075154595;6198.938010619926;6204.224471941007;6205.751769929243;5929.555283353766;5974.940250597525;5992.0712289595185;6066;6077.65617375545;6110.419510146529;6126.30393285589;6135.171422439398;6136.610985192573;6143.32423433422;5777.711114444261;5875.941240587539;6121.056693972082;6139.921477737008;6143.266246520256;5923.940760592339;5990.161377407167;5991.700414979234;6003.759849605967;6030.30651475086;4570.9314360284625;4579.793909274091;4590.977045114753;4591.862244132682;4609.469910960215;4027.637512623878;4050.3519436889956;4057.756534607933;4113.835446582163;4141.730787498803;5680.403557626472;5691.516221121251;5702.971485142548;5711.7144142792695;5724.112762521816;5973.850653733604;6017.398260173947;6028.095785632103;6040.124181993587;6057.151998720183;5601.467860553241;5667.121596152603;5691.089425691869;5705.857353566194;5721.914171287434;5205.453427390145;5226.738663066832;5228.712420816892;5230.738463076831;5233.528982391527;6305.621662700161;6310.337414571404;6310.810675679737;6312.305646378894;6321.683915804191;5816.82549523515;6056.515478761777;6135.110408990582;6139.938600614116;6172.043333283348;6341.365863413622;6373.139626150411;6377.202849643794;6379.904301435389;6392.648404337806;5760.884782304414;6057.848553786191;6059.151718759465;6063.272407310975;6067.271927368744;5971.7611295547695;5991.2510936132985;6046.123312119806;6101.694915254131;6108.08378743192;5893.882122357614;5933.110033495011;5937.614055086406;5943.167956486009;5947.613405128653;5711.685857277724;5858.297004359504;5872.853178670567;5930;5939.67331796738;5828.446297601721;5915.763369465173;5922.851928701817;5928.377520360473;5957.91063134062;6043.728032238472;6045.10185493951;6051.213342265477;6071.666058366833;6082.2093127893095;5527.944720552743;5701.771929122789;5743.1385292206505;5905.734241959208;5928.703564821828 09:36:12 INFO - PROCESS | 19225 | |1;ext.html;603.9879202415949;632.2792017100505;646.0761111610398;661.0579923608856;661.4179522020621;33207.16982075446;36783.632163678325;36797.81601091994;36798.448023279605;36814.26371472569;47815.282770758466;48605.27092093613;48611.51388486114;52418;52439;8973;9103;9103.135202155785;9106.089391060874;9107.36248462608;8378.622961966696;8848.2478989286;10579.3652380857;10840.67477975662;10847.240693151483;10917.126629869592;10929.672109836718;10932.508037138348;10981.945090274578;10987.39569323687;8403.36974726898;10821.891781082168;10895;10895.182861285435;10911.890881091247;10054;10913.454327283604;10983.835242471368;11265.718357041058;11268.492917818718;42064.36903446451;42721.57278427207;46306.76846615745;46366.30450543245;46429.53570464286;3742.906427339311;3812.2375524895133;3852.633999770045;4140.37894315852;4395.868123956287;35115.29769404623;35418.8229058853;35457.29085418303;35588.822055889555;35899.102522436886;4750.358701575312;4808.759562021885;4841.080194762983;4842.709437433765;4842.878928026792;381951;384384;384834;384861.07569462253;386651.9999999972;69352.3064769356;70108.64945675239;70119;70125;70226.64886675533;40079.39880901789;41166.176676466304;41253.381199282034;43798.78100609477;74227.25772742204;17560.56098597545;17701;17771.822281777277;17784.644307114046;17796.288148474174;12269.263844169378;13471.259080750653;13615.319234038456;13627;13775.517856874962;21343;21795.67306490404;22064.33806985793;22103.889480552494;22132.114715411524;7962.2037796221075;8103.756887293391;8196.508209507449;8302.418830681794;8314.214738062696;981.7202097402206;1087.983680244797;1091.4870011094772;1129.632869317475;1160.100921785606;32813.343733125206;34251.486227706606;34717.47923781145;34733.65266347304;34750.49880386325;1146.850909381775;1157.2246594781348;1167.1654766841748;1200.1778781534676;1206.3847437806605;17684.734728979074;18054;18075.638487230182;18141.818581814012;18186.63626727458;571459;580383;585277;587573;590962;65923.34076659172;66622.33377666162;66652.66673666601;66767;66787;1396.3576754692956;1439.0358459831937;1471.220253265771;1493.5444689369629;1512.3648067811325;31801.8409907949;34575.317126049355;34670.65329346674;34948.65051349556;35404.46893296654;55072.17391739047;55390.44609553853;55539.44460555343;55629.1655625174;55712.72143639256;423303;423896;424218;425391;426153;26716;31564.842175788974;31615.525767113053;31757;33122.33755324928;52326.47673523216;53223.73388133034;53297;53580.73209634083;54376;44307;45034.54965450435;45061;45648.54351456443;45976;44895;45000.54999450094;45086;45909;46269;44518.55481445144;44588.33117503305;45478.77260613676;45577.54422455733;46890.296645549664;39677.80161099176;40338.394924075576;40607.1878562433;40932.59067409407;40934.38598421086;29525.557116643697;29765.553516696833;29809.95665151796;29840.850795745882;30261.243468912573 09:36:12 INFO - PROCESS | 19225 | |2;jquery.html;407.2303346674784;412.83486605357865;421.69637860740255;422.7864928211255;443.6650329001603;18845.71731424028;19600;19874.205031798712;19960.201591936304;20532.794672053275;19229.134688938997;19237.80762192381;19244.326448574302;20147.798522014775;20286.59426811463;5627.831165065055;5790.594658373915;5831.241938548004;5833.095870140135;5839.065749480084;4246.639035681956;4896.069746748124;5689.4879460848515;5747.827565173052;6338.587991780542;6479.902801457982;6496.837579060514;6497.285298617172;6514.967425162891;6521.641309727963;6268.373162683741;6381.042843573455;6419.807405777788;6426.742930282783;6473.1261279727105;3852.3065848147603;4021.195760847813;5294.549963253128;5681.829545113653;5685.4883060524335;22262.109515619355;22979;23819.523609527885;24097.87951060251;24210.273691789276;832.4713806732708;1341.9731605367838;1718.8054302259934;2547.4013606802387;2635.9438741202252;14747.926260368737;15162.924185379003;15177.468788592456;15366.154861482613;15377.461788837449;2048.94877628059;2065.3597384810605;2119.6608542633257;2122.8301735861096;2160.8811515366647;73882.63058684726;75004.4873456852;77571.61214193949;77937.22062779356;78236.60881695613;10935.671929842118;10951.178661600412;11035.2275340727;11279.154063445274;11428.657140285804;15798.763018554731;15857.286422111032;15929.920350398173;15940.123293218985;15958.042517448757;8202.220789025034;8514.48913065218;8528.147185281547;8681.435706679045;8681.956590217009;8371.246587807005;8443.36674749384;8444.324454043663;8605.31157507398;8647.956760216284;6814.318568143146;6966.338197871194;7004;7007.964960175268;7023.08699869024;1951.7170010348705;2015.7077223802467;2016.899155042242;2023.7672667643114;2060.732104826363;947.9383840050375;1002.1682003936716;1044.6604853422668;1045.3309881675582;1048.9265751397481;18642.627147456977;18847;19854.205831766856;19976.90011549962;20201.49496262576;1018.7351288664852;1048.6015314180563;1059.772148987956;1083.2628470521984;1112.1881026850283;10648.627298044532;10929.289596176382;10947.890521094847;11026.614068507564;11029.503672334604;478274;483461;486634;501915.96168077167;502186;71663.42848602538;82787;100292.49853750685;100297;101691;1106.690126764507;1124.791913496006;1150.8734039255676;1190.2501424102759;1203.1457665057733;18055.368062117763;18213.271469141087;18337.449876503728;18396.172172252012;18537.721934171266;38787;39855.601443985186;40454.1909161821;41866.58133418627;42233.1553368937;60831.69584152051;61251.081233780635;62119;62159.6892015537;62829;8706.869396958924;8931.374803763674;9123.270138389045;9177.21993630549;9225.215856651992;12607.306598137191;13765.174089554659;13805.240711760547;13960.441582336998;14077.929610351883;11598.94200528992;11710.882891170979;12183.817242741188;12274.669581635837;12315.19951203169;11707.941460292644;11897.107716921093;11938.462769175234;12120.636380908587;12238.265704057429;12225.572104976283;12394.442250098948;12543.87456125427;12593.181443206162;12619.30593817349;8771.473711577326;9051.54742262871;9185.586648600962;9187;9324.254059675346;12087.274763514217;12264.325462099314;12543.93728031354;12569.685757856127;12646.494140234288 09:36:12 INFO - PROCESS | 19225 | |3;mootools.html;1392.596147117336;1550.2481296571164;1596.848299411556;1768.301520899245;1882.9340973065955;9163.221126204273;9565.473898935565;9589.808203835919;9834.704958851227;9857;9617.663381781638;9804.362716423446;9806.509674516281;9810.117089461946;9812.264080193978;2105.4210092224685;2440.0239904038394;2464.0636558107894;2481.7394173611783;2538.0989748639245;2509;2533.8353007054484;2542.8474291542475;2546.885390157438;2547.4777670577487;2537.796976241896;2560.513502434541;2561.346856551586;2562.987185064072;2563.51293254282;2128.7232659754286;2433.3429973907037;2497.625356196567;2499.9500009999697;2515.283144303883;2412.746661600524;2428.86641234732;2436.792872605831;2475.7152927413217;2476.05909754294;9842.458664773434;9855.90144098557;9856.063673950963;9884.90115098847;9927.950360248153;5594.4964953154;5747.252857128588;5747.741351639186;5748.425157484218;5751.482366586985;3473.096994781375;3487.9128021799656;3495.4407294832704;3706.184639379332;3737.682297004758;1151.4875880233299;1187.1749134351628;1213.2599114540124;2145.249162793026;2168.8698678079368;14038.789418158736;14122.082064665867;14125.081869678437;14134.50529231472;14218;4764.8003955011745;4785.186518291899;4854.150523658345;4873.707577545359;4880.853574392774;10777.137828973664;10889.509972051119;10927.945360273148;10934.617288394868;10938.343699377903;5057.140286151363;5627.071533197059;5652.886942261214;5653.208550802891;5662.490375866192;5153.304303918998;5307.973460132752;5315.548178404879;5347.064263753828;5357.19642053695;4095.9180816383505;4125.525564560054;4168.499780026416;4203.642690371292;4203.894902627459;1215.3194211241732;1250.249850089957;1256.0705078242036;1265.3230521670862;1266.050462153385;859.8065435277013;935.4667839331652;956.5934477846887;959.2302252149121;964;5825.300963884276;5854.356020837705;5879.588428809942;6025.8192254232445;6036.185115009417;450.34025153150793;464.06490920794624;467.59085799925066;470.1067970855402;471.69104236725;3700.389435743126;3706.29580379727;3764.9058773530332;3782.167923056923;3836.9040773980873;11995.700107497383;12253.632391028283;12253.877461225451;12335.383230838423;12390.5663301784;11814.523184601925;12071.698207544881;12118.575849845212;12189.695257618454;12206.32865192423;8902.599383027778;9098.04470530598;9132.3150763693;9255.53722313895;9258.768530786785;5855.765769369348;5859.296884373903;5872.825434912949;5948.464638182584;5992.760289588367;11236.101111911192;11288.492017859051;11294.661160165208;11303.208775385972;11384.886151138382;10675.733106672085;11060.612878549535;11091.33451992883;11268.098552115975;11378.772424551627;7857.607119644109;8388.161183881562;8593.570321484025;8600.913990860261;8774.034856165945;9596.376235544667;9627.133557979816;9637.132658060807;9682.322237443312;9688.41869487833;8483.236508714246;8774.605142768718;8788.121187881161;8821.514816685143;8935.151160639729;8222.917770822454;8459.111793261618;8569;8592.699255526026;8765.956170219108;7412.481126321321;7471.813204669927;7517.248275172439;7529.811754705957;7573.772786816405;5190.987757387274;5248.239005344128;5317.122674758776;5371.892562148657;5436.918446223389;5315.282436870973;5354.866128346823;5371.086915224343;5376.838694839161;5452.836414907559 09:36:12 INFO - PROCESS | 19225 | |4;prototype.html;350.9385857474942;358.21372088266247;360.6988164882323;365.32415032190465;365.98170091495416;5540.697935985046;5646.576506761989;5657.886842263162;5660.264165658458;5668.319801623806;5562.192287506567;5565.582581306398;5569.6658200508;5580.609357344987;5594.9720251398785;4156.521999970005;4311.9651283692;4314.935275970863;4348.934765978513;4351.758844195242;4253.574642535753;4290.914181716364;4329.67527435442;4347.586979236968;4366.825326986933;4277.14457108578;4295.7232666686705;4297.5057868345075;4299.269124248885;4314.417553630251;4149.751014939098;4266.935995960063;4293.570642935712;4301.634361079312;4305.913881722364;3804.714646401531;3810.733248672594;3813.3136035513876;3815.4086116651965;3817.4464702618243;5366.806749983753;5382.546712387633;5387.353517577876;5414.891702165974;5428.267183930158;4732.645051621108;5001;5005.874853128701;5010.599152067863;5013.298138260647;5796.1309151970045;5841.503472204868;5863.736131874076;5877.617954832922;5883.529317654577;4190.329547272422;4193.245215861144;4196.979015104936;4216.494020717503;4248.596383343577;5703.429657034305;5842.824715258506;5861.501772349313;6009.729562169713;6122.234720659918;4144.689148313889;4155.293600087993;4163.625273725379;4176.770277634699;4283.914321713548;4361.411209486679;4362.825486980485;4371.103923695635;4383.189110014658;4389.934150987738;4872.439669437989;4957.553820156203;4963.528464795839;4985.105659849234;5023.547880690755;5640.294963129609;5648.192578374963;5748.281464816898;5817.7091145442555;5850.9414905851245;1479.127314884202;1484.7030593881268;1490.970180596382;1496.5360738171203;1505.6838064006463;920.8250432417819;939.455116032713;947.1901524196712;954.6897258391051;955.7323949294208;5027.673201241908;5046.899062018812;5057.595392368602;5076.13705670037;5102.413222479388;5309.814156504503;5312.707801070978;5315.521603055744;5319.244649265733;5328.786848526015;5076.695398276115;5106.923396149061;5139.357580302462;5304.230886521433;5713.657180569096;3181.80909145452;3216.3728073025536;3221.90334289967;3229.257270827723;3235.433799085151;6158.692065396713;6305;6317.589356691892;6335.434149169594;6341.524385671093;6029.608075475167;6029.698515074228;6064.181335519736;6165.753369865245;6177.938220617826;6102.389761023862;6145.5698101133385;6208.286047104551;6211.2857021442205;6230.252369715572;5774.249347584787;5977.163197152315;5994.3705910878725;6004.628171872173;6010.278766548042;5947.732352044255;5950.9404905952115;5961.4038596140035;5974.2233509643465;5979.551533634908;5898.734556945034;6117.665872099806;6143.416375444325;6145.924463219008;6260.561760676709;4306.25596633364;4318.3090705486975;4322.524522302607;4340.544242854581;4340.631046360968;5775.8556036099435;5881.73532191061;6039.758409663565;6051.425114614105;6094.20775299226;5803.854903627274;5811.82564523065;5845.532357411312;5873.4126587340925;5936;5835;5835.358110607915;5853.736581853742;5856.560757943256;5856.707164641836;5553.72231388437;5735.827925162252;5754.309482862083;5768.721355064642;5790.536757059509;5811.883762324815;5817.534597232296;5832.766689332379;5872.1778950946045;5882.882342353215;5511.559075273888;5721.856953576027;5770.942290577208;5781;5786.276715410574 09:36:12 INFO - PROCESS | 19225 | |5;yui.html;718.0557566799662;743.3495691270139;774.4617490843863;775.8487095016472;794.2835502581173;16443.342266309348;16606.418775342867;17339.219735111918;17355.82644173558;17373.739393909087;13885.375158117884;13903.165810051403;13914.930425347884;13928.93035534821;13963.511277105303;730.9122905251377;859.9656013759442;872.1322284327088;878.802269489363;883.6156272021659;871.7384784564637;873.244643383475;875.2604049578097;876.5310558851016;880.6081293824251;926.4811705444972;934.0752654871662;934.9626014959393;935.5228833295;936.9203617692505;768.3699366519456;904.5160838951163;925.6112432778182;925.6945208081318;930.6835675870236;680.7855525509498;680.9455243580502;681.158768920383;683.111954459206;683.453237410075;16359.672806543922;16391.91804040972;16398.26207820651;16413.1793410329;16421.261043253082;14050.578482645536;14079.85920140806;14255.714885702331;14407.423703051865;14439.783403248959;12446.564370246997;12537.934275586585;12537.937310313482;12539.184952978027;12725.872741272655;4584.289435137559;4592.678512504126;4625.537446255347;4628.560286772751;4668.50980647034;73649.99999999946;73731.26268737298;73868.26131738721;74272;74894.62552687257;10505.894941050645;10762.139028877671;10803.837942430713;10818.513166907507;10936.343819370863;19983.600327993357;20287.28994485186;20300.898495507427;20318.593628127353;20331.796682033288;8148.755537333889;8207.71273005442;8293.668253269801;8329.167083291744;8508.404411691246;4964.145616270659;5152.690838549699;5190.6366554341585;5221.765020574083;5309.017831701148;3726.347889119393;3809.942850857239;3824.2733880562605;3830.597787232356;3832.080300727805;650.0931200974709;652.3704625036768;700.2087640965779;736.3777607921298;741.7255615422221;582.6591444005304;653.3858173317067;673.2997682410238;709.6841905352172;717.8169566760442;9622.807543849223;10272.178225742073;10296.536655850505;10354.482275886176;10469.738256543647;705.989410158848;719.3022767915114;782.3506489613607;791.192983157188;810.0765127754365;4756.310335001405;4931.654784165074;5035.093683137071;5049.368828896388;5115.948840511547;97321;99864.50067749615;101857;113572;115359;91857;96831.51584242034;99236;99744.5012774946;101821;85153.57423212845;88498.55750721334;89149.55425222962;89462.55268723745;90477.54761226283;9943.353682010646;10181.643642472478;10340.482975851328;10384.584616615251;10451.42517161533;49626;49744;50992.49007509877;51872;52051.479485206175;64943.71412241284;66713.33286667071;73374.63312683403;74711.25288747043;76327.85508217492;13189.802152967904;13258.20450772918;13332.2667253302;13792.586222413343;13888.097273677178;10809.721375622003;13857.37641806101;14187.361568729226;14875.702485950436;15507.534773956799;13587.52443664467;14191.361388737738;14405;14468.565943021726;14539.636509087357;13880.097793643381;13957.720845582828;14290.142591444546;14337.928310358382;14379.281035947952;14170.433182672577;14360.425582976564;14362.784558231844;14376.784348234994;15128.773068404204;10620.628278010232;10678.092362149304;10918.836217456586;10968.616098436516;11065.944670276596;11793.646190614296;11866.288022718665;11916.732909380036;12175.634730958085;12528.248305101723 09:36:12 INFO - PROCESS | 19225 | __end_tp_report 09:36:12 INFO - PROCESS | 19225 | __start_cc_report 09:36:12 INFO - PROCESS | 19225 | _x_x_mozilla_cycle_collect,874 09:36:12 INFO - PROCESS | 19225 | __end_cc_report 09:36:12 INFO - PROCESS | 19225 | __startTimestamp1467650172607__endTimestamp 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | ------- Summary: start ------- 09:36:12 INFO - PROCESS | 19225 | Number of tests: 6 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#0] dojo.html Cycles:165 Average:5620.15 Median:5828.45 stddev:638.21 (10.9%) stddev-sans-first:616.00 09:36:12 INFO - PROCESS | 19225 | Values: 3402.6 3409.9 3415.4 3447.1 3469.3 5532.0 5618.7 5629.8 5637.2 5639.4 5558.4 5594.4 5597.0 5602.0 5605.8 4912.9 5048.7 5066.5 5097.9 5153.5 4959.0 5032.7 5085.7 5095.8 5104.1 5111.3 5129.4 5150.2 5184.1 5185.5 5288.0 5296.4 5300.5 5301.7 5333.7 4961.7 5022.6 5057.9 5186.1 5196.7 5439.9 5446.6 5459.4 5517.2 5530.8 6059.8 6067.8 6076.5 6106.1 6107.9 5968.7 6000.0 6017.1 6107.4 6108.7 5653.0 5668.9 5685.9 5707.6 5710.6 5909.2 6185.0 6198.9 6204.2 6205.8 5929.6 5974.9 5992.1 6066.0 6077.7 6110.4 6126.3 6135.2 6136.6 6143.3 5777.7 5875.9 6121.1 6139.9 6143.3 5923.9 5990.2 5991.7 6003.8 6030.3 4570.9 4579.8 4591.0 4591.9 4609.5 4027.6 4050.4 4057.8 4113.8 4141.7 5680.4 5691.5 5703.0 5711.7 5724.1 5973.9 6017.4 6028.1 6040.1 6057.2 5601.5 5667.1 5691.1 5705.9 5721.9 5205.5 5226.7 5228.7 5230.7 5233.5 6305.6 6310.3 6310.8 6312.3 6321.7 5816.8 6056.5 6135.1 6139.9 6172.0 6341.4 6373.1 6377.2 6379.9 6392.6 5760.9 6057.8 6059.2 6063.3 6067.3 5971.8 5991.3 6046.1 6101.7 6108.1 5893.9 5933.1 5937.6 5943.2 5947.6 5711.7 5858.3 5872.9 5930.0 5939.7 5828.4 5915.8 5922.9 5928.4 5957.9 6043.7 6045.1 6051.2 6071.7 6082.2 5527.9 5701.8 5743.1 5905.7 5928.7 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#1] ext.html Cycles:180 Average:63930.26 Median:40208.90 stddev:125055.10 (311.0%) stddev-sans-first:125315.53 09:36:12 INFO - PROCESS | 19225 | Values: 604.0 632.3 646.1 661.1 661.4 33207.2 36783.6 36797.8 36798.4 36814.3 47815.3 48605.3 48611.5 52418.0 52439.0 8973.0 9103.0 9103.1 9106.1 9107.4 8378.6 8848.2 10579.4 10840.7 10847.2 10917.1 10929.7 10932.5 10981.9 10987.4 8403.4 10821.9 10895.0 10895.2 10911.9 10054.0 10913.5 10983.8 11265.7 11268.5 42064.4 42721.6 46306.8 46366.3 46429.5 3742.9 3812.2 3852.6 4140.4 4395.9 35115.3 35418.8 35457.3 35588.8 35899.1 4750.4 4808.8 4841.1 4842.7 4842.9 381951.0 384384.0 384834.0 384861.1 386652.0 69352.3 70108.6 70119.0 70125.0 70226.6 40079.4 41166.2 41253.4 43798.8 74227.3 17560.6 17701.0 17771.8 17784.6 17796.3 12269.3 13471.3 13615.3 13627.0 13775.5 21343.0 21795.7 22064.3 22103.9 22132.1 7962.2 8103.8 8196.5 8302.4 8314.2 981.7 1088.0 1091.5 1129.6 1160.1 32813.3 34251.5 34717.5 34733.7 34750.5 1146.9 1157.2 1167.2 1200.2 1206.4 17684.7 18054.0 18075.6 18141.8 18186.6 571459.0 580383.0 585277.0 587573.0 590962.0 65923.3 66622.3 66652.7 66767.0 66787.0 1396.4 1439.0 1471.2 1493.5 1512.4 31801.8 34575.3 34670.7 34948.7 35404.5 55072.2 55390.4 55539.4 55629.2 55712.7 423303.0 423896.0 424218.0 425391.0 426153.0 26716.0 31564.8 31615.5 31757.0 33122.3 52326.5 53223.7 53297.0 53580.7 54376.0 44307.0 45034.5 45061.0 45648.5 45976.0 44895.0 45000.5 45086.0 45909.0 46269.0 44518.6 44588.3 45478.8 45577.5 46890.3 39677.8 40338.4 40607.2 40932.6 40934.4 29525.6 29765.6 29810.0 29840.9 30261.2 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#2] jquery.html Cycles:180 Average:29384.80 Median:2092.51 stddev:80764.80 (3859.7%) stddev-sans-first:80962.06 09:36:12 INFO - PROCESS | 19225 | Values: 407.2 412.8 421.7 422.8 443.7 18845.7 19600.0 19874.2 19960.2 20532.8 19229.1 19237.8 19244.3 20147.8 20286.6 5627.8 5790.6 5831.2 5833.1 5839.1 4246.6 4896.1 5689.5 5747.8 6338.6 6479.9 6496.8 6497.3 6515.0 6521.6 6268.4 6381.0 6419.8 6426.7 6473.1 3852.3 4021.2 5294.5 5681.8 5685.5 22262.1 22979.0 23819.5 24097.9 24210.3 832.5 1342.0 1718.8 2547.4 2635.9 14747.9 15162.9 15177.5 15366.2 15377.5 2048.9 2065.4 2119.7 2122.8 2160.9 73882.6 75004.5 77571.6 77937.2 78236.6 10935.7 10951.2 11035.2 11279.2 11428.7 15798.8 15857.3 15929.9 15940.1 15958.0 8202.2 8514.5 8528.1 8681.4 8682.0 8371.2 8443.4 8444.3 8605.3 8648.0 6814.3 6966.3 7004.0 7008.0 7023.1 1951.7 2015.7 2016.9 2023.8 2060.7 947.9 1002.2 1044.7 1045.3 1048.9 18642.6 18847.0 19854.2 19976.9 20201.5 1018.7 1048.6 1059.8 1083.3 1112.2 10648.6 10929.3 10947.9 11026.6 11029.5 478274.0 483461.0 486634.0 501916.0 502186.0 71663.4 82787.0 100292.5 100297.0 101691.0 1106.7 1124.8 1150.9 1190.3 1203.1 18055.4 18213.3 18337.4 18396.2 18537.7 38787.0 39855.6 40454.2 41866.6 42233.2 60831.7 61251.1 62119.0 62159.7 62829.0 8706.9 8931.4 9123.3 9177.2 9225.2 12607.3 13765.2 13805.2 13960.4 14077.9 11598.9 11710.9 12183.8 12274.7 12315.2 11707.9 11897.1 11938.5 12120.6 12238.3 12225.6 12394.4 12543.9 12593.2 12619.3 8771.5 9051.5 9185.6 9187.0 9324.3 12087.3 12264.3 12543.9 12569.7 12646.5 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#3] mootools.html Cycles:180 Average:6294.57 Median:4774.99 stddev:3745.39 (78.4%) stddev-sans-first:3737.78 09:36:12 INFO - PROCESS | 19225 | Values: 1392.6 1550.2 1596.8 1768.3 1882.9 9163.2 9565.5 9589.8 9834.7 9857.0 9617.7 9804.4 9806.5 9810.1 9812.3 2105.4 2440.0 2464.1 2481.7 2538.1 2509.0 2533.8 2542.8 2546.9 2547.5 2537.8 2560.5 2561.3 2563.0 2563.5 2128.7 2433.3 2497.6 2500.0 2515.3 2412.7 2428.9 2436.8 2475.7 2476.1 9842.5 9855.9 9856.1 9884.9 9928.0 5594.5 5747.3 5747.7 5748.4 5751.5 3473.1 3487.9 3495.4 3706.2 3737.7 1151.5 1187.2 1213.3 2145.2 2168.9 14038.8 14122.1 14125.1 14134.5 14218.0 4764.8 4785.2 4854.2 4873.7 4880.9 10777.1 10889.5 10927.9 10934.6 10938.3 5057.1 5627.1 5652.9 5653.2 5662.5 5153.3 5308.0 5315.5 5347.1 5357.2 4095.9 4125.5 4168.5 4203.6 4203.9 1215.3 1250.2 1256.1 1265.3 1266.1 859.8 935.5 956.6 959.2 964.0 5825.3 5854.4 5879.6 6025.8 6036.2 450.3 464.1 467.6 470.1 471.7 3700.4 3706.3 3764.9 3782.2 3836.9 11995.7 12253.6 12253.9 12335.4 12390.6 11814.5 12071.7 12118.6 12189.7 12206.3 8902.6 9098.0 9132.3 9255.5 9258.8 5855.8 5859.3 5872.8 5948.5 5992.8 11236.1 11288.5 11294.7 11303.2 11384.9 10675.7 11060.6 11091.3 11268.1 11378.8 7857.6 8388.2 8593.6 8600.9 8774.0 9596.4 9627.1 9637.1 9682.3 9688.4 8483.2 8774.6 8788.1 8821.5 8935.2 8222.9 8459.1 8569.0 8592.7 8766.0 7412.5 7471.8 7517.2 7529.8 7573.8 5191.0 5248.2 5317.1 5371.9 5436.9 5315.3 5354.9 5371.1 5376.8 5452.8 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#4] prototype.html Cycles:180 Average:4869.49 Median:5547.21 stddev:1430.07 (25.8%) stddev-sans-first:1393.29 09:36:12 INFO - PROCESS | 19225 | Values: 350.9 358.2 360.7 365.3 366.0 5540.7 5646.6 5657.9 5660.3 5668.3 5562.2 5565.6 5569.7 5580.6 5595.0 4156.5 4312.0 4314.9 4348.9 4351.8 4253.6 4290.9 4329.7 4347.6 4366.8 4277.1 4295.7 4297.5 4299.3 4314.4 4149.8 4266.9 4293.6 4301.6 4305.9 3804.7 3810.7 3813.3 3815.4 3817.4 5366.8 5382.5 5387.4 5414.9 5428.3 4732.6 5001.0 5005.9 5010.6 5013.3 5796.1 5841.5 5863.7 5877.6 5883.5 4190.3 4193.2 4197.0 4216.5 4248.6 5703.4 5842.8 5861.5 6009.7 6122.2 4144.7 4155.3 4163.6 4176.8 4283.9 4361.4 4362.8 4371.1 4383.2 4389.9 4872.4 4957.6 4963.5 4985.1 5023.5 5640.3 5648.2 5748.3 5817.7 5850.9 1479.1 1484.7 1491.0 1496.5 1505.7 920.8 939.5 947.2 954.7 955.7 5027.7 5046.9 5057.6 5076.1 5102.4 5309.8 5312.7 5315.5 5319.2 5328.8 5076.7 5106.9 5139.4 5304.2 5713.7 3181.8 3216.4 3221.9 3229.3 3235.4 6158.7 6305.0 6317.6 6335.4 6341.5 6029.6 6029.7 6064.2 6165.8 6177.9 6102.4 6145.6 6208.3 6211.3 6230.3 5774.2 5977.2 5994.4 6004.6 6010.3 5947.7 5950.9 5961.4 5974.2 5979.6 5898.7 6117.7 6143.4 6145.9 6260.6 4306.3 4318.3 4322.5 4340.5 4340.6 5775.9 5881.7 6039.8 6051.4 6094.2 5803.9 5811.8 5845.5 5873.4 5936.0 5835.0 5835.4 5853.7 5856.6 5856.7 5553.7 5735.8 5754.3 5768.7 5790.5 5811.9 5817.5 5832.8 5872.2 5882.9 5511.6 5721.9 5770.9 5781.0 5786.3 09:36:12 INFO - PROCESS | 19225 | 09:36:12 INFO - PROCESS | 19225 | [#5] yui.html Cycles:180 Average:20581.31 Median:4648.54 stddev:28864.54 (620.9%) stddev-sans-first:28906.98 09:36:12 INFO - PROCESS | 19225 | Values: 718.1 743.3 774.5 775.8 794.3 16443.3 16606.4 17339.2 17355.8 17373.7 13885.4 13903.2 13914.9 13928.9 13963.5 730.9 860.0 872.1 878.8 883.6 871.7 873.2 875.3 876.5 880.6 926.5 934.1 935.0 935.5 936.9 768.4 904.5 925.6 925.7 930.7 680.8 680.9 681.2 683.1 683.5 16359.7 16391.9 16398.3 16413.2 16421.3 14050.6 14079.9 14255.7 14407.4 14439.8 12446.6 12537.9 12537.9 12539.2 12725.9 4584.3 4592.7 4625.5 4628.6 4668.5 73650.0 73731.3 73868.3 74272.0 74894.6 10505.9 10762.1 10803.8 10818.5 10936.3 19983.6 20287.3 20300.9 20318.6 20331.8 8148.8 8207.7 8293.7 8329.2 8508.4 4964.1 5152.7 5190.6 5221.8 5309.0 3726.3 3809.9 3824.3 3830.6 3832.1 650.1 652.4 700.2 736.4 741.7 582.7 653.4 673.3 709.7 717.8 9622.8 10272.2 10296.5 10354.5 10469.7 706.0 719.3 782.4 791.2 810.1 4756.3 4931.7 5035.1 5049.4 5115.9 97321.0 99864.5 101857.0 113572.0 115359.0 91857.0 96831.5 99236.0 99744.5 101821.0 85153.6 88498.6 89149.6 89462.6 90477.5 9943.4 10181.6 10340.5 10384.6 10451.4 49626.0 49744.0 50992.5 51872.0 52051.5 64943.7 66713.3 73374.6 74711.3 76327.9 13189.8 13258.2 13332.3 13792.6 13888.1 10809.7 13857.4 14187.4 14875.7 15507.5 13587.5 14191.4 14405.0 14468.6 14539.6 13880.1 13957.7 14290.1 14337.9 14379.3 14170.4 14360.4 14362.8 14376.8 15128.8 10620.6 10678.1 10918.8 10968.6 11065.9 11793.6 11866.3 11916.7 12175.6 12528.2 09:36:12 INFO - PROCESS | 19225 | -------- Summary: end -------- 09:36:12 INFO - PROCESS | 19225 | 09:36:13 INFO - TEST-INFO | 19225: exit 0 09:36:13 INFO - TEST-OK | dromaeo_css | took 1113390ms 09:36:13 INFO - TEST-START | kraken 09:36:13 INFO - operating with platform_type : linux_ 09:36:13 INFO - Initialising browser for kraken test... 09:36:13 INFO - TEST-INFO | started process 19423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmj1u1/profile http://localhost:52204/getInfo.html) 09:36:17 INFO - PROCESS | 19423 | __metrics Screen width/height:1600/1200 09:36:17 INFO - PROCESS | 19423 | colorDepth:24 09:36:17 INFO - PROCESS | 19423 | Browser inner width/height: 1024/697 09:36:17 INFO - PROCESS | 19423 | __metrics 09:36:18 ERROR - PROCESS | 19423 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:36:19 INFO - TEST-INFO | 19423: exit 0 09:36:19 INFO - Browser initialized. 09:36:19 INFO - Running cycle 1/1 for kraken test... 09:36:19 INFO - TEST-INFO | started process 19515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmj1u1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:30 INFO - PROCESS | 19515 | RSS: Main: 145960960 09:36:30 INFO - PROCESS | 19515 | 09:36:33 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52204/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:34 INFO - PROCESS | 19515 | RSS: Main: 194830336 09:36:34 INFO - PROCESS | 19515 | 09:36:36 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52204/tests/kraken/driver.html?testName=audio-dft) 09:36:37 INFO - PROCESS | 19515 | RSS: Main: 188805120 09:36:37 INFO - PROCESS | 19515 | 09:36:40 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52204/tests/kraken/driver.html?testName=audio-fft) 09:36:40 INFO - PROCESS | 19515 | RSS: Main: 279310336 09:36:40 INFO - PROCESS | 19515 | 09:36:42 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52204/tests/kraken/driver.html?testName=audio-oscillator) 09:36:43 INFO - PROCESS | 19515 | RSS: Main: 187920384 09:36:43 INFO - PROCESS | 19515 | 09:36:45 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52204/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:46 INFO - PROCESS | 19515 | RSS: Main: 188592128 09:36:46 INFO - PROCESS | 19515 | 09:36:49 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52204/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:49 INFO - PROCESS | 19515 | RSS: Main: 222609408 09:36:49 INFO - PROCESS | 19515 | 09:36:53 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52204/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:53 INFO - PROCESS | 19515 | RSS: Main: 223244288 09:36:53 INFO - PROCESS | 19515 | 09:36:56 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52204/tests/kraken/driver.html?testName=json-parse-financial) 09:36:57 INFO - PROCESS | 19515 | RSS: Main: 223424512 09:36:57 INFO - PROCESS | 19515 | 09:36:58 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52204/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:59 INFO - PROCESS | 19515 | RSS: Main: 200896512 09:36:59 INFO - PROCESS | 19515 | 09:37:01 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:01 INFO - PROCESS | 19515 | RSS: Main: 202080256 09:37:01 INFO - PROCESS | 19515 | 09:37:03 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:04 INFO - PROCESS | 19515 | RSS: Main: 210440192 09:37:04 INFO - PROCESS | 19515 | 09:37:06 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:07 INFO - PROCESS | 19515 | RSS: Main: 213086208 09:37:07 INFO - PROCESS | 19515 | 09:37:10 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:10 INFO - PROCESS | 19515 | RSS: Main: 212406272 09:37:10 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | Cycle 1(1): loaded http://localhost:52204/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52204/tests/kraken/driver.html?testName=ai-astar) 09:37:12 INFO - PROCESS | 19515 | RSS: Main: 254197760 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | __start_tp_report 09:37:12 INFO - PROCESS | 19515 | _x_x_mozilla_page_load 09:37:12 INFO - PROCESS | 19515 | _x_x_mozilla_page_load_details 09:37:12 INFO - PROCESS | 19515 | |i|pagename|runs| 09:37:12 INFO - PROCESS | 19515 | |0;ai-astar;112;106;109;111;111;111;109;109;111;110 09:37:12 INFO - PROCESS | 19515 | |1;audio-beat-detection;165;123;125;174;130;127;152;123;173;124 09:37:12 INFO - PROCESS | 19515 | |2;audio-dft;161;160;160;161;159;160;160;161;160;160 09:37:12 INFO - PROCESS | 19515 | |3;audio-fft;75;75;76;126;76;76;76;75;96;74 09:37:12 INFO - PROCESS | 19515 | |4;audio-oscillator;85;84;110;83;84;84;120;84;94;135 09:37:12 INFO - PROCESS | 19515 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:37:12 INFO - PROCESS | 19515 | |6;imaging-darkroom;146;146;146;146;145;145;145;146;146;145 09:37:12 INFO - PROCESS | 19515 | |7;imaging-desaturate;94;96;96;95;95;95;95;95;95;95 09:37:12 INFO - PROCESS | 19515 | |8;json-parse-financial;50;48;49;50;50;51;53;51;50;51 09:37:12 INFO - PROCESS | 19515 | |9;json-stringify-tinderbox;60;60;60;59;61;59;59;60;62;60 09:37:12 INFO - PROCESS | 19515 | |10;stanford-crypto-aes;82;79;75;74;76;78;77;76;102;77 09:37:12 INFO - PROCESS | 19515 | |11;stanford-crypto-ccm;133;121;121;126;128;124;124;126;129;121 09:37:12 INFO - PROCESS | 19515 | |12;stanford-crypto-pbkdf2;154;158;157;154;153;155;155;156;156;155 09:37:12 INFO - PROCESS | 19515 | |13;stanford-crypto-sha256-iterative;67;63;63;62;60;69;68;60;62;59 09:37:12 INFO - PROCESS | 19515 | __end_tp_report 09:37:12 INFO - PROCESS | 19515 | __start_cc_report 09:37:12 INFO - PROCESS | 19515 | _x_x_mozilla_cycle_collect,1588 09:37:12 INFO - PROCESS | 19515 | __end_cc_report 09:37:12 INFO - PROCESS | 19515 | __startTimestamp1467650232683__endTimestamp 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | ------- Summary: start ------- 09:37:12 INFO - PROCESS | 19515 | Number of tests: 14 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:1.73 (1.6%) stddev-sans-first:1.66 09:37:12 INFO - PROCESS | 19515 | Values: 112.0 106.0 109.0 111.0 111.0 111.0 109.0 109.0 111.0 110.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:141.00 stddev:21.90 (15.5%) stddev-sans-first:21.53 09:37:12 INFO - PROCESS | 19515 | Values: 165.0 123.0 125.0 174.0 130.0 127.0 152.0 123.0 173.0 124.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 09:37:12 INFO - PROCESS | 19515 | Values: 161.0 160.0 160.0 161.0 159.0 160.0 160.0 161.0 160.0 160.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:16.61 (21.9%) stddev-sans-first:17.40 09:37:12 INFO - PROCESS | 19515 | Values: 75.0 75.0 76.0 126.0 76.0 76.0 76.0 75.0 96.0 74.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:84.00 stddev:18.74 (22.3%) stddev-sans-first:19.43 09:37:12 INFO - PROCESS | 19515 | Values: 85.0 84.0 110.0 83.0 84.0 84.0 120.0 84.0 94.0 135.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:37:12 INFO - PROCESS | 19515 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:37:12 INFO - PROCESS | 19515 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 145.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 09:37:12 INFO - PROCESS | 19515 | Values: 94.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.34 (2.6%) stddev-sans-first:1.41 09:37:12 INFO - PROCESS | 19515 | Values: 50.0 48.0 49.0 50.0 50.0 51.0 53.0 51.0 50.0 51.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.94 (1.6%) stddev-sans-first:1.00 09:37:12 INFO - PROCESS | 19515 | Values: 60.0 60.0 60.0 59.0 61.0 59.0 59.0 60.0 62.0 60.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:77.00 stddev:8.18 (10.6%) stddev-sans-first:8.63 09:37:12 INFO - PROCESS | 19515 | Values: 82.0 79.0 75.0 74.0 76.0 78.0 77.0 76.0 102.0 77.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:126.00 stddev:3.95 (3.1%) stddev-sans-first:3.05 09:37:12 INFO - PROCESS | 19515 | Values: 133.0 121.0 121.0 126.0 128.0 124.0 124.0 126.0 129.0 121.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.30 Median:155.50 stddev:1.49 (1.0%) stddev-sans-first:1.51 09:37:12 INFO - PROCESS | 19515 | Values: 154.0 158.0 157.0 154.0 153.0 155.0 155.0 156.0 156.0 155.0 09:37:12 INFO - PROCESS | 19515 | 09:37:12 INFO - PROCESS | 19515 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:3.53 (5.6%) stddev-sans-first:3.48 09:37:12 INFO - PROCESS | 19515 | Values: 67.0 63.0 63.0 62.0 60.0 69.0 68.0 60.0 62.0 59.0 09:37:12 INFO - PROCESS | 19515 | -------- Summary: end -------- 09:37:12 INFO - PROCESS | 19515 | 09:37:13 INFO - TEST-INFO | 19515: exit 0 09:37:13 INFO - TEST-OK | kraken | took 60322ms 09:37:13 INFO - SUITE-END | took 1173s 09:37:13 INFO - Completed test suite (00:19:33) 09:37:13 INFO - javascript benchmark 09:37:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.557667503225, 3409.948850767237, 3415.4193787056206, 3447.1382154461385, 3469.2541103662707, 5532.0318944184755, 5618.662880227184, 5629.802956896509, 5637.182608521762, 5639.436056394358, 5558.44415558444, 5594.384617692051, 5597.048501754702, 5601.971990140044, 5605.775768969236, 4912.852614421573, 5048.7223202723835, 5066.493350664941, 5097.872553186163, 5153.510416510426, 4959.0, 5032.672876263049, 5085.720285384303, 5095.79616815327, 5104.132297509414, 5111.309973153614, 5129.410117836441, 5150.227465880111, 5184.066867963765, 5185.455527169649, 5288.0, 5296.364436267634, 5300.522952934216, 5301.73491325436, 5333.679979201222, 4961.7022978621035, 5022.573081288095, 5057.924131138036, 5186.118359878829, 5196.714180720059, 5439.945600544023, 5446.564274858002, 5459.426760190163, 5517.200005999108, 5530.751116199781, 6059.757609695607, 6067.820924070054, 6076.4834989025985, 6106.084087386883, 6107.908381374283, 5968.731407086691, 6000.0, 6017.06735456005, 6107.4197951194565, 6108.725107370179, 5653.010723123437, 5668.943310566924, 5685.857853553653, 5707.571932105067, 5710.628809127435, 5909.202257695156, 6184.969075154595, 6198.938010619926, 6204.224471941007, 6205.751769929243, 5929.555283353766, 5974.940250597525, 5992.0712289595185, 6066.0, 6077.65617375545, 6110.419510146529, 6126.30393285589, 6135.171422439398, 6136.610985192573, 6143.32423433422, 5777.711114444261, 5875.941240587539, 6121.056693972082, 6139.921477737008, 6143.266246520256, 5923.940760592339, 5990.161377407167, 5991.700414979234, 6003.759849605967, 6030.30651475086, 4570.9314360284625, 4579.793909274091, 4590.977045114753, 4591.862244132682, 4609.469910960215, 4027.637512623878, 4050.3519436889956, 4057.756534607933, 4113.835446582163, 4141.730787498803, 5680.403557626472, 5691.516221121251, 5702.971485142548, 5711.7144142792695, 5724.112762521816, 5973.850653733604, 6017.398260173947, 6028.095785632103, 6040.124181993587, 6057.151998720183, 5601.467860553241, 5667.121596152603, 5691.089425691869, 5705.857353566194, 5721.914171287434, 5205.453427390145, 5226.738663066832, 5228.712420816892, 5230.738463076831, 5233.528982391527, 6305.621662700161, 6310.337414571404, 6310.810675679737, 6312.305646378894, 6321.683915804191, 5816.82549523515, 6056.515478761777, 6135.110408990582, 6139.938600614116, 6172.043333283348, 6341.365863413622, 6373.139626150411, 6377.202849643794, 6379.904301435389, 6392.648404337806, 5760.884782304414, 6057.848553786191, 6059.151718759465, 6063.272407310975, 6067.271927368744, 5971.7611295547695, 5991.2510936132985, 6046.123312119806, 6101.694915254131, 6108.08378743192, 5893.882122357614, 5933.110033495011, 5937.614055086406, 5943.167956486009, 5947.613405128653, 5711.685857277724, 5858.297004359504, 5872.853178670567, 5930.0, 5939.67331796738, 5828.446297601721, 5915.763369465173, 5922.851928701817, 5928.377520360473, 5957.91063134062, 6043.728032238472, 6045.10185493951, 6051.213342265477, 6071.666058366833, 6082.2093127893095, 5527.944720552743, 5701.771929122789, 5743.1385292206505, 5905.734241959208, 5928.703564821828], "value": 5578.26861913338, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.9879202415949, 632.2792017100505, 646.0761111610398, 661.0579923608856, 661.4179522020621, 33207.16982075446, 36783.632163678325, 36797.81601091994, 36798.448023279605, 36814.26371472569, 47815.282770758466, 48605.27092093613, 48611.51388486114, 52418.0, 52439.0, 8973.0, 9103.0, 9103.135202155785, 9106.089391060874, 9107.36248462608, 8378.622961966696, 8848.2478989286, 10579.3652380857, 10840.67477975662, 10847.240693151483, 10917.126629869592, 10929.672109836718, 10932.508037138348, 10981.945090274578, 10987.39569323687, 8403.36974726898, 10821.891781082168, 10895.0, 10895.182861285435, 10911.890881091247, 10054.0, 10913.454327283604, 10983.835242471368, 11265.718357041058, 11268.492917818718, 42064.36903446451, 42721.57278427207, 46306.76846615745, 46366.30450543245, 46429.53570464286, 3742.906427339311, 3812.2375524895133, 3852.633999770045, 4140.37894315852, 4395.868123956287, 35115.29769404623, 35418.8229058853, 35457.29085418303, 35588.822055889555, 35899.102522436886, 4750.358701575312, 4808.759562021885, 4841.080194762983, 4842.709437433765, 4842.878928026792, 381951.0, 384384.0, 384834.0, 384861.07569462253, 386651.9999999972, 69352.3064769356, 70108.64945675239, 70119.0, 70125.0, 70226.64886675533, 40079.39880901789, 41166.176676466304, 41253.381199282034, 43798.78100609477, 74227.25772742204, 17560.56098597545, 17701.0, 17771.822281777277, 17784.644307114046, 17796.288148474174, 12269.263844169378, 13471.259080750653, 13615.319234038456, 13627.0, 13775.517856874962, 21343.0, 21795.67306490404, 22064.33806985793, 22103.889480552494, 22132.114715411524, 7962.2037796221075, 8103.756887293391, 8196.508209507449, 8302.418830681794, 8314.214738062696, 981.7202097402206, 1087.983680244797, 1091.4870011094772, 1129.632869317475, 1160.100921785606, 32813.343733125206, 34251.486227706606, 34717.47923781145, 34733.65266347304, 34750.49880386325, 1146.850909381775, 1157.2246594781348, 1167.1654766841748, 1200.1778781534676, 1206.3847437806605, 17684.734728979074, 18054.0, 18075.638487230182, 18141.818581814012, 18186.63626727458, 571459.0, 580383.0, 585277.0, 587573.0, 590962.0, 65923.34076659172, 66622.33377666162, 66652.66673666601, 66767.0, 66787.0, 1396.3576754692956, 1439.0358459831937, 1471.220253265771, 1493.5444689369629, 1512.3648067811325, 31801.8409907949, 34575.317126049355, 34670.65329346674, 34948.65051349556, 35404.46893296654, 55072.17391739047, 55390.44609553853, 55539.44460555343, 55629.1655625174, 55712.72143639256, 423303.0, 423896.0, 424218.0, 425391.0, 426153.0, 26716.0, 31564.842175788974, 31615.525767113053, 31757.0, 33122.33755324928, 52326.47673523216, 53223.73388133034, 53297.0, 53580.73209634083, 54376.0, 44307.0, 45034.54965450435, 45061.0, 45648.54351456443, 45976.0, 44895.0, 45000.54999450094, 45086.0, 45909.0, 46269.0, 44518.55481445144, 44588.33117503305, 45478.77260613676, 45577.54422455733, 46890.296645549664, 39677.80161099176, 40338.394924075576, 40607.1878562433, 40932.59067409407, 40934.38598421086, 29525.557116643697, 29765.553516696833, 29809.95665151796, 29840.850795745882, 30261.243468912573], "value": 21772.358293574558, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.2303346674784, 412.83486605357865, 421.69637860740255, 422.7864928211255, 443.6650329001603, 18845.71731424028, 19600.0, 19874.205031798712, 19960.201591936304, 20532.794672053275, 19229.134688938997, 19237.80762192381, 19244.326448574302, 20147.798522014775, 20286.59426811463, 5627.831165065055, 5790.594658373915, 5831.241938548004, 5833.095870140135, 5839.065749480084, 4246.639035681956, 4896.069746748124, 5689.4879460848515, 5747.827565173052, 6338.587991780542, 6479.902801457982, 6496.837579060514, 6497.285298617172, 6514.967425162891, 6521.641309727963, 6268.373162683741, 6381.042843573455, 6419.807405777788, 6426.742930282783, 6473.1261279727105, 3852.3065848147603, 4021.195760847813, 5294.549963253128, 5681.829545113653, 5685.4883060524335, 22262.109515619355, 22979.0, 23819.523609527885, 24097.87951060251, 24210.273691789276, 832.4713806732708, 1341.9731605367838, 1718.8054302259934, 2547.4013606802387, 2635.9438741202252, 14747.926260368737, 15162.924185379003, 15177.468788592456, 15366.154861482613, 15377.461788837449, 2048.94877628059, 2065.3597384810605, 2119.6608542633257, 2122.8301735861096, 2160.8811515366647, 73882.63058684726, 75004.4873456852, 77571.61214193949, 77937.22062779356, 78236.60881695613, 10935.671929842118, 10951.178661600412, 11035.2275340727, 11279.154063445274, 11428.657140285804, 15798.763018554731, 15857.286422111032, 15929.920350398173, 15940.123293218985, 15958.042517448757, 8202.220789025034, 8514.48913065218, 8528.147185281547, 8681.435706679045, 8681.956590217009, 8371.246587807005, 8443.36674749384, 8444.324454043663, 8605.31157507398, 8647.956760216284, 6814.318568143146, 6966.338197871194, 7004.0, 7007.964960175268, 7023.08699869024, 1951.7170010348705, 2015.7077223802467, 2016.899155042242, 2023.7672667643114, 2060.732104826363, 947.9383840050375, 1002.1682003936716, 1044.6604853422668, 1045.3309881675582, 1048.9265751397481, 18642.627147456977, 18847.0, 19854.205831766856, 19976.90011549962, 20201.49496262576, 1018.7351288664852, 1048.6015314180563, 1059.772148987956, 1083.2628470521984, 1112.1881026850283, 10648.627298044532, 10929.289596176382, 10947.890521094847, 11026.614068507564, 11029.503672334604, 478274.0, 483461.0, 486634.0, 501915.96168077167, 502186.0, 71663.42848602538, 82787.0, 100292.49853750685, 100297.0, 101691.0, 1106.690126764507, 1124.791913496006, 1150.8734039255676, 1190.2501424102759, 1203.1457665057733, 18055.368062117763, 18213.271469141087, 18337.449876503728, 18396.172172252012, 18537.721934171266, 38787.0, 39855.601443985186, 40454.1909161821, 41866.58133418627, 42233.1553368937, 60831.69584152051, 61251.081233780635, 62119.0, 62159.6892015537, 62829.0, 8706.869396958924, 8931.374803763674, 9123.270138389045, 9177.21993630549, 9225.215856651992, 12607.306598137191, 13765.174089554659, 13805.240711760547, 13960.441582336998, 14077.929610351883, 11598.94200528992, 11710.882891170979, 12183.817242741188, 12274.669581635837, 12315.19951203169, 11707.941460292644, 11897.107716921093, 11938.462769175234, 12120.636380908587, 12238.265704057429, 12225.572104976283, 12394.442250098948, 12543.87456125427, 12593.181443206162, 12619.30593817349, 8771.473711577326, 9051.54742262871, 9185.586648600962, 9187.0, 9324.254059675346, 12087.274763514217, 12264.325462099314, 12543.93728031354, 12569.685757856127, 12646.494140234288], "value": 9641.407695978165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.596147117336, 1550.2481296571164, 1596.848299411556, 1768.301520899245, 1882.9340973065955, 9163.221126204273, 9565.473898935565, 9589.808203835919, 9834.704958851227, 9857.0, 9617.663381781638, 9804.362716423446, 9806.509674516281, 9810.117089461946, 9812.264080193978, 2105.4210092224685, 2440.0239904038394, 2464.0636558107894, 2481.7394173611783, 2538.0989748639245, 2509.0, 2533.8353007054484, 2542.8474291542475, 2546.885390157438, 2547.4777670577487, 2537.796976241896, 2560.513502434541, 2561.346856551586, 2562.987185064072, 2563.51293254282, 2128.7232659754286, 2433.3429973907037, 2497.625356196567, 2499.9500009999697, 2515.283144303883, 2412.746661600524, 2428.86641234732, 2436.792872605831, 2475.7152927413217, 2476.05909754294, 9842.458664773434, 9855.90144098557, 9856.063673950963, 9884.90115098847, 9927.950360248153, 5594.4964953154, 5747.252857128588, 5747.741351639186, 5748.425157484218, 5751.482366586985, 3473.096994781375, 3487.9128021799656, 3495.4407294832704, 3706.184639379332, 3737.682297004758, 1151.4875880233299, 1187.1749134351628, 1213.2599114540124, 2145.249162793026, 2168.8698678079368, 14038.789418158736, 14122.082064665867, 14125.081869678437, 14134.50529231472, 14218.0, 4764.8003955011745, 4785.186518291899, 4854.150523658345, 4873.707577545359, 4880.853574392774, 10777.137828973664, 10889.509972051119, 10927.945360273148, 10934.617288394868, 10938.343699377903, 5057.140286151363, 5627.071533197059, 5652.886942261214, 5653.208550802891, 5662.490375866192, 5153.304303918998, 5307.973460132752, 5315.548178404879, 5347.064263753828, 5357.19642053695, 4095.9180816383505, 4125.525564560054, 4168.499780026416, 4203.642690371292, 4203.894902627459, 1215.3194211241732, 1250.249850089957, 1256.0705078242036, 1265.3230521670862, 1266.050462153385, 859.8065435277013, 935.4667839331652, 956.5934477846887, 959.2302252149121, 964.0, 5825.300963884276, 5854.356020837705, 5879.588428809942, 6025.8192254232445, 6036.185115009417, 450.34025153150793, 464.06490920794624, 467.59085799925066, 470.1067970855402, 471.69104236725, 3700.389435743126, 3706.29580379727, 3764.9058773530332, 3782.167923056923, 3836.9040773980873, 11995.700107497383, 12253.632391028283, 12253.877461225451, 12335.383230838423, 12390.5663301784, 11814.523184601925, 12071.698207544881, 12118.575849845212, 12189.695257618454, 12206.32865192423, 8902.599383027778, 9098.04470530598, 9132.3150763693, 9255.53722313895, 9258.768530786785, 5855.765769369348, 5859.296884373903, 5872.825434912949, 5948.464638182584, 5992.760289588367, 11236.101111911192, 11288.492017859051, 11294.661160165208, 11303.208775385972, 11384.886151138382, 10675.733106672085, 11060.612878549535, 11091.33451992883, 11268.098552115975, 11378.772424551627, 7857.607119644109, 8388.161183881562, 8593.570321484025, 8600.913990860261, 8774.034856165945, 9596.376235544667, 9627.133557979816, 9637.132658060807, 9682.322237443312, 9688.41869487833, 8483.236508714246, 8774.605142768718, 8788.121187881161, 8821.514816685143, 8935.151160639729, 8222.917770822454, 8459.111793261618, 8569.0, 8592.699255526026, 8765.956170219108, 7412.481126321321, 7471.813204669927, 7517.248275172439, 7529.811754705957, 7573.772786816405, 5190.987757387274, 5248.239005344128, 5317.122674758776, 5371.892562148657, 5436.918446223389, 5315.282436870973, 5354.866128346823, 5371.086915224343, 5376.838694839161, 5452.836414907559], "value": 4888.339611774498, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.9385857474942, 358.21372088266247, 360.6988164882323, 365.32415032190465, 365.98170091495416, 5540.697935985046, 5646.576506761989, 5657.886842263162, 5660.264165658458, 5668.319801623806, 5562.192287506567, 5565.582581306398, 5569.6658200508, 5580.609357344987, 5594.9720251398785, 4156.521999970005, 4311.9651283692, 4314.935275970863, 4348.934765978513, 4351.758844195242, 4253.574642535753, 4290.914181716364, 4329.67527435442, 4347.586979236968, 4366.825326986933, 4277.14457108578, 4295.7232666686705, 4297.5057868345075, 4299.269124248885, 4314.417553630251, 4149.751014939098, 4266.935995960063, 4293.570642935712, 4301.634361079312, 4305.913881722364, 3804.714646401531, 3810.733248672594, 3813.3136035513876, 3815.4086116651965, 3817.4464702618243, 5366.806749983753, 5382.546712387633, 5387.353517577876, 5414.891702165974, 5428.267183930158, 4732.645051621108, 5001.0, 5005.874853128701, 5010.599152067863, 5013.298138260647, 5796.1309151970045, 5841.503472204868, 5863.736131874076, 5877.617954832922, 5883.529317654577, 4190.329547272422, 4193.245215861144, 4196.979015104936, 4216.494020717503, 4248.596383343577, 5703.429657034305, 5842.824715258506, 5861.501772349313, 6009.729562169713, 6122.234720659918, 4144.689148313889, 4155.293600087993, 4163.625273725379, 4176.770277634699, 4283.914321713548, 4361.411209486679, 4362.825486980485, 4371.103923695635, 4383.189110014658, 4389.934150987738, 4872.439669437989, 4957.553820156203, 4963.528464795839, 4985.105659849234, 5023.547880690755, 5640.294963129609, 5648.192578374963, 5748.281464816898, 5817.7091145442555, 5850.9414905851245, 1479.127314884202, 1484.7030593881268, 1490.970180596382, 1496.5360738171203, 1505.6838064006463, 920.8250432417819, 939.455116032713, 947.1901524196712, 954.6897258391051, 955.7323949294208, 5027.673201241908, 5046.899062018812, 5057.595392368602, 5076.13705670037, 5102.413222479388, 5309.814156504503, 5312.707801070978, 5315.521603055744, 5319.244649265733, 5328.786848526015, 5076.695398276115, 5106.923396149061, 5139.357580302462, 5304.230886521433, 5713.657180569096, 3181.80909145452, 3216.3728073025536, 3221.90334289967, 3229.257270827723, 3235.433799085151, 6158.692065396713, 6305.0, 6317.589356691892, 6335.434149169594, 6341.524385671093, 6029.608075475167, 6029.698515074228, 6064.181335519736, 6165.753369865245, 6177.938220617826, 6102.389761023862, 6145.5698101133385, 6208.286047104551, 6211.2857021442205, 6230.252369715572, 5774.249347584787, 5977.163197152315, 5994.3705910878725, 6004.628171872173, 6010.278766548042, 5947.732352044255, 5950.9404905952115, 5961.4038596140035, 5974.2233509643465, 5979.551533634908, 5898.734556945034, 6117.665872099806, 6143.416375444325, 6145.924463219008, 6260.561760676709, 4306.25596633364, 4318.3090705486975, 4322.524522302607, 4340.544242854581, 4340.631046360968, 5775.8556036099435, 5881.73532191061, 6039.758409663565, 6051.425114614105, 6094.20775299226, 5803.854903627274, 5811.82564523065, 5845.532357411312, 5873.4126587340925, 5936.0, 5835.0, 5835.358110607915, 5853.736581853742, 5856.560757943256, 5856.707164641836, 5553.72231388437, 5735.827925162252, 5754.309482862083, 5768.721355064642, 5790.536757059509, 5811.883762324815, 5817.534597232296, 5832.766689332379, 5872.1778950946045, 5882.882342353215, 5511.559075273888, 5721.856953576027, 5770.942290577208, 5781.0, 5786.276715410574], "value": 4415.7733962385955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.0557566799662, 743.3495691270139, 774.4617490843863, 775.8487095016472, 794.2835502581173, 16443.342266309348, 16606.418775342867, 17339.219735111918, 17355.82644173558, 17373.739393909087, 13885.375158117884, 13903.165810051403, 13914.930425347884, 13928.93035534821, 13963.511277105303, 730.9122905251377, 859.9656013759442, 872.1322284327088, 878.802269489363, 883.6156272021659, 871.7384784564637, 873.244643383475, 875.2604049578097, 876.5310558851016, 880.6081293824251, 926.4811705444972, 934.0752654871662, 934.9626014959393, 935.5228833295, 936.9203617692505, 768.3699366519456, 904.5160838951163, 925.6112432778182, 925.6945208081318, 930.6835675870236, 680.7855525509498, 680.9455243580502, 681.158768920383, 683.111954459206, 683.453237410075, 16359.672806543922, 16391.91804040972, 16398.26207820651, 16413.1793410329, 16421.261043253082, 14050.578482645536, 14079.85920140806, 14255.714885702331, 14407.423703051865, 14439.783403248959, 12446.564370246997, 12537.934275586585, 12537.937310313482, 12539.184952978027, 12725.872741272655, 4584.289435137559, 4592.678512504126, 4625.537446255347, 4628.560286772751, 4668.50980647034, 73649.99999999946, 73731.26268737298, 73868.26131738721, 74272.0, 74894.62552687257, 10505.894941050645, 10762.139028877671, 10803.837942430713, 10818.513166907507, 10936.343819370863, 19983.600327993357, 20287.28994485186, 20300.898495507427, 20318.593628127353, 20331.796682033288, 8148.755537333889, 8207.71273005442, 8293.668253269801, 8329.167083291744, 8508.404411691246, 4964.145616270659, 5152.690838549699, 5190.6366554341585, 5221.765020574083, 5309.017831701148, 3726.347889119393, 3809.942850857239, 3824.2733880562605, 3830.597787232356, 3832.080300727805, 650.0931200974709, 652.3704625036768, 700.2087640965779, 736.3777607921298, 741.7255615422221, 582.6591444005304, 653.3858173317067, 673.2997682410238, 709.6841905352172, 717.8169566760442, 9622.807543849223, 10272.178225742073, 10296.536655850505, 10354.482275886176, 10469.738256543647, 705.989410158848, 719.3022767915114, 782.3506489613607, 791.192983157188, 810.0765127754365, 4756.310335001405, 4931.654784165074, 5035.093683137071, 5049.368828896388, 5115.948840511547, 97321.0, 99864.50067749615, 101857.0, 113572.0, 115359.0, 91857.0, 96831.51584242034, 99236.0, 99744.5012774946, 101821.0, 85153.57423212845, 88498.55750721334, 89149.55425222962, 89462.55268723745, 90477.54761226283, 9943.353682010646, 10181.643642472478, 10340.482975851328, 10384.584616615251, 10451.42517161533, 49626.0, 49744.0, 50992.49007509877, 51872.0, 52051.479485206175, 64943.71412241284, 66713.33286667071, 73374.63312683403, 74711.25288747043, 76327.85508217492, 13189.802152967904, 13258.20450772918, 13332.2667253302, 13792.586222413343, 13888.097273677178, 10809.721375622003, 13857.37641806101, 14187.361568729226, 14875.702485950436, 15507.534773956799, 13587.52443664467, 14191.361388737738, 14405.0, 14468.565943021726, 14539.636509087357, 13880.097793643381, 13957.720845582828, 14290.142591444546, 14337.928310358382, 14379.281035947952, 14170.433182672577, 14360.425582976564, 14362.784558231844, 14376.784348234994, 15128.773068404204, 10620.628278010232, 10678.092362149304, 10918.836217456586, 10968.616098436516, 11065.944670276596, 11793.646190614296, 11866.288022718665, 11916.732909380036, 12175.634730958085, 12528.248305101723], "value": 7780.205702447162, "unit": "score"}], "name": "dromaeo_css", "value": 7625.906107378543, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 109.0, 111.0, 111.0, 111.0, 109.0, 109.0, 111.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 123.0, 125.0, 174.0, 130.0, 127.0, 152.0, 123.0, 173.0, 124.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 159.0, 160.0, 160.0, 161.0, 160.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 126.0, 76.0, 76.0, 76.0, 75.0, 96.0, 74.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 110.0, 83.0, 84.0, 84.0, 120.0, 84.0, 94.0, 135.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 49.0, 50.0, 50.0, 51.0, 53.0, 51.0, 50.0, 51.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 61.0, 59.0, 59.0, 60.0, 62.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 75.0, 74.0, 76.0, 78.0, 77.0, 76.0, 102.0, 77.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 121.0, 121.0, 126.0, 128.0, 124.0, 124.0, 126.0, 129.0, 121.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 158.0, 157.0, 154.0, 153.0, 155.0, 155.0, 156.0, 156.0, 155.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 62.0, 60.0, 69.0, 68.0, 60.0, 62.0, 59.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1481.9999999999998, "alertThreshold": 2.0}]} 09:37:13 INFO - Return code: 0 09:37:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:13 ERROR - # TBPL SUCCESS # 09:37:13 INFO - Running post-action listener: _resource_record_post_action 09:37:13 INFO - ##### 09:37:13 INFO - ##### Finished run-tests step (success) 09:37:13 INFO - ##### 09:37:13 INFO - Running post-run listener: _resource_record_post_run 09:37:14 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 290816; Write bytes: 507547648; Read time: 36; Write time: 368172 09:37:14 INFO - TinderboxPrint: CPU usage
13.1% 09:37:14 INFO - TinderboxPrint: I/O read bytes / time
290,816 / 36 09:37:14 INFO - TinderboxPrint: I/O write bytes / time
507,547,648 / 368,172 09:37:14 INFO - TinderboxPrint: CPU idle
8,262.1 (86.9%) 09:37:14 INFO - TinderboxPrint: CPU user
1,199.3 (12.6%) 09:37:14 INFO - TinderboxPrint: Swap in / out
0 / 0 09:37:14 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252952576; Read time: 0; Write time: 230168 09:37:14 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254595072; Read time: 20; Write time: 138004 09:37:14 INFO - Running post-run listener: _upload_blobber_files 09:37:14 INFO - Blob upload gear active. 09:37:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:14 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 09:37:14 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:14 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:18 INFO - (blobuploader) - INFO - Done attempting. 09:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:19 INFO - (blobuploader) - INFO - Done attempting. 09:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:21 INFO - (blobuploader) - INFO - Done attempting. 09:37:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:21 INFO - Return code: 0 09:37:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fb73e0d78bceb8ec605733ed53a57985b9fcb1d7122ae37d1ccd666622da11cc9aff85355d8fc7a6b653b84600a9ab1ce4d2bee9e41557605c02f0e56f3d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38f220100720478fba92da0b5f3885e27dafd65de74275d2f506e9b82d700a48fd452d818d070002dcd300b714d36f425699fd3b362e01d5ff19a4a1e7436818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8e79b92a7a608915844c99c1348d84a9b01cc8137ff9fe7a20eb4b038ce99b7ff5ff9ae543468d14b1b7534bbe58f82697ea57b02e3fc97a5f3a842a4d6adc"} 09:37:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:21 INFO - Contents: 09:37:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fb73e0d78bceb8ec605733ed53a57985b9fcb1d7122ae37d1ccd666622da11cc9aff85355d8fc7a6b653b84600a9ab1ce4d2bee9e41557605c02f0e56f3d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38f220100720478fba92da0b5f3885e27dafd65de74275d2f506e9b82d700a48fd452d818d070002dcd300b714d36f425699fd3b362e01d5ff19a4a1e7436818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8e79b92a7a608915844c99c1348d84a9b01cc8137ff9fe7a20eb4b038ce99b7ff5ff9ae543468d14b1b7534bbe58f82697ea57b02e3fc97a5f3a842a4d6adc"} 09:37:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:21 INFO - Copying logs to upload dir... 09:37:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.143930 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-04 09:37:21.081758) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:21.085678) ========= 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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fb73e0d78bceb8ec605733ed53a57985b9fcb1d7122ae37d1ccd666622da11cc9aff85355d8fc7a6b653b84600a9ab1ce4d2bee9e41557605c02f0e56f3d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38f220100720478fba92da0b5f3885e27dafd65de74275d2f506e9b82d700a48fd452d818d070002dcd300b714d36f425699fd3b362e01d5ff19a4a1e7436818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8e79b92a7a608915844c99c1348d84a9b01cc8137ff9fe7a20eb4b038ce99b7ff5ff9ae543468d14b1b7534bbe58f82697ea57b02e3fc97a5f3a842a4d6adc"} build_url:https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/IWDdJudcR2iIVsasRkp8Cw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43fb73e0d78bceb8ec605733ed53a57985b9fcb1d7122ae37d1ccd666622da11cc9aff85355d8fc7a6b653b84600a9ab1ce4d2bee9e41557605c02f0e56f3d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38f220100720478fba92da0b5f3885e27dafd65de74275d2f506e9b82d700a48fd452d818d070002dcd300b714d36f425699fd3b362e01d5ff19a4a1e7436818", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8e79b92a7a608915844c99c1348d84a9b01cc8137ff9fe7a20eb4b038ce99b7ff5ff9ae543468d14b1b7534bbe58f82697ea57b02e3fc97a5f3a842a4d6adc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:21.111387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:21.111717) ========= 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=8887e36bb8e381bfd44124ac000001a8-1467649006.357658-1721958419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 09:37:21.196112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:37:21.196442) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 09:37:21.196752) ========= ========= Total master_lag: 0.17 =========