builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1469452678.12 results: success (0) buildid: 20160725020629 builduid: e9cffd69a2054ead90893fbe00f05894 revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.115418) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.115788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.116063) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.148106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.148372) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-25 06:17:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.1M=0s 2016-07-25 06:17:58 (25.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137145 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.300161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.300499) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015208 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.353196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:58.353513) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --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=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-25 06:17:58,557 truncating revision to first 12 chars 2016-07-25 06:17:58,557 Setting DEBUG logging. 2016-07-25 06:17:58,557 attempt 1/10 2016-07-25 06:17:58,557 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/517663b29d7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 06:17:58,866 unpacking tar archive at: mozilla-aurora-517663b29d7f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:59.015127) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:59.015460) ========= script_repo_revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:59.015856) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:59.016137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:59.026945) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2016-07-25 06:17:59.027212) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:17:59 INFO - MultiFileLogger online at 20160725 06:17:59 in /builds/slave/test 06:17:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:17:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:17:59 INFO - {'append_to_log': False, 06:17:59 INFO - 'base_work_dir': '/builds/slave/test', 06:17:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:17:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:17:59 INFO - 'branch': 'Mozilla-Aurora', 06:17:59 INFO - 'buildbot_json_path': 'buildprops.json', 06:17:59 INFO - 'config_files': ('talos/linux_config.py',), 06:17:59 INFO - 'default_actions': ('clobber', 06:17:59 INFO - 'read-buildbot-config', 06:17:59 INFO - 'download-and-extract', 06:17:59 INFO - 'populate-webroot', 06:17:59 INFO - 'create-virtualenv', 06:17:59 INFO - 'install', 06:17:59 INFO - 'run-tests'), 06:17:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:17:59 INFO - 'download_minidump_stackwalk': True, 06:17:59 INFO - 'download_symbols': 'ondemand', 06:17:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:17:59 INFO - 'tooltool.py': '/tools/tooltool.py', 06:17:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:17:59 INFO - '/tools/misc-python/virtualenv.py')}, 06:17:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:17:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:17:59 INFO - 'installer_path': 'installer.exe', 06:17:59 INFO - 'log_level': 'info', 06:17:59 INFO - 'log_name': 'talos', 06:17:59 INFO - 'log_to_console': True, 06:17:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:17:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:17:59 INFO - 'opt_config_files': (), 06:17:59 INFO - 'pip_index': False, 06:17:59 INFO - 'sps_profile': False, 06:17:59 INFO - 'sps_profile_interval': 0, 06:17:59 INFO - 'suite': 'dromaeojs', 06:17:59 INFO - 'system_bits': '32', 06:17:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:17:59 INFO - 'title': 'talos-linux64-ix-051', 06:17:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:17:59 INFO - 'use_talos_json': True, 06:17:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:17:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:17:59 INFO - 'work_dir': 'build'} 06:17:59 INFO - ##### 06:17:59 INFO - ##### Running clobber step. 06:17:59 INFO - ##### 06:17:59 INFO - Running pre-action listener: _resource_record_pre_action 06:17:59 INFO - Running main action method: clobber 06:17:59 INFO - rmtree: /builds/slave/test/build 06:17:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:17:59 INFO - Running post-action listener: _resource_record_post_action 06:17:59 INFO - ##### 06:17:59 INFO - ##### Finished clobber step (success) 06:17:59 INFO - ##### 06:17:59 INFO - ##### 06:17:59 INFO - ##### Running read-buildbot-config step. 06:17:59 INFO - ##### 06:17:59 INFO - Running pre-action listener: _resource_record_pre_action 06:17:59 INFO - Running main action method: read_buildbot_config 06:17:59 INFO - Using buildbot properties: 06:17:59 INFO - { 06:17:59 INFO - "project": "", 06:17:59 INFO - "product": "firefox", 06:17:59 INFO - "script_repo_revision": "production", 06:17:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:17:59 INFO - "repository": "", 06:17:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:17:59 INFO - "buildid": "20160725020629", 06:17:59 INFO - "pgo_build": "True", 06:17:59 INFO - "basedir": "/builds/slave/test", 06:17:59 INFO - "buildnumber": 630, 06:17:59 INFO - "slavename": "talos-linux64-ix-051", 06:17:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:17:59 INFO - "platform": "ubuntu64_hw", 06:17:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:17:59 INFO - "branch": "mozilla-aurora", 06:17:59 INFO - "repo_path": "releases/mozilla-aurora", 06:17:59 INFO - "revision": "517663b29d7ffc2ce91f90dc7beaac4fa35e52e5", 06:17:59 INFO - "stage_platform": "linux64-pgo", 06:17:59 INFO - "builduid": "e9cffd69a2054ead90893fbe00f05894", 06:17:59 INFO - "slavebuilddir": "test" 06:17:59 INFO - } 06:17:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 06:17:59 INFO - Running post-action listener: _resource_record_post_action 06:17:59 INFO - ##### 06:17:59 INFO - ##### Finished read-buildbot-config step (success) 06:17:59 INFO - ##### 06:17:59 INFO - ##### 06:17:59 INFO - ##### Running download-and-extract step. 06:17:59 INFO - ##### 06:17:59 INFO - Running pre-action listener: _resource_record_pre_action 06:17:59 INFO - Running main action method: download_and_extract 06:17:59 INFO - mkdir: /builds/slave/test/build/tests 06:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:17:59 INFO - https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:17:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:17:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:17:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:18:26 INFO - Downloaded 1361 bytes. 06:18:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:18:26 INFO - Using the following test package requirements: 06:18:26 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 06:18:26 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:18:26 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:18:26 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'jsshell-linux-x86_64.zip'], 06:18:26 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:18:26 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 06:18:26 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:18:26 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:18:26 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:18:26 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:18:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:18:26 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:18:26 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')]} 06:18:26 INFO - https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:18:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:18:41 INFO - Downloaded 24494715 bytes. 06:18:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:18:42 INFO - Return code: 0 06:18:42 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:18:42 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')]} 06:18:42 INFO - https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:18:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:19:12 INFO - Downloaded 24494715 bytes. 06:19:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:19:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:19:14 INFO - Return code: 0 06:19:14 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')]} 06:19:14 INFO - https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:19:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:19:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:19:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:19:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:19:15 INFO - Downloaded 10833369 bytes. 06:19:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:19:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:19:15 INFO - Return code: 0 06:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:15 INFO - https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:19:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:19:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:19:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:19:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:19:17 INFO - Downloaded 63633994 bytes. 06:19:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:19:17 INFO - mkdir: /builds/slave/test/properties 06:19:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:19:17 INFO - Writing to file /builds/slave/test/properties/build_url 06:19:17 INFO - Contents: 06:19:17 INFO - build_url:https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:19:18 INFO - Running post-action listener: _resource_record_post_action 06:19:18 INFO - Running post-action listener: set_extra_try_arguments 06:19:18 INFO - ##### 06:19:18 INFO - ##### Finished download-and-extract step (success) 06:19:18 INFO - ##### 06:19:18 INFO - ##### 06:19:18 INFO - ##### Running populate-webroot step. 06:19:18 INFO - ##### 06:19:18 INFO - Running pre-action listener: _resource_record_pre_action 06:19:18 INFO - Running main action method: populate_webroot 06:19:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:19:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:19:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:19:18 INFO - '--tppagecycles', 06:19:18 INFO - '7'], 06:19:18 INFO - 'tests': ['tsvgm']}}, 06:19:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:19:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:19:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:19:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:19:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['damp', 'tps']}, 06:19:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'tests': ['damp', 'tps']}, 06:19:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['dromaeo_dom']}, 06:19:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:19:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'other-e10s': {'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['a11yr', 06:19:18 INFO - 'ts_paint', 06:19:18 INFO - 'tpaint', 06:19:18 INFO - 'sessionrestore', 06:19:18 INFO - 'sessionrestore_no_auto_restore', 06:19:18 INFO - 'tabpaint']}, 06:19:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['tsvgx', 06:19:18 INFO - 'tsvgr_opacity', 06:19:18 INFO - 'tart', 06:19:18 INFO - 'tscrollx', 06:19:18 INFO - 'cart']}, 06:19:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:19:18 INFO - 'tsvgr_opacity', 06:19:18 INFO - 'tart', 06:19:18 INFO - 'tscrollx', 06:19:18 INFO - 'cart']}, 06:19:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'talos_options': ['--disable-e10s'], 06:19:18 INFO - 'tests': ['tp5o']}, 06:19:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'tests': ['tp5o']}, 06:19:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'talos_options': ['--disable-e10s', 06:19:18 INFO - '--xperf_path', 06:19:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:18 INFO - 'tests': ['tp5n']}, 06:19:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:18 INFO - 'talos_options': ['--xperf_path', 06:19:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:18 INFO - 'tests': ['tp5n']}}, 06:19:18 INFO - 'talos.zip': {'path': '', 06:19:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:19:18 INFO - Running post-action listener: _resource_record_post_action 06:19:18 INFO - ##### 06:19:18 INFO - ##### Finished populate-webroot step (success) 06:19:18 INFO - ##### 06:19:18 INFO - ##### 06:19:18 INFO - ##### Running create-virtualenv step. 06:19:18 INFO - ##### 06:19:18 INFO - Running pre-action listener: _resource_record_pre_action 06:19:18 INFO - Running main action method: create_virtualenv 06:19:18 INFO - Creating virtualenv /builds/slave/test/build/venv 06:19:18 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 06:19:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:19:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:19:18 INFO - Using real prefix '/usr' 06:19:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:19:19 INFO - Installing distribute.............................................................................................................................................................................................done. 06:19:21 INFO - Installing pip.................done. 06:19:21 INFO - Return code: 0 06:19:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:19:21 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')]} 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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')]} 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:21 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 06:19:21 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 06:19:21 INFO - Using env: {'DISPLAY': ':0', 06:19:21 INFO - 'HOME': '/home/cltbld', 06:19:21 INFO - 'LANG': 'en_US.UTF-8', 06:19:21 INFO - 'LANGUAGE': 'en_US:en', 06:19:21 INFO - 'LOGNAME': 'cltbld', 06:19:21 INFO - 'MAIL': '/var/mail/cltbld', 06:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:21 INFO - 'MOZ_NO_REMOTE': '1', 06:19:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:21 INFO - 'NO_EM_RESTART': '1', 06:19:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:21 INFO - 'PWD': '/builds/slave/test', 06:19:21 INFO - 'SHELL': '/bin/bash', 06:19:21 INFO - 'SHLVL': '1', 06:19:21 INFO - 'TERM': 'linux', 06:19:21 INFO - 'TMOUT': '86400', 06:19:21 INFO - 'USER': 'cltbld', 06:19:21 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:21 INFO - '_': '/tools/buildbot/bin/python'} 06:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:21 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 06:19:21 INFO - Cleaning up... 06:19:21 INFO - Return code: 0 06:19:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:19:21 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')]} 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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')]} 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:21 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 06:19:21 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 06:19:21 INFO - Using env: {'DISPLAY': ':0', 06:19:21 INFO - 'HOME': '/home/cltbld', 06:19:21 INFO - 'LANG': 'en_US.UTF-8', 06:19:21 INFO - 'LANGUAGE': 'en_US:en', 06:19:21 INFO - 'LOGNAME': 'cltbld', 06:19:21 INFO - 'MAIL': '/var/mail/cltbld', 06:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:21 INFO - 'MOZ_NO_REMOTE': '1', 06:19:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:21 INFO - 'NO_EM_RESTART': '1', 06:19:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:21 INFO - 'PWD': '/builds/slave/test', 06:19:21 INFO - 'SHELL': '/bin/bash', 06:19:21 INFO - 'SHLVL': '1', 06:19:21 INFO - 'TERM': 'linux', 06:19:21 INFO - 'TMOUT': '86400', 06:19:21 INFO - 'USER': 'cltbld', 06:19:21 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:21 INFO - '_': '/tools/buildbot/bin/python'} 06:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:21 INFO - Downloading/unpacking psutil>=3.1.1 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:19:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:23 INFO - Installing collected packages: psutil 06:19:23 INFO - Running setup.py install for psutil 06:19:23 INFO - building 'psutil._psutil_linux' extension 06:19:23 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 06:19:23 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 06:19:23 INFO - building 'psutil._psutil_posix' extension 06:19:23 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 06:19:23 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 06:19:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:24 INFO - Successfully installed psutil 06:19:24 INFO - Cleaning up... 06:19:24 INFO - Return code: 0 06:19:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:19:24 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')]} 06:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:24 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')]} 06:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:24 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:24 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 06:19:24 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 06:19:24 INFO - Using env: {'DISPLAY': ':0', 06:19:24 INFO - 'HOME': '/home/cltbld', 06:19:24 INFO - 'LANG': 'en_US.UTF-8', 06:19:24 INFO - 'LANGUAGE': 'en_US:en', 06:19:24 INFO - 'LOGNAME': 'cltbld', 06:19:24 INFO - 'MAIL': '/var/mail/cltbld', 06:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:24 INFO - 'MOZ_NO_REMOTE': '1', 06:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:24 INFO - 'NO_EM_RESTART': '1', 06:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:24 INFO - 'PWD': '/builds/slave/test', 06:19:24 INFO - 'SHELL': '/bin/bash', 06:19:24 INFO - 'SHLVL': '1', 06:19:24 INFO - 'TERM': 'linux', 06:19:24 INFO - 'TMOUT': '86400', 06:19:24 INFO - 'USER': 'cltbld', 06:19:24 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:24 INFO - '_': '/tools/buildbot/bin/python'} 06:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:19:26 INFO - Installing collected packages: mozsystemmonitor 06:19:26 INFO - Running setup.py install for mozsystemmonitor 06:19:26 INFO - Successfully installed mozsystemmonitor 06:19:26 INFO - Cleaning up... 06:19:26 INFO - Return code: 0 06:19:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:19:26 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')]} 06:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:26 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')]} 06:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:26 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:26 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 06:19:26 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 06:19:26 INFO - Using env: {'DISPLAY': ':0', 06:19:26 INFO - 'HOME': '/home/cltbld', 06:19:26 INFO - 'LANG': 'en_US.UTF-8', 06:19:26 INFO - 'LANGUAGE': 'en_US:en', 06:19:26 INFO - 'LOGNAME': 'cltbld', 06:19:26 INFO - 'MAIL': '/var/mail/cltbld', 06:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:26 INFO - 'MOZ_NO_REMOTE': '1', 06:19:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:26 INFO - 'NO_EM_RESTART': '1', 06:19:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:26 INFO - 'PWD': '/builds/slave/test', 06:19:26 INFO - 'SHELL': '/bin/bash', 06:19:26 INFO - 'SHLVL': '1', 06:19:26 INFO - 'TERM': 'linux', 06:19:26 INFO - 'TMOUT': '86400', 06:19:26 INFO - 'USER': 'cltbld', 06:19:26 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:26 INFO - '_': '/tools/buildbot/bin/python'} 06:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:26 INFO - Downloading/unpacking jsonschema==2.5.1 06:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:29 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:29 INFO - warning: no files found matching '*.txt' 06:19:29 INFO - no previously-included directories found matching 'build' 06:19:29 INFO - no previously-included directories found matching 'dist' 06:19:29 INFO - no previously-included directories found matching '.git*' 06:19:29 INFO - Installing collected packages: jsonschema, functools32 06:19:29 INFO - Running setup.py install for functools32 06:19:29 INFO - warning: no files found matching '*.txt' 06:19:29 INFO - no previously-included directories found matching 'build' 06:19:29 INFO - no previously-included directories found matching 'dist' 06:19:29 INFO - no previously-included directories found matching '.git*' 06:19:29 INFO - Successfully installed jsonschema functools32 06:19:29 INFO - Cleaning up... 06:19:29 INFO - Return code: 0 06:19:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:19:29 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')]} 06:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:29 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')]} 06:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:29 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:29 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 06:19:29 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 06:19:29 INFO - Using env: {'DISPLAY': ':0', 06:19:29 INFO - 'HOME': '/home/cltbld', 06:19:29 INFO - 'LANG': 'en_US.UTF-8', 06:19:29 INFO - 'LANGUAGE': 'en_US:en', 06:19:29 INFO - 'LOGNAME': 'cltbld', 06:19:29 INFO - 'MAIL': '/var/mail/cltbld', 06:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:29 INFO - 'MOZ_NO_REMOTE': '1', 06:19:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:29 INFO - 'NO_EM_RESTART': '1', 06:19:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:29 INFO - 'PWD': '/builds/slave/test', 06:19:29 INFO - 'SHELL': '/bin/bash', 06:19:29 INFO - 'SHLVL': '1', 06:19:29 INFO - 'TERM': 'linux', 06:19:29 INFO - 'TMOUT': '86400', 06:19:29 INFO - 'USER': 'cltbld', 06:19:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:29 INFO - '_': '/tools/buildbot/bin/python'} 06:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:29 INFO - Downloading/unpacking blobuploader==1.2.4 06:19:29 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 06:19:29 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 06:19:29 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 06:19:29 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 06:19:29 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 06:19:29 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 06:19:31 INFO - Downloading blobuploader-1.2.4.tar.gz 06:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:19:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:19:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:32 INFO - Downloading docopt-0.6.1.tar.gz 06:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:19:32 INFO - Installing collected packages: blobuploader, requests, docopt 06:19:32 INFO - Running setup.py install for blobuploader 06:19:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:19:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:19:32 INFO - Running setup.py install for requests 06:19:32 INFO - Running setup.py install for docopt 06:19:32 INFO - Successfully installed blobuploader requests docopt 06:19:32 INFO - Cleaning up... 06:19:32 INFO - Return code: 0 06:19:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:32 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 06:19:32 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 06:19:32 INFO - Using env: {'DISPLAY': ':0', 06:19:32 INFO - 'HOME': '/home/cltbld', 06:19:32 INFO - 'LANG': 'en_US.UTF-8', 06:19:32 INFO - 'LANGUAGE': 'en_US:en', 06:19:32 INFO - 'LOGNAME': 'cltbld', 06:19:32 INFO - 'MAIL': '/var/mail/cltbld', 06:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:32 INFO - 'MOZ_NO_REMOTE': '1', 06:19:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:32 INFO - 'NO_EM_RESTART': '1', 06:19:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:32 INFO - 'PWD': '/builds/slave/test', 06:19:32 INFO - 'SHELL': '/bin/bash', 06:19:32 INFO - 'SHLVL': '1', 06:19:32 INFO - 'TERM': 'linux', 06:19:32 INFO - 'TMOUT': '86400', 06:19:32 INFO - 'USER': 'cltbld', 06:19:32 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:32 INFO - '_': '/tools/buildbot/bin/python'} 06:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:33 INFO - Running setup.py (path:/tmp/pip-yK99Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:33 INFO - Running setup.py (path:/tmp/pip-QafWxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:33 INFO - Running setup.py (path:/tmp/pip-y5beH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:33 INFO - Running setup.py (path:/tmp/pip-c7hKNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:33 INFO - Running setup.py (path:/tmp/pip-OP8m91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:33 INFO - Running setup.py (path:/tmp/pip-pyzToW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:33 INFO - Running setup.py (path:/tmp/pip-q4F2Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:33 INFO - Running setup.py (path:/tmp/pip-LKwRYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:33 INFO - Running setup.py (path:/tmp/pip-y3O59B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:33 INFO - Running setup.py (path:/tmp/pip-4Cb_ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:33 INFO - Running setup.py (path:/tmp/pip-1pjlLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:33 INFO - Running setup.py (path:/tmp/pip-_0T06D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:33 INFO - Running setup.py (path:/tmp/pip-QarAjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:34 INFO - Running setup.py (path:/tmp/pip-_syeHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:34 INFO - Running setup.py (path:/tmp/pip-2xWLPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:34 INFO - Running setup.py (path:/tmp/pip-ONYNLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:34 INFO - Running setup.py (path:/tmp/pip-8Fw9GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:19:34 INFO - Running setup.py install for manifestparser 06:19:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:34 INFO - Running setup.py install for mozcrash 06:19:34 INFO - Running setup.py install for mozdebug 06:19:34 INFO - Running setup.py install for mozdevice 06:19:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:19:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:34 INFO - Running setup.py install for mozfile 06:19:34 INFO - Running setup.py install for mozhttpd 06:19:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:34 INFO - Running setup.py install for mozinfo 06:19:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for mozInstall 06:19:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for mozleak 06:19:35 INFO - Running setup.py install for mozlog 06:19:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for moznetwork 06:19:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for mozprocess 06:19:35 INFO - Running setup.py install for mozprofile 06:19:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for mozrunner 06:19:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:35 INFO - Running setup.py install for mozscreenshot 06:19:35 INFO - Running setup.py install for moztest 06:19:35 INFO - Running setup.py install for mozversion 06:19:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:36 INFO - Cleaning up... 06:19:36 INFO - Return code: 0 06:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:36 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:36 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 06:19:36 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 06:19:36 INFO - Using env: {'DISPLAY': ':0', 06:19:36 INFO - 'HOME': '/home/cltbld', 06:19:36 INFO - 'LANG': 'en_US.UTF-8', 06:19:36 INFO - 'LANGUAGE': 'en_US:en', 06:19:36 INFO - 'LOGNAME': 'cltbld', 06:19:36 INFO - 'MAIL': '/var/mail/cltbld', 06:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:36 INFO - 'MOZ_NO_REMOTE': '1', 06:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:36 INFO - 'NO_EM_RESTART': '1', 06:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:36 INFO - 'PWD': '/builds/slave/test', 06:19:36 INFO - 'SHELL': '/bin/bash', 06:19:36 INFO - 'SHLVL': '1', 06:19:36 INFO - 'TERM': 'linux', 06:19:36 INFO - 'TMOUT': '86400', 06:19:36 INFO - 'USER': 'cltbld', 06:19:36 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:36 INFO - '_': '/tools/buildbot/bin/python'} 06:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:36 INFO - Running setup.py (path:/tmp/pip-ZRz_eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:36 INFO - Running setup.py (path:/tmp/pip-ulk7VA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:36 INFO - Running setup.py (path:/tmp/pip-s9zqMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:36 INFO - Running setup.py (path:/tmp/pip-b8vi_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:36 INFO - Running setup.py (path:/tmp/pip-5Sl0ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:36 INFO - Running setup.py (path:/tmp/pip-lnXCu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:36 INFO - Running setup.py (path:/tmp/pip-D66Wtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:36 INFO - Running setup.py (path:/tmp/pip-TkmSm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:36 INFO - Running setup.py (path:/tmp/pip-qUt_Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:36 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)) 06:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:36 INFO - Running setup.py (path:/tmp/pip-Hud5Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:37 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)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:37 INFO - Running setup.py (path:/tmp/pip-e2PLaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:37 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)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:37 INFO - Running setup.py (path:/tmp/pip-O5Qnw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:37 INFO - Running setup.py (path:/tmp/pip-Q9atbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:37 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)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:37 INFO - Running setup.py (path:/tmp/pip-95MCb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:37 INFO - Running setup.py (path:/tmp/pip-fsVTvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:37 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)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:37 INFO - Running setup.py (path:/tmp/pip-FvgXL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:37 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)) 06:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:37 INFO - Running setup.py (path:/tmp/pip-O2WzW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:37 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)) 06:19:37 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)) 06:19:37 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)) 06:19:37 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)) 06:19:37 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)) 06:19:37 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)) 06:19:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:37 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 06:19:37 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 06:19:37 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 06:19:37 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 06:19:37 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 06:19:37 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 06:19:39 INFO - Downloading blessings-1.6.tar.gz 06:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:39 INFO - Installing collected packages: blessings 06:19:39 INFO - Running setup.py install for blessings 06:19:39 INFO - Successfully installed blessings 06:19:39 INFO - Cleaning up... 06:19:39 INFO - Return code: 0 06:19:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:39 INFO - Reading from file tmpfile_stdout 06:19:39 INFO - Current package versions: 06:19:39 INFO - argparse == 1.2.1 06:19:39 INFO - blessings == 1.6 06:19:39 INFO - blobuploader == 1.2.4 06:19:39 INFO - docopt == 0.6.1 06:19:39 INFO - functools32 == 3.2.3-2 06:19:39 INFO - jsonschema == 2.5.1 06:19:39 INFO - manifestparser == 1.1 06:19:39 INFO - mozInstall == 1.12 06:19:39 INFO - mozcrash == 0.17 06:19:39 INFO - mozdebug == 0.1 06:19:39 INFO - mozdevice == 0.48 06:19:39 INFO - mozfile == 1.2 06:19:39 INFO - mozhttpd == 0.7 06:19:39 INFO - mozinfo == 0.9 06:19:39 INFO - mozleak == 0.1 06:19:39 INFO - mozlog == 3.2 06:19:39 INFO - moznetwork == 0.27 06:19:39 INFO - mozprocess == 0.23 06:19:39 INFO - mozprofile == 0.28 06:19:39 INFO - mozrunner == 6.12 06:19:39 INFO - mozscreenshot == 0.1 06:19:39 INFO - mozsystemmonitor == 0.3 06:19:39 INFO - moztest == 0.7 06:19:39 INFO - mozversion == 1.4 06:19:39 INFO - psutil == 3.1.1 06:19:39 INFO - requests == 1.2.3 06:19:39 INFO - wsgiref == 0.1.2 06:19:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:39 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:39 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 06:19:39 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 06:19:39 INFO - Using env: {'DISPLAY': ':0', 06:19:39 INFO - 'HOME': '/home/cltbld', 06:19:39 INFO - 'LANG': 'en_US.UTF-8', 06:19:39 INFO - 'LANGUAGE': 'en_US:en', 06:19:39 INFO - 'LOGNAME': 'cltbld', 06:19:39 INFO - 'MAIL': '/var/mail/cltbld', 06:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:39 INFO - 'MOZ_NO_REMOTE': '1', 06:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:39 INFO - 'NO_EM_RESTART': '1', 06:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:39 INFO - 'PWD': '/builds/slave/test', 06:19:39 INFO - 'SHELL': '/bin/bash', 06:19:39 INFO - 'SHLVL': '1', 06:19:39 INFO - 'TERM': 'linux', 06:19:39 INFO - 'TMOUT': '86400', 06:19:39 INFO - 'USER': 'cltbld', 06:19:39 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:39 INFO - '_': '/tools/buildbot/bin/python'} 06:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 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)) 06:19:40 INFO - Cleaning up... 06:19:40 INFO - Return code: 0 06:19:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:40 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:40 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 06:19:40 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 06:19:40 INFO - Using env: {'DISPLAY': ':0', 06:19:40 INFO - 'HOME': '/home/cltbld', 06:19:40 INFO - 'LANG': 'en_US.UTF-8', 06:19:40 INFO - 'LANGUAGE': 'en_US:en', 06:19:40 INFO - 'LOGNAME': 'cltbld', 06:19:40 INFO - 'MAIL': '/var/mail/cltbld', 06:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:40 INFO - 'MOZ_NO_REMOTE': '1', 06:19:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:40 INFO - 'NO_EM_RESTART': '1', 06:19:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:40 INFO - 'PWD': '/builds/slave/test', 06:19:40 INFO - 'SHELL': '/bin/bash', 06:19:40 INFO - 'SHLVL': '1', 06:19:40 INFO - 'TERM': 'linux', 06:19:40 INFO - 'TMOUT': '86400', 06:19:40 INFO - 'USER': 'cltbld', 06:19:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:40 INFO - '_': '/tools/buildbot/bin/python'} 06:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:19:40 INFO - Cleaning up... 06:19:40 INFO - Return code: 0 06:19:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:40 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 0x7f2f6eca6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2303020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', '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 06:19:40 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 06:19:40 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 06:19:40 INFO - Using env: {'DISPLAY': ':0', 06:19:40 INFO - 'HOME': '/home/cltbld', 06:19:40 INFO - 'LANG': 'en_US.UTF-8', 06:19:40 INFO - 'LANGUAGE': 'en_US:en', 06:19:40 INFO - 'LOGNAME': 'cltbld', 06:19:40 INFO - 'MAIL': '/var/mail/cltbld', 06:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:40 INFO - 'MOZ_NO_REMOTE': '1', 06:19:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:40 INFO - 'NO_EM_RESTART': '1', 06:19:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:40 INFO - 'PWD': '/builds/slave/test', 06:19:40 INFO - 'SHELL': '/bin/bash', 06:19:40 INFO - 'SHLVL': '1', 06:19:40 INFO - 'TERM': 'linux', 06:19:40 INFO - 'TMOUT': '86400', 06:19:40 INFO - 'USER': 'cltbld', 06:19:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:40 INFO - '_': '/tools/buildbot/bin/python'} 06:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:40 INFO - Downloading/unpacking flake8 06:19:40 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 06:19:40 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 06:19:40 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 06:19:40 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 06:19:40 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 06:19:40 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 06:19:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:19:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:19:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:42 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 06:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:19:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:43 INFO - no previously-included directories found matching 'docs/_build' 06:19:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:19:43 INFO - Running setup.py install for pep8 06:19:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:43 INFO - no previously-included directories found matching 'docs/_build' 06:19:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:19:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:19:43 INFO - Cleaning up... 06:19:44 INFO - Return code: 0 06:19:44 INFO - Running post-action listener: _resource_record_post_action 06:19:44 INFO - Running post-action listener: _start_resource_monitoring 06:19:44 INFO - Starting resource monitoring. 06:19:44 INFO - ##### 06:19:44 INFO - ##### Finished create-virtualenv step (success) 06:19:44 INFO - ##### 06:19:44 INFO - ##### 06:19:44 INFO - ##### Running install step. 06:19:44 INFO - ##### 06:19:44 INFO - Running pre-action listener: _resource_record_pre_action 06:19:44 INFO - Running main action method: install 06:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:44 INFO - Reading from file tmpfile_stdout 06:19:44 INFO - Detecting whether we're running mozinstall >=1.0... 06:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:19:44 INFO - Reading from file tmpfile_stdout 06:19:44 INFO - Output received: 06:19:44 INFO - Usage: mozinstall [options] installer 06:19:44 INFO - Options: 06:19:44 INFO - -h, --help show this help message and exit 06:19:44 INFO - -d DEST, --destination=DEST 06:19:44 INFO - Directory to install application into. [default: 06:19:44 INFO - "/builds/slave/test"] 06:19:44 INFO - --app=APP Application being installed. [default: firefox] 06:19:44 INFO - mkdir: /builds/slave/test/build/application 06:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:20:00 INFO - Reading from file tmpfile_stdout 06:20:00 INFO - Output received: 06:20:00 INFO - /builds/slave/test/build/application/firefox/firefox 06:20:00 INFO - Running post-action listener: _resource_record_post_action 06:20:00 INFO - ##### 06:20:00 INFO - ##### Finished install step (success) 06:20:00 INFO - ##### 06:20:00 INFO - ##### 06:20:00 INFO - ##### Running run-tests step. 06:20:00 INFO - ##### 06:20:00 INFO - Running pre-action listener: _resource_record_pre_action 06:20:00 INFO - Running main action method: run_tests 06:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:20:00 INFO - Python 2.7.3 06:20:00 INFO - Return code: 0 06:20:00 INFO - grabbing minidump binary from tooltool 06:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:00 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 0x23952e0>, '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 0x2301140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23015d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:20:00 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 06:20:00 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 06:20:00 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 06:20:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:20:00 INFO - Return code: 0 06:20:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:20:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:20:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:20:01 INFO - Return code: 0 06:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:20:01 INFO - Using env: {'DISPLAY': ':0', 06:20:01 INFO - 'HOME': '/home/cltbld', 06:20:01 INFO - 'LANG': 'en_US.UTF-8', 06:20:01 INFO - 'LANGUAGE': 'en_US:en', 06:20:01 INFO - 'LOGNAME': 'cltbld', 06:20:01 INFO - 'MAIL': '/var/mail/cltbld', 06:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:01 INFO - 'MOZ_NO_REMOTE': '1', 06:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:01 INFO - 'NO_EM_RESTART': '1', 06:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:01 INFO - 'PWD': '/builds/slave/test', 06:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:20:01 INFO - 'SHELL': '/bin/bash', 06:20:01 INFO - 'SHLVL': '1', 06:20:01 INFO - 'TERM': 'linux', 06:20:01 INFO - 'TMOUT': '86400', 06:20:01 INFO - 'USER': 'cltbld', 06:20:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469', 06:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:01 INFO - '_': '/tools/buildbot/bin/python'} 06:20:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:20:01 INFO - mozversion application_buildid: 20160725020629 06:20:01 INFO - mozversion application_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 06:20:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:20:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:20:01 INFO - mozversion application_name: Firefox 06:20:01 INFO - mozversion application_remotingname: firefox-dev 06:20:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:20:01 INFO - mozversion application_vendor: Mozilla 06:20:01 INFO - mozversion application_version: 49.0a2 06:20:01 INFO - mozversion platform_buildid: 20160725020629 06:20:01 INFO - mozversion platform_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 06:20:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:20:01 INFO - mozversion platform_version: 49.0a2 06:20:01 INFO - using testdate: 1469452801 06:20:01 INFO - actual date: 1469452801 06:20:01 INFO - starting webserver on 'localhost:60231' 06:20:01 INFO - SUITE-START | Running 2 tests 06:20:01 INFO - TEST-START | dromaeo_css 06:20:01 INFO - operating with platform_type : linux_ 06:20:01 INFO - Initialising browser for dromaeo_css test... 06:20:01 INFO - TEST-INFO | started process 3359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP4yMFi/profile http://localhost:60231/getInfo.html) 06:20:05 INFO - PROCESS | 3359 | __metrics Screen width/height:1600/1200 06:20:05 INFO - PROCESS | 3359 | colorDepth:24 06:20:05 INFO - PROCESS | 3359 | Browser inner width/height: 1024/697 06:20:05 INFO - PROCESS | 3359 | __metrics 06:20:06 INFO - PROCESS | 3359 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:20:06 ERROR - PROCESS | 3359 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:20:07 INFO - TEST-INFO | 3359: exit 0 06:20:07 INFO - Browser initialized. 06:20:07 INFO - Running cycle 1/1 for dromaeo_css test... 06:20:07 INFO - TEST-INFO | started process 3451 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP4yMFi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:19 INFO - PROCESS | 3451 | RSS: Main: 145248256 06:20:19 INFO - PROCESS | 3451 | 06:21:11 INFO - PROCESS | 3451 | 1469452871045 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] 06:23:18 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60231/tests/dromaeo/cssquery-ext.html) 06:23:19 INFO - PROCESS | 3451 | RSS: Main: 253407232 06:23:19 INFO - PROCESS | 3451 | 06:26:21 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-ext.html (next: http://localhost:60231/tests/dromaeo/cssquery-jquery.html) 06:26:22 INFO - PROCESS | 3451 | RSS: Main: 231002112 06:26:22 INFO - PROCESS | 3451 | 06:29:24 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60231/tests/dromaeo/cssquery-mootools.html) 06:29:24 INFO - PROCESS | 3451 | RSS: Main: 224075776 06:29:24 INFO - PROCESS | 3451 | 06:32:26 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60231/tests/dromaeo/cssquery-prototype.html) 06:32:27 INFO - PROCESS | 3451 | RSS: Main: 225464320 06:32:27 INFO - PROCESS | 3451 | 06:35:29 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60231/tests/dromaeo/cssquery-yui.html) 06:35:30 INFO - PROCESS | 3451 | RSS: Main: 214220800 06:35:30 INFO - PROCESS | 3451 | 06:38:32 INFO - PROCESS | 3451 | Cycle 1(1): loaded http://localhost:60231/tests/dromaeo/cssquery-yui.html (next: http://localhost:60231/tests/dromaeo/cssquery-dojo.html) 06:38:33 INFO - PROCESS | 3451 | RSS: Main: 261873664 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | __start_tp_report 06:38:33 INFO - PROCESS | 3451 | _x_x_mozilla_page_load 06:38:33 INFO - PROCESS | 3451 | _x_x_mozilla_page_load_details 06:38:33 INFO - PROCESS | 3451 | |i|pagename|runs| 06:38:33 INFO - PROCESS | 3451 | |0;dojo.html;3319.5186697928802;3319.6680331966804;3335.833208339584;3352.4971254311836;3385.966140338596;5438;5515.448455154483;5530.723463826803;5533.446655334465;5543.390227075019;5414.187871819229;5496.670199788005;5499.092649712803;5499.972500137494;5535.086710692741;4924.8276310328965;4970.602351811855;5003.349564556603;5039.596832253411;5071.873203169914;4882.194437917757;4904.337914381566;4928.359313289267;4934.703917764928;4997.60019198465;4921.360223171001;4974.626902982291;4994.425641051289;4995.675281106716;5097.515736005093;4997.100521906055;5092.261622064817;5106.744662766865;5111.616628752859;5135.820246291362;4808.182608956486;4815.66290359675;4860.562549370538;4868.586170175539;4947.134251506008;5310.9734451327495;5359.1425371940695;5373.059714549938;5380.24676545284;5382.273393091921;5683.175939488751;5857.384974577651;5861;5885.823425297205;5889.646621202742;5781.566382521328;5864.7067646617925;5868.2371291732225;5931.6441013539325;5934.910976335358;5556.138798486241;5562.916556251659;5564.8052318169075;5566.137248726453;5566.972165139148;5819.679917604488;6006.069059295816;6056.757729690763;6068.4538391544975;6068.726907289182;5721.57088218377;5797.333306669702;5799.739011744482;5877.294724633071;5884.941150588439;5917.733701983421;5939.673317967467;5964.105384192406;5970.432808883149;5974.163617093611;5688.374278829326;5815.273090863642;5890.734916928748;5891.175235467038;5909.409059094056;5880.794172203952;5887.558433117448;5894.086416605433;5906.5570082244;5921.763129474859;4520.299353600131;4527.796249168795;4531.43357080365;4547.226971414867;4555.772211389417;3905.179912218466;3920.6079392060565;3952.505936757905;4011;4060.0661847774586;5523.033469142964;5525.088360420566;5547.2511210986;5550.111982082847;5556.388797232301;5842.415758424124;5846.912296315559;5846.941530584725;5853.795117170964;5882.23530940975;5492.258545096441;5496.367917689517;5535.25274087993;5552.111662134039;5554.750036248379;5016.849494515171;5049.116404629175;5050.217216331474;5050.621203409759;5052.191649336089;6102.481289090477;6142.447179753754;6151.200343955257;6157.876842463215;6169.321374648785;5768.682722450221;5804.767809287581;5855.765769369177;5865.912011319919;5928.258967629046;6131.96934015345;6135.478484328703;6136.938630613815;6138.140660307471;6147.877042459215;5631.831045068655;5821.679807610622;5823.475887170175;5827.24245848037;5828.795992140254;5697.601167918372;5764.596478246483;5772.422757724194;5836.445537673914;5837.066069428871;5656.151577263443;5687.260656114679;5694.515966142759;5698.31620205578;5702.51528620072;5543.085390910616;5632.267805185464;5679.545636349165;5689.975804355256;5691.573132015033;5612.4387561245185;5642.351129620146;5690.77236910519;5694.288213973254;5727.942720572741;5759.193712880284;5805.274340707411;5829.970850145722;5832.708364581838;5832.912506312494;5447.101228297445;5550.250716153268;5639.830805075854;5717.22817419643;5741 06:38:33 INFO - PROCESS | 3451 | |1;ext.html;593.922790037295;634.2515831319043;647.1457675867855;655.8425977765338;657.6613044282195;32987.99999999997;36115.45826812596;44650.106997860064;45465.54534454655;45597;47874.281885771656;48196.277055844104;48209.51790482094;48325.516744832545;48330.516694833044;8975.820483590323;9143.771405714844;9147.405418647766;9148.588313525874;9150.49672268025;8957.910420895772;9034.503102329369;9062.139096785828;9065.864012039825;9069.274458043341;9012.369134160612;9015.639374425014;9046.638134474613;9051.592678329458;9056.77358066047;9060.275177985744;9095.181433670936;9100.81798364029;9109.726708198765;9116.635334586641;9080.636774529012;9114.13415725505;9115.316351273681;9136.132067453582;9146.908530914672;44101.55898441007;44247.77876110631;44495;44612.3308150378;44693.77653111746;3752;3867.883963481072;3893.3186692328727;4221.6833737469815;4602.194615942197;33881.66118338834;34526;34709.47935780965;34780.304393912236;34845.12887177841;4498.257787465064;4534.478534968488;4556.54434556545;4626.976865115686;4765.7140571565815;383886;386218;387149;387537;388246;38087.61912380841;63547.36452635415;63559.04661430082;63860.68069659715;63967.36032639707;39245.41131883024;40742.388864167064;40757.79621101876;40762.79618601888;40780.79609601993;17041.659166816593;17310.65378692419;17326.04706741116;17342.219600118024;17360.39238626642;14244;14335.78496322556;14336.49822256216;14340.928295358457;14342.282885855666;9071.637134514678;9082.22801061904;9096.772580685403;9147.908520914705;9151.267898568098;3453.3783918894337;3458.6887180153403;3505.088676944013;3561.0919215599856;3571.8928432147077;1034.425893629043;1179.2983175010854;1199.322382853685;1217.2088142707312;1245.6926859188868;34313.48529772055;34321.31357372839;34344;34405.655943440244;34605.13487162841;1194.8207768834745;1214.4110106598293;1223.3699644682995;1226.5277868020876;1292.6703690559036;17493.125343733016;17852.821471785115;18104;18131.63736725258;18135.45593632193;658430;662540;666618;669261;669283;63609;64048.35951640424;64081.359186408474;64083.35916640867;64404.67797661076;1331.5206525650758;1416.964575885611;1450.52132796177;1451.7894905238682;1463.4219483304034;34217.48673769945;35580.28839423249;35589.93230203098;35708;36427.08932276714;54106.729466352415;54521.45478545322;54695.72652136714;54712;54715.72642136764;450340;451218;451412;452592;455311;27236.591451127853;29791.404171916005;30783.2304192397;30842.383152337276;32036;52112.21831672604;53920.73039634777;57932.13101803392;58039.41960580509;58482.707586461795;44645;46121.769391152826;47579.44817315451;48573;49629.751851241956;45997.08005839797;47103.293450598954;47143;47189.29216061831;47307.290390644856;47614;48440.27339589839;49178.50821491739;49206.75396622994;49300.75349623229;40663;41585.37621935734;42191.15617687568;42243.15513689647;42752;31448.213794655316;31853.840730796197;31955.201119972186;31999.200019999687;32247.838760806048 06:38:33 INFO - PROCESS | 3451 | |2;jquery.html;416.61255032819463;421.1156571200481;423.91097869447424;425.41717846550233;440.0780365135041;18483.722744158833;19377.903110484447;20227.696584551224;20781.584368312626;20962.37112886612;19357.903210483964;19658.901705491488;19728.605427891434;20072.996350182468;20076.899615501938;5564.749586268627;5773.249477567911;5807.680577568232;5817.650940943536;5829.242198514187;4121.237571049365;4577.267637178053;5636.126400407943;5740.397258287882;6299.023651334051;6315.052742088677;6326.272478664943;6353.809385718436;6371.171747672796;6375.266844312917;6208.410201030895;6246.344133865924;6306.337834527354;6313.368663133696;6318.210223722035;3827.598102199257;5162.838361368662;5453.182022696627;5650.943490565083;5657.915131273035;23987;24024.759752402428;24050;24404.633930491058;24422.877885610458;2530.215633153728;2542.300867261499;2667.1198504493473;2706.9458610827674;3038.5745995560637;13926.93036534821;14589.197594132318;14637.194954277511;15136.394544218218;15184.772228416583;2016.5966806638585;2036.3178335257705;2057.4136371134155;2084.4267826347723;2116.9576608467896;75591.62204188999;78456.60771696105;78590.60704696497;79114.60442697807;79235.6038219811;9514.762130946781;10239.334443261314;10526.263161578616;10760.892391076146;11126.165537584713;14049.859501405059;14050.929745351208;14074.155550666992;14161.929190353982;14169.645758855904;7482.775516734507;7738.458307918391;7907.56508392044;8039.437239393186;8074.475159114756;7568.772936811904;7656.61716914152;7725.111612164561;7741.419393545394;7880.605969701492;4307.827686892552;4319.956800432018;4389.604935555815;4444.622207112367;4483.730976141442;2051.4050925231813;2077;2116.1641151744975;2142.6250406178856;2151.3008272311563;810.5947026486757;1036.5387402605768;1119.8936101070385;1142.3659868772745;1147.8737338892715;19281.710774338397;19419.2232310706;19490.122944467534;19674.213031478866;20120.19519219215;1014.644874294006;1060.289605964006;1067.151614466501;1108.844761733358;1134.500819639356;10980.835287470694;11177.776444471228;11239.887601124048;11254.268472549258;11258.943705281421;506202;571024;572072;576458;580739;65543.34456655468;85264.14735852562;88281.55859220662;93632;96882;1046.9581216751244;1075.704181350135;1077.4451157653814;1090.9781804364027;1091.3779145886767;17708.114594270493;17830.5542361442;18066.81933180704;18157.727634085237;18481.81518184801;40549.79725101356;40750.59249407468;42822.57177428186;42926.35610465908;43321.350179746696;60773.08840367486;61516;62117;62439.6878015607;63944.68027660018;8648.30813534903;8704.956475217583;8719.956400217958;8904.065073167225;9026.097390260922;10811.24321297533;12695.174813637084;12802.74394512086;12934.547290844775;12960.481580736665;11083.667489975314;11156.609518666808;11385.146114041314;11543.8268425972;11698.880468394947;8831.955840220759;11161.330320180816;11318.830217546909;11556.306621602731;11687.707807304885;10126.645567405105;11276.548938042715;11418.714532136763;11649.534018639159;11773;8627.094155113873;8703.347248956481;8725.171108744658;8853.69012084574;8958.19376256114;11473.942630286796;11541.826872597087;11563.6530904073;11638.941805290919;11684.532618695157 06:38:33 INFO - PROCESS | 3451 | |3;mootools.html;1323.032014784484;1525.7711343298504;1559.3372816552965;1750.3086280919038;1876.5965317456744;8362.79093022674;8764.386492945488;8788.209061184492;8980.371374003822;8982.685606003792;8785.868211976825;8942.508162051085;8955.596998135083;8967.058458861808;8967.282617390609;2070.710100585919;2326.7905888470036;2389.330987323552;2395.3173345596465;2428.235105941623;2431.5744744669614;2440.1581454398292;2441.389652586853;2442.1818690738537;2442.4016116051594;2333.509962907783;2407.5305315463493;2408.50625621748;2409.6265078912797;2411.9758802412016;2077.366403247009;2307.157887371114;2365.4322962488877;2371.4664200554907;2387.3673476528734;2319.0839618350824;2319.3389883883015;2320.6983092197906;2324.4886125052462;2357.26924653358;9056.230220431271;9097.13577210164;9108.635654573809;9119.954400227956;9150.725478235663;5276.709780962045;5386.703731294777;5391.245225668409;5394.433584473652;5399.62202645815;3140.4975203967483;3229.4994275887275;3273.427150248721;3465.2549701813978;3467.1678797088516;1188.9702757431046;1193.3854065156452;1196.8025275829477;1210.1589395370222;1232.4638782129803;12732.87267127326;12745.235285882876;12782.744345113046;12801.871981280161;12808;4497.123061003073;4606.078784243165;4613.423322084739;4703.482616912137;4787.880302992453;10547.472626368652;10555.683329500127;10585.84121238182;10603.628872989408;10612.20408469368;4859.757012149449;5330.147176451749;5335.519803217729;5337.2527846101575;5344.759485823147;4840.128776820206;5004.674696144812;5005.474425185376;5031.4213865405945;5051.621128415383;4147.854825081107;4159.355299928516;4221.472315960505;4269.637080848162;4326.545712700184;1308.8625694302152;1332.8333958255218;1337.699017721005;1346.912450690702;1347.9595612131457;1077.5582011375297;1085.0776839686362;1087.7443800706828;1091.983620245697;1095.3427943234155;6197;6269.341719119518;6318.494520438446;6322.209723784527;6377.872442551123;937.9718608441758;946.536197263336;1001.7145113642723;1004.9648262310784;1050.14937900301;4344.891377715519;4413.668974826901;4434.445694288214;4489.842855500042;4490.2142125127975;10772.569097236023;11432;11445.027172690414;11446.198766086294;11454.198206125659;11044.33733975964;11214.158938079676;11326.773464530663;11368.715782105513;11391.544338226378;8527.23254907049;8628.741137765752;8632.784180395665;8659.393842431095;8697;6223.719932602953;6279.843003924939;6299.244090708961;6340.651264180515;6370.968145159244;10321.225908056776;10478.318909270873;10635.787284254116;10699;10743.731406714582;10746.301490403099;10763.569457221936;10874.891251087703;10934.179936504635;10949.945250273699;7096.2548932363425;7466.626668666654;7599.848003040019;7677.0403699537555;7703.422243331885;9082.137196966278;9093.272538197061;9125.77185570366;9164.495952722664;9170.312226583166;7867.09528404218;8091.919080809352;8106;8144.022717273965;8240.793980150544;8015.47899386538;8039.67841286342;8042.758717238492;8229.917700823155;8324.417290789586;7378.852422951403;7381.33567978863;7389.778306650809;7427.517211381243;7491.662875170521;5281.55106815925;5281.709505977208;5520.917186242129;5558.888822223614;5648.661080335193;5651.632643878134;5665.886682266414;5669.716514174357;5698.857528561819;5744.540436765133 06:38:33 INFO - PROCESS | 3451 | |4;prototype.html;354.2507596433543;358.96769290763837;363.1321142469498;363.5927760907782;370.33894498320467;5259.132243179875;5340.866478338044;5384.5692344612435;5411.350637923445;5426.972865135669;5299.099153143965;5332.493413125757;5352.2506849041165;5389.8113566025195;5408.188771684249;3784.545854497464;4022.7385219960684;4099.872535052869;4178.415021896937;4188.97905510472;4176.895577610554;4177.206330797154;4179.310413781737;4182.6235638792505;4192.49690037196;4071.7760523171214;4139.19285739281;4163.604457576541;4163.68772342074;4197.664186865059;4012.257732319501;4159.83360665573;4193.182329445759;4200.3699445083175;4229.7462152270655;3736.532933383327;3742;3744.850205991757;3785.9399368176955;3787.450819631166;5094.61790365724;5131.461196574343;5142.51146141116;5149.76826042829;5179.249008893689;4645.465771436295;4737.928931066037;4754.049190161982;4770.212914869043;4815.133276010317;5478.342598888119;5487.216654587275;5494.725263736797;5501.889962200734;5518.641288316287;4057.6753859691153;4060.3706425503797;4063.0248740302113;4083.4079058536645;4088.2436749201493;5520.420355862617;5533.197686335499;5619.438056194389;5753.223314852525;5789.478946894799;4108.1783643271465;4109.4246805447265;4116.320807066861;4128.60778226068;4151.232022075926;4202.201581699467;4202.474690663667;4204.747715137101;4207.600277973587;4212.789360531961;4535.138323718549;4702.623790096784;4726.669133160714;4763.904721905612;4792.544708252711;5539.390667026624;5540.418256083134;5540.63985840919;5548.722563871871;5586.748596313176;1429.7640889253364;1443.4731323067215;1444.8410674825761;1454.338276084379;1462.1008080030704;927.8979312275644;942.9858552121724;946.2713710442921;955.1899151108611;970.6748239339901;4839.274108883626;4845.491223421561;4848.903021939542;4860.226017503656;4868.805247790048;5030.2957585938;5032.924506132411;5064.620153488503;5084.949150508522;5112.923306150411;4667.836625718083;4843.95156048435;4858.271259311132;4872.585830204402;4907.435644900811;2703.6485256916867;2711.7423844734717;2712.6707913122427;2727.2227415186576;2742.245882382361;5224.738763061831;5276.894462110737;5297.337832770903;5310.840674779763;5322.547583455373;4828.841078141221;5205.375354957429;5237.764300606405;5239.952009598096;5249.921251181236;5237.659552129099;5248.895022099537;5250.842474725764;5263.499967503158;5280.3663560373;5056.367954005749;5062.164742817394;5075.7818123650795;5083.983203359269;5110.642255042111;4829.975850120727;5006.574441172541;5065.594752419871;5130.614264367087;5135.640505164752;5345.4654534546235;5725.885482290414;5798.710064496674;6007.008843540942;6022.548308876764;4167.124903770121;4177.8537751178565;4180.226658068267;4182.707210495236;4191.140816132746;5596.013415237532;5774.769009239583;5781.768729250783;5796.101604251347;5805.216295800013;5520.641158324696;5577.386487486455;5599.412061733459;5612.326520817364;5616.775328986795;5513.173024046425;5525.557955363642;5559.16612508127;5620.213170156263;5641.8589535261945;5363.597730170175;5424.403315635198;5497.367802702581;5511.311086114236;5520.61984503874;5592.5738936570915;5600.663960162403;5617.747201375866;5639.7744090237575;5642.774289028393;5516.317523155476;5550.195221692751;5551.333839939233;5569.164625306236;5580.330360356621 06:38:33 INFO - PROCESS | 3451 | |5;yui.html;866.1554983890708;874.7725591346249;903.2909166304453;907.3965812734532;918;14878.256087195638;15689.37242510301;15879.761803572941;15887.205639718026;15935.442259520905;14957.252137393141;15208.923955380234;15253.31360088796;15401.460948866792;15542;703.4688809948507;821.9930585033346;858.1375717404031;862.0775769926181;863.3826813828111;857.8670306102533;866.3891956170916;868.8436081505326;870.1472556894247;870.6212797433137;896.1934259166737;900.572228191607;900.5722281916103;900.9009009009004;900.9144131307548;733.3180142467503;874.33113668044;893.6023469556051;905.959231834569;906.5784410249199;668.7425341243608;668.8896332105197;669.517947078103;670.0418401685588;670.3263220463451;15460.768088478682;15462.458813941457;15542.844571554253;15567.922160389238;15574.688506229926;14678.633034174125;15098.773518397233;15485.14831684257;15529.767053494206;15771.526854194277;12081.456334464881;12201.511939522408;12444.191127576678;13892.722145557032;14353;4760.744978552269;4819.036192761461;4859.902801943941;4910.337104490884;5018.548330650223;74091;74887.62556187238;74902.62548687276;75112.24887751162;75364;11779.469923853447;12058.864695179316;12081.154319197572;12204;12287.447064882102;20971.475713107295;21012.894935525226;21053.263135790174;21111.155553777677;21140;8637.136286371313;8760.08019157992;8778.166074223067;8790.252828509649;8940.821183576421;4951.480094590088;4984.576311013532;5057.974710126426;5090.185570308733;5163.638545301868;3563.2160924596546;3649.087728067983;3661.761985470989;3665.578458477256;3666.431703086026;606.3974434045527;621.3196549777986;631.4064779107624;636.9522285828581;637.8819918315127;609.4788955443121;736.988945165823;762.2073044034142;808.4502538273913;827.1067247372823;11232.213745037932;11441.141914356456;11613.3612651305;11627.6511704649;11634.76730465386;796.4066770256196;803.819140693351;819.647551552826;822.481836443049;831.1563762780783;5208.7916483341;5413.350397952271;5499.450054994469;5517.200005999108;5571.804986825442;78162.21837781549;120516;124492.75507244993;128727;135010.3249483766;82661.04033757994;111624.44187779009;111883.44058279657;112650.43674781574;113054.43472782584;96500;96606;97425.51287243661;99741;100481;10291;10301.124404425707;10405.47972601382;10430.53062612169;10579.31234469757;45079.77460112678;45943;48525.757371212916;48644.27033594428;50281.49718502768;50376;50541.241881371076;60617;73471.26528734644;75884;13801.723965520834;13900.72198556003;13999.930000349934;14186.503472378417;14327.28363581796;16012.279447425128;16196.838031619533;16198.514044578682;16475;16636.334546618;14539.92730036343;14765.261736913326;14914.328855201758;15382.923085384502;15384.461543845911;14634.560963171121;14793.852061479247;15095.32071056827;15681.921590391976;15713.68572628518;13553.796693049415;13995;14006.509772157926;14173.291335432981;14435.639109022359;11686.123540734648;11792.52829886795;11825.940870295593;12025.579104731292;12107.939460302994;11929.522819087138;11987.820182697442;12054.51781928713;12095.697607559881;12282.140250182401 06:38:33 INFO - PROCESS | 3451 | __end_tp_report 06:38:33 INFO - PROCESS | 3451 | __start_cc_report 06:38:33 INFO - PROCESS | 3451 | _x_x_mozilla_cycle_collect,907 06:38:33 INFO - PROCESS | 3451 | __end_cc_report 06:38:33 INFO - PROCESS | 3451 | __startTimestamp1469453913168__endTimestamp 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | ------- Summary: start ------- 06:38:33 INFO - PROCESS | 3451 | Number of tests: 6 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#0] dojo.html Cycles:165 Average:5453.76 Median:5642.35 stddev:607.05 (10.8%) stddev-sans-first:585.37 06:38:33 INFO - PROCESS | 3451 | Values: 3319.5 3319.7 3335.8 3352.5 3386.0 5438.0 5515.4 5530.7 5533.4 5543.4 5414.2 5496.7 5499.1 5500.0 5535.1 4924.8 4970.6 5003.3 5039.6 5071.9 4882.2 4904.3 4928.4 4934.7 4997.6 4921.4 4974.6 4994.4 4995.7 5097.5 4997.1 5092.3 5106.7 5111.6 5135.8 4808.2 4815.7 4860.6 4868.6 4947.1 5311.0 5359.1 5373.1 5380.2 5382.3 5683.2 5857.4 5861.0 5885.8 5889.6 5781.6 5864.7 5868.2 5931.6 5934.9 5556.1 5562.9 5564.8 5566.1 5567.0 5819.7 6006.1 6056.8 6068.5 6068.7 5721.6 5797.3 5799.7 5877.3 5884.9 5917.7 5939.7 5964.1 5970.4 5974.2 5688.4 5815.3 5890.7 5891.2 5909.4 5880.8 5887.6 5894.1 5906.6 5921.8 4520.3 4527.8 4531.4 4547.2 4555.8 3905.2 3920.6 3952.5 4011.0 4060.1 5523.0 5525.1 5547.3 5550.1 5556.4 5842.4 5846.9 5846.9 5853.8 5882.2 5492.3 5496.4 5535.3 5552.1 5554.8 5016.8 5049.1 5050.2 5050.6 5052.2 6102.5 6142.4 6151.2 6157.9 6169.3 5768.7 5804.8 5855.8 5865.9 5928.3 6132.0 6135.5 6136.9 6138.1 6147.9 5631.8 5821.7 5823.5 5827.2 5828.8 5697.6 5764.6 5772.4 5836.4 5837.1 5656.2 5687.3 5694.5 5698.3 5702.5 5543.1 5632.3 5679.5 5690.0 5691.6 5612.4 5642.4 5690.8 5694.3 5727.9 5759.2 5805.3 5830.0 5832.7 5832.9 5447.1 5550.3 5639.8 5717.2 5741.0 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#1] ext.html Cycles:180 Average:66206.08 Median:450779.00 stddev:137374.67 (30.5%) stddev-sans-first:137671.72 06:38:33 INFO - PROCESS | 3451 | Values: 593.9 634.3 647.1 655.8 657.7 32988.0 36115.5 44650.1 45465.5 45597.0 47874.3 48196.3 48209.5 48325.5 48330.5 8975.8 9143.8 9147.4 9148.6 9150.5 8957.9 9034.5 9062.1 9065.9 9069.3 9012.4 9015.6 9046.6 9051.6 9056.8 9060.3 9095.2 9100.8 9109.7 9116.6 9080.6 9114.1 9115.3 9136.1 9146.9 44101.6 44247.8 44495.0 44612.3 44693.8 3752.0 3867.9 3893.3 4221.7 4602.2 33881.7 34526.0 34709.5 34780.3 34845.1 4498.3 4534.5 4556.5 4627.0 4765.7 383886.0 386218.0 387149.0 387537.0 388246.0 38087.6 63547.4 63559.0 63860.7 63967.4 39245.4 40742.4 40757.8 40762.8 40780.8 17041.7 17310.7 17326.0 17342.2 17360.4 14244.0 14335.8 14336.5 14340.9 14342.3 9071.6 9082.2 9096.8 9147.9 9151.3 3453.4 3458.7 3505.1 3561.1 3571.9 1034.4 1179.3 1199.3 1217.2 1245.7 34313.5 34321.3 34344.0 34405.7 34605.1 1194.8 1214.4 1223.4 1226.5 1292.7 17493.1 17852.8 18104.0 18131.6 18135.5 658430.0 662540.0 666618.0 669261.0 669283.0 63609.0 64048.4 64081.4 64083.4 64404.7 1331.5 1417.0 1450.5 1451.8 1463.4 34217.5 35580.3 35589.9 35708.0 36427.1 54106.7 54521.5 54695.7 54712.0 54715.7 450340.0 451218.0 451412.0 452592.0 455311.0 27236.6 29791.4 30783.2 30842.4 32036.0 52112.2 53920.7 57932.1 58039.4 58482.7 44645.0 46121.8 47579.4 48573.0 49629.8 45997.1 47103.3 47143.0 47189.3 47307.3 47614.0 48440.3 49178.5 49206.8 49300.8 40663.0 41585.4 42191.2 42243.2 42752.0 31448.2 31853.8 31955.2 31999.2 32247.8 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#2] jquery.html Cycles:180 Average:31000.59 Median:24413.76 stddev:92205.92 (377.7%) stddev-sans-first:92435.98 06:38:33 INFO - PROCESS | 3451 | Values: 416.6 421.1 423.9 425.4 440.1 18483.7 19377.9 20227.7 20781.6 20962.4 19357.9 19658.9 19728.6 20073.0 20076.9 5564.7 5773.2 5807.7 5817.7 5829.2 4121.2 4577.3 5636.1 5740.4 6299.0 6315.1 6326.3 6353.8 6371.2 6375.3 6208.4 6246.3 6306.3 6313.4 6318.2 3827.6 5162.8 5453.2 5650.9 5657.9 23987.0 24024.8 24050.0 24404.6 24422.9 2530.2 2542.3 2667.1 2706.9 3038.6 13926.9 14589.2 14637.2 15136.4 15184.8 2016.6 2036.3 2057.4 2084.4 2117.0 75591.6 78456.6 78590.6 79114.6 79235.6 9514.8 10239.3 10526.3 10760.9 11126.2 14049.9 14050.9 14074.2 14161.9 14169.6 7482.8 7738.5 7907.6 8039.4 8074.5 7568.8 7656.6 7725.1 7741.4 7880.6 4307.8 4320.0 4389.6 4444.6 4483.7 2051.4 2077.0 2116.2 2142.6 2151.3 810.6 1036.5 1119.9 1142.4 1147.9 19281.7 19419.2 19490.1 19674.2 20120.2 1014.6 1060.3 1067.2 1108.8 1134.5 10980.8 11177.8 11239.9 11254.3 11258.9 506202.0 571024.0 572072.0 576458.0 580739.0 65543.3 85264.1 88281.6 93632.0 96882.0 1047.0 1075.7 1077.4 1091.0 1091.4 17708.1 17830.6 18066.8 18157.7 18481.8 40549.8 40750.6 42822.6 42926.4 43321.4 60773.1 61516.0 62117.0 62439.7 63944.7 8648.3 8705.0 8720.0 8904.1 9026.1 10811.2 12695.2 12802.7 12934.5 12960.5 11083.7 11156.6 11385.1 11543.8 11698.9 8832.0 11161.3 11318.8 11556.3 11687.7 10126.6 11276.5 11418.7 11649.5 11773.0 8627.1 8703.3 8725.2 8853.7 8958.2 11473.9 11541.8 11563.7 11638.9 11684.5 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#3] mootools.html Cycles:180 Average:6028.75 Median:4462.14 stddev:3426.89 (76.8%) stddev-sans-first:3418.25 06:38:33 INFO - PROCESS | 3451 | Values: 1323.0 1525.8 1559.3 1750.3 1876.6 8362.8 8764.4 8788.2 8980.4 8982.7 8785.9 8942.5 8955.6 8967.1 8967.3 2070.7 2326.8 2389.3 2395.3 2428.2 2431.6 2440.2 2441.4 2442.2 2442.4 2333.5 2407.5 2408.5 2409.6 2412.0 2077.4 2307.2 2365.4 2371.5 2387.4 2319.1 2319.3 2320.7 2324.5 2357.3 9056.2 9097.1 9108.6 9120.0 9150.7 5276.7 5386.7 5391.2 5394.4 5399.6 3140.5 3229.5 3273.4 3465.3 3467.2 1189.0 1193.4 1196.8 1210.2 1232.5 12732.9 12745.2 12782.7 12801.9 12808.0 4497.1 4606.1 4613.4 4703.5 4787.9 10547.5 10555.7 10585.8 10603.6 10612.2 4859.8 5330.1 5335.5 5337.3 5344.8 4840.1 5004.7 5005.5 5031.4 5051.6 4147.9 4159.4 4221.5 4269.6 4326.5 1308.9 1332.8 1337.7 1346.9 1348.0 1077.6 1085.1 1087.7 1092.0 1095.3 6197.0 6269.3 6318.5 6322.2 6377.9 938.0 946.5 1001.7 1005.0 1050.1 4344.9 4413.7 4434.4 4489.8 4490.2 10772.6 11432.0 11445.0 11446.2 11454.2 11044.3 11214.2 11326.8 11368.7 11391.5 8527.2 8628.7 8632.8 8659.4 8697.0 6223.7 6279.8 6299.2 6340.7 6371.0 10321.2 10478.3 10635.8 10699.0 10743.7 10746.3 10763.6 10874.9 10934.2 10949.9 7096.3 7466.6 7599.8 7677.0 7703.4 9082.1 9093.3 9125.8 9164.5 9170.3 7867.1 8091.9 8106.0 8144.0 8240.8 8015.5 8039.7 8042.8 8229.9 8324.4 7378.9 7381.3 7389.8 7427.5 7491.7 5281.6 5281.7 5520.9 5558.9 5648.7 5651.6 5665.9 5669.7 5698.9 5744.5 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#4] prototype.html Cycles:180 Average:4562.11 Median:5084.47 stddev:1304.04 (25.6%) stddev-sans-first:1268.88 06:38:33 INFO - PROCESS | 3451 | Values: 354.3 359.0 363.1 363.6 370.3 5259.1 5340.9 5384.6 5411.4 5427.0 5299.1 5332.5 5352.3 5389.8 5408.2 3784.5 4022.7 4099.9 4178.4 4189.0 4176.9 4177.2 4179.3 4182.6 4192.5 4071.8 4139.2 4163.6 4163.7 4197.7 4012.3 4159.8 4193.2 4200.4 4229.7 3736.5 3742.0 3744.9 3785.9 3787.5 5094.6 5131.5 5142.5 5149.8 5179.2 4645.5 4737.9 4754.0 4770.2 4815.1 5478.3 5487.2 5494.7 5501.9 5518.6 4057.7 4060.4 4063.0 4083.4 4088.2 5520.4 5533.2 5619.4 5753.2 5789.5 4108.2 4109.4 4116.3 4128.6 4151.2 4202.2 4202.5 4204.7 4207.6 4212.8 4535.1 4702.6 4726.7 4763.9 4792.5 5539.4 5540.4 5540.6 5548.7 5586.7 1429.8 1443.5 1444.8 1454.3 1462.1 927.9 943.0 946.3 955.2 970.7 4839.3 4845.5 4848.9 4860.2 4868.8 5030.3 5032.9 5064.6 5084.9 5112.9 4667.8 4844.0 4858.3 4872.6 4907.4 2703.6 2711.7 2712.7 2727.2 2742.2 5224.7 5276.9 5297.3 5310.8 5322.5 4828.8 5205.4 5237.8 5240.0 5249.9 5237.7 5248.9 5250.8 5263.5 5280.4 5056.4 5062.2 5075.8 5084.0 5110.6 4830.0 5006.6 5065.6 5130.6 5135.6 5345.5 5725.9 5798.7 6007.0 6022.5 4167.1 4177.9 4180.2 4182.7 4191.1 5596.0 5774.8 5781.8 5796.1 5805.2 5520.6 5577.4 5599.4 5612.3 5616.8 5513.2 5525.6 5559.2 5620.2 5641.9 5363.6 5424.4 5497.4 5511.3 5520.6 5592.6 5600.7 5617.7 5639.8 5642.8 5516.3 5550.2 5551.3 5569.2 5580.3 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - PROCESS | 3451 | [#5] yui.html Cycles:180 Average:21388.05 Median:3655.42 stddev:30913.29 (845.7%) stddev-sans-first:30961.60 06:38:33 INFO - PROCESS | 3451 | Values: 866.2 874.8 903.3 907.4 918.0 14878.3 15689.4 15879.8 15887.2 15935.4 14957.3 15208.9 15253.3 15401.5 15542.0 703.5 822.0 858.1 862.1 863.4 857.9 866.4 868.8 870.1 870.6 896.2 900.6 900.6 900.9 900.9 733.3 874.3 893.6 906.0 906.6 668.7 668.9 669.5 670.0 670.3 15460.8 15462.5 15542.8 15567.9 15574.7 14678.6 15098.8 15485.1 15529.8 15771.5 12081.5 12201.5 12444.2 13892.7 14353.0 4760.7 4819.0 4859.9 4910.3 5018.5 74091.0 74887.6 74902.6 75112.2 75364.0 11779.5 12058.9 12081.2 12204.0 12287.4 20971.5 21012.9 21053.3 21111.2 21140.0 8637.1 8760.1 8778.2 8790.3 8940.8 4951.5 4984.6 5058.0 5090.2 5163.6 3563.2 3649.1 3661.8 3665.6 3666.4 606.4 621.3 631.4 637.0 637.9 609.5 737.0 762.2 808.5 827.1 11232.2 11441.1 11613.4 11627.7 11634.8 796.4 803.8 819.6 822.5 831.2 5208.8 5413.4 5499.5 5517.2 5571.8 78162.2 120516.0 124492.8 128727.0 135010.3 82661.0 111624.4 111883.4 112650.4 113054.4 96500.0 96606.0 97425.5 99741.0 100481.0 10291.0 10301.1 10405.5 10430.5 10579.3 45079.8 45943.0 48525.8 48644.3 50281.5 50376.0 50541.2 60617.0 73471.3 75884.0 13801.7 13900.7 13999.9 14186.5 14327.3 16012.3 16196.8 16198.5 16475.0 16636.3 14539.9 14765.3 14914.3 15382.9 15384.5 14634.6 14793.9 15095.3 15681.9 15713.7 13553.8 13995.0 14006.5 14173.3 14435.6 11686.1 11792.5 11825.9 12025.6 12107.9 11929.5 11987.8 12054.5 12095.7 12282.1 06:38:33 INFO - PROCESS | 3451 | -------- Summary: end -------- 06:38:33 INFO - PROCESS | 3451 | 06:38:33 INFO - TEST-INFO | 3451: exit 0 06:38:33 INFO - TEST-OK | dromaeo_css | took 1112458ms 06:38:33 INFO - TEST-START | kraken 06:38:33 INFO - operating with platform_type : linux_ 06:38:33 INFO - Initialising browser for kraken test... 06:38:33 INFO - TEST-INFO | started process 3641 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBmo70z/profile http://localhost:60231/getInfo.html) 06:38:37 INFO - PROCESS | 3641 | __metrics Screen width/height:1600/1200 06:38:37 INFO - PROCESS | 3641 | colorDepth:24 06:38:37 INFO - PROCESS | 3641 | Browser inner width/height: 1024/697 06:38:37 INFO - PROCESS | 3641 | __metrics 06:38:38 INFO - PROCESS | 3641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:38:38 ERROR - PROCESS | 3641 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:38:39 INFO - TEST-INFO | 3641: exit 0 06:38:39 INFO - Browser initialized. 06:38:39 INFO - Running cycle 1/1 for kraken test... 06:38:39 INFO - TEST-INFO | started process 3732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBmo70z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:51 INFO - PROCESS | 3732 | RSS: Main: 145911808 06:38:51 INFO - PROCESS | 3732 | 06:38:53 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60231/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:54 INFO - PROCESS | 3732 | RSS: Main: 194232320 06:38:54 INFO - PROCESS | 3732 | 06:38:57 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60231/tests/kraken/driver.html?testName=audio-dft) 06:38:57 INFO - PROCESS | 3732 | RSS: Main: 192495616 06:38:57 INFO - PROCESS | 3732 | 06:39:00 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60231/tests/kraken/driver.html?testName=audio-fft) 06:39:01 INFO - PROCESS | 3732 | RSS: Main: 278618112 06:39:01 INFO - PROCESS | 3732 | 06:39:03 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60231/tests/kraken/driver.html?testName=audio-oscillator) 06:39:03 INFO - PROCESS | 3732 | RSS: Main: 187772928 06:39:03 INFO - PROCESS | 3732 | 06:39:05 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:39:06 INFO - PROCESS | 3732 | RSS: Main: 188354560 06:39:06 INFO - PROCESS | 3732 | 06:39:09 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60231/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:10 INFO - PROCESS | 3732 | RSS: Main: 223084544 06:39:10 INFO - PROCESS | 3732 | 06:39:13 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60231/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:14 INFO - PROCESS | 3732 | RSS: Main: 223154176 06:39:14 INFO - PROCESS | 3732 | 06:39:16 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60231/tests/kraken/driver.html?testName=json-parse-financial) 06:39:17 INFO - PROCESS | 3732 | RSS: Main: 222498816 06:39:17 INFO - PROCESS | 3732 | 06:39:19 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:19 INFO - PROCESS | 3732 | RSS: Main: 200937472 06:39:19 INFO - PROCESS | 3732 | 06:39:21 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:21 INFO - PROCESS | 3732 | RSS: Main: 202944512 06:39:21 INFO - PROCESS | 3732 | 06:39:23 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:24 INFO - PROCESS | 3732 | RSS: Main: 208859136 06:39:24 INFO - PROCESS | 3732 | 06:39:27 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:39:27 INFO - PROCESS | 3732 | RSS: Main: 214310912 06:39:27 INFO - PROCESS | 3732 | 06:39:30 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:39:31 INFO - PROCESS | 3732 | RSS: Main: 213209088 06:39:31 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | Cycle 1(1): loaded http://localhost:60231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60231/tests/kraken/driver.html?testName=ai-astar) 06:39:32 INFO - PROCESS | 3732 | RSS: Main: 256098304 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | __start_tp_report 06:39:32 INFO - PROCESS | 3732 | _x_x_mozilla_page_load 06:39:32 INFO - PROCESS | 3732 | _x_x_mozilla_page_load_details 06:39:32 INFO - PROCESS | 3732 | |i|pagename|runs| 06:39:32 INFO - PROCESS | 3732 | |0;ai-astar;110;104;112;111;109;111;110;110;109;110 06:39:32 INFO - PROCESS | 3732 | |1;audio-beat-detection;154;126;130;177;132;125;152;123;154;124 06:39:32 INFO - PROCESS | 3732 | |2;audio-dft;160;161;161;161;161;161;160;160;161;161 06:39:32 INFO - PROCESS | 3732 | |3;audio-fft;74;76;75;119;77;75;76;75;99;74 06:39:32 INFO - PROCESS | 3732 | |4;audio-oscillator;84;84;114;86;85;84;112;83;84;121 06:39:32 INFO - PROCESS | 3732 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;116;117 06:39:32 INFO - PROCESS | 3732 | |6;imaging-darkroom;145;147;145;146;147;147;146;146;146;145 06:39:32 INFO - PROCESS | 3732 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;94;95 06:39:32 INFO - PROCESS | 3732 | |8;json-parse-financial;51;50;50;51;50;50;52;51;51;53 06:39:32 INFO - PROCESS | 3732 | |9;json-stringify-tinderbox;59;59;59;60;60;59;59;60;60;60 06:39:32 INFO - PROCESS | 3732 | |10;stanford-crypto-aes;82;77;76;76;78;77;77;78;108;77 06:39:32 INFO - PROCESS | 3732 | |11;stanford-crypto-ccm;121;123;120;131;125;125;119;119;121;133 06:39:32 INFO - PROCESS | 3732 | |12;stanford-crypto-pbkdf2;159;159;155;154;154;154;154;150;154;152 06:39:32 INFO - PROCESS | 3732 | |13;stanford-crypto-sha256-iterative;62;64;63;61;61;59;61;62;62;61 06:39:32 INFO - PROCESS | 3732 | __end_tp_report 06:39:32 INFO - PROCESS | 3732 | __start_cc_report 06:39:32 INFO - PROCESS | 3732 | _x_x_mozilla_cycle_collect,1551 06:39:32 INFO - PROCESS | 3732 | __end_cc_report 06:39:32 INFO - PROCESS | 3732 | __startTimestamp1469453972857__endTimestamp 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | ------- Summary: start ------- 06:39:32 INFO - PROCESS | 3732 | Number of tests: 14 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.17 (2.0%) stddev-sans-first:2.30 06:39:32 INFO - PROCESS | 3732 | Values: 110.0 104.0 112.0 111.0 109.0 111.0 110.0 110.0 109.0 110.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:142.00 stddev:18.36 (12.9%) stddev-sans-first:18.73 06:39:32 INFO - PROCESS | 3732 | Values: 154.0 126.0 130.0 177.0 132.0 125.0 152.0 123.0 154.0 124.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 06:39:32 INFO - PROCESS | 3732 | Values: 160.0 161.0 161.0 161.0 161.0 161.0 160.0 160.0 161.0 161.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.50 stddev:15.02 (19.9%) stddev-sans-first:15.65 06:39:32 INFO - PROCESS | 3732 | Values: 74.0 76.0 75.0 119.0 77.0 75.0 76.0 75.0 99.0 74.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:84.00 stddev:15.34 (18.3%) stddev-sans-first:15.86 06:39:32 INFO - PROCESS | 3732 | Values: 84.0 84.0 114.0 86.0 85.0 84.0 112.0 83.0 84.0 121.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:39:32 INFO - PROCESS | 3732 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 06:39:32 INFO - PROCESS | 3732 | Values: 145.0 147.0 145.0 146.0 147.0 147.0 146.0 146.0 146.0 145.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:39:32 INFO - PROCESS | 3732 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 06:39:32 INFO - PROCESS | 3732 | Values: 51.0 50.0 50.0 51.0 50.0 50.0 52.0 51.0 51.0 53.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 06:39:32 INFO - PROCESS | 3732 | Values: 59.0 59.0 59.0 60.0 60.0 59.0 59.0 60.0 60.0 60.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:77.00 stddev:9.78 (12.7%) stddev-sans-first:10.36 06:39:32 INFO - PROCESS | 3732 | Values: 82.0 77.0 76.0 76.0 78.0 77.0 77.0 78.0 108.0 77.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:124.00 stddev:4.90 (4.0%) stddev-sans-first:5.10 06:39:32 INFO - PROCESS | 3732 | Values: 121.0 123.0 120.0 131.0 125.0 125.0 119.0 119.0 121.0 133.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:154.00 stddev:2.76 (1.8%) stddev-sans-first:2.40 06:39:32 INFO - PROCESS | 3732 | Values: 159.0 159.0 155.0 154.0 154.0 154.0 154.0 150.0 154.0 152.0 06:39:32 INFO - PROCESS | 3732 | 06:39:32 INFO - PROCESS | 3732 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.42 06:39:32 INFO - PROCESS | 3732 | Values: 62.0 64.0 63.0 61.0 61.0 59.0 61.0 62.0 62.0 61.0 06:39:32 INFO - PROCESS | 3732 | -------- Summary: end -------- 06:39:32 INFO - PROCESS | 3732 | 06:39:33 INFO - TEST-INFO | 3732: exit 0 06:39:33 INFO - TEST-OK | kraken | took 59970ms 06:39:33 INFO - SUITE-END | took 1172s 06:39:34 INFO - Completed test suite (00:19:32) 06:39:34 INFO - javascript benchmark 06:39:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.5186697928802, 3319.6680331966804, 3335.833208339584, 3352.4971254311836, 3385.966140338596, 5438.0, 5515.448455154483, 5530.723463826803, 5533.446655334465, 5543.390227075019, 5414.187871819229, 5496.670199788005, 5499.092649712803, 5499.972500137494, 5535.086710692741, 4924.8276310328965, 4970.602351811855, 5003.349564556603, 5039.596832253411, 5071.873203169914, 4882.194437917757, 4904.337914381566, 4928.359313289267, 4934.703917764928, 4997.60019198465, 4921.360223171001, 4974.626902982291, 4994.425641051289, 4995.675281106716, 5097.515736005093, 4997.100521906055, 5092.261622064817, 5106.744662766865, 5111.616628752859, 5135.820246291362, 4808.182608956486, 4815.66290359675, 4860.562549370538, 4868.586170175539, 4947.134251506008, 5310.9734451327495, 5359.1425371940695, 5373.059714549938, 5380.24676545284, 5382.273393091921, 5683.175939488751, 5857.384974577651, 5861.0, 5885.823425297205, 5889.646621202742, 5781.566382521328, 5864.7067646617925, 5868.2371291732225, 5931.6441013539325, 5934.910976335358, 5556.138798486241, 5562.916556251659, 5564.8052318169075, 5566.137248726453, 5566.972165139148, 5819.679917604488, 6006.069059295816, 6056.757729690763, 6068.4538391544975, 6068.726907289182, 5721.57088218377, 5797.333306669702, 5799.739011744482, 5877.294724633071, 5884.941150588439, 5917.733701983421, 5939.673317967467, 5964.105384192406, 5970.432808883149, 5974.163617093611, 5688.374278829326, 5815.273090863642, 5890.734916928748, 5891.175235467038, 5909.409059094056, 5880.794172203952, 5887.558433117448, 5894.086416605433, 5906.5570082244, 5921.763129474859, 4520.299353600131, 4527.796249168795, 4531.43357080365, 4547.226971414867, 4555.772211389417, 3905.179912218466, 3920.6079392060565, 3952.505936757905, 4011.0, 4060.0661847774586, 5523.033469142964, 5525.088360420566, 5547.2511210986, 5550.111982082847, 5556.388797232301, 5842.415758424124, 5846.912296315559, 5846.941530584725, 5853.795117170964, 5882.23530940975, 5492.258545096441, 5496.367917689517, 5535.25274087993, 5552.111662134039, 5554.750036248379, 5016.849494515171, 5049.116404629175, 5050.217216331474, 5050.621203409759, 5052.191649336089, 6102.481289090477, 6142.447179753754, 6151.200343955257, 6157.876842463215, 6169.321374648785, 5768.682722450221, 5804.767809287581, 5855.765769369177, 5865.912011319919, 5928.258967629046, 6131.96934015345, 6135.478484328703, 6136.938630613815, 6138.140660307471, 6147.877042459215, 5631.831045068655, 5821.679807610622, 5823.475887170175, 5827.24245848037, 5828.795992140254, 5697.601167918372, 5764.596478246483, 5772.422757724194, 5836.445537673914, 5837.066069428871, 5656.151577263443, 5687.260656114679, 5694.515966142759, 5698.31620205578, 5702.51528620072, 5543.085390910616, 5632.267805185464, 5679.545636349165, 5689.975804355256, 5691.573132015033, 5612.4387561245185, 5642.351129620146, 5690.77236910519, 5694.288213973254, 5727.942720572741, 5759.193712880284, 5805.274340707411, 5829.970850145722, 5832.708364581838, 5832.912506312494, 5447.101228297445, 5550.250716153268, 5639.830805075854, 5717.22817419643, 5741.0], "value": 5414.549957372491, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.922790037295, 634.2515831319043, 647.1457675867855, 655.8425977765338, 657.6613044282195, 32987.99999999997, 36115.45826812596, 44650.106997860064, 45465.54534454655, 45597.0, 47874.281885771656, 48196.277055844104, 48209.51790482094, 48325.516744832545, 48330.516694833044, 8975.820483590323, 9143.771405714844, 9147.405418647766, 9148.588313525874, 9150.49672268025, 8957.910420895772, 9034.503102329369, 9062.139096785828, 9065.864012039825, 9069.274458043341, 9012.369134160612, 9015.639374425014, 9046.638134474613, 9051.592678329458, 9056.77358066047, 9060.275177985744, 9095.181433670936, 9100.81798364029, 9109.726708198765, 9116.635334586641, 9080.636774529012, 9114.13415725505, 9115.316351273681, 9136.132067453582, 9146.908530914672, 44101.55898441007, 44247.77876110631, 44495.0, 44612.3308150378, 44693.77653111746, 3752.0, 3867.883963481072, 3893.3186692328727, 4221.6833737469815, 4602.194615942197, 33881.66118338834, 34526.0, 34709.47935780965, 34780.304393912236, 34845.12887177841, 4498.257787465064, 4534.478534968488, 4556.54434556545, 4626.976865115686, 4765.7140571565815, 383886.0, 386218.0, 387149.0, 387537.0, 388246.0, 38087.61912380841, 63547.36452635415, 63559.04661430082, 63860.68069659715, 63967.36032639707, 39245.41131883024, 40742.388864167064, 40757.79621101876, 40762.79618601888, 40780.79609601993, 17041.659166816593, 17310.65378692419, 17326.04706741116, 17342.219600118024, 17360.39238626642, 14244.0, 14335.78496322556, 14336.49822256216, 14340.928295358457, 14342.282885855666, 9071.637134514678, 9082.22801061904, 9096.772580685403, 9147.908520914705, 9151.267898568098, 3453.3783918894337, 3458.6887180153403, 3505.088676944013, 3561.0919215599856, 3571.8928432147077, 1034.425893629043, 1179.2983175010854, 1199.322382853685, 1217.2088142707312, 1245.6926859188868, 34313.48529772055, 34321.31357372839, 34344.0, 34405.655943440244, 34605.13487162841, 1194.8207768834745, 1214.4110106598293, 1223.3699644682995, 1226.5277868020876, 1292.6703690559036, 17493.125343733016, 17852.821471785115, 18104.0, 18131.63736725258, 18135.45593632193, 658430.0, 662540.0, 666618.0, 669261.0, 669283.0, 63609.0, 64048.35951640424, 64081.359186408474, 64083.35916640867, 64404.67797661076, 1331.5206525650758, 1416.964575885611, 1450.52132796177, 1451.7894905238682, 1463.4219483304034, 34217.48673769945, 35580.28839423249, 35589.93230203098, 35708.0, 36427.08932276714, 54106.729466352415, 54521.45478545322, 54695.72652136714, 54712.0, 54715.72642136764, 450340.0, 451218.0, 451412.0, 452592.0, 455311.0, 27236.591451127853, 29791.404171916005, 30783.2304192397, 30842.383152337276, 32036.0, 52112.21831672604, 53920.73039634777, 57932.13101803392, 58039.41960580509, 58482.707586461795, 44645.0, 46121.769391152826, 47579.44817315451, 48573.0, 49629.751851241956, 45997.08005839797, 47103.293450598954, 47143.0, 47189.29216061831, 47307.290390644856, 47614.0, 48440.27339589839, 49178.50821491739, 49206.75396622994, 49300.75349623229, 40663.0, 41585.37621935734, 42191.15617687568, 42243.15513689647, 42752.0, 31448.213794655316, 31853.840730796197, 31955.201119972186, 31999.200019999687, 32247.838760806048], "value": 20567.659235050975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.61255032819463, 421.1156571200481, 423.91097869447424, 425.41717846550233, 440.0780365135041, 18483.722744158833, 19377.903110484447, 20227.696584551224, 20781.584368312626, 20962.37112886612, 19357.903210483964, 19658.901705491488, 19728.605427891434, 20072.996350182468, 20076.899615501938, 5564.749586268627, 5773.249477567911, 5807.680577568232, 5817.650940943536, 5829.242198514187, 4121.237571049365, 4577.267637178053, 5636.126400407943, 5740.397258287882, 6299.023651334051, 6315.052742088677, 6326.272478664943, 6353.809385718436, 6371.171747672796, 6375.266844312917, 6208.410201030895, 6246.344133865924, 6306.337834527354, 6313.368663133696, 6318.210223722035, 3827.598102199257, 5162.838361368662, 5453.182022696627, 5650.943490565083, 5657.915131273035, 23987.0, 24024.759752402428, 24050.0, 24404.633930491058, 24422.877885610458, 2530.215633153728, 2542.300867261499, 2667.1198504493473, 2706.9458610827674, 3038.5745995560637, 13926.93036534821, 14589.197594132318, 14637.194954277511, 15136.394544218218, 15184.772228416583, 2016.5966806638585, 2036.3178335257705, 2057.4136371134155, 2084.4267826347723, 2116.9576608467896, 75591.62204188999, 78456.60771696105, 78590.60704696497, 79114.60442697807, 79235.6038219811, 9514.762130946781, 10239.334443261314, 10526.263161578616, 10760.892391076146, 11126.165537584713, 14049.859501405059, 14050.929745351208, 14074.155550666992, 14161.929190353982, 14169.645758855904, 7482.775516734507, 7738.458307918391, 7907.56508392044, 8039.437239393186, 8074.475159114756, 7568.772936811904, 7656.61716914152, 7725.111612164561, 7741.419393545394, 7880.605969701492, 4307.827686892552, 4319.956800432018, 4389.604935555815, 4444.622207112367, 4483.730976141442, 2051.4050925231813, 2077.0, 2116.1641151744975, 2142.6250406178856, 2151.3008272311563, 810.5947026486757, 1036.5387402605768, 1119.8936101070385, 1142.3659868772745, 1147.8737338892715, 19281.710774338397, 19419.2232310706, 19490.122944467534, 19674.213031478866, 20120.19519219215, 1014.644874294006, 1060.289605964006, 1067.151614466501, 1108.844761733358, 1134.500819639356, 10980.835287470694, 11177.776444471228, 11239.887601124048, 11254.268472549258, 11258.943705281421, 506202.0, 571024.0, 572072.0, 576458.0, 580739.0, 65543.34456655468, 85264.14735852562, 88281.55859220662, 93632.0, 96882.0, 1046.9581216751244, 1075.704181350135, 1077.4451157653814, 1090.9781804364027, 1091.3779145886767, 17708.114594270493, 17830.5542361442, 18066.81933180704, 18157.727634085237, 18481.81518184801, 40549.79725101356, 40750.59249407468, 42822.57177428186, 42926.35610465908, 43321.350179746696, 60773.08840367486, 61516.0, 62117.0, 62439.6878015607, 63944.68027660018, 8648.30813534903, 8704.956475217583, 8719.956400217958, 8904.065073167225, 9026.097390260922, 10811.24321297533, 12695.174813637084, 12802.74394512086, 12934.547290844775, 12960.481580736665, 11083.667489975314, 11156.609518666808, 11385.146114041314, 11543.8268425972, 11698.880468394947, 8831.955840220759, 11161.330320180816, 11318.830217546909, 11556.306621602731, 11687.707807304885, 10126.645567405105, 11276.548938042715, 11418.714532136763, 11649.534018639159, 11773.0, 8627.094155113873, 8703.347248956481, 8725.171108744658, 8853.69012084574, 8958.19376256114, 11473.942630286796, 11541.826872597087, 11563.6530904073, 11638.941805290919, 11684.532618695157], "value": 9442.251326082463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.032014784484, 1525.7711343298504, 1559.3372816552965, 1750.3086280919038, 1876.5965317456744, 8362.79093022674, 8764.386492945488, 8788.209061184492, 8980.371374003822, 8982.685606003792, 8785.868211976825, 8942.508162051085, 8955.596998135083, 8967.058458861808, 8967.282617390609, 2070.710100585919, 2326.7905888470036, 2389.330987323552, 2395.3173345596465, 2428.235105941623, 2431.5744744669614, 2440.1581454398292, 2441.389652586853, 2442.1818690738537, 2442.4016116051594, 2333.509962907783, 2407.5305315463493, 2408.50625621748, 2409.6265078912797, 2411.9758802412016, 2077.366403247009, 2307.157887371114, 2365.4322962488877, 2371.4664200554907, 2387.3673476528734, 2319.0839618350824, 2319.3389883883015, 2320.6983092197906, 2324.4886125052462, 2357.26924653358, 9056.230220431271, 9097.13577210164, 9108.635654573809, 9119.954400227956, 9150.725478235663, 5276.709780962045, 5386.703731294777, 5391.245225668409, 5394.433584473652, 5399.62202645815, 3140.4975203967483, 3229.4994275887275, 3273.427150248721, 3465.2549701813978, 3467.1678797088516, 1188.9702757431046, 1193.3854065156452, 1196.8025275829477, 1210.1589395370222, 1232.4638782129803, 12732.87267127326, 12745.235285882876, 12782.744345113046, 12801.871981280161, 12808.0, 4497.123061003073, 4606.078784243165, 4613.423322084739, 4703.482616912137, 4787.880302992453, 10547.472626368652, 10555.683329500127, 10585.84121238182, 10603.628872989408, 10612.20408469368, 4859.757012149449, 5330.147176451749, 5335.519803217729, 5337.2527846101575, 5344.759485823147, 4840.128776820206, 5004.674696144812, 5005.474425185376, 5031.4213865405945, 5051.621128415383, 4147.854825081107, 4159.355299928516, 4221.472315960505, 4269.637080848162, 4326.545712700184, 1308.8625694302152, 1332.8333958255218, 1337.699017721005, 1346.912450690702, 1347.9595612131457, 1077.5582011375297, 1085.0776839686362, 1087.7443800706828, 1091.983620245697, 1095.3427943234155, 6197.0, 6269.341719119518, 6318.494520438446, 6322.209723784527, 6377.872442551123, 937.9718608441758, 946.536197263336, 1001.7145113642723, 1004.9648262310784, 1050.14937900301, 4344.891377715519, 4413.668974826901, 4434.445694288214, 4489.842855500042, 4490.2142125127975, 10772.569097236023, 11432.0, 11445.027172690414, 11446.198766086294, 11454.198206125659, 11044.33733975964, 11214.158938079676, 11326.773464530663, 11368.715782105513, 11391.544338226378, 8527.23254907049, 8628.741137765752, 8632.784180395665, 8659.393842431095, 8697.0, 6223.719932602953, 6279.843003924939, 6299.244090708961, 6340.651264180515, 6370.968145159244, 10321.225908056776, 10478.318909270873, 10635.787284254116, 10699.0, 10743.731406714582, 10746.301490403099, 10763.569457221936, 10874.891251087703, 10934.179936504635, 10949.945250273699, 7096.2548932363425, 7466.626668666654, 7599.848003040019, 7677.0403699537555, 7703.422243331885, 9082.137196966278, 9093.272538197061, 9125.77185570366, 9164.495952722664, 9170.312226583166, 7867.09528404218, 8091.919080809352, 8106.0, 8144.022717273965, 8240.793980150544, 8015.47899386538, 8039.67841286342, 8042.758717238492, 8229.917700823155, 8324.417290789586, 7378.852422951403, 7381.33567978863, 7389.778306650809, 7427.517211381243, 7491.662875170521, 5281.55106815925, 5281.709505977208, 5520.917186242129, 5558.888822223614, 5648.661080335193, 5651.632643878134, 5665.886682266414, 5669.716514174357, 5698.857528561819, 5744.540436765133], "value": 4827.17780868112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.2507596433543, 358.96769290763837, 363.1321142469498, 363.5927760907782, 370.33894498320467, 5259.132243179875, 5340.866478338044, 5384.5692344612435, 5411.350637923445, 5426.972865135669, 5299.099153143965, 5332.493413125757, 5352.2506849041165, 5389.8113566025195, 5408.188771684249, 3784.545854497464, 4022.7385219960684, 4099.872535052869, 4178.415021896937, 4188.97905510472, 4176.895577610554, 4177.206330797154, 4179.310413781737, 4182.6235638792505, 4192.49690037196, 4071.7760523171214, 4139.19285739281, 4163.604457576541, 4163.68772342074, 4197.664186865059, 4012.257732319501, 4159.83360665573, 4193.182329445759, 4200.3699445083175, 4229.7462152270655, 3736.532933383327, 3742.0, 3744.850205991757, 3785.9399368176955, 3787.450819631166, 5094.61790365724, 5131.461196574343, 5142.51146141116, 5149.76826042829, 5179.249008893689, 4645.465771436295, 4737.928931066037, 4754.049190161982, 4770.212914869043, 4815.133276010317, 5478.342598888119, 5487.216654587275, 5494.725263736797, 5501.889962200734, 5518.641288316287, 4057.6753859691153, 4060.3706425503797, 4063.0248740302113, 4083.4079058536645, 4088.2436749201493, 5520.420355862617, 5533.197686335499, 5619.438056194389, 5753.223314852525, 5789.478946894799, 4108.1783643271465, 4109.4246805447265, 4116.320807066861, 4128.60778226068, 4151.232022075926, 4202.201581699467, 4202.474690663667, 4204.747715137101, 4207.600277973587, 4212.789360531961, 4535.138323718549, 4702.623790096784, 4726.669133160714, 4763.904721905612, 4792.544708252711, 5539.390667026624, 5540.418256083134, 5540.63985840919, 5548.722563871871, 5586.748596313176, 1429.7640889253364, 1443.4731323067215, 1444.8410674825761, 1454.338276084379, 1462.1008080030704, 927.8979312275644, 942.9858552121724, 946.2713710442921, 955.1899151108611, 970.6748239339901, 4839.274108883626, 4845.491223421561, 4848.903021939542, 4860.226017503656, 4868.805247790048, 5030.2957585938, 5032.924506132411, 5064.620153488503, 5084.949150508522, 5112.923306150411, 4667.836625718083, 4843.95156048435, 4858.271259311132, 4872.585830204402, 4907.435644900811, 2703.6485256916867, 2711.7423844734717, 2712.6707913122427, 2727.2227415186576, 2742.245882382361, 5224.738763061831, 5276.894462110737, 5297.337832770903, 5310.840674779763, 5322.547583455373, 4828.841078141221, 5205.375354957429, 5237.764300606405, 5239.952009598096, 5249.921251181236, 5237.659552129099, 5248.895022099537, 5250.842474725764, 5263.499967503158, 5280.3663560373, 5056.367954005749, 5062.164742817394, 5075.7818123650795, 5083.983203359269, 5110.642255042111, 4829.975850120727, 5006.574441172541, 5065.594752419871, 5130.614264367087, 5135.640505164752, 5345.4654534546235, 5725.885482290414, 5798.710064496674, 6007.008843540942, 6022.548308876764, 4167.124903770121, 4177.8537751178565, 4180.226658068267, 4182.707210495236, 4191.140816132746, 5596.013415237532, 5774.769009239583, 5781.768729250783, 5796.101604251347, 5805.216295800013, 5520.641158324696, 5577.386487486455, 5599.412061733459, 5612.326520817364, 5616.775328986795, 5513.173024046425, 5525.557955363642, 5559.16612508127, 5620.213170156263, 5641.8589535261945, 5363.597730170175, 5424.403315635198, 5497.367802702581, 5511.311086114236, 5520.61984503874, 5592.5738936570915, 5600.663960162403, 5617.747201375866, 5639.7744090237575, 5642.774289028393, 5516.317523155476, 5550.195221692751, 5551.333839939233, 5569.164625306236, 5580.330360356621], "value": 4158.393775527981, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.1554983890708, 874.7725591346249, 903.2909166304453, 907.3965812734532, 918.0, 14878.256087195638, 15689.37242510301, 15879.761803572941, 15887.205639718026, 15935.442259520905, 14957.252137393141, 15208.923955380234, 15253.31360088796, 15401.460948866792, 15542.0, 703.4688809948507, 821.9930585033346, 858.1375717404031, 862.0775769926181, 863.3826813828111, 857.8670306102533, 866.3891956170916, 868.8436081505326, 870.1472556894247, 870.6212797433137, 896.1934259166737, 900.572228191607, 900.5722281916103, 900.9009009009004, 900.9144131307548, 733.3180142467503, 874.33113668044, 893.6023469556051, 905.959231834569, 906.5784410249199, 668.7425341243608, 668.8896332105197, 669.517947078103, 670.0418401685588, 670.3263220463451, 15460.768088478682, 15462.458813941457, 15542.844571554253, 15567.922160389238, 15574.688506229926, 14678.633034174125, 15098.773518397233, 15485.14831684257, 15529.767053494206, 15771.526854194277, 12081.456334464881, 12201.511939522408, 12444.191127576678, 13892.722145557032, 14353.0, 4760.744978552269, 4819.036192761461, 4859.902801943941, 4910.337104490884, 5018.548330650223, 74091.0, 74887.62556187238, 74902.62548687276, 75112.24887751162, 75364.0, 11779.469923853447, 12058.864695179316, 12081.154319197572, 12204.0, 12287.447064882102, 20971.475713107295, 21012.894935525226, 21053.263135790174, 21111.155553777677, 21140.0, 8637.136286371313, 8760.08019157992, 8778.166074223067, 8790.252828509649, 8940.821183576421, 4951.480094590088, 4984.576311013532, 5057.974710126426, 5090.185570308733, 5163.638545301868, 3563.2160924596546, 3649.087728067983, 3661.761985470989, 3665.578458477256, 3666.431703086026, 606.3974434045527, 621.3196549777986, 631.4064779107624, 636.9522285828581, 637.8819918315127, 609.4788955443121, 736.988945165823, 762.2073044034142, 808.4502538273913, 827.1067247372823, 11232.213745037932, 11441.141914356456, 11613.3612651305, 11627.6511704649, 11634.76730465386, 796.4066770256196, 803.819140693351, 819.647551552826, 822.481836443049, 831.1563762780783, 5208.7916483341, 5413.350397952271, 5499.450054994469, 5517.200005999108, 5571.804986825442, 78162.21837781549, 120516.0, 124492.75507244993, 128727.0, 135010.3249483766, 82661.04033757994, 111624.44187779009, 111883.44058279657, 112650.43674781574, 113054.43472782584, 96500.0, 96606.0, 97425.51287243661, 99741.0, 100481.0, 10291.0, 10301.124404425707, 10405.47972601382, 10430.53062612169, 10579.31234469757, 45079.77460112678, 45943.0, 48525.757371212916, 48644.27033594428, 50281.49718502768, 50376.0, 50541.241881371076, 60617.0, 73471.26528734644, 75884.0, 13801.723965520834, 13900.72198556003, 13999.930000349934, 14186.503472378417, 14327.28363581796, 16012.279447425128, 16196.838031619533, 16198.514044578682, 16475.0, 16636.334546618, 14539.92730036343, 14765.261736913326, 14914.328855201758, 15382.923085384502, 15384.461543845911, 14634.560963171121, 14793.852061479247, 15095.32071056827, 15681.921590391976, 15713.68572628518, 13553.796693049415, 13995.0, 14006.509772157926, 14173.291335432981, 14435.639109022359, 11686.123540734648, 11792.52829886795, 11825.940870295593, 12025.579104731292, 12107.939460302994, 11929.522819087138, 11987.820182697442, 12054.51781928713, 12095.697607559881, 12282.140250182401], "value": 8017.712172337942, "unit": "score"}], "name": "dromaeo_css", "value": 7437.430952650488, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 112.0, 111.0, 109.0, 111.0, 110.0, 110.0, 109.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 130.0, 177.0, 132.0, 125.0, 152.0, 123.0, 154.0, 124.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 119.0, 77.0, 75.0, 76.0, 75.0, 99.0, 74.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 114.0, 86.0, 85.0, 84.0, 112.0, 83.0, 84.0, 121.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 50.0, 50.0, 52.0, 51.0, 51.0, 53.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 76.0, 76.0, 78.0, 77.0, 77.0, 78.0, 108.0, 77.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 120.0, 131.0, 125.0, 125.0, 119.0, 119.0, 121.0, 133.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 155.0, 154.0, 154.0, 154.0, 154.0, 150.0, 154.0, 152.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 61.0, 61.0, 59.0, 61.0, 62.0, 62.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1473.9999999999998, "alertThreshold": 2.0}]} 06:39:34 INFO - Return code: 0 06:39:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:39:34 ERROR - # TBPL SUCCESS # 06:39:34 INFO - Running post-action listener: _resource_record_post_action 06:39:34 INFO - ##### 06:39:34 INFO - ##### Finished run-tests step (success) 06:39:34 INFO - ##### 06:39:34 INFO - Running post-run listener: _resource_record_post_run 06:39:34 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 430505984; Read time: 0; Write time: 340876 06:39:34 INFO - TinderboxPrint: CPU usage
13.1% 06:39:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:39:34 INFO - TinderboxPrint: I/O write bytes / time
430,505,984 / 340,876 06:39:34 INFO - TinderboxPrint: CPU idle
8,250.6 (86.9%) 06:39:34 INFO - TinderboxPrint: CPU user
1,202.1 (12.7%) 06:39:34 INFO - TinderboxPrint: Swap in / out
0 / 0 06:39:34 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 175484928; Read time: 0; Write time: 172860 06:39:34 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 251215872; Read time: 0; Write time: 167832 06:39:34 INFO - Running post-run listener: _upload_blobber_files 06:39:34 INFO - Blob upload gear active. 06:39:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:39:34 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 06:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:39:35 INFO - (blobuploader) - INFO - Open directory for files ... 06:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:39:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:35 INFO - (blobuploader) - INFO - Done attempting. 06:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:39:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:39:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:36 INFO - (blobuploader) - INFO - Done attempting. 06:39:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:39:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:39:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:47 INFO - (blobuploader) - INFO - Done attempting. 06:39:47 INFO - (blobuploader) - INFO - Iteration through files over. 06:39:47 INFO - Return code: 0 06:39:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:39:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:39:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a83cae23c899ed72387ef50e661a185c0f0231eaebb32d786f8836a92e87c8b0ff515c1d87fb6554e5c9e3fee4d947467c43393a3917064b1ff37446ee78ccc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3bba6e1c4da2bf315bb80ff5ba723191ee9cd606269ff544a71ed5cde7668fc793ba1f46446c7a4b4bb7c6b81852fba862571929261042c78f7003f3f8aba3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fee51fd16da2912ed4c6bedd0438d290c619b0fc210d974978c9646938c845076eb11e8fd53ad9449fcd09ca80454491d2526cbb6173c739099d44da0e4aacc"} 06:39:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:39:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:39:47 INFO - Contents: 06:39:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a83cae23c899ed72387ef50e661a185c0f0231eaebb32d786f8836a92e87c8b0ff515c1d87fb6554e5c9e3fee4d947467c43393a3917064b1ff37446ee78ccc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3bba6e1c4da2bf315bb80ff5ba723191ee9cd606269ff544a71ed5cde7668fc793ba1f46446c7a4b4bb7c6b81852fba862571929261042c78f7003f3f8aba3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fee51fd16da2912ed4c6bedd0438d290c619b0fc210d974978c9646938c845076eb11e8fd53ad9449fcd09ca80454491d2526cbb6173c739099d44da0e4aacc"} 06:39:47 INFO - Running post-run listener: copy_logs_to_upload_dir 06:39:47 INFO - Copying logs to upload dir... 06:39:47 INFO - mkdir: /builds/slave/test/build/upload/logs 06:39:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1307.966278 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2016-07-25 06:39:47.004028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:39:47.007779) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a83cae23c899ed72387ef50e661a185c0f0231eaebb32d786f8836a92e87c8b0ff515c1d87fb6554e5c9e3fee4d947467c43393a3917064b1ff37446ee78ccc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3bba6e1c4da2bf315bb80ff5ba723191ee9cd606269ff544a71ed5cde7668fc793ba1f46446c7a4b4bb7c6b81852fba862571929261042c78f7003f3f8aba3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fee51fd16da2912ed4c6bedd0438d290c619b0fc210d974978c9646938c845076eb11e8fd53ad9449fcd09ca80454491d2526cbb6173c739099d44da0e4aacc"} build_url:https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/FBJIZmzeRzWE48WjAir0jg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a83cae23c899ed72387ef50e661a185c0f0231eaebb32d786f8836a92e87c8b0ff515c1d87fb6554e5c9e3fee4d947467c43393a3917064b1ff37446ee78ccc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3bba6e1c4da2bf315bb80ff5ba723191ee9cd606269ff544a71ed5cde7668fc793ba1f46446c7a4b4bb7c6b81852fba862571929261042c78f7003f3f8aba3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fee51fd16da2912ed4c6bedd0438d290c619b0fc210d974978c9646938c845076eb11e8fd53ad9449fcd09ca80454491d2526cbb6173c739099d44da0e4aacc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:39:47.033712) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:39:47.034090) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469452026.247016-1763852469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:39:47.117376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:39:47.117736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:39:47.118054) ========= ========= Total master_lag: 0.17 =========