builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1463285297.81 results: success (0) buildid: 20160514182335 builduid: cca324c9e5104676bf7e026fb9914f98 revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:17.808851) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:17.809641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:17.810126) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:17.852981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:17.853343) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 21:08:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-05-14 21:08:18 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.248280 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.114746) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.115027) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018300 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.164266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.164625) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --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 f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 21:08:18,654 truncating revision to first 12 chars 2016-05-14 21:08:18,654 Setting DEBUG logging. 2016-05-14 21:08:18,654 attempt 1/10 2016-05-14 21:08:18,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f00f9e754ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 21:08:18,922 unpacking tar archive at: mozilla-aurora-f00f9e754ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.569485 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.747179) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.747453) ========= script_repo_revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.747872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.748131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 21:08:18.756537) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-14 21:08:18.756852) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:08:19 INFO - MultiFileLogger online at 20160514 21:08:19 in /builds/slave/test 21:08:19 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 21:08:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:19 INFO - {'append_to_log': False, 21:08:19 INFO - 'base_work_dir': '/builds/slave/test', 21:08:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:08:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:19 INFO - 'branch': 'Mozilla-Aurora', 21:08:19 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:19 INFO - 'config_files': ('talos/linux_config.py',), 21:08:19 INFO - 'default_actions': ('clobber', 21:08:19 INFO - 'read-buildbot-config', 21:08:19 INFO - 'download-and-extract', 21:08:19 INFO - 'populate-webroot', 21:08:19 INFO - 'create-virtualenv', 21:08:19 INFO - 'install', 21:08:19 INFO - 'run-tests'), 21:08:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:19 INFO - 'download_minidump_stackwalk': True, 21:08:19 INFO - 'download_symbols': 'ondemand', 21:08:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:19 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:19 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:19 INFO - 'installer_path': 'installer.exe', 21:08:19 INFO - 'log_level': 'info', 21:08:19 INFO - 'log_name': 'talos', 21:08:19 INFO - 'log_to_console': True, 21:08:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:08:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:08:19 INFO - 'opt_config_files': (), 21:08:19 INFO - 'pip_index': False, 21:08:19 INFO - 'sps_profile': False, 21:08:19 INFO - 'sps_profile_interval': 0, 21:08:19 INFO - 'suite': 'dromaeojs', 21:08:19 INFO - 'system_bits': '32', 21:08:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:19 INFO - 'title': 'talos-linux64-ix-066', 21:08:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:19 INFO - 'use_talos_json': True, 21:08:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:19 INFO - 'work_dir': 'build'} 21:08:19 INFO - ##### 21:08:19 INFO - ##### Running clobber step. 21:08:19 INFO - ##### 21:08:19 INFO - Running pre-action listener: _resource_record_pre_action 21:08:19 INFO - Running main action method: clobber 21:08:19 INFO - rmtree: /builds/slave/test/build 21:08:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:20 INFO - Running post-action listener: _resource_record_post_action 21:08:20 INFO - ##### 21:08:20 INFO - ##### Running read-buildbot-config step. 21:08:20 INFO - ##### 21:08:20 INFO - Running pre-action listener: _resource_record_pre_action 21:08:20 INFO - Running main action method: read_buildbot_config 21:08:20 INFO - Using buildbot properties: 21:08:20 INFO - { 21:08:20 INFO - "project": "", 21:08:20 INFO - "product": "firefox", 21:08:20 INFO - "script_repo_revision": "production", 21:08:20 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:08:20 INFO - "repository": "", 21:08:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:08:20 INFO - "buildid": "20160514182335", 21:08:20 INFO - "pgo_build": "True", 21:08:20 INFO - "basedir": "/builds/slave/test", 21:08:20 INFO - "buildnumber": 233, 21:08:20 INFO - "slavename": "talos-linux64-ix-066", 21:08:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:08:20 INFO - "platform": "ubuntu64_hw", 21:08:20 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:08:20 INFO - "branch": "mozilla-aurora", 21:08:20 INFO - "repo_path": "releases/mozilla-aurora", 21:08:20 INFO - "revision": "f00f9e754ce71fc5efa3e4334afea5cf6ad6c215", 21:08:20 INFO - "stage_platform": "linux64-pgo", 21:08:20 INFO - "builduid": "cca324c9e5104676bf7e026fb9914f98", 21:08:20 INFO - "slavebuilddir": "test" 21:08:20 INFO - } 21:08:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 21:08:20 INFO - Running post-action listener: _resource_record_post_action 21:08:20 INFO - ##### 21:08:20 INFO - ##### Running download-and-extract step. 21:08:20 INFO - ##### 21:08:20 INFO - Running pre-action listener: _resource_record_pre_action 21:08:20 INFO - Running main action method: download_and_extract 21:08:20 INFO - mkdir: /builds/slave/test/build/tests 21:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:20 INFO - https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:08:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:08:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:08:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:08:30 INFO - Downloaded 1361 bytes. 21:08:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:08:30 INFO - Using the following test package requirements: 21:08:30 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:08:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:08:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:08:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'jsshell-linux-x86_64.zip'], 21:08:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:08:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:08:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:08:30 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:08:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:08:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:08:30 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:08:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:08:30 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')]} 21:08:30 INFO - https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:08:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:36 INFO - Downloaded 23813138 bytes. 21:08:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:08:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:08:38 INFO - Return code: 0 21:08:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:08: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')]} 21:08:38 INFO - https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:42 INFO - Downloaded 23813138 bytes. 21:08:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:08:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:08:43 INFO - Return code: 0 21:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:43 INFO - https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:08:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:08:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:08:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:08:44 INFO - Downloaded 10840145 bytes. 21:08:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:08:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:08:44 INFO - Return code: 0 21:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:44 INFO - https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:08:47 INFO - Downloaded 62721617 bytes. 21:08:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:08:47 INFO - mkdir: /builds/slave/test/properties 21:08:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:47 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:47 INFO - Contents: 21:08:47 INFO - build_url:https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:08:47 INFO - Running post-action listener: _resource_record_post_action 21:08:47 INFO - Running post-action listener: set_extra_try_arguments 21:08:47 INFO - ##### 21:08:47 INFO - ##### Running populate-webroot step. 21:08:47 INFO - ##### 21:08:47 INFO - Running pre-action listener: _resource_record_pre_action 21:08:47 INFO - Running main action method: populate_webroot 21:08:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:47 INFO - '--tppagecycles', 21:08:47 INFO - '7'], 21:08:47 INFO - 'tests': ['tsvgm']}}, 21:08:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['damp', 'tps']}, 21:08:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'tests': ['damp', 'tps']}, 21:08:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['dromaeo_dom']}, 21:08:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['a11yr', 21:08:47 INFO - 'ts_paint', 21:08:47 INFO - 'tpaint', 21:08:47 INFO - 'sessionrestore', 21:08:47 INFO - 'sessionrestore_no_auto_restore', 21:08:47 INFO - 'tabpaint']}, 21:08:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['tsvgx', 21:08:47 INFO - 'tsvgr_opacity', 21:08:47 INFO - 'tart', 21:08:47 INFO - 'tscrollx', 21:08:47 INFO - 'cart']}, 21:08:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:47 INFO - 'tsvgr_opacity', 21:08:47 INFO - 'tart', 21:08:47 INFO - 'tscrollx', 21:08:47 INFO - 'cart']}, 21:08:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'talos_options': ['--disable-e10s'], 21:08:47 INFO - 'tests': ['tp5o']}, 21:08:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'tests': ['tp5o']}, 21:08:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'talos_options': ['--disable-e10s', 21:08:47 INFO - '--xperf_path', 21:08:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:47 INFO - 'tests': ['tp5n']}, 21:08:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:47 INFO - 'talos_options': ['--xperf_path', 21:08:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:47 INFO - 'tests': ['tp5n']}}, 21:08:47 INFO - 'talos.zip': {'path': '', 21:08:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:47 INFO - Running post-action listener: _resource_record_post_action 21:08:47 INFO - ##### 21:08:47 INFO - ##### Running create-virtualenv step. 21:08:47 INFO - ##### 21:08:47 INFO - Running pre-action listener: _resource_record_pre_action 21:08:47 INFO - Running main action method: create_virtualenv 21:08:47 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:47 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 21:08:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:48 INFO - Using real prefix '/usr' 21:08:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:48 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:50 INFO - Installing pip.................done. 21:08:50 INFO - Return code: 0 21:08:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:08:50 INFO - Using env: {'DISPLAY': ':0', 21:08:50 INFO - 'HOME': '/home/cltbld', 21:08:50 INFO - 'LANG': 'en_US.UTF-8', 21:08:50 INFO - 'LANGUAGE': 'en_US:en', 21:08:50 INFO - 'LOGNAME': 'cltbld', 21:08:50 INFO - 'MAIL': '/var/mail/cltbld', 21:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:50 INFO - 'MOZ_NO_REMOTE': '1', 21:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:50 INFO - 'NO_EM_RESTART': '1', 21:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:50 INFO - 'PWD': '/builds/slave/test', 21:08:50 INFO - 'SHELL': '/bin/bash', 21:08:50 INFO - 'SHLVL': '1', 21:08:50 INFO - 'TERM': 'linux', 21:08:50 INFO - 'TMOUT': '86400', 21:08:50 INFO - 'USER': 'cltbld', 21:08:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:50 INFO - '_': '/tools/buildbot/bin/python'} 21:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:50 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 21:08:50 INFO - Cleaning up... 21:08:50 INFO - Return code: 0 21:08:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 21:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:08:50 INFO - Using env: {'DISPLAY': ':0', 21:08:50 INFO - 'HOME': '/home/cltbld', 21:08:50 INFO - 'LANG': 'en_US.UTF-8', 21:08:50 INFO - 'LANGUAGE': 'en_US:en', 21:08:50 INFO - 'LOGNAME': 'cltbld', 21:08:50 INFO - 'MAIL': '/var/mail/cltbld', 21:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:50 INFO - 'MOZ_NO_REMOTE': '1', 21:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:50 INFO - 'NO_EM_RESTART': '1', 21:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:50 INFO - 'PWD': '/builds/slave/test', 21:08:50 INFO - 'SHELL': '/bin/bash', 21:08:50 INFO - 'SHLVL': '1', 21:08:50 INFO - 'TERM': 'linux', 21:08:50 INFO - 'TMOUT': '86400', 21:08:50 INFO - 'USER': 'cltbld', 21:08:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:50 INFO - '_': '/tools/buildbot/bin/python'} 21:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:50 INFO - Downloading/unpacking psutil>=0.7.1 21:08:50 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 21:08:50 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 21:08:50 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 21:08:50 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 21:08:50 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 21:08:50 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 21:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:52 INFO - Installing collected packages: psutil 21:08:52 INFO - Running setup.py install for psutil 21:08:53 INFO - building 'psutil._psutil_linux' extension 21:08:53 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 21:08:53 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 21:08:53 INFO - building 'psutil._psutil_posix' extension 21:08:53 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 21:08:53 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 21:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:53 INFO - Successfully installed psutil 21:08:53 INFO - Cleaning up... 21:08:53 INFO - Return code: 0 21:08:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:08:53 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')]} 21:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:53 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')]} 21:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:53 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:08:53 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.0.0'] in /builds/slave/test/build 21:08:53 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.0.0 21:08:53 INFO - Using env: {'DISPLAY': ':0', 21:08:53 INFO - 'HOME': '/home/cltbld', 21:08:53 INFO - 'LANG': 'en_US.UTF-8', 21:08:53 INFO - 'LANGUAGE': 'en_US:en', 21:08:53 INFO - 'LOGNAME': 'cltbld', 21:08:53 INFO - 'MAIL': '/var/mail/cltbld', 21:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:53 INFO - 'MOZ_NO_REMOTE': '1', 21:08:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:53 INFO - 'NO_EM_RESTART': '1', 21:08:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:53 INFO - 'PWD': '/builds/slave/test', 21:08:53 INFO - 'SHELL': '/bin/bash', 21:08:53 INFO - 'SHLVL': '1', 21:08:53 INFO - 'TERM': 'linux', 21:08:53 INFO - 'TMOUT': '86400', 21:08:53 INFO - 'USER': 'cltbld', 21:08:53 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:53 INFO - '_': '/tools/buildbot/bin/python'} 21:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:08:53 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 21:08:53 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 21:08:53 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 21:08:53 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 21:08:53 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 21:08:53 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 21:08:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:08:55 INFO - Installing collected packages: mozsystemmonitor 21:08:55 INFO - Running setup.py install for mozsystemmonitor 21:08:55 INFO - Successfully installed mozsystemmonitor 21:08:55 INFO - Cleaning up... 21:08:55 INFO - Return code: 0 21:08:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:08:55 INFO - Using env: {'DISPLAY': ':0', 21:08:55 INFO - 'HOME': '/home/cltbld', 21:08:55 INFO - 'LANG': 'en_US.UTF-8', 21:08:55 INFO - 'LANGUAGE': 'en_US:en', 21:08:55 INFO - 'LOGNAME': 'cltbld', 21:08:55 INFO - 'MAIL': '/var/mail/cltbld', 21:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:55 INFO - 'MOZ_NO_REMOTE': '1', 21:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:55 INFO - 'NO_EM_RESTART': '1', 21:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:55 INFO - 'PWD': '/builds/slave/test', 21:08:55 INFO - 'SHELL': '/bin/bash', 21:08:55 INFO - 'SHLVL': '1', 21:08:55 INFO - 'TERM': 'linux', 21:08:55 INFO - 'TMOUT': '86400', 21:08:55 INFO - 'USER': 'cltbld', 21:08:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:55 INFO - '_': '/tools/buildbot/bin/python'} 21:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:55 INFO - Downloading/unpacking blobuploader==1.2.4 21:08:55 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 21:08:55 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 21:08:55 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 21:08:55 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 21:08:55 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 21:08:55 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 21:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 21:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:08:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:08:57 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 21:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 INFO - Downloading docopt-0.6.1.tar.gz 21:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:08:58 INFO - Installing collected packages: blobuploader, requests, docopt 21:08:58 INFO - Running setup.py install for blobuploader 21:08:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:08:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:08:58 INFO - Running setup.py install for requests 21:08:59 INFO - Running setup.py install for docopt 21:08:59 INFO - Successfully installed blobuploader requests docopt 21:08:59 INFO - Cleaning up... 21:08:59 INFO - Return code: 0 21:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:59 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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:08:59 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 21:08:59 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 21:08:59 INFO - Using env: {'DISPLAY': ':0', 21:08:59 INFO - 'HOME': '/home/cltbld', 21:08:59 INFO - 'LANG': 'en_US.UTF-8', 21:08:59 INFO - 'LANGUAGE': 'en_US:en', 21:08:59 INFO - 'LOGNAME': 'cltbld', 21:08:59 INFO - 'MAIL': '/var/mail/cltbld', 21:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:59 INFO - 'MOZ_NO_REMOTE': '1', 21:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:59 INFO - 'NO_EM_RESTART': '1', 21:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:59 INFO - 'PWD': '/builds/slave/test', 21:08:59 INFO - 'SHELL': '/bin/bash', 21:08:59 INFO - 'SHLVL': '1', 21:08:59 INFO - 'TERM': 'linux', 21:08:59 INFO - 'TMOUT': '86400', 21:08:59 INFO - 'USER': 'cltbld', 21:08:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:59 INFO - '_': '/tools/buildbot/bin/python'} 21:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:59 INFO - Running setup.py (path:/tmp/pip-CabkFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:59 INFO - Running setup.py (path:/tmp/pip-3s6Kui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:59 INFO - Running setup.py (path:/tmp/pip-FHgPDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:59 INFO - Running setup.py (path:/tmp/pip-b58vE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:59 INFO - Running setup.py (path:/tmp/pip-q8mPDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:59 INFO - Running setup.py (path:/tmp/pip-jXdkI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:59 INFO - Running setup.py (path:/tmp/pip-TqcQpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:59 INFO - Running setup.py (path:/tmp/pip-mOGrMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:00 INFO - Running setup.py (path:/tmp/pip-YMCee0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:00 INFO - Running setup.py (path:/tmp/pip-8MFCwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:00 INFO - Running setup.py (path:/tmp/pip-EXEFPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:00 INFO - Running setup.py (path:/tmp/pip-kZ7WNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:00 INFO - Running setup.py (path:/tmp/pip-sLAas8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:00 INFO - Running setup.py (path:/tmp/pip-vSM5GE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:00 INFO - Running setup.py (path:/tmp/pip-yGtlNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:00 INFO - Running setup.py (path:/tmp/pip-zp_wE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:00 INFO - Running setup.py (path:/tmp/pip-acstdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:09:00 INFO - Running setup.py install for manifestparser 21:09:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:09:00 INFO - Running setup.py install for mozcrash 21:09:00 INFO - Running setup.py install for mozdebug 21:09:00 INFO - Running setup.py install for mozdevice 21:09:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for mozfile 21:09:01 INFO - Running setup.py install for mozhttpd 21:09:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for mozinfo 21:09:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for mozInstall 21:09:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for mozleak 21:09:01 INFO - Running setup.py install for mozlog 21:09:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for moznetwork 21:09:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:09:01 INFO - Running setup.py install for mozprocess 21:09:01 INFO - Running setup.py install for mozprofile 21:09:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:09:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:09:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:09:02 INFO - Running setup.py install for mozrunner 21:09:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:09:02 INFO - Running setup.py install for mozscreenshot 21:09:02 INFO - Running setup.py install for moztest 21:09:02 INFO - Running setup.py install for mozversion 21:09:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:09:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:09:02 INFO - Cleaning up... 21:09:02 INFO - Return code: 0 21:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:09:02 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 21:09:02 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 21:09:02 INFO - Using env: {'DISPLAY': ':0', 21:09:02 INFO - 'HOME': '/home/cltbld', 21:09:02 INFO - 'LANG': 'en_US.UTF-8', 21:09:02 INFO - 'LANGUAGE': 'en_US:en', 21:09:02 INFO - 'LOGNAME': 'cltbld', 21:09:02 INFO - 'MAIL': '/var/mail/cltbld', 21:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:02 INFO - 'MOZ_NO_REMOTE': '1', 21:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:02 INFO - 'NO_EM_RESTART': '1', 21:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:02 INFO - 'PWD': '/builds/slave/test', 21:09:02 INFO - 'SHELL': '/bin/bash', 21:09:02 INFO - 'SHLVL': '1', 21:09:02 INFO - 'TERM': 'linux', 21:09:02 INFO - 'TMOUT': '86400', 21:09:02 INFO - 'USER': 'cltbld', 21:09:02 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:02 INFO - '_': '/tools/buildbot/bin/python'} 21:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:09:02 INFO - Running setup.py (path:/tmp/pip-6ZFQhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:09:02 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)) 21:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:09:02 INFO - Running setup.py (path:/tmp/pip-7i6mLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:09:02 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)) 21:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:09:02 INFO - Running setup.py (path:/tmp/pip-LTlrjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:09:02 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)) 21:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:09:02 INFO - Running setup.py (path:/tmp/pip-hEoTEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:09:03 INFO - Running setup.py (path:/tmp/pip-z7ob4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:09:03 INFO - Running setup.py (path:/tmp/pip-wNh1CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:09:03 INFO - Running setup.py (path:/tmp/pip-kxagoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:09:03 INFO - Running setup.py (path:/tmp/pip-7xc_ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:03 INFO - Running setup.py (path:/tmp/pip-hYSuBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:03 INFO - Running setup.py (path:/tmp/pip-I3mmt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:03 INFO - Running setup.py (path:/tmp/pip-HtDK31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:03 INFO - Running setup.py (path:/tmp/pip-yiyZDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:03 INFO - Running setup.py (path:/tmp/pip-D2FuGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:03 INFO - Running setup.py (path:/tmp/pip-ZR4fXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:03 INFO - Running setup.py (path:/tmp/pip-AQ6ZuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:03 INFO - Running setup.py (path:/tmp/pip-VcM8oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:03 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)) 21:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:03 INFO - Running setup.py (path:/tmp/pip-LHdNU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:03 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)) 21:09:03 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)) 21:09:03 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)) 21:09:03 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)) 21:09:03 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)) 21:09:03 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)) 21:09:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:05 INFO - Downloading blessings-1.6.tar.gz 21:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:09:05 INFO - Installing collected packages: blessings 21:09:05 INFO - Running setup.py install for blessings 21:09:05 INFO - Successfully installed blessings 21:09:05 INFO - Cleaning up... 21:09:06 INFO - Return code: 0 21:09:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:06 INFO - Reading from file tmpfile_stdout 21:09:06 INFO - Current package versions: 21:09:06 INFO - argparse == 1.2.1 21:09:06 INFO - blessings == 1.6 21:09:06 INFO - blobuploader == 1.2.4 21:09:06 INFO - docopt == 0.6.1 21:09:06 INFO - manifestparser == 1.1 21:09:06 INFO - mozInstall == 1.12 21:09:06 INFO - mozcrash == 0.17 21:09:06 INFO - mozdebug == 0.1 21:09:06 INFO - mozdevice == 0.48 21:09:06 INFO - mozfile == 1.2 21:09:06 INFO - mozhttpd == 0.7 21:09:06 INFO - mozinfo == 0.9 21:09:06 INFO - mozleak == 0.1 21:09:06 INFO - mozlog == 3.1 21:09:06 INFO - moznetwork == 0.27 21:09:06 INFO - mozprocess == 0.22 21:09:06 INFO - mozprofile == 0.28 21:09:06 INFO - mozrunner == 6.11 21:09:06 INFO - mozscreenshot == 0.1 21:09:06 INFO - mozsystemmonitor == 0.0 21:09:06 INFO - moztest == 0.7 21:09:06 INFO - mozversion == 1.4 21:09:06 INFO - psutil == 3.1.1 21:09:06 INFO - requests == 1.2.3 21:09:06 INFO - wsgiref == 0.1.2 21:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:06 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')]} 21:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:06 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')]} 21:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:06 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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:09:06 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 21:09:06 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 21:09:06 INFO - Using env: {'DISPLAY': ':0', 21:09:06 INFO - 'HOME': '/home/cltbld', 21:09:06 INFO - 'LANG': 'en_US.UTF-8', 21:09:06 INFO - 'LANGUAGE': 'en_US:en', 21:09:06 INFO - 'LOGNAME': 'cltbld', 21:09:06 INFO - 'MAIL': '/var/mail/cltbld', 21:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:06 INFO - 'MOZ_NO_REMOTE': '1', 21:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:06 INFO - 'NO_EM_RESTART': '1', 21:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:06 INFO - 'PWD': '/builds/slave/test', 21:09:06 INFO - 'SHELL': '/bin/bash', 21:09:06 INFO - 'SHLVL': '1', 21:09:06 INFO - 'TERM': 'linux', 21:09:06 INFO - 'TMOUT': '86400', 21:09:06 INFO - 'USER': 'cltbld', 21:09:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:06 INFO - '_': '/tools/buildbot/bin/python'} 21:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 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)) 21:09:06 INFO - Cleaning up... 21:09:06 INFO - Return code: 0 21:09:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:09:06 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')]} 21:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:06 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')]} 21:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:06 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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:09:06 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 21:09:06 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 21:09:06 INFO - Using env: {'DISPLAY': ':0', 21:09:06 INFO - 'HOME': '/home/cltbld', 21:09:06 INFO - 'LANG': 'en_US.UTF-8', 21:09:06 INFO - 'LANGUAGE': 'en_US:en', 21:09:06 INFO - 'LOGNAME': 'cltbld', 21:09:06 INFO - 'MAIL': '/var/mail/cltbld', 21:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:06 INFO - 'MOZ_NO_REMOTE': '1', 21:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:06 INFO - 'NO_EM_RESTART': '1', 21:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:06 INFO - 'PWD': '/builds/slave/test', 21:09:06 INFO - 'SHELL': '/bin/bash', 21:09:06 INFO - 'SHLVL': '1', 21:09:06 INFO - 'TERM': 'linux', 21:09:06 INFO - 'TMOUT': '86400', 21:09:06 INFO - 'USER': 'cltbld', 21:09:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:06 INFO - '_': '/tools/buildbot/bin/python'} 21:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:06 INFO - Downloading/unpacking jsonschema 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:09:08 INFO - Downloading/unpacking functools32 (from jsonschema) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:08 INFO - Downloading functools32-3.2.3-2.tar.gz 21:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:09:08 INFO - warning: no files found matching '*.txt' 21:09:08 INFO - no previously-included directories found matching 'build' 21:09:08 INFO - no previously-included directories found matching 'dist' 21:09:08 INFO - no previously-included directories found matching '.git*' 21:09:08 INFO - Installing collected packages: jsonschema, functools32 21:09:08 INFO - Running setup.py install for functools32 21:09:09 INFO - warning: no files found matching '*.txt' 21:09:09 INFO - no previously-included directories found matching 'build' 21:09:09 INFO - no previously-included directories found matching 'dist' 21:09:09 INFO - no previously-included directories found matching '.git*' 21:09:09 INFO - Successfully installed jsonschema functools32 21:09:09 INFO - Cleaning up... 21:09:09 INFO - Return code: 0 21:09:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:09:09 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')]} 21:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:09 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')]} 21:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:09 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 0x7f3a77001f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20895a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', '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 21:09:09 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 21:09:09 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 21:09:09 INFO - Using env: {'DISPLAY': ':0', 21:09:09 INFO - 'HOME': '/home/cltbld', 21:09:09 INFO - 'LANG': 'en_US.UTF-8', 21:09:09 INFO - 'LANGUAGE': 'en_US:en', 21:09:09 INFO - 'LOGNAME': 'cltbld', 21:09:09 INFO - 'MAIL': '/var/mail/cltbld', 21:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:09 INFO - 'MOZ_NO_REMOTE': '1', 21:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:09 INFO - 'NO_EM_RESTART': '1', 21:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:09 INFO - 'PWD': '/builds/slave/test', 21:09:09 INFO - 'SHELL': '/bin/bash', 21:09:09 INFO - 'SHLVL': '1', 21:09:09 INFO - 'TERM': 'linux', 21:09:09 INFO - 'TMOUT': '86400', 21:09:09 INFO - 'USER': 'cltbld', 21:09:09 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:09 INFO - '_': '/tools/buildbot/bin/python'} 21:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:09 INFO - Downloading/unpacking flake8 21:09:09 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 21:09:09 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 21:09:09 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 21:09:09 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 21:09:09 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 21:09:09 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 21:09:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:09:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:09:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09: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 21:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:09:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:09:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:09:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:09:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:09:11 INFO - no previously-included directories found matching 'docs/_build' 21:09:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:09:11 INFO - Running setup.py install for pep8 21:09:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:09:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:09:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:09:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:09:12 INFO - no previously-included directories found matching 'docs/_build' 21:09:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:09:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:09:12 INFO - Cleaning up... 21:09:12 INFO - Return code: 0 21:09:12 INFO - Running post-action listener: _resource_record_post_action 21:09:12 INFO - Running post-action listener: _start_resource_monitoring 21:09:12 INFO - Starting resource monitoring. 21:09:12 INFO - ##### 21:09:12 INFO - ##### Running install step. 21:09:12 INFO - ##### 21:09:12 INFO - Running pre-action listener: _resource_record_pre_action 21:09:12 INFO - Running main action method: install 21:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:12 INFO - Reading from file tmpfile_stdout 21:09:12 INFO - Detecting whether we're running mozinstall >=1.0... 21:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:09:12 INFO - Reading from file tmpfile_stdout 21:09:12 INFO - Output received: 21:09:12 INFO - Usage: mozinstall [options] installer 21:09:12 INFO - Options: 21:09:12 INFO - -h, --help show this help message and exit 21:09:12 INFO - -d DEST, --destination=DEST 21:09:12 INFO - Directory to install application into. [default: 21:09:12 INFO - "/builds/slave/test"] 21:09:12 INFO - --app=APP Application being installed. [default: firefox] 21:09:12 INFO - mkdir: /builds/slave/test/build/application 21:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:09:28 INFO - Reading from file tmpfile_stdout 21:09:28 INFO - Output received: 21:09:28 INFO - /builds/slave/test/build/application/firefox/firefox 21:09:28 INFO - Running post-action listener: _resource_record_post_action 21:09:28 INFO - ##### 21:09:28 INFO - ##### Running run-tests step. 21:09:28 INFO - ##### 21:09:28 INFO - Running pre-action listener: _resource_record_pre_action 21:09:28 INFO - Running main action method: run_tests 21:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:09:28 INFO - Python 2.7.3 21:09:28 INFO - Return code: 0 21:09:28 INFO - grabbing minidump binary from tooltool 21:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:09:28 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118410>, '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 0x2119310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21197a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:09:28 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 21:09:28 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 21:09:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:09:28 INFO - Return code: 0 21:09:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:09:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:09:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:09:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:09:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:09:29 INFO - Return code: 0 21:09:29 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/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 21:09:29 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/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 21:09:29 INFO - Using env: {'DISPLAY': ':0', 21:09:29 INFO - 'HOME': '/home/cltbld', 21:09:29 INFO - 'LANG': 'en_US.UTF-8', 21:09:29 INFO - 'LANGUAGE': 'en_US:en', 21:09:29 INFO - 'LOGNAME': 'cltbld', 21:09:29 INFO - 'MAIL': '/var/mail/cltbld', 21:09:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:29 INFO - 'MOZ_NO_REMOTE': '1', 21:09:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:29 INFO - 'NO_EM_RESTART': '1', 21:09:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:29 INFO - 'PWD': '/builds/slave/test', 21:09:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:29 INFO - 'SHELL': '/bin/bash', 21:09:29 INFO - 'SHLVL': '1', 21:09:29 INFO - 'TERM': 'linux', 21:09:29 INFO - 'TMOUT': '86400', 21:09:29 INFO - 'USER': 'cltbld', 21:09:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905', 21:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:29 INFO - '_': '/tools/buildbot/bin/python'} 21:09:29 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/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 21:09:29 INFO - mozversion application_buildid: 20160514182335 21:09:29 INFO - mozversion application_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 21:09:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:09:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:09:29 INFO - mozversion application_name: Firefox 21:09:29 INFO - mozversion application_remotingname: firefox-dev 21:09:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:09:29 INFO - mozversion application_vendor: Mozilla 21:09:29 INFO - mozversion application_version: 48.0a2 21:09:29 INFO - mozversion platform_buildid: 20160514182335 21:09:29 INFO - mozversion platform_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 21:09:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:09:29 INFO - mozversion platform_version: 48.0a2 21:09:29 INFO - using testdate: 1463285369 21:09:29 INFO - actual date: 1463285369 21:09:29 INFO - starting webserver on 'localhost:46490' 21:09:29 INFO - SUITE-START | Running 2 tests 21:09:29 INFO - TEST-START | dromaeo_css 21:09:29 INFO - operating with platform_type : linux_ 21:09:29 INFO - Initialising browser for dromaeo_css test... 21:09:29 INFO - TEST-INFO | started process 20983 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH_SX8c/profile http://localhost:46490/getInfo.html) 21:09:33 INFO - PROCESS | 20983 | __metrics Screen width/height:1600/1200 21:09:33 INFO - PROCESS | 20983 | colorDepth:24 21:09:33 INFO - PROCESS | 20983 | Browser inner width/height: 1024/697 21:09:33 INFO - PROCESS | 20983 | __metrics 21:09:34 ERROR - PROCESS | 20983 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:09:35 INFO - TEST-INFO | 20983: exit 0 21:09:35 INFO - Browser initialized. 21:09:35 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:35 INFO - TEST-INFO | started process 21075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH_SX8c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:47 INFO - PROCESS | 21075 | RSS: Main: 142360576 21:09:47 INFO - PROCESS | 21075 | 21:10:37 INFO - PROCESS | 21075 | 1463285437958 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] 21:12:45 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46490/tests/dromaeo/cssquery-ext.html) 21:12:46 INFO - PROCESS | 21075 | RSS: Main: 245817344 21:12:46 INFO - PROCESS | 21075 | 21:15:48 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-ext.html (next: http://localhost:46490/tests/dromaeo/cssquery-jquery.html) 21:15:49 INFO - PROCESS | 21075 | RSS: Main: 222068736 21:15:49 INFO - PROCESS | 21075 | 21:18:51 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46490/tests/dromaeo/cssquery-mootools.html) 21:18:51 INFO - PROCESS | 21075 | RSS: Main: 218656768 21:18:51 INFO - PROCESS | 21075 | 21:21:53 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46490/tests/dromaeo/cssquery-prototype.html) 21:21:54 INFO - PROCESS | 21075 | RSS: Main: 214720512 21:21:54 INFO - PROCESS | 21075 | 21:24:56 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46490/tests/dromaeo/cssquery-yui.html) 21:24:57 INFO - PROCESS | 21075 | RSS: Main: 188899328 21:24:57 INFO - PROCESS | 21075 | 21:27:59 INFO - PROCESS | 21075 | Cycle 1(1): loaded http://localhost:46490/tests/dromaeo/cssquery-yui.html (next: http://localhost:46490/tests/dromaeo/cssquery-dojo.html) 21:28:00 INFO - PROCESS | 21075 | RSS: Main: 251629568 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | __start_tp_report 21:28:00 INFO - PROCESS | 21075 | _x_x_mozilla_page_load 21:28:00 INFO - PROCESS | 21075 | _x_x_mozilla_page_load_details 21:28:00 INFO - PROCESS | 21075 | |i|pagename|runs| 21:28:00 INFO - PROCESS | 21075 | |0;dojo.html;3263.804171749695;3326.717229035531;3340.2985373071656;3372.123247955531;3380.8816691415805;5490.423505531917;5505.697186654733;5534.944650553493;5538.224648549206;5544.889102217953;5521.5306698930535;5547.25112109865;5559.749811258493;5575.303087114111;5579.442055794419;4819.445763737162;5002.674826136298;5096.617753668471;5145.3311069560905;5171.948280517203;4933.161362568372;4980.203167493201;4988.501149885019;4989.875253118683;5017.122003649365;4993.051320249159;5055.898882022358;5074.340335756365;5077.720725360104;5105.923411148836;5091.872703182413;5097.617678674096;5109.897802043957;5110.744462776846;5112.258722485219;4886.1204983103025;4957.256411538262;4998.525140111684;5017.7491125443585;5047.772850221712;5347.679139251657;5350.946490535122;5378.784848606051;5398.946010539884;5405.540529054996;5824.53403727705;5830.154627578977;5840.415958404168;5847.122931560258;5854.648721076749;5880.088586269135;5891.911621325684;5930.644161350333;5947;5958.672273024982;5518.255035570187;5544.944550554484;5558.833235002916;5564.304461942257;5566.749496272718;5857.355690874;6056.485198758067;6060.575759696867;6063.242094738158;6073.544484163705;5793.536517078624;5835.883282334415;5849.415058494116;5856.297244330707;5869.7945571904775;5921.437463440966;5925.377835327228;5925.585209035412;5925.674087925119;5927;5632.211490391349;5709.0865461525955;5880.882382352414;5883.14694369314;5885.529157667376;5824.504917082011;5842.941570584325;5844.707764611752;5849.590528663038;5887.705614719247;4459.063596644741;4463.821447142078;4474.865754027384;4475.306327519174;4501.212287849613;3974.046228905042;4023.979880100581;4037.7981100944835;4068.5321188063754;4077.32724100526;5530.5022547970875;5557.722113894289;5558.360788509373;5572.247746554244;5575.972120139454;5863.941360586425;5867.471927526543;5867.64794112346;5873.442023007808;5881.647101173943;5488.643238189505;5544.1960915667005;5551.528120109755;5617.325920889438;5686.175504551817;5073.289739436482;5078.111330517145;5084.949150508522;5086.64393492452;5113.667611605234;6080.331163572004;6085.056816193497;6085.66528840918;6091.634501929899;6092.939070609325;5794.971025145016;5866.794662186803;5905.202797622266;5908.527317814479;5930.05119180929;6107.735039974475;6119.228375855631;6131.828320783331;6139.768135342541;6147.477464415575;5840.649561026352;5849.830033993304;5851.356350801493;5856.033754430471;5891.968905441617;5744.368119506935;5875.265591801025;5927.585069045125;5984.551158663031;5996.730147143477;5661;5665.150227465913;5680.7443665036;5718.685472299064;5741.741621626953;5648.181013753065;5691.3170419549915;5704.3154821421695;5707.600467967202;5715.485606295453;5440.646357986877;5668.178114173505;5742.339630942495;5769.53843692512;5780.768769249183;5784.82645520635;5790.623609465371;5792;5796.768129274782;5800.854978625568;5561.5272701820795;5667.2349232854685;5671.092625179951;5674.971625141848;5709.229254050816 21:28:00 INFO - PROCESS | 21075 | |1;ext.html;655.1221363373079;665.4676258992805;673.3064943108599;722.010845142155;753.8416932444186;32498.837505812466;33180.834095829516;33186.17034574137;33219;33223.66776332233;42205;43194.784026079906;43210.567894321044;43257;43289.7835510822;8877.422967507107;9070.319726020542;9072.36493445459;9075.682351117712;9076.092390760938;8427.873581896276;8722.956385218096;8872.556372181365;10792.190585706065;11073.889261107366;10754.946225268863;10825.945870270676;10826.350418974887;10828.187885908548;10842.403667798268;8461.788455288606;10667.199960002992;10754;10771.62299319524;10775.5689772409;10417.479126043667;10610.257281990263;10614.522346494427;10615.734606634818;10619.78760424795;40037.59962400368;41210.587894120974;41230.79384603088;41294.174116517504;41358.37962430566;3927.509061367329;3956.1135662923716;4205.053862880827;4305.63402110821;4435.290353543418;31866.362672746647;33084.503732444035;34005;35239.647603523896;35431.291374172375;4704.129735998817;4715.726753776461;4723.811047558093;4736.242201247784;4839.741667166527;341610;341704;342790;343037;345788;69952.6502367485;70427;71020;71061;71182.64408677924;73915.63042184754;74002;74087.62956185185;74670;74760.62619686975;19437.90281048614;19635.901820490806;19708.21167153298;19716.802831971785;19722.211111555378;14367.928160359132;15529.223538823013;15606.219689015505;15616.297266623029;15769.526914192593;21692.783072169394;21721.131154753948;22013.889930550566;22069.227577034726;22120.778792212193;7762.411064033887;7936.841263174704;8182.590870456454;8220.8355832883;8296.70961516344;1013.4780587997187;1090.0625462102585;1094.5949998500446;1198.298995087866;1206.2099324942176;33921.15197120042;34153.31693366119;34296.65703342934;34307;34496.310073798384;1158.8956993870424;1190.1312042209236;1200.1598880783486;1221.9266843989212;1281.9935900320438;17975.640487190183;18393.54016149607;18406.907965460086;18481.1683474244;18539.165737541844;229357.41285174538;231821;231995;232047;232482;61837;61890.38109618846;62395.376046239864;62496;62526.687366562874;1419.6237996930822;1498.168516473346;1510;1510.1920472547135;1522.9771553426708;30444.847775761864;32469.675303246666;32917.67082329146;33014.33971320608;34242.31515369729;50223;60445;61636;61873;61946.69026654838;455699;456620;459439;460577;462310;40112;40685;45475;47204.7639761799;47594.762026189645;45386;45614;45710.314345285515;46291;46667.766661166475;39049.219015620096;41213.79393103135;42024.159516810105;42492.5750742501;42759.78620107004;39025.60974390333;42344.80972821176;42707;47262;47516.762416187696;42924.14151716886;43798;43862.34206486841;44058;44273.33589996217;36860.26279474449;37458.6254137466;37954.43068354032;38388.42417363686;38621.80689096537;29462.41075178527;29592.704072958993;29774.66014029417;29884.551731724478;31809.204769880937 21:28:00 INFO - PROCESS | 21075 | |2;jquery.html;437.52747033204145;438.2086111469936;444.48661795626066;447.7783497168902;454.8817307500051;18499.0750462477;18583.163757630904;19086;19151.521211969706;19206.327778527753;18318.542036449096;18357.357492487765;19004;19131.139098740554;19147.712784308245;5740.741666624991;5745.138229265613;5979.342272350038;5986.730597123118;5989.251343582052;4369.584889435499;5886.735096920649;5949.762009519614;6331.77838775643;6624.403803657669;6549.083128362033;6593.505487088463;6603.603783772989;6606.3063378345305;6609.900851487231;6330.588511746721;6531.05299731541;6544.345565443466;6545.37818907203;6554.541182117254;4028.194361127757;4058.391241313797;5675.2054712340305;5826.650400975955;5826.854328641775;22293.777062229332;22432.551348972927;22630.207942721925;22815.42961425961;22826.885865570734;2728.7816974641983;3012.9397412051853;3023.4708925938094;3170.239142605781;3182.80903145812;10341.276110672256;10608.204384671104;10701.197410194267;10745.462726863625;10838.403887786248;1765.9911700441544;1975.980240197594;2012.0241682783837;2077.6364136276093;2194.3746032380855;77690.61154694247;80987.5950620249;81613;82587.58706206491;84696.57651711763;10852.186086043579;11077.723056923642;11143.777124457627;11407.429628518541;11630.360330181751;15869.444569440015;15881.68236635284;15903.681926361409;15950;16029.919850400907;8516.446430981967;8750.212480876751;8896.59965301563;8900.510471924106;9034.683786067588;8848.734537963872;8849.115088491099;8883.156100170474;8949.776255593662;8955.910440895637;5071.873203169876;5144.250954675428;5153.665011774223;5233.502817232356;5236.1098613235845;1865.0301843041543;2039.510517475795;2073.52308968935;2090.4669309326327;2097.5280561873456;977.3451787302524;1063.4151216830712;1110.5779803674714;1180.339010154299;1195.8325834383193;17470.563235919202;17853.821461785476;18412.81587184138;18788.624227515375;19105.426837194627;1054.667779649408;1078.0728573426848;1105.7788442311569;1136.72718547548;1165.1086918507349;10937.56249750001;10939.179561532917;10939.56241750321;10993.340399576051;11046.723831904266;531890;534504;543720;544758;555404;95777.521112394;96466.07067858604;96564.51717741367;97660.51169744106;97958;1071.7588542577857;1085.7719878825535;1086.3319058778793;1097.8792332843382;1102.4212288548576;17027;17208.913955430144;17307.740383894503;17322.566935826704;17468.563285917953;39256;40720.38919416152;40726.79636601798;41068.178636426506;41728;57591.424085758605;57699.42300576941;57773.422265776804;58083.709581451825;58193.127103092644;9051.094890510896;9164.95417522908;9238.769030774016;9256.352055356061;9462.810743785223;10184.514297624743;13592.66018349549;13657.954884229513;13748.65628359299;14102.36539355711;11876.228045177037;12401.875981240433;12498.937505312415;12503.749925001632;12562.560310389092;11470;11611.026125558845;12118.272903625812;12169.939150304546;12291.32397718134;11991.880081199426;12211.633650990485;12323.383830808603;12407.875921240671;12583;8381.287590554872;8858.069902660385;8861.291096712377;9160.083991600786;9300.813983720423;10837.837432438362;12067.155299128977;12135;12142.75714485723;12147.878521214674 21:28:00 INFO - PROCESS | 21075 | |3;mootools.html;1430.6208854653526;1518.7949626800382;1536.7848501209833;1747.2923465996275;1916.128161686432;8263.13237110103;8427.19941605548;8434.789130271738;8449.450785698926;8453.112423195567;8153.225443582865;8336.874946875801;8344.45761025533;8347.707830225943;8353.540555269457;2156.0621129808505;2391;2445.9143929962456;2445.938851528713;2471.369800700828;2353.482233908537;2356.670066190735;2358.2689366296418;2362.267697013931;2363.2673871100014;2333.0900948630047;2393.8204634652384;2394.1381102802975;2401.687780588521;2410.144398738449;2132.8400369972237;2378.108209421467;2430.343807172071;2435.6346548017764;2438.4147804526965;2327.290176496185;2381.4760752634566;2404.8076153907646;2407.5666380051584;2412.19191570824;8366.414350995432;8402.991641003058;8414.915850841475;8420.989481262226;8429.83140337196;5237.816676416307;5345.118055520835;5354.27717258169;5392.4337944515655;5470.781168753245;3555.3955827509385;3591.6408359164134;3654.3239500692457;3657.579378371495;3685.1155722626645;1163.9873310220162;1203.6569577670323;1211.0916812390708;1235.258844693186;1264.2856785915928;12180.695482612917;12253.509859605605;12299.938500307531;12310.692232694164;12314.938425307906;4522.954770452319;4669;4744.715317080917;4803.735794531264;4817.662763606514;10264;10286.125679317192;10309.329893556895;10318.071373576415;10347.017033381817;4890.339804126468;5383.703896285742;5391.272178255886;5393.7572809223675;5408;4929.704217746947;4961.603071754251;4962.478939711351;4981.227909674007;5000.1749711297225;4109.589041095866;4138.606832350982;4149.149424367997;4186.853460128942;4193.51774545925;1292.883640472362;1304.7194853106628;1309.3256972659087;1319.4326439631052;1337.3513845784787;962.3985009369144;1001.0840081325651;1002.6089824968268;1011.6964910526813;1014.802113587858;6201.534884883652;6207.037909124003;6224.284207316216;6226.6575338355915;6317.5893566918;490.872373182975;490.9140900342426;492.5370152057055;496.69701482095894;500.50700060440437;4323.221820072355;4362.956370436318;4368.170047690928;4397.582229688174;4400.317950717644;10423.635172769069;10827.296225745458;10846.18653600983;10864.728381790519;10870.782584348268;10635.787284254271;10658.73353166177;10666.200034997406;10668.733281667865;10671;7890.329322007694;8148.144444833444;8151.429399942066;8208.179182081743;8287.41988060842;5934.910976335272;5964.135200396005;6033.637981721111;6138.846528836815;6140.723667435065;9176.403533770284;9256.49089300095;9292.535373231176;9440.10319019692;9452.291078169303;9269.212116970133;9540;9541.379810312308;9544.809103818023;9545.952270238604;6932;7102.289771022856;7114.964425177841;7716.189800071136;7880.369570434237;7830.491018083806;7996.56018918965;8054.516728996279;8060.395470339865;8079.394045446497;7238.710451582088;7425.071866016748;7544.358729508053;7544.886826697494;7569.848603028019;7329.450291228072;7343.522671026366;7375.889361659472;7422.294881986203;7438.404927605887;7054.753083642253;7104.644767761694;7105.147382314155;7111.395531379891;7136.429085673238;4912.606991440604;5220.425753167225;5221.2690223368;5245.160774276097;5248.685078895126;5338.439463856239;5382.4886635769535;5414.3232095988005;5448.645838020516;5525.419830917695 21:28:00 INFO - PROCESS | 21075 | |4;prototype.html;366.02089410826034;371.2037679178164;374.07603220046485;376.689231384108;378.5041595509882;6094.817155485332;6182.567220294582;6213.161223234862;6235.095911092889;6258.780942667007;6189.102580125879;6323.5257355698395;6338.366163383659;6341.651209183493;6342.714577843996;4661.207594708908;4754.429468463789;4851.175300198957;4855.126077306083;4885.316055752206;4837.588804951566;4843.9515604843855;4854.247591623286;4868.537488938563;4869.951300486985;4812.759362031902;4818.229083346665;4820.445648750404;4820.469748327699;4822.204336284509;4821.035792841446;4837.7339246341435;4858.295547145644;4865.0026744517645;4867.415910090812;4316.719413238129;4319.092990472004;4323.6757243206885;4325.8485952991805;4331.328644060176;5978.521718262528;5991.910121348184;6085.574009819315;6087.05650624154;6094.9390506094815;5628.43715628438;5677.205191273225;5679.545636349082;5682.971585142048;5683.715814209314;6622.4370928471135;6633.502487313471;6646.634435106067;6649.035889795952;6651.567648102906;4712.5051869553545;4781.497942716;4797.0645724083815;4797.56821886032;4817.927731084036;6756.729730810762;6873.20958089816;6890.655467226618;6892.758753443605;6895.379415852597;4888.315635810989;4901.240307752304;4901.58336541398;4907.631927605444;4913.975430122827;4950.579200767903;4954.182559877652;4959.851204463872;4960.057589058068;4973.428055773632;5391.272178255886;5552.194931734876;5611.0741727615305;5611.494965453047;5617.129344951457;6659.633720145342;6693.263740988487;6696.497762667722;6705.664716764142;6706.83232919181;1746.2578404178173;1774.2814160265114;1774.3612299572144;1775.2810111904698;1776.2450958342652;1154.2324354304462;1165.469711281382;1166.8249762535688;1190.0717440396504;1214.0773012510556;6534.509911756637;6563.7702680405955;6563.803085907431;6567.179102612174;6582.407583317525;6871.690773915186;6884.51808373409;6890.276520965327;6896.137982752156;6915.930840691529;6465.353464653497;6466.482681385478;6469.450096741735;6471.935280647227;6478.935210647927;3676;3692.612275711065;3693.0398096495105;3706.369917114097;3714.9226724250207;7013.228544860061;7252.637368131573;7255.8911616325795;7266.382357499671;7270.890936635955;7103.289671032855;7134.072570565897;7143.749968751069;7146;7148.249433809375;7224.855502889913;7231.385332246713;7236.6019868907715;7243.311885370987;7255.746048888369;6808.148981377328;6954.269801670752;6964.930350696428;6968.198657154492;6972.442204623517;6839.076724642308;6855.725770969305;6859.485538584726;6861.862762744618;6864.347886950732;7128;7180.9640951797;7184.461165412719;7210.747623833141;7212.42300615939;4730.361401210793;4781.832635857728;4788.377510923558;4791.664583479123;4791.712497250176;6651.767188148391;6761;6891.897296432547;6924.6537673117155;6942.375186233263;6724.361185687352;6755.3244675534015;6759.493038022069;6760.188777346553;6761.560498567577;6711.993201019886;6720.1599800025;6722.596644201363;6727.966360168168;6731.192256929004;6590.538662293593;6616.305287944697;6623.470122390294;6627.536072474881;6629.237637671729;6476.676166191766;6714.328567143247;6751.122354093936;6786.253512113763;6800.863982720417;6577.309382514767;6611.206655201407;6611.933880661324;6613.735450581923;6619.635920024252 21:28:00 INFO - PROCESS | 21075 | |5;yui.html;844.9957750211248;891.5720454181989;906.8821053263076;916.1891725822647;940.1632547033138;14826.77759833602;15133.092014479125;17091.059991700466;17406.477805665818;17452.47642570725;15994.120323382209;16024.198790060509;16037.35850565979;16115.113668748214;16134.83865161348;742.4876834983844;890.1899271662788;903.2728653434;913.3652111782314;918.3296193778546;904.2314033071902;905.2305540290766;906.6645341223724;909.1272378516632;909.5179554835947;944.504135328951;946.820104180207;947.1286416496839;947.1570302430807;947.2895328503622;780.7228433905967;939.7603611079165;940.7271891151523;956.7416797464646;957.9185769209626;698.4796326736563;700.4746440169872;701.694762778188;702.0241863809309;702.3292755418563;14410.639734006734;14448.133112013207;14469.782953255604;14492.565223043326;14507.347169377403;13931.512397066155;14341.856581434156;14514.854851451455;14706.779398309034;14760.630984225372;12063.819042714365;12185.207961482476;12224.14430989831;12225.510979560808;12455.93772031143;4002;4020.336644453662;4066.9389959150635;4138.6482149017365;4153.439285696422;58854.7057264711;59289.99999999957;61155.388446115416;62092.68953655248;62556;11798.5870494534;11999.220050696676;12015.879841201477;12109.333986630643;12205.877941220651;21008.579828403348;21061.368158955254;21070.47323816917;21127.154913803275;21161.25935592247;8855.3801233913;8861.379703420698;8872.24585910205;8987.595558199897;9188.724338269863;4723.48041715411;4884.438289596671;4918.926216106761;4960.330355402047;5022.121128802445;3555.1289933966345;3627.0750958505414;3631.4915911772373;3664.1755604988666;3667.4132138857653;671.412514050206;673.2392396591918;678.816719485736;688.5696439725172;707.5471698113212;837.3552364679163;837.9371547133867;838;840.6689705023191;860.7891066688576;9706.514674266258;10063.245256605784;10145.391276523433;10302;10316.329438586468;762.6872982077322;778.5678948183812;786.6499407763605;794.1105961323354;820.3191351178508;4478.731276123443;4704.5765881070865;4716.386869706985;4718.740469274223;4808.855734327975;64700;65942.67028664827;66058;66209;66267.33732662708;55439.16841247304;56388.43611563832;56773.43226567764;57541.712291439115;58009.12986305209;51518.742406287725;52141;52244.21633675498;52787.472125278255;52940;9167.587458564514;9273.768155796159;9333.439993600407;9383.85924211151;9498;38988;39579;39978.400323995746;40190;40197.79901100476;45300.32049519326;45514.544854552354;50248.706922177465;50333.49666503288;50515.49484505108;10883.727906802393;10956.34261944286;11031.17266205054;11045.392503412068;11332.830007550057;12891.742165156456;12974;13094.541691040768;13157.671058223237;13189.736205276033;11953.282803031498;11960;12154.817677734663;12157.513699451923;12324;12323.753524929272;12477;12543.87456125427;12557.937210314256;12608;11941.16411851162;11981.221220620631;12093.15347925672;12273.509059637872;12320.075994300643;10023.599056037678;10164.695059148238;10176.389416635026;10275.794484110127;10456.111230545488;9980.151687106387;10344.534495947852;10374.377537347784;10387.480625968823;10421.06210441064 21:28:00 INFO - PROCESS | 21075 | __end_tp_report 21:28:00 INFO - PROCESS | 21075 | __start_cc_report 21:28:00 INFO - PROCESS | 21075 | _x_x_mozilla_cycle_collect,857 21:28:00 INFO - PROCESS | 21075 | __end_cc_report 21:28:00 INFO - PROCESS | 21075 | __startTimestamp1463286480066__endTimestamp 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | ------- Summary: start ------- 21:28:00 INFO - PROCESS | 21075 | Number of tests: 6 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#0] dojo.html Cycles:165 Average:5475.78 Median:5671.09 stddev:600.75 (10.6%) stddev-sans-first:576.98 21:28:00 INFO - PROCESS | 21075 | Values: 3263.8 3326.7 3340.3 3372.1 3380.9 5490.4 5505.7 5534.9 5538.2 5544.9 5521.5 5547.3 5559.7 5575.3 5579.4 4819.4 5002.7 5096.6 5145.3 5171.9 4933.2 4980.2 4988.5 4989.9 5017.1 4993.1 5055.9 5074.3 5077.7 5105.9 5091.9 5097.6 5109.9 5110.7 5112.3 4886.1 4957.3 4998.5 5017.7 5047.8 5347.7 5350.9 5378.8 5398.9 5405.5 5824.5 5830.2 5840.4 5847.1 5854.6 5880.1 5891.9 5930.6 5947.0 5958.7 5518.3 5544.9 5558.8 5564.3 5566.7 5857.4 6056.5 6060.6 6063.2 6073.5 5793.5 5835.9 5849.4 5856.3 5869.8 5921.4 5925.4 5925.6 5925.7 5927.0 5632.2 5709.1 5880.9 5883.1 5885.5 5824.5 5842.9 5844.7 5849.6 5887.7 4459.1 4463.8 4474.9 4475.3 4501.2 3974.0 4024.0 4037.8 4068.5 4077.3 5530.5 5557.7 5558.4 5572.2 5576.0 5863.9 5867.5 5867.6 5873.4 5881.6 5488.6 5544.2 5551.5 5617.3 5686.2 5073.3 5078.1 5084.9 5086.6 5113.7 6080.3 6085.1 6085.7 6091.6 6092.9 5795.0 5866.8 5905.2 5908.5 5930.1 6107.7 6119.2 6131.8 6139.8 6147.5 5840.6 5849.8 5851.4 5856.0 5892.0 5744.4 5875.3 5927.6 5984.6 5996.7 5661.0 5665.2 5680.7 5718.7 5741.7 5648.2 5691.3 5704.3 5707.6 5715.5 5440.6 5668.2 5742.3 5769.5 5780.8 5784.8 5790.6 5792.0 5796.8 5800.9 5561.5 5667.2 5671.1 5675.0 5709.2 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#1] ext.html Cycles:180 Average:54224.88 Median:38823.71 stddev:93952.55 (242.0%) stddev-sans-first:94130.01 21:28:00 INFO - PROCESS | 21075 | Values: 655.1 665.5 673.3 722.0 753.8 32498.8 33180.8 33186.2 33219.0 33223.7 42205.0 43194.8 43210.6 43257.0 43289.8 8877.4 9070.3 9072.4 9075.7 9076.1 8427.9 8723.0 8872.6 10792.2 11073.9 10754.9 10825.9 10826.4 10828.2 10842.4 8461.8 10667.2 10754.0 10771.6 10775.6 10417.5 10610.3 10614.5 10615.7 10619.8 40037.6 41210.6 41230.8 41294.2 41358.4 3927.5 3956.1 4205.1 4305.6 4435.3 31866.4 33084.5 34005.0 35239.6 35431.3 4704.1 4715.7 4723.8 4736.2 4839.7 341610.0 341704.0 342790.0 343037.0 345788.0 69952.7 70427.0 71020.0 71061.0 71182.6 73915.6 74002.0 74087.6 74670.0 74760.6 19437.9 19635.9 19708.2 19716.8 19722.2 14367.9 15529.2 15606.2 15616.3 15769.5 21692.8 21721.1 22013.9 22069.2 22120.8 7762.4 7936.8 8182.6 8220.8 8296.7 1013.5 1090.1 1094.6 1198.3 1206.2 33921.2 34153.3 34296.7 34307.0 34496.3 1158.9 1190.1 1200.2 1221.9 1282.0 17975.6 18393.5 18406.9 18481.2 18539.2 229357.4 231821.0 231995.0 232047.0 232482.0 61837.0 61890.4 62395.4 62496.0 62526.7 1419.6 1498.2 1510.0 1510.2 1523.0 30444.8 32469.7 32917.7 33014.3 34242.3 50223.0 60445.0 61636.0 61873.0 61946.7 455699.0 456620.0 459439.0 460577.0 462310.0 40112.0 40685.0 45475.0 47204.8 47594.8 45386.0 45614.0 45710.3 46291.0 46667.8 39049.2 41213.8 42024.2 42492.6 42759.8 39025.6 42344.8 42707.0 47262.0 47516.8 42924.1 43798.0 43862.3 44058.0 44273.3 36860.3 37458.6 37954.4 38388.4 38621.8 29462.4 29592.7 29774.7 29884.6 31809.2 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#2] jquery.html Cycles:180 Average:30748.53 Median:22531.38 stddev:89173.92 (395.8%) stddev-sans-first:89395.03 21:28:00 INFO - PROCESS | 21075 | Values: 437.5 438.2 444.5 447.8 454.9 18499.1 18583.2 19086.0 19151.5 19206.3 18318.5 18357.4 19004.0 19131.1 19147.7 5740.7 5745.1 5979.3 5986.7 5989.3 4369.6 5886.7 5949.8 6331.8 6624.4 6549.1 6593.5 6603.6 6606.3 6609.9 6330.6 6531.1 6544.3 6545.4 6554.5 4028.2 4058.4 5675.2 5826.7 5826.9 22293.8 22432.6 22630.2 22815.4 22826.9 2728.8 3012.9 3023.5 3170.2 3182.8 10341.3 10608.2 10701.2 10745.5 10838.4 1766.0 1976.0 2012.0 2077.6 2194.4 77690.6 80987.6 81613.0 82587.6 84696.6 10852.2 11077.7 11143.8 11407.4 11630.4 15869.4 15881.7 15903.7 15950.0 16029.9 8516.4 8750.2 8896.6 8900.5 9034.7 8848.7 8849.1 8883.2 8949.8 8955.9 5071.9 5144.3 5153.7 5233.5 5236.1 1865.0 2039.5 2073.5 2090.5 2097.5 977.3 1063.4 1110.6 1180.3 1195.8 17470.6 17853.8 18412.8 18788.6 19105.4 1054.7 1078.1 1105.8 1136.7 1165.1 10937.6 10939.2 10939.6 10993.3 11046.7 531890.0 534504.0 543720.0 544758.0 555404.0 95777.5 96466.1 96564.5 97660.5 97958.0 1071.8 1085.8 1086.3 1097.9 1102.4 17027.0 17208.9 17307.7 17322.6 17468.6 39256.0 40720.4 40726.8 41068.2 41728.0 57591.4 57699.4 57773.4 58083.7 58193.1 9051.1 9165.0 9238.8 9256.4 9462.8 10184.5 13592.7 13658.0 13748.7 14102.4 11876.2 12401.9 12498.9 12503.7 12562.6 11470.0 11611.0 12118.3 12169.9 12291.3 11991.9 12211.6 12323.4 12407.9 12583.0 8381.3 8858.1 8861.3 9160.1 9300.8 10837.8 12067.2 12135.0 12142.8 12147.9 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#3] mootools.html Cycles:180 Average:5729.42 Median:2729.15 stddev:3158.92 (115.7%) stddev-sans-first:3151.26 21:28:00 INFO - PROCESS | 21075 | Values: 1430.6 1518.8 1536.8 1747.3 1916.1 8263.1 8427.2 8434.8 8449.5 8453.1 8153.2 8336.9 8344.5 8347.7 8353.5 2156.1 2391.0 2445.9 2445.9 2471.4 2353.5 2356.7 2358.3 2362.3 2363.3 2333.1 2393.8 2394.1 2401.7 2410.1 2132.8 2378.1 2430.3 2435.6 2438.4 2327.3 2381.5 2404.8 2407.6 2412.2 8366.4 8403.0 8414.9 8421.0 8429.8 5237.8 5345.1 5354.3 5392.4 5470.8 3555.4 3591.6 3654.3 3657.6 3685.1 1164.0 1203.7 1211.1 1235.3 1264.3 12180.7 12253.5 12299.9 12310.7 12314.9 4523.0 4669.0 4744.7 4803.7 4817.7 10264.0 10286.1 10309.3 10318.1 10347.0 4890.3 5383.7 5391.3 5393.8 5408.0 4929.7 4961.6 4962.5 4981.2 5000.2 4109.6 4138.6 4149.1 4186.9 4193.5 1292.9 1304.7 1309.3 1319.4 1337.4 962.4 1001.1 1002.6 1011.7 1014.8 6201.5 6207.0 6224.3 6226.7 6317.6 490.9 490.9 492.5 496.7 500.5 4323.2 4363.0 4368.2 4397.6 4400.3 10423.6 10827.3 10846.2 10864.7 10870.8 10635.8 10658.7 10666.2 10668.7 10671.0 7890.3 8148.1 8151.4 8208.2 8287.4 5934.9 5964.1 6033.6 6138.8 6140.7 9176.4 9256.5 9292.5 9440.1 9452.3 9269.2 9540.0 9541.4 9544.8 9546.0 6932.0 7102.3 7115.0 7716.2 7880.4 7830.5 7996.6 8054.5 8060.4 8079.4 7238.7 7425.1 7544.4 7544.9 7569.8 7329.5 7343.5 7375.9 7422.3 7438.4 7054.8 7104.6 7105.1 7111.4 7136.4 4912.6 5220.4 5221.3 5245.2 5248.7 5338.4 5382.5 5414.3 5448.6 5525.4 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#4] prototype.html Cycles:180 Average:5632.82 Median:6465.92 stddev:1676.43 (25.9%) stddev-sans-first:1633.86 21:28:00 INFO - PROCESS | 21075 | Values: 366.0 371.2 374.1 376.7 378.5 6094.8 6182.6 6213.2 6235.1 6258.8 6189.1 6323.5 6338.4 6341.7 6342.7 4661.2 4754.4 4851.2 4855.1 4885.3 4837.6 4844.0 4854.2 4868.5 4870.0 4812.8 4818.2 4820.4 4820.5 4822.2 4821.0 4837.7 4858.3 4865.0 4867.4 4316.7 4319.1 4323.7 4325.8 4331.3 5978.5 5991.9 6085.6 6087.1 6094.9 5628.4 5677.2 5679.5 5683.0 5683.7 6622.4 6633.5 6646.6 6649.0 6651.6 4712.5 4781.5 4797.1 4797.6 4817.9 6756.7 6873.2 6890.7 6892.8 6895.4 4888.3 4901.2 4901.6 4907.6 4914.0 4950.6 4954.2 4959.9 4960.1 4973.4 5391.3 5552.2 5611.1 5611.5 5617.1 6659.6 6693.3 6696.5 6705.7 6706.8 1746.3 1774.3 1774.4 1775.3 1776.2 1154.2 1165.5 1166.8 1190.1 1214.1 6534.5 6563.8 6563.8 6567.2 6582.4 6871.7 6884.5 6890.3 6896.1 6915.9 6465.4 6466.5 6469.5 6471.9 6478.9 3676.0 3692.6 3693.0 3706.4 3714.9 7013.2 7252.6 7255.9 7266.4 7270.9 7103.3 7134.1 7143.7 7146.0 7148.2 7224.9 7231.4 7236.6 7243.3 7255.7 6808.1 6954.3 6964.9 6968.2 6972.4 6839.1 6855.7 6859.5 6861.9 6864.3 7128.0 7181.0 7184.5 7210.7 7212.4 4730.4 4781.8 4788.4 4791.7 4791.7 6651.8 6761.0 6891.9 6924.7 6942.4 6724.4 6755.3 6759.5 6760.2 6761.6 6712.0 6720.2 6722.6 6728.0 6731.2 6590.5 6616.3 6623.5 6627.5 6629.2 6476.7 6714.3 6751.1 6786.3 6800.9 6577.3 6611.2 6611.9 6613.7 6619.6 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - PROCESS | 21075 | [#5] yui.html Cycles:180 Average:15701.72 Median:25109.56 stddev:18342.70 (73.1%) stddev-sans-first:18360.23 21:28:00 INFO - PROCESS | 21075 | Values: 845.0 891.6 906.9 916.2 940.2 14826.8 15133.1 17091.1 17406.5 17452.5 15994.1 16024.2 16037.4 16115.1 16134.8 742.5 890.2 903.3 913.4 918.3 904.2 905.2 906.7 909.1 909.5 944.5 946.8 947.1 947.2 947.3 780.7 939.8 940.7 956.7 957.9 698.5 700.5 701.7 702.0 702.3 14410.6 14448.1 14469.8 14492.6 14507.3 13931.5 14341.9 14514.9 14706.8 14760.6 12063.8 12185.2 12224.1 12225.5 12455.9 4002.0 4020.3 4066.9 4138.6 4153.4 58854.7 59290.0 61155.4 62092.7 62556.0 11798.6 11999.2 12015.9 12109.3 12205.9 21008.6 21061.4 21070.5 21127.2 21161.3 8855.4 8861.4 8872.2 8987.6 9188.7 4723.5 4884.4 4918.9 4960.3 5022.1 3555.1 3627.1 3631.5 3664.2 3667.4 671.4 673.2 678.8 688.6 707.5 837.4 837.9 838.0 840.7 860.8 9706.5 10063.2 10145.4 10302.0 10316.3 762.7 778.6 786.6 794.1 820.3 4478.7 4704.6 4716.4 4718.7 4808.9 64700.0 65942.7 66058.0 66209.0 66267.3 55439.2 56388.4 56773.4 57541.7 58009.1 51518.7 52141.0 52244.2 52787.5 52940.0 9167.6 9273.8 9333.4 9383.9 9498.0 38988.0 39579.0 39978.4 40190.0 40197.8 45300.3 45514.5 50248.7 50333.5 50515.5 10883.7 10956.3 11031.2 11045.4 11332.8 12891.7 12974.0 13094.5 13157.7 13189.7 11953.3 11960.0 12154.8 12157.5 12324.0 12323.8 12477.0 12543.9 12557.9 12608.0 11941.2 11981.2 12093.2 12273.5 12320.1 10023.6 10164.7 10176.4 10275.8 10456.1 9980.2 10344.5 10374.4 10387.5 10421.1 21:28:00 INFO - PROCESS | 21075 | -------- Summary: end -------- 21:28:00 INFO - PROCESS | 21075 | 21:28:00 INFO - TEST-INFO | 21075: exit 0 21:28:00 INFO - TEST-OK | dromaeo_css | took 1111543ms 21:28:00 INFO - TEST-START | kraken 21:28:00 INFO - operating with platform_type : linux_ 21:28:00 INFO - Initialising browser for kraken test... 21:28:00 INFO - TEST-INFO | started process 21268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeMd_9f/profile http://localhost:46490/getInfo.html) 21:28:04 INFO - PROCESS | 21268 | __metrics Screen width/height:1600/1200 21:28:04 INFO - PROCESS | 21268 | colorDepth:24 21:28:04 INFO - PROCESS | 21268 | Browser inner width/height: 1024/697 21:28:04 INFO - PROCESS | 21268 | __metrics 21:28:06 ERROR - PROCESS | 21268 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:28:06 INFO - TEST-INFO | 21268: exit 0 21:28:06 INFO - Browser initialized. 21:28:06 INFO - Running cycle 1/1 for kraken test... 21:28:06 INFO - TEST-INFO | started process 21361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeMd_9f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:18 INFO - PROCESS | 21361 | RSS: Main: 142163968 21:28:18 INFO - PROCESS | 21361 | 21:28:21 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46490/tests/kraken/driver.html?testName=audio-beat-detection) 21:28:21 INFO - PROCESS | 21361 | RSS: Main: 197210112 21:28:21 INFO - PROCESS | 21361 | 21:28:24 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46490/tests/kraken/driver.html?testName=audio-dft) 21:28:25 INFO - PROCESS | 21361 | RSS: Main: 182587392 21:28:25 INFO - PROCESS | 21361 | 21:28:27 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46490/tests/kraken/driver.html?testName=audio-fft) 21:28:28 INFO - PROCESS | 21361 | RSS: Main: 272855040 21:28:28 INFO - PROCESS | 21361 | 21:28:30 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46490/tests/kraken/driver.html?testName=audio-oscillator) 21:28:31 INFO - PROCESS | 21361 | RSS: Main: 179109888 21:28:31 INFO - PROCESS | 21361 | 21:28:33 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:28:34 INFO - PROCESS | 21361 | RSS: Main: 177221632 21:28:34 INFO - PROCESS | 21361 | 21:28:36 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46490/tests/kraken/driver.html?testName=imaging-darkroom) 21:28:37 INFO - PROCESS | 21361 | RSS: Main: 211681280 21:28:37 INFO - PROCESS | 21361 | 21:28:40 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46490/tests/kraken/driver.html?testName=imaging-desaturate) 21:28:41 INFO - PROCESS | 21361 | RSS: Main: 213143552 21:28:41 INFO - PROCESS | 21361 | 21:28:44 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46490/tests/kraken/driver.html?testName=json-parse-financial) 21:28:44 INFO - PROCESS | 21361 | RSS: Main: 212721664 21:28:44 INFO - PROCESS | 21361 | 21:28:46 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:46 INFO - PROCESS | 21361 | RSS: Main: 192950272 21:28:46 INFO - PROCESS | 21361 | 21:28:48 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:28:49 INFO - PROCESS | 21361 | RSS: Main: 198533120 21:28:49 INFO - PROCESS | 21361 | 21:28:51 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:28:51 INFO - PROCESS | 21361 | RSS: Main: 210411520 21:28:51 INFO - PROCESS | 21361 | 21:28:54 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:28:54 INFO - PROCESS | 21361 | RSS: Main: 214999040 21:28:54 INFO - PROCESS | 21361 | 21:28:57 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:28:58 INFO - PROCESS | 21361 | RSS: Main: 211197952 21:28:58 INFO - PROCESS | 21361 | 21:28:59 INFO - PROCESS | 21361 | Cycle 1(1): loaded http://localhost:46490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46490/tests/kraken/driver.html?testName=ai-astar) 21:29:00 INFO - PROCESS | 21361 | RSS: Main: 275042304 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | __start_tp_report 21:29:00 INFO - PROCESS | 21361 | _x_x_mozilla_page_load 21:29:00 INFO - PROCESS | 21361 | _x_x_mozilla_page_load_details 21:29:00 INFO - PROCESS | 21361 | |i|pagename|runs| 21:29:00 INFO - PROCESS | 21361 | |0;ai-astar;111;111;111;110;109;111;107;109;107;109 21:29:00 INFO - PROCESS | 21361 | |1;audio-beat-detection;156;132;156;180;127;157;150;127;181;126 21:29:00 INFO - PROCESS | 21361 | |2;audio-dft;162;160;160;160;159;159;159;162;159;160 21:29:00 INFO - PROCESS | 21361 | |3;audio-fft;77;77;77;172;76;75;76;75;166;75 21:29:00 INFO - PROCESS | 21361 | |4;audio-oscillator;92;91;157;90;91;91;146;91;92;152 21:29:00 INFO - PROCESS | 21361 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;116;117 21:29:00 INFO - PROCESS | 21361 | |6;imaging-darkroom;145;145;145;146;145;146;144;146;145;145 21:29:00 INFO - PROCESS | 21361 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;94;95 21:29:00 INFO - PROCESS | 21361 | |8;json-parse-financial;54;54;55;54;54;54;55;78;54;53 21:29:00 INFO - PROCESS | 21361 | |9;json-stringify-tinderbox;57;67;57;58;57;57;58;57;57;58 21:29:00 INFO - PROCESS | 21361 | |10;stanford-crypto-aes;74;72;80;71;74;73;73;73;85;73 21:29:00 INFO - PROCESS | 21361 | |11;stanford-crypto-ccm;118;122;117;115;120;113;112;120;130;129 21:29:00 INFO - PROCESS | 21361 | |12;stanford-crypto-pbkdf2;162;159;159;163;166;160;159;168;160;163 21:29:00 INFO - PROCESS | 21361 | |13;stanford-crypto-sha256-iterative;65;67;77;66;67;67;65;67;67;62 21:29:00 INFO - PROCESS | 21361 | __end_tp_report 21:29:00 INFO - PROCESS | 21361 | __start_cc_report 21:29:00 INFO - PROCESS | 21361 | _x_x_mozilla_cycle_collect,1562 21:29:00 INFO - PROCESS | 21361 | __end_cc_report 21:29:00 INFO - PROCESS | 21361 | __startTimestamp1463286540056__endTimestamp 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | ------- Summary: start ------- 21:29:00 INFO - PROCESS | 21361 | Number of tests: 14 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.50 stddev:1.58 (1.4%) stddev-sans-first:1.58 21:29:00 INFO - PROCESS | 21361 | Values: 111.0 111.0 111.0 110.0 109.0 111.0 107.0 109.0 107.0 109.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#1] audio-beat-detection Cycles:10 Average:149.20 Median:156.00 stddev:20.91 (13.4%) stddev-sans-first:22.03 21:29:00 INFO - PROCESS | 21361 | Values: 156.0 132.0 156.0 180.0 127.0 157.0 150.0 127.0 181.0 126.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 21:29:00 INFO - PROCESS | 21361 | Values: 162.0 160.0 160.0 160.0 159.0 159.0 159.0 162.0 159.0 160.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#3] audio-fft Cycles:10 Average:94.60 Median:76.00 stddev:39.25 (51.6%) stddev-sans-first:41.11 21:29:00 INFO - PROCESS | 21361 | Values: 77.0 77.0 77.0 172.0 76.0 75.0 76.0 75.0 166.0 75.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#4] audio-oscillator Cycles:10 Average:109.30 Median:91.00 stddev:29.36 (32.3%) stddev-sans-first:30.46 21:29:00 INFO - PROCESS | 21361 | Values: 92.0 91.0 157.0 90.0 91.0 91.0 146.0 91.0 92.0 152.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:29:00 INFO - PROCESS | 21361 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 21:29:00 INFO - PROCESS | 21361 | Values: 145.0 145.0 145.0 146.0 145.0 146.0 144.0 146.0 145.0 145.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 21:29:00 INFO - PROCESS | 21361 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:54.00 stddev:7.58 (14.0%) stddev-sans-first:7.98 21:29:00 INFO - PROCESS | 21361 | Values: 54.0 54.0 55.0 54.0 54.0 54.0 55.0 78.0 54.0 53.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:57.50 stddev:3.09 (5.4%) stddev-sans-first:3.24 21:29:00 INFO - PROCESS | 21361 | Values: 57.0 67.0 57.0 58.0 57.0 57.0 58.0 57.0 57.0 58.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:73.50 stddev:4.32 (5.9%) stddev-sans-first:4.57 21:29:00 INFO - PROCESS | 21361 | Values: 74.0 72.0 80.0 71.0 74.0 73.0 73.0 73.0 85.0 73.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#11] stanford-crypto-ccm Cycles:10 Average:119.60 Median:120.00 stddev:6.10 (5.1%) stddev-sans-first:6.44 21:29:00 INFO - PROCESS | 21361 | Values: 118.0 122.0 117.0 115.0 120.0 113.0 112.0 120.0 130.0 129.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.50 stddev:3.14 (1.9%) stddev-sans-first:3.33 21:29:00 INFO - PROCESS | 21361 | Values: 162.0 159.0 159.0 163.0 166.0 160.0 159.0 168.0 160.0 163.0 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - PROCESS | 21361 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:3.86 (5.8%) stddev-sans-first:4.02 21:29:00 INFO - PROCESS | 21361 | Values: 65.0 67.0 77.0 66.0 67.0 67.0 65.0 67.0 67.0 62.0 21:29:00 INFO - PROCESS | 21361 | -------- Summary: end -------- 21:29:00 INFO - PROCESS | 21361 | 21:29:00 INFO - TEST-INFO | 21361: exit 0 21:29:01 INFO - TEST-OK | kraken | took 60258ms 21:29:01 INFO - SUITE-END | took 1171s 21:29:01 INFO - Completed test suite (00:19:31) 21:29:01 INFO - javascript benchmark 21:29:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.804171749695, 3326.717229035531, 3340.2985373071656, 3372.123247955531, 3380.8816691415805, 5490.423505531917, 5505.697186654733, 5534.944650553493, 5538.224648549206, 5544.889102217953, 5521.5306698930535, 5547.25112109865, 5559.749811258493, 5575.303087114111, 5579.442055794419, 4819.445763737162, 5002.674826136298, 5096.617753668471, 5145.3311069560905, 5171.948280517203, 4933.161362568372, 4980.203167493201, 4988.501149885019, 4989.875253118683, 5017.122003649365, 4993.051320249159, 5055.898882022358, 5074.340335756365, 5077.720725360104, 5105.923411148836, 5091.872703182413, 5097.617678674096, 5109.897802043957, 5110.744462776846, 5112.258722485219, 4886.1204983103025, 4957.256411538262, 4998.525140111684, 5017.7491125443585, 5047.772850221712, 5347.679139251657, 5350.946490535122, 5378.784848606051, 5398.946010539884, 5405.540529054996, 5824.53403727705, 5830.154627578977, 5840.415958404168, 5847.122931560258, 5854.648721076749, 5880.088586269135, 5891.911621325684, 5930.644161350333, 5947.0, 5958.672273024982, 5518.255035570187, 5544.944550554484, 5558.833235002916, 5564.304461942257, 5566.749496272718, 5857.355690874, 6056.485198758067, 6060.575759696867, 6063.242094738158, 6073.544484163705, 5793.536517078624, 5835.883282334415, 5849.415058494116, 5856.297244330707, 5869.7945571904775, 5921.437463440966, 5925.377835327228, 5925.585209035412, 5925.674087925119, 5927.0, 5632.211490391349, 5709.0865461525955, 5880.882382352414, 5883.14694369314, 5885.529157667376, 5824.504917082011, 5842.941570584325, 5844.707764611752, 5849.590528663038, 5887.705614719247, 4459.063596644741, 4463.821447142078, 4474.865754027384, 4475.306327519174, 4501.212287849613, 3974.046228905042, 4023.979880100581, 4037.7981100944835, 4068.5321188063754, 4077.32724100526, 5530.5022547970875, 5557.722113894289, 5558.360788509373, 5572.247746554244, 5575.972120139454, 5863.941360586425, 5867.471927526543, 5867.64794112346, 5873.442023007808, 5881.647101173943, 5488.643238189505, 5544.1960915667005, 5551.528120109755, 5617.325920889438, 5686.175504551817, 5073.289739436482, 5078.111330517145, 5084.949150508522, 5086.64393492452, 5113.667611605234, 6080.331163572004, 6085.056816193497, 6085.66528840918, 6091.634501929899, 6092.939070609325, 5794.971025145016, 5866.794662186803, 5905.202797622266, 5908.527317814479, 5930.05119180929, 6107.735039974475, 6119.228375855631, 6131.828320783331, 6139.768135342541, 6147.477464415575, 5840.649561026352, 5849.830033993304, 5851.356350801493, 5856.033754430471, 5891.968905441617, 5744.368119506935, 5875.265591801025, 5927.585069045125, 5984.551158663031, 5996.730147143477, 5661.0, 5665.150227465913, 5680.7443665036, 5718.685472299064, 5741.741621626953, 5648.181013753065, 5691.3170419549915, 5704.3154821421695, 5707.600467967202, 5715.485606295453, 5440.646357986877, 5668.178114173505, 5742.339630942495, 5769.53843692512, 5780.768769249183, 5784.82645520635, 5790.623609465371, 5792.0, 5796.768129274782, 5800.854978625568, 5561.5272701820795, 5667.2349232854685, 5671.092625179951, 5674.971625141848, 5709.229254050816], "value": 5437.2946988306285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.1221363373079, 665.4676258992805, 673.3064943108599, 722.010845142155, 753.8416932444186, 32498.837505812466, 33180.834095829516, 33186.17034574137, 33219.0, 33223.66776332233, 42205.0, 43194.784026079906, 43210.567894321044, 43257.0, 43289.7835510822, 8877.422967507107, 9070.319726020542, 9072.36493445459, 9075.682351117712, 9076.092390760938, 8427.873581896276, 8722.956385218096, 8872.556372181365, 10792.190585706065, 11073.889261107366, 10754.946225268863, 10825.945870270676, 10826.350418974887, 10828.187885908548, 10842.403667798268, 8461.788455288606, 10667.199960002992, 10754.0, 10771.62299319524, 10775.5689772409, 10417.479126043667, 10610.257281990263, 10614.522346494427, 10615.734606634818, 10619.78760424795, 40037.59962400368, 41210.587894120974, 41230.79384603088, 41294.174116517504, 41358.37962430566, 3927.509061367329, 3956.1135662923716, 4205.053862880827, 4305.63402110821, 4435.290353543418, 31866.362672746647, 33084.503732444035, 34005.0, 35239.647603523896, 35431.291374172375, 4704.129735998817, 4715.726753776461, 4723.811047558093, 4736.242201247784, 4839.741667166527, 341610.0, 341704.0, 342790.0, 343037.0, 345788.0, 69952.6502367485, 70427.0, 71020.0, 71061.0, 71182.64408677924, 73915.63042184754, 74002.0, 74087.62956185185, 74670.0, 74760.62619686975, 19437.90281048614, 19635.901820490806, 19708.21167153298, 19716.802831971785, 19722.211111555378, 14367.928160359132, 15529.223538823013, 15606.219689015505, 15616.297266623029, 15769.526914192593, 21692.783072169394, 21721.131154753948, 22013.889930550566, 22069.227577034726, 22120.778792212193, 7762.411064033887, 7936.841263174704, 8182.590870456454, 8220.8355832883, 8296.70961516344, 1013.4780587997187, 1090.0625462102585, 1094.5949998500446, 1198.298995087866, 1206.2099324942176, 33921.15197120042, 34153.31693366119, 34296.65703342934, 34307.0, 34496.310073798384, 1158.8956993870424, 1190.1312042209236, 1200.1598880783486, 1221.9266843989212, 1281.9935900320438, 17975.640487190183, 18393.54016149607, 18406.907965460086, 18481.1683474244, 18539.165737541844, 229357.41285174538, 231821.0, 231995.0, 232047.0, 232482.0, 61837.0, 61890.38109618846, 62395.376046239864, 62496.0, 62526.687366562874, 1419.6237996930822, 1498.168516473346, 1510.0, 1510.1920472547135, 1522.9771553426708, 30444.847775761864, 32469.675303246666, 32917.67082329146, 33014.33971320608, 34242.31515369729, 50223.0, 60445.0, 61636.0, 61873.0, 61946.69026654838, 455699.0, 456620.0, 459439.0, 460577.0, 462310.0, 40112.0, 40685.0, 45475.0, 47204.7639761799, 47594.762026189645, 45386.0, 45614.0, 45710.314345285515, 46291.0, 46667.766661166475, 39049.219015620096, 41213.79393103135, 42024.159516810105, 42492.5750742501, 42759.78620107004, 39025.60974390333, 42344.80972821176, 42707.0, 47262.0, 47516.762416187696, 42924.14151716886, 43798.0, 43862.34206486841, 44058.0, 44273.33589996217, 36860.26279474449, 37458.6254137466, 37954.43068354032, 38388.42417363686, 38621.80689096537, 29462.41075178527, 29592.704072958993, 29774.66014029417, 29884.551731724478, 31809.204769880937], "value": 21411.847810411447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.52747033204145, 438.2086111469936, 444.48661795626066, 447.7783497168902, 454.8817307500051, 18499.0750462477, 18583.163757630904, 19086.0, 19151.521211969706, 19206.327778527753, 18318.542036449096, 18357.357492487765, 19004.0, 19131.139098740554, 19147.712784308245, 5740.741666624991, 5745.138229265613, 5979.342272350038, 5986.730597123118, 5989.251343582052, 4369.584889435499, 5886.735096920649, 5949.762009519614, 6331.77838775643, 6624.403803657669, 6549.083128362033, 6593.505487088463, 6603.603783772989, 6606.3063378345305, 6609.900851487231, 6330.588511746721, 6531.05299731541, 6544.345565443466, 6545.37818907203, 6554.541182117254, 4028.194361127757, 4058.391241313797, 5675.2054712340305, 5826.650400975955, 5826.854328641775, 22293.777062229332, 22432.551348972927, 22630.207942721925, 22815.42961425961, 22826.885865570734, 2728.7816974641983, 3012.9397412051853, 3023.4708925938094, 3170.239142605781, 3182.80903145812, 10341.276110672256, 10608.204384671104, 10701.197410194267, 10745.462726863625, 10838.403887786248, 1765.9911700441544, 1975.980240197594, 2012.0241682783837, 2077.6364136276093, 2194.3746032380855, 77690.61154694247, 80987.5950620249, 81613.0, 82587.58706206491, 84696.57651711763, 10852.186086043579, 11077.723056923642, 11143.777124457627, 11407.429628518541, 11630.360330181751, 15869.444569440015, 15881.68236635284, 15903.681926361409, 15950.0, 16029.919850400907, 8516.446430981967, 8750.212480876751, 8896.59965301563, 8900.510471924106, 9034.683786067588, 8848.734537963872, 8849.115088491099, 8883.156100170474, 8949.776255593662, 8955.910440895637, 5071.873203169876, 5144.250954675428, 5153.665011774223, 5233.502817232356, 5236.1098613235845, 1865.0301843041543, 2039.510517475795, 2073.52308968935, 2090.4669309326327, 2097.5280561873456, 977.3451787302524, 1063.4151216830712, 1110.5779803674714, 1180.339010154299, 1195.8325834383193, 17470.563235919202, 17853.821461785476, 18412.81587184138, 18788.624227515375, 19105.426837194627, 1054.667779649408, 1078.0728573426848, 1105.7788442311569, 1136.72718547548, 1165.1086918507349, 10937.56249750001, 10939.179561532917, 10939.56241750321, 10993.340399576051, 11046.723831904266, 531890.0, 534504.0, 543720.0, 544758.0, 555404.0, 95777.521112394, 96466.07067858604, 96564.51717741367, 97660.51169744106, 97958.0, 1071.7588542577857, 1085.7719878825535, 1086.3319058778793, 1097.8792332843382, 1102.4212288548576, 17027.0, 17208.913955430144, 17307.740383894503, 17322.566935826704, 17468.563285917953, 39256.0, 40720.38919416152, 40726.79636601798, 41068.178636426506, 41728.0, 57591.424085758605, 57699.42300576941, 57773.422265776804, 58083.709581451825, 58193.127103092644, 9051.094890510896, 9164.95417522908, 9238.769030774016, 9256.352055356061, 9462.810743785223, 10184.514297624743, 13592.66018349549, 13657.954884229513, 13748.65628359299, 14102.36539355711, 11876.228045177037, 12401.875981240433, 12498.937505312415, 12503.749925001632, 12562.560310389092, 11470.0, 11611.026125558845, 12118.272903625812, 12169.939150304546, 12291.32397718134, 11991.880081199426, 12211.633650990485, 12323.383830808603, 12407.875921240671, 12583.0, 8381.287590554872, 8858.069902660385, 8861.291096712377, 9160.083991600786, 9300.813983720423, 10837.837432438362, 12067.155299128977, 12135.0, 12142.75714485723, 12147.878521214674], "value": 9648.786002444846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.6208854653526, 1518.7949626800382, 1536.7848501209833, 1747.2923465996275, 1916.128161686432, 8263.13237110103, 8427.19941605548, 8434.789130271738, 8449.450785698926, 8453.112423195567, 8153.225443582865, 8336.874946875801, 8344.45761025533, 8347.707830225943, 8353.540555269457, 2156.0621129808505, 2391.0, 2445.9143929962456, 2445.938851528713, 2471.369800700828, 2353.482233908537, 2356.670066190735, 2358.2689366296418, 2362.267697013931, 2363.2673871100014, 2333.0900948630047, 2393.8204634652384, 2394.1381102802975, 2401.687780588521, 2410.144398738449, 2132.8400369972237, 2378.108209421467, 2430.343807172071, 2435.6346548017764, 2438.4147804526965, 2327.290176496185, 2381.4760752634566, 2404.8076153907646, 2407.5666380051584, 2412.19191570824, 8366.414350995432, 8402.991641003058, 8414.915850841475, 8420.989481262226, 8429.83140337196, 5237.816676416307, 5345.118055520835, 5354.27717258169, 5392.4337944515655, 5470.781168753245, 3555.3955827509385, 3591.6408359164134, 3654.3239500692457, 3657.579378371495, 3685.1155722626645, 1163.9873310220162, 1203.6569577670323, 1211.0916812390708, 1235.258844693186, 1264.2856785915928, 12180.695482612917, 12253.509859605605, 12299.938500307531, 12310.692232694164, 12314.938425307906, 4522.954770452319, 4669.0, 4744.715317080917, 4803.735794531264, 4817.662763606514, 10264.0, 10286.125679317192, 10309.329893556895, 10318.071373576415, 10347.017033381817, 4890.339804126468, 5383.703896285742, 5391.272178255886, 5393.7572809223675, 5408.0, 4929.704217746947, 4961.603071754251, 4962.478939711351, 4981.227909674007, 5000.1749711297225, 4109.589041095866, 4138.606832350982, 4149.149424367997, 4186.853460128942, 4193.51774545925, 1292.883640472362, 1304.7194853106628, 1309.3256972659087, 1319.4326439631052, 1337.3513845784787, 962.3985009369144, 1001.0840081325651, 1002.6089824968268, 1011.6964910526813, 1014.802113587858, 6201.534884883652, 6207.037909124003, 6224.284207316216, 6226.6575338355915, 6317.5893566918, 490.872373182975, 490.9140900342426, 492.5370152057055, 496.69701482095894, 500.50700060440437, 4323.221820072355, 4362.956370436318, 4368.170047690928, 4397.582229688174, 4400.317950717644, 10423.635172769069, 10827.296225745458, 10846.18653600983, 10864.728381790519, 10870.782584348268, 10635.787284254271, 10658.73353166177, 10666.200034997406, 10668.733281667865, 10671.0, 7890.329322007694, 8148.144444833444, 8151.429399942066, 8208.179182081743, 8287.41988060842, 5934.910976335272, 5964.135200396005, 6033.637981721111, 6138.846528836815, 6140.723667435065, 9176.403533770284, 9256.49089300095, 9292.535373231176, 9440.10319019692, 9452.291078169303, 9269.212116970133, 9540.0, 9541.379810312308, 9544.809103818023, 9545.952270238604, 6932.0, 7102.289771022856, 7114.964425177841, 7716.189800071136, 7880.369570434237, 7830.491018083806, 7996.56018918965, 8054.516728996279, 8060.395470339865, 8079.394045446497, 7238.710451582088, 7425.071866016748, 7544.358729508053, 7544.886826697494, 7569.848603028019, 7329.450291228072, 7343.522671026366, 7375.889361659472, 7422.294881986203, 7438.404927605887, 7054.753083642253, 7104.644767761694, 7105.147382314155, 7111.395531379891, 7136.429085673238, 4912.606991440604, 5220.425753167225, 5221.2690223368, 5245.160774276097, 5248.685078895126, 5338.439463856239, 5382.4886635769535, 5414.3232095988005, 5448.645838020516, 5525.419830917695], "value": 4583.5597115462215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.02089410826034, 371.2037679178164, 374.07603220046485, 376.689231384108, 378.5041595509882, 6094.817155485332, 6182.567220294582, 6213.161223234862, 6235.095911092889, 6258.780942667007, 6189.102580125879, 6323.5257355698395, 6338.366163383659, 6341.651209183493, 6342.714577843996, 4661.207594708908, 4754.429468463789, 4851.175300198957, 4855.126077306083, 4885.316055752206, 4837.588804951566, 4843.9515604843855, 4854.247591623286, 4868.537488938563, 4869.951300486985, 4812.759362031902, 4818.229083346665, 4820.445648750404, 4820.469748327699, 4822.204336284509, 4821.035792841446, 4837.7339246341435, 4858.295547145644, 4865.0026744517645, 4867.415910090812, 4316.719413238129, 4319.092990472004, 4323.6757243206885, 4325.8485952991805, 4331.328644060176, 5978.521718262528, 5991.910121348184, 6085.574009819315, 6087.05650624154, 6094.9390506094815, 5628.43715628438, 5677.205191273225, 5679.545636349082, 5682.971585142048, 5683.715814209314, 6622.4370928471135, 6633.502487313471, 6646.634435106067, 6649.035889795952, 6651.567648102906, 4712.5051869553545, 4781.497942716, 4797.0645724083815, 4797.56821886032, 4817.927731084036, 6756.729730810762, 6873.20958089816, 6890.655467226618, 6892.758753443605, 6895.379415852597, 4888.315635810989, 4901.240307752304, 4901.58336541398, 4907.631927605444, 4913.975430122827, 4950.579200767903, 4954.182559877652, 4959.851204463872, 4960.057589058068, 4973.428055773632, 5391.272178255886, 5552.194931734876, 5611.0741727615305, 5611.494965453047, 5617.129344951457, 6659.633720145342, 6693.263740988487, 6696.497762667722, 6705.664716764142, 6706.83232919181, 1746.2578404178173, 1774.2814160265114, 1774.3612299572144, 1775.2810111904698, 1776.2450958342652, 1154.2324354304462, 1165.469711281382, 1166.8249762535688, 1190.0717440396504, 1214.0773012510556, 6534.509911756637, 6563.7702680405955, 6563.803085907431, 6567.179102612174, 6582.407583317525, 6871.690773915186, 6884.51808373409, 6890.276520965327, 6896.137982752156, 6915.930840691529, 6465.353464653497, 6466.482681385478, 6469.450096741735, 6471.935280647227, 6478.935210647927, 3676.0, 3692.612275711065, 3693.0398096495105, 3706.369917114097, 3714.9226724250207, 7013.228544860061, 7252.637368131573, 7255.8911616325795, 7266.382357499671, 7270.890936635955, 7103.289671032855, 7134.072570565897, 7143.749968751069, 7146.0, 7148.249433809375, 7224.855502889913, 7231.385332246713, 7236.6019868907715, 7243.311885370987, 7255.746048888369, 6808.148981377328, 6954.269801670752, 6964.930350696428, 6968.198657154492, 6972.442204623517, 6839.076724642308, 6855.725770969305, 6859.485538584726, 6861.862762744618, 6864.347886950732, 7128.0, 7180.9640951797, 7184.461165412719, 7210.747623833141, 7212.42300615939, 4730.361401210793, 4781.832635857728, 4788.377510923558, 4791.664583479123, 4791.712497250176, 6651.767188148391, 6761.0, 6891.897296432547, 6924.6537673117155, 6942.375186233263, 6724.361185687352, 6755.3244675534015, 6759.493038022069, 6760.188777346553, 6761.560498567577, 6711.993201019886, 6720.1599800025, 6722.596644201363, 6727.966360168168, 6731.192256929004, 6590.538662293593, 6616.305287944697, 6623.470122390294, 6627.536072474881, 6629.237637671729, 6476.676166191766, 6714.328567143247, 6751.122354093936, 6786.253512113763, 6800.863982720417, 6577.309382514767, 6611.206655201407, 6611.933880661324, 6613.735450581923, 6619.635920024252], "value": 5096.703513754536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.9957750211248, 891.5720454181989, 906.8821053263076, 916.1891725822647, 940.1632547033138, 14826.77759833602, 15133.092014479125, 17091.059991700466, 17406.477805665818, 17452.47642570725, 15994.120323382209, 16024.198790060509, 16037.35850565979, 16115.113668748214, 16134.83865161348, 742.4876834983844, 890.1899271662788, 903.2728653434, 913.3652111782314, 918.3296193778546, 904.2314033071902, 905.2305540290766, 906.6645341223724, 909.1272378516632, 909.5179554835947, 944.504135328951, 946.820104180207, 947.1286416496839, 947.1570302430807, 947.2895328503622, 780.7228433905967, 939.7603611079165, 940.7271891151523, 956.7416797464646, 957.9185769209626, 698.4796326736563, 700.4746440169872, 701.694762778188, 702.0241863809309, 702.3292755418563, 14410.639734006734, 14448.133112013207, 14469.782953255604, 14492.565223043326, 14507.347169377403, 13931.512397066155, 14341.856581434156, 14514.854851451455, 14706.779398309034, 14760.630984225372, 12063.819042714365, 12185.207961482476, 12224.14430989831, 12225.510979560808, 12455.93772031143, 4002.0, 4020.336644453662, 4066.9389959150635, 4138.6482149017365, 4153.439285696422, 58854.7057264711, 59289.99999999957, 61155.388446115416, 62092.68953655248, 62556.0, 11798.5870494534, 11999.220050696676, 12015.879841201477, 12109.333986630643, 12205.877941220651, 21008.579828403348, 21061.368158955254, 21070.47323816917, 21127.154913803275, 21161.25935592247, 8855.3801233913, 8861.379703420698, 8872.24585910205, 8987.595558199897, 9188.724338269863, 4723.48041715411, 4884.438289596671, 4918.926216106761, 4960.330355402047, 5022.121128802445, 3555.1289933966345, 3627.0750958505414, 3631.4915911772373, 3664.1755604988666, 3667.4132138857653, 671.412514050206, 673.2392396591918, 678.816719485736, 688.5696439725172, 707.5471698113212, 837.3552364679163, 837.9371547133867, 838.0, 840.6689705023191, 860.7891066688576, 9706.514674266258, 10063.245256605784, 10145.391276523433, 10302.0, 10316.329438586468, 762.6872982077322, 778.5678948183812, 786.6499407763605, 794.1105961323354, 820.3191351178508, 4478.731276123443, 4704.5765881070865, 4716.386869706985, 4718.740469274223, 4808.855734327975, 64700.0, 65942.67028664827, 66058.0, 66209.0, 66267.33732662708, 55439.16841247304, 56388.43611563832, 56773.43226567764, 57541.712291439115, 58009.12986305209, 51518.742406287725, 52141.0, 52244.21633675498, 52787.472125278255, 52940.0, 9167.587458564514, 9273.768155796159, 9333.439993600407, 9383.85924211151, 9498.0, 38988.0, 39579.0, 39978.400323995746, 40190.0, 40197.79901100476, 45300.32049519326, 45514.544854552354, 50248.706922177465, 50333.49666503288, 50515.49484505108, 10883.727906802393, 10956.34261944286, 11031.17266205054, 11045.392503412068, 11332.830007550057, 12891.742165156456, 12974.0, 13094.541691040768, 13157.671058223237, 13189.736205276033, 11953.282803031498, 11960.0, 12154.817677734663, 12157.513699451923, 12324.0, 12323.753524929272, 12477.0, 12543.87456125427, 12557.937210314256, 12608.0, 11941.16411851162, 11981.221220620631, 12093.15347925672, 12273.509059637872, 12320.075994300643, 10023.599056037678, 10164.695059148238, 10176.389416635026, 10275.794484110127, 10456.111230545488, 9980.151687106387, 10344.534495947852, 10374.377537347784, 10387.480625968823, 10421.06210441064], "value": 7167.240581408591, "unit": "score"}], "name": "dromaeo_css", "value": 7569.477476327619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 110.0, 109.0, 111.0, 107.0, 109.0, 107.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 132.0, 156.0, 180.0, 127.0, 157.0, 150.0, 127.0, 181.0, 126.0], "value": 149.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 159.0, 159.0, 159.0, 162.0, 159.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 172.0, 76.0, 75.0, 76.0, 75.0, 166.0, 75.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 157.0, 90.0, 91.0, 91.0, 146.0, 91.0, 92.0, 152.0], "value": 109.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 144.0, 146.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0, 78.0, 54.0, 53.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 67.0, 57.0, 58.0, 57.0, 57.0, 58.0, 57.0, 57.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 80.0, 71.0, 74.0, 73.0, 73.0, 73.0, 85.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 117.0, 115.0, 120.0, 113.0, 112.0, 120.0, 130.0, 129.0], "value": 119.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 159.0, 163.0, 166.0, 160.0, 159.0, 168.0, 160.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 77.0, 66.0, 67.0, 67.0, 65.0, 67.0, 67.0, 62.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1517.6999999999998, "alertThreshold": 2.0}]} 21:29:01 INFO - Return code: 0 21:29:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:29:01 ERROR - # TBPL SUCCESS # 21:29:01 INFO - Running post-action listener: _resource_record_post_action 21:29:01 INFO - Running post-run listener: _resource_record_post_run 21:29:02 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 278528; Write bytes: 498331648; Read time: 472; Write time: 589284 21:29:02 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 4096; Write bytes: 248803328; Read time: 168; Write time: 362796 21:29:02 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 274432; Write bytes: 249528320; Read time: 304; Write time: 226488 21:29:02 INFO - Running post-run listener: _upload_blobber_files 21:29:02 INFO - Blob upload gear active. 21:29:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:29:02 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 21:29:02 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'] 21:29:02 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 21:29:02 INFO - (blobuploader) - INFO - Open directory for files ... 21:29:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:29:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:04 INFO - (blobuploader) - INFO - Done attempting. 21:29:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:29:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:04 INFO - (blobuploader) - INFO - Done attempting. 21:29:04 INFO - (blobuploader) - INFO - Iteration through files over. 21:29:04 INFO - Return code: 0 21:29:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:29:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:29:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33a5d766d4ce2733d19268aff19daee5194807e1ac943beed099994f1acdff68c862b10690e059311a1928af5aa552d7f823e8490aaa1775129ad9e15609a323", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc99a6f4651ed742784acd4ffbf7e2cb01a685b35a601daea4636731b7dbd6f822c748fb6550dc9f14d44f77228cdc8e16d3f2006b09a054f54ee4fd1b2df4"} 21:29:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:29:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:29:04 INFO - Contents: 21:29:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33a5d766d4ce2733d19268aff19daee5194807e1ac943beed099994f1acdff68c862b10690e059311a1928af5aa552d7f823e8490aaa1775129ad9e15609a323", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc99a6f4651ed742784acd4ffbf7e2cb01a685b35a601daea4636731b7dbd6f822c748fb6550dc9f14d44f77228cdc8e16d3f2006b09a054f54ee4fd1b2df4"} 21:29:04 INFO - Running post-run listener: copy_logs_to_upload_dir 21:29:04 INFO - Copying logs to upload dir... 21:29:04 INFO - mkdir: /builds/slave/test/build/upload/logs 21:29:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.729268 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-14 21:29:04.480035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 21:29:04.483991) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 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/33a5d766d4ce2733d19268aff19daee5194807e1ac943beed099994f1acdff68c862b10690e059311a1928af5aa552d7f823e8490aaa1775129ad9e15609a323", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc99a6f4651ed742784acd4ffbf7e2cb01a685b35a601daea4636731b7dbd6f822c748fb6550dc9f14d44f77228cdc8e16d3f2006b09a054f54ee4fd1b2df4"} build_url:https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/fqkyTgL-QcGHtwzqgOsCpA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33a5d766d4ce2733d19268aff19daee5194807e1ac943beed099994f1acdff68c862b10690e059311a1928af5aa552d7f823e8490aaa1775129ad9e15609a323", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc99a6f4651ed742784acd4ffbf7e2cb01a685b35a601daea4636731b7dbd6f822c748fb6550dc9f14d44f77228cdc8e16d3f2006b09a054f54ee4fd1b2df4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 21:29:04.509984) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:29:04.510410) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463282939.549528-1753845905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 21:29:04.596116) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 21:29:04.596573) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 21:29:04.596923) ========= ========= Total master_lag: 0.15 =========