builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1470046454.55 results: success (0) buildid: 20160801004002 builduid: eadec9eaa794460d83ac595f887c2c33 revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.547863) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.548319) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.548866) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.588980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.589311) ========= 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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 03:14:16-- 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% 223M=0s 2016-08-01 03:14:16 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119423 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.728881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.729246) ========= 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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015870 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.782507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:14.783324) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --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 fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 03:14:16,688 truncating revision to first 12 chars 2016-08-01 03:14:16,688 Setting DEBUG logging. 2016-08-01 03:14:16,688 attempt 1/10 2016-08-01 03:14:16,688 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcdf4bb70356?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 03:14:16,988 unpacking tar archive at: mozilla-aurora-fcdf4bb70356/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:15.446255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:15.446972) ========= script_repo_revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:15.447981) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:15.448779) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:14:15.462743) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-01 03:14:15.463087) ========= /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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:14:17 INFO - MultiFileLogger online at 20160801 03:14:17 in /builds/slave/test 03:14:17 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 03:14:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:14:17 INFO - {'append_to_log': False, 03:14:17 INFO - 'base_work_dir': '/builds/slave/test', 03:14:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:14:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:14:17 INFO - 'branch': 'Mozilla-Aurora', 03:14:17 INFO - 'buildbot_json_path': 'buildprops.json', 03:14:17 INFO - 'config_files': ('talos/linux_config.py',), 03:14:17 INFO - 'default_actions': ('clobber', 03:14:17 INFO - 'read-buildbot-config', 03:14:17 INFO - 'download-and-extract', 03:14:17 INFO - 'populate-webroot', 03:14:17 INFO - 'create-virtualenv', 03:14:17 INFO - 'install', 03:14:17 INFO - 'run-tests'), 03:14:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:14:17 INFO - 'download_minidump_stackwalk': True, 03:14:17 INFO - 'download_symbols': 'ondemand', 03:14:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:14:17 INFO - 'tooltool.py': '/tools/tooltool.py', 03:14:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:14:17 INFO - '/tools/misc-python/virtualenv.py')}, 03:14:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:14:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:14:17 INFO - 'installer_path': 'installer.exe', 03:14:17 INFO - 'log_level': 'info', 03:14:17 INFO - 'log_name': 'talos', 03:14:17 INFO - 'log_to_console': True, 03:14:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:14:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:14:17 INFO - 'opt_config_files': (), 03:14:17 INFO - 'pip_index': False, 03:14:17 INFO - 'sps_profile': False, 03:14:17 INFO - 'sps_profile_interval': 0, 03:14:17 INFO - 'suite': 'dromaeojs', 03:14:17 INFO - 'system_bits': '32', 03:14:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:14:17 INFO - 'title': 'talos-linux64-ix-052', 03:14:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:14:17 INFO - 'use_talos_json': True, 03:14:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:14:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:14:17 INFO - 'work_dir': 'build'} 03:14:17 INFO - ##### 03:14:17 INFO - ##### Running clobber step. 03:14:17 INFO - ##### 03:14:17 INFO - Running pre-action listener: _resource_record_pre_action 03:14:17 INFO - Running main action method: clobber 03:14:17 INFO - rmtree: /builds/slave/test/build 03:14:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:14:17 INFO - Running post-action listener: _resource_record_post_action 03:14:17 INFO - ##### 03:14:17 INFO - ##### Finished clobber step (success) 03:14:17 INFO - ##### 03:14:17 INFO - ##### 03:14:17 INFO - ##### Running read-buildbot-config step. 03:14:17 INFO - ##### 03:14:17 INFO - Running pre-action listener: _resource_record_pre_action 03:14:17 INFO - Running main action method: read_buildbot_config 03:14:17 INFO - Using buildbot properties: 03:14:17 INFO - { 03:14:17 INFO - "project": "", 03:14:17 INFO - "product": "firefox", 03:14:17 INFO - "script_repo_revision": "production", 03:14:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:14:17 INFO - "repository": "", 03:14:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:14:17 INFO - "buildid": "20160801004002", 03:14:17 INFO - "stage_platform": "linux64-pgo", 03:14:17 INFO - "basedir": "/builds/slave/test", 03:14:17 INFO - "nightly_build": "True", 03:14:17 INFO - "buildnumber": 438, 03:14:17 INFO - "slavename": "talos-linux64-ix-052", 03:14:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:14:17 INFO - "platform": "ubuntu64_hw", 03:14:17 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:14:17 INFO - "branch": "mozilla-aurora", 03:14:17 INFO - "repo_path": "releases/mozilla-aurora", 03:14:17 INFO - "revision": "fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff", 03:14:17 INFO - "pgo_build": "True", 03:14:17 INFO - "builduid": "eadec9eaa794460d83ac595f887c2c33", 03:14:17 INFO - "slavebuilddir": "test" 03:14:17 INFO - } 03:14:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:14:17 INFO - Running post-action listener: _resource_record_post_action 03:14:17 INFO - ##### 03:14:17 INFO - ##### Finished read-buildbot-config step (success) 03:14:17 INFO - ##### 03:14:17 INFO - ##### 03:14:17 INFO - ##### Running download-and-extract step. 03:14:17 INFO - ##### 03:14:17 INFO - Running pre-action listener: _resource_record_pre_action 03:14:17 INFO - Running main action method: download_and_extract 03:14:17 INFO - mkdir: /builds/slave/test/build/tests 03:14:17 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')]} 03:14:17 INFO - https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:14:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:14:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:14:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:25 INFO - Downloaded 1361 bytes. 03:14:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:14:25 INFO - Using the following test package requirements: 03:14:25 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:14:25 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:14:25 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:14:25 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'jsshell-linux-x86_64.zip'], 03:14:25 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:14:25 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:14:25 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:14:25 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:14:25 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:14:25 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:14:25 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:14:25 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:25 INFO - https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:14:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:14:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:14:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:26 INFO - Downloaded 21967042 bytes. 03:14:26 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'] 03:14:26 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 03:14:28 INFO - Return code: 0 03:14:28 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 03:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:28 INFO - https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:14:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:14:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:14:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:33 INFO - Downloaded 21967042 bytes. 03:14:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:14:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:14:34 INFO - Return code: 0 03:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:34 INFO - https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:14:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:14:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:14:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:35 INFO - Downloaded 10833369 bytes. 03:14:35 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'] 03:14:35 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 03:14:35 INFO - Return code: 0 03:14:35 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')]} 03:14:35 INFO - https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:14:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:14:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:14:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W3ymngBqSj6COe9gLCiEWw/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 03:14:36 INFO - Downloaded 61961054 bytes. 03:14:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:14:36 INFO - mkdir: /builds/slave/test/properties 03:14:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:14:36 INFO - Writing to file /builds/slave/test/properties/build_url 03:14:36 INFO - Contents: 03:14:36 INFO - build_url:https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:14:37 INFO - Running post-action listener: _resource_record_post_action 03:14:37 INFO - Running post-action listener: set_extra_try_arguments 03:14:37 INFO - ##### 03:14:37 INFO - ##### Finished download-and-extract step (success) 03:14:37 INFO - ##### 03:14:37 INFO - ##### 03:14:37 INFO - ##### Running populate-webroot step. 03:14:37 INFO - ##### 03:14:37 INFO - Running pre-action listener: _resource_record_pre_action 03:14:37 INFO - Running main action method: populate_webroot 03:14:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:14:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:14:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:14:37 INFO - '--tppagecycles', 03:14:37 INFO - '7'], 03:14:37 INFO - 'tests': ['tsvgm']}}, 03:14:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:14:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:14:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:14:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:14:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['damp', 'tps']}, 03:14:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'tests': ['damp', 'tps']}, 03:14:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['dromaeo_dom']}, 03:14:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:14:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'other-e10s': {'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['a11yr', 03:14:37 INFO - 'ts_paint', 03:14:37 INFO - 'tpaint', 03:14:37 INFO - 'sessionrestore', 03:14:37 INFO - 'sessionrestore_no_auto_restore', 03:14:37 INFO - 'tabpaint']}, 03:14:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['tsvgx', 03:14:37 INFO - 'tsvgr_opacity', 03:14:37 INFO - 'tart', 03:14:37 INFO - 'tscrollx', 03:14:37 INFO - 'cart']}, 03:14:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:14:37 INFO - 'tsvgr_opacity', 03:14:37 INFO - 'tart', 03:14:37 INFO - 'tscrollx', 03:14:37 INFO - 'cart']}, 03:14:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'talos_options': ['--disable-e10s'], 03:14:37 INFO - 'tests': ['tp5o']}, 03:14:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'tests': ['tp5o']}, 03:14:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'talos_options': ['--disable-e10s', 03:14:37 INFO - '--xperf_path', 03:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:37 INFO - 'tests': ['tp5n']}, 03:14:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:14:37 INFO - 'talos_options': ['--xperf_path', 03:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:37 INFO - 'tests': ['tp5n']}}, 03:14:37 INFO - 'talos.zip': {'path': '', 03:14:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:14:37 INFO - Running post-action listener: _resource_record_post_action 03:14:37 INFO - ##### 03:14:37 INFO - ##### Finished populate-webroot step (success) 03:14:37 INFO - ##### 03:14:37 INFO - ##### 03:14:37 INFO - ##### Running create-virtualenv step. 03:14:37 INFO - ##### 03:14:37 INFO - Running pre-action listener: _resource_record_pre_action 03:14:37 INFO - Running main action method: create_virtualenv 03:14:37 INFO - Creating virtualenv /builds/slave/test/build/venv 03:14:37 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 03:14:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:14:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:14:37 INFO - Using real prefix '/usr' 03:14:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:14:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:14:40 INFO - Installing pip.................done. 03:14:40 INFO - Return code: 0 03:14:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:14: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')]} 03:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14: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')]} 03:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14: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', 'pip>=1.5'] in /builds/slave/test/build 03:14: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 pip>=1.5 03:14:40 INFO - Using env: {'DISPLAY': ':0', 03:14:40 INFO - 'HOME': '/home/cltbld', 03:14:40 INFO - 'LANG': 'en_US.UTF-8', 03:14:40 INFO - 'LANGUAGE': 'en_US:en', 03:14:40 INFO - 'LOGNAME': 'cltbld', 03:14:40 INFO - 'MAIL': '/var/mail/cltbld', 03:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:40 INFO - 'MOZ_NO_REMOTE': '1', 03:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:40 INFO - 'NO_EM_RESTART': '1', 03:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:40 INFO - 'PWD': '/builds/slave/test', 03:14:40 INFO - 'SHELL': '/bin/bash', 03:14:40 INFO - 'SHLVL': '1', 03:14:40 INFO - 'TERM': 'linux', 03:14:40 INFO - 'TMOUT': '86400', 03:14:40 INFO - 'USER': 'cltbld', 03:14:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:40 INFO - '_': '/tools/buildbot/bin/python'} 03:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:40 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 03:14:40 INFO - Cleaning up... 03:14:40 INFO - Return code: 0 03:14:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:14: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')]} 03:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14: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')]} 03:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14: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', '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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:14: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 psutil>=3.1.1 03:14:40 INFO - Using env: {'DISPLAY': ':0', 03:14:40 INFO - 'HOME': '/home/cltbld', 03:14:40 INFO - 'LANG': 'en_US.UTF-8', 03:14:40 INFO - 'LANGUAGE': 'en_US:en', 03:14:40 INFO - 'LOGNAME': 'cltbld', 03:14:40 INFO - 'MAIL': '/var/mail/cltbld', 03:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:40 INFO - 'MOZ_NO_REMOTE': '1', 03:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:40 INFO - 'NO_EM_RESTART': '1', 03:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:40 INFO - 'PWD': '/builds/slave/test', 03:14:40 INFO - 'SHELL': '/bin/bash', 03:14:40 INFO - 'SHLVL': '1', 03:14:40 INFO - 'TERM': 'linux', 03:14:40 INFO - 'TMOUT': '86400', 03:14:40 INFO - 'USER': 'cltbld', 03:14:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:40 INFO - '_': '/tools/buildbot/bin/python'} 03:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:40 INFO - Downloading/unpacking psutil>=3.1.1 03:14: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 03:14: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 03:14: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 03:14: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 03:14: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 03:14: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 03:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:14:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:42 INFO - Installing collected packages: psutil 03:14:42 INFO - Running setup.py install for psutil 03:14:42 INFO - building 'psutil._psutil_linux' extension 03:14:42 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 03:14:42 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 03:14:42 INFO - building 'psutil._psutil_posix' extension 03:14:42 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 03:14:42 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 03:14:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:43 INFO - Successfully installed psutil 03:14:43 INFO - Cleaning up... 03:14:43 INFO - Return code: 0 03:14:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:43 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:43 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 03:14:43 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 03:14:43 INFO - Using env: {'DISPLAY': ':0', 03:14:43 INFO - 'HOME': '/home/cltbld', 03:14:43 INFO - 'LANG': 'en_US.UTF-8', 03:14:43 INFO - 'LANGUAGE': 'en_US:en', 03:14:43 INFO - 'LOGNAME': 'cltbld', 03:14:43 INFO - 'MAIL': '/var/mail/cltbld', 03:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:43 INFO - 'MOZ_NO_REMOTE': '1', 03:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:43 INFO - 'NO_EM_RESTART': '1', 03:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:43 INFO - 'PWD': '/builds/slave/test', 03:14:43 INFO - 'SHELL': '/bin/bash', 03:14:43 INFO - 'SHLVL': '1', 03:14:43 INFO - 'TERM': 'linux', 03:14:43 INFO - 'TMOUT': '86400', 03:14:43 INFO - 'USER': 'cltbld', 03:14:43 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:43 INFO - '_': '/tools/buildbot/bin/python'} 03:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:14:45 INFO - Installing collected packages: mozsystemmonitor 03:14:45 INFO - Running setup.py install for mozsystemmonitor 03:14:45 INFO - Successfully installed mozsystemmonitor 03:14:45 INFO - Cleaning up... 03:14:45 INFO - Return code: 0 03:14:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:45 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:45 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 03:14:45 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 03:14:45 INFO - Using env: {'DISPLAY': ':0', 03:14:45 INFO - 'HOME': '/home/cltbld', 03:14:45 INFO - 'LANG': 'en_US.UTF-8', 03:14:45 INFO - 'LANGUAGE': 'en_US:en', 03:14:45 INFO - 'LOGNAME': 'cltbld', 03:14:45 INFO - 'MAIL': '/var/mail/cltbld', 03:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:45 INFO - 'MOZ_NO_REMOTE': '1', 03:14:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:45 INFO - 'NO_EM_RESTART': '1', 03:14:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:45 INFO - 'PWD': '/builds/slave/test', 03:14:45 INFO - 'SHELL': '/bin/bash', 03:14:45 INFO - 'SHLVL': '1', 03:14:45 INFO - 'TERM': 'linux', 03:14:45 INFO - 'TMOUT': '86400', 03:14:45 INFO - 'USER': 'cltbld', 03:14:45 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:45 INFO - '_': '/tools/buildbot/bin/python'} 03:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:14:45 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 03:14:45 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 03:14:45 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 03:14:45 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 03:14:45 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 03:14:45 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 03:14:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:14:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:14:47 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 03:14:47 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 03:14:47 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 03:14:47 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 03:14:47 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 03:14:47 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 03:14:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:14:47 INFO - warning: no files found matching '*.txt' 03:14:47 INFO - no previously-included directories found matching 'build' 03:14:47 INFO - no previously-included directories found matching 'dist' 03:14:47 INFO - no previously-included directories found matching '.git*' 03:14:47 INFO - Installing collected packages: jsonschema, functools32 03:14:47 INFO - Running setup.py install for functools32 03:14:47 INFO - warning: no files found matching '*.txt' 03:14:47 INFO - no previously-included directories found matching 'build' 03:14:47 INFO - no previously-included directories found matching 'dist' 03:14:47 INFO - no previously-included directories found matching '.git*' 03:14:47 INFO - Successfully installed jsonschema functools32 03:14:47 INFO - Cleaning up... 03:14:47 INFO - Return code: 0 03:14:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:14:47 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')]} 03:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:47 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')]} 03:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:47 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:47 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 03:14:47 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 03:14:47 INFO - Using env: {'DISPLAY': ':0', 03:14:47 INFO - 'HOME': '/home/cltbld', 03:14:47 INFO - 'LANG': 'en_US.UTF-8', 03:14:47 INFO - 'LANGUAGE': 'en_US:en', 03:14:47 INFO - 'LOGNAME': 'cltbld', 03:14:47 INFO - 'MAIL': '/var/mail/cltbld', 03:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:47 INFO - 'MOZ_NO_REMOTE': '1', 03:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:47 INFO - 'NO_EM_RESTART': '1', 03:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:47 INFO - 'PWD': '/builds/slave/test', 03:14:47 INFO - 'SHELL': '/bin/bash', 03:14:47 INFO - 'SHLVL': '1', 03:14:47 INFO - 'TERM': 'linux', 03:14:47 INFO - 'TMOUT': '86400', 03:14:47 INFO - 'USER': 'cltbld', 03:14:47 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:47 INFO - '_': '/tools/buildbot/bin/python'} 03:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:48 INFO - Downloading/unpacking blobuploader==1.2.4 03:14:48 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 03:14:48 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 03:14:48 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 03:14:48 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 03:14:48 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 03:14:48 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 03:14:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:14:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:14:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:50 INFO - Downloading docopt-0.6.1.tar.gz 03:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:14:50 INFO - Installing collected packages: blobuploader, requests, docopt 03:14:50 INFO - Running setup.py install for blobuploader 03:14:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:14:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:14:50 INFO - Running setup.py install for requests 03:14:51 INFO - Running setup.py install for docopt 03:14:51 INFO - Successfully installed blobuploader requests docopt 03:14:51 INFO - Cleaning up... 03:14:51 INFO - Return code: 0 03:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:51 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:51 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 03:14:51 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 03:14:51 INFO - Using env: {'DISPLAY': ':0', 03:14:51 INFO - 'HOME': '/home/cltbld', 03:14:51 INFO - 'LANG': 'en_US.UTF-8', 03:14:51 INFO - 'LANGUAGE': 'en_US:en', 03:14:51 INFO - 'LOGNAME': 'cltbld', 03:14:51 INFO - 'MAIL': '/var/mail/cltbld', 03:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:51 INFO - 'MOZ_NO_REMOTE': '1', 03:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:51 INFO - 'NO_EM_RESTART': '1', 03:14:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:51 INFO - 'PWD': '/builds/slave/test', 03:14:51 INFO - 'SHELL': '/bin/bash', 03:14:51 INFO - 'SHLVL': '1', 03:14:51 INFO - 'TERM': 'linux', 03:14:51 INFO - 'TMOUT': '86400', 03:14:51 INFO - 'USER': 'cltbld', 03:14:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:51 INFO - '_': '/tools/buildbot/bin/python'} 03:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:51 INFO - Running setup.py (path:/tmp/pip-uFkOBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:51 INFO - Running setup.py (path:/tmp/pip-UrolAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:51 INFO - Running setup.py (path:/tmp/pip-3Sike8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:51 INFO - Running setup.py (path:/tmp/pip-Yua1uI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:51 INFO - Running setup.py (path:/tmp/pip-ZbRbaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:51 INFO - Running setup.py (path:/tmp/pip-huissh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:52 INFO - Running setup.py (path:/tmp/pip-u5Fcej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:52 INFO - Running setup.py (path:/tmp/pip-dx7FXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:52 INFO - Running setup.py (path:/tmp/pip-_JPH2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:52 INFO - Running setup.py (path:/tmp/pip-UUzCsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:52 INFO - Running setup.py (path:/tmp/pip-PKF3ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:52 INFO - Running setup.py (path:/tmp/pip-ywf6es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:52 INFO - Running setup.py (path:/tmp/pip-BuFJqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:52 INFO - Running setup.py (path:/tmp/pip-wF8zZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:52 INFO - Running setup.py (path:/tmp/pip-sHYpHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:52 INFO - Running setup.py (path:/tmp/pip-jD0Ed5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:52 INFO - Running setup.py (path:/tmp/pip-8fREbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:14:52 INFO - Running setup.py install for manifestparser 03:14:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:14:52 INFO - Running setup.py install for mozcrash 03:14:53 INFO - Running setup.py install for mozdebug 03:14:53 INFO - Running setup.py install for mozdevice 03:14:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for mozfile 03:14:53 INFO - Running setup.py install for mozhttpd 03:14:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for mozinfo 03:14:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for mozInstall 03:14:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for mozleak 03:14:53 INFO - Running setup.py install for mozlog 03:14:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for moznetwork 03:14:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:14:53 INFO - Running setup.py install for mozprocess 03:14:54 INFO - Running setup.py install for mozprofile 03:14:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:14:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:14:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:14:54 INFO - Running setup.py install for mozrunner 03:14:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:14:54 INFO - Running setup.py install for mozscreenshot 03:14:54 INFO - Running setup.py install for moztest 03:14:54 INFO - Running setup.py install for mozversion 03:14:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:14:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:14:54 INFO - Cleaning up... 03:14:54 INFO - Return code: 0 03:14:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:54 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:54 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 03:14:54 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 03:14:54 INFO - Using env: {'DISPLAY': ':0', 03:14:54 INFO - 'HOME': '/home/cltbld', 03:14:54 INFO - 'LANG': 'en_US.UTF-8', 03:14:54 INFO - 'LANGUAGE': 'en_US:en', 03:14:54 INFO - 'LOGNAME': 'cltbld', 03:14:54 INFO - 'MAIL': '/var/mail/cltbld', 03:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:54 INFO - 'MOZ_NO_REMOTE': '1', 03:14:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:54 INFO - 'NO_EM_RESTART': '1', 03:14:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:54 INFO - 'PWD': '/builds/slave/test', 03:14:54 INFO - 'SHELL': '/bin/bash', 03:14:54 INFO - 'SHLVL': '1', 03:14:54 INFO - 'TERM': 'linux', 03:14:54 INFO - 'TMOUT': '86400', 03:14:54 INFO - 'USER': 'cltbld', 03:14:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:54 INFO - '_': '/tools/buildbot/bin/python'} 03:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:54 INFO - Running setup.py (path:/tmp/pip-kIFWyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:54 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)) 03:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:54 INFO - Running setup.py (path:/tmp/pip-x3cpPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:54 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)) 03:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:54 INFO - Running setup.py (path:/tmp/pip-7wBa8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:55 INFO - Running setup.py (path:/tmp/pip-bv7NjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:55 INFO - Running setup.py (path:/tmp/pip-f4_g8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:55 INFO - Running setup.py (path:/tmp/pip-yzNKNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:55 INFO - Running setup.py (path:/tmp/pip-MuLi1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:55 INFO - Running setup.py (path:/tmp/pip-1SiNT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:55 INFO - Running setup.py (path:/tmp/pip-UzNWdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:55 INFO - Running setup.py (path:/tmp/pip-Dzm9pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:55 INFO - Running setup.py (path:/tmp/pip-sumpjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:55 INFO - Running setup.py (path:/tmp/pip-QBpdYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:55 INFO - Running setup.py (path:/tmp/pip-P1VSLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:55 INFO - Running setup.py (path:/tmp/pip-fp92mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:55 INFO - Running setup.py (path:/tmp/pip-KP6tvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:55 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)) 03:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:55 INFO - Running setup.py (path:/tmp/pip-Jeu9X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:56 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)) 03:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:56 INFO - Running setup.py (path:/tmp/pip-Nma49_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:56 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)) 03:14:56 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)) 03:14:56 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)) 03:14:56 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)) 03:14:56 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)) 03:14:56 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)) 03:14:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:57 INFO - Downloading blessings-1.6.tar.gz 03:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:14:58 INFO - Installing collected packages: blessings 03:14:58 INFO - Running setup.py install for blessings 03:14:58 INFO - Successfully installed blessings 03:14:58 INFO - Cleaning up... 03:14:58 INFO - Return code: 0 03:14:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:14:58 INFO - Reading from file tmpfile_stdout 03:14:58 INFO - Current package versions: 03:14:58 INFO - argparse == 1.2.1 03:14:58 INFO - blessings == 1.6 03:14:58 INFO - blobuploader == 1.2.4 03:14:58 INFO - docopt == 0.6.1 03:14:58 INFO - functools32 == 3.2.3-2 03:14:58 INFO - jsonschema == 2.5.1 03:14:58 INFO - manifestparser == 1.1 03:14:58 INFO - mozInstall == 1.12 03:14:58 INFO - mozcrash == 0.17 03:14:58 INFO - mozdebug == 0.1 03:14:58 INFO - mozdevice == 0.48 03:14:58 INFO - mozfile == 1.2 03:14:58 INFO - mozhttpd == 0.7 03:14:58 INFO - mozinfo == 0.9 03:14:58 INFO - mozleak == 0.1 03:14:58 INFO - mozlog == 3.2 03:14:58 INFO - moznetwork == 0.27 03:14:58 INFO - mozprocess == 0.23 03:14:58 INFO - mozprofile == 0.28 03:14:58 INFO - mozrunner == 6.12 03:14:58 INFO - mozscreenshot == 0.1 03:14:58 INFO - mozsystemmonitor == 0.3 03:14:58 INFO - moztest == 0.7 03:14:58 INFO - mozversion == 1.4 03:14:58 INFO - psutil == 3.1.1 03:14:58 INFO - requests == 1.2.3 03:14:58 INFO - wsgiref == 0.1.2 03:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:58 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')]} 03:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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')]} 03:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:58 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 03:14:58 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 03:14:58 INFO - Using env: {'DISPLAY': ':0', 03:14:58 INFO - 'HOME': '/home/cltbld', 03:14:58 INFO - 'LANG': 'en_US.UTF-8', 03:14:58 INFO - 'LANGUAGE': 'en_US:en', 03:14:58 INFO - 'LOGNAME': 'cltbld', 03:14:58 INFO - 'MAIL': '/var/mail/cltbld', 03:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:58 INFO - 'MOZ_NO_REMOTE': '1', 03:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:58 INFO - 'NO_EM_RESTART': '1', 03:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:58 INFO - 'PWD': '/builds/slave/test', 03:14:58 INFO - 'SHELL': '/bin/bash', 03:14:58 INFO - 'SHLVL': '1', 03:14:58 INFO - 'TERM': 'linux', 03:14:58 INFO - 'TMOUT': '86400', 03:14:58 INFO - 'USER': 'cltbld', 03:14:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:58 INFO - '_': '/tools/buildbot/bin/python'} 03:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 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)) 03:14:58 INFO - Cleaning up... 03:14:58 INFO - Return code: 0 03:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:14:58 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')]} 03:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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')]} 03:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:58 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 03:14:58 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 03:14:58 INFO - Using env: {'DISPLAY': ':0', 03:14:58 INFO - 'HOME': '/home/cltbld', 03:14:58 INFO - 'LANG': 'en_US.UTF-8', 03:14:58 INFO - 'LANGUAGE': 'en_US:en', 03:14:58 INFO - 'LOGNAME': 'cltbld', 03:14:58 INFO - 'MAIL': '/var/mail/cltbld', 03:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:58 INFO - 'MOZ_NO_REMOTE': '1', 03:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:58 INFO - 'NO_EM_RESTART': '1', 03:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:58 INFO - 'PWD': '/builds/slave/test', 03:14:58 INFO - 'SHELL': '/bin/bash', 03:14:58 INFO - 'SHLVL': '1', 03:14:58 INFO - 'TERM': 'linux', 03:14:58 INFO - 'TMOUT': '86400', 03:14:58 INFO - 'USER': 'cltbld', 03:14:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:58 INFO - '_': '/tools/buildbot/bin/python'} 03:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:14:58 INFO - Cleaning up... 03:14:58 INFO - Return code: 0 03:14:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:14:58 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')]} 03:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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')]} 03:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:14:58 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 0x7fe91d051f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, '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': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', '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 03:14:58 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 03:14:58 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 03:14:58 INFO - Using env: {'DISPLAY': ':0', 03:14:58 INFO - 'HOME': '/home/cltbld', 03:14:58 INFO - 'LANG': 'en_US.UTF-8', 03:14:58 INFO - 'LANGUAGE': 'en_US:en', 03:14:58 INFO - 'LOGNAME': 'cltbld', 03:14:58 INFO - 'MAIL': '/var/mail/cltbld', 03:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:58 INFO - 'MOZ_NO_REMOTE': '1', 03:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:58 INFO - 'NO_EM_RESTART': '1', 03:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:58 INFO - 'PWD': '/builds/slave/test', 03:14:58 INFO - 'SHELL': '/bin/bash', 03:14:58 INFO - 'SHLVL': '1', 03:14:58 INFO - 'TERM': 'linux', 03:14:58 INFO - 'TMOUT': '86400', 03:14:58 INFO - 'USER': 'cltbld', 03:14:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:58 INFO - '_': '/tools/buildbot/bin/python'} 03:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:59 INFO - Downloading/unpacking flake8 03:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:15:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:15:00 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 03:15:00 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 03:15:00 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 03:15:00 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 03:15:00 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 03:15:00 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 03:15:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:15:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:15:01 INFO - no previously-included directories found matching 'docs/_build' 03:15:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:15:01 INFO - Running setup.py install for pep8 03:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:15:01 INFO - no previously-included directories found matching 'docs/_build' 03:15:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:15:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:15:01 INFO - Cleaning up... 03:15:01 INFO - Return code: 0 03:15:01 INFO - Running post-action listener: _resource_record_post_action 03:15:01 INFO - Running post-action listener: _start_resource_monitoring 03:15:01 INFO - Starting resource monitoring. 03:15:01 INFO - ##### 03:15:01 INFO - ##### Finished create-virtualenv step (success) 03:15:01 INFO - ##### 03:15:01 INFO - ##### 03:15:01 INFO - ##### Running install step. 03:15:01 INFO - ##### 03:15:01 INFO - Running pre-action listener: _resource_record_pre_action 03:15:01 INFO - Running main action method: install 03:15:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:15:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:15:02 INFO - Reading from file tmpfile_stdout 03:15:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:15:02 INFO - Reading from file tmpfile_stdout 03:15:02 INFO - Output received: 03:15:02 INFO - Usage: mozinstall [options] installer 03:15:02 INFO - Options: 03:15:02 INFO - -h, --help show this help message and exit 03:15:02 INFO - -d DEST, --destination=DEST 03:15:02 INFO - Directory to install application into. [default: 03:15:02 INFO - "/builds/slave/test"] 03:15:02 INFO - --app=APP Application being installed. [default: firefox] 03:15:02 INFO - mkdir: /builds/slave/test/build/application 03:15:02 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'] 03:15:02 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 03:15:17 INFO - Reading from file tmpfile_stdout 03:15:17 INFO - Output received: 03:15:17 INFO - /builds/slave/test/build/application/firefox/firefox 03:15:17 INFO - Running post-action listener: _resource_record_post_action 03:15:17 INFO - ##### 03:15:17 INFO - ##### Finished install step (success) 03:15:17 INFO - ##### 03:15:17 INFO - ##### 03:15:17 INFO - ##### Running run-tests step. 03:15:17 INFO - ##### 03:15:17 INFO - Running pre-action listener: _resource_record_pre_action 03:15:17 INFO - Running main action method: run_tests 03:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:15:17 INFO - Python 2.7.3 03:15:17 INFO - Return code: 0 03:15:17 INFO - grabbing minidump binary from tooltool 03:15:17 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')]} 03:15:17 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 0xffe990>, '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 0xf6a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6a900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:15:17 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 03:15:17 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 03:15:17 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 03:15:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:15:17 INFO - Return code: 0 03:15:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:15:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:15:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:15:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:15:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:15:18 INFO - Return code: 0 03:15:18 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/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 03:15:18 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/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 03:15:18 INFO - Using env: {'DISPLAY': ':0', 03:15:18 INFO - 'HOME': '/home/cltbld', 03:15:18 INFO - 'LANG': 'en_US.UTF-8', 03:15:18 INFO - 'LANGUAGE': 'en_US:en', 03:15:18 INFO - 'LOGNAME': 'cltbld', 03:15:18 INFO - 'MAIL': '/var/mail/cltbld', 03:15:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:15:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:18 INFO - 'MOZ_NO_REMOTE': '1', 03:15:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:18 INFO - 'NO_EM_RESTART': '1', 03:15:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:18 INFO - 'PWD': '/builds/slave/test', 03:15:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:15:18 INFO - 'SHELL': '/bin/bash', 03:15:18 INFO - 'SHLVL': '1', 03:15:18 INFO - 'TERM': 'linux', 03:15:18 INFO - 'TMOUT': '86400', 03:15:18 INFO - 'USER': 'cltbld', 03:15:18 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998', 03:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:18 INFO - '_': '/tools/buildbot/bin/python'} 03:15:18 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/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 03:15:19 INFO - mozversion application_buildid: 20160801004002 03:15:19 INFO - mozversion application_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:15:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:15:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:15:19 INFO - mozversion application_name: Firefox 03:15:19 INFO - mozversion application_remotingname: firefox-dev 03:15:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:15:19 INFO - mozversion application_vendor: Mozilla 03:15:19 INFO - mozversion application_version: 49.0a2 03:15:19 INFO - mozversion platform_buildid: 20160801004002 03:15:19 INFO - mozversion platform_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:15:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:15:19 INFO - mozversion platform_version: 49.0a2 03:15:19 INFO - using testdate: 1470046519 03:15:19 INFO - actual date: 1470046519 03:15:19 INFO - starting webserver on 'localhost:41229' 03:15:19 INFO - SUITE-START | Running 2 tests 03:15:19 INFO - TEST-START | dromaeo_css 03:15:19 INFO - operating with platform_type : linux_ 03:15:19 INFO - Initialising browser for dromaeo_css test... 03:15:19 INFO - TEST-INFO | started process 23839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSH8DtY/profile http://localhost:41229/getInfo.html) 03:15:22 INFO - PROCESS | 23839 | __metrics Screen width/height:1600/1200 03:15:22 INFO - PROCESS | 23839 | colorDepth:24 03:15:22 INFO - PROCESS | 23839 | Browser inner width/height: 1024/697 03:15:22 INFO - PROCESS | 23839 | __metrics 03:15:23 INFO - PROCESS | 23839 | 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" 03:15:23 ERROR - PROCESS | 23839 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:15:24 INFO - TEST-INFO | 23839: exit 0 03:15:24 INFO - Browser initialized. 03:15:24 INFO - Running cycle 1/1 for dromaeo_css test... 03:15:24 INFO - TEST-INFO | started process 23930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSH8DtY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:15:36 INFO - PROCESS | 23930 | RSS: Main: 142712832 03:15:36 INFO - PROCESS | 23930 | 03:16:27 INFO - PROCESS | 23930 | 1470046587524 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] 03:18:35 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41229/tests/dromaeo/cssquery-ext.html) 03:18:36 INFO - PROCESS | 23930 | RSS: Main: 256036864 03:18:36 INFO - PROCESS | 23930 | 03:21:39 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-ext.html (next: http://localhost:41229/tests/dromaeo/cssquery-jquery.html) 03:21:39 INFO - PROCESS | 23930 | RSS: Main: 235188224 03:21:39 INFO - PROCESS | 23930 | 03:24:41 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41229/tests/dromaeo/cssquery-mootools.html) 03:24:42 INFO - PROCESS | 23930 | RSS: Main: 227119104 03:24:42 INFO - PROCESS | 23930 | 03:27:44 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41229/tests/dromaeo/cssquery-prototype.html) 03:27:44 INFO - PROCESS | 23930 | RSS: Main: 218824704 03:27:44 INFO - PROCESS | 23930 | 03:30:47 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41229/tests/dromaeo/cssquery-yui.html) 03:30:47 INFO - PROCESS | 23930 | RSS: Main: 217702400 03:30:47 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | Cycle 1(1): loaded http://localhost:41229/tests/dromaeo/cssquery-yui.html (next: http://localhost:41229/tests/dromaeo/cssquery-dojo.html) 03:33:50 INFO - PROCESS | 23930 | RSS: Main: 257564672 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | __start_tp_report 03:33:50 INFO - PROCESS | 23930 | _x_x_mozilla_page_load 03:33:50 INFO - PROCESS | 23930 | _x_x_mozilla_page_load_details 03:33:50 INFO - PROCESS | 23930 | |i|pagename|runs| 03:33:50 INFO - PROCESS | 23930 | |0;dojo.html;3408.488726690996;3419.555457790487;3439.380911435942;3444.3111377724463;3466.9826650866744;5632.57755668325;5704.942950570493;5711.171880077397;5714.171445140452;5731.770729170828;5638.661680299185;5676.829695109143;5687.914681279784;5690.971545142279;5696.430356964302;4954.479779623141;5116.590672746181;5165;5184.196449550307;5222.451642577513;4998.700077995314;5093.134167191568;5130.281760553526;5132.486751324856;5137.820176293831;5070.619703522232;5129.102407078765;5144.1254986652175;5165.9225111623355;5167.069927413064;5224.686518808846;5227.294315267428;5237.711925844077;5238.161894096965;5268.657537260085;5018.372703412074;5081.771320290596;5136.563392111675;5141.0232055909255;5151.5363617274625;5450.972745136289;5505.917411238835;5512.476314750093;5530.032244357221;5559.221708960748;6093.740646886307;6097.085437184413;6112.174856394375;6117.632942023449;6118.3235254481215;6028.668423236721;6092.9695351523405;6102.603330783485;6139.693015349215;6160.199174107337;5704.942950570483;5747.59766816323;5750.568707346882;5753.424657534255;5759.625624334447;6004.819855404257;6222.12890195373;6226.439620434183;6227.09707092469;6232.906506402408;5900.76396944126;5927.436893495111;5940.079287710412;6029.517638588903;6038.33578306386;6054.547624731436;6089;6091.634501929899;6098.512119030467;6122.448979591769;5840.065589505659;5970.373110823301;6072.240969878765;6077.36187700294;6098.786542471081;6063.151158837766;6068.787592434332;6069.696515174223;6079.452849243589;6116.235470566179;4534.387857639243;4556.134334476439;4561.612262957619;4561.817527298937;4585.12882552314;4085.4484644572603;4093.5087789465456;4095.610916962884;4117.094239267416;4129.752214867118;5888.205092312569;5904.940950590439;5906.320773111147;5906.793262235887;5929.703514824241;6151.569390142647;6161.691915404212;6171.1668924694595;6192.969035154795;6198.876022479525;5706.058500347396;5749.8275051748515;5780.479756821906;5813.82558523245;5928.051511758098;5183.637145399861;5184.714840683723;5189.247559103909;5190.792368305226;5191.8182863599595;6365.809025729235;6374.872502549923;6376.362363763679;6376.74493020274;6381.776637817654;5963.821085367446;5968.283805943314;6042.063480160582;6055.848603784764;6081.604695694766;6291.402316779899;6307.684615769285;6342.778002769881;6359.332270111571;6359.936400636119;5766.94233057664;5987.850303742442;5995.160677505066;5995.550333724901;5999.4900433463645;5983.072623743326;6016.639001659914;6045.1234570987845;6120.785772497941;6126.0198368262645;5955.67243801595;5957.821265362046;5959.523238141025;5960.403959604005;5962.493188079062;5731.254936858182;5825.621334613236;5868.735906884115;5952.107183922446;5952.8809423812145;5806.158107074366;5881.5588830838715;5891.970540147272;5892;5911.201987731602;5988.8802223956145;5999.940000600112;6000.609960352563;6037.426444487766;6044.9395506044375;5738.340090889435;5742.19609254713;5792;5880.500157486662;5901.911471327848 03:33:50 INFO - PROCESS | 23930 | |1;ext.html;614.649649699671;658.5488940076049;663.4128796015525;664.1233571685376;673.7608149107067;33746.15634609136;45077.32384014238;45761.54238457618;45948.540514594846;45974.77012614932;48496.75751621237;48842;48893;48943.265851012264;48961.265581016225;8237.46456480327;9088.50013249271;9250.07499250073;9253.074692530761;10665.253432259746;10519.474026298692;10937.289076210021;10942.781144377108;10943.179261555415;10948.616798412057;10952.780944381107;10978.505967231495;11044.447777611127;11049.28179668323;11053.115750739962;8331.916680833205;10841.620543280947;10883.619073332435;11072.667819965414;11077.833832492519;10670.573177072907;11058.889411105867;11063.778724425547;11068.501917413736;11071.72320691989;46559;47073.76463117662;47212.7639361801;47294;47320.52679473196;3385.4752513360468;3385.830708464567;3763.379042457991;4011.8796436106963;4039.373897045933;35672;35999;36353.45469817928;36525.45211821825;37819;4507.318814709062;4547.704399214041;4601.562851529099;4629.722216667011;4654.301854721819;372468;374582;375126;376243;376654;39080.804595976835;69353.30646693468;70518.64740676264;70520;70584;39020;41119.7944010278;41165.176696465904;41167.588324116376;41190.1761964765;13918.860811391958;14061;14064.648383790487;14068.08557443763;14073.859261407255;11274.379909104913;12116.454759535842;12372.072094592762;12415.255084694947;12608.873911260953;7071.823204419828;7189;7223.133224013152;7291.70833166678;7331.193568707438;3541.911452213715;3615.240799432096;3615.6022837487853;3660.2679464106754;3683.889483315505;1089.841972913923;1160.7562411893427;1190.6070996571111;1195.1514424758518;1201.6575276046285;33039.50440743391;34055.489167662505;35123;36906.815465923035;36919.815400922824;1115.6597237842373;1148.9253198542067;1178.2871362825538;1185.958491452795;1189.4944648524342;16072.196390180445;16404.425845095604;16422.260998255108;16648.916755416387;16656.833431665527;603185;607588;608857;612919;615147;63565.68217158884;63975.36024639787;64046.35953640497;64132.35867641357;64136.35863641304;1318.736252749454;1321.458202137119;1447.957470621151;1459.5329494561638;1495.5662857771124;33508.664913350556;34489.310213795085;34664;34796.304073917876;35015.299694006484;54145;54615.45384546262;54812.177817331976;54872.725636371564;54899;464379;466182.6690866524;466579;467399;468412;27290.86354568294;29540.704592953796;30016.84991575028;30281.394372112874;31806;49343.506564933894;50330.496695034046;50995;51377.229341559156;51381.22928156155;43092.56907431011;43492.56507435012;43632.34551481667;43638.34542481802;43930;42828.57171428246;43216.78391608148;43545;43859;44089.338659920766;42730.359044614976;43657.781711091244;43659.78170109129;43888.561114389726;43970.78014609907;36575.817120914224;37503.81248093742;38168.80915595404;38637;38877.22245555017;29087.272818179714;29146.41707165887;29269.451593743477;29303.706962930097;29355 03:33:50 INFO - PROCESS | 23930 | |2;jquery.html;410.4807418615451;412.7152264937195;425.627575871113;427.52117628256354;434.96085352318283;18224.179911903964;19739.21043158274;19877.503062423446;19967.10148043338;20509.897450512763;18156.63686726265;18277.086145692727;18351.082445877688;18397.356092536764;18480.815191848076;5454.263674403955;5825.038868586678;5866.296044474648;5873.0603103503445;5875.118732190184;4240.469941257343;5679.630823996447;5787.594868359217;6153.261608606974;6469.385408386196;6518.152640156773;6549.377809108151;6553.279139294674;6558.803235902907;6563.638999855006;6431.742730290783;6461.224653041641;6528.902066469007;6529;6531.118299029641;3825.674817640504;5460.535854452376;5774.769009239625;5780.537556995472;5829.795957141479;23448;23515.41211469727;23611;23815.285541433786;23905.880470597534;2381.8094552435764;2432.270318904339;2751.17464760571;2979.2998645318335;3002.324476992659;14162.504312349121;14558.417663293456;14598.416063357454;14862.777058344134;15049.473268435551;2012.7886571909885;2016.4353980885376;2058.3927741316197;2088.049937278535;2118.4386137673373;76067.99999999945;77981.61009194974;78550;80302;83963.16036839614;8827.779305517413;9408.294377921684;9613.423194608346;10010.14913732341;10351.844722329171;13771.655708607164;13781.862181378057;13825.654358640913;13893.722125557635;13911.513097041556;7627;7821.960890195513;7888.921110788819;7976.361891048702;7986;7718.343940765098;7775.183605721431;7776.688932442752;7819.530828150329;7873.921260787319;4190.559991200941;4232.767197804089;4242.596953289432;4302.44068271129;4315.050688848449;1813.6554054729559;1884.2368840619572;2013.3357289732546;2058.651583212968;2083.9791602083783;952.6713283917038;972.7227740094181;1045;1085.4464223246202;1106.4965440724452;18070.277188912558;19058.809411905702;19146.71279930802;19561.510962225773;19871.40385788429;1032.9535170917327;1041.770810421706;1043.7495001199657;1056.8784589772122;1074.6883403812963;10741.731456713645;10802.89197108019;10953.726156846142;11006.559737610565;11068.612598559173;466386;489502;493650;498008;499162;89478;94464;94614;94679;96103;1066.6746642274181;1073.382804887193;1074.9247552671395;1080.173667144635;1093.1746531368924;17321.74017389715;17668.558286042953;17784;17940.461786146436;18111;38589;38873;39842.402363963985;40827.7958610205;43089.56910430981;56687.71656141693;57411.42588574061;57680;60407.395926041936;60987;8346.081930987708;8417.5791210438;8652.134786521297;8657.783555411164;8789.516576588094;8441;9554.570044347882;12809.871901280869;12887;12943.870561294267;11527.193096483166;11816.828666473546;11902.464389102684;11943.820842687193;12070.215435996632;10923.180761442765;11200.215984881306;11522.769544608893;11569.826452603049;11693.532258709896;11476.254043487146;12378.44297006655;12436.253824770543;12453.564125255934;12544.937275313565;8561.1866872647;8581.35639827003;8747.16901894319;8764.298856091369;8985.101489850962;11576.36330001858;12079.51681932748;12141.150119491904;12173.45219465144;12253.75492490163 03:33:50 INFO - PROCESS | 23930 | |3;mootools.html;1320.3992183556484;1517.9468718594849;1558.2286768049814;1720.3032771727455;1864.375434229534;9120.498372589505;9545.904540954589;9570.47362395068;9831;9875.901240987589;9574.090461406155;9753.561089750961;9791.559379827908;9792.265580081505;9794.804103917917;2093.4033800366897;2158.622241107808;2164.5454454564483;2391.4619210677633;2438.622013587888;2287.9885600571974;2389.8566086034893;2391.342380845264;2401.8799060047013;2407.458321877581;2408.217329367954;2444.4622183119686;2445.205308274809;2466.482038771851;2469.715982661999;2066.5350296183387;2144.4531644430726;2371.5019845832394;2402.843815152018;2404.879756012201;2103.3269353806786;2294.564032833773;2361.7165940087298;2365.9053637854463;2388.5461762265113;9478.90521094787;9530.714078577654;9547.904520954771;9552.331336806426;9554.092361225672;5278.47215278473;5393.568514518829;5399.19012148177;5400.216968539579;5402.648827826218;3506.36885360635;3553.8045407502577;3629.0927268182954;3630.419132938717;3657.871974480907;1130.9943450282778;1134.466800603715;1149.7585507043532;1207.9698007549794;1212.3150420012664;14006.29968501581;14009.649758756012;14021.369038393297;14026.088304260293;14058.859411405858;4868.488808675038;4868.732219727878;4880.145974454456;5036.244563315532;5065.924011139836;11226.775464490664;11227.382493962747;11251.156163287786;11280.435978201222;11301.660950171508;5511.58663100269;5567.638103523258;5568.1091025435735;5574.108142697149;5577.776888924479;4332.891677708019;5005.524475174852;5094.566961808214;5147.819826306061;5163.948360516422;4305.117450922553;4345.261305578059;4383.189110014658;4385.166818304512;4425.845095421645;1295.184034058537;1333.9066265361525;1334.8665133486575;1340.544214966916;1344.388303321986;1070.1385384765338;1072.3780207479642;1093.276350444771;1110.3282514078867;1118.9873164785881;6206.782762603378;6437.967810160919;6473.967630161819;6508.3491650835485;6561.0814485972005;459.1528629678238;464.2340138771055;464.85589467265254;469.17893686049416;473.3302377136382;4496.659298080054;4503.482099558527;4505.098980203972;4511.120331535319;4563.269876819693;12059.15585908978;12386.380680965916;12446.377681115908;12486.562970295996;12489.875101249054;12249.203801752858;12309.876901231051;12355.444005019795;12361.876381236252;12412.62762117138;8508.957455212685;8911.866322005175;8935.687250946186;9111.27109831212;9144.725658230263;6493.415592596689;6816.625085620339;6848.897266541106;6912.516123871281;6926.099607051051;11157.609483668033;11433.656990290305;11435.771284574428;11495.310281383145;11574.826377604511;11390.430478476208;11558.42207889619;11567.76864462723;11694.590689325832;11712.297262164297;7908.051033875971;8211;8212.13772553897;8840.734777956672;9026;9104.544772761468;9362.110599493037;9415.623375065195;9702.951485242529;9703;8236.176382361717;8437.873431898404;8459.83080338402;8544.829103418022;8545.529995850287;8224.465409748347;8311.6675332986;8316.293115085286;8380.58097095155;8462.492250464991;7591.127020392505;7664.501807382502;7672.386209103147;7695.769126926201;7700.268474494914;5341.065313570032;5395.649282796762;5502.312210973629;5533.474319939599;5570.359408668055;5409.675419474844;5411.972940135274;5421.539169170507;5516.089845175662;5712.571557133148 03:33:50 INFO - PROCESS | 23930 | |4;prototype.html;339.5619650650661;346.7590024932672;356.33543441481635;356.613074813827;359.1165732298547;5210.55710264627;5393.487618676229;5454.890902181959;5511.944880551193;5553.305836770403;5418.2956215692;5445.945540544593;5456.127019676853;5482.205080263359;5534.778608855641;4269.765162916031;4272.978635106821;4280.807363668627;4308.375285583604;4316.309390497521;3999.740016898907;4158.002079500913;4258.594663760951;4272.145570885819;4272.380504826799;4223.049813791888;4264.744115353089;4271.508776490697;4278.908878236061;4287.421198138242;4199.790010499493;4230.534641189467;4242.8785607196405;4247.768147237298;4279.272523670983;3640.690541303992;3775.3581891078584;3807.276617442677;3808.5810560838286;3812.0088776917923;5203.895922081575;5205.167173252262;5228.738563071869;5239.659422137549;5240.475952404767;4863.732494712789;4868.537488938545;4870.951290487121;4888.951110488921;4905.288733133676;5433.972830135824;5672.461116193955;5673.8581535461535;5682.062459694145;5693.886122277531;4080.7349721586106;4096.201240758053;4106.096658735073;4126.793660316972;4129.194807012634;5895.911561326584;5915.349311575723;5962.791302304442;5976.910346344852;5981.252343457068;3920.725549211585;4135.193637240769;4136.21411931732;4187.644050255702;4201.579842015774;4234.9576504234565;4263.467066616673;4268.317069268902;4271.145770845843;4272.829086836554;4863.9785645014945;4864.489228631013;4870.9269360959615;4879.463259041502;4882.829100981448;5546.805861794818;5698.8290351289525;5714.685692286964;5730.36965933747;5752.309722833287;1393.143216921586;1438.3239877257672;1459.2484870291808;1467.8458761830068;1474.6387135151956;929.8423462788871;944.4616568555995;948.374073111743;955.3074021334616;968.9854652180223;4939.6542242042715;4948.678335908155;4976.975115124401;5056.82301119459;5061.417936937226;5075.365579302587;5135.537801597874;5210.374755029421;5218.269442278084;5226.346706661668;4786.7845946932475;4945.9258111128365;4976.552110310089;4992.2761199626575;4997.400311962588;2755.8484283364605;2772.6534183227095;2781.332480204777;2790.8325500470037;2792.8324300541626;5238.140460136673;5330.387005494418;5333.759980800874;5403.810866619728;5442.401335853053;4901;5148.099082660594;5186.92219616706;5246.580273578181;5288.65623734456;5267.288915996368;5333.280007198979;5342.732863356817;5345.305110335631;5348.010618035676;5155.2782610433815;5173.163526948029;5180.119379705384;5205.297284866646;5241.0042092002395;5133.486651334837;5349.545288650353;5775.220345253337;5796.21751063601;5831.271091113611;6127.417895299939;6134.693265336626;6142.570020098729;6144.17053697763;6157.84605384869;4123.154753275511;4218.599233072853;4236.491621005499;4238.25830479671;4239;5639.54883609302;5754.56840736938;5830.82507524775;5841.853953651193;5848.239728835224;5745.2818397700285;5764.711764411846;5773.884522309614;5790.971045144747;5792.942070579409;5591.860203494945;5667.3482549507335;5710.685912274865;5721.22763426932;5744.597878148489;5571.860703482445;5580.888382232251;5659.037963546125;5663.461971112738;5668.829935101953;5732.971335143297;5759.136129580596;5768.971155144198;5816.214811000461;5838.503727183237;5437.4018857926385;5564.332280126248;5642.322921249476;5648.88702225945;5668.291463567054 03:33:50 INFO - PROCESS | 23930 | |5;yui.html;798.8681867491864;827.255470076931;838.1241602525361;857.575500127437;906.5104843384574;15320.770188447168;15851.682966340695;16332.83667163328;16481.67036659266;16723.498295051137;14716.705665886675;14768.187749673763;14837.92581037096;14871.405143794262;14874.776878346807;727.8216836874973;846.2975730143996;853.2789792625238;866.536403024382;871.7515508080198;865.1478293880523;865.26020252684;868.1708967935606;869.0874581689233;869.5652173913044;917.156216281023;921.9078092190795;921.9170274675278;922.2437601167046;923.1968187676731;761.8323968726871;891.3315013739663;912.8448163812168;927.5037854747745;929.4469790474657;680.6732768271257;686.6772616870063;686.6909890549273;687.1856849633201;687.5049964025943;14430.567082987527;14465.855341446557;14495.347709353104;14514.346854391577;14529.273536323248;13492.595222143253;13759.518416855362;13799.448022079105;13943.442262309596;14064.929675351661;12742.490300387974;12744.426500807485;12766.17019893704;12909.354532273443;12925.482980680761;4561.156186105581;4593.28804035375;4622.121796858586;4633.86098417048;4644.373009643722;73239.26760732378;74441.62779186123;75405.86891196636;75824.24175758228;75865.62067189685;11982;12084.57703980374;12087.395630218454;12115;12245.632631021084;22308.330750077523;22780.4304892379;22810;22811.543769124855;22817.31548053561;8594.699185528601;8749.16882896124;9001.684941027033;9213.401128926598;9255.722328330161;4336.6313863321975;4754.952450475451;4920.6555541111775;5032.597392208614;5080.695158290514;3792.2036372361563;3998.200359928026;4015.437838702584;4021.4571032910767;4023;582.1587805620921;718.6442710858054;721.1130309719075;722.2957616324042;730.4156674660356;747.8541684371495;757.443279189795;770.1759117744073;773.2576726342774;779.9922000779919;10292.897071029194;10474.633387831389;10562.20783441245;10604.893951060545;10688.198385121146;612.2010775937445;716.6631683108931;732.8717474442058;742.3207764895061;771.9614019299013;5037.345145131109;5071.239314102914;5122.897542049138;5199.532042116228;5210.192420174803;73790.63104684443;74032;74152;90508.54745726229;122847.3857630724;101765;105673;108630.45684771525;108677;108910.45544772226;57574.7121264391;59666;92993.5350323244;95300;95919;9930.106290433896;10018.098371146632;10036.397816131055;10154.949225253826;10171.28800983924;53445.7327713359;54499;54733.45266547283;54852.451475486334;56830.147547787645;70789.64605177147;82634;82658;83298;83588.58205708933;12137.635870923887;12207.389630518615;12399.442025108712;12411.606561613286;12736.808947865604;13827.585172444842;14288.285585720465;14372.568822935329;14654.56036318912;15676.372945082068;13822.447102116204;13903.09629874055;14532.200728960008;14692.706145877237;15164.393424262906;13704.794428083786;13733;13892.16647001183;14243.501477448239;14303.64240893986;13835.584932452042;14246.430142794587;14406.279686015867;15098.54704358871;15134.243287835345;11114.22200445961;11193.328400296008;11299.3785341807;11326.093912487146;11331.490082946451;11703.531858725897;11746.941265293619;12051.879481205076;12141.57504487374;12143.81784273219 03:33:50 INFO - PROCESS | 23930 | __end_tp_report 03:33:50 INFO - PROCESS | 23930 | __start_cc_report 03:33:50 INFO - PROCESS | 23930 | _x_x_mozilla_cycle_collect,893 03:33:50 INFO - PROCESS | 23930 | __end_cc_report 03:33:50 INFO - PROCESS | 23930 | __startTimestamp1470047630750__endTimestamp 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | ------- Summary: start ------- 03:33:50 INFO - PROCESS | 23930 | Number of tests: 6 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#0] dojo.html Cycles:165 Average:5645.90 Median:5892.00 stddev:642.02 (10.9%) stddev-sans-first:619.53 03:33:50 INFO - PROCESS | 23930 | Values: 3408.5 3419.6 3439.4 3444.3 3467.0 5632.6 5704.9 5711.2 5714.2 5731.8 5638.7 5676.8 5687.9 5691.0 5696.4 4954.5 5116.6 5165.0 5184.2 5222.5 4998.7 5093.1 5130.3 5132.5 5137.8 5070.6 5129.1 5144.1 5165.9 5167.1 5224.7 5227.3 5237.7 5238.2 5268.7 5018.4 5081.8 5136.6 5141.0 5151.5 5451.0 5505.9 5512.5 5530.0 5559.2 6093.7 6097.1 6112.2 6117.6 6118.3 6028.7 6093.0 6102.6 6139.7 6160.2 5704.9 5747.6 5750.6 5753.4 5759.6 6004.8 6222.1 6226.4 6227.1 6232.9 5900.8 5927.4 5940.1 6029.5 6038.3 6054.5 6089.0 6091.6 6098.5 6122.4 5840.1 5970.4 6072.2 6077.4 6098.8 6063.2 6068.8 6069.7 6079.5 6116.2 4534.4 4556.1 4561.6 4561.8 4585.1 4085.4 4093.5 4095.6 4117.1 4129.8 5888.2 5904.9 5906.3 5906.8 5929.7 6151.6 6161.7 6171.2 6193.0 6198.9 5706.1 5749.8 5780.5 5813.8 5928.1 5183.6 5184.7 5189.2 5190.8 5191.8 6365.8 6374.9 6376.4 6376.7 6381.8 5963.8 5968.3 6042.1 6055.8 6081.6 6291.4 6307.7 6342.8 6359.3 6359.9 5766.9 5987.9 5995.2 5995.6 5999.5 5983.1 6016.6 6045.1 6120.8 6126.0 5955.7 5957.8 5959.5 5960.4 5962.5 5731.3 5825.6 5868.7 5952.1 5952.9 5806.2 5881.6 5892.0 5892.0 5911.2 5988.9 5999.9 6000.6 6037.4 6044.9 5738.3 5742.2 5792.0 5880.5 5901.9 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#1] ext.html Cycles:180 Average:64361.44 Median:37993.90 stddev:131254.73 (345.5%) stddev-sans-first:131535.67 03:33:50 INFO - PROCESS | 23930 | Values: 614.6 658.5 663.4 664.1 673.8 33746.2 45077.3 45761.5 45948.5 45974.8 48496.8 48842.0 48893.0 48943.3 48961.3 8237.5 9088.5 9250.1 9253.1 10665.3 10519.5 10937.3 10942.8 10943.2 10948.6 10952.8 10978.5 11044.4 11049.3 11053.1 8331.9 10841.6 10883.6 11072.7 11077.8 10670.6 11058.9 11063.8 11068.5 11071.7 46559.0 47073.8 47212.8 47294.0 47320.5 3385.5 3385.8 3763.4 4011.9 4039.4 35672.0 35999.0 36353.5 36525.5 37819.0 4507.3 4547.7 4601.6 4629.7 4654.3 372468.0 374582.0 375126.0 376243.0 376654.0 39080.8 69353.3 70518.6 70520.0 70584.0 39020.0 41119.8 41165.2 41167.6 41190.2 13918.9 14061.0 14064.6 14068.1 14073.9 11274.4 12116.5 12372.1 12415.3 12608.9 7071.8 7189.0 7223.1 7291.7 7331.2 3541.9 3615.2 3615.6 3660.3 3683.9 1089.8 1160.8 1190.6 1195.2 1201.7 33039.5 34055.5 35123.0 36906.8 36919.8 1115.7 1148.9 1178.3 1186.0 1189.5 16072.2 16404.4 16422.3 16648.9 16656.8 603185.0 607588.0 608857.0 612919.0 615147.0 63565.7 63975.4 64046.4 64132.4 64136.4 1318.7 1321.5 1448.0 1459.5 1495.6 33508.7 34489.3 34664.0 34796.3 35015.3 54145.0 54615.5 54812.2 54872.7 54899.0 464379.0 466182.7 466579.0 467399.0 468412.0 27290.9 29540.7 30016.8 30281.4 31806.0 49343.5 50330.5 50995.0 51377.2 51381.2 43092.6 43492.6 43632.3 43638.3 43930.0 42828.6 43216.8 43545.0 43859.0 44089.3 42730.4 43657.8 43659.8 43888.6 43970.8 36575.8 37503.8 38168.8 38637.0 38877.2 29087.3 29146.4 29269.5 29303.7 29355.0 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#2] jquery.html Cycles:180 Average:29035.64 Median:2865.24 stddev:80710.24 (2816.9%) stddev-sans-first:80908.03 03:33:50 INFO - PROCESS | 23930 | Values: 410.5 412.7 425.6 427.5 435.0 18224.2 19739.2 19877.5 19967.1 20509.9 18156.6 18277.1 18351.1 18397.4 18480.8 5454.3 5825.0 5866.3 5873.1 5875.1 4240.5 5679.6 5787.6 6153.3 6469.4 6518.2 6549.4 6553.3 6558.8 6563.6 6431.7 6461.2 6528.9 6529.0 6531.1 3825.7 5460.5 5774.8 5780.5 5829.8 23448.0 23515.4 23611.0 23815.3 23905.9 2381.8 2432.3 2751.2 2979.3 3002.3 14162.5 14558.4 14598.4 14862.8 15049.5 2012.8 2016.4 2058.4 2088.0 2118.4 76068.0 77981.6 78550.0 80302.0 83963.2 8827.8 9408.3 9613.4 10010.1 10351.8 13771.7 13781.9 13825.7 13893.7 13911.5 7627.0 7822.0 7888.9 7976.4 7986.0 7718.3 7775.2 7776.7 7819.5 7873.9 4190.6 4232.8 4242.6 4302.4 4315.1 1813.7 1884.2 2013.3 2058.7 2084.0 952.7 972.7 1045.0 1085.4 1106.5 18070.3 19058.8 19146.7 19561.5 19871.4 1033.0 1041.8 1043.7 1056.9 1074.7 10741.7 10802.9 10953.7 11006.6 11068.6 466386.0 489502.0 493650.0 498008.0 499162.0 89478.0 94464.0 94614.0 94679.0 96103.0 1066.7 1073.4 1074.9 1080.2 1093.2 17321.7 17668.6 17784.0 17940.5 18111.0 38589.0 38873.0 39842.4 40827.8 43089.6 56687.7 57411.4 57680.0 60407.4 60987.0 8346.1 8417.6 8652.1 8657.8 8789.5 8441.0 9554.6 12809.9 12887.0 12943.9 11527.2 11816.8 11902.5 11943.8 12070.2 10923.2 11200.2 11522.8 11569.8 11693.5 11476.3 12378.4 12436.3 12453.6 12544.9 8561.2 8581.4 8747.2 8764.3 8985.1 11576.4 12079.5 12141.2 12173.5 12253.8 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#3] mootools.html Cycles:180 Average:6331.72 Median:2511.21 stddev:3780.23 (150.5%) stddev-sans-first:3772.08 03:33:50 INFO - PROCESS | 23930 | Values: 1320.4 1517.9 1558.2 1720.3 1864.4 9120.5 9545.9 9570.5 9831.0 9875.9 9574.1 9753.6 9791.6 9792.3 9794.8 2093.4 2158.6 2164.5 2391.5 2438.6 2288.0 2389.9 2391.3 2401.9 2407.5 2408.2 2444.5 2445.2 2466.5 2469.7 2066.5 2144.5 2371.5 2402.8 2404.9 2103.3 2294.6 2361.7 2365.9 2388.5 9478.9 9530.7 9547.9 9552.3 9554.1 5278.5 5393.6 5399.2 5400.2 5402.6 3506.4 3553.8 3629.1 3630.4 3657.9 1131.0 1134.5 1149.8 1208.0 1212.3 14006.3 14009.6 14021.4 14026.1 14058.9 4868.5 4868.7 4880.1 5036.2 5065.9 11226.8 11227.4 11251.2 11280.4 11301.7 5511.6 5567.6 5568.1 5574.1 5577.8 4332.9 5005.5 5094.6 5147.8 5163.9 4305.1 4345.3 4383.2 4385.2 4425.8 1295.2 1333.9 1334.9 1340.5 1344.4 1070.1 1072.4 1093.3 1110.3 1119.0 6206.8 6438.0 6474.0 6508.3 6561.1 459.2 464.2 464.9 469.2 473.3 4496.7 4503.5 4505.1 4511.1 4563.3 12059.2 12386.4 12446.4 12486.6 12489.9 12249.2 12309.9 12355.4 12361.9 12412.6 8509.0 8911.9 8935.7 9111.3 9144.7 6493.4 6816.6 6848.9 6912.5 6926.1 11157.6 11433.7 11435.8 11495.3 11574.8 11390.4 11558.4 11567.8 11694.6 11712.3 7908.1 8211.0 8212.1 8840.7 9026.0 9104.5 9362.1 9415.6 9703.0 9703.0 8236.2 8437.9 8459.8 8544.8 8545.5 8224.5 8311.7 8316.3 8380.6 8462.5 7591.1 7664.5 7672.4 7695.8 7700.3 5341.1 5395.6 5502.3 5533.5 5570.4 5409.7 5412.0 5421.5 5516.1 5712.6 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#4] prototype.html Cycles:180 Average:4672.55 Median:5205.23 stddev:1354.71 (26.0%) stddev-sans-first:1318.89 03:33:50 INFO - PROCESS | 23930 | Values: 339.6 346.8 356.3 356.6 359.1 5210.6 5393.5 5454.9 5511.9 5553.3 5418.3 5445.9 5456.1 5482.2 5534.8 4269.8 4273.0 4280.8 4308.4 4316.3 3999.7 4158.0 4258.6 4272.1 4272.4 4223.0 4264.7 4271.5 4278.9 4287.4 4199.8 4230.5 4242.9 4247.8 4279.3 3640.7 3775.4 3807.3 3808.6 3812.0 5203.9 5205.2 5228.7 5239.7 5240.5 4863.7 4868.5 4871.0 4889.0 4905.3 5434.0 5672.5 5673.9 5682.1 5693.9 4080.7 4096.2 4106.1 4126.8 4129.2 5895.9 5915.3 5962.8 5976.9 5981.3 3920.7 4135.2 4136.2 4187.6 4201.6 4235.0 4263.5 4268.3 4271.1 4272.8 4864.0 4864.5 4870.9 4879.5 4882.8 5546.8 5698.8 5714.7 5730.4 5752.3 1393.1 1438.3 1459.2 1467.8 1474.6 929.8 944.5 948.4 955.3 969.0 4939.7 4948.7 4977.0 5056.8 5061.4 5075.4 5135.5 5210.4 5218.3 5226.3 4786.8 4945.9 4976.6 4992.3 4997.4 2755.8 2772.7 2781.3 2790.8 2792.8 5238.1 5330.4 5333.8 5403.8 5442.4 4901.0 5148.1 5186.9 5246.6 5288.7 5267.3 5333.3 5342.7 5345.3 5348.0 5155.3 5173.2 5180.1 5205.3 5241.0 5133.5 5349.5 5775.2 5796.2 5831.3 6127.4 6134.7 6142.6 6144.2 6157.8 4123.2 4218.6 4236.5 4238.3 4239.0 5639.5 5754.6 5830.8 5841.9 5848.2 5745.3 5764.7 5773.9 5791.0 5792.9 5591.9 5667.3 5710.7 5721.2 5744.6 5571.9 5580.9 5659.0 5663.5 5668.8 5733.0 5759.1 5769.0 5816.2 5838.5 5437.4 5564.3 5642.3 5648.9 5668.3 03:33:50 INFO - PROCESS | 23930 | 03:33:50 INFO - PROCESS | 23930 | [#5] yui.html Cycles:180 Average:20549.54 Median:4448.89 stddev:28653.98 (644.1%) stddev-sans-first:28695.98 03:33:50 INFO - PROCESS | 23930 | Values: 798.9 827.3 838.1 857.6 906.5 15320.8 15851.7 16332.8 16481.7 16723.5 14716.7 14768.2 14837.9 14871.4 14874.8 727.8 846.3 853.3 866.5 871.8 865.1 865.3 868.2 869.1 869.6 917.2 921.9 921.9 922.2 923.2 761.8 891.3 912.8 927.5 929.4 680.7 686.7 686.7 687.2 687.5 14430.6 14465.9 14495.3 14514.3 14529.3 13492.6 13759.5 13799.4 13943.4 14064.9 12742.5 12744.4 12766.2 12909.4 12925.5 4561.2 4593.3 4622.1 4633.9 4644.4 73239.3 74441.6 75405.9 75824.2 75865.6 11982.0 12084.6 12087.4 12115.0 12245.6 22308.3 22780.4 22810.0 22811.5 22817.3 8594.7 8749.2 9001.7 9213.4 9255.7 4336.6 4755.0 4920.7 5032.6 5080.7 3792.2 3998.2 4015.4 4021.5 4023.0 582.2 718.6 721.1 722.3 730.4 747.9 757.4 770.2 773.3 780.0 10292.9 10474.6 10562.2 10604.9 10688.2 612.2 716.7 732.9 742.3 772.0 5037.3 5071.2 5122.9 5199.5 5210.2 73790.6 74032.0 74152.0 90508.5 122847.4 101765.0 105673.0 108630.5 108677.0 108910.5 57574.7 59666.0 92993.5 95300.0 95919.0 9930.1 10018.1 10036.4 10154.9 10171.3 53445.7 54499.0 54733.5 54852.5 56830.1 70789.6 82634.0 82658.0 83298.0 83588.6 12137.6 12207.4 12399.4 12411.6 12736.8 13827.6 14288.3 14372.6 14654.6 15676.4 13822.4 13903.1 14532.2 14692.7 15164.4 13704.8 13733.0 13892.2 14243.5 14303.6 13835.6 14246.4 14406.3 15098.5 15134.2 11114.2 11193.3 11299.4 11326.1 11331.5 11703.5 11746.9 12051.9 12141.6 12143.8 03:33:50 INFO - PROCESS | 23930 | -------- Summary: end -------- 03:33:50 INFO - PROCESS | 23930 | 03:33:51 INFO - TEST-INFO | 23930: exit 0 03:33:51 INFO - TEST-OK | dromaeo_css | took 1112405ms 03:33:51 INFO - TEST-START | kraken 03:33:51 INFO - operating with platform_type : linux_ 03:33:51 INFO - Initialising browser for kraken test... 03:33:51 INFO - TEST-INFO | started process 24117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZLpno5/profile http://localhost:41229/getInfo.html) 03:33:55 INFO - PROCESS | 24117 | __metrics Screen width/height:1600/1200 03:33:55 INFO - PROCESS | 24117 | colorDepth:24 03:33:55 INFO - PROCESS | 24117 | Browser inner width/height: 1024/697 03:33:55 INFO - PROCESS | 24117 | __metrics 03:33:55 INFO - PROCESS | 24117 | 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" 03:33:55 ERROR - PROCESS | 24117 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:33:56 INFO - TEST-INFO | 24117: exit 0 03:33:56 INFO - Browser initialized. 03:33:56 INFO - Running cycle 1/1 for kraken test... 03:33:56 INFO - TEST-INFO | started process 24213 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZLpno5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:34:08 INFO - PROCESS | 24213 | RSS: Main: 142962688 03:34:08 INFO - PROCESS | 24213 | 03:34:10 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41229/tests/kraken/driver.html?testName=audio-beat-detection) 03:34:11 INFO - PROCESS | 24213 | RSS: Main: 192716800 03:34:11 INFO - PROCESS | 24213 | 03:34:14 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41229/tests/kraken/driver.html?testName=audio-dft) 03:34:15 INFO - PROCESS | 24213 | RSS: Main: 192532480 03:34:15 INFO - PROCESS | 24213 | 03:34:17 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41229/tests/kraken/driver.html?testName=audio-fft) 03:34:18 INFO - PROCESS | 24213 | RSS: Main: 277995520 03:34:18 INFO - PROCESS | 24213 | 03:34:20 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41229/tests/kraken/driver.html?testName=audio-oscillator) 03:34:21 INFO - PROCESS | 24213 | RSS: Main: 186187776 03:34:21 INFO - PROCESS | 24213 | 03:34:23 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:34:23 INFO - PROCESS | 24213 | RSS: Main: 186187776 03:34:23 INFO - PROCESS | 24213 | 03:34:26 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41229/tests/kraken/driver.html?testName=imaging-darkroom) 03:34:27 INFO - PROCESS | 24213 | RSS: Main: 220491776 03:34:27 INFO - PROCESS | 24213 | 03:34:30 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41229/tests/kraken/driver.html?testName=imaging-desaturate) 03:34:31 INFO - PROCESS | 24213 | RSS: Main: 221335552 03:34:31 INFO - PROCESS | 24213 | 03:34:34 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41229/tests/kraken/driver.html?testName=json-parse-financial) 03:34:34 INFO - PROCESS | 24213 | RSS: Main: 220688384 03:34:34 INFO - PROCESS | 24213 | 03:34:36 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:34:36 INFO - PROCESS | 24213 | RSS: Main: 199606272 03:34:36 INFO - PROCESS | 24213 | 03:34:38 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:34:39 INFO - PROCESS | 24213 | RSS: Main: 202199040 03:34:39 INFO - PROCESS | 24213 | 03:34:41 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:34:41 INFO - PROCESS | 24213 | RSS: Main: 212135936 03:34:41 INFO - PROCESS | 24213 | 03:34:44 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:34:44 INFO - PROCESS | 24213 | RSS: Main: 214044672 03:34:44 INFO - PROCESS | 24213 | 03:34:47 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:34:48 INFO - PROCESS | 24213 | RSS: Main: 215568384 03:34:48 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | Cycle 1(1): loaded http://localhost:41229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41229/tests/kraken/driver.html?testName=ai-astar) 03:34:49 INFO - PROCESS | 24213 | RSS: Main: 258301952 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | __start_tp_report 03:34:49 INFO - PROCESS | 24213 | _x_x_mozilla_page_load 03:34:49 INFO - PROCESS | 24213 | _x_x_mozilla_page_load_details 03:34:49 INFO - PROCESS | 24213 | |i|pagename|runs| 03:34:49 INFO - PROCESS | 24213 | |0;ai-astar;113;104;109;108;111;110;108;109;108;109 03:34:49 INFO - PROCESS | 24213 | |1;audio-beat-detection;149;123;130;177;126;130;149;130;160;130 03:34:49 INFO - PROCESS | 24213 | |2;audio-dft;162;161;160;159;159;160;159;159;160;159 03:34:49 INFO - PROCESS | 24213 | |3;audio-fft;73;74;75;106;74;75;75;77;108;75 03:34:49 INFO - PROCESS | 24213 | |4;audio-oscillator;83;84;116;83;84;85;119;84;84;129 03:34:49 INFO - PROCESS | 24213 | |5;imaging-gaussian-blur;116;117;117;118;117;117;116;116;116;117 03:34:49 INFO - PROCESS | 24213 | |6;imaging-darkroom;145;145;146;147;146;146;146;147;145;146 03:34:49 INFO - PROCESS | 24213 | |7;imaging-desaturate;94;95;95;94;94;95;95;95;95;95 03:34:49 INFO - PROCESS | 24213 | |8;json-parse-financial;49;57;51;51;50;52;51;52;54;52 03:34:49 INFO - PROCESS | 24213 | |9;json-stringify-tinderbox;59;59;59;58;59;59;60;59;59;59 03:34:49 INFO - PROCESS | 24213 | |10;stanford-crypto-aes;81;76;74;75;75;84;80;80;102;78 03:34:49 INFO - PROCESS | 24213 | |11;stanford-crypto-ccm;124;117;120;116;116;117;119;116;122;128 03:34:49 INFO - PROCESS | 24213 | |12;stanford-crypto-pbkdf2;158;154;163;154;154;155;154;156;159;156 03:34:49 INFO - PROCESS | 24213 | |13;stanford-crypto-sha256-iterative;61;64;61;61;62;62;60;61;60;60 03:34:49 INFO - PROCESS | 24213 | __end_tp_report 03:34:49 INFO - PROCESS | 24213 | __start_cc_report 03:34:49 INFO - PROCESS | 24213 | _x_x_mozilla_cycle_collect,1564 03:34:49 INFO - PROCESS | 24213 | __end_cc_report 03:34:49 INFO - PROCESS | 24213 | __startTimestamp1470047689914__endTimestamp 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | ------- Summary: start ------- 03:34:49 INFO - PROCESS | 24213 | Number of tests: 14 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#0] ai-astar Cycles:10 Average:108.90 Median:109.00 stddev:2.33 (2.1%) stddev-sans-first:1.94 03:34:49 INFO - PROCESS | 24213 | Values: 113.0 104.0 109.0 108.0 111.0 110.0 108.0 109.0 108.0 109.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:139.50 stddev:17.68 (12.7%) stddev-sans-first:18.48 03:34:49 INFO - PROCESS | 24213 | Values: 149.0 123.0 130.0 177.0 126.0 130.0 149.0 130.0 160.0 130.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.03 (0.6%) stddev-sans-first:0.73 03:34:49 INFO - PROCESS | 24213 | Values: 162.0 161.0 160.0 159.0 159.0 160.0 159.0 159.0 160.0 159.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:13.64 (18.2%) stddev-sans-first:14.15 03:34:49 INFO - PROCESS | 24213 | Values: 73.0 74.0 75.0 106.0 74.0 75.0 75.0 77.0 108.0 75.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.00 stddev:18.39 (21.9%) stddev-sans-first:18.98 03:34:49 INFO - PROCESS | 24213 | Values: 83.0 84.0 116.0 83.0 84.0 85.0 119.0 84.0 84.0 129.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 03:34:49 INFO - PROCESS | 24213 | Values: 116.0 117.0 117.0 118.0 117.0 117.0 116.0 116.0 116.0 117.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 03:34:49 INFO - PROCESS | 24213 | Values: 145.0 145.0 146.0 147.0 146.0 146.0 146.0 147.0 145.0 146.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 03:34:49 INFO - PROCESS | 24213 | Values: 94.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:2.23 (4.3%) stddev-sans-first:2.11 03:34:49 INFO - PROCESS | 24213 | Values: 49.0 57.0 51.0 51.0 50.0 52.0 51.0 52.0 54.0 52.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 03:34:49 INFO - PROCESS | 24213 | Values: 59.0 59.0 59.0 58.0 59.0 59.0 60.0 59.0 59.0 59.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:79.00 stddev:8.20 (10.4%) stddev-sans-first:8.69 03:34:49 INFO - PROCESS | 24213 | Values: 81.0 76.0 74.0 75.0 75.0 84.0 80.0 80.0 102.0 78.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:119.50 stddev:4.06 (3.4%) stddev-sans-first:3.97 03:34:49 INFO - PROCESS | 24213 | Values: 124.0 117.0 120.0 116.0 116.0 117.0 119.0 116.0 122.0 128.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.30 Median:156.00 stddev:2.95 (1.9%) stddev-sans-first:3.06 03:34:49 INFO - PROCESS | 24213 | Values: 158.0 154.0 163.0 154.0 154.0 155.0 154.0 156.0 159.0 156.0 03:34:49 INFO - PROCESS | 24213 | 03:34:49 INFO - PROCESS | 24213 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 03:34:49 INFO - PROCESS | 24213 | Values: 61.0 64.0 61.0 61.0 62.0 62.0 60.0 61.0 60.0 60.0 03:34:49 INFO - PROCESS | 24213 | -------- Summary: end -------- 03:34:49 INFO - PROCESS | 24213 | 03:34:50 INFO - TEST-INFO | 24213: exit 0 03:34:50 INFO - TEST-OK | kraken | took 59479ms 03:34:50 INFO - SUITE-END | took 1171s 03:34:51 INFO - Completed test suite (00:19:32) 03:34:51 INFO - javascript benchmark 03:34:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.488726690996, 3419.555457790487, 3439.380911435942, 3444.3111377724463, 3466.9826650866744, 5632.57755668325, 5704.942950570493, 5711.171880077397, 5714.171445140452, 5731.770729170828, 5638.661680299185, 5676.829695109143, 5687.914681279784, 5690.971545142279, 5696.430356964302, 4954.479779623141, 5116.590672746181, 5165.0, 5184.196449550307, 5222.451642577513, 4998.700077995314, 5093.134167191568, 5130.281760553526, 5132.486751324856, 5137.820176293831, 5070.619703522232, 5129.102407078765, 5144.1254986652175, 5165.9225111623355, 5167.069927413064, 5224.686518808846, 5227.294315267428, 5237.711925844077, 5238.161894096965, 5268.657537260085, 5018.372703412074, 5081.771320290596, 5136.563392111675, 5141.0232055909255, 5151.5363617274625, 5450.972745136289, 5505.917411238835, 5512.476314750093, 5530.032244357221, 5559.221708960748, 6093.740646886307, 6097.085437184413, 6112.174856394375, 6117.632942023449, 6118.3235254481215, 6028.668423236721, 6092.9695351523405, 6102.603330783485, 6139.693015349215, 6160.199174107337, 5704.942950570483, 5747.59766816323, 5750.568707346882, 5753.424657534255, 5759.625624334447, 6004.819855404257, 6222.12890195373, 6226.439620434183, 6227.09707092469, 6232.906506402408, 5900.76396944126, 5927.436893495111, 5940.079287710412, 6029.517638588903, 6038.33578306386, 6054.547624731436, 6089.0, 6091.634501929899, 6098.512119030467, 6122.448979591769, 5840.065589505659, 5970.373110823301, 6072.240969878765, 6077.36187700294, 6098.786542471081, 6063.151158837766, 6068.787592434332, 6069.696515174223, 6079.452849243589, 6116.235470566179, 4534.387857639243, 4556.134334476439, 4561.612262957619, 4561.817527298937, 4585.12882552314, 4085.4484644572603, 4093.5087789465456, 4095.610916962884, 4117.094239267416, 4129.752214867118, 5888.205092312569, 5904.940950590439, 5906.320773111147, 5906.793262235887, 5929.703514824241, 6151.569390142647, 6161.691915404212, 6171.1668924694595, 6192.969035154795, 6198.876022479525, 5706.058500347396, 5749.8275051748515, 5780.479756821906, 5813.82558523245, 5928.051511758098, 5183.637145399861, 5184.714840683723, 5189.247559103909, 5190.792368305226, 5191.8182863599595, 6365.809025729235, 6374.872502549923, 6376.362363763679, 6376.74493020274, 6381.776637817654, 5963.821085367446, 5968.283805943314, 6042.063480160582, 6055.848603784764, 6081.604695694766, 6291.402316779899, 6307.684615769285, 6342.778002769881, 6359.332270111571, 6359.936400636119, 5766.94233057664, 5987.850303742442, 5995.160677505066, 5995.550333724901, 5999.4900433463645, 5983.072623743326, 6016.639001659914, 6045.1234570987845, 6120.785772497941, 6126.0198368262645, 5955.67243801595, 5957.821265362046, 5959.523238141025, 5960.403959604005, 5962.493188079062, 5731.254936858182, 5825.621334613236, 5868.735906884115, 5952.107183922446, 5952.8809423812145, 5806.158107074366, 5881.5588830838715, 5891.970540147272, 5892.0, 5911.201987731602, 5988.8802223956145, 5999.940000600112, 6000.609960352563, 6037.426444487766, 6044.9395506044375, 5738.340090889435, 5742.19609254713, 5792.0, 5880.500157486662, 5901.911471327848], "value": 5603.417814725812, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.649649699671, 658.5488940076049, 663.4128796015525, 664.1233571685376, 673.7608149107067, 33746.15634609136, 45077.32384014238, 45761.54238457618, 45948.540514594846, 45974.77012614932, 48496.75751621237, 48842.0, 48893.0, 48943.265851012264, 48961.265581016225, 8237.46456480327, 9088.50013249271, 9250.07499250073, 9253.074692530761, 10665.253432259746, 10519.474026298692, 10937.289076210021, 10942.781144377108, 10943.179261555415, 10948.616798412057, 10952.780944381107, 10978.505967231495, 11044.447777611127, 11049.28179668323, 11053.115750739962, 8331.916680833205, 10841.620543280947, 10883.619073332435, 11072.667819965414, 11077.833832492519, 10670.573177072907, 11058.889411105867, 11063.778724425547, 11068.501917413736, 11071.72320691989, 46559.0, 47073.76463117662, 47212.7639361801, 47294.0, 47320.52679473196, 3385.4752513360468, 3385.830708464567, 3763.379042457991, 4011.8796436106963, 4039.373897045933, 35672.0, 35999.0, 36353.45469817928, 36525.45211821825, 37819.0, 4507.318814709062, 4547.704399214041, 4601.562851529099, 4629.722216667011, 4654.301854721819, 372468.0, 374582.0, 375126.0, 376243.0, 376654.0, 39080.804595976835, 69353.30646693468, 70518.64740676264, 70520.0, 70584.0, 39020.0, 41119.7944010278, 41165.176696465904, 41167.588324116376, 41190.1761964765, 13918.860811391958, 14061.0, 14064.648383790487, 14068.08557443763, 14073.859261407255, 11274.379909104913, 12116.454759535842, 12372.072094592762, 12415.255084694947, 12608.873911260953, 7071.823204419828, 7189.0, 7223.133224013152, 7291.70833166678, 7331.193568707438, 3541.911452213715, 3615.240799432096, 3615.6022837487853, 3660.2679464106754, 3683.889483315505, 1089.841972913923, 1160.7562411893427, 1190.6070996571111, 1195.1514424758518, 1201.6575276046285, 33039.50440743391, 34055.489167662505, 35123.0, 36906.815465923035, 36919.815400922824, 1115.6597237842373, 1148.9253198542067, 1178.2871362825538, 1185.958491452795, 1189.4944648524342, 16072.196390180445, 16404.425845095604, 16422.260998255108, 16648.916755416387, 16656.833431665527, 603185.0, 607588.0, 608857.0, 612919.0, 615147.0, 63565.68217158884, 63975.36024639787, 64046.35953640497, 64132.35867641357, 64136.35863641304, 1318.736252749454, 1321.458202137119, 1447.957470621151, 1459.5329494561638, 1495.5662857771124, 33508.664913350556, 34489.310213795085, 34664.0, 34796.304073917876, 35015.299694006484, 54145.0, 54615.45384546262, 54812.177817331976, 54872.725636371564, 54899.0, 464379.0, 466182.6690866524, 466579.0, 467399.0, 468412.0, 27290.86354568294, 29540.704592953796, 30016.84991575028, 30281.394372112874, 31806.0, 49343.506564933894, 50330.496695034046, 50995.0, 51377.229341559156, 51381.22928156155, 43092.56907431011, 43492.56507435012, 43632.34551481667, 43638.34542481802, 43930.0, 42828.57171428246, 43216.78391608148, 43545.0, 43859.0, 44089.338659920766, 42730.359044614976, 43657.781711091244, 43659.78170109129, 43888.561114389726, 43970.78014609907, 36575.817120914224, 37503.81248093742, 38168.80915595404, 38637.0, 38877.22245555017, 29087.272818179714, 29146.41707165887, 29269.451593743477, 29303.706962930097, 29355.0], "value": 20308.935063587716, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.4807418615451, 412.7152264937195, 425.627575871113, 427.52117628256354, 434.96085352318283, 18224.179911903964, 19739.21043158274, 19877.503062423446, 19967.10148043338, 20509.897450512763, 18156.63686726265, 18277.086145692727, 18351.082445877688, 18397.356092536764, 18480.815191848076, 5454.263674403955, 5825.038868586678, 5866.296044474648, 5873.0603103503445, 5875.118732190184, 4240.469941257343, 5679.630823996447, 5787.594868359217, 6153.261608606974, 6469.385408386196, 6518.152640156773, 6549.377809108151, 6553.279139294674, 6558.803235902907, 6563.638999855006, 6431.742730290783, 6461.224653041641, 6528.902066469007, 6529.0, 6531.118299029641, 3825.674817640504, 5460.535854452376, 5774.769009239625, 5780.537556995472, 5829.795957141479, 23448.0, 23515.41211469727, 23611.0, 23815.285541433786, 23905.880470597534, 2381.8094552435764, 2432.270318904339, 2751.17464760571, 2979.2998645318335, 3002.324476992659, 14162.504312349121, 14558.417663293456, 14598.416063357454, 14862.777058344134, 15049.473268435551, 2012.7886571909885, 2016.4353980885376, 2058.3927741316197, 2088.049937278535, 2118.4386137673373, 76067.99999999945, 77981.61009194974, 78550.0, 80302.0, 83963.16036839614, 8827.779305517413, 9408.294377921684, 9613.423194608346, 10010.14913732341, 10351.844722329171, 13771.655708607164, 13781.862181378057, 13825.654358640913, 13893.722125557635, 13911.513097041556, 7627.0, 7821.960890195513, 7888.921110788819, 7976.361891048702, 7986.0, 7718.343940765098, 7775.183605721431, 7776.688932442752, 7819.530828150329, 7873.921260787319, 4190.559991200941, 4232.767197804089, 4242.596953289432, 4302.44068271129, 4315.050688848449, 1813.6554054729559, 1884.2368840619572, 2013.3357289732546, 2058.651583212968, 2083.9791602083783, 952.6713283917038, 972.7227740094181, 1045.0, 1085.4464223246202, 1106.4965440724452, 18070.277188912558, 19058.809411905702, 19146.71279930802, 19561.510962225773, 19871.40385788429, 1032.9535170917327, 1041.770810421706, 1043.7495001199657, 1056.8784589772122, 1074.6883403812963, 10741.731456713645, 10802.89197108019, 10953.726156846142, 11006.559737610565, 11068.612598559173, 466386.0, 489502.0, 493650.0, 498008.0, 499162.0, 89478.0, 94464.0, 94614.0, 94679.0, 96103.0, 1066.6746642274181, 1073.382804887193, 1074.9247552671395, 1080.173667144635, 1093.1746531368924, 17321.74017389715, 17668.558286042953, 17784.0, 17940.461786146436, 18111.0, 38589.0, 38873.0, 39842.402363963985, 40827.7958610205, 43089.56910430981, 56687.71656141693, 57411.42588574061, 57680.0, 60407.395926041936, 60987.0, 8346.081930987708, 8417.5791210438, 8652.134786521297, 8657.783555411164, 8789.516576588094, 8441.0, 9554.570044347882, 12809.871901280869, 12887.0, 12943.870561294267, 11527.193096483166, 11816.828666473546, 11902.464389102684, 11943.820842687193, 12070.215435996632, 10923.180761442765, 11200.215984881306, 11522.769544608893, 11569.826452603049, 11693.532258709896, 11476.254043487146, 12378.44297006655, 12436.253824770543, 12453.564125255934, 12544.937275313565, 8561.1866872647, 8581.35639827003, 8747.16901894319, 8764.298856091369, 8985.101489850962, 11576.36330001858, 12079.51681932748, 12141.150119491904, 12173.45219465144, 12253.75492490163], "value": 9366.763200134572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.3992183556484, 1517.9468718594849, 1558.2286768049814, 1720.3032771727455, 1864.375434229534, 9120.498372589505, 9545.904540954589, 9570.47362395068, 9831.0, 9875.901240987589, 9574.090461406155, 9753.561089750961, 9791.559379827908, 9792.265580081505, 9794.804103917917, 2093.4033800366897, 2158.622241107808, 2164.5454454564483, 2391.4619210677633, 2438.622013587888, 2287.9885600571974, 2389.8566086034893, 2391.342380845264, 2401.8799060047013, 2407.458321877581, 2408.217329367954, 2444.4622183119686, 2445.205308274809, 2466.482038771851, 2469.715982661999, 2066.5350296183387, 2144.4531644430726, 2371.5019845832394, 2402.843815152018, 2404.879756012201, 2103.3269353806786, 2294.564032833773, 2361.7165940087298, 2365.9053637854463, 2388.5461762265113, 9478.90521094787, 9530.714078577654, 9547.904520954771, 9552.331336806426, 9554.092361225672, 5278.47215278473, 5393.568514518829, 5399.19012148177, 5400.216968539579, 5402.648827826218, 3506.36885360635, 3553.8045407502577, 3629.0927268182954, 3630.419132938717, 3657.871974480907, 1130.9943450282778, 1134.466800603715, 1149.7585507043532, 1207.9698007549794, 1212.3150420012664, 14006.29968501581, 14009.649758756012, 14021.369038393297, 14026.088304260293, 14058.859411405858, 4868.488808675038, 4868.732219727878, 4880.145974454456, 5036.244563315532, 5065.924011139836, 11226.775464490664, 11227.382493962747, 11251.156163287786, 11280.435978201222, 11301.660950171508, 5511.58663100269, 5567.638103523258, 5568.1091025435735, 5574.108142697149, 5577.776888924479, 4332.891677708019, 5005.524475174852, 5094.566961808214, 5147.819826306061, 5163.948360516422, 4305.117450922553, 4345.261305578059, 4383.189110014658, 4385.166818304512, 4425.845095421645, 1295.184034058537, 1333.9066265361525, 1334.8665133486575, 1340.544214966916, 1344.388303321986, 1070.1385384765338, 1072.3780207479642, 1093.276350444771, 1110.3282514078867, 1118.9873164785881, 6206.782762603378, 6437.967810160919, 6473.967630161819, 6508.3491650835485, 6561.0814485972005, 459.1528629678238, 464.2340138771055, 464.85589467265254, 469.17893686049416, 473.3302377136382, 4496.659298080054, 4503.482099558527, 4505.098980203972, 4511.120331535319, 4563.269876819693, 12059.15585908978, 12386.380680965916, 12446.377681115908, 12486.562970295996, 12489.875101249054, 12249.203801752858, 12309.876901231051, 12355.444005019795, 12361.876381236252, 12412.62762117138, 8508.957455212685, 8911.866322005175, 8935.687250946186, 9111.27109831212, 9144.725658230263, 6493.415592596689, 6816.625085620339, 6848.897266541106, 6912.516123871281, 6926.099607051051, 11157.609483668033, 11433.656990290305, 11435.771284574428, 11495.310281383145, 11574.826377604511, 11390.430478476208, 11558.42207889619, 11567.76864462723, 11694.590689325832, 11712.297262164297, 7908.051033875971, 8211.0, 8212.13772553897, 8840.734777956672, 9026.0, 9104.544772761468, 9362.110599493037, 9415.623375065195, 9702.951485242529, 9703.0, 8236.176382361717, 8437.873431898404, 8459.83080338402, 8544.829103418022, 8545.529995850287, 8224.465409748347, 8311.6675332986, 8316.293115085286, 8380.58097095155, 8462.492250464991, 7591.127020392505, 7664.501807382502, 7672.386209103147, 7695.769126926201, 7700.268474494914, 5341.065313570032, 5395.649282796762, 5502.312210973629, 5533.474319939599, 5570.359408668055, 5409.675419474844, 5411.972940135274, 5421.539169170507, 5516.089845175662, 5712.571557133148], "value": 4888.6873896911275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.5619650650661, 346.7590024932672, 356.33543441481635, 356.613074813827, 359.1165732298547, 5210.55710264627, 5393.487618676229, 5454.890902181959, 5511.944880551193, 5553.305836770403, 5418.2956215692, 5445.945540544593, 5456.127019676853, 5482.205080263359, 5534.778608855641, 4269.765162916031, 4272.978635106821, 4280.807363668627, 4308.375285583604, 4316.309390497521, 3999.740016898907, 4158.002079500913, 4258.594663760951, 4272.145570885819, 4272.380504826799, 4223.049813791888, 4264.744115353089, 4271.508776490697, 4278.908878236061, 4287.421198138242, 4199.790010499493, 4230.534641189467, 4242.8785607196405, 4247.768147237298, 4279.272523670983, 3640.690541303992, 3775.3581891078584, 3807.276617442677, 3808.5810560838286, 3812.0088776917923, 5203.895922081575, 5205.167173252262, 5228.738563071869, 5239.659422137549, 5240.475952404767, 4863.732494712789, 4868.537488938545, 4870.951290487121, 4888.951110488921, 4905.288733133676, 5433.972830135824, 5672.461116193955, 5673.8581535461535, 5682.062459694145, 5693.886122277531, 4080.7349721586106, 4096.201240758053, 4106.096658735073, 4126.793660316972, 4129.194807012634, 5895.911561326584, 5915.349311575723, 5962.791302304442, 5976.910346344852, 5981.252343457068, 3920.725549211585, 4135.193637240769, 4136.21411931732, 4187.644050255702, 4201.579842015774, 4234.9576504234565, 4263.467066616673, 4268.317069268902, 4271.145770845843, 4272.829086836554, 4863.9785645014945, 4864.489228631013, 4870.9269360959615, 4879.463259041502, 4882.829100981448, 5546.805861794818, 5698.8290351289525, 5714.685692286964, 5730.36965933747, 5752.309722833287, 1393.143216921586, 1438.3239877257672, 1459.2484870291808, 1467.8458761830068, 1474.6387135151956, 929.8423462788871, 944.4616568555995, 948.374073111743, 955.3074021334616, 968.9854652180223, 4939.6542242042715, 4948.678335908155, 4976.975115124401, 5056.82301119459, 5061.417936937226, 5075.365579302587, 5135.537801597874, 5210.374755029421, 5218.269442278084, 5226.346706661668, 4786.7845946932475, 4945.9258111128365, 4976.552110310089, 4992.2761199626575, 4997.400311962588, 2755.8484283364605, 2772.6534183227095, 2781.332480204777, 2790.8325500470037, 2792.8324300541626, 5238.140460136673, 5330.387005494418, 5333.759980800874, 5403.810866619728, 5442.401335853053, 4901.0, 5148.099082660594, 5186.92219616706, 5246.580273578181, 5288.65623734456, 5267.288915996368, 5333.280007198979, 5342.732863356817, 5345.305110335631, 5348.010618035676, 5155.2782610433815, 5173.163526948029, 5180.119379705384, 5205.297284866646, 5241.0042092002395, 5133.486651334837, 5349.545288650353, 5775.220345253337, 5796.21751063601, 5831.271091113611, 6127.417895299939, 6134.693265336626, 6142.570020098729, 6144.17053697763, 6157.84605384869, 4123.154753275511, 4218.599233072853, 4236.491621005499, 4238.25830479671, 4239.0, 5639.54883609302, 5754.56840736938, 5830.82507524775, 5841.853953651193, 5848.239728835224, 5745.2818397700285, 5764.711764411846, 5773.884522309614, 5790.971045144747, 5792.942070579409, 5591.860203494945, 5667.3482549507335, 5710.685912274865, 5721.22763426932, 5744.597878148489, 5571.860703482445, 5580.888382232251, 5659.037963546125, 5663.461971112738, 5668.829935101953, 5732.971335143297, 5759.136129580596, 5768.971155144198, 5816.214811000461, 5838.503727183237, 5437.4018857926385, 5564.332280126248, 5642.322921249476, 5648.88702225945, 5668.291463567054], "value": 4246.0331204689965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.8681867491864, 827.255470076931, 838.1241602525361, 857.575500127437, 906.5104843384574, 15320.770188447168, 15851.682966340695, 16332.83667163328, 16481.67036659266, 16723.498295051137, 14716.705665886675, 14768.187749673763, 14837.92581037096, 14871.405143794262, 14874.776878346807, 727.8216836874973, 846.2975730143996, 853.2789792625238, 866.536403024382, 871.7515508080198, 865.1478293880523, 865.26020252684, 868.1708967935606, 869.0874581689233, 869.5652173913044, 917.156216281023, 921.9078092190795, 921.9170274675278, 922.2437601167046, 923.1968187676731, 761.8323968726871, 891.3315013739663, 912.8448163812168, 927.5037854747745, 929.4469790474657, 680.6732768271257, 686.6772616870063, 686.6909890549273, 687.1856849633201, 687.5049964025943, 14430.567082987527, 14465.855341446557, 14495.347709353104, 14514.346854391577, 14529.273536323248, 13492.595222143253, 13759.518416855362, 13799.448022079105, 13943.442262309596, 14064.929675351661, 12742.490300387974, 12744.426500807485, 12766.17019893704, 12909.354532273443, 12925.482980680761, 4561.156186105581, 4593.28804035375, 4622.121796858586, 4633.86098417048, 4644.373009643722, 73239.26760732378, 74441.62779186123, 75405.86891196636, 75824.24175758228, 75865.62067189685, 11982.0, 12084.57703980374, 12087.395630218454, 12115.0, 12245.632631021084, 22308.330750077523, 22780.4304892379, 22810.0, 22811.543769124855, 22817.31548053561, 8594.699185528601, 8749.16882896124, 9001.684941027033, 9213.401128926598, 9255.722328330161, 4336.6313863321975, 4754.952450475451, 4920.6555541111775, 5032.597392208614, 5080.695158290514, 3792.2036372361563, 3998.200359928026, 4015.437838702584, 4021.4571032910767, 4023.0, 582.1587805620921, 718.6442710858054, 721.1130309719075, 722.2957616324042, 730.4156674660356, 747.8541684371495, 757.443279189795, 770.1759117744073, 773.2576726342774, 779.9922000779919, 10292.897071029194, 10474.633387831389, 10562.20783441245, 10604.893951060545, 10688.198385121146, 612.2010775937445, 716.6631683108931, 732.8717474442058, 742.3207764895061, 771.9614019299013, 5037.345145131109, 5071.239314102914, 5122.897542049138, 5199.532042116228, 5210.192420174803, 73790.63104684443, 74032.0, 74152.0, 90508.54745726229, 122847.3857630724, 101765.0, 105673.0, 108630.45684771525, 108677.0, 108910.45544772226, 57574.7121264391, 59666.0, 92993.5350323244, 95300.0, 95919.0, 9930.106290433896, 10018.098371146632, 10036.397816131055, 10154.949225253826, 10171.28800983924, 53445.7327713359, 54499.0, 54733.45266547283, 54852.451475486334, 56830.147547787645, 70789.64605177147, 82634.0, 82658.0, 83298.0, 83588.58205708933, 12137.635870923887, 12207.389630518615, 12399.442025108712, 12411.606561613286, 12736.808947865604, 13827.585172444842, 14288.285585720465, 14372.568822935329, 14654.56036318912, 15676.372945082068, 13822.447102116204, 13903.09629874055, 14532.200728960008, 14692.706145877237, 15164.393424262906, 13704.794428083786, 13733.0, 13892.16647001183, 14243.501477448239, 14303.64240893986, 13835.584932452042, 14246.430142794587, 14406.279686015867, 15098.54704358871, 15134.243287835345, 11114.22200445961, 11193.328400296008, 11299.3785341807, 11326.093912487146, 11331.490082946451, 11703.531858725897, 11746.941265293619, 12051.879481205076, 12141.57504487374, 12143.81784273219], "value": 7853.941290673074, "unit": "score"}], "name": "dromaeo_css", "value": 7470.321727340585, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 109.0, 108.0, 111.0, 110.0, 108.0, 109.0, 108.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 123.0, 130.0, 177.0, 126.0, 130.0, 149.0, 130.0, 160.0, 130.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 159.0, 159.0, 160.0, 159.0, 159.0, 160.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 106.0, 74.0, 75.0, 75.0, 77.0, 108.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 116.0, 83.0, 84.0, 85.0, 119.0, 84.0, 84.0, 129.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 57.0, 51.0, 51.0, 50.0, 52.0, 51.0, 52.0, 54.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 74.0, 75.0, 75.0, 84.0, 80.0, 80.0, 102.0, 78.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 117.0, 120.0, 116.0, 116.0, 117.0, 119.0, 116.0, 122.0, 128.0], "value": 119.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 163.0, 154.0, 154.0, 155.0, 154.0, 156.0, 159.0, 156.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 61.0, 61.0, 62.0, 62.0, 60.0, 61.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1471.1, "alertThreshold": 2.0}]} 03:34:51 INFO - Return code: 0 03:34:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:34:51 ERROR - # TBPL SUCCESS # 03:34:51 INFO - Running post-action listener: _resource_record_post_action 03:34:51 INFO - ##### 03:34:51 INFO - ##### Finished run-tests step (success) 03:34:51 INFO - ##### 03:34:51 INFO - Running post-run listener: _resource_record_post_run 03:34:51 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 290816; Write bytes: 496611328; Read time: 40; Write time: 442948 03:34:51 INFO - TinderboxPrint: CPU usage
13.1% 03:34:51 INFO - TinderboxPrint: I/O read bytes / time
290,816 / 40 03:34:51 INFO - TinderboxPrint: I/O write bytes / time
496,611,328 / 442,948 03:34:51 INFO - TinderboxPrint: CPU idle
8,245.2 (86.9%) 03:34:51 INFO - TinderboxPrint: CPU user
1,201.0 (12.7%) 03:34:51 INFO - TinderboxPrint: Swap in / out
0 / 0 03:34:51 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 242503680; Read time: 0; Write time: 285008 03:34:51 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 290816; Write bytes: 254107648; Read time: 40; Write time: 157940 03:34:51 INFO - Running post-run listener: _upload_blobber_files 03:34:51 INFO - Blob upload gear active. 03:34:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:34:51 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 03:34:51 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'] 03:34:51 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 03:34:52 INFO - (blobuploader) - INFO - Open directory for files ... 03:34:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:34:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:34:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:52 INFO - (blobuploader) - INFO - Done attempting. 03:34:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:34:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:57 INFO - (blobuploader) - INFO - Done attempting. 03:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:34:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:58 INFO - (blobuploader) - INFO - Done attempting. 03:34:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:34:58 INFO - Return code: 0 03:34:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:34:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:34:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17bb7cc0d25c000259d8903463a03e48f84220dfa71311f53f946b45eb1708aef49bffb77974de5f12fc16350dcfa782d631d5c769a7373702a08d16e4650e14", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47a8f536b815ed5868b5c0b9e45c2c28a0ba9c2c342a0d3b792ff4e7e5eb319933f91fabc06d6efc4417b4ea6f65c3a6bec0478b0ed317f2e6cec4c4951e9cfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/662b4c4ad9ec3e1598e2f28cf6b015ee8e08e7983df5d83be1945a2862f10efad4badd9c3734e403d01439d6fb1b06ff5d85367b416e64bac8e19a5ff06fb563"} 03:34:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:34:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:34:58 INFO - Contents: 03:34:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17bb7cc0d25c000259d8903463a03e48f84220dfa71311f53f946b45eb1708aef49bffb77974de5f12fc16350dcfa782d631d5c769a7373702a08d16e4650e14", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47a8f536b815ed5868b5c0b9e45c2c28a0ba9c2c342a0d3b792ff4e7e5eb319933f91fabc06d6efc4417b4ea6f65c3a6bec0478b0ed317f2e6cec4c4951e9cfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/662b4c4ad9ec3e1598e2f28cf6b015ee8e08e7983df5d83be1945a2862f10efad4badd9c3734e403d01439d6fb1b06ff5d85367b416e64bac8e19a5ff06fb563"} 03:34:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:34:58 INFO - Copying logs to upload dir... 03:34:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:34:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.275307 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-01 03:34:56.766199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 03:34:56.770397) ========= 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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 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/17bb7cc0d25c000259d8903463a03e48f84220dfa71311f53f946b45eb1708aef49bffb77974de5f12fc16350dcfa782d631d5c769a7373702a08d16e4650e14", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47a8f536b815ed5868b5c0b9e45c2c28a0ba9c2c342a0d3b792ff4e7e5eb319933f91fabc06d6efc4417b4ea6f65c3a6bec0478b0ed317f2e6cec4c4951e9cfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/662b4c4ad9ec3e1598e2f28cf6b015ee8e08e7983df5d83be1945a2862f10efad4badd9c3734e403d01439d6fb1b06ff5d85367b416e64bac8e19a5ff06fb563"} build_url:https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/W3ymngBqSj6COe9gLCiEWw/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/17bb7cc0d25c000259d8903463a03e48f84220dfa71311f53f946b45eb1708aef49bffb77974de5f12fc16350dcfa782d631d5c769a7373702a08d16e4650e14", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47a8f536b815ed5868b5c0b9e45c2c28a0ba9c2c342a0d3b792ff4e7e5eb319933f91fabc06d6efc4417b4ea6f65c3a6bec0478b0ed317f2e6cec4c4951e9cfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/662b4c4ad9ec3e1598e2f28cf6b015ee8e08e7983df5d83be1945a2862f10efad4badd9c3734e403d01439d6fb1b06ff5d85367b416e64bac8e19a5ff06fb563"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 03:34:56.798695) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:34:56.799040) ========= 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=826874ccc97bd7bf541b4875000001d5-1470042643.773473-95224998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:34:56.884786) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 03:34:56.885473) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 03:34:56.886133) ========= ========= Total master_lag: 0.20 =========