builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-044 starttime: 1464930017.67 results: success (0) buildid: 20160602193138 builduid: 9a59e4d19a5a4d89b490b9986f12fa77 revision: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.666313) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.666849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.667284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.684434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.684714) ========= 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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 22:00:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 7.99M=0.001s 2016-06-02 22:00:17 (7.99 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098836 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.798090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.798403) ========= 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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017076 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.850401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:17.850763) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c5c9bc65a408fa0c26ec742c0950b6fc484cb635 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c5c9bc65a408fa0c26ec742c0950b6fc484cb635 --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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 22:00:18,050 truncating revision to first 12 chars 2016-06-02 22:00:18,050 Setting DEBUG logging. 2016-06-02 22:00:18,051 attempt 1/10 2016-06-02 22:00:18,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c5c9bc65a408?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 22:00:18,526 unpacking tar archive at: mozilla-inbound-c5c9bc65a408/testing/mozharness/ program finished with exit code 0 elapsedTime=0.835583 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:18.700230) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:18.700569) ========= script_repo_revision: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:18.700977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:18.701254) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:18.712668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-02 22:00:18.712959) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:18 INFO - MultiFileLogger online at 20160602 22:00:18 in /builds/slave/test 22:00:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:00:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:18 INFO - {'append_to_log': False, 22:00:18 INFO - 'base_work_dir': '/builds/slave/test', 22:00:18 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:18 INFO - 'branch': 'Mozilla-Inbound', 22:00:18 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:18 INFO - 'config_files': ('talos/linux_config.py',), 22:00:18 INFO - 'default_actions': ('clobber', 22:00:18 INFO - 'read-buildbot-config', 22:00:18 INFO - 'download-and-extract', 22:00:18 INFO - 'populate-webroot', 22:00:18 INFO - 'create-virtualenv', 22:00:18 INFO - 'install', 22:00:18 INFO - 'run-tests'), 22:00:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:18 INFO - 'download_minidump_stackwalk': True, 22:00:18 INFO - 'download_symbols': 'ondemand', 22:00:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:19 INFO - 'installer_path': 'installer.exe', 22:00:19 INFO - 'log_level': 'info', 22:00:19 INFO - 'log_name': 'talos', 22:00:19 INFO - 'log_to_console': True, 22:00:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:19 INFO - 'opt_config_files': (), 22:00:19 INFO - 'pip_index': False, 22:00:19 INFO - 'sps_profile': False, 22:00:19 INFO - 'sps_profile_interval': 0, 22:00:19 INFO - 'suite': 'dromaeojs-e10s', 22:00:19 INFO - 'system_bits': '32', 22:00:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:19 INFO - 'title': 'talos-linux64-ix-044', 22:00:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:19 INFO - 'use_talos_json': True, 22:00:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:19 INFO - 'work_dir': 'build'} 22:00:19 INFO - ##### 22:00:19 INFO - ##### Running clobber step. 22:00:19 INFO - ##### 22:00:19 INFO - Running pre-action listener: _resource_record_pre_action 22:00:19 INFO - Running main action method: clobber 22:00:19 INFO - rmtree: /builds/slave/test/build 22:00:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:19 INFO - Running post-action listener: _resource_record_post_action 22:00:19 INFO - ##### 22:00:19 INFO - ##### Finished clobber step (success) 22:00:19 INFO - ##### 22:00:19 INFO - ##### 22:00:19 INFO - ##### Running read-buildbot-config step. 22:00:19 INFO - ##### 22:00:19 INFO - Running pre-action listener: _resource_record_pre_action 22:00:19 INFO - Running main action method: read_buildbot_config 22:00:19 INFO - Using buildbot properties: 22:00:19 INFO - { 22:00:19 INFO - "project": "", 22:00:19 INFO - "product": "firefox", 22:00:19 INFO - "script_repo_revision": "production", 22:00:19 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:00:19 INFO - "repository": "", 22:00:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:00:19 INFO - "buildid": "20160602193138", 22:00:19 INFO - "pgo_build": "True", 22:00:19 INFO - "basedir": "/builds/slave/test", 22:00:19 INFO - "buildnumber": 649, 22:00:19 INFO - "slavename": "talos-linux64-ix-044", 22:00:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:00:19 INFO - "platform": "ubuntu64_hw", 22:00:19 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:19 INFO - "branch": "mozilla-inbound", 22:00:19 INFO - "repo_path": "integration/mozilla-inbound", 22:00:19 INFO - "revision": "c5c9bc65a408fa0c26ec742c0950b6fc484cb635", 22:00:19 INFO - "stage_platform": "linux64-pgo", 22:00:19 INFO - "builduid": "9a59e4d19a5a4d89b490b9986f12fa77", 22:00:19 INFO - "slavebuilddir": "test" 22:00:19 INFO - } 22:00:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:00:19 INFO - Running post-action listener: _resource_record_post_action 22:00:19 INFO - ##### 22:00:19 INFO - ##### Finished read-buildbot-config step (success) 22:00:19 INFO - ##### 22:00:19 INFO - ##### 22:00:19 INFO - ##### Running download-and-extract step. 22:00:19 INFO - ##### 22:00:19 INFO - Running pre-action listener: _resource_record_pre_action 22:00:19 INFO - Running main action method: download_and_extract 22:00:19 INFO - mkdir: /builds/slave/test/build/tests 22:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:19 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:00:20 INFO - Downloaded 1361 bytes. 22:00:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:20 INFO - Using the following test package requirements: 22:00:20 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'jsshell-linux-x86_64.zip'], 22:00:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:20 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:21 INFO - Downloaded 24424251 bytes. 22:00:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:22 INFO - Return code: 0 22:00:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:00:22 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')]} 22:00:22 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:23 INFO - Downloaded 24424251 bytes. 22:00:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:25 INFO - Return code: 0 22:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:25 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:00:27 INFO - Downloaded 10833369 bytes. 22:00:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:00:27 INFO - Return code: 0 22:00:27 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')]} 22:00:27 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:00:30 INFO - Downloaded 59144868 bytes. 22:00:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:30 INFO - mkdir: /builds/slave/test/properties 22:00:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:30 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:30 INFO - Contents: 22:00:30 INFO - build_url:https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:31 INFO - Running post-action listener: _resource_record_post_action 22:00:31 INFO - Running post-action listener: set_extra_try_arguments 22:00:31 INFO - ##### 22:00:31 INFO - ##### Finished download-and-extract step (success) 22:00:31 INFO - ##### 22:00:31 INFO - ##### 22:00:31 INFO - ##### Running populate-webroot step. 22:00:31 INFO - ##### 22:00:31 INFO - Running pre-action listener: _resource_record_pre_action 22:00:31 INFO - Running main action method: populate_webroot 22:00:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:31 INFO - '--tppagecycles', 22:00:31 INFO - '7'], 22:00:31 INFO - 'tests': ['tsvgm']}}, 22:00:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['damp', 'tps']}, 22:00:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'tests': ['damp', 'tps']}, 22:00:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['dromaeo_dom']}, 22:00:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['a11yr', 22:00:31 INFO - 'ts_paint', 22:00:31 INFO - 'tpaint', 22:00:31 INFO - 'sessionrestore', 22:00:31 INFO - 'sessionrestore_no_auto_restore', 22:00:31 INFO - 'tabpaint']}, 22:00:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['tsvgx', 22:00:31 INFO - 'tsvgr_opacity', 22:00:31 INFO - 'tart', 22:00:31 INFO - 'tscrollx', 22:00:31 INFO - 'cart']}, 22:00:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:31 INFO - 'tsvgr_opacity', 22:00:31 INFO - 'tart', 22:00:31 INFO - 'tscrollx', 22:00:31 INFO - 'cart']}, 22:00:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'talos_options': ['--disable-e10s'], 22:00:31 INFO - 'tests': ['tp5o']}, 22:00:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'tests': ['tp5o']}, 22:00:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'talos_options': ['--disable-e10s', 22:00:31 INFO - '--xperf_path', 22:00:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:31 INFO - 'tests': ['tp5n']}, 22:00:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:31 INFO - 'talos_options': ['--xperf_path', 22:00:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:31 INFO - 'tests': ['tp5n']}}, 22:00:31 INFO - 'talos.zip': {'path': '', 22:00:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:31 INFO - Running post-action listener: _resource_record_post_action 22:00:31 INFO - ##### 22:00:31 INFO - ##### Finished populate-webroot step (success) 22:00:31 INFO - ##### 22:00:31 INFO - ##### 22:00:31 INFO - ##### Running create-virtualenv step. 22:00:31 INFO - ##### 22:00:31 INFO - Running pre-action listener: _resource_record_pre_action 22:00:31 INFO - Running main action method: create_virtualenv 22:00:31 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:31 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 22:00:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:00:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:31 INFO - Using real prefix '/usr' 22:00:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:00:31 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:33 INFO - Installing pip.................done. 22:00:33 INFO - Return code: 0 22:00:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:00:33 INFO - Using env: {'DISPLAY': ':0', 22:00:33 INFO - 'HOME': '/home/cltbld', 22:00:33 INFO - 'LANG': 'en_US.UTF-8', 22:00:33 INFO - 'LANGUAGE': 'en_US:en', 22:00:33 INFO - 'LOGNAME': 'cltbld', 22:00:33 INFO - 'MAIL': '/var/mail/cltbld', 22:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:33 INFO - 'MOZ_NO_REMOTE': '1', 22:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:33 INFO - 'NO_EM_RESTART': '1', 22:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:33 INFO - 'PWD': '/builds/slave/test', 22:00:33 INFO - 'SHELL': '/bin/bash', 22:00:33 INFO - 'SHLVL': '1', 22:00:33 INFO - 'TERM': 'linux', 22:00:33 INFO - 'TMOUT': '86400', 22:00:33 INFO - 'USER': 'cltbld', 22:00:33 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:33 INFO - '_': '/tools/buildbot/bin/python'} 22:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:33 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 22:00:33 INFO - Cleaning up... 22:00:33 INFO - Return code: 0 22:00:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:00:33 INFO - Using env: {'DISPLAY': ':0', 22:00:33 INFO - 'HOME': '/home/cltbld', 22:00:33 INFO - 'LANG': 'en_US.UTF-8', 22:00:33 INFO - 'LANGUAGE': 'en_US:en', 22:00:33 INFO - 'LOGNAME': 'cltbld', 22:00:33 INFO - 'MAIL': '/var/mail/cltbld', 22:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:33 INFO - 'MOZ_NO_REMOTE': '1', 22:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:33 INFO - 'NO_EM_RESTART': '1', 22:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:33 INFO - 'PWD': '/builds/slave/test', 22:00:33 INFO - 'SHELL': '/bin/bash', 22:00:33 INFO - 'SHLVL': '1', 22:00:33 INFO - 'TERM': 'linux', 22:00:33 INFO - 'TMOUT': '86400', 22:00:33 INFO - 'USER': 'cltbld', 22:00:33 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:33 INFO - '_': '/tools/buildbot/bin/python'} 22:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:34 INFO - Downloading/unpacking psutil>=3.1.1 22:00:34 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 22:00:34 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 22:00:34 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 22:00:34 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 22:00:34 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 22:00:34 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 22:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:36 INFO - Installing collected packages: psutil 22:00:36 INFO - Running setup.py install for psutil 22:00:36 INFO - building 'psutil._psutil_linux' extension 22:00:36 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 22:00:36 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 22:00:36 INFO - building 'psutil._psutil_posix' extension 22:00:36 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 22:00:36 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 22:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:36 INFO - Successfully installed psutil 22:00:36 INFO - Cleaning up... 22:00:36 INFO - Return code: 0 22:00:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:00:36 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')]} 22:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:36 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')]} 22:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:36 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:36 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 22:00:36 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 22:00:36 INFO - Using env: {'DISPLAY': ':0', 22:00:36 INFO - 'HOME': '/home/cltbld', 22:00:36 INFO - 'LANG': 'en_US.UTF-8', 22:00:36 INFO - 'LANGUAGE': 'en_US:en', 22:00:36 INFO - 'LOGNAME': 'cltbld', 22:00:36 INFO - 'MAIL': '/var/mail/cltbld', 22:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:36 INFO - 'MOZ_NO_REMOTE': '1', 22:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:36 INFO - 'NO_EM_RESTART': '1', 22:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:36 INFO - 'PWD': '/builds/slave/test', 22:00:36 INFO - 'SHELL': '/bin/bash', 22:00:36 INFO - 'SHLVL': '1', 22:00:36 INFO - 'TERM': 'linux', 22:00:36 INFO - 'TMOUT': '86400', 22:00:36 INFO - 'USER': 'cltbld', 22:00:36 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:36 INFO - '_': '/tools/buildbot/bin/python'} 22:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:00:38 INFO - Installing collected packages: mozsystemmonitor 22:00:38 INFO - Running setup.py install for mozsystemmonitor 22:00:38 INFO - Successfully installed mozsystemmonitor 22:00:38 INFO - Cleaning up... 22:00:39 INFO - Return code: 0 22:00:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:00:39 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')]} 22:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:39 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')]} 22:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:39 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:39 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 22:00:39 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 22:00:39 INFO - Using env: {'DISPLAY': ':0', 22:00:39 INFO - 'HOME': '/home/cltbld', 22:00:39 INFO - 'LANG': 'en_US.UTF-8', 22:00:39 INFO - 'LANGUAGE': 'en_US:en', 22:00:39 INFO - 'LOGNAME': 'cltbld', 22:00:39 INFO - 'MAIL': '/var/mail/cltbld', 22:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:39 INFO - 'MOZ_NO_REMOTE': '1', 22:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:39 INFO - 'NO_EM_RESTART': '1', 22:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:39 INFO - 'PWD': '/builds/slave/test', 22:00:39 INFO - 'SHELL': '/bin/bash', 22:00:39 INFO - 'SHLVL': '1', 22:00:39 INFO - 'TERM': 'linux', 22:00:39 INFO - 'TMOUT': '86400', 22:00:39 INFO - 'USER': 'cltbld', 22:00:39 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:39 INFO - '_': '/tools/buildbot/bin/python'} 22:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:00:39 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 22:00:39 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 22:00:39 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 22:00:39 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 22:00:39 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 22:00:39 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 22:00:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:41 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:41 INFO - warning: no files found matching '*.txt' 22:00:41 INFO - no previously-included directories found matching 'build' 22:00:41 INFO - no previously-included directories found matching 'dist' 22:00:41 INFO - no previously-included directories found matching '.git*' 22:00:41 INFO - Installing collected packages: jsonschema, functools32 22:00:41 INFO - Running setup.py install for functools32 22:00:41 INFO - warning: no files found matching '*.txt' 22:00:41 INFO - no previously-included directories found matching 'build' 22:00:41 INFO - no previously-included directories found matching 'dist' 22:00:41 INFO - no previously-included directories found matching '.git*' 22:00:41 INFO - Successfully installed jsonschema functools32 22:00:41 INFO - Cleaning up... 22:00:41 INFO - Return code: 0 22:00:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:00:41 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')]} 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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')]} 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:41 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 22:00:41 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 22:00:41 INFO - Using env: {'DISPLAY': ':0', 22:00:41 INFO - 'HOME': '/home/cltbld', 22:00:41 INFO - 'LANG': 'en_US.UTF-8', 22:00:41 INFO - 'LANGUAGE': 'en_US:en', 22:00:41 INFO - 'LOGNAME': 'cltbld', 22:00:41 INFO - 'MAIL': '/var/mail/cltbld', 22:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:41 INFO - 'MOZ_NO_REMOTE': '1', 22:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:41 INFO - 'NO_EM_RESTART': '1', 22:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:41 INFO - 'PWD': '/builds/slave/test', 22:00:41 INFO - 'SHELL': '/bin/bash', 22:00:41 INFO - 'SHLVL': '1', 22:00:41 INFO - 'TERM': 'linux', 22:00:41 INFO - 'TMOUT': '86400', 22:00:41 INFO - 'USER': 'cltbld', 22:00:41 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:41 INFO - '_': '/tools/buildbot/bin/python'} 22:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:41 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:43 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:43 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 22:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:00:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:44 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 22:00:44 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 22:00:44 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 22:00:44 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 22:00:44 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 22:00:44 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 22:00:44 INFO - Downloading docopt-0.6.1.tar.gz 22:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:44 INFO - Running setup.py install for blobuploader 22:00:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:00:44 INFO - Running setup.py install for requests 22:00:44 INFO - Running setup.py install for docopt 22:00:45 INFO - Successfully installed blobuploader requests docopt 22:00:45 INFO - Cleaning up... 22:00:45 INFO - Return code: 0 22:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:45 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 22:00:45 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 22:00:45 INFO - Using env: {'DISPLAY': ':0', 22:00:45 INFO - 'HOME': '/home/cltbld', 22:00:45 INFO - 'LANG': 'en_US.UTF-8', 22:00:45 INFO - 'LANGUAGE': 'en_US:en', 22:00:45 INFO - 'LOGNAME': 'cltbld', 22:00:45 INFO - 'MAIL': '/var/mail/cltbld', 22:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:45 INFO - 'MOZ_NO_REMOTE': '1', 22:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:45 INFO - 'NO_EM_RESTART': '1', 22:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:45 INFO - 'PWD': '/builds/slave/test', 22:00:45 INFO - 'SHELL': '/bin/bash', 22:00:45 INFO - 'SHLVL': '1', 22:00:45 INFO - 'TERM': 'linux', 22:00:45 INFO - 'TMOUT': '86400', 22:00:45 INFO - 'USER': 'cltbld', 22:00:45 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:45 INFO - '_': '/tools/buildbot/bin/python'} 22:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:45 INFO - Running setup.py (path:/tmp/pip-xmK31w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:45 INFO - Running setup.py (path:/tmp/pip-pB_rX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:45 INFO - Running setup.py (path:/tmp/pip-zZFThh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:45 INFO - Running setup.py (path:/tmp/pip-DmrBLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:45 INFO - Running setup.py (path:/tmp/pip-mfGr6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:45 INFO - Running setup.py (path:/tmp/pip-qWjhqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:45 INFO - Running setup.py (path:/tmp/pip-F2KfP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:45 INFO - Running setup.py (path:/tmp/pip-Nkdvan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:45 INFO - Running setup.py (path:/tmp/pip-ChFyNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:45 INFO - Running setup.py (path:/tmp/pip-jT3H3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:46 INFO - Running setup.py (path:/tmp/pip-4X9NFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:46 INFO - Running setup.py (path:/tmp/pip-VQJTWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:46 INFO - Running setup.py (path:/tmp/pip-XISpSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:46 INFO - Running setup.py (path:/tmp/pip-PNhPPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:46 INFO - Running setup.py (path:/tmp/pip-Q0J4GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:46 INFO - Running setup.py (path:/tmp/pip-2W4XcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:46 INFO - Running setup.py (path:/tmp/pip-Fsv8ZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:46 INFO - Running setup.py install for manifestparser 22:00:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:00:46 INFO - Running setup.py install for mozcrash 22:00:46 INFO - Running setup.py install for mozdebug 22:00:46 INFO - Running setup.py install for mozdevice 22:00:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:00:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:00:46 INFO - Running setup.py install for mozfile 22:00:47 INFO - Running setup.py install for mozhttpd 22:00:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for mozinfo 22:00:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for mozInstall 22:00:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for mozleak 22:00:47 INFO - Running setup.py install for mozlog 22:00:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for moznetwork 22:00:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for mozprocess 22:00:47 INFO - Running setup.py install for mozprofile 22:00:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:00:47 INFO - Running setup.py install for mozrunner 22:00:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:00:48 INFO - Running setup.py install for mozscreenshot 22:00:48 INFO - Running setup.py install for moztest 22:00:48 INFO - Running setup.py install for mozversion 22:00:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:00:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:48 INFO - Cleaning up... 22:00:48 INFO - Return code: 0 22:00:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:48 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')]} 22:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:48 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')]} 22:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:48 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:48 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 22:00:48 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 22:00:48 INFO - Using env: {'DISPLAY': ':0', 22:00:48 INFO - 'HOME': '/home/cltbld', 22:00:48 INFO - 'LANG': 'en_US.UTF-8', 22:00:48 INFO - 'LANGUAGE': 'en_US:en', 22:00:48 INFO - 'LOGNAME': 'cltbld', 22:00:48 INFO - 'MAIL': '/var/mail/cltbld', 22:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:48 INFO - 'MOZ_NO_REMOTE': '1', 22:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:48 INFO - 'NO_EM_RESTART': '1', 22:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:48 INFO - 'PWD': '/builds/slave/test', 22:00:48 INFO - 'SHELL': '/bin/bash', 22:00:48 INFO - 'SHLVL': '1', 22:00:48 INFO - 'TERM': 'linux', 22:00:48 INFO - 'TMOUT': '86400', 22:00:48 INFO - 'USER': 'cltbld', 22:00:48 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:48 INFO - '_': '/tools/buildbot/bin/python'} 22:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:48 INFO - Running setup.py (path:/tmp/pip-Fli2aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:48 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)) 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:48 INFO - Running setup.py (path:/tmp/pip-ksGR2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:48 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)) 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:48 INFO - Running setup.py (path:/tmp/pip-XksuS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:48 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)) 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:48 INFO - Running setup.py (path:/tmp/pip-6QU1HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:48 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)) 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:48 INFO - Running setup.py (path:/tmp/pip-buIH12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:48 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)) 22:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:48 INFO - Running setup.py (path:/tmp/pip-vqAd3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:49 INFO - Running setup.py (path:/tmp/pip-pZIfJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:49 INFO - Running setup.py (path:/tmp/pip-EHY2sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:49 INFO - Running setup.py (path:/tmp/pip-wHiTj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:49 INFO - Running setup.py (path:/tmp/pip-Yms6ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:49 INFO - Running setup.py (path:/tmp/pip-hbxIKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:49 INFO - Running setup.py (path:/tmp/pip-NusROX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:49 INFO - Running setup.py (path:/tmp/pip-0wcRvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:49 INFO - Running setup.py (path:/tmp/pip-eovelE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:49 INFO - Running setup.py (path:/tmp/pip-tCuUyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:49 INFO - Running setup.py (path:/tmp/pip-K6eCcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:49 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)) 22:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:49 INFO - Running setup.py (path:/tmp/pip-JuBzXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:49 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)) 22:00:49 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)) 22:00:49 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)) 22:00:49 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)) 22:00:49 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)) 22:00:49 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)) 22:00:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:00:49 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 22:00:49 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 22:00:49 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 22:00:49 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 22:00:49 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 22:00:49 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 22:00:51 INFO - Downloading blessings-1.6.tar.gz 22:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:51 INFO - Installing collected packages: blessings 22:00:51 INFO - Running setup.py install for blessings 22:00:51 INFO - Successfully installed blessings 22:00:51 INFO - Cleaning up... 22:00:51 INFO - Return code: 0 22:00:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:00:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:52 INFO - Reading from file tmpfile_stdout 22:00:52 INFO - Current package versions: 22:00:52 INFO - argparse == 1.2.1 22:00:52 INFO - blessings == 1.6 22:00:52 INFO - blobuploader == 1.2.4 22:00:52 INFO - docopt == 0.6.1 22:00:52 INFO - functools32 == 3.2.3-2 22:00:52 INFO - jsonschema == 2.5.1 22:00:52 INFO - manifestparser == 1.1 22:00:52 INFO - mozInstall == 1.12 22:00:52 INFO - mozcrash == 0.17 22:00:52 INFO - mozdebug == 0.1 22:00:52 INFO - mozdevice == 0.48 22:00:52 INFO - mozfile == 1.2 22:00:52 INFO - mozhttpd == 0.7 22:00:52 INFO - mozinfo == 0.9 22:00:52 INFO - mozleak == 0.1 22:00:52 INFO - mozlog == 3.2 22:00:52 INFO - moznetwork == 0.27 22:00:52 INFO - mozprocess == 0.22 22:00:52 INFO - mozprofile == 0.28 22:00:52 INFO - mozrunner == 6.11 22:00:52 INFO - mozscreenshot == 0.1 22:00:52 INFO - mozsystemmonitor == 0.3 22:00:52 INFO - moztest == 0.7 22:00:52 INFO - mozversion == 1.4 22:00:52 INFO - psutil == 3.1.1 22:00:52 INFO - requests == 1.2.3 22:00:52 INFO - wsgiref == 0.1.2 22:00:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:52 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 22:00:52 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 22:00:52 INFO - Using env: {'DISPLAY': ':0', 22:00:52 INFO - 'HOME': '/home/cltbld', 22:00:52 INFO - 'LANG': 'en_US.UTF-8', 22:00:52 INFO - 'LANGUAGE': 'en_US:en', 22:00:52 INFO - 'LOGNAME': 'cltbld', 22:00:52 INFO - 'MAIL': '/var/mail/cltbld', 22:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:52 INFO - 'MOZ_NO_REMOTE': '1', 22:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:52 INFO - 'NO_EM_RESTART': '1', 22:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:52 INFO - 'PWD': '/builds/slave/test', 22:00:52 INFO - 'SHELL': '/bin/bash', 22:00:52 INFO - 'SHLVL': '1', 22:00:52 INFO - 'TERM': 'linux', 22:00:52 INFO - 'TMOUT': '86400', 22:00:52 INFO - 'USER': 'cltbld', 22:00:52 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:52 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 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)) 22:00:52 INFO - Cleaning up... 22:00:52 INFO - Return code: 0 22:00:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:52 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 22:00:52 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 22:00:52 INFO - Using env: {'DISPLAY': ':0', 22:00:52 INFO - 'HOME': '/home/cltbld', 22:00:52 INFO - 'LANG': 'en_US.UTF-8', 22:00:52 INFO - 'LANGUAGE': 'en_US:en', 22:00:52 INFO - 'LOGNAME': 'cltbld', 22:00:52 INFO - 'MAIL': '/var/mail/cltbld', 22:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:52 INFO - 'MOZ_NO_REMOTE': '1', 22:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:52 INFO - 'NO_EM_RESTART': '1', 22:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:52 INFO - 'PWD': '/builds/slave/test', 22:00:52 INFO - 'SHELL': '/bin/bash', 22:00:52 INFO - 'SHLVL': '1', 22:00:52 INFO - 'TERM': 'linux', 22:00:52 INFO - 'TMOUT': '86400', 22:00:52 INFO - 'USER': 'cltbld', 22:00:52 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:52 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:00:52 INFO - Cleaning up... 22:00:52 INFO - Return code: 0 22:00:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 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 0x7f42af255f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, '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': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', '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 22:00:52 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 22:00:52 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 22:00:52 INFO - Using env: {'DISPLAY': ':0', 22:00:52 INFO - 'HOME': '/home/cltbld', 22:00:52 INFO - 'LANG': 'en_US.UTF-8', 22:00:52 INFO - 'LANGUAGE': 'en_US:en', 22:00:52 INFO - 'LOGNAME': 'cltbld', 22:00:52 INFO - 'MAIL': '/var/mail/cltbld', 22:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:52 INFO - 'MOZ_NO_REMOTE': '1', 22:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:52 INFO - 'NO_EM_RESTART': '1', 22:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:52 INFO - 'PWD': '/builds/slave/test', 22:00:52 INFO - 'SHELL': '/bin/bash', 22:00:52 INFO - 'SHLVL': '1', 22:00:52 INFO - 'TERM': 'linux', 22:00:52 INFO - 'TMOUT': '86400', 22:00:52 INFO - 'USER': 'cltbld', 22:00:52 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:52 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 INFO - Downloading/unpacking flake8 22:00:52 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 22:00:52 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 22:00:52 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 22:00:52 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 22:00:52 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 22:00:52 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 22:00:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:00:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:00:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:00:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:55 INFO - no previously-included directories found matching 'docs/_build' 22:00:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:00:55 INFO - Running setup.py install for pep8 22:00:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:55 INFO - no previously-included directories found matching 'docs/_build' 22:00:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:00:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:00:55 INFO - Cleaning up... 22:00:55 INFO - Return code: 0 22:00:55 INFO - Running post-action listener: _resource_record_post_action 22:00:55 INFO - Running post-action listener: _start_resource_monitoring 22:00:55 INFO - Starting resource monitoring. 22:00:55 INFO - ##### 22:00:55 INFO - ##### Finished create-virtualenv step (success) 22:00:55 INFO - ##### 22:00:55 INFO - ##### 22:00:55 INFO - ##### Running install step. 22:00:55 INFO - ##### 22:00:55 INFO - Running pre-action listener: _resource_record_pre_action 22:00:55 INFO - Running main action method: install 22:00:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:55 INFO - Reading from file tmpfile_stdout 22:00:55 INFO - Detecting whether we're running mozinstall >=1.0... 22:00:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:00:55 INFO - Reading from file tmpfile_stdout 22:00:55 INFO - Output received: 22:00:55 INFO - Usage: mozinstall [options] installer 22:00:55 INFO - Options: 22:00:55 INFO - -h, --help show this help message and exit 22:00:55 INFO - -d DEST, --destination=DEST 22:00:55 INFO - Directory to install application into. [default: 22:00:55 INFO - "/builds/slave/test"] 22:00:55 INFO - --app=APP Application being installed. [default: firefox] 22:00:55 INFO - mkdir: /builds/slave/test/build/application 22:00:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:12 INFO - Reading from file tmpfile_stdout 22:01:12 INFO - Output received: 22:01:12 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:12 INFO - Running post-action listener: _resource_record_post_action 22:01:12 INFO - ##### 22:01:12 INFO - ##### Finished install step (success) 22:01:12 INFO - ##### 22:01:12 INFO - ##### 22:01:12 INFO - ##### Running run-tests step. 22:01:12 INFO - ##### 22:01:12 INFO - Running pre-action listener: _resource_record_pre_action 22:01:12 INFO - Running main action method: run_tests 22:01:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:12 INFO - Python 2.7.3 22:01:12 INFO - Return code: 0 22:01:12 INFO - grabbing minidump binary from tooltool 22:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:12 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 0x1c1e1c0>, '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 0x1c1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:12 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 22:01:12 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 22:01:12 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 22:01:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:12 INFO - Return code: 0 22:01:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:13 INFO - Return code: 0 22:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:01:13 INFO - Using env: {'DISPLAY': ':0', 22:01:13 INFO - 'HOME': '/home/cltbld', 22:01:13 INFO - 'LANG': 'en_US.UTF-8', 22:01:13 INFO - 'LANGUAGE': 'en_US:en', 22:01:13 INFO - 'LOGNAME': 'cltbld', 22:01:13 INFO - 'MAIL': '/var/mail/cltbld', 22:01:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:13 INFO - 'MOZ_NO_REMOTE': '1', 22:01:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:13 INFO - 'NO_EM_RESTART': '1', 22:01:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:13 INFO - 'PWD': '/builds/slave/test', 22:01:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:13 INFO - 'SHELL': '/bin/bash', 22:01:13 INFO - 'SHLVL': '1', 22:01:13 INFO - 'TERM': 'linux', 22:01:13 INFO - 'TMOUT': '86400', 22:01:13 INFO - 'USER': 'cltbld', 22:01:13 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708', 22:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:13 INFO - '_': '/tools/buildbot/bin/python'} 22:01:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:01:13 INFO - mozversion application_buildid: 20160602193138 22:01:13 INFO - mozversion application_changeset: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 22:01:13 INFO - mozversion application_display_name: Nightly 22:01:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:13 INFO - mozversion application_name: Firefox 22:01:13 INFO - mozversion application_remotingname: firefox 22:01:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:13 INFO - mozversion application_vendor: Mozilla 22:01:13 INFO - mozversion application_version: 49.0a1 22:01:13 INFO - mozversion platform_buildid: 20160602193138 22:01:13 INFO - mozversion platform_changeset: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 22:01:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:13 INFO - mozversion platform_version: 49.0a1 22:01:13 INFO - using testdate: 1464930073 22:01:13 INFO - actual date: 1464930073 22:01:13 INFO - starting webserver on 'localhost:51669' 22:01:13 INFO - SUITE-START | Running 2 tests 22:01:13 INFO - TEST-START | dromaeo_css 22:01:13 INFO - operating with platform_type : linux_ 22:01:13 INFO - Initialising browser for dromaeo_css test... 22:01:14 INFO - TEST-INFO | started process 30574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Ismn4/profile http://localhost:51669/getInfo.html) 22:01:18 INFO - PROCESS | 30574 | __metrics Screen width/height:1600/1200 22:01:18 INFO - PROCESS | 30574 | colorDepth:24 22:01:18 INFO - PROCESS | 30574 | Browser inner width/height: 1024/697 22:01:18 INFO - PROCESS | 30574 | __metrics 22:01:19 ERROR - PROCESS | 30574 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 22:01:20 INFO - TEST-INFO | 30574: exit 0 22:01:20 INFO - Browser initialized. 22:01:20 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:20 INFO - TEST-INFO | started process 30692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Ismn4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:32 INFO - PROCESS | 30692 | RSS: Main: 167641088 22:01:32 INFO - PROCESS | 30692 | 22:02:21 INFO - PROCESS | 30692 | 1464930141833 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] 22:04:29 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51669/tests/dromaeo/cssquery-ext.html) 22:04:30 INFO - PROCESS | 30692 | RSS: Main: 171278336 22:04:30 INFO - PROCESS | 30692 | 22:07:32 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-ext.html (next: http://localhost:51669/tests/dromaeo/cssquery-jquery.html) 22:07:32 INFO - PROCESS | 30692 | RSS: Main: 170209280 22:07:32 INFO - PROCESS | 30692 | 22:10:34 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51669/tests/dromaeo/cssquery-mootools.html) 22:10:35 INFO - PROCESS | 30692 | RSS: Main: 171540480 22:10:35 INFO - PROCESS | 30692 | 22:13:37 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51669/tests/dromaeo/cssquery-prototype.html) 22:13:37 INFO - PROCESS | 30692 | RSS: Main: 169046016 22:13:37 INFO - PROCESS | 30692 | 22:16:40 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51669/tests/dromaeo/cssquery-yui.html) 22:16:40 INFO - PROCESS | 30692 | RSS: Main: 171171840 22:16:40 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | Cycle 1(1): loaded http://localhost:51669/tests/dromaeo/cssquery-yui.html (next: http://localhost:51669/tests/dromaeo/cssquery-dojo.html) 22:19:43 INFO - PROCESS | 30692 | RSS: Main: 172298240 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | __start_tp_report 22:19:43 INFO - PROCESS | 30692 | _x_x_mozilla_page_load 22:19:43 INFO - PROCESS | 30692 | _x_x_mozilla_page_load_details 22:19:43 INFO - PROCESS | 30692 | |i|pagename|runs| 22:19:43 INFO - PROCESS | 30692 | |0;dojo.html;3183.4906414973602;3200.519922011698;3214.871405143794;3230.2731885325798;3245.0589329094564;5478.822053258554;5504.669719816814;5558.499735023842;5599.32808063033;5625.6905870177125;5346.652467589604;5466.562674986001;5560.304961879765;5629.296337957755;5634.859128521779;4975.626827987906;5000.049990501811;5043.445221025684;5081.644284900059;5150.227465880111;307.40032878469924;5065.797368105272;5149.639525233235;5163.432022477525;5167.250748641463;5026.321446604698;5058.140116180258;5079.3650793650795;5097.821576244833;5102.974485127569;5058.3929928408825;5072.6195535335;5095.592352611782;5113.054084994251;5141.434442211354;4899.044686286175;4965.851024469272;5003.3495645565845;5042.520960508746;5058.747062646853;5457.154141108134;5459.508644221999;5487.451254874481;5493.340799104093;5504.80733174337;5972.64164150148;5985.700714964234;5996.670183139926;5998.520118390518;6008.48927841134;5826.504747096502;5923.259592550931;5942.881142377171;5948.881022379528;6030.698465076773;5570;5576.86057848553;5581.581381396411;5582.972085139589;5589.2175095486255;5943.524518038546;6092.482139018233;6097.664628445478;6103.725332360054;6116.174316467309;5839.328477225088;5891.46976772084;5920.259967504062;5925.73334199962;5958.940410595926;6021.5182785377065;6069.453749162508;6069.605475644069;6074.271087469444;6086.817395478142;5730.627509211888;5779.393163717749;5953.136795164677;6024.457798798129;6032.457078862835;5722.170285308607;5844.94155058444;5845.649261044351;5943.07882278239;5988.28140623128;4479.865604031885;4485.618722408566;4508.391367165391;4519.81920723174;4560.042391097906;3976.840926362971;4014.9197016060098;4024.6176613221696;4045.37296719009;4088.448059511987;5630.465105814941;5644.153376993402;5682.602217844794;5703.201551782754;5715.371309155989;5989.460948514655;6002.339742628307;6004.609700369462;6060.636361818305;6071.301800293023;5564.998300305984;5685.630434021776;5710.486056254874;5717.142428635655;5735.11105778605;5144.897102057937;5195.168772996302;5220.9738951305;5231.71225582589;5257.684538927677;6296.024116261987;6313.305536390993;6322.462590679752;6322.9051564226575;6355.237371515448;5795.333536643171;6011.72947217385;6060.454559089703;6081.695915204222;6110.022396416552;6293.905591416224;6296.087067375113;6317.241930968313;6319.715612797527;6325.936740632535;5885.676287804041;5960.642361458326;5963.940360596339;5997.610155339889;6042.969785151046;5794.217780599565;5945.137954996415;5998.520118390606;6000.309964354155;6073.0283154695035;5865.266841644794;5878.794242201502;5889.970550147222;5890.352061273342;5898.351181370132;5767.740451679769;5883.382244864399;5890.764369425175;5893.351731309467;5913.113033045078;5502.862428439161;5540.362858271189;5611.158326251095;5648.943510564842;5672.914906276492;5784.21913041734;5822.94177058224;5834.737436815269;5878.059510478303;5902.852428689317;5731.340895797037;5747.626404283708;5765.077587585966;5799.913001305068;5835.67903765297 22:19:43 INFO - PROCESS | 30692 | |1;ext.html;568.9914651280229;614.42858141928;625.8654389306304;630.9652969086701;654.6693919570616;41119;42419.57580424195;42506.574934250646;42599.14801703968;46249.30626040612;42745.78627106868;43128.568714312925;43488.78255608725;43711.562884371146;43759.34360984588;8589.699360522367;8765.254953328975;8769.736907892742;8777.2100510954;8814;8690.261327787146;8722.956385218065;8766.912330876705;8783.692570760024;8793.516356600416;8722.34582406322;8738.257248133885;8756.474611523328;8788.8681669775;8839.425437346552;8712.912870871305;8759.605817738217;8785.472871627722;8804.735857924241;8815.51514666693;8591.656333746643;8632.913670863336;8648.913510864873;8684.175003374668;8685.782855428602;40906.386404203964;41346;41464.37803432951;41497.58502414998;41507.7924610378;3449.9827500862584;3539.1682954505673;3585.731070169748;3923.5684074751753;3957.525096988351;31081.533776993365;31101;31282.21794455134;31331.686683133106;31470.685293147006;4142.772147531885;4158.480189976253;4288.957110428918;4355.216061108999;4435;301964;302639;303218;303739.4813025943;304816;49405;49483.50516494861;49618.751906240235;49747;49813.750931245115;58381.12428313579;58472.70763646154;58759.412405876246;58808.705956470796;58827;15310.081395116329;15644.061356318658;15672;15700.136492492795;15702.057876527444;12350.567730129582;12375.876241237653;12389.442475088643;12402.875971240353;12428.13003089775;15072.54782356531;15714.607134821721;15767.132807695687;15828.44600438979;15923.566878980715;6196.318404975449;6237.719302631393;6339.175907132043;6446.194225721785;6520.119783829217;1042.6402891002592;1071.1805468816362;1142.2860712054965;1147.3460127727114;1209.800382936823;32203.194920127185;32238;32238.516422253684;32385.51421728676;32716.509252361233;1133.7664800343312;1160.5937921727327;1185.9051275897907;1202.2485946283573;1236.6475554467113;17212.655746884993;17561.278001909723;17638.735418968725;17707.911460442614;17719.29122835098;542651;543202;545135;549256;552749;58166.41833581695;59626.70186649126;59687;59875;59970.40029599648;1313.2186349122449;1402.3759427054863;1403.9432231780286;1422.3101795629111;1423.459085547506;30303.242418939702;32130.51804222892;32238.838805805823;32571.674283256863;32592.674073259914;51306.48693513017;51527.2270915944;51819;51895.22157167721;51991;458672;459011;459451;463319;464593;25479.23562293134;26032.609510856975;26143.73856261413;27592.448151036464;40820;46313;46653;47152.29271560861;47397.289040665106;48098.27852582144;39994.2001159981;40108.1978360437;40284.99287517835;41064;41121;39436.2112757749;40208.396874047496;40391.192176155724;40423.191536168524;41096.38355424611;39994;40430.19139617132;41198.38202427026;41519.1696166081;41805.1638967225;35361.29277414386;37530.437043444916;37794.43308350432;38208.80895595504;38281;28644.856775715987;28703.282417938884;28733.856330718212;29096;29229.269268268465 22:19:43 INFO - PROCESS | 30692 | |2;jquery.html;386.2526012166458;388.2235528942116;389.82457893947714;394.2174783055635;417.68464809069167;16856.494305170832;18085.819141808577;19450.41648750536;19597.804021959757;19795.406137815888;11553.960143587097;11575.189736718434;11607.883921160786;11868.169228154033;11885.465154068066;5189.325387699594;5389.9191512127345;5390.272313237702;5393.703346315951;5411.83764487064;5160.380754309488;5253.6322457428;5278.788848446057;5347.224652425396;5359.758810853521;5289.153735402318;5322.973385133088;5324.414314425429;5340.905114451548;5348.839534813962;5301.814436494743;5340.786368545254;5352.946470535284;5682.943170568283;5724.4561766632105;5043.4956504349275;5059.544640982329;5065.518775716301;5067.138586440277;5084.313617661605;13763.174209547358;13835.79246311306;13879.583612491642;13889.930550347284;19417;2079.70198906364;2192.331338941622;2262.7171603549555;2320.385097949045;2322.7212734471805;9571.37786043912;9688.176504997084;9772.755681108029;9835.754106147404;9856.211503079736;1892.6782446984046;1919.7504324437873;1949.8537609679188;1966.4395647240467;1988.7514060742408;61930.69034654798;70865;72202.63898680473;79222;83267;9897.554610042565;10251;10286.074253317238;10348.430836304076;10436.63471778484;13927.373268202955;13990.370433330525;14001.159930404208;14074.437022518985;14098.647533811532;7773.494722842997;7907.367410607138;7936.087349954713;7937.126916039231;8146;7551.320381165721;7656.157822639505;7714.382849372037;7782.299593036654;8138.633761480747;4355.934660980088;4358.36803663467;4359.934600980988;4473.261911784519;4539.296409056601;1851.7870444899008;1879.7838248601313;1919.7120431935043;1925.4512463947708;1925.6148770246007;973.9707808765748;978.0561757903657;989.5497548615363;1019.9847002294972;1042.1714736784274;17806.37677681275;18809.90595047016;19456.221751129797;19504;19737.50656233577;980.7401038724744;1011.0849681038671;1019.1235537437798;1033.4884232304908;1057.434272664121;10694.73263168427;10899.101493543882;10970.561177552969;10993.725156871142;11009.605293406174;439203;449757;486913;488916;492858;70557;87839.56080219558;89166;92049.53975230081;92154.53922730478;960.9471479068578;987.4272921705393;991.7520619845038;1006.3710181136789;1015.1168483419325;17581.560460988578;17600.471985840446;18038.819611803712;18117;18267.177976991334;41193.38209926793;41339.586604133576;41418;42152.78923605362;42940.78529607332;56449.43550564442;56847.71576142093;57951;58869;59167.11249331349;7287.8542429152185;8392.160783921803;8396.748097557083;8721.825563488568;8741.694040708544;12453;12459.813102803646;13265.86734132685;13329.066965312335;13677.794833077312;11371.123934269073;11374.715632109264;11472.598459053894;11570.421478926188;11600.18798684084;11497.08023358146;11522.423878806194;11598.71003224926;11735.589254376397;11885.34630595291;11907.642770716893;11933.28400295985;11964.282143071443;12143;12223.511059557519;7682.385409167365;7688.8462230756195;7742.574158421341;7841.529508229296;7896.921030789848;10691.465426728477;11680.591179308683;11732.765344692887;11762.529498819951;11782.234154779911 22:19:43 INFO - PROCESS | 30692 | |3;mootools.html;1513.7048275586258;1532.6934613077383;1693.8898971566848;1798.3346161920092;1801.1444563832174;7752.7674169775;8280.337572994162;8394.49633022019;8882.200601945831;8888.28893688505;8493.787655308604;8715.825683486326;8733.825323493542;8784.472931624092;8806;2293.5986202414592;2319.7332306784765;2336.2874323331375;2337.3572267626455;2339.4034521197073;2254.41385239838;2265.8187345012443;2291.1980806717597;2314.606516892124;2322.036354912709;2268.0700912625825;2278.4759505313787;2278.760730123338;2282.395165281202;2285.337252196861;2240.5294888073363;2268.274152271274;2297.0811675329833;2302.343832007878;2302.827287953402;2239.876806775627;2261.7964383205426;2274.8180145588312;2276.430892276931;2285.1773361589812;8671.089535598798;8690.435121717068;8730.956345218234;8744.956275218583;8769.868451973225;4963.677360971526;5087.364079490064;5099.872503187413;5105.693658380472;5114.079465696173;3065.432894914448;3158.2893848884046;3282.6717328267223;3303.620083690382;3311.072899588119;1098.7363032872054;1103.0844399148687;1109.9667009989632;1134.7219931116847;1138.6356365962895;12023.93988030063;12048.698782530419;12049.879501204963;12078.698032549257;12126.454309556091;4470.463544374696;4516.345129956204;4607.14767767964;4615.607673347736;4619.815207391666;10318.742031449123;10329.741756455998;10336.844947325797;10357.378557286587;10371.27401081917;5242.344706911636;5244.685318880879;5248.527632513092;5248.763805628756;5283.392409872837;4145.54399016108;4633.606143477842;4651.534846515389;4756.357891684647;4770.856874293777;3996.7002474813926;4014.494564538298;4046.817893194813;4092.5062352241216;4124.979375103166;1265.645619226618;1271.3134907150036;1289.1813698301457;1294.3140135728079;1298.805179223124;983.7589790501373;1017.3336464615688;1019.5972590826582;1024.938503689781;1044.0551301072542;6185.195924529782;6219.098230756515;6235.563510554309;6254.6247225166635;6298.968505157536;424.49061126648144;426.91248294099637;429.9054208074219;430.2127107393525;432.9285667864767;4429.667774916894;4442.977785111054;4455.353973673799;4457.175422546839;4461.420015397977;10129.290949633454;10492.003259690317;10535.209859260432;10588.364698118137;10614.310069845435;10022.799544009078;10554.366737995744;10613.9469302653;10624.787504250025;10684.305520141166;7694;7875.566843823644;7883.36933045355;7884.84230315402;7942.880856787037;6049.727762250621;6237.781677641261;6311.368863113836;6319.05214217871;6330.746770129143;10041.49792510357;10069.446180460145;10085.848712269175;10125.392476451141;10162;9805.166560842408;10063.798724025331;10262.743431414274;10298.3820970742;10360.119389851947;7111.217766045627;7440;7568.356689681439;7643.503172293783;7707.152213256425;8400.033996090528;8407.3694472916;8417.789555261168;8422.157784221528;8443.957780211305;7554.811129721801;7666.15672276038;7745.7676269712;7763.184865589032;7838.686452542062;7429.33136017761;7607.706689862847;7622.923770762443;7703.961480192564;7717.6141192939;6871.587704737732;6959.89560156588;6975.267596902455;7011.929880701127;7023.016777651031;5185.611079169153;5220.5823534117935;5415.702136382379;5423.403425623257;5475.0418676730615;5438.619296649196;5465.207544906046;5483.972580137074;5491.75287112073;5635.605507614428 22:19:43 INFO - PROCESS | 30692 | |4;prototype.html;331.4365578516522;334.6352475801376;337.330399157672;343.0326479328292;344.4420039535952;5580.86047848804;5715.742791574378;5725.828225153242;5766.654000759957;5786.71066446677;5623;5642.351129620095;5646.943530564693;5649.209110724501;5656.321241451032;4140.772257525835;4256.87229383119;4303.483581970168;4350.934735978947;4357.237483440401;4300.591443812833;4312.741235525878;4316.870493885189;4345.565443455661;4349.739015659071;4288.892777680568;4300.35494675798;4317;4321.157374312011;4325.307950727884;4218.240716670998;4265.615198494484;4315.697901146889;4321.416608757841;4327.653787696977;3807.9619203807883;3809.733318667694;3813.4470501777364;3819.3698039823394;3820.8853734387735;5395.271638328814;5426.782928682848;5451.809186678447;5457.863553411157;5486.91769623456;4925.581325587329;4955.331030310898;4986;4992.47579004203;5027.798888044474;5805.970970145165;5811.88376232473;5850.3564607893095;5868.706564671749;5913.674747888865;4193.769342686151;4226.619604235633;4227.894302642428;4228.746275223496;4231.788410579489;5750.971245143747;6082.847928801771;6086.269647642267;6094.299155597075;6127.417895299939;4110.232599982037;4112.835486580563;4120.362967700614;4126.793660316972;4128.710990230655;4155.2728272552185;4183.937240941388;4186.476690413699;4200.928763165434;4206.85276015338;4752.7861246244;4856.053069651384;4859.1496488114435;4869.928615704539;4897.951020489821;5884.29355486904;5887.528117970507;5901.262342207224;5912.9113063304085;6010.338862725097;1477.5493474490384;1494.0438119603461;1495.3794175417297;1496.7231062253518;1496.9176695281647;903.9050899655441;916.6616739560317;917.3165991336364;924.990750092504;929.7396728915915;5085.847424577269;5103.642745007888;5108.518529626367;5172.431032586413;5182.600697811645;5306.442823503554;5383.81156659515;5387.703676297763;5464.836054918359;5537.307836520435;4964.652474326763;5121.308623335802;5187.507186817246;5188.247704082887;5211.400688920747;2921.780866435026;2930.5164647833294;2956.74867636249;2962.9185347348493;2977.8511074446624;5377.435369286247;5500.3949565547755;5539.335279766454;5541.4483944495605;5549.250851135126;5472.863178420572;5475.288212532345;5492.642978206404;5522.392536820947;5523.735268539879;5549.472800083986;5552.444755524496;5576.215611004521;5576.414476479911;5591.608587398843;5171.095058364695;5352.330958630171;5381.246625472515;5423.511883930465;5433.483819037185;5287.598786321705;5291.973540132275;5355.116405793001;5366.892662146814;5392.595555333287;5472.907807088982;5532.2254884315425;5557.944420555743;5568.944310557004;5585.776568937197;3820.8471661134354;3852.4028775539837;4198.6221240088535;4222.683298752544;4223.852165174204;5947.464728174485;6020.8795824084145;6039.879202415839;6057.969710151421;6108.541859360477;5879.794207202727;5919.845630102261;5930.560275040552;5942.58401911862;5971.7611295547695;5905.734241959036;5949.2266005419015;5989.1914591529585;6007;6050.941085310141;5699.772009119589;5853.765849366148;5874.618149820248;5904.261967254093;5913.2608423947;5923.163819216057;5948.970255148696;6008.489278411209;6015.759369625341;6023.608465449732;5592.245046918614;5807.419258074159;5807.651540907559;5818.7415047573495;5856.882862342644 22:19:43 INFO - PROCESS | 30692 | |5;yui.html;740.4779630360595;769.449843361996;777.0403551613758;802.6428239433454;842.8777827215058;13807;16691.582710432245;17364.913175434136;17397;17405.651886962252;16839.91580042101;16977.2360243789;17021.48935531933;17061.061641609736;17101.0594417307;668.2949488289852;780.153533416244;813.4509206285749;813.556611646654;818.3575892924056;803.2048272210499;804.0311424733212;810.0360570920615;811.7118422959853;812.0661239574507;859.9914000860006;862.5471627395605;862.5687156421789;864.7059999600135;867.1935100356666;848.893888263967;854.7222152800302;855.3883972959333;856.0027567883377;857.8370109679141;624.7157543317779;624.8219257511587;630.09895848936;630.9179806625156;630.9652969086699;15816.130112843788;16025.439109631223;16044.117573533451;16110.597235069099;16139.757903631455;14374.92812535941;14759.114453132846;14956.32696528648;15000.174990375524;15063.246837658073;12733.172343797623;12737.617871463779;12777.872221277761;13013.86986130136;13060.934695326558;3911.706622003361;3961.1483531040676;3970.4441378207075;4016.357382818735;4058.594140585947;78857.60571197107;78950;79875;80124.59937700332;80336.59831700863;10682.78634427307;11173.888261117447;11848.703782405337;12011.219270747373;12513.311767852856;22219;22407.88796056042;22428;22476.438089047577;22584.548309034057;8488.872666910001;8558.101399352978;8575.871361929576;8697.608607612672;8748.825023499494;4941.034861801211;4941.561314634257;5035.874103147377;5039.218921067167;5255.0540902637895;3590.838412271402;3728.067983004249;3742.2702572998546;3747.0257732989076;3754.4180651998986;629.6270670449002;633.9302676705558;636.1570918532963;661.0943008078866;662.602438536874;693.5769180799708;747.5402627384119;764.2433990349514;781.2265856801725;789.2778108031088;9218.124278193562;9404.24766018717;9545.809083818285;9670.806583868283;9691.59471876588;689.9862002759917;703.465366321589;705.8811783323388;707.6461769115442;710.5132983906041;4282.8715138545895;4598.287265473857;4725.220338644154;4725.763711814396;4756.619470442425;67061;68440.31559684439;68523.65738171377;89538.55230723805;119133;96959;97673;97960;99248.50375748075;101696.49151754194;54878.45121548813;54969;58620.7068964661;88715.55642221747;88911.55544222238;9016.233620142099;9033.457992520469;9035;9035.322350823582;9161.267098632226;37908.81045594755;48832.093432524605;53082.46917530775;54051.459485406216;55165;67165.66417167883;68030;77497;77833;79838.2016179854;12024.939875300568;12149.514019439124;12235.510579576718;12306.692332691755;12467.812982805446;11869.287842729464;11971.10216733731;12258.20321679088;12524.185927914656;12833.807492887427;11705.414729263674;11852.229605075643;11925.501517800134;12558.937205313914;12563.685907852378;11761.235519691192;11849.170558061198;12014.63956081319;12415.44130514111;12446.25322480654;11652.30086194831;11753.412329383325;11825.349605771766;11916.821247681464;12302.507899683913;9572.282078843975;9635.903640963501;9676.564554595201;9770.80458390814;9915.603375865174;9480.55100130273;9538.95230523843;10174.593016279267;10506.842397364198;10555.63055293061 22:19:43 INFO - PROCESS | 30692 | __end_tp_report 22:19:43 INFO - PROCESS | 30692 | __start_cc_report 22:19:43 INFO - PROCESS | 30692 | _x_x_mozilla_cycle_collect,356 22:19:43 INFO - PROCESS | 30692 | __end_cc_report 22:19:43 INFO - PROCESS | 30692 | __startTimestamp1464931183617__endTimestamp 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | ------- Summary: start ------- 22:19:43 INFO - PROCESS | 30692 | Number of tests: 6 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#0] dojo.html Cycles:165 Average:5520.10 Median:5735.11 stddev:766.06 (13.4%) stddev-sans-first:746.15 22:19:43 INFO - PROCESS | 30692 | Values: 3183.5 3200.5 3214.9 3230.3 3245.1 5478.8 5504.7 5558.5 5599.3 5625.7 5346.7 5466.6 5560.3 5629.3 5634.9 4975.6 5000.0 5043.4 5081.6 5150.2 307.4 5065.8 5149.6 5163.4 5167.3 5026.3 5058.1 5079.4 5097.8 5103.0 5058.4 5072.6 5095.6 5113.1 5141.4 4899.0 4965.9 5003.3 5042.5 5058.7 5457.2 5459.5 5487.5 5493.3 5504.8 5972.6 5985.7 5996.7 5998.5 6008.5 5826.5 5923.3 5942.9 5948.9 6030.7 5570.0 5576.9 5581.6 5583.0 5589.2 5943.5 6092.5 6097.7 6103.7 6116.2 5839.3 5891.5 5920.3 5925.7 5958.9 6021.5 6069.5 6069.6 6074.3 6086.8 5730.6 5779.4 5953.1 6024.5 6032.5 5722.2 5844.9 5845.6 5943.1 5988.3 4479.9 4485.6 4508.4 4519.8 4560.0 3976.8 4014.9 4024.6 4045.4 4088.4 5630.5 5644.2 5682.6 5703.2 5715.4 5989.5 6002.3 6004.6 6060.6 6071.3 5565.0 5685.6 5710.5 5717.1 5735.1 5144.9 5195.2 5221.0 5231.7 5257.7 6296.0 6313.3 6322.5 6322.9 6355.2 5795.3 6011.7 6060.5 6081.7 6110.0 6293.9 6296.1 6317.2 6319.7 6325.9 5885.7 5960.6 5963.9 5997.6 6043.0 5794.2 5945.1 5998.5 6000.3 6073.0 5865.3 5878.8 5890.0 5890.4 5898.4 5767.7 5883.4 5890.8 5893.4 5913.1 5502.9 5540.4 5611.2 5648.9 5672.9 5784.2 5822.9 5834.7 5878.1 5902.9 5731.3 5747.6 5765.1 5799.9 5835.7 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#1] ext.html Cycles:180 Average:59488.90 Median:22744.39 stddev:119144.66 (523.8%) stddev-sans-first:119396.76 22:19:43 INFO - PROCESS | 30692 | Values: 569.0 614.4 625.9 631.0 654.7 41119.0 42419.6 42506.6 42599.1 46249.3 42745.8 43128.6 43488.8 43711.6 43759.3 8589.7 8765.3 8769.7 8777.2 8814.0 8690.3 8723.0 8766.9 8783.7 8793.5 8722.3 8738.3 8756.5 8788.9 8839.4 8712.9 8759.6 8785.5 8804.7 8815.5 8591.7 8632.9 8648.9 8684.2 8685.8 40906.4 41346.0 41464.4 41497.6 41507.8 3450.0 3539.2 3585.7 3923.6 3957.5 31081.5 31101.0 31282.2 31331.7 31470.7 4142.8 4158.5 4289.0 4355.2 4435.0 301964.0 302639.0 303218.0 303739.5 304816.0 49405.0 49483.5 49618.8 49747.0 49813.8 58381.1 58472.7 58759.4 58808.7 58827.0 15310.1 15644.1 15672.0 15700.1 15702.1 12350.6 12375.9 12389.4 12402.9 12428.1 15072.5 15714.6 15767.1 15828.4 15923.6 6196.3 6237.7 6339.2 6446.2 6520.1 1042.6 1071.2 1142.3 1147.3 1209.8 32203.2 32238.0 32238.5 32385.5 32716.5 1133.8 1160.6 1185.9 1202.2 1236.6 17212.7 17561.3 17638.7 17707.9 17719.3 542651.0 543202.0 545135.0 549256.0 552749.0 58166.4 59626.7 59687.0 59875.0 59970.4 1313.2 1402.4 1403.9 1422.3 1423.5 30303.2 32130.5 32238.8 32571.7 32592.7 51306.5 51527.2 51819.0 51895.2 51991.0 458672.0 459011.0 459451.0 463319.0 464593.0 25479.2 26032.6 26143.7 27592.4 40820.0 46313.0 46653.0 47152.3 47397.3 48098.3 39994.2 40108.2 40285.0 41064.0 41121.0 39436.2 40208.4 40391.2 40423.2 41096.4 39994.0 40430.2 41198.4 41519.2 41805.2 35361.3 37530.4 37794.4 38208.8 38281.0 28644.9 28703.3 28733.9 29096.0 29229.3 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#2] jquery.html Cycles:180 Average:27507.98 Median:20793.80 stddev:77808.79 (374.2%) stddev-sans-first:78000.41 22:19:43 INFO - PROCESS | 30692 | Values: 386.3 388.2 389.8 394.2 417.7 16856.5 18085.8 19450.4 19597.8 19795.4 11554.0 11575.2 11607.9 11868.2 11885.5 5189.3 5389.9 5390.3 5393.7 5411.8 5160.4 5253.6 5278.8 5347.2 5359.8 5289.2 5323.0 5324.4 5340.9 5348.8 5301.8 5340.8 5352.9 5682.9 5724.5 5043.5 5059.5 5065.5 5067.1 5084.3 13763.2 13835.8 13879.6 13889.9 19417.0 2079.7 2192.3 2262.7 2320.4 2322.7 9571.4 9688.2 9772.8 9835.8 9856.2 1892.7 1919.8 1949.9 1966.4 1988.8 61930.7 70865.0 72202.6 79222.0 83267.0 9897.6 10251.0 10286.1 10348.4 10436.6 13927.4 13990.4 14001.2 14074.4 14098.6 7773.5 7907.4 7936.1 7937.1 8146.0 7551.3 7656.2 7714.4 7782.3 8138.6 4355.9 4358.4 4359.9 4473.3 4539.3 1851.8 1879.8 1919.7 1925.5 1925.6 974.0 978.1 989.5 1020.0 1042.2 17806.4 18809.9 19456.2 19504.0 19737.5 980.7 1011.1 1019.1 1033.5 1057.4 10694.7 10899.1 10970.6 10993.7 11009.6 439203.0 449757.0 486913.0 488916.0 492858.0 70557.0 87839.6 89166.0 92049.5 92154.5 960.9 987.4 991.8 1006.4 1015.1 17581.6 17600.5 18038.8 18117.0 18267.2 41193.4 41339.6 41418.0 42152.8 42940.8 56449.4 56847.7 57951.0 58869.0 59167.1 7287.9 8392.2 8396.7 8721.8 8741.7 12453.0 12459.8 13265.9 13329.1 13677.8 11371.1 11374.7 11472.6 11570.4 11600.2 11497.1 11522.4 11598.7 11735.6 11885.3 11907.6 11933.3 11964.3 12143.0 12223.5 7682.4 7688.8 7742.6 7841.5 7896.9 10691.5 11680.6 11732.8 11762.5 11782.2 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#3] mootools.html Cycles:180 Average:5752.17 Median:4449.17 stddev:3243.84 (72.9%) stddev-sans-first:3237.30 22:19:43 INFO - PROCESS | 30692 | Values: 1513.7 1532.7 1693.9 1798.3 1801.1 7752.8 8280.3 8394.5 8882.2 8888.3 8493.8 8715.8 8733.8 8784.5 8806.0 2293.6 2319.7 2336.3 2337.4 2339.4 2254.4 2265.8 2291.2 2314.6 2322.0 2268.1 2278.5 2278.8 2282.4 2285.3 2240.5 2268.3 2297.1 2302.3 2302.8 2239.9 2261.8 2274.8 2276.4 2285.2 8671.1 8690.4 8731.0 8745.0 8769.9 4963.7 5087.4 5099.9 5105.7 5114.1 3065.4 3158.3 3282.7 3303.6 3311.1 1098.7 1103.1 1110.0 1134.7 1138.6 12023.9 12048.7 12049.9 12078.7 12126.5 4470.5 4516.3 4607.1 4615.6 4619.8 10318.7 10329.7 10336.8 10357.4 10371.3 5242.3 5244.7 5248.5 5248.8 5283.4 4145.5 4633.6 4651.5 4756.4 4770.9 3996.7 4014.5 4046.8 4092.5 4125.0 1265.6 1271.3 1289.2 1294.3 1298.8 983.8 1017.3 1019.6 1024.9 1044.1 6185.2 6219.1 6235.6 6254.6 6299.0 424.5 426.9 429.9 430.2 432.9 4429.7 4443.0 4455.4 4457.2 4461.4 10129.3 10492.0 10535.2 10588.4 10614.3 10022.8 10554.4 10613.9 10624.8 10684.3 7694.0 7875.6 7883.4 7884.8 7942.9 6049.7 6237.8 6311.4 6319.1 6330.7 10041.5 10069.4 10085.8 10125.4 10162.0 9805.2 10063.8 10262.7 10298.4 10360.1 7111.2 7440.0 7568.4 7643.5 7707.2 8400.0 8407.4 8417.8 8422.2 8444.0 7554.8 7666.2 7745.8 7763.2 7838.7 7429.3 7607.7 7622.9 7704.0 7717.6 6871.6 6959.9 6975.3 7011.9 7023.0 5185.6 5220.6 5415.7 5423.4 5475.0 5438.6 5465.2 5484.0 5491.8 5635.6 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#4] prototype.html Cycles:180 Average:4771.53 Median:5390.15 stddev:1397.86 (25.9%) stddev-sans-first:1361.47 22:19:43 INFO - PROCESS | 30692 | Values: 331.4 334.6 337.3 343.0 344.4 5580.9 5715.7 5725.8 5766.7 5786.7 5623.0 5642.4 5646.9 5649.2 5656.3 4140.8 4256.9 4303.5 4350.9 4357.2 4300.6 4312.7 4316.9 4345.6 4349.7 4288.9 4300.4 4317.0 4321.2 4325.3 4218.2 4265.6 4315.7 4321.4 4327.7 3808.0 3809.7 3813.4 3819.4 3820.9 5395.3 5426.8 5451.8 5457.9 5486.9 4925.6 4955.3 4986.0 4992.5 5027.8 5806.0 5811.9 5850.4 5868.7 5913.7 4193.8 4226.6 4227.9 4228.7 4231.8 5751.0 6082.8 6086.3 6094.3 6127.4 4110.2 4112.8 4120.4 4126.8 4128.7 4155.3 4183.9 4186.5 4200.9 4206.9 4752.8 4856.1 4859.1 4869.9 4898.0 5884.3 5887.5 5901.3 5912.9 6010.3 1477.5 1494.0 1495.4 1496.7 1496.9 903.9 916.7 917.3 925.0 929.7 5085.8 5103.6 5108.5 5172.4 5182.6 5306.4 5383.8 5387.7 5464.8 5537.3 4964.7 5121.3 5187.5 5188.2 5211.4 2921.8 2930.5 2956.7 2962.9 2977.9 5377.4 5500.4 5539.3 5541.4 5549.3 5472.9 5475.3 5492.6 5522.4 5523.7 5549.5 5552.4 5576.2 5576.4 5591.6 5171.1 5352.3 5381.2 5423.5 5433.5 5287.6 5292.0 5355.1 5366.9 5392.6 5472.9 5532.2 5557.9 5568.9 5585.8 3820.8 3852.4 4198.6 4222.7 4223.9 5947.5 6020.9 6039.9 6058.0 6108.5 5879.8 5919.8 5930.6 5942.6 5971.8 5905.7 5949.2 5989.2 6007.0 6050.9 5699.8 5853.8 5874.6 5904.3 5913.3 5923.2 5949.0 6008.5 6015.8 6023.6 5592.2 5807.4 5807.7 5818.7 5856.9 22:19:43 INFO - PROCESS | 30692 | 22:19:43 INFO - PROCESS | 30692 | [#5] yui.html Cycles:180 Average:19362.74 Median:55067.00 stddev:26948.18 (48.9%) stddev-sans-first:26987.49 22:19:43 INFO - PROCESS | 30692 | Values: 740.5 769.4 777.0 802.6 842.9 13807.0 16691.6 17364.9 17397.0 17405.7 16839.9 16977.2 17021.5 17061.1 17101.1 668.3 780.2 813.5 813.6 818.4 803.2 804.0 810.0 811.7 812.1 860.0 862.5 862.6 864.7 867.2 848.9 854.7 855.4 856.0 857.8 624.7 624.8 630.1 630.9 631.0 15816.1 16025.4 16044.1 16110.6 16139.8 14374.9 14759.1 14956.3 15000.2 15063.2 12733.2 12737.6 12777.9 13013.9 13060.9 3911.7 3961.1 3970.4 4016.4 4058.6 78857.6 78950.0 79875.0 80124.6 80336.6 10682.8 11173.9 11848.7 12011.2 12513.3 22219.0 22407.9 22428.0 22476.4 22584.5 8488.9 8558.1 8575.9 8697.6 8748.8 4941.0 4941.6 5035.9 5039.2 5255.1 3590.8 3728.1 3742.3 3747.0 3754.4 629.6 633.9 636.2 661.1 662.6 693.6 747.5 764.2 781.2 789.3 9218.1 9404.2 9545.8 9670.8 9691.6 690.0 703.5 705.9 707.6 710.5 4282.9 4598.3 4725.2 4725.8 4756.6 67061.0 68440.3 68523.7 89538.6 119133.0 96959.0 97673.0 97960.0 99248.5 101696.5 54878.5 54969.0 58620.7 88715.6 88911.6 9016.2 9033.5 9035.0 9035.3 9161.3 37908.8 48832.1 53082.5 54051.5 55165.0 67165.7 68030.0 77497.0 77833.0 79838.2 12024.9 12149.5 12235.5 12306.7 12467.8 11869.3 11971.1 12258.2 12524.2 12833.8 11705.4 11852.2 11925.5 12558.9 12563.7 11761.2 11849.2 12014.6 12415.4 12446.3 11652.3 11753.4 11825.3 11916.8 12302.5 9572.3 9635.9 9676.6 9770.8 9915.6 9480.6 9539.0 10174.6 10506.8 10555.6 22:19:43 INFO - PROCESS | 30692 | -------- Summary: end -------- 22:19:43 INFO - PROCESS | 30692 | 22:19:44 INFO - TEST-INFO | 30692: exit 0 22:19:44 INFO - TEST-OK | dromaeo_css | took 1110352ms 22:19:44 INFO - TEST-START | kraken 22:19:44 INFO - operating with platform_type : linux_ 22:19:44 INFO - Initialising browser for kraken test... 22:19:44 INFO - TEST-INFO | started process 30930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7fELGf/profile http://localhost:51669/getInfo.html) 22:19:49 INFO - PROCESS | 30930 | __metrics Screen width/height:1600/1200 22:19:49 INFO - PROCESS | 30930 | colorDepth:24 22:19:49 INFO - PROCESS | 30930 | Browser inner width/height: 1024/697 22:19:49 INFO - PROCESS | 30930 | __metrics 22:19:49 ERROR - PROCESS | 30930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 22:19:50 INFO - TEST-INFO | 30930: exit 0 22:19:50 INFO - Browser initialized. 22:19:50 INFO - Running cycle 1/1 for kraken test... 22:19:50 INFO - TEST-INFO | started process 31049 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7fELGf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:02 INFO - PROCESS | 31049 | RSS: Main: 167403520 22:20:02 INFO - PROCESS | 31049 | 22:20:05 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51669/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:05 INFO - PROCESS | 31049 | RSS: Main: 172666880 22:20:05 INFO - PROCESS | 31049 | 22:20:08 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51669/tests/kraken/driver.html?testName=audio-dft) 22:20:09 INFO - PROCESS | 31049 | RSS: Main: 172294144 22:20:09 INFO - PROCESS | 31049 | 22:20:12 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51669/tests/kraken/driver.html?testName=audio-fft) 22:20:12 INFO - PROCESS | 31049 | RSS: Main: 167665664 22:20:12 INFO - PROCESS | 31049 | 22:20:15 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51669/tests/kraken/driver.html?testName=audio-oscillator) 22:20:15 INFO - PROCESS | 31049 | RSS: Main: 167874560 22:20:15 INFO - PROCESS | 31049 | 22:20:17 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:18 INFO - PROCESS | 31049 | RSS: Main: 168275968 22:20:18 INFO - PROCESS | 31049 | 22:20:21 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51669/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:22 INFO - PROCESS | 31049 | RSS: Main: 168914944 22:20:22 INFO - PROCESS | 31049 | 22:20:25 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51669/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:26 INFO - PROCESS | 31049 | RSS: Main: 166068224 22:20:26 INFO - PROCESS | 31049 | 22:20:29 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51669/tests/kraken/driver.html?testName=json-parse-financial) 22:20:29 INFO - PROCESS | 31049 | RSS: Main: 167239680 22:20:29 INFO - PROCESS | 31049 | 22:20:31 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:31 INFO - PROCESS | 31049 | RSS: Main: 167751680 22:20:31 INFO - PROCESS | 31049 | 22:20:33 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:20:34 INFO - PROCESS | 31049 | RSS: Main: 168034304 22:20:34 INFO - PROCESS | 31049 | 22:20:36 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:37 INFO - PROCESS | 31049 | RSS: Main: 168382464 22:20:37 INFO - PROCESS | 31049 | 22:20:39 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:40 INFO - PROCESS | 31049 | RSS: Main: 168284160 22:20:40 INFO - PROCESS | 31049 | 22:20:43 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:43 INFO - PROCESS | 31049 | RSS: Main: 168177664 22:20:43 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | Cycle 1(1): loaded http://localhost:51669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51669/tests/kraken/driver.html?testName=ai-astar) 22:20:45 INFO - PROCESS | 31049 | RSS: Main: 169267200 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | __start_tp_report 22:20:45 INFO - PROCESS | 31049 | _x_x_mozilla_page_load 22:20:45 INFO - PROCESS | 31049 | _x_x_mozilla_page_load_details 22:20:45 INFO - PROCESS | 31049 | |i|pagename|runs| 22:20:45 INFO - PROCESS | 31049 | |0;ai-astar;111;106;112;111;111;111;110;109;112;110 22:20:45 INFO - PROCESS | 31049 | |1;audio-beat-detection;159;127;162;184;127;156;154;129;182;126 22:20:45 INFO - PROCESS | 31049 | |2;audio-dft;164;166;162;162;162;163;169;162;162;161 22:20:45 INFO - PROCESS | 31049 | |3;audio-fft;76;77;76;164;74;77;75;74;163;75 22:20:45 INFO - PROCESS | 31049 | |4;audio-oscillator;87;89;146;86;85;86;144;87;85;138 22:20:45 INFO - PROCESS | 31049 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;117;117 22:20:45 INFO - PROCESS | 31049 | |6;imaging-darkroom;147;147;148;148;146;149;145;147;146;146 22:20:45 INFO - PROCESS | 31049 | |7;imaging-desaturate;97;95;95;95;95;96;96;96;94;95 22:20:45 INFO - PROCESS | 31049 | |8;json-parse-financial;56;55;56;54;57;55;54;57;54;58 22:20:45 INFO - PROCESS | 31049 | |9;json-stringify-tinderbox;62;62;63;63;62;62;63;62;63;63 22:20:45 INFO - PROCESS | 31049 | |10;stanford-crypto-aes;80;84;79;81;91;84;83;81;112;80 22:20:45 INFO - PROCESS | 31049 | |11;stanford-crypto-ccm;121;130;122;120;135;128;138;132;144;133 22:20:45 INFO - PROCESS | 31049 | |12;stanford-crypto-pbkdf2;166;169;158;164;169;163;160;158;157;162 22:20:45 INFO - PROCESS | 31049 | |13;stanford-crypto-sha256-iterative;63;65;66;61;64;63;63;65;62;62 22:20:45 INFO - PROCESS | 31049 | __end_tp_report 22:20:45 INFO - PROCESS | 31049 | __start_cc_report 22:20:45 INFO - PROCESS | 31049 | _x_x_mozilla_cycle_collect,894 22:20:45 INFO - PROCESS | 31049 | __end_cc_report 22:20:45 INFO - PROCESS | 31049 | __startTimestamp1464931245693__endTimestamp 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | ------- Summary: start ------- 22:20:45 INFO - PROCESS | 31049 | Number of tests: 14 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:1.77 (1.6%) stddev-sans-first:1.86 22:20:45 INFO - PROCESS | 31049 | Values: 111.0 106.0 112.0 111.0 111.0 111.0 110.0 109.0 112.0 110.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:157.50 stddev:22.43 (14.2%) stddev-sans-first:23.58 22:20:45 INFO - PROCESS | 31049 | Values: 159.0 127.0 162.0 184.0 127.0 156.0 154.0 129.0 182.0 126.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:2.45 (1.5%) stddev-sans-first:2.59 22:20:45 INFO - PROCESS | 31049 | Values: 164.0 166.0 162.0 162.0 162.0 163.0 169.0 162.0 162.0 161.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#3] audio-fft Cycles:10 Average:93.10 Median:75.50 stddev:37.12 (49.2%) stddev-sans-first:38.85 22:20:45 INFO - PROCESS | 31049 | Values: 76.0 77.0 76.0 164.0 74.0 77.0 75.0 74.0 163.0 75.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:86.00 stddev:27.26 (31.7%) stddev-sans-first:28.27 22:20:45 INFO - PROCESS | 31049 | Values: 87.0 89.0 146.0 86.0 85.0 86.0 144.0 87.0 85.0 138.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:20:45 INFO - PROCESS | 31049 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:1.20 (0.8%) stddev-sans-first:1.27 22:20:45 INFO - PROCESS | 31049 | Values: 147.0 147.0 148.0 148.0 146.0 149.0 145.0 147.0 146.0 146.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.84 (0.9%) stddev-sans-first:0.67 22:20:45 INFO - PROCESS | 31049 | Values: 97.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 94.0 95.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.43 (2.6%) stddev-sans-first:1.51 22:20:45 INFO - PROCESS | 31049 | Values: 56.0 55.0 56.0 54.0 57.0 55.0 54.0 57.0 54.0 58.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 22:20:45 INFO - PROCESS | 31049 | Values: 62.0 62.0 63.0 63.0 62.0 62.0 63.0 62.0 63.0 63.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:82.00 stddev:9.92 (12.1%) stddev-sans-first:10.33 22:20:45 INFO - PROCESS | 31049 | Values: 80.0 84.0 79.0 81.0 91.0 84.0 83.0 81.0 112.0 80.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:132.50 stddev:7.79 (5.9%) stddev-sans-first:7.50 22:20:45 INFO - PROCESS | 31049 | Values: 121.0 130.0 122.0 120.0 135.0 128.0 138.0 132.0 144.0 133.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:163.50 stddev:4.43 (2.7%) stddev-sans-first:4.52 22:20:45 INFO - PROCESS | 31049 | Values: 166.0 169.0 158.0 164.0 169.0 163.0 160.0 158.0 157.0 162.0 22:20:45 INFO - PROCESS | 31049 | 22:20:45 INFO - PROCESS | 31049 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.58 (2.5%) stddev-sans-first:1.67 22:20:45 INFO - PROCESS | 31049 | Values: 63.0 65.0 66.0 61.0 64.0 63.0 63.0 65.0 62.0 62.0 22:20:45 INFO - PROCESS | 31049 | -------- Summary: end -------- 22:20:45 INFO - PROCESS | 31049 | 22:20:46 INFO - TEST-INFO | 31049: exit 0 22:20:46 INFO - TEST-OK | kraken | took 62335ms 22:20:46 INFO - SUITE-END | took 1172s 22:20:46 INFO - Completed test suite (00:19:32) 22:20:46 INFO - javascript benchmark 22:20:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7184.6220924224535, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.4906414973602, 3200.519922011698, 3214.871405143794, 3230.2731885325798, 3245.0589329094564, 5478.822053258554, 5504.669719816814, 5558.499735023842, 5599.32808063033, 5625.6905870177125, 5346.652467589604, 5466.562674986001, 5560.304961879765, 5629.296337957755, 5634.859128521779, 4975.626827987906, 5000.049990501811, 5043.445221025684, 5081.644284900059, 5150.227465880111, 307.40032878469924, 5065.797368105272, 5149.639525233235, 5163.432022477525, 5167.250748641463, 5026.321446604698, 5058.140116180258, 5079.3650793650795, 5097.821576244833, 5102.974485127569, 5058.3929928408825, 5072.6195535335, 5095.592352611782, 5113.054084994251, 5141.434442211354, 4899.044686286175, 4965.851024469272, 5003.3495645565845, 5042.520960508746, 5058.747062646853, 5457.154141108134, 5459.508644221999, 5487.451254874481, 5493.340799104093, 5504.80733174337, 5972.64164150148, 5985.700714964234, 5996.670183139926, 5998.520118390518, 6008.48927841134, 5826.504747096502, 5923.259592550931, 5942.881142377171, 5948.881022379528, 6030.698465076773, 5570.0, 5576.86057848553, 5581.581381396411, 5582.972085139589, 5589.2175095486255, 5943.524518038546, 6092.482139018233, 6097.664628445478, 6103.725332360054, 6116.174316467309, 5839.328477225088, 5891.46976772084, 5920.259967504062, 5925.73334199962, 5958.940410595926, 6021.5182785377065, 6069.453749162508, 6069.605475644069, 6074.271087469444, 6086.817395478142, 5730.627509211888, 5779.393163717749, 5953.136795164677, 6024.457798798129, 6032.457078862835, 5722.170285308607, 5844.94155058444, 5845.649261044351, 5943.07882278239, 5988.28140623128, 4479.865604031885, 4485.618722408566, 4508.391367165391, 4519.81920723174, 4560.042391097906, 3976.840926362971, 4014.9197016060098, 4024.6176613221696, 4045.37296719009, 4088.448059511987, 5630.465105814941, 5644.153376993402, 5682.602217844794, 5703.201551782754, 5715.371309155989, 5989.460948514655, 6002.339742628307, 6004.609700369462, 6060.636361818305, 6071.301800293023, 5564.998300305984, 5685.630434021776, 5710.486056254874, 5717.142428635655, 5735.11105778605, 5144.897102057937, 5195.168772996302, 5220.9738951305, 5231.71225582589, 5257.684538927677, 6296.024116261987, 6313.305536390993, 6322.462590679752, 6322.9051564226575, 6355.237371515448, 5795.333536643171, 6011.72947217385, 6060.454559089703, 6081.695915204222, 6110.022396416552, 6293.905591416224, 6296.087067375113, 6317.241930968313, 6319.715612797527, 6325.936740632535, 5885.676287804041, 5960.642361458326, 5963.940360596339, 5997.610155339889, 6042.969785151046, 5794.217780599565, 5945.137954996415, 5998.520118390606, 6000.309964354155, 6073.0283154695035, 5865.266841644794, 5878.794242201502, 5889.970550147222, 5890.352061273342, 5898.351181370132, 5767.740451679769, 5883.382244864399, 5890.764369425175, 5893.351731309467, 5913.113033045078, 5502.862428439161, 5540.362858271189, 5611.158326251095, 5648.943510564842, 5672.914906276492, 5784.21913041734, 5822.94177058224, 5834.737436815269, 5878.059510478303, 5902.852428689317, 5731.340895797037, 5747.626404283708, 5765.077587585966, 5799.913001305068, 5835.67903765297], "value": 5469.804790101579, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.9914651280229, 614.42858141928, 625.8654389306304, 630.9652969086701, 654.6693919570616, 41119.0, 42419.57580424195, 42506.574934250646, 42599.14801703968, 46249.30626040612, 42745.78627106868, 43128.568714312925, 43488.78255608725, 43711.562884371146, 43759.34360984588, 8589.699360522367, 8765.254953328975, 8769.736907892742, 8777.2100510954, 8814.0, 8690.261327787146, 8722.956385218065, 8766.912330876705, 8783.692570760024, 8793.516356600416, 8722.34582406322, 8738.257248133885, 8756.474611523328, 8788.8681669775, 8839.425437346552, 8712.912870871305, 8759.605817738217, 8785.472871627722, 8804.735857924241, 8815.51514666693, 8591.656333746643, 8632.913670863336, 8648.913510864873, 8684.175003374668, 8685.782855428602, 40906.386404203964, 41346.0, 41464.37803432951, 41497.58502414998, 41507.7924610378, 3449.9827500862584, 3539.1682954505673, 3585.731070169748, 3923.5684074751753, 3957.525096988351, 31081.533776993365, 31101.0, 31282.21794455134, 31331.686683133106, 31470.685293147006, 4142.772147531885, 4158.480189976253, 4288.957110428918, 4355.216061108999, 4435.0, 301964.0, 302639.0, 303218.0, 303739.4813025943, 304816.0, 49405.0, 49483.50516494861, 49618.751906240235, 49747.0, 49813.750931245115, 58381.12428313579, 58472.70763646154, 58759.412405876246, 58808.705956470796, 58827.0, 15310.081395116329, 15644.061356318658, 15672.0, 15700.136492492795, 15702.057876527444, 12350.567730129582, 12375.876241237653, 12389.442475088643, 12402.875971240353, 12428.13003089775, 15072.54782356531, 15714.607134821721, 15767.132807695687, 15828.44600438979, 15923.566878980715, 6196.318404975449, 6237.719302631393, 6339.175907132043, 6446.194225721785, 6520.119783829217, 1042.6402891002592, 1071.1805468816362, 1142.2860712054965, 1147.3460127727114, 1209.800382936823, 32203.194920127185, 32238.0, 32238.516422253684, 32385.51421728676, 32716.509252361233, 1133.7664800343312, 1160.5937921727327, 1185.9051275897907, 1202.2485946283573, 1236.6475554467113, 17212.655746884993, 17561.278001909723, 17638.735418968725, 17707.911460442614, 17719.29122835098, 542651.0, 543202.0, 545135.0, 549256.0, 552749.0, 58166.41833581695, 59626.70186649126, 59687.0, 59875.0, 59970.40029599648, 1313.2186349122449, 1402.3759427054863, 1403.9432231780286, 1422.3101795629111, 1423.459085547506, 30303.242418939702, 32130.51804222892, 32238.838805805823, 32571.674283256863, 32592.674073259914, 51306.48693513017, 51527.2270915944, 51819.0, 51895.22157167721, 51991.0, 458672.0, 459011.0, 459451.0, 463319.0, 464593.0, 25479.23562293134, 26032.609510856975, 26143.73856261413, 27592.448151036464, 40820.0, 46313.0, 46653.0, 47152.29271560861, 47397.289040665106, 48098.27852582144, 39994.2001159981, 40108.1978360437, 40284.99287517835, 41064.0, 41121.0, 39436.2112757749, 40208.396874047496, 40391.192176155724, 40423.191536168524, 41096.38355424611, 39994.0, 40430.19139617132, 41198.38202427026, 41519.1696166081, 41805.1638967225, 35361.29277414386, 37530.437043444916, 37794.43308350432, 38208.80895595504, 38281.0, 28644.856775715987, 28703.282417938884, 28733.856330718212, 29096.0, 29229.269268268465], "value": 19856.345427424098, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.2526012166458, 388.2235528942116, 389.82457893947714, 394.2174783055635, 417.68464809069167, 16856.494305170832, 18085.819141808577, 19450.41648750536, 19597.804021959757, 19795.406137815888, 11553.960143587097, 11575.189736718434, 11607.883921160786, 11868.169228154033, 11885.465154068066, 5189.325387699594, 5389.9191512127345, 5390.272313237702, 5393.703346315951, 5411.83764487064, 5160.380754309488, 5253.6322457428, 5278.788848446057, 5347.224652425396, 5359.758810853521, 5289.153735402318, 5322.973385133088, 5324.414314425429, 5340.905114451548, 5348.839534813962, 5301.814436494743, 5340.786368545254, 5352.946470535284, 5682.943170568283, 5724.4561766632105, 5043.4956504349275, 5059.544640982329, 5065.518775716301, 5067.138586440277, 5084.313617661605, 13763.174209547358, 13835.79246311306, 13879.583612491642, 13889.930550347284, 19417.0, 2079.70198906364, 2192.331338941622, 2262.7171603549555, 2320.385097949045, 2322.7212734471805, 9571.37786043912, 9688.176504997084, 9772.755681108029, 9835.754106147404, 9856.211503079736, 1892.6782446984046, 1919.7504324437873, 1949.8537609679188, 1966.4395647240467, 1988.7514060742408, 61930.69034654798, 70865.0, 72202.63898680473, 79222.0, 83267.0, 9897.554610042565, 10251.0, 10286.074253317238, 10348.430836304076, 10436.63471778484, 13927.373268202955, 13990.370433330525, 14001.159930404208, 14074.437022518985, 14098.647533811532, 7773.494722842997, 7907.367410607138, 7936.087349954713, 7937.126916039231, 8146.0, 7551.320381165721, 7656.157822639505, 7714.382849372037, 7782.299593036654, 8138.633761480747, 4355.934660980088, 4358.36803663467, 4359.934600980988, 4473.261911784519, 4539.296409056601, 1851.7870444899008, 1879.7838248601313, 1919.7120431935043, 1925.4512463947708, 1925.6148770246007, 973.9707808765748, 978.0561757903657, 989.5497548615363, 1019.9847002294972, 1042.1714736784274, 17806.37677681275, 18809.90595047016, 19456.221751129797, 19504.0, 19737.50656233577, 980.7401038724744, 1011.0849681038671, 1019.1235537437798, 1033.4884232304908, 1057.434272664121, 10694.73263168427, 10899.101493543882, 10970.561177552969, 10993.725156871142, 11009.605293406174, 439203.0, 449757.0, 486913.0, 488916.0, 492858.0, 70557.0, 87839.56080219558, 89166.0, 92049.53975230081, 92154.53922730478, 960.9471479068578, 987.4272921705393, 991.7520619845038, 1006.3710181136789, 1015.1168483419325, 17581.560460988578, 17600.471985840446, 18038.819611803712, 18117.0, 18267.177976991334, 41193.38209926793, 41339.586604133576, 41418.0, 42152.78923605362, 42940.78529607332, 56449.43550564442, 56847.71576142093, 57951.0, 58869.0, 59167.11249331349, 7287.8542429152185, 8392.160783921803, 8396.748097557083, 8721.825563488568, 8741.694040708544, 12453.0, 12459.813102803646, 13265.86734132685, 13329.066965312335, 13677.794833077312, 11371.123934269073, 11374.715632109264, 11472.598459053894, 11570.421478926188, 11600.18798684084, 11497.08023358146, 11522.423878806194, 11598.71003224926, 11735.589254376397, 11885.34630595291, 11907.642770716893, 11933.28400295985, 11964.282143071443, 12143.0, 12223.511059557519, 7682.385409167365, 7688.8462230756195, 7742.574158421341, 7841.529508229296, 7896.921030789848, 10691.465426728477, 11680.591179308683, 11732.765344692887, 11762.529498819951, 11782.234154779911], "value": 8723.596073385545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.7048275586258, 1532.6934613077383, 1693.8898971566848, 1798.3346161920092, 1801.1444563832174, 7752.7674169775, 8280.337572994162, 8394.49633022019, 8882.200601945831, 8888.28893688505, 8493.787655308604, 8715.825683486326, 8733.825323493542, 8784.472931624092, 8806.0, 2293.5986202414592, 2319.7332306784765, 2336.2874323331375, 2337.3572267626455, 2339.4034521197073, 2254.41385239838, 2265.8187345012443, 2291.1980806717597, 2314.606516892124, 2322.036354912709, 2268.0700912625825, 2278.4759505313787, 2278.760730123338, 2282.395165281202, 2285.337252196861, 2240.5294888073363, 2268.274152271274, 2297.0811675329833, 2302.343832007878, 2302.827287953402, 2239.876806775627, 2261.7964383205426, 2274.8180145588312, 2276.430892276931, 2285.1773361589812, 8671.089535598798, 8690.435121717068, 8730.956345218234, 8744.956275218583, 8769.868451973225, 4963.677360971526, 5087.364079490064, 5099.872503187413, 5105.693658380472, 5114.079465696173, 3065.432894914448, 3158.2893848884046, 3282.6717328267223, 3303.620083690382, 3311.072899588119, 1098.7363032872054, 1103.0844399148687, 1109.9667009989632, 1134.7219931116847, 1138.6356365962895, 12023.93988030063, 12048.698782530419, 12049.879501204963, 12078.698032549257, 12126.454309556091, 4470.463544374696, 4516.345129956204, 4607.14767767964, 4615.607673347736, 4619.815207391666, 10318.742031449123, 10329.741756455998, 10336.844947325797, 10357.378557286587, 10371.27401081917, 5242.344706911636, 5244.685318880879, 5248.527632513092, 5248.763805628756, 5283.392409872837, 4145.54399016108, 4633.606143477842, 4651.534846515389, 4756.357891684647, 4770.856874293777, 3996.7002474813926, 4014.494564538298, 4046.817893194813, 4092.5062352241216, 4124.979375103166, 1265.645619226618, 1271.3134907150036, 1289.1813698301457, 1294.3140135728079, 1298.805179223124, 983.7589790501373, 1017.3336464615688, 1019.5972590826582, 1024.938503689781, 1044.0551301072542, 6185.195924529782, 6219.098230756515, 6235.563510554309, 6254.6247225166635, 6298.968505157536, 424.49061126648144, 426.91248294099637, 429.9054208074219, 430.2127107393525, 432.9285667864767, 4429.667774916894, 4442.977785111054, 4455.353973673799, 4457.175422546839, 4461.420015397977, 10129.290949633454, 10492.003259690317, 10535.209859260432, 10588.364698118137, 10614.310069845435, 10022.799544009078, 10554.366737995744, 10613.9469302653, 10624.787504250025, 10684.305520141166, 7694.0, 7875.566843823644, 7883.36933045355, 7884.84230315402, 7942.880856787037, 6049.727762250621, 6237.781677641261, 6311.368863113836, 6319.05214217871, 6330.746770129143, 10041.49792510357, 10069.446180460145, 10085.848712269175, 10125.392476451141, 10162.0, 9805.166560842408, 10063.798724025331, 10262.743431414274, 10298.3820970742, 10360.119389851947, 7111.217766045627, 7440.0, 7568.356689681439, 7643.503172293783, 7707.152213256425, 8400.033996090528, 8407.3694472916, 8417.789555261168, 8422.157784221528, 8443.957780211305, 7554.811129721801, 7666.15672276038, 7745.7676269712, 7763.184865589032, 7838.686452542062, 7429.33136017761, 7607.706689862847, 7622.923770762443, 7703.961480192564, 7717.6141192939, 6871.587704737732, 6959.89560156588, 6975.267596902455, 7011.929880701127, 7023.016777651031, 5185.611079169153, 5220.5823534117935, 5415.702136382379, 5423.403425623257, 5475.0418676730615, 5438.619296649196, 5465.207544906046, 5483.972580137074, 5491.75287112073, 5635.605507614428], "value": 4539.481479578263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.4365578516522, 334.6352475801376, 337.330399157672, 343.0326479328292, 344.4420039535952, 5580.86047848804, 5715.742791574378, 5725.828225153242, 5766.654000759957, 5786.71066446677, 5623.0, 5642.351129620095, 5646.943530564693, 5649.209110724501, 5656.321241451032, 4140.772257525835, 4256.87229383119, 4303.483581970168, 4350.934735978947, 4357.237483440401, 4300.591443812833, 4312.741235525878, 4316.870493885189, 4345.565443455661, 4349.739015659071, 4288.892777680568, 4300.35494675798, 4317.0, 4321.157374312011, 4325.307950727884, 4218.240716670998, 4265.615198494484, 4315.697901146889, 4321.416608757841, 4327.653787696977, 3807.9619203807883, 3809.733318667694, 3813.4470501777364, 3819.3698039823394, 3820.8853734387735, 5395.271638328814, 5426.782928682848, 5451.809186678447, 5457.863553411157, 5486.91769623456, 4925.581325587329, 4955.331030310898, 4986.0, 4992.47579004203, 5027.798888044474, 5805.970970145165, 5811.88376232473, 5850.3564607893095, 5868.706564671749, 5913.674747888865, 4193.769342686151, 4226.619604235633, 4227.894302642428, 4228.746275223496, 4231.788410579489, 5750.971245143747, 6082.847928801771, 6086.269647642267, 6094.299155597075, 6127.417895299939, 4110.232599982037, 4112.835486580563, 4120.362967700614, 4126.793660316972, 4128.710990230655, 4155.2728272552185, 4183.937240941388, 4186.476690413699, 4200.928763165434, 4206.85276015338, 4752.7861246244, 4856.053069651384, 4859.1496488114435, 4869.928615704539, 4897.951020489821, 5884.29355486904, 5887.528117970507, 5901.262342207224, 5912.9113063304085, 6010.338862725097, 1477.5493474490384, 1494.0438119603461, 1495.3794175417297, 1496.7231062253518, 1496.9176695281647, 903.9050899655441, 916.6616739560317, 917.3165991336364, 924.990750092504, 929.7396728915915, 5085.847424577269, 5103.642745007888, 5108.518529626367, 5172.431032586413, 5182.600697811645, 5306.442823503554, 5383.81156659515, 5387.703676297763, 5464.836054918359, 5537.307836520435, 4964.652474326763, 5121.308623335802, 5187.507186817246, 5188.247704082887, 5211.400688920747, 2921.780866435026, 2930.5164647833294, 2956.74867636249, 2962.9185347348493, 2977.8511074446624, 5377.435369286247, 5500.3949565547755, 5539.335279766454, 5541.4483944495605, 5549.250851135126, 5472.863178420572, 5475.288212532345, 5492.642978206404, 5522.392536820947, 5523.735268539879, 5549.472800083986, 5552.444755524496, 5576.215611004521, 5576.414476479911, 5591.608587398843, 5171.095058364695, 5352.330958630171, 5381.246625472515, 5423.511883930465, 5433.483819037185, 5287.598786321705, 5291.973540132275, 5355.116405793001, 5366.892662146814, 5392.595555333287, 5472.907807088982, 5532.2254884315425, 5557.944420555743, 5568.944310557004, 5585.776568937197, 3820.8471661134354, 3852.4028775539837, 4198.6221240088535, 4222.683298752544, 4223.852165174204, 5947.464728174485, 6020.8795824084145, 6039.879202415839, 6057.969710151421, 6108.541859360477, 5879.794207202727, 5919.845630102261, 5930.560275040552, 5942.58401911862, 5971.7611295547695, 5905.734241959036, 5949.2266005419015, 5989.1914591529585, 6007.0, 6050.941085310141, 5699.772009119589, 5853.765849366148, 5874.618149820248, 5904.261967254093, 5913.2608423947, 5923.163819216057, 5948.970255148696, 6008.489278411209, 6015.759369625341, 6023.608465449732, 5592.245046918614, 5807.419258074159, 5807.651540907559, 5818.7415047573495, 5856.882862342644], "value": 4322.8253605666805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.4779630360595, 769.449843361996, 777.0403551613758, 802.6428239433454, 842.8777827215058, 13807.0, 16691.582710432245, 17364.913175434136, 17397.0, 17405.651886962252, 16839.91580042101, 16977.2360243789, 17021.48935531933, 17061.061641609736, 17101.0594417307, 668.2949488289852, 780.153533416244, 813.4509206285749, 813.556611646654, 818.3575892924056, 803.2048272210499, 804.0311424733212, 810.0360570920615, 811.7118422959853, 812.0661239574507, 859.9914000860006, 862.5471627395605, 862.5687156421789, 864.7059999600135, 867.1935100356666, 848.893888263967, 854.7222152800302, 855.3883972959333, 856.0027567883377, 857.8370109679141, 624.7157543317779, 624.8219257511587, 630.09895848936, 630.9179806625156, 630.9652969086699, 15816.130112843788, 16025.439109631223, 16044.117573533451, 16110.597235069099, 16139.757903631455, 14374.92812535941, 14759.114453132846, 14956.32696528648, 15000.174990375524, 15063.246837658073, 12733.172343797623, 12737.617871463779, 12777.872221277761, 13013.86986130136, 13060.934695326558, 3911.706622003361, 3961.1483531040676, 3970.4441378207075, 4016.357382818735, 4058.594140585947, 78857.60571197107, 78950.0, 79875.0, 80124.59937700332, 80336.59831700863, 10682.78634427307, 11173.888261117447, 11848.703782405337, 12011.219270747373, 12513.311767852856, 22219.0, 22407.88796056042, 22428.0, 22476.438089047577, 22584.548309034057, 8488.872666910001, 8558.101399352978, 8575.871361929576, 8697.608607612672, 8748.825023499494, 4941.034861801211, 4941.561314634257, 5035.874103147377, 5039.218921067167, 5255.0540902637895, 3590.838412271402, 3728.067983004249, 3742.2702572998546, 3747.0257732989076, 3754.4180651998986, 629.6270670449002, 633.9302676705558, 636.1570918532963, 661.0943008078866, 662.602438536874, 693.5769180799708, 747.5402627384119, 764.2433990349514, 781.2265856801725, 789.2778108031088, 9218.124278193562, 9404.24766018717, 9545.809083818285, 9670.806583868283, 9691.59471876588, 689.9862002759917, 703.465366321589, 705.8811783323388, 707.6461769115442, 710.5132983906041, 4282.8715138545895, 4598.287265473857, 4725.220338644154, 4725.763711814396, 4756.619470442425, 67061.0, 68440.31559684439, 68523.65738171377, 89538.55230723805, 119133.0, 96959.0, 97673.0, 97960.0, 99248.50375748075, 101696.49151754194, 54878.45121548813, 54969.0, 58620.7068964661, 88715.55642221747, 88911.55544222238, 9016.233620142099, 9033.457992520469, 9035.0, 9035.322350823582, 9161.267098632226, 37908.81045594755, 48832.093432524605, 53082.46917530775, 54051.459485406216, 55165.0, 67165.66417167883, 68030.0, 77497.0, 77833.0, 79838.2016179854, 12024.939875300568, 12149.514019439124, 12235.510579576718, 12306.692332691755, 12467.812982805446, 11869.287842729464, 11971.10216733731, 12258.20321679088, 12524.185927914656, 12833.807492887427, 11705.414729263674, 11852.229605075643, 11925.501517800134, 12558.937205313914, 12563.685907852378, 11761.235519691192, 11849.170558061198, 12014.63956081319, 12415.44130514111, 12446.25322480654, 11652.30086194831, 11753.412329383325, 11825.349605771766, 11916.821247681464, 12302.507899683913, 9572.282078843975, 9635.903640963501, 9676.564554595201, 9770.80458390814, 9915.603375865174, 9480.55100130273, 9538.95230523843, 10174.593016279267, 10506.842397364198, 10555.63055293061], "value": 7396.729485563708, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 111.0, 111.0, 111.0, 110.0, 109.0, 112.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 127.0, 162.0, 184.0, 127.0, 156.0, 154.0, 129.0, 182.0, 126.0], "value": 150.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 162.0, 162.0, 162.0, 163.0, 169.0, 162.0, 162.0, 161.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 164.0, 74.0, 77.0, 75.0, 74.0, 163.0, 75.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 146.0, 86.0, 85.0, 86.0, 144.0, 87.0, 85.0, 138.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 146.0, 149.0, 145.0, 147.0, 146.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 94.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 54.0, 57.0, 55.0, 54.0, 57.0, 54.0, 58.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 63.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 79.0, 81.0, 91.0, 84.0, 83.0, 81.0, 112.0, 80.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 130.0, 122.0, 120.0, 135.0, 128.0, 138.0, 132.0, 144.0, 133.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 158.0, 164.0, 169.0, 163.0, 160.0, 158.0, 157.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 61.0, 64.0, 63.0, 63.0, 65.0, 62.0, 62.0], "value": 63.4, "unit": "score"}]}]} 22:20:46 INFO - Return code: 0 22:20:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:20:46 ERROR - # TBPL SUCCESS # 22:20:46 INFO - Running post-action listener: _resource_record_post_action 22:20:46 INFO - ##### 22:20:46 INFO - ##### Finished run-tests step (success) 22:20:46 INFO - ##### 22:20:46 INFO - Running post-run listener: _resource_record_post_run 22:20:47 INFO - Total resource usage - Wall time: 1191s; CPU: 14.0%; Read bytes: 4096; Write bytes: 541548544; Read time: 16; Write time: 500252 22:20:47 INFO - TinderboxPrint: CPU usage
13.5% 22:20:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:20:47 INFO - TinderboxPrint: I/O write bytes / time
541,548,544 / 500,252 22:20:47 INFO - TinderboxPrint: CPU idle
8,215.6 (86.5%) 22:20:47 INFO - TinderboxPrint: CPU user
1,229.7 (12.9%) 22:20:47 INFO - TinderboxPrint: Swap in / out
0 / 0 22:20:47 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254103552; Read time: 0; Write time: 281520 22:20:47 INFO - run-tests - Wall time: 1174s; CPU: 14.0%; Read bytes: 4096; Write bytes: 287268864; Read time: 16; Write time: 218716 22:20:47 INFO - Running post-run listener: _upload_blobber_files 22:20:47 INFO - Blob upload gear active. 22:20:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:20:47 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 22:20:47 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:20:47 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:20:48 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:48 INFO - (blobuploader) - INFO - Done attempting. 22:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:52 INFO - (blobuploader) - INFO - Done attempting. 22:20:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:20:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:20:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:53 INFO - (blobuploader) - INFO - Done attempting. 22:20:53 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:53 INFO - Return code: 0 22:20:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:20:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c5522bb987dbfdc109a1385499e798fdde255f64b3213e3f8e89f16f81b9e0c886f9c4656b15b978abbc8fd75aa1da706328f4a75782f5608525a0cc9252608b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b648f869f9e9f8cb6528326dc3df05412ee8eaa0046b0837beb312646c4f0011172dc3cf72f36139160da3278d8308dae42c650f1ea4171336cb7e8d9ac24403", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/35924aa23d356d76b7508a4c558779ed48b2c00fb57f408121b0acbf9ff52d2196a0ecfd384aa527c24cb7b5d97e05a786244a90a9ab55eb7de979862d373734"} 22:20:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:20:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:20:53 INFO - Contents: 22:20:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c5522bb987dbfdc109a1385499e798fdde255f64b3213e3f8e89f16f81b9e0c886f9c4656b15b978abbc8fd75aa1da706328f4a75782f5608525a0cc9252608b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b648f869f9e9f8cb6528326dc3df05412ee8eaa0046b0837beb312646c4f0011172dc3cf72f36139160da3278d8308dae42c650f1ea4171336cb7e8d9ac24403", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/35924aa23d356d76b7508a4c558779ed48b2c00fb57f408121b0acbf9ff52d2196a0ecfd384aa527c24cb7b5d97e05a786244a90a9ab55eb7de979862d373734"} 22:20:53 INFO - Running post-run listener: copy_logs_to_upload_dir 22:20:53 INFO - Copying logs to upload dir... 22:20:53 INFO - mkdir: /builds/slave/test/build/upload/logs 22:20:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.285041 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-02 22:20:53.003619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:53.006975) ========= 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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c5522bb987dbfdc109a1385499e798fdde255f64b3213e3f8e89f16f81b9e0c886f9c4656b15b978abbc8fd75aa1da706328f4a75782f5608525a0cc9252608b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b648f869f9e9f8cb6528326dc3df05412ee8eaa0046b0837beb312646c4f0011172dc3cf72f36139160da3278d8308dae42c650f1ea4171336cb7e8d9ac24403", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/35924aa23d356d76b7508a4c558779ed48b2c00fb57f408121b0acbf9ff52d2196a0ecfd384aa527c24cb7b5d97e05a786244a90a9ab55eb7de979862d373734"} build_url:https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011167 build_url: 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c5522bb987dbfdc109a1385499e798fdde255f64b3213e3f8e89f16f81b9e0c886f9c4656b15b978abbc8fd75aa1da706328f4a75782f5608525a0cc9252608b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b648f869f9e9f8cb6528326dc3df05412ee8eaa0046b0837beb312646c4f0011172dc3cf72f36139160da3278d8308dae42c650f1ea4171336cb7e8d9ac24403", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/35924aa23d356d76b7508a4c558779ed48b2c00fb57f408121b0acbf9ff52d2196a0ecfd384aa527c24cb7b5d97e05a786244a90a9ab55eb7de979862d373734"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:53.031392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:53.031714) ========= 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=574dc5aafe09851ec43d388c000001b6-1464929982.869050-1063233708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004460 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:53.116573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:20:53.116888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:20:53.117206) ========= ========= Total master_lag: 0.16 =========