builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1465402091.7 results: success (0) buildid: 20160608061731 builduid: bb02556692494efd83a786b8f78d49fd revision: 3a4cd59e97b5100b2b14185a068271531b80c68d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.698435) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.699180) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.699775) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.737532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.737847) ========= 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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-08 09:08:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.1M=0s 2016-06-08 09:08:11 (67.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115510 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.883457) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.883810) ========= 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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021183 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.943695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:11.944104) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a4cd59e97b5100b2b14185a068271531b80c68d --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 3a4cd59e97b5100b2b14185a068271531b80c68d --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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-08 09:08:12,146 truncating revision to first 12 chars 2016-06-08 09:08:12,146 Setting DEBUG logging. 2016-06-08 09:08:12,146 attempt 1/10 2016-06-08 09:08:12,146 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a4cd59e97b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-08 09:08:12,704 unpacking tar archive at: mozilla-aurora-3a4cd59e97b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.901759 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:12.863895) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:12.864220) ========= script_repo_revision: 3a4cd59e97b5100b2b14185a068271531b80c68d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:12.864669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:12.864984) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-08 09:08:12.875828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-08 09:08:12.876142) ========= /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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:08:13 INFO - MultiFileLogger online at 20160608 09:08:13 in /builds/slave/test 09:08:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:08:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:08:13 INFO - {'append_to_log': False, 09:08:13 INFO - 'base_work_dir': '/builds/slave/test', 09:08:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:08:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:08:13 INFO - 'branch': 'Mozilla-Aurora', 09:08:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:08:13 INFO - 'config_files': ('talos/linux_config.py',), 09:08:13 INFO - 'default_actions': ('clobber', 09:08:13 INFO - 'read-buildbot-config', 09:08:13 INFO - 'download-and-extract', 09:08:13 INFO - 'populate-webroot', 09:08:13 INFO - 'create-virtualenv', 09:08:13 INFO - 'install', 09:08:13 INFO - 'run-tests'), 09:08:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:08:13 INFO - 'download_minidump_stackwalk': True, 09:08:13 INFO - 'download_symbols': 'ondemand', 09:08:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:08:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:08:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:08:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:08:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:08:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:08:13 INFO - 'installer_path': 'installer.exe', 09:08:13 INFO - 'log_level': 'info', 09:08:13 INFO - 'log_name': 'talos', 09:08:13 INFO - 'log_to_console': True, 09:08:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:08:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:08:13 INFO - 'opt_config_files': (), 09:08:13 INFO - 'pip_index': False, 09:08:13 INFO - 'sps_profile': False, 09:08:13 INFO - 'sps_profile_interval': 0, 09:08:13 INFO - 'suite': 'dromaeojs', 09:08:13 INFO - 'system_bits': '32', 09:08:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:08:13 INFO - 'title': 'talos-linux64-ix-038', 09:08:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:08:13 INFO - 'use_talos_json': True, 09:08:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:08:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:08:13 INFO - 'work_dir': 'build'} 09:08:13 INFO - ##### 09:08:13 INFO - ##### Running clobber step. 09:08:13 INFO - ##### 09:08:13 INFO - Running pre-action listener: _resource_record_pre_action 09:08:13 INFO - Running main action method: clobber 09:08:13 INFO - rmtree: /builds/slave/test/build 09:08:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:08:13 INFO - Running post-action listener: _resource_record_post_action 09:08:13 INFO - ##### 09:08:13 INFO - ##### Finished clobber step (success) 09:08:13 INFO - ##### 09:08:13 INFO - ##### 09:08:13 INFO - ##### Running read-buildbot-config step. 09:08:13 INFO - ##### 09:08:13 INFO - Running pre-action listener: _resource_record_pre_action 09:08:13 INFO - Running main action method: read_buildbot_config 09:08:13 INFO - Using buildbot properties: 09:08:13 INFO - { 09:08:13 INFO - "project": "", 09:08:13 INFO - "product": "firefox", 09:08:13 INFO - "script_repo_revision": "production", 09:08:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:08:13 INFO - "repository": "", 09:08:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:08:13 INFO - "buildid": "20160608061731", 09:08:13 INFO - "pgo_build": "True", 09:08:13 INFO - "basedir": "/builds/slave/test", 09:08:13 INFO - "buildnumber": 293, 09:08:13 INFO - "slavename": "talos-linux64-ix-038", 09:08:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:08:13 INFO - "platform": "ubuntu64_hw", 09:08:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:08:13 INFO - "branch": "mozilla-aurora", 09:08:13 INFO - "repo_path": "releases/mozilla-aurora", 09:08:13 INFO - "revision": "3a4cd59e97b5100b2b14185a068271531b80c68d", 09:08:13 INFO - "stage_platform": "linux64-pgo", 09:08:13 INFO - "builduid": "bb02556692494efd83a786b8f78d49fd", 09:08:13 INFO - "slavebuilddir": "test" 09:08:13 INFO - } 09:08:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:08:13 INFO - Running post-action listener: _resource_record_post_action 09:08:13 INFO - ##### 09:08:13 INFO - ##### Finished read-buildbot-config step (success) 09:08:13 INFO - ##### 09:08:13 INFO - ##### 09:08:13 INFO - ##### Running download-and-extract step. 09:08:13 INFO - ##### 09:08:13 INFO - Running pre-action listener: _resource_record_pre_action 09:08:13 INFO - Running main action method: download_and_extract 09:08:13 INFO - mkdir: /builds/slave/test/build/tests 09:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:13 INFO - https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:08:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:08:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:08:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:08:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:08:32 INFO - Downloaded 1361 bytes. 09:08:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:08:32 INFO - Using the following test package requirements: 09:08:32 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:08:32 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:08:32 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:08:32 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'jsshell-linux-x86_64.zip'], 09:08:32 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:08:32 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:08:32 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:08:32 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:08:32 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:08:32 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:32 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:08:32 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:32 INFO - https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:33 INFO - Downloaded 24473779 bytes. 09:08:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:08:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:08:34 INFO - Return code: 0 09:08:34 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:34 INFO - https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:36 INFO - Downloaded 24473779 bytes. 09:08:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:08:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:08:38 INFO - Return code: 0 09: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')]} 09:08:38 INFO - https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:08:40 INFO - Downloaded 10833369 bytes. 09:08:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:08:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:08:40 INFO - Return code: 0 09:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:40 INFO - https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:08:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:08:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:08:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:08:47 INFO - Downloaded 63577559 bytes. 09:08:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:08:47 INFO - mkdir: /builds/slave/test/properties 09:08:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:08:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:08:47 INFO - Contents: 09:08:47 INFO - build_url:https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:08:48 INFO - Running post-action listener: _resource_record_post_action 09:08:48 INFO - Running post-action listener: set_extra_try_arguments 09:08:48 INFO - ##### 09:08:48 INFO - ##### Finished download-and-extract step (success) 09:08:48 INFO - ##### 09:08:48 INFO - ##### 09:08:48 INFO - ##### Running populate-webroot step. 09:08:48 INFO - ##### 09:08:48 INFO - Running pre-action listener: _resource_record_pre_action 09:08:48 INFO - Running main action method: populate_webroot 09:08:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:08:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:08:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:08:48 INFO - '--tppagecycles', 09:08:48 INFO - '7'], 09:08:48 INFO - 'tests': ['tsvgm']}}, 09:08:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:08:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:08:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:08:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:08:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['damp', 'tps']}, 09:08:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'tests': ['damp', 'tps']}, 09:08:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['dromaeo_dom']}, 09:08:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:08:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['a11yr', 09:08:48 INFO - 'ts_paint', 09:08:48 INFO - 'tpaint', 09:08:48 INFO - 'sessionrestore', 09:08:48 INFO - 'sessionrestore_no_auto_restore', 09:08:48 INFO - 'tabpaint']}, 09:08:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['tsvgx', 09:08:48 INFO - 'tsvgr_opacity', 09:08:48 INFO - 'tart', 09:08:48 INFO - 'tscrollx', 09:08:48 INFO - 'cart']}, 09:08:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:08:48 INFO - 'tsvgr_opacity', 09:08:48 INFO - 'tart', 09:08:48 INFO - 'tscrollx', 09:08:48 INFO - 'cart']}, 09:08:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'talos_options': ['--disable-e10s'], 09:08:48 INFO - 'tests': ['tp5o']}, 09:08:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'tests': ['tp5o']}, 09:08:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'talos_options': ['--disable-e10s', 09:08:48 INFO - '--xperf_path', 09:08:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:48 INFO - 'tests': ['tp5n']}, 09:08:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:48 INFO - 'talos_options': ['--xperf_path', 09:08:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:48 INFO - 'tests': ['tp5n']}}, 09:08:48 INFO - 'talos.zip': {'path': '', 09:08:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:08:48 INFO - Running post-action listener: _resource_record_post_action 09:08:48 INFO - ##### 09:08:48 INFO - ##### Finished populate-webroot step (success) 09:08:48 INFO - ##### 09:08:48 INFO - ##### 09:08:48 INFO - ##### Running create-virtualenv step. 09:08:48 INFO - ##### 09:08:48 INFO - Running pre-action listener: _resource_record_pre_action 09:08:48 INFO - Running main action method: create_virtualenv 09:08:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:08:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:08:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:08:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:08:48 INFO - Using real prefix '/usr' 09:08:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:08:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:51 INFO - Installing pip.................done. 09:08:51 INFO - Return code: 0 09:08:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:51 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:08:51 INFO - Using env: {'DISPLAY': ':0', 09:08:51 INFO - 'HOME': '/home/cltbld', 09:08:51 INFO - 'LANG': 'en_US.UTF-8', 09:08:51 INFO - 'LANGUAGE': 'en_US:en', 09:08:51 INFO - 'LOGNAME': 'cltbld', 09:08:51 INFO - 'MAIL': '/var/mail/cltbld', 09:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:51 INFO - 'MOZ_NO_REMOTE': '1', 09:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:51 INFO - 'NO_EM_RESTART': '1', 09:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:51 INFO - 'PWD': '/builds/slave/test', 09:08:51 INFO - 'SHELL': '/bin/bash', 09:08:51 INFO - 'SHLVL': '1', 09:08:51 INFO - 'TERM': 'linux', 09:08:51 INFO - 'TMOUT': '86400', 09:08:51 INFO - 'USER': 'cltbld', 09:08:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:51 INFO - '_': '/tools/buildbot/bin/python'} 09:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:08:51 INFO - Cleaning up... 09:08:51 INFO - Return code: 0 09:08:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:08:51 INFO - Using env: {'DISPLAY': ':0', 09:08:51 INFO - 'HOME': '/home/cltbld', 09:08:51 INFO - 'LANG': 'en_US.UTF-8', 09:08:51 INFO - 'LANGUAGE': 'en_US:en', 09:08:51 INFO - 'LOGNAME': 'cltbld', 09:08:51 INFO - 'MAIL': '/var/mail/cltbld', 09:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:51 INFO - 'MOZ_NO_REMOTE': '1', 09:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:51 INFO - 'NO_EM_RESTART': '1', 09:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:51 INFO - 'PWD': '/builds/slave/test', 09:08:51 INFO - 'SHELL': '/bin/bash', 09:08:51 INFO - 'SHLVL': '1', 09:08:51 INFO - 'TERM': 'linux', 09:08:51 INFO - 'TMOUT': '86400', 09:08:51 INFO - 'USER': 'cltbld', 09:08:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:51 INFO - '_': '/tools/buildbot/bin/python'} 09:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:51 INFO - Downloading/unpacking psutil>=3.1.1 09:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:53 INFO - Installing collected packages: psutil 09:08:53 INFO - Running setup.py install for psutil 09:08:53 INFO - building 'psutil._psutil_linux' extension 09: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 09: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 09:08:53 INFO - building 'psutil._psutil_posix' extension 09: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 09:08:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:08:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:54 INFO - Successfully installed psutil 09:08:54 INFO - Cleaning up... 09:08:54 INFO - Return code: 0 09:08:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:08:54 INFO - Using env: {'DISPLAY': ':0', 09:08:54 INFO - 'HOME': '/home/cltbld', 09:08:54 INFO - 'LANG': 'en_US.UTF-8', 09:08:54 INFO - 'LANGUAGE': 'en_US:en', 09:08:54 INFO - 'LOGNAME': 'cltbld', 09:08:54 INFO - 'MAIL': '/var/mail/cltbld', 09:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:54 INFO - 'MOZ_NO_REMOTE': '1', 09:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:54 INFO - 'NO_EM_RESTART': '1', 09:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:54 INFO - 'PWD': '/builds/slave/test', 09:08:54 INFO - 'SHELL': '/bin/bash', 09:08:54 INFO - 'SHLVL': '1', 09:08:54 INFO - 'TERM': 'linux', 09:08:54 INFO - 'TMOUT': '86400', 09:08:54 INFO - 'USER': 'cltbld', 09:08:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:54 INFO - '_': '/tools/buildbot/bin/python'} 09:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:08:56 INFO - Installing collected packages: mozsystemmonitor 09:08:56 INFO - Running setup.py install for mozsystemmonitor 09:08:56 INFO - Successfully installed mozsystemmonitor 09:08:56 INFO - Cleaning up... 09:08:56 INFO - Return code: 0 09:08:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:08:56 INFO - Using env: {'DISPLAY': ':0', 09:08:56 INFO - 'HOME': '/home/cltbld', 09:08:56 INFO - 'LANG': 'en_US.UTF-8', 09:08:56 INFO - 'LANGUAGE': 'en_US:en', 09:08:56 INFO - 'LOGNAME': 'cltbld', 09:08:56 INFO - 'MAIL': '/var/mail/cltbld', 09:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:56 INFO - 'MOZ_NO_REMOTE': '1', 09:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:56 INFO - 'NO_EM_RESTART': '1', 09:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:56 INFO - 'PWD': '/builds/slave/test', 09:08:56 INFO - 'SHELL': '/bin/bash', 09:08:56 INFO - 'SHLVL': '1', 09:08:56 INFO - 'TERM': 'linux', 09:08:56 INFO - 'TMOUT': '86400', 09:08:56 INFO - 'USER': 'cltbld', 09:08:56 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:56 INFO - '_': '/tools/buildbot/bin/python'} 09:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09: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 09: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 09: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 09: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 09: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 09: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 09:08:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:58 INFO - warning: no files found matching '*.txt' 09:08:58 INFO - no previously-included directories found matching 'build' 09:08:58 INFO - no previously-included directories found matching 'dist' 09:08:58 INFO - no previously-included directories found matching '.git*' 09:08:58 INFO - Installing collected packages: jsonschema, functools32 09:08:58 INFO - Running setup.py install for functools32 09:08:59 INFO - warning: no files found matching '*.txt' 09:08:59 INFO - no previously-included directories found matching 'build' 09:08:59 INFO - no previously-included directories found matching 'dist' 09:08:59 INFO - no previously-included directories found matching '.git*' 09:08:59 INFO - Successfully installed jsonschema functools32 09:08:59 INFO - Cleaning up... 09:08:59 INFO - Return code: 0 09:08:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:59 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:08:59 INFO - Using env: {'DISPLAY': ':0', 09:08:59 INFO - 'HOME': '/home/cltbld', 09:08:59 INFO - 'LANG': 'en_US.UTF-8', 09:08:59 INFO - 'LANGUAGE': 'en_US:en', 09:08:59 INFO - 'LOGNAME': 'cltbld', 09:08:59 INFO - 'MAIL': '/var/mail/cltbld', 09:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:59 INFO - 'MOZ_NO_REMOTE': '1', 09:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:59 INFO - 'NO_EM_RESTART': '1', 09:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:59 INFO - 'PWD': '/builds/slave/test', 09:08:59 INFO - 'SHELL': '/bin/bash', 09:08:59 INFO - 'SHLVL': '1', 09:08:59 INFO - 'TERM': 'linux', 09:08:59 INFO - 'TMOUT': '86400', 09:08:59 INFO - 'USER': 'cltbld', 09:08:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:59 INFO - '_': '/tools/buildbot/bin/python'} 09:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:01 INFO - Downloading docopt-0.6.1.tar.gz 09:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:02 INFO - Running setup.py install for blobuploader 09:09:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:02 INFO - Running setup.py install for requests 09:09:02 INFO - Running setup.py install for docopt 09:09:02 INFO - Successfully installed blobuploader requests docopt 09:09:02 INFO - Cleaning up... 09:09:02 INFO - Return code: 0 09:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:02 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:02 INFO - Using env: {'DISPLAY': ':0', 09:09:02 INFO - 'HOME': '/home/cltbld', 09:09:02 INFO - 'LANG': 'en_US.UTF-8', 09:09:02 INFO - 'LANGUAGE': 'en_US:en', 09:09:02 INFO - 'LOGNAME': 'cltbld', 09:09:02 INFO - 'MAIL': '/var/mail/cltbld', 09:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:02 INFO - 'MOZ_NO_REMOTE': '1', 09:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:02 INFO - 'NO_EM_RESTART': '1', 09:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:02 INFO - 'PWD': '/builds/slave/test', 09:09:02 INFO - 'SHELL': '/bin/bash', 09:09:02 INFO - 'SHLVL': '1', 09:09:02 INFO - 'TERM': 'linux', 09:09:02 INFO - 'TMOUT': '86400', 09:09:02 INFO - 'USER': 'cltbld', 09:09:02 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:02 INFO - '_': '/tools/buildbot/bin/python'} 09:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:02 INFO - Running setup.py (path:/tmp/pip-Tnrxu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:02 INFO - Running setup.py (path:/tmp/pip-cDVVQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:03 INFO - Running setup.py (path:/tmp/pip-knrDqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:03 INFO - Running setup.py (path:/tmp/pip-Zfe9Uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:03 INFO - Running setup.py (path:/tmp/pip-Q1BNuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:03 INFO - Running setup.py (path:/tmp/pip-BIths8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:03 INFO - Running setup.py (path:/tmp/pip-6FoYOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:03 INFO - Running setup.py (path:/tmp/pip-GdSLgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:03 INFO - Running setup.py (path:/tmp/pip-A16TAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:03 INFO - Running setup.py (path:/tmp/pip-lQx3vA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:03 INFO - Running setup.py (path:/tmp/pip-I1xrJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:03 INFO - Running setup.py (path:/tmp/pip-1mdlOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:03 INFO - Running setup.py (path:/tmp/pip-VGzx31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:03 INFO - Running setup.py (path:/tmp/pip-62lzZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:03 INFO - Running setup.py (path:/tmp/pip-Et6bGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:03 INFO - Running setup.py (path:/tmp/pip-w6mvNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:04 INFO - Running setup.py (path:/tmp/pip-BChLMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:04 INFO - Running setup.py install for manifestparser 09:09:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Running setup.py install for mozcrash 09:09:04 INFO - Running setup.py install for mozdebug 09:09:04 INFO - Running setup.py install for mozdevice 09:09:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Running setup.py install for mozfile 09:09:04 INFO - Running setup.py install for mozhttpd 09:09:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Running setup.py install for mozinfo 09:09:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Running setup.py install for mozInstall 09:09:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:04 INFO - Running setup.py install for mozleak 09:09:04 INFO - Running setup.py install for mozlog 09:09:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Running setup.py install for moznetwork 09:09:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Running setup.py install for mozprocess 09:09:05 INFO - Running setup.py install for mozprofile 09:09:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Running setup.py install for mozrunner 09:09:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Running setup.py install for mozscreenshot 09:09:05 INFO - Running setup.py install for moztest 09:09:05 INFO - Running setup.py install for mozversion 09:09:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:09:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:09:05 INFO - Cleaning up... 09:09:05 INFO - Return code: 0 09:09:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:05 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:05 INFO - Using env: {'DISPLAY': ':0', 09:09:05 INFO - 'HOME': '/home/cltbld', 09:09:05 INFO - 'LANG': 'en_US.UTF-8', 09:09:05 INFO - 'LANGUAGE': 'en_US:en', 09:09:05 INFO - 'LOGNAME': 'cltbld', 09:09:05 INFO - 'MAIL': '/var/mail/cltbld', 09:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:05 INFO - 'MOZ_NO_REMOTE': '1', 09:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:05 INFO - 'NO_EM_RESTART': '1', 09:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:05 INFO - 'PWD': '/builds/slave/test', 09:09:05 INFO - 'SHELL': '/bin/bash', 09:09:05 INFO - 'SHLVL': '1', 09:09:05 INFO - 'TERM': 'linux', 09:09:05 INFO - 'TMOUT': '86400', 09:09:05 INFO - 'USER': 'cltbld', 09:09:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:05 INFO - '_': '/tools/buildbot/bin/python'} 09:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:06 INFO - Running setup.py (path:/tmp/pip-OFqWlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:06 INFO - Running setup.py (path:/tmp/pip-pfaMHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:06 INFO - Running setup.py (path:/tmp/pip-JCJioY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:06 INFO - Running setup.py (path:/tmp/pip-dbq6KT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:06 INFO - Running setup.py (path:/tmp/pip-OeJPdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:06 INFO - Running setup.py (path:/tmp/pip-yrWMqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:06 INFO - Running setup.py (path:/tmp/pip-gRcrfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:06 INFO - Running setup.py (path:/tmp/pip-xkJpqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:06 INFO - Running setup.py (path:/tmp/pip-FMD1d8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:06 INFO - Running setup.py (path:/tmp/pip-4EKZPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:06 INFO - Running setup.py (path:/tmp/pip-l9zvuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:06 INFO - Running setup.py (path:/tmp/pip-4lehY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:06 INFO - Running setup.py (path:/tmp/pip-5l9Gtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:07 INFO - Running setup.py (path:/tmp/pip-Hdv6Fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:07 INFO - Running setup.py (path:/tmp/pip-NyXuKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:07 INFO - Running setup.py (path:/tmp/pip-0IOT0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:07 INFO - Running setup.py (path:/tmp/pip-iC2gfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:09 INFO - Downloading blessings-1.6.tar.gz 09:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:09:09 INFO - Installing collected packages: blessings 09:09:09 INFO - Running setup.py install for blessings 09:09:09 INFO - Successfully installed blessings 09:09:09 INFO - Cleaning up... 09:09:09 INFO - Return code: 0 09:09:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:09 INFO - Reading from file tmpfile_stdout 09:09:09 INFO - Current package versions: 09:09:09 INFO - argparse == 1.2.1 09:09:09 INFO - blessings == 1.6 09:09:09 INFO - blobuploader == 1.2.4 09:09:09 INFO - docopt == 0.6.1 09:09:09 INFO - functools32 == 3.2.3-2 09:09:09 INFO - jsonschema == 2.5.1 09:09:09 INFO - manifestparser == 1.1 09:09:09 INFO - mozInstall == 1.12 09:09:09 INFO - mozcrash == 0.17 09:09:09 INFO - mozdebug == 0.1 09:09:09 INFO - mozdevice == 0.48 09:09:09 INFO - mozfile == 1.2 09:09:09 INFO - mozhttpd == 0.7 09:09:09 INFO - mozinfo == 0.9 09:09:09 INFO - mozleak == 0.1 09:09:09 INFO - mozlog == 3.2 09:09:09 INFO - moznetwork == 0.27 09:09:09 INFO - mozprocess == 0.22 09:09:09 INFO - mozprofile == 0.28 09:09:09 INFO - mozrunner == 6.11 09:09:09 INFO - mozscreenshot == 0.1 09:09:09 INFO - mozsystemmonitor == 0.3 09:09:09 INFO - moztest == 0.7 09:09:09 INFO - mozversion == 1.4 09:09:09 INFO - psutil == 3.1.1 09:09:09 INFO - requests == 1.2.3 09:09:09 INFO - wsgiref == 0.1.2 09:09:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:09 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:09 INFO - Using env: {'DISPLAY': ':0', 09:09:09 INFO - 'HOME': '/home/cltbld', 09:09:09 INFO - 'LANG': 'en_US.UTF-8', 09:09:09 INFO - 'LANGUAGE': 'en_US:en', 09:09:09 INFO - 'LOGNAME': 'cltbld', 09:09:09 INFO - 'MAIL': '/var/mail/cltbld', 09:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:09 INFO - 'MOZ_NO_REMOTE': '1', 09:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:09 INFO - 'NO_EM_RESTART': '1', 09:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:09 INFO - 'PWD': '/builds/slave/test', 09:09:09 INFO - 'SHELL': '/bin/bash', 09:09:09 INFO - 'SHLVL': '1', 09:09:09 INFO - 'TERM': 'linux', 09:09:09 INFO - 'TMOUT': '86400', 09:09:09 INFO - 'USER': 'cltbld', 09:09:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:09 INFO - '_': '/tools/buildbot/bin/python'} 09:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:09 INFO - Cleaning up... 09:09:09 INFO - Return code: 0 09:09:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09: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')]} 09:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09: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', 'jsonschema'] in /builds/slave/test/build 09: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 jsonschema 09:09:09 INFO - Using env: {'DISPLAY': ':0', 09:09:09 INFO - 'HOME': '/home/cltbld', 09:09:09 INFO - 'LANG': 'en_US.UTF-8', 09:09:09 INFO - 'LANGUAGE': 'en_US:en', 09:09:09 INFO - 'LOGNAME': 'cltbld', 09:09:09 INFO - 'MAIL': '/var/mail/cltbld', 09:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:09 INFO - 'MOZ_NO_REMOTE': '1', 09:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:09 INFO - 'NO_EM_RESTART': '1', 09:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:09 INFO - 'PWD': '/builds/slave/test', 09:09:09 INFO - 'SHELL': '/bin/bash', 09:09:09 INFO - 'SHLVL': '1', 09:09:09 INFO - 'TERM': 'linux', 09:09:09 INFO - 'TMOUT': '86400', 09:09:09 INFO - 'USER': 'cltbld', 09:09:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:09 INFO - '_': '/tools/buildbot/bin/python'} 09:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:09:10 INFO - Cleaning up... 09:09:10 INFO - Return code: 0 09:09:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:10 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 0x7fccc3189f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1496350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, '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': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:09:10 INFO - Using env: {'DISPLAY': ':0', 09:09:10 INFO - 'HOME': '/home/cltbld', 09:09:10 INFO - 'LANG': 'en_US.UTF-8', 09:09:10 INFO - 'LANGUAGE': 'en_US:en', 09:09:10 INFO - 'LOGNAME': 'cltbld', 09:09:10 INFO - 'MAIL': '/var/mail/cltbld', 09:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:10 INFO - 'MOZ_NO_REMOTE': '1', 09:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:10 INFO - 'NO_EM_RESTART': '1', 09:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:10 INFO - 'PWD': '/builds/slave/test', 09:09:10 INFO - 'SHELL': '/bin/bash', 09:09:10 INFO - 'SHLVL': '1', 09:09:10 INFO - 'TERM': 'linux', 09:09:10 INFO - 'TMOUT': '86400', 09:09:10 INFO - 'USER': 'cltbld', 09:09:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:10 INFO - '_': '/tools/buildbot/bin/python'} 09:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:10 INFO - Downloading/unpacking flake8 09:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:09:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:09:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:09:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:09:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:09:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:09:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:09:12 INFO - no previously-included directories found matching 'docs/_build' 09:09:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:09:13 INFO - Running setup.py install for pep8 09:09:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:09:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:09:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:09:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:09:13 INFO - no previously-included directories found matching 'docs/_build' 09:09:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:09:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:09:14 INFO - Cleaning up... 09:09:15 INFO - Return code: 0 09:09:15 INFO - Running post-action listener: _resource_record_post_action 09:09:15 INFO - Running post-action listener: _start_resource_monitoring 09:09:15 INFO - Starting resource monitoring. 09:09:15 INFO - ##### 09:09:15 INFO - ##### Finished create-virtualenv step (success) 09:09:15 INFO - ##### 09:09:15 INFO - ##### 09:09:15 INFO - ##### Running install step. 09:09:15 INFO - ##### 09:09:15 INFO - Running pre-action listener: _resource_record_pre_action 09:09:15 INFO - Running main action method: install 09:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:15 INFO - Reading from file tmpfile_stdout 09:09:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:09:15 INFO - Reading from file tmpfile_stdout 09:09:15 INFO - Output received: 09:09:15 INFO - Usage: mozinstall [options] installer 09:09:15 INFO - Options: 09:09:15 INFO - -h, --help show this help message and exit 09:09:15 INFO - -d DEST, --destination=DEST 09:09:15 INFO - Directory to install application into. [default: 09:09:15 INFO - "/builds/slave/test"] 09:09:15 INFO - --app=APP Application being installed. [default: firefox] 09:09:15 INFO - mkdir: /builds/slave/test/build/application 09:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:09:31 INFO - Reading from file tmpfile_stdout 09:09:31 INFO - Output received: 09:09:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:09:31 INFO - Running post-action listener: _resource_record_post_action 09:09:31 INFO - ##### 09:09:31 INFO - ##### Finished install step (success) 09:09:31 INFO - ##### 09:09:31 INFO - ##### 09:09:31 INFO - ##### Running run-tests step. 09:09:31 INFO - ##### 09:09:31 INFO - Running pre-action listener: _resource_record_pre_action 09:09:31 INFO - Running main action method: run_tests 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:31 INFO - Python 2.7.3 09:09:31 INFO - Return code: 0 09:09:31 INFO - grabbing minidump binary from tooltool 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15251c0>, '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 0x15260c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1526550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:09:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:09:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:09:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:31 INFO - Return code: 0 09:09:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:09:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:09:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:09:32 INFO - Return code: 0 09:09:32 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/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:09:32 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/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:09:32 INFO - Using env: {'DISPLAY': ':0', 09:09:32 INFO - 'HOME': '/home/cltbld', 09:09:32 INFO - 'LANG': 'en_US.UTF-8', 09:09:32 INFO - 'LANGUAGE': 'en_US:en', 09:09:32 INFO - 'LOGNAME': 'cltbld', 09:09:32 INFO - 'MAIL': '/var/mail/cltbld', 09:09:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:32 INFO - 'MOZ_NO_REMOTE': '1', 09:09:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:32 INFO - 'NO_EM_RESTART': '1', 09:09:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:32 INFO - 'PWD': '/builds/slave/test', 09:09:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:32 INFO - 'SHELL': '/bin/bash', 09:09:32 INFO - 'SHLVL': '1', 09:09:32 INFO - 'TERM': 'linux', 09:09:32 INFO - 'TMOUT': '86400', 09:09:32 INFO - 'USER': 'cltbld', 09:09:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631', 09:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:32 INFO - '_': '/tools/buildbot/bin/python'} 09:09:32 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/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:09:32 INFO - mozversion application_buildid: 20160608061731 09:09:32 INFO - mozversion application_changeset: 3a4cd59e97b5100b2b14185a068271531b80c68d 09:09:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:09:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:32 INFO - mozversion application_name: Firefox 09:09:32 INFO - mozversion application_remotingname: firefox-dev 09:09:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:09:32 INFO - mozversion application_vendor: Mozilla 09:09:32 INFO - mozversion application_version: 49.0a2 09:09:32 INFO - mozversion platform_buildid: 20160608061731 09:09:32 INFO - mozversion platform_changeset: 3a4cd59e97b5100b2b14185a068271531b80c68d 09:09:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:09:32 INFO - mozversion platform_version: 49.0a2 09:09:32 INFO - using testdate: 1465402172 09:09:32 INFO - actual date: 1465402172 09:09:32 INFO - starting webserver on 'localhost:33457' 09:09:32 INFO - SUITE-START | Running 2 tests 09:09:32 INFO - TEST-START | dromaeo_css 09:09:32 INFO - operating with platform_type : linux_ 09:09:32 INFO - Initialising browser for dromaeo_css test... 09:09:32 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOuZBKV/profile http://localhost:33457/getInfo.html) 09:09:37 INFO - PROCESS | 1911 | __metrics Screen width/height:1600/1200 09:09:37 INFO - PROCESS | 1911 | colorDepth:24 09:09:37 INFO - PROCESS | 1911 | Browser inner width/height: 1024/697 09:09:37 INFO - PROCESS | 1911 | __metrics 09:09:38 ERROR - PROCESS | 1911 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:09:38 INFO - PROCESS | 1911 | console.error: PushService: 09:09:38 INFO - PROCESS | 1911 | stateChangeProcessEnqueue: Error transitioning state 09:09:38 INFO - PROCESS | 1911 | UnknownError 09:09:39 INFO - TEST-INFO | 1911: exit 0 09:09:39 INFO - Browser initialized. 09:09:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:39 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOuZBKV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:50 INFO - PROCESS | 1998 | RSS: Main: 149401600 09:09:50 INFO - PROCESS | 1998 | 09:10:42 INFO - PROCESS | 1998 | 1465402242517 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:12:50 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33457/tests/dromaeo/cssquery-ext.html) 09:12:51 INFO - PROCESS | 1998 | RSS: Main: 257413120 09:12:51 INFO - PROCESS | 1998 | 09:15:54 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-ext.html (next: http://localhost:33457/tests/dromaeo/cssquery-jquery.html) 09:15:54 INFO - PROCESS | 1998 | RSS: Main: 224530432 09:15:54 INFO - PROCESS | 1998 | 09:18:56 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33457/tests/dromaeo/cssquery-mootools.html) 09:18:57 INFO - PROCESS | 1998 | RSS: Main: 218492928 09:18:57 INFO - PROCESS | 1998 | 09:21:59 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33457/tests/dromaeo/cssquery-prototype.html) 09:21:59 INFO - PROCESS | 1998 | RSS: Main: 220123136 09:21:59 INFO - PROCESS | 1998 | 09:25:02 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33457/tests/dromaeo/cssquery-yui.html) 09:25:03 INFO - PROCESS | 1998 | RSS: Main: 214814720 09:25:03 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:33457/tests/dromaeo/cssquery-yui.html (next: http://localhost:33457/tests/dromaeo/cssquery-dojo.html) 09:28:05 INFO - PROCESS | 1998 | RSS: Main: 255913984 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | __start_tp_report 09:28:05 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 09:28:05 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 09:28:05 INFO - PROCESS | 1998 | |i|pagename|runs| 09:28:05 INFO - PROCESS | 1998 | |0;dojo.html;3457.6715212054855;3488.7034602058825;3489.546358973334;3519.401701710709;3532.2582257725876;5753.769849206032;5853.09277062055;5875.76496940123;5879.353271140171;5882.794102206423;5665.575081868866;5728.513076388512;5741.311042674884;5744.885102297952;5746.798862039829;5020.648554601161;5132.948670513284;5157.948420515784;5230.895382092356;5244.029854476934;5063.518965698253;5173.5085166909275;5203.271541984125;5209.296744939442;5213.9478605214035;5123.308353372303;5142;5200.6879587224885;5233.607479439038;5256.474352564751;5297.8940421191555;5313.1233346497975;5319.521243088102;5333.493318134771;5360.91958620621;5032.748362581893;5068.746562671889;5082;5178.275041494194;5189.455107213764;5560.054790685593;5565.666060036411;5569.220309156722;5579.69311687857;5602.187682785973;6172.722227499773;6178.969105154445;6184.56708030438;6185.783497577609;6193.0710393440895;6141.915626108633;6175.320714721377;6177.474914632262;6237.407446292595;6279.780207692709;5845.298564172326;5887.646741195542;5899.085641725539;5900.911486327752;5901.527877769769;6057.925495964724;6406.071119687713;6406.615603063831;6407.359264073555;6426.389492998158;5857.179994800731;5927.881442371128;5968.46283834457;5975.462208401264;5998.670073146019;6046.425589568984;6046.848828779316;6049.304330002018;6076.848078798065;6082.939170608237;5989.071693887455;6004.309504407049;6015.969920150371;6018.428249316308;6110.938890611037;6156.322804491502;6271.529635277372;6275.874482510324;6277.623342599459;6280.623162610258;4678.672492925462;4716.6698331116495;4717.787699553528;4731.69243999139;4742.739149346819;4097.467329247238;4133.834646614102;4168.916621667549;4197.286461301586;4221.071364299849;5943.197668314809;5957.523398128139;5960.463558279689;5961.5230781537375;5974.61165024272;6239.469645080128;6287.717052732638;6291.622502649856;6292.05619157121;6302.873942521215;5624.0439125348785;5816.82549523515;5839.036558967723;5849.356570777211;5945.970270148621;5227.660202086852;5237.083510385667;5238.895222095613;5241;5258.526732594085;6396.584222025554;6443.7744678936015;6452.548321617442;6456.063870738717;6459.612423254526;6023.096535519707;6127.785527506516;6143.266246520256;6154.569180157346;6245;6418.19772528434;6424.678766061772;6435.388638079375;6440.549161558646;6450.322716114928;6089.786857459968;6093.847653808513;6101.725422356093;6102.084687296941;6112.083187521907;5968.194293770459;6131.601445906002;6132.906245166512;6157.907631385622;6241.251049874044;6088.012018824206;6092.847678808065;6101;6101.694915254308;6109.602875813058;5813.738381772746;6048;6063.636181829104;6064.332923378513;6083.117947897618;5913.05391137416;5947.881042379215;5955.047192449188;5980.491658209101;5987.550933679904;6135.1717518136265;6158.076288556573;6161.784337548164;6162.445379915829;6163.537734669828;5692.174634678031;5809.912851307318;5987.7604895805425;6006;6026.879462410815 09:28:05 INFO - PROCESS | 1998 | |1;ext.html;614.1616693213765;649.2663290481755;651.5113664751436;668.1147479589544;687.7352219395531;32833.67166328339;46087.30869036963;47679.76160119203;47855;47887.76056119723;48641;49246.50753492464;49252;49266.75366623171;49375;8317.83364332713;8981;9199.310051746112;9202.355835091546;10827.458627068654;10536.736581585445;10959.397233152173;10962.123030157569;10968.506417211245;10973.890261097407;10978.615748448807;11060.22578419511;11074.833877491845;11075;11087;8601.13988601138;8836.602352894135;10853.837192442119;10966.342019478778;11228.663140105728;10657.307275027099;11016.449177541172;11016.779664406668;11043.061339786127;11046.171537134667;45047.54952450466;45633.54366456326;45729.31406028879;45781.77109114466;45858;3641.745077844552;3803.7717736935874;3847.7306588538813;3990.5610382857863;4126.3397856343145;34442.138946526284;34998.47502287468;35291.4706279406;35413.822930885435;35443.822780886185;4088.5894167747592;4205.894852628679;4229.38673892287;4237.13138806547;4248.214080395137;389858;393686;394673;396107.0194649037;397456;38400.231995359936;66896;67207.32792672109;67785;67806.66096669485;39769.40345894814;41878.581214188074;42003;42013.57986420158;42037.36943945843;15633.14017729036;15679.68640627181;15737.68524629501;15738;15741.606459838596;11131.276467029616;13248.40382182804;13522.391492382867;13539.39072741729;13766.518171863936;9206.217471514996;9214.861777073349;9259.166674999282;9279.953600232091;9335.486548239913;3551.271989242199;3562.946555801665;3565.3938830398893;3577.606463289036;3608.332458495187;1061.8354155105972;1079.983800242997;1100.350793032115;1114.509615769059;1130.4404319861558;32737.508937365958;34377.656223437945;34550.30899382049;34794.304113918086;35210.295794084486;1148.758760660254;1178.911581631381;1211.3216598704753;1212.7331986962854;1221.8464559510496;17909.01692139458;18008.941877794336;18070.26550172187;18184.272629094954;18287.177077031567;562310;577672;579493;581620;583550;63761.36238637647;65458;65643.67178164079;65697.34302656913;65744;1376.2980879751199;1463.5097242423908;1486.6506371002806;1508.924553772307;1512.37992423106;31882.68117318797;35362;35734.28531429305;35982.64017359793;36116;47080;47464;47501.76249118732;47503.04993900172;47603.28595071007;445107;445592;449831;450349;451221;27368.863155684096;30215.395692085596;31352.05943821689;31373.215669608442;31451.84274078615;50751.492485074676;51522;52752.73623632011;53307.20039199493;57257.71371143118;43183.13633727371;44323.77838110789;44853.102937940406;45180;45594;42918;44715.32927006033;45109.77445112754;46009.30986035145;46567.53432465632;44559.55440445554;45399.54600453953;45424.09151817011;45678.54321456876;46138.538614614765;39126.2174756509;39873;40222.1955560893;40375.3623621523;40672;29768.404631907673;30140;30188.5291544032;30320;30323.848380757954 09:28:05 INFO - PROCESS | 1998 | |2;jquery.html;416.5421026407574;426.52016481458367;426.6480153873056;433.28291677274115;444.2026562320636;18607;19121.61756764864;20184.89907550462;20197.59604807903;20214.191432342726;18698.626027479444;18898.716519252222;18981.14584843682;19559.315423960164;19578.902105489487;5631.549476041927;5909.290885093795;5911.645301281916;5914.733836977346;5917.852053698649;5643.209950606898;5818.621789583684;5833.649981001132;5876.147958546008;5876.4123587641325;5733.913991290113;5750.3962083981205;5764.9423505765035;5771.884562308751;5784.884302313952;5557.249771280886;5765.164051212571;5772.913406298909;5802.303723553158;5818.127280907897;5173.896522069537;5176.456472070416;5179.274901513753;5183.248428977777;5198.350206224222;22290.88854555733;23386.18148364799;23760.287191384286;23964.64053039206;23985.52028959411;2315.2012555668266;2388.988055059731;2452.3010941881657;2472.332470233045;2535.430186568441;14815.555533334018;14856.405743770236;14907.701845963129;15337.923310383489;15634.843651563453;2020.504976280806;2095.5389814240843;2118.9682154767693;2128.6594144936735;2151.0427859602482;71523.64238178828;72260;72831;74352;75748.62125689337;8722.215000649972;9306.488143152192;9352.532373381304;9976.401415915068;10744.30162039465;12973.156744811557;13064.54274100402;13092.410841512155;13160.934195329153;13168.60494185176;7652.540847549053;7770.689172433039;7830.765077047698;7896.960515197502;7963.522188668699;7755.53466791983;7859.056913170456;7884.093329267093;7978.6409611567615;8164.510129392255;4148.087420767426;4181.958180418218;4222.493300803923;4230.048239146229;4250.447441832542;1844.2162081115473;1910.3504808365224;1951.7853036166011;1974.8222659960672;2065.1120018391935;983.1937810994915;1002.7944271424341;1043.0664555223034;1104.2215238257008;1108.1356541897333;19168.520786980436;19783.604327913363;19796.604067918848;19855.503612409804;20198;1015.6445244164631;1020.8621836052187;1030.9484525773682;1054.7046826888484;1092.9890701093045;9194;10811.891881081247;11206.775864482664;11222.270552414067;11265.54937802495;341046;345546;345571;345614;541775;80389.1510018996;96263;100839.99160008453;102147;103632.48183759033;441.62682533259346;459.68741255946276;472.55107647735474;473.3278744183268;473.7725891572065;10000.349977251455;10405.375677459377;10511.57953681844;10669.946650266698;10907.2364934457;41328.58671413247;42244.36633450439;42499.787501062296;43452.565474344854;43486;60298.397016031035;60637;60773.69613151906;61645.691771540856;61865;6191.978323576559;8101.432899696964;8512.489250644981;8618.741437756877;9079.319051071065;13304.46782128704;13853.722925541635;13943;14047.36786844574;14136.78794818058;11842.644720658394;11883.524659013543;11964.461599227881;12043.819342710041;12624.621261362174;11944.761104777683;12011.63965081049;12085.154039217523;12263.38683065861;12391.876081239432;12135.757284854077;12487.250764954131;12487.687807804878;12605;12739.171953822972;8253.257206851413;8567.571621419029;8580.270676992526;8670.82658346817;9074.727758167266;10459.94770026145;10847.67456976292;10951.45242737844;11371.829422558834;12118.394080295773 09:28:05 INFO - PROCESS | 1998 | |3;mootools.html;1397.9440822367105;1517.393042782887;1552.6273694313363;1738.3220543987848;1916.7604049493814;9086.636534538618;9518.85721714174;9520.428774273549;9829.901700982988;9834.50827458626;9553.617855285798;9715.805683886318;9748.70753877383;9753.7561560961;9754;2209.0611490116635;2426.9271921842374;2493.0775613023206;2508.41052352697;2529.203300960201;2506.8997240110375;2527.9620805687928;2533.138732830837;2537.7208507064206;2538.834975726581;2452.19077704358;2478.033566908907;2482.6772519572432;2482.8013758899247;2484.0188125690343;2156.5686862627535;2411.915582954597;2480.119557557059;2483.900643974248;2501.4121681404854;2408.2534414331612;2425.551273014498;2428.6842710447704;2466.3834041489627;2466.5066986602574;9704.417734935856;9721.854172187423;9753.170980466668;9755.463449510275;9768.853467197998;5586.106223004341;5710.685912274823;5719.799807006776;5722.399148089474;5727.055035919068;3330.58367704037;3453.9827300863335;3496.3356962177104;3507.6141624421293;3509.5963964144194;1111.2554588425774;1124.7581769919507;1131.9943400283028;1133.7165708572857;1143.4682872464261;14017.78973315391;14059.156450612894;14102.92948535261;14127.505537306248;14143.151410915378;4841.709497430095;4930.778114984834;4939.185034469344;4970.527799859007;4996.975015124974;11666.88333116658;11710.297382157098;11719.472623731952;11722.062235021178;11730.589429370102;5118.411382690963;5727.455891690282;5795.88408231833;5797.36229014808;5801;4965.950340496621;5133.12736834739;5157.406898206679;5209.062368773657;5210.947890521122;4306.763128027926;4319.114581510783;4377.9781101094295;4409.625181859514;4410.669199809963;1331.4141777617817;1358.2868993778345;1360.435419300979;1376.1124074971588;1377.4903285784324;1056.1972900595604;1057.8889216632142;1081.1296905990753;1085.234909388879;1086.21792309537;6424.935750642528;6596.736130554724;6640.535162538673;6679.899801502981;6765.695543700545;474.3951461886123;479.9976000119977;481.92289233722437;482.5946205187589;497.39069639691087;4582.656300777455;4611.538846115429;4636.976815115903;4655;4655.4878963314;12281.447334869952;12668.23990560551;12682.239065656091;12703.68240793969;12726.23642581448;11512.481938312796;12074.87925120755;12088.818667719992;12591.181573197713;12625.179363341353;8860.3797734158;8957.776055598531;8959.910400896038;8975.730728078168;9041.593128309241;6446.866051543052;6632.303608121078;6678.265390806911;6699.564528305645;6817.079694241214;10784.622538211124;11227.943860280646;11537.942310288396;11548.59579914699;11567.595134170264;11424.371659558472;11624.011958983243;11657.300561966309;11659.88340116588;11768.646940591796;7753.263439973193;7919.12889582157;7976.042874855055;8240.629171687408;8415.284700800501;9503;9508.667196648084;9510.524473776422;9540.427574345562;9572.425654460754;8092.109867914643;8245.051819040887;8283.668653253802;8383.455075420079;8477.448965817202;8099.617011090608;8226.753197404087;8603.09667484905;8617.784555386166;8732;7322.3775979042375;7336.339729424378;7351.081114860643;7354.4484163689;7369.447291453055;5529.087700529529;5629.240052592848;5691.77232910679;5841.678707671119;5843.094320380348;5591.21722958778;5754.539636829128;5779.133130030361;5839.5036421904615;5862.853428664317 09:28:05 INFO - PROCESS | 1998 | |4;prototype.html;331.9287645094609;341.31566209749457;346.42666387129276;348.23388545200555;352.8006676227931;5396.514313711765;5439.3200849893765;5558.610897237195;5590.972045139773;5635.718214089295;5476.343973173008;5478.91781623275;5514.979728750184;5520.309961254843;5522.171674248864;4342.956570434302;4355.520892701808;4381.671374646899;4389.4513185851765;4391.604755572014;4143.171365726852;4178.310578754502;4210.799922022225;4326.632236259922;4362.520122786491;4275.636570891478;4277.3156294992805;4284.657227421799;4294.892627684318;4307.353896915456;4312.762798046107;4323.762193079364;4356.150550642626;4367.104743527601;4369.257226271541;3603.1352475405974;3757.229767897574;3767.472553842464;3845.8269377878064;3859.9614003859883;5280.709560974145;5305.408377486777;5342.1452567589;5342.385625653061;5368.926214757064;4933.45731969483;4941.604671626261;4953.727544985024;4971.726555039471;4992.525710057539;5611.158326251095;5845.824625261249;5856.882862342772;5857.619254748471;5861.765529378819;4110.671146308318;4112.814923328458;4117.608827161415;4130.648894843942;4136.793160341971;5762.456089504924;6075.48358389533;6100.328963813977;6101;6109.938900610981;4176.2691528982305;4180.498340199196;4183.100633363842;4205.558416366238;4208.726432781859;4201.70588058833;4222.577742225752;4237.809298581571;4238.109996900624;4253.80857861397;4923.704577725348;4930.457649658535;4930.9013819723405;4932.8520144395725;4959.594596620636;5592.328920529562;5604.663720176803;5683.488486036276;5894.233749612523;5900.1739756434135;1468.4713503138862;1473.7052589482146;1477.062065588359;1482.7553453680023;1485.613740427475;911.5852287209303;919.7470695558775;936.7611259129014;943.2029934705163;952.6046690623314;5055.797768089309;5077.746112694351;5120.155174396257;5130.589552835764;5141.357330333708;5272.971770504715;5348.732563371816;5351.0736134991375;5396.514313711707;5409.702466364388;5068.6451948364;5109.821156259513;5113.284140220372;5283.147100565729;5813.186153938452;3213.7268332192025;3225.7903236290035;3229.1442767666585;3235.9838200809318;3239.174010627275;6023.668698221552;6218.19163508741;6243.500519958392;6250.343713910065;6262.436380725757;6141.201643786369;6149.815505534841;6152.692365381713;6156.630602163885;6162.383761623892;6229.844253893689;6231.75072997075;6241.75032998684;6262.434391402149;6263.311035785969;5819.70901454934;6031.7587296507645;6034.637921724535;6038.758449661965;6042.039400693717;5946.98901186808;5967;5968.313643931004;5976.820695379146;5995.820125396245;5954.374790647093;6197.96901015492;6222.693234420823;6224.035274532454;6250.843593934955;4145.756273118112;4176.60322269384;4178.770167640809;4194.322271091588;4202.978985105055;5784;5879.617824841372;6008.536500034965;6016.669083200466;6066.757329706762;5875.794347197828;5902.439268269508;5911.91132133027;5920.822375328747;5922.881542369215;5737.713114344349;5854.619449735754;5865.384134665969;5876.588638795243;5882.176495290748;5466.863328416822;5729.197912292366;5793.681347525927;5799.362070172362;5807.94192058074;5837.737301821343;5838.416158384298;5871.823845284648;5886.323072846506;5893.2044174035955;5482.588805839658;5752.741126649395;5776.797812076557;5782.624129431573;5782.971085144547 09:28:05 INFO - PROCESS | 1998 | |5;yui.html;699.9965000174998;742.261449857392;753.8040109571509;845.8562044452442;878.5036454403262;15031.09813411196;15730.134842583653;16052.75920861188;16092.83907160928;16109.194540272998;15061.924690376532;15264.236788160602;15292.694146117072;15340.309686064125;15357;721.9927800721978;748.8352562436255;751.031169790969;864.7103220421167;878.3456325037856;744.8696478116308;848.1306660672798;884.5533005832078;887.4010043220833;889.0265159650205;914.6067191107821;918.2975024106564;921.8525035994243;925.3060204846365;925.9768505787342;757.1633345153581;916.6699988004314;916.8487199612056;917.9678711245108;918.5407296351824;650.177525430331;676.2426082787309;677.1467950382504;678.0947435174048;678.396227357652;14905.478308259268;14921.253937303092;15013.324400402009;15039;15054.548363549111;14453.494127705584;15134.167620780852;15155.318010689436;15229.61925951849;15337.616559585987;13211.79956923479;13563.118397304144;13586.116902401312;13887.58337249884;14483.42066317346;4721.102990431807;4737.597304229144;4757.405324334458;4808.471068182497;4853.830115945961;74549.62725186393;75711;77912;78456;79038;11319.207655464039;11703.414829258503;11818.82271765907;12496.437660305308;12716.491340346283;23526.176583819484;23563.528729425314;23704.762952370602;23784.643230351558;23850.284491465285;8177.100518942912;8297.95851020753;8559.34015124022;8759.693410730593;8850.026497085315;4564.246899261651;4613.331066995267;4638.466576343763;4816.470188279286;4849.95150048495;3602.8558857645926;3612.223371975038;3674.5590529136675;3711.480392744964;3717.3308804415465;661.8213082467706;662.059874977533;676.4047638078558;680.9761658341935;689.2315068698465;598.5271635408066;611.9020956646915;647.9761976077785;704.844934114494;721.0770214125927;10033;10287.125594324567;10326.535305911251;10364.948175259075;10510.316829406063;604.8185544336682;642.9774957876452;688.8656711941223;697.7174244431013;698.5215127637584;4237.851675191354;4324.783760811947;4413.095315460323;4456.041950980555;4482.731036137842;73280.63359683275;74799.62600187073;74846.25153748502;110420.44789776;118721;65712.67143664346;82589.58705206556;97109.5144524287;98315;100433;85468.57265713756;87327.12672873317;88282;89276.10723892807;89906;9338.159565639126;9517;9552.14030737237;9555.952220239133;9622.615095396106;45246.321305179794;46367.53632463767;46645.76677116593;46669.76665116653;51291.743541282056;76261.23738762764;76360.61819691089;77221;78882.21117788748;80195.59902200452;12700.425944142982;12730.745385092432;12926.806097908728;12944.870551294744;13517.864821352054;14725;14795.260236987891;15105;15235.847641523444;15261.389544418098;11169;13662.453501860213;13708.93145534266;13736;14421.711565768835;13154.473821047051;13561.321933903067;13748.93125534366;14540.854591454374;14947.551573452814;14248.430062797372;14611.780823287447;14724.411023558938;15206.923965380545;15357.155356455502;11233.438328083726;11728.296302221554;12023.21849079807;12109.455074521844;12109.515619375126;11933.224340418192;12116.697082573006;12369.505219791108;12390.070744694003;12692.111552191627 09:28:05 INFO - PROCESS | 1998 | __end_tp_report 09:28:05 INFO - PROCESS | 1998 | __start_cc_report 09:28:05 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,962 09:28:05 INFO - PROCESS | 1998 | __end_cc_report 09:28:05 INFO - PROCESS | 1998 | __startTimestamp1465403285812__endTimestamp 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | ------- Summary: start ------- 09:28:05 INFO - PROCESS | 1998 | Number of tests: 6 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#0] dojo.html Cycles:165 Average:5728.89 Median:5968.19 stddev:652.76 (10.9%) stddev-sans-first:629.97 09:28:05 INFO - PROCESS | 1998 | Values: 3457.7 3488.7 3489.5 3519.4 3532.3 5753.8 5853.1 5875.8 5879.4 5882.8 5665.6 5728.5 5741.3 5744.9 5746.8 5020.6 5132.9 5157.9 5230.9 5244.0 5063.5 5173.5 5203.3 5209.3 5213.9 5123.3 5142.0 5200.7 5233.6 5256.5 5297.9 5313.1 5319.5 5333.5 5360.9 5032.7 5068.7 5082.0 5178.3 5189.5 5560.1 5565.7 5569.2 5579.7 5602.2 6172.7 6179.0 6184.6 6185.8 6193.1 6141.9 6175.3 6177.5 6237.4 6279.8 5845.3 5887.6 5899.1 5900.9 5901.5 6057.9 6406.1 6406.6 6407.4 6426.4 5857.2 5927.9 5968.5 5975.5 5998.7 6046.4 6046.8 6049.3 6076.8 6082.9 5989.1 6004.3 6016.0 6018.4 6110.9 6156.3 6271.5 6275.9 6277.6 6280.6 4678.7 4716.7 4717.8 4731.7 4742.7 4097.5 4133.8 4168.9 4197.3 4221.1 5943.2 5957.5 5960.5 5961.5 5974.6 6239.5 6287.7 6291.6 6292.1 6302.9 5624.0 5816.8 5839.0 5849.4 5946.0 5227.7 5237.1 5238.9 5241.0 5258.5 6396.6 6443.8 6452.5 6456.1 6459.6 6023.1 6127.8 6143.3 6154.6 6245.0 6418.2 6424.7 6435.4 6440.5 6450.3 6089.8 6093.8 6101.7 6102.1 6112.1 5968.2 6131.6 6132.9 6157.9 6241.3 6088.0 6092.8 6101.0 6101.7 6109.6 5813.7 6048.0 6063.6 6064.3 6083.1 5913.1 5947.9 5955.0 5980.5 5987.6 6135.2 6158.1 6161.8 6162.4 6163.5 5692.2 5809.9 5987.8 6006.0 6026.9 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#1] ext.html Cycles:180 Average:63681.31 Median:39821.20 stddev:127242.72 (319.5%) stddev-sans-first:127511.56 09:28:05 INFO - PROCESS | 1998 | Values: 614.2 649.3 651.5 668.1 687.7 32833.7 46087.3 47679.8 47855.0 47887.8 48641.0 49246.5 49252.0 49266.8 49375.0 8317.8 8981.0 9199.3 9202.4 10827.5 10536.7 10959.4 10962.1 10968.5 10973.9 10978.6 11060.2 11074.8 11075.0 11087.0 8601.1 8836.6 10853.8 10966.3 11228.7 10657.3 11016.4 11016.8 11043.1 11046.2 45047.5 45633.5 45729.3 45781.8 45858.0 3641.7 3803.8 3847.7 3990.6 4126.3 34442.1 34998.5 35291.5 35413.8 35443.8 4088.6 4205.9 4229.4 4237.1 4248.2 389858.0 393686.0 394673.0 396107.0 397456.0 38400.2 66896.0 67207.3 67785.0 67806.7 39769.4 41878.6 42003.0 42013.6 42037.4 15633.1 15679.7 15737.7 15738.0 15741.6 11131.3 13248.4 13522.4 13539.4 13766.5 9206.2 9214.9 9259.2 9280.0 9335.5 3551.3 3562.9 3565.4 3577.6 3608.3 1061.8 1080.0 1100.4 1114.5 1130.4 32737.5 34377.7 34550.3 34794.3 35210.3 1148.8 1178.9 1211.3 1212.7 1221.8 17909.0 18008.9 18070.3 18184.3 18287.2 562310.0 577672.0 579493.0 581620.0 583550.0 63761.4 65458.0 65643.7 65697.3 65744.0 1376.3 1463.5 1486.7 1508.9 1512.4 31882.7 35362.0 35734.3 35982.6 36116.0 47080.0 47464.0 47501.8 47503.0 47603.3 445107.0 445592.0 449831.0 450349.0 451221.0 27368.9 30215.4 31352.1 31373.2 31451.8 50751.5 51522.0 52752.7 53307.2 57257.7 43183.1 44323.8 44853.1 45180.0 45594.0 42918.0 44715.3 45109.8 46009.3 46567.5 44559.6 45399.5 45424.1 45678.5 46138.5 39126.2 39873.0 40222.2 40375.4 40672.0 29768.4 30140.0 30188.5 30320.0 30323.8 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#2] jquery.html Cycles:180 Average:25968.96 Median:2503.88 stddev:65442.78 (2613.7%) stddev-sans-first:65598.24 09:28:05 INFO - PROCESS | 1998 | Values: 416.5 426.5 426.6 433.3 444.2 18607.0 19121.6 20184.9 20197.6 20214.2 18698.6 18898.7 18981.1 19559.3 19578.9 5631.5 5909.3 5911.6 5914.7 5917.9 5643.2 5818.6 5833.6 5876.1 5876.4 5733.9 5750.4 5764.9 5771.9 5784.9 5557.2 5765.2 5772.9 5802.3 5818.1 5173.9 5176.5 5179.3 5183.2 5198.4 22290.9 23386.2 23760.3 23964.6 23985.5 2315.2 2389.0 2452.3 2472.3 2535.4 14815.6 14856.4 14907.7 15337.9 15634.8 2020.5 2095.5 2119.0 2128.7 2151.0 71523.6 72260.0 72831.0 74352.0 75748.6 8722.2 9306.5 9352.5 9976.4 10744.3 12973.2 13064.5 13092.4 13160.9 13168.6 7652.5 7770.7 7830.8 7897.0 7963.5 7755.5 7859.1 7884.1 7978.6 8164.5 4148.1 4182.0 4222.5 4230.0 4250.4 1844.2 1910.4 1951.8 1974.8 2065.1 983.2 1002.8 1043.1 1104.2 1108.1 19168.5 19783.6 19796.6 19855.5 20198.0 1015.6 1020.9 1030.9 1054.7 1093.0 9194.0 10811.9 11206.8 11222.3 11265.5 341046.0 345546.0 345571.0 345614.0 541775.0 80389.2 96263.0 100840.0 102147.0 103632.5 441.6 459.7 472.6 473.3 473.8 10000.3 10405.4 10511.6 10669.9 10907.2 41328.6 42244.4 42499.8 43452.6 43486.0 60298.4 60637.0 60773.7 61645.7 61865.0 6192.0 8101.4 8512.5 8618.7 9079.3 13304.5 13853.7 13943.0 14047.4 14136.8 11842.6 11883.5 11964.5 12043.8 12624.6 11944.8 12011.6 12085.2 12263.4 12391.9 12135.8 12487.3 12487.7 12605.0 12739.2 8253.3 8567.6 8580.3 8670.8 9074.7 10459.9 10847.7 10951.5 11371.8 12118.4 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#3] mootools.html Cycles:180 Average:6382.50 Median:477.20 stddev:3775.85 (791.3%) stddev-sans-first:3767.86 09:28:05 INFO - PROCESS | 1998 | Values: 1397.9 1517.4 1552.6 1738.3 1916.8 9086.6 9518.9 9520.4 9829.9 9834.5 9553.6 9715.8 9748.7 9753.8 9754.0 2209.1 2426.9 2493.1 2508.4 2529.2 2506.9 2528.0 2533.1 2537.7 2538.8 2452.2 2478.0 2482.7 2482.8 2484.0 2156.6 2411.9 2480.1 2483.9 2501.4 2408.3 2425.6 2428.7 2466.4 2466.5 9704.4 9721.9 9753.2 9755.5 9768.9 5586.1 5710.7 5719.8 5722.4 5727.1 3330.6 3454.0 3496.3 3507.6 3509.6 1111.3 1124.8 1132.0 1133.7 1143.5 14017.8 14059.2 14102.9 14127.5 14143.2 4841.7 4930.8 4939.2 4970.5 4997.0 11666.9 11710.3 11719.5 11722.1 11730.6 5118.4 5727.5 5795.9 5797.4 5801.0 4966.0 5133.1 5157.4 5209.1 5210.9 4306.8 4319.1 4378.0 4409.6 4410.7 1331.4 1358.3 1360.4 1376.1 1377.5 1056.2 1057.9 1081.1 1085.2 1086.2 6424.9 6596.7 6640.5 6679.9 6765.7 474.4 480.0 481.9 482.6 497.4 4582.7 4611.5 4637.0 4655.0 4655.5 12281.4 12668.2 12682.2 12703.7 12726.2 11512.5 12074.9 12088.8 12591.2 12625.2 8860.4 8957.8 8959.9 8975.7 9041.6 6446.9 6632.3 6678.3 6699.6 6817.1 10784.6 11227.9 11537.9 11548.6 11567.6 11424.4 11624.0 11657.3 11659.9 11768.6 7753.3 7919.1 7976.0 8240.6 8415.3 9503.0 9508.7 9510.5 9540.4 9572.4 8092.1 8245.1 8283.7 8383.5 8477.4 8099.6 8226.8 8603.1 8617.8 8732.0 7322.4 7336.3 7351.1 7354.4 7369.4 5529.1 5629.2 5691.8 5841.7 5843.1 5591.2 5754.5 5779.1 5839.5 5862.9 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#4] prototype.html Cycles:180 Average:4861.56 Median:5453.09 stddev:1441.17 (26.4%) stddev-sans-first:1404.54 09:28:05 INFO - PROCESS | 1998 | Values: 331.9 341.3 346.4 348.2 352.8 5396.5 5439.3 5558.6 5591.0 5635.7 5476.3 5478.9 5515.0 5520.3 5522.2 4343.0 4355.5 4381.7 4389.5 4391.6 4143.2 4178.3 4210.8 4326.6 4362.5 4275.6 4277.3 4284.7 4294.9 4307.4 4312.8 4323.8 4356.2 4367.1 4369.3 3603.1 3757.2 3767.5 3845.8 3860.0 5280.7 5305.4 5342.1 5342.4 5368.9 4933.5 4941.6 4953.7 4971.7 4992.5 5611.2 5845.8 5856.9 5857.6 5861.8 4110.7 4112.8 4117.6 4130.6 4136.8 5762.5 6075.5 6100.3 6101.0 6109.9 4176.3 4180.5 4183.1 4205.6 4208.7 4201.7 4222.6 4237.8 4238.1 4253.8 4923.7 4930.5 4930.9 4932.9 4959.6 5592.3 5604.7 5683.5 5894.2 5900.2 1468.5 1473.7 1477.1 1482.8 1485.6 911.6 919.7 936.8 943.2 952.6 5055.8 5077.7 5120.2 5130.6 5141.4 5273.0 5348.7 5351.1 5396.5 5409.7 5068.6 5109.8 5113.3 5283.1 5813.2 3213.7 3225.8 3229.1 3236.0 3239.2 6023.7 6218.2 6243.5 6250.3 6262.4 6141.2 6149.8 6152.7 6156.6 6162.4 6229.8 6231.8 6241.8 6262.4 6263.3 5819.7 6031.8 6034.6 6038.8 6042.0 5947.0 5967.0 5968.3 5976.8 5995.8 5954.4 6198.0 6222.7 6224.0 6250.8 4145.8 4176.6 4178.8 4194.3 4203.0 5784.0 5879.6 6008.5 6016.7 6066.8 5875.8 5902.4 5911.9 5920.8 5922.9 5737.7 5854.6 5865.4 5876.6 5882.2 5466.9 5729.2 5793.7 5799.4 5807.9 5837.7 5838.4 5871.8 5886.3 5893.2 5482.6 5752.7 5776.8 5782.6 5783.0 09:28:05 INFO - PROCESS | 1998 | 09:28:05 INFO - PROCESS | 1998 | [#5] yui.html Cycles:180 Average:20196.68 Median:25490.43 stddev:27670.55 (108.6%) stddev-sans-first:27709.44 09:28:05 INFO - PROCESS | 1998 | Values: 700.0 742.3 753.8 845.9 878.5 15031.1 15730.1 16052.8 16092.8 16109.2 15061.9 15264.2 15292.7 15340.3 15357.0 722.0 748.8 751.0 864.7 878.3 744.9 848.1 884.6 887.4 889.0 914.6 918.3 921.9 925.3 926.0 757.2 916.7 916.8 918.0 918.5 650.2 676.2 677.1 678.1 678.4 14905.5 14921.3 15013.3 15039.0 15054.5 14453.5 15134.2 15155.3 15229.6 15337.6 13211.8 13563.1 13586.1 13887.6 14483.4 4721.1 4737.6 4757.4 4808.5 4853.8 74549.6 75711.0 77912.0 78456.0 79038.0 11319.2 11703.4 11818.8 12496.4 12716.5 23526.2 23563.5 23704.8 23784.6 23850.3 8177.1 8298.0 8559.3 8759.7 8850.0 4564.2 4613.3 4638.5 4816.5 4850.0 3602.9 3612.2 3674.6 3711.5 3717.3 661.8 662.1 676.4 681.0 689.2 598.5 611.9 648.0 704.8 721.1 10033.0 10287.1 10326.5 10364.9 10510.3 604.8 643.0 688.9 697.7 698.5 4237.9 4324.8 4413.1 4456.0 4482.7 73280.6 74799.6 74846.3 110420.4 118721.0 65712.7 82589.6 97109.5 98315.0 100433.0 85468.6 87327.1 88282.0 89276.1 89906.0 9338.2 9517.0 9552.1 9556.0 9622.6 45246.3 46367.5 46645.8 46669.8 51291.7 76261.2 76360.6 77221.0 78882.2 80195.6 12700.4 12730.7 12926.8 12944.9 13517.9 14725.0 14795.3 15105.0 15235.8 15261.4 11169.0 13662.5 13708.9 13736.0 14421.7 13154.5 13561.3 13748.9 14540.9 14947.6 14248.4 14611.8 14724.4 15206.9 15357.2 11233.4 11728.3 12023.2 12109.5 12109.5 11933.2 12116.7 12369.5 12390.1 12692.1 09:28:05 INFO - PROCESS | 1998 | -------- Summary: end -------- 09:28:05 INFO - PROCESS | 1998 | 09:28:06 INFO - TEST-INFO | 1998: exit 0 09:28:06 INFO - TEST-OK | dromaeo_css | took 1113702ms 09:28:06 INFO - TEST-START | kraken 09:28:06 INFO - operating with platform_type : linux_ 09:28:06 INFO - Initialising browser for kraken test... 09:28:06 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL_Cx5R/profile http://localhost:33457/getInfo.html) 09:28:10 INFO - PROCESS | 2191 | __metrics Screen width/height:1600/1200 09:28:10 INFO - PROCESS | 2191 | colorDepth:24 09:28:10 INFO - PROCESS | 2191 | Browser inner width/height: 1024/697 09:28:10 INFO - PROCESS | 2191 | __metrics 09:28:11 ERROR - PROCESS | 2191 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:28:11 INFO - PROCESS | 2191 | console.error: PushService: 09:28:11 INFO - PROCESS | 2191 | stateChangeProcessEnqueue: Error transitioning state 09:28:11 INFO - PROCESS | 2191 | UnknownError 09:28:12 INFO - TEST-INFO | 2191: exit 0 09:28:12 INFO - Browser initialized. 09:28:12 INFO - Running cycle 1/1 for kraken test... 09:28:12 INFO - TEST-INFO | started process 2279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL_Cx5R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:24 INFO - PROCESS | 2279 | RSS: Main: 145235968 09:28:24 INFO - PROCESS | 2279 | 09:28:26 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33457/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:27 INFO - PROCESS | 2279 | RSS: Main: 194281472 09:28:27 INFO - PROCESS | 2279 | 09:28:30 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33457/tests/kraken/driver.html?testName=audio-dft) 09:28:30 INFO - PROCESS | 2279 | RSS: Main: 191963136 09:28:30 INFO - PROCESS | 2279 | 09:28:33 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33457/tests/kraken/driver.html?testName=audio-fft) 09:28:34 INFO - PROCESS | 2279 | RSS: Main: 276344832 09:28:34 INFO - PROCESS | 2279 | 09:28:36 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33457/tests/kraken/driver.html?testName=audio-oscillator) 09:28:37 INFO - PROCESS | 2279 | RSS: Main: 184160256 09:28:37 INFO - PROCESS | 2279 | 09:28:39 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33457/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:39 INFO - PROCESS | 2279 | RSS: Main: 184352768 09:28:39 INFO - PROCESS | 2279 | 09:28:42 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33457/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:43 INFO - PROCESS | 2279 | RSS: Main: 219754496 09:28:43 INFO - PROCESS | 2279 | 09:28:46 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33457/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:47 INFO - PROCESS | 2279 | RSS: Main: 219873280 09:28:47 INFO - PROCESS | 2279 | 09:28:50 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33457/tests/kraken/driver.html?testName=json-parse-financial) 09:28:50 INFO - PROCESS | 2279 | RSS: Main: 219635712 09:28:50 INFO - PROCESS | 2279 | 09:28:52 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33457/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:52 INFO - PROCESS | 2279 | RSS: Main: 197722112 09:28:52 INFO - PROCESS | 2279 | 09:28:54 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:55 INFO - PROCESS | 2279 | RSS: Main: 201302016 09:28:55 INFO - PROCESS | 2279 | 09:28:57 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:57 INFO - PROCESS | 2279 | RSS: Main: 210153472 09:28:57 INFO - PROCESS | 2279 | 09:29:00 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:00 INFO - PROCESS | 2279 | RSS: Main: 214630400 09:29:00 INFO - PROCESS | 2279 | 09:29:03 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:04 INFO - PROCESS | 2279 | RSS: Main: 215883776 09:29:04 INFO - PROCESS | 2279 | 09:29:05 INFO - PROCESS | 2279 | Cycle 1(1): loaded http://localhost:33457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33457/tests/kraken/driver.html?testName=ai-astar) 09:29:06 INFO - PROCESS | 2279 | RSS: Main: 258879488 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | __start_tp_report 09:29:06 INFO - PROCESS | 2279 | _x_x_mozilla_page_load 09:29:06 INFO - PROCESS | 2279 | _x_x_mozilla_page_load_details 09:29:06 INFO - PROCESS | 2279 | |i|pagename|runs| 09:29:06 INFO - PROCESS | 2279 | |0;ai-astar;110;103;110;110;110;108;109;110;109;109 09:29:06 INFO - PROCESS | 2279 | |1;audio-beat-detection;154;123;127;173;140;129;151;126;156;125 09:29:06 INFO - PROCESS | 2279 | |2;audio-dft;160;161;159;160;160;159;159;162;160;163 09:29:06 INFO - PROCESS | 2279 | |3;audio-fft;76;76;74;123;74;75;75;74;104;75 09:29:06 INFO - PROCESS | 2279 | |4;audio-oscillator;84;85;112;85;85;85;110;84;84;121 09:29:06 INFO - PROCESS | 2279 | |5;imaging-gaussian-blur;117;117;117;118;116;117;116;116;116;117 09:29:06 INFO - PROCESS | 2279 | |6;imaging-darkroom;146;146;146;147;147;147;146;146;145;145 09:29:06 INFO - PROCESS | 2279 | |7;imaging-desaturate;95;95;95;94;94;94;95;94;95;95 09:29:06 INFO - PROCESS | 2279 | |8;json-parse-financial;49;50;51;52;49;51;51;50;53;50 09:29:06 INFO - PROCESS | 2279 | |9;json-stringify-tinderbox;63;60;60;60;60;60;60;60;59;60 09:29:06 INFO - PROCESS | 2279 | |10;stanford-crypto-aes;76;76;75;77;76;78;77;79;103;77 09:29:06 INFO - PROCESS | 2279 | |11;stanford-crypto-ccm;118;117;126;118;117;117;116;121;119;121 09:29:06 INFO - PROCESS | 2279 | |12;stanford-crypto-pbkdf2;156;153;155;153;157;155;155;156;153;153 09:29:06 INFO - PROCESS | 2279 | |13;stanford-crypto-sha256-iterative;65;62;63;62;63;62;62;61;63;63 09:29:06 INFO - PROCESS | 2279 | __end_tp_report 09:29:06 INFO - PROCESS | 2279 | __start_cc_report 09:29:06 INFO - PROCESS | 2279 | _x_x_mozilla_cycle_collect,1574 09:29:06 INFO - PROCESS | 2279 | __end_cc_report 09:29:06 INFO - PROCESS | 2279 | __startTimestamp1465403346029__endTimestamp 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | ------- Summary: start ------- 09:29:06 INFO - PROCESS | 2279 | Number of tests: 14 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:2.15 (2.0%) stddev-sans-first:2.24 09:29:06 INFO - PROCESS | 2279 | Values: 110.0 103.0 110.0 110.0 110.0 108.0 109.0 110.0 109.0 109.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:145.50 stddev:17.19 (11.8%) stddev-sans-first:17.52 09:29:06 INFO - PROCESS | 2279 | Values: 154.0 123.0 127.0 173.0 140.0 129.0 151.0 126.0 156.0 125.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.34 (0.8%) stddev-sans-first:1.41 09:29:06 INFO - PROCESS | 2279 | Values: 160.0 161.0 159.0 160.0 160.0 159.0 159.0 162.0 160.0 163.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.91 (22.5%) stddev-sans-first:17.76 09:29:06 INFO - PROCESS | 2279 | Values: 76.0 76.0 74.0 123.0 74.0 75.0 75.0 74.0 104.0 75.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:84.50 stddev:14.65 (17.3%) stddev-sans-first:15.13 09:29:06 INFO - PROCESS | 2279 | Values: 84.0 85.0 112.0 85.0 85.0 85.0 110.0 84.0 84.0 121.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:29:06 INFO - PROCESS | 2279 | Values: 117.0 117.0 117.0 118.0 116.0 117.0 116.0 116.0 116.0 117.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:29:06 INFO - PROCESS | 2279 | Values: 146.0 146.0 146.0 147.0 147.0 147.0 146.0 146.0 145.0 145.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:29:06 INFO - PROCESS | 2279 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.26 (2.5%) stddev-sans-first:1.20 09:29:06 INFO - PROCESS | 2279 | Values: 49.0 50.0 51.0 52.0 49.0 51.0 51.0 50.0 53.0 50.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:1.03 (1.7%) stddev-sans-first:0.33 09:29:06 INFO - PROCESS | 2279 | Values: 63.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 59.0 60.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:77.00 stddev:8.37 (10.9%) stddev-sans-first:8.79 09:29:06 INFO - PROCESS | 2279 | Values: 76.0 76.0 75.0 77.0 76.0 78.0 77.0 79.0 103.0 77.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#11] stanford-crypto-ccm Cycles:10 Average:119.00 Median:118.50 stddev:2.98 (2.5%) stddev-sans-first:3.14 09:29:06 INFO - PROCESS | 2279 | Values: 118.0 117.0 126.0 118.0 117.0 117.0 116.0 121.0 119.0 121.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.00 stddev:1.51 (1.0%) stddev-sans-first:1.51 09:29:06 INFO - PROCESS | 2279 | Values: 156.0 153.0 155.0 153.0 157.0 155.0 155.0 156.0 153.0 153.0 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - PROCESS | 2279 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.07 (1.7%) stddev-sans-first:0.71 09:29:06 INFO - PROCESS | 2279 | Values: 65.0 62.0 63.0 62.0 63.0 62.0 62.0 61.0 63.0 63.0 09:29:06 INFO - PROCESS | 2279 | -------- Summary: end -------- 09:29:06 INFO - PROCESS | 2279 | 09:29:06 INFO - TEST-INFO | 2279: exit 0 09:29:06 INFO - TEST-OK | kraken | took 60477ms 09:29:06 INFO - SUITE-END | took 1174s 09:29:07 INFO - Completed test suite (00:19:34) 09:29:07 INFO - javascript benchmark 09:29:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3457.6715212054855, 3488.7034602058825, 3489.546358973334, 3519.401701710709, 3532.2582257725876, 5753.769849206032, 5853.09277062055, 5875.76496940123, 5879.353271140171, 5882.794102206423, 5665.575081868866, 5728.513076388512, 5741.311042674884, 5744.885102297952, 5746.798862039829, 5020.648554601161, 5132.948670513284, 5157.948420515784, 5230.895382092356, 5244.029854476934, 5063.518965698253, 5173.5085166909275, 5203.271541984125, 5209.296744939442, 5213.9478605214035, 5123.308353372303, 5142.0, 5200.6879587224885, 5233.607479439038, 5256.474352564751, 5297.8940421191555, 5313.1233346497975, 5319.521243088102, 5333.493318134771, 5360.91958620621, 5032.748362581893, 5068.746562671889, 5082.0, 5178.275041494194, 5189.455107213764, 5560.054790685593, 5565.666060036411, 5569.220309156722, 5579.69311687857, 5602.187682785973, 6172.722227499773, 6178.969105154445, 6184.56708030438, 6185.783497577609, 6193.0710393440895, 6141.915626108633, 6175.320714721377, 6177.474914632262, 6237.407446292595, 6279.780207692709, 5845.298564172326, 5887.646741195542, 5899.085641725539, 5900.911486327752, 5901.527877769769, 6057.925495964724, 6406.071119687713, 6406.615603063831, 6407.359264073555, 6426.389492998158, 5857.179994800731, 5927.881442371128, 5968.46283834457, 5975.462208401264, 5998.670073146019, 6046.425589568984, 6046.848828779316, 6049.304330002018, 6076.848078798065, 6082.939170608237, 5989.071693887455, 6004.309504407049, 6015.969920150371, 6018.428249316308, 6110.938890611037, 6156.322804491502, 6271.529635277372, 6275.874482510324, 6277.623342599459, 6280.623162610258, 4678.672492925462, 4716.6698331116495, 4717.787699553528, 4731.69243999139, 4742.739149346819, 4097.467329247238, 4133.834646614102, 4168.916621667549, 4197.286461301586, 4221.071364299849, 5943.197668314809, 5957.523398128139, 5960.463558279689, 5961.5230781537375, 5974.61165024272, 6239.469645080128, 6287.717052732638, 6291.622502649856, 6292.05619157121, 6302.873942521215, 5624.0439125348785, 5816.82549523515, 5839.036558967723, 5849.356570777211, 5945.970270148621, 5227.660202086852, 5237.083510385667, 5238.895222095613, 5241.0, 5258.526732594085, 6396.584222025554, 6443.7744678936015, 6452.548321617442, 6456.063870738717, 6459.612423254526, 6023.096535519707, 6127.785527506516, 6143.266246520256, 6154.569180157346, 6245.0, 6418.19772528434, 6424.678766061772, 6435.388638079375, 6440.549161558646, 6450.322716114928, 6089.786857459968, 6093.847653808513, 6101.725422356093, 6102.084687296941, 6112.083187521907, 5968.194293770459, 6131.601445906002, 6132.906245166512, 6157.907631385622, 6241.251049874044, 6088.012018824206, 6092.847678808065, 6101.0, 6101.694915254308, 6109.602875813058, 5813.738381772746, 6048.0, 6063.636181829104, 6064.332923378513, 6083.117947897618, 5913.05391137416, 5947.881042379215, 5955.047192449188, 5980.491658209101, 5987.550933679904, 6135.1717518136265, 6158.076288556573, 6161.784337548164, 6162.445379915829, 6163.537734669828, 5692.174634678031, 5809.912851307318, 5987.7604895805425, 6006.0, 6026.879462410815], "value": 5685.877609794744, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.1616693213765, 649.2663290481755, 651.5113664751436, 668.1147479589544, 687.7352219395531, 32833.67166328339, 46087.30869036963, 47679.76160119203, 47855.0, 47887.76056119723, 48641.0, 49246.50753492464, 49252.0, 49266.75366623171, 49375.0, 8317.83364332713, 8981.0, 9199.310051746112, 9202.355835091546, 10827.458627068654, 10536.736581585445, 10959.397233152173, 10962.123030157569, 10968.506417211245, 10973.890261097407, 10978.615748448807, 11060.22578419511, 11074.833877491845, 11075.0, 11087.0, 8601.13988601138, 8836.602352894135, 10853.837192442119, 10966.342019478778, 11228.663140105728, 10657.307275027099, 11016.449177541172, 11016.779664406668, 11043.061339786127, 11046.171537134667, 45047.54952450466, 45633.54366456326, 45729.31406028879, 45781.77109114466, 45858.0, 3641.745077844552, 3803.7717736935874, 3847.7306588538813, 3990.5610382857863, 4126.3397856343145, 34442.138946526284, 34998.47502287468, 35291.4706279406, 35413.822930885435, 35443.822780886185, 4088.5894167747592, 4205.894852628679, 4229.38673892287, 4237.13138806547, 4248.214080395137, 389858.0, 393686.0, 394673.0, 396107.0194649037, 397456.0, 38400.231995359936, 66896.0, 67207.32792672109, 67785.0, 67806.66096669485, 39769.40345894814, 41878.581214188074, 42003.0, 42013.57986420158, 42037.36943945843, 15633.14017729036, 15679.68640627181, 15737.68524629501, 15738.0, 15741.606459838596, 11131.276467029616, 13248.40382182804, 13522.391492382867, 13539.39072741729, 13766.518171863936, 9206.217471514996, 9214.861777073349, 9259.166674999282, 9279.953600232091, 9335.486548239913, 3551.271989242199, 3562.946555801665, 3565.3938830398893, 3577.606463289036, 3608.332458495187, 1061.8354155105972, 1079.983800242997, 1100.350793032115, 1114.509615769059, 1130.4404319861558, 32737.508937365958, 34377.656223437945, 34550.30899382049, 34794.304113918086, 35210.295794084486, 1148.758760660254, 1178.911581631381, 1211.3216598704753, 1212.7331986962854, 1221.8464559510496, 17909.01692139458, 18008.941877794336, 18070.26550172187, 18184.272629094954, 18287.177077031567, 562310.0, 577672.0, 579493.0, 581620.0, 583550.0, 63761.36238637647, 65458.0, 65643.67178164079, 65697.34302656913, 65744.0, 1376.2980879751199, 1463.5097242423908, 1486.6506371002806, 1508.924553772307, 1512.37992423106, 31882.68117318797, 35362.0, 35734.28531429305, 35982.64017359793, 36116.0, 47080.0, 47464.0, 47501.76249118732, 47503.04993900172, 47603.28595071007, 445107.0, 445592.0, 449831.0, 450349.0, 451221.0, 27368.863155684096, 30215.395692085596, 31352.05943821689, 31373.215669608442, 31451.84274078615, 50751.492485074676, 51522.0, 52752.73623632011, 53307.20039199493, 57257.71371143118, 43183.13633727371, 44323.77838110789, 44853.102937940406, 45180.0, 45594.0, 42918.0, 44715.32927006033, 45109.77445112754, 46009.30986035145, 46567.53432465632, 44559.55440445554, 45399.54600453953, 45424.09151817011, 45678.54321456876, 46138.538614614765, 39126.2174756509, 39873.0, 40222.1955560893, 40375.3623621523, 40672.0, 29768.404631907673, 30140.0, 30188.5291544032, 30320.0, 30323.848380757954], "value": 20572.21451212076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.5421026407574, 426.52016481458367, 426.6480153873056, 433.28291677274115, 444.2026562320636, 18607.0, 19121.61756764864, 20184.89907550462, 20197.59604807903, 20214.191432342726, 18698.626027479444, 18898.716519252222, 18981.14584843682, 19559.315423960164, 19578.902105489487, 5631.549476041927, 5909.290885093795, 5911.645301281916, 5914.733836977346, 5917.852053698649, 5643.209950606898, 5818.621789583684, 5833.649981001132, 5876.147958546008, 5876.4123587641325, 5733.913991290113, 5750.3962083981205, 5764.9423505765035, 5771.884562308751, 5784.884302313952, 5557.249771280886, 5765.164051212571, 5772.913406298909, 5802.303723553158, 5818.127280907897, 5173.896522069537, 5176.456472070416, 5179.274901513753, 5183.248428977777, 5198.350206224222, 22290.88854555733, 23386.18148364799, 23760.287191384286, 23964.64053039206, 23985.52028959411, 2315.2012555668266, 2388.988055059731, 2452.3010941881657, 2472.332470233045, 2535.430186568441, 14815.555533334018, 14856.405743770236, 14907.701845963129, 15337.923310383489, 15634.843651563453, 2020.504976280806, 2095.5389814240843, 2118.9682154767693, 2128.6594144936735, 2151.0427859602482, 71523.64238178828, 72260.0, 72831.0, 74352.0, 75748.62125689337, 8722.215000649972, 9306.488143152192, 9352.532373381304, 9976.401415915068, 10744.30162039465, 12973.156744811557, 13064.54274100402, 13092.410841512155, 13160.934195329153, 13168.60494185176, 7652.540847549053, 7770.689172433039, 7830.765077047698, 7896.960515197502, 7963.522188668699, 7755.53466791983, 7859.056913170456, 7884.093329267093, 7978.6409611567615, 8164.510129392255, 4148.087420767426, 4181.958180418218, 4222.493300803923, 4230.048239146229, 4250.447441832542, 1844.2162081115473, 1910.3504808365224, 1951.7853036166011, 1974.8222659960672, 2065.1120018391935, 983.1937810994915, 1002.7944271424341, 1043.0664555223034, 1104.2215238257008, 1108.1356541897333, 19168.520786980436, 19783.604327913363, 19796.604067918848, 19855.503612409804, 20198.0, 1015.6445244164631, 1020.8621836052187, 1030.9484525773682, 1054.7046826888484, 1092.9890701093045, 9194.0, 10811.891881081247, 11206.775864482664, 11222.270552414067, 11265.54937802495, 341046.0, 345546.0, 345571.0, 345614.0, 541775.0, 80389.1510018996, 96263.0, 100839.99160008453, 102147.0, 103632.48183759033, 441.62682533259346, 459.68741255946276, 472.55107647735474, 473.3278744183268, 473.7725891572065, 10000.349977251455, 10405.375677459377, 10511.57953681844, 10669.946650266698, 10907.2364934457, 41328.58671413247, 42244.36633450439, 42499.787501062296, 43452.565474344854, 43486.0, 60298.397016031035, 60637.0, 60773.69613151906, 61645.691771540856, 61865.0, 6191.978323576559, 8101.432899696964, 8512.489250644981, 8618.741437756877, 9079.319051071065, 13304.46782128704, 13853.722925541635, 13943.0, 14047.36786844574, 14136.78794818058, 11842.644720658394, 11883.524659013543, 11964.461599227881, 12043.819342710041, 12624.621261362174, 11944.761104777683, 12011.63965081049, 12085.154039217523, 12263.38683065861, 12391.876081239432, 12135.757284854077, 12487.250764954131, 12487.687807804878, 12605.0, 12739.171953822972, 8253.257206851413, 8567.571621419029, 8580.270676992526, 8670.82658346817, 9074.727758167266, 10459.94770026145, 10847.67456976292, 10951.45242737844, 11371.829422558834, 12118.394080295773], "value": 8936.582814425954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.9440822367105, 1517.393042782887, 1552.6273694313363, 1738.3220543987848, 1916.7604049493814, 9086.636534538618, 9518.85721714174, 9520.428774273549, 9829.901700982988, 9834.50827458626, 9553.617855285798, 9715.805683886318, 9748.70753877383, 9753.7561560961, 9754.0, 2209.0611490116635, 2426.9271921842374, 2493.0775613023206, 2508.41052352697, 2529.203300960201, 2506.8997240110375, 2527.9620805687928, 2533.138732830837, 2537.7208507064206, 2538.834975726581, 2452.19077704358, 2478.033566908907, 2482.6772519572432, 2482.8013758899247, 2484.0188125690343, 2156.5686862627535, 2411.915582954597, 2480.119557557059, 2483.900643974248, 2501.4121681404854, 2408.2534414331612, 2425.551273014498, 2428.6842710447704, 2466.3834041489627, 2466.5066986602574, 9704.417734935856, 9721.854172187423, 9753.170980466668, 9755.463449510275, 9768.853467197998, 5586.106223004341, 5710.685912274823, 5719.799807006776, 5722.399148089474, 5727.055035919068, 3330.58367704037, 3453.9827300863335, 3496.3356962177104, 3507.6141624421293, 3509.5963964144194, 1111.2554588425774, 1124.7581769919507, 1131.9943400283028, 1133.7165708572857, 1143.4682872464261, 14017.78973315391, 14059.156450612894, 14102.92948535261, 14127.505537306248, 14143.151410915378, 4841.709497430095, 4930.778114984834, 4939.185034469344, 4970.527799859007, 4996.975015124974, 11666.88333116658, 11710.297382157098, 11719.472623731952, 11722.062235021178, 11730.589429370102, 5118.411382690963, 5727.455891690282, 5795.88408231833, 5797.36229014808, 5801.0, 4965.950340496621, 5133.12736834739, 5157.406898206679, 5209.062368773657, 5210.947890521122, 4306.763128027926, 4319.114581510783, 4377.9781101094295, 4409.625181859514, 4410.669199809963, 1331.4141777617817, 1358.2868993778345, 1360.435419300979, 1376.1124074971588, 1377.4903285784324, 1056.1972900595604, 1057.8889216632142, 1081.1296905990753, 1085.234909388879, 1086.21792309537, 6424.935750642528, 6596.736130554724, 6640.535162538673, 6679.899801502981, 6765.695543700545, 474.3951461886123, 479.9976000119977, 481.92289233722437, 482.5946205187589, 497.39069639691087, 4582.656300777455, 4611.538846115429, 4636.976815115903, 4655.0, 4655.4878963314, 12281.447334869952, 12668.23990560551, 12682.239065656091, 12703.68240793969, 12726.23642581448, 11512.481938312796, 12074.87925120755, 12088.818667719992, 12591.181573197713, 12625.179363341353, 8860.3797734158, 8957.776055598531, 8959.910400896038, 8975.730728078168, 9041.593128309241, 6446.866051543052, 6632.303608121078, 6678.265390806911, 6699.564528305645, 6817.079694241214, 10784.622538211124, 11227.943860280646, 11537.942310288396, 11548.59579914699, 11567.595134170264, 11424.371659558472, 11624.011958983243, 11657.300561966309, 11659.88340116588, 11768.646940591796, 7753.263439973193, 7919.12889582157, 7976.042874855055, 8240.629171687408, 8415.284700800501, 9503.0, 9508.667196648084, 9510.524473776422, 9540.427574345562, 9572.425654460754, 8092.109867914643, 8245.051819040887, 8283.668653253802, 8383.455075420079, 8477.448965817202, 8099.617011090608, 8226.753197404087, 8603.09667484905, 8617.784555386166, 8732.0, 7322.3775979042375, 7336.339729424378, 7351.081114860643, 7354.4484163689, 7369.447291453055, 5529.087700529529, 5629.240052592848, 5691.77232910679, 5841.678707671119, 5843.094320380348, 5591.21722958778, 5754.539636829128, 5779.133130030361, 5839.5036421904615, 5862.853428664317], "value": 4951.83912732548, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.9287645094609, 341.31566209749457, 346.42666387129276, 348.23388545200555, 352.8006676227931, 5396.514313711765, 5439.3200849893765, 5558.610897237195, 5590.972045139773, 5635.718214089295, 5476.343973173008, 5478.91781623275, 5514.979728750184, 5520.309961254843, 5522.171674248864, 4342.956570434302, 4355.520892701808, 4381.671374646899, 4389.4513185851765, 4391.604755572014, 4143.171365726852, 4178.310578754502, 4210.799922022225, 4326.632236259922, 4362.520122786491, 4275.636570891478, 4277.3156294992805, 4284.657227421799, 4294.892627684318, 4307.353896915456, 4312.762798046107, 4323.762193079364, 4356.150550642626, 4367.104743527601, 4369.257226271541, 3603.1352475405974, 3757.229767897574, 3767.472553842464, 3845.8269377878064, 3859.9614003859883, 5280.709560974145, 5305.408377486777, 5342.1452567589, 5342.385625653061, 5368.926214757064, 4933.45731969483, 4941.604671626261, 4953.727544985024, 4971.726555039471, 4992.525710057539, 5611.158326251095, 5845.824625261249, 5856.882862342772, 5857.619254748471, 5861.765529378819, 4110.671146308318, 4112.814923328458, 4117.608827161415, 4130.648894843942, 4136.793160341971, 5762.456089504924, 6075.48358389533, 6100.328963813977, 6101.0, 6109.938900610981, 4176.2691528982305, 4180.498340199196, 4183.100633363842, 4205.558416366238, 4208.726432781859, 4201.70588058833, 4222.577742225752, 4237.809298581571, 4238.109996900624, 4253.80857861397, 4923.704577725348, 4930.457649658535, 4930.9013819723405, 4932.8520144395725, 4959.594596620636, 5592.328920529562, 5604.663720176803, 5683.488486036276, 5894.233749612523, 5900.1739756434135, 1468.4713503138862, 1473.7052589482146, 1477.062065588359, 1482.7553453680023, 1485.613740427475, 911.5852287209303, 919.7470695558775, 936.7611259129014, 943.2029934705163, 952.6046690623314, 5055.797768089309, 5077.746112694351, 5120.155174396257, 5130.589552835764, 5141.357330333708, 5272.971770504715, 5348.732563371816, 5351.0736134991375, 5396.514313711707, 5409.702466364388, 5068.6451948364, 5109.821156259513, 5113.284140220372, 5283.147100565729, 5813.186153938452, 3213.7268332192025, 3225.7903236290035, 3229.1442767666585, 3235.9838200809318, 3239.174010627275, 6023.668698221552, 6218.19163508741, 6243.500519958392, 6250.343713910065, 6262.436380725757, 6141.201643786369, 6149.815505534841, 6152.692365381713, 6156.630602163885, 6162.383761623892, 6229.844253893689, 6231.75072997075, 6241.75032998684, 6262.434391402149, 6263.311035785969, 5819.70901454934, 6031.7587296507645, 6034.637921724535, 6038.758449661965, 6042.039400693717, 5946.98901186808, 5967.0, 5968.313643931004, 5976.820695379146, 5995.820125396245, 5954.374790647093, 6197.96901015492, 6222.693234420823, 6224.035274532454, 6250.843593934955, 4145.756273118112, 4176.60322269384, 4178.770167640809, 4194.322271091588, 4202.978985105055, 5784.0, 5879.617824841372, 6008.536500034965, 6016.669083200466, 6066.757329706762, 5875.794347197828, 5902.439268269508, 5911.91132133027, 5920.822375328747, 5922.881542369215, 5737.713114344349, 5854.619449735754, 5865.384134665969, 5876.588638795243, 5882.176495290748, 5466.863328416822, 5729.197912292366, 5793.681347525927, 5799.362070172362, 5807.94192058074, 5837.737301821343, 5838.416158384298, 5871.823845284648, 5886.323072846506, 5893.2044174035955, 5482.588805839658, 5752.741126649395, 5776.797812076557, 5782.624129431573, 5782.971085144547], "value": 4399.512698837961, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9965000174998, 742.261449857392, 753.8040109571509, 845.8562044452442, 878.5036454403262, 15031.09813411196, 15730.134842583653, 16052.75920861188, 16092.83907160928, 16109.194540272998, 15061.924690376532, 15264.236788160602, 15292.694146117072, 15340.309686064125, 15357.0, 721.9927800721978, 748.8352562436255, 751.031169790969, 864.7103220421167, 878.3456325037856, 744.8696478116308, 848.1306660672798, 884.5533005832078, 887.4010043220833, 889.0265159650205, 914.6067191107821, 918.2975024106564, 921.8525035994243, 925.3060204846365, 925.9768505787342, 757.1633345153581, 916.6699988004314, 916.8487199612056, 917.9678711245108, 918.5407296351824, 650.177525430331, 676.2426082787309, 677.1467950382504, 678.0947435174048, 678.396227357652, 14905.478308259268, 14921.253937303092, 15013.324400402009, 15039.0, 15054.548363549111, 14453.494127705584, 15134.167620780852, 15155.318010689436, 15229.61925951849, 15337.616559585987, 13211.79956923479, 13563.118397304144, 13586.116902401312, 13887.58337249884, 14483.42066317346, 4721.102990431807, 4737.597304229144, 4757.405324334458, 4808.471068182497, 4853.830115945961, 74549.62725186393, 75711.0, 77912.0, 78456.0, 79038.0, 11319.207655464039, 11703.414829258503, 11818.82271765907, 12496.437660305308, 12716.491340346283, 23526.176583819484, 23563.528729425314, 23704.762952370602, 23784.643230351558, 23850.284491465285, 8177.100518942912, 8297.95851020753, 8559.34015124022, 8759.693410730593, 8850.026497085315, 4564.246899261651, 4613.331066995267, 4638.466576343763, 4816.470188279286, 4849.95150048495, 3602.8558857645926, 3612.223371975038, 3674.5590529136675, 3711.480392744964, 3717.3308804415465, 661.8213082467706, 662.059874977533, 676.4047638078558, 680.9761658341935, 689.2315068698465, 598.5271635408066, 611.9020956646915, 647.9761976077785, 704.844934114494, 721.0770214125927, 10033.0, 10287.125594324567, 10326.535305911251, 10364.948175259075, 10510.316829406063, 604.8185544336682, 642.9774957876452, 688.8656711941223, 697.7174244431013, 698.5215127637584, 4237.851675191354, 4324.783760811947, 4413.095315460323, 4456.041950980555, 4482.731036137842, 73280.63359683275, 74799.62600187073, 74846.25153748502, 110420.44789776, 118721.0, 65712.67143664346, 82589.58705206556, 97109.5144524287, 98315.0, 100433.0, 85468.57265713756, 87327.12672873317, 88282.0, 89276.10723892807, 89906.0, 9338.159565639126, 9517.0, 9552.14030737237, 9555.952220239133, 9622.615095396106, 45246.321305179794, 46367.53632463767, 46645.76677116593, 46669.76665116653, 51291.743541282056, 76261.23738762764, 76360.61819691089, 77221.0, 78882.21117788748, 80195.59902200452, 12700.425944142982, 12730.745385092432, 12926.806097908728, 12944.870551294744, 13517.864821352054, 14725.0, 14795.260236987891, 15105.0, 15235.847641523444, 15261.389544418098, 11169.0, 13662.453501860213, 13708.93145534266, 13736.0, 14421.711565768835, 13154.473821047051, 13561.321933903067, 13748.93125534366, 14540.854591454374, 14947.551573452814, 14248.430062797372, 14611.780823287447, 14724.411023558938, 15206.923965380545, 15357.155356455502, 11233.438328083726, 11728.296302221554, 12023.21849079807, 12109.455074521844, 12109.515619375126, 11933.224340418192, 12116.697082573006, 12369.505219791108, 12390.070744694003, 12692.111552191627], "value": 7713.310221953459, "unit": "score"}], "name": "dromaeo_css", "value": 7483.712433460224, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 110.0, 110.0, 110.0, 108.0, 109.0, 110.0, 109.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 127.0, 173.0, 140.0, 129.0, 151.0, 126.0, 156.0, 125.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 160.0, 160.0, 159.0, 159.0, 162.0, 160.0, 163.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 74.0, 123.0, 74.0, 75.0, 75.0, 74.0, 104.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 112.0, 85.0, 85.0, 85.0, 110.0, 84.0, 84.0, 121.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 145.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 52.0, 49.0, 51.0, 51.0, 50.0, 53.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 77.0, 76.0, 78.0, 77.0, 79.0, 103.0, 77.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 126.0, 118.0, 117.0, 117.0, 116.0, 121.0, 119.0, 121.0], "value": 119.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 153.0, 155.0, 153.0, 157.0, 155.0, 155.0, 156.0, 153.0, 153.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 62.0, 63.0, 62.0, 62.0, 61.0, 63.0, 63.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1469.4, "alertThreshold": 2.0}]} 09:29:07 INFO - Return code: 0 09:29:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:07 ERROR - # TBPL SUCCESS # 09:29:07 INFO - Running post-action listener: _resource_record_post_action 09:29:07 INFO - ##### 09:29:07 INFO - ##### Finished run-tests step (success) 09:29:07 INFO - ##### 09:29:07 INFO - Running post-run listener: _resource_record_post_run 09:29:07 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 266551296; Read time: 4; Write time: 325856 09:29:07 INFO - TinderboxPrint: CPU usage
13.1% 09:29:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 09:29:07 INFO - TinderboxPrint: I/O write bytes / time
266,551,296 / 325,856 09:29:07 INFO - TinderboxPrint: CPU idle
8,260.5 (86.9%) 09:29:07 INFO - TinderboxPrint: CPU user
1,198.9 (12.6%) 09:29:07 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 20639744; Read time: 0; Write time: 7700 09:29:07 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 245907456; Read time: 4; Write time: 317112 09:29:07 INFO - Running post-run listener: _upload_blobber_files 09:29:07 INFO - Blob upload gear active. 09:29:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:09 INFO - (blobuploader) - INFO - Done attempting. 09:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:12 INFO - (blobuploader) - INFO - Done attempting. 09:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:13 INFO - (blobuploader) - INFO - Done attempting. 09:29:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:13 INFO - Return code: 0 09:29:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9af094831501418ea2b572672986117e379dbb915e218a04ac43965c36d9767e94c5c807913c0b15a369077c2e24e6ba8be81f8a1400e225ce9924b5dbdde6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5eb1e16df382bd7b05f32eceaac07063fc4a38e2ff5a8f633ab0eb62904edf0292ee1608fcbc1dba1afcbf0a42e70c628f8bf8162ef35738ab2ca736d0f3de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27c414ffc821d784ecea1ff6be48a2cd11ffaa49c2745b3afecb0115e40935b0b32dcec83fd75cf4128a5f05338a9c741444a13ef1cada9b75a010e92fb64b6e"} 09:29:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:13 INFO - Contents: 09:29:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9af094831501418ea2b572672986117e379dbb915e218a04ac43965c36d9767e94c5c807913c0b15a369077c2e24e6ba8be81f8a1400e225ce9924b5dbdde6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5eb1e16df382bd7b05f32eceaac07063fc4a38e2ff5a8f633ab0eb62904edf0292ee1608fcbc1dba1afcbf0a42e70c628f8bf8162ef35738ab2ca736d0f3de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27c414ffc821d784ecea1ff6be48a2cd11ffaa49c2745b3afecb0115e40935b0b32dcec83fd75cf4128a5f05338a9c741444a13ef1cada9b75a010e92fb64b6e"} 09:29:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:13 INFO - Copying logs to upload dir... 09:29:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.764227 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-08 09:29:13.674298) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-08 09:29:13.678001) ========= 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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 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/bf9af094831501418ea2b572672986117e379dbb915e218a04ac43965c36d9767e94c5c807913c0b15a369077c2e24e6ba8be81f8a1400e225ce9924b5dbdde6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5eb1e16df382bd7b05f32eceaac07063fc4a38e2ff5a8f633ab0eb62904edf0292ee1608fcbc1dba1afcbf0a42e70c628f8bf8162ef35738ab2ca736d0f3de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27c414ffc821d784ecea1ff6be48a2cd11ffaa49c2745b3afecb0115e40935b0b32dcec83fd75cf4128a5f05338a9c741444a13ef1cada9b75a010e92fb64b6e"} build_url:https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/OxBHYH8JQuS--nZ5D8ygUA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9af094831501418ea2b572672986117e379dbb915e218a04ac43965c36d9767e94c5c807913c0b15a369077c2e24e6ba8be81f8a1400e225ce9924b5dbdde6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5eb1e16df382bd7b05f32eceaac07063fc4a38e2ff5a8f633ab0eb62904edf0292ee1608fcbc1dba1afcbf0a42e70c628f8bf8162ef35738ab2ca736d0f3de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27c414ffc821d784ecea1ff6be48a2cd11ffaa49c2745b3afecb0115e40935b0b32dcec83fd75cf4128a5f05338a9c741444a13ef1cada9b75a010e92fb64b6e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-08 09:29:13.703350) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:29:13.703710) ========= 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=8783db0840cc980e80bb0bd400000236-1465399848.952222-141234631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004391 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-08 09:29:13.791333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-08 09:29:13.791955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-08 09:29:13.792411) ========= ========= Total master_lag: 0.22 =========