builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1465648704.01 results: success (0) buildid: 20160611030214 builduid: d47660f1fbdf4b8ab08b01a16754fd6a revision: b6f7d0eb61b1878d3d906bd231edf225463ece3f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.014185) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.014636) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.014948) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.043762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.044064) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-11 05:38:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.6M=0s 2016-06-11 05:38:24 (40.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113233 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.171518) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.171840) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016014 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.223260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.223670) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b6f7d0eb61b1878d3d906bd231edf225463ece3f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b6f7d0eb61b1878d3d906bd231edf225463ece3f --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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-11 05:38:24,324 truncating revision to first 12 chars 2016-06-11 05:38:24,324 Setting DEBUG logging. 2016-06-11 05:38:24,324 attempt 1/10 2016-06-11 05:38:24,324 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b6f7d0eb61b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-11 05:38:24,632 unpacking tar archive at: mozilla-central-b6f7d0eb61b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655034 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.892591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.892969) ========= script_repo_revision: b6f7d0eb61b1878d3d906bd231edf225463ece3f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.893494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.893901) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-11 05:38:24.904595) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-11 05:38:24.904900) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:38:25 INFO - MultiFileLogger online at 20160611 05:38:25 in /builds/slave/test 05:38:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:38:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:38:25 INFO - {'append_to_log': False, 05:38:25 INFO - 'base_work_dir': '/builds/slave/test', 05:38:25 INFO - 'blob_upload_branch': 'Firefox', 05:38:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:38:25 INFO - 'branch': 'Firefox', 05:38:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:38:25 INFO - 'config_files': ('talos/linux_config.py',), 05:38:25 INFO - 'default_actions': ('clobber', 05:38:25 INFO - 'read-buildbot-config', 05:38:25 INFO - 'download-and-extract', 05:38:25 INFO - 'populate-webroot', 05:38:25 INFO - 'create-virtualenv', 05:38:25 INFO - 'install', 05:38:25 INFO - 'run-tests'), 05:38:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:38:25 INFO - 'download_minidump_stackwalk': True, 05:38:25 INFO - 'download_symbols': 'ondemand', 05:38:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:38:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:38:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:38:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:38:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:38:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:38:25 INFO - 'installer_path': 'installer.exe', 05:38:25 INFO - 'log_level': 'info', 05:38:25 INFO - 'log_name': 'talos', 05:38:25 INFO - 'log_to_console': True, 05:38:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:38:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:38:25 INFO - 'opt_config_files': (), 05:38:25 INFO - 'pip_index': False, 05:38:25 INFO - 'sps_profile': False, 05:38:25 INFO - 'sps_profile_interval': 0, 05:38:25 INFO - 'suite': 'dromaeojs', 05:38:25 INFO - 'system_bits': '32', 05:38:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:38:25 INFO - 'title': 'talos-linux64-ix-042', 05:38:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:38:25 INFO - 'use_talos_json': True, 05:38:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:38:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:38:25 INFO - 'work_dir': 'build'} 05:38:25 INFO - ##### 05:38:25 INFO - ##### Running clobber step. 05:38:25 INFO - ##### 05:38:25 INFO - Running pre-action listener: _resource_record_pre_action 05:38:25 INFO - Running main action method: clobber 05:38:25 INFO - rmtree: /builds/slave/test/build 05:38:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:38:25 INFO - Running post-action listener: _resource_record_post_action 05:38:25 INFO - ##### 05:38:25 INFO - ##### Finished clobber step (success) 05:38:25 INFO - ##### 05:38:25 INFO - ##### 05:38:25 INFO - ##### Running read-buildbot-config step. 05:38:25 INFO - ##### 05:38:25 INFO - Running pre-action listener: _resource_record_pre_action 05:38:25 INFO - Running main action method: read_buildbot_config 05:38:25 INFO - Using buildbot properties: 05:38:25 INFO - { 05:38:25 INFO - "project": "", 05:38:25 INFO - "product": "firefox", 05:38:25 INFO - "script_repo_revision": "production", 05:38:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:38:25 INFO - "repository": "", 05:38:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:38:25 INFO - "buildid": "20160611030214", 05:38:25 INFO - "stage_platform": "linux64-pgo", 05:38:25 INFO - "basedir": "/builds/slave/test", 05:38:25 INFO - "nightly_build": "True", 05:38:25 INFO - "buildnumber": 783, 05:38:25 INFO - "slavename": "talos-linux64-ix-042", 05:38:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:38:25 INFO - "platform": "ubuntu64_hw", 05:38:25 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:38:25 INFO - "branch": "mozilla-central", 05:38:25 INFO - "repo_path": "mozilla-central", 05:38:25 INFO - "revision": "b6f7d0eb61b1878d3d906bd231edf225463ece3f", 05:38:25 INFO - "pgo_build": "True", 05:38:25 INFO - "builduid": "d47660f1fbdf4b8ab08b01a16754fd6a", 05:38:25 INFO - "slavebuilddir": "test" 05:38:25 INFO - } 05:38:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:38:25 INFO - Running post-action listener: _resource_record_post_action 05:38:25 INFO - ##### 05:38:25 INFO - ##### Finished read-buildbot-config step (success) 05:38:25 INFO - ##### 05:38:25 INFO - ##### 05:38:25 INFO - ##### Running download-and-extract step. 05:38:25 INFO - ##### 05:38:25 INFO - Running pre-action listener: _resource_record_pre_action 05:38:25 INFO - Running main action method: download_and_extract 05:38:25 INFO - mkdir: /builds/slave/test/build/tests 05:38: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')]} 05:38:25 INFO - https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:38:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:38:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:38:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:38:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:38:35 INFO - Downloaded 1361 bytes. 05:38:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:38:35 INFO - Using the following test package requirements: 05:38:35 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:38:35 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:38:35 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:38:35 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'jsshell-linux-x86_64.zip'], 05:38:35 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:38:35 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:38:35 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:38:35 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:38:35 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:38:35 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:38:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:38:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:38: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')]} 05:38:35 INFO - https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:38:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:38:40 INFO - Downloaded 24463230 bytes. 05:38:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:38:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:38:41 INFO - Return code: 0 05:38:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:41 INFO - https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:38:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:38:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:38:43 INFO - Downloaded 24463230 bytes. 05:38:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:38:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:38:44 INFO - Return code: 0 05:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:44 INFO - https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:38:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:38:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:38:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:38:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:38:45 INFO - Downloaded 10833377 bytes. 05:38:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:38:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:38:46 INFO - Return code: 0 05:38:46 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')]} 05:38:46 INFO - https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:38:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:38:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:38:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:38:50 INFO - Downloaded 59321581 bytes. 05:38:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:38:50 INFO - mkdir: /builds/slave/test/properties 05:38:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:38:50 INFO - Writing to file /builds/slave/test/properties/build_url 05:38:50 INFO - Contents: 05:38:50 INFO - build_url:https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:38:51 INFO - Running post-action listener: _resource_record_post_action 05:38:51 INFO - Running post-action listener: set_extra_try_arguments 05:38:51 INFO - ##### 05:38:51 INFO - ##### Finished download-and-extract step (success) 05:38:51 INFO - ##### 05:38:51 INFO - ##### 05:38:51 INFO - ##### Running populate-webroot step. 05:38:51 INFO - ##### 05:38:51 INFO - Running pre-action listener: _resource_record_pre_action 05:38:51 INFO - Running main action method: populate_webroot 05:38:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:38:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:38:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:38:51 INFO - '--tppagecycles', 05:38:51 INFO - '7'], 05:38:51 INFO - 'tests': ['tsvgm']}}, 05:38:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:38:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:38:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:38:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:38:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:38:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:38:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['damp', 'tps']}, 05:38:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'tests': ['damp', 'tps']}, 05:38:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['dromaeo_dom']}, 05:38:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:38:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['a11yr', 05:38:51 INFO - 'ts_paint', 05:38:51 INFO - 'tpaint', 05:38:51 INFO - 'sessionrestore', 05:38:51 INFO - 'sessionrestore_no_auto_restore', 05:38:51 INFO - 'tabpaint']}, 05:38:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['tsvgx', 05:38:51 INFO - 'tsvgr_opacity', 05:38:51 INFO - 'tart', 05:38:51 INFO - 'tscrollx', 05:38:51 INFO - 'cart']}, 05:38:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:38:51 INFO - 'tsvgr_opacity', 05:38:51 INFO - 'tart', 05:38:51 INFO - 'tscrollx', 05:38:51 INFO - 'cart']}, 05:38:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'talos_options': ['--disable-e10s'], 05:38:51 INFO - 'tests': ['tp5o']}, 05:38:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'tests': ['tp5o']}, 05:38:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'talos_options': ['--disable-e10s', 05:38:51 INFO - '--xperf_path', 05:38:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:38:51 INFO - 'tests': ['tp5n']}, 05:38:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:51 INFO - 'talos_options': ['--xperf_path', 05:38:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:38:51 INFO - 'tests': ['tp5n']}}, 05:38:51 INFO - 'talos.zip': {'path': '', 05:38:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:38:51 INFO - Running post-action listener: _resource_record_post_action 05:38:51 INFO - ##### 05:38:51 INFO - ##### Finished populate-webroot step (success) 05:38:51 INFO - ##### 05:38:51 INFO - ##### 05:38:51 INFO - ##### Running create-virtualenv step. 05:38:51 INFO - ##### 05:38:51 INFO - Running pre-action listener: _resource_record_pre_action 05:38:51 INFO - Running main action method: create_virtualenv 05:38:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:38:51 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 05:38:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:38:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:38:51 INFO - Using real prefix '/usr' 05:38:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:38:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:38:54 INFO - Installing pip.................done. 05:38:54 INFO - Return code: 0 05:38:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:38:54 INFO - Using env: {'DISPLAY': ':0', 05:38:54 INFO - 'HOME': '/home/cltbld', 05:38:54 INFO - 'LANG': 'en_US.UTF-8', 05:38:54 INFO - 'LANGUAGE': 'en_US:en', 05:38:54 INFO - 'LOGNAME': 'cltbld', 05:38:54 INFO - 'MAIL': '/var/mail/cltbld', 05:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:54 INFO - 'MOZ_NO_REMOTE': '1', 05:38:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:54 INFO - 'NO_EM_RESTART': '1', 05:38:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:54 INFO - 'PWD': '/builds/slave/test', 05:38:54 INFO - 'SHELL': '/bin/bash', 05:38:54 INFO - 'SHLVL': '1', 05:38:54 INFO - 'TERM': 'linux', 05:38:54 INFO - 'TMOUT': '86400', 05:38:54 INFO - 'USER': 'cltbld', 05:38:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:54 INFO - '_': '/tools/buildbot/bin/python'} 05:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:54 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 05:38:54 INFO - Cleaning up... 05:38:54 INFO - Return code: 0 05:38:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:38:54 INFO - Using env: {'DISPLAY': ':0', 05:38:54 INFO - 'HOME': '/home/cltbld', 05:38:54 INFO - 'LANG': 'en_US.UTF-8', 05:38:54 INFO - 'LANGUAGE': 'en_US:en', 05:38:54 INFO - 'LOGNAME': 'cltbld', 05:38:54 INFO - 'MAIL': '/var/mail/cltbld', 05:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:54 INFO - 'MOZ_NO_REMOTE': '1', 05:38:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:54 INFO - 'NO_EM_RESTART': '1', 05:38:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:54 INFO - 'PWD': '/builds/slave/test', 05:38:54 INFO - 'SHELL': '/bin/bash', 05:38:54 INFO - 'SHLVL': '1', 05:38:54 INFO - 'TERM': 'linux', 05:38:54 INFO - 'TMOUT': '86400', 05:38:54 INFO - 'USER': 'cltbld', 05:38:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:54 INFO - '_': '/tools/buildbot/bin/python'} 05:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:54 INFO - Downloading/unpacking psutil>=3.1.1 05:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:38:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:57 INFO - Installing collected packages: psutil 05:38:57 INFO - Running setup.py install for psutil 05:38:57 INFO - building 'psutil._psutil_linux' extension 05:38:57 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 05:38:57 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 05:38:57 INFO - building 'psutil._psutil_posix' extension 05:38:57 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 05:38:57 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 05:38:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:57 INFO - Successfully installed psutil 05:38:57 INFO - Cleaning up... 05:38:58 INFO - Return code: 0 05:38:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:38: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:38: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 mozsystemmonitor==0.3 05:38:58 INFO - Using env: {'DISPLAY': ':0', 05:38:58 INFO - 'HOME': '/home/cltbld', 05:38:58 INFO - 'LANG': 'en_US.UTF-8', 05:38:58 INFO - 'LANGUAGE': 'en_US:en', 05:38:58 INFO - 'LOGNAME': 'cltbld', 05:38:58 INFO - 'MAIL': '/var/mail/cltbld', 05:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:58 INFO - 'MOZ_NO_REMOTE': '1', 05:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:58 INFO - 'NO_EM_RESTART': '1', 05:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:58 INFO - 'PWD': '/builds/slave/test', 05:38:58 INFO - 'SHELL': '/bin/bash', 05:38:58 INFO - 'SHLVL': '1', 05:38:58 INFO - 'TERM': 'linux', 05:38:58 INFO - 'TMOUT': '86400', 05:38:58 INFO - 'USER': 'cltbld', 05:38:58 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:58 INFO - '_': '/tools/buildbot/bin/python'} 05:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:39:00 INFO - Installing collected packages: mozsystemmonitor 05:39:00 INFO - Running setup.py install for mozsystemmonitor 05:39:00 INFO - Successfully installed mozsystemmonitor 05:39:00 INFO - Cleaning up... 05:39:00 INFO - Return code: 0 05:39:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:00 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:00 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 05:39:00 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 05:39:00 INFO - Using env: {'DISPLAY': ':0', 05:39:00 INFO - 'HOME': '/home/cltbld', 05:39:00 INFO - 'LANG': 'en_US.UTF-8', 05:39:00 INFO - 'LANGUAGE': 'en_US:en', 05:39:00 INFO - 'LOGNAME': 'cltbld', 05:39:00 INFO - 'MAIL': '/var/mail/cltbld', 05:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:00 INFO - 'MOZ_NO_REMOTE': '1', 05:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:00 INFO - 'NO_EM_RESTART': '1', 05:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:00 INFO - 'PWD': '/builds/slave/test', 05:39:00 INFO - 'SHELL': '/bin/bash', 05:39:00 INFO - 'SHLVL': '1', 05:39:00 INFO - 'TERM': 'linux', 05:39:00 INFO - 'TMOUT': '86400', 05:39:00 INFO - 'USER': 'cltbld', 05:39:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:00 INFO - '_': '/tools/buildbot/bin/python'} 05:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:39: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 05:39: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 05:39: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 05:39: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 05:39: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 05:39: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 05:39:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:39:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:39:02 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 05:39:02 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 05:39:02 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 05:39:02 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 05:39:02 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 05:39:02 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 05:39:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:39:02 INFO - warning: no files found matching '*.txt' 05:39:02 INFO - no previously-included directories found matching 'build' 05:39:02 INFO - no previously-included directories found matching 'dist' 05:39:02 INFO - no previously-included directories found matching '.git*' 05:39:02 INFO - Installing collected packages: jsonschema, functools32 05:39:02 INFO - Running setup.py install for functools32 05:39:02 INFO - warning: no files found matching '*.txt' 05:39:02 INFO - no previously-included directories found matching 'build' 05:39:02 INFO - no previously-included directories found matching 'dist' 05:39:02 INFO - no previously-included directories found matching '.git*' 05:39:02 INFO - Successfully installed jsonschema functools32 05:39:02 INFO - Cleaning up... 05:39:02 INFO - Return code: 0 05:39:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:02 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:02 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 05:39:02 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 05:39:02 INFO - Using env: {'DISPLAY': ':0', 05:39:02 INFO - 'HOME': '/home/cltbld', 05:39:02 INFO - 'LANG': 'en_US.UTF-8', 05:39:02 INFO - 'LANGUAGE': 'en_US:en', 05:39:02 INFO - 'LOGNAME': 'cltbld', 05:39:02 INFO - 'MAIL': '/var/mail/cltbld', 05:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:02 INFO - 'MOZ_NO_REMOTE': '1', 05:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:02 INFO - 'NO_EM_RESTART': '1', 05:39:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:02 INFO - 'PWD': '/builds/slave/test', 05:39:02 INFO - 'SHELL': '/bin/bash', 05:39:02 INFO - 'SHLVL': '1', 05:39:02 INFO - 'TERM': 'linux', 05:39:02 INFO - 'TMOUT': '86400', 05:39:02 INFO - 'USER': 'cltbld', 05:39:02 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:02 INFO - '_': '/tools/buildbot/bin/python'} 05:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:03 INFO - Downloading/unpacking blobuploader==1.2.4 05:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:39:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:39:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 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 05:39:05 INFO - Downloading docopt-0.6.1.tar.gz 05:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:39:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:39:05 INFO - Running setup.py install for blobuploader 05:39:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:39:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:39:05 INFO - Running setup.py install for requests 05:39:06 INFO - Running setup.py install for docopt 05:39:06 INFO - Successfully installed blobuploader requests docopt 05:39:06 INFO - Cleaning up... 05:39:06 INFO - Return code: 0 05:39:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:06 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:06 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 05:39:06 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 05:39:06 INFO - Using env: {'DISPLAY': ':0', 05:39:06 INFO - 'HOME': '/home/cltbld', 05:39:06 INFO - 'LANG': 'en_US.UTF-8', 05:39:06 INFO - 'LANGUAGE': 'en_US:en', 05:39:06 INFO - 'LOGNAME': 'cltbld', 05:39:06 INFO - 'MAIL': '/var/mail/cltbld', 05:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:06 INFO - 'MOZ_NO_REMOTE': '1', 05:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:06 INFO - 'NO_EM_RESTART': '1', 05:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:06 INFO - 'PWD': '/builds/slave/test', 05:39:06 INFO - 'SHELL': '/bin/bash', 05:39:06 INFO - 'SHLVL': '1', 05:39:06 INFO - 'TERM': 'linux', 05:39:06 INFO - 'TMOUT': '86400', 05:39:06 INFO - 'USER': 'cltbld', 05:39:06 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:06 INFO - '_': '/tools/buildbot/bin/python'} 05:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:39:06 INFO - Running setup.py (path:/tmp/pip-YWW_9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:39:06 INFO - Running setup.py (path:/tmp/pip-rB8IXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:39:06 INFO - Running setup.py (path:/tmp/pip-xT0QO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:39:06 INFO - Running setup.py (path:/tmp/pip-2fRNLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:39:06 INFO - Running setup.py (path:/tmp/pip-jHPHBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:39:06 INFO - Running setup.py (path:/tmp/pip-qQ5Tsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:39:06 INFO - Running setup.py (path:/tmp/pip-NuVfN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:39:07 INFO - Running setup.py (path:/tmp/pip-IDH5i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:39:07 INFO - Running setup.py (path:/tmp/pip-mTiXhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:39:07 INFO - Running setup.py (path:/tmp/pip-lFwPPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:39:07 INFO - Running setup.py (path:/tmp/pip-3TUEFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:39:07 INFO - Running setup.py (path:/tmp/pip-I8c7wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:39:07 INFO - Running setup.py (path:/tmp/pip-js6GpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:39:07 INFO - Running setup.py (path:/tmp/pip-G96MTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:07 INFO - Running setup.py (path:/tmp/pip-5l_Hsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:39:07 INFO - Running setup.py (path:/tmp/pip-4D3Ttw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:39:07 INFO - Running setup.py (path:/tmp/pip-IqVhQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:39:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:39:07 INFO - Running setup.py install for manifestparser 05:39:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:39:07 INFO - Running setup.py install for mozcrash 05:39:07 INFO - Running setup.py install for mozdebug 05:39:08 INFO - Running setup.py install for mozdevice 05:39:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for mozfile 05:39:08 INFO - Running setup.py install for mozhttpd 05:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for mozinfo 05:39:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for mozInstall 05:39:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for mozleak 05:39:08 INFO - Running setup.py install for mozlog 05:39:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for moznetwork 05:39:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:39:08 INFO - Running setup.py install for mozprocess 05:39:08 INFO - Running setup.py install for mozprofile 05:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:39:09 INFO - Running setup.py install for mozrunner 05:39:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:39:09 INFO - Running setup.py install for mozscreenshot 05:39:09 INFO - Running setup.py install for moztest 05:39:09 INFO - Running setup.py install for mozversion 05:39:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:39:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:39:09 INFO - Cleaning up... 05:39:09 INFO - Return code: 0 05:39:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:09 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:09 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 05:39:09 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 05:39:09 INFO - Using env: {'DISPLAY': ':0', 05:39:09 INFO - 'HOME': '/home/cltbld', 05:39:09 INFO - 'LANG': 'en_US.UTF-8', 05:39:09 INFO - 'LANGUAGE': 'en_US:en', 05:39:09 INFO - 'LOGNAME': 'cltbld', 05:39:09 INFO - 'MAIL': '/var/mail/cltbld', 05:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:09 INFO - 'MOZ_NO_REMOTE': '1', 05:39:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:09 INFO - 'NO_EM_RESTART': '1', 05:39:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:09 INFO - 'PWD': '/builds/slave/test', 05:39:09 INFO - 'SHELL': '/bin/bash', 05:39:09 INFO - 'SHLVL': '1', 05:39:09 INFO - 'TERM': 'linux', 05:39:09 INFO - 'TMOUT': '86400', 05:39:09 INFO - 'USER': 'cltbld', 05:39:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:09 INFO - '_': '/tools/buildbot/bin/python'} 05:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:39:09 INFO - Running setup.py (path:/tmp/pip-qzgmoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:39:09 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)) 05:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:39:09 INFO - Running setup.py (path:/tmp/pip-9reEi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:39:09 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)) 05:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:39:09 INFO - Running setup.py (path:/tmp/pip-BWEUxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:39:09 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)) 05:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:39:09 INFO - Running setup.py (path:/tmp/pip-HWfhYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:39:10 INFO - Running setup.py (path:/tmp/pip-m6zWnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:39:10 INFO - Running setup.py (path:/tmp/pip-Bf4gyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:39:10 INFO - Running setup.py (path:/tmp/pip-qnyqWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:39:10 INFO - Running setup.py (path:/tmp/pip-8YWnOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:39:10 INFO - Running setup.py (path:/tmp/pip-7na2Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:39:10 INFO - Running setup.py (path:/tmp/pip-ZX10_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:39:10 INFO - Running setup.py (path:/tmp/pip-DCvRqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:39:10 INFO - Running setup.py (path:/tmp/pip-ZZLqg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:39:10 INFO - Running setup.py (path:/tmp/pip-j6K6rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:39:10 INFO - Running setup.py (path:/tmp/pip-zo8xgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:10 INFO - Running setup.py (path:/tmp/pip-fMMLJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:39:10 INFO - Running setup.py (path:/tmp/pip-Vn0WdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:39:10 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)) 05:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:39:10 INFO - Running setup.py (path:/tmp/pip-W8Fhb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:39:10 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)) 05:39:10 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)) 05:39:10 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)) 05:39:10 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)) 05:39:10 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)) 05:39:11 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)) 05:39:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:39:12 INFO - Downloading blessings-1.6.tar.gz 05:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:39:12 INFO - Installing collected packages: blessings 05:39:12 INFO - Running setup.py install for blessings 05:39:13 INFO - Successfully installed blessings 05:39:13 INFO - Cleaning up... 05:39:13 INFO - Return code: 0 05:39:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:39:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:39:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:39:13 INFO - Reading from file tmpfile_stdout 05:39:13 INFO - Current package versions: 05:39:13 INFO - argparse == 1.2.1 05:39:13 INFO - blessings == 1.6 05:39:13 INFO - blobuploader == 1.2.4 05:39:13 INFO - docopt == 0.6.1 05:39:13 INFO - functools32 == 3.2.3-2 05:39:13 INFO - jsonschema == 2.5.1 05:39:13 INFO - manifestparser == 1.1 05:39:13 INFO - mozInstall == 1.12 05:39:13 INFO - mozcrash == 0.17 05:39:13 INFO - mozdebug == 0.1 05:39:13 INFO - mozdevice == 0.48 05:39:13 INFO - mozfile == 1.2 05:39:13 INFO - mozhttpd == 0.7 05:39:13 INFO - mozinfo == 0.9 05:39:13 INFO - mozleak == 0.1 05:39:13 INFO - mozlog == 3.2 05:39:13 INFO - moznetwork == 0.27 05:39:13 INFO - mozprocess == 0.22 05:39:13 INFO - mozprofile == 0.28 05:39:13 INFO - mozrunner == 6.11 05:39:13 INFO - mozscreenshot == 0.1 05:39:13 INFO - mozsystemmonitor == 0.3 05:39:13 INFO - moztest == 0.7 05:39:13 INFO - mozversion == 1.4 05:39:13 INFO - psutil == 3.1.1 05:39:13 INFO - requests == 1.2.3 05:39:13 INFO - wsgiref == 0.1.2 05:39:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:13 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 05:39:13 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 05:39:13 INFO - Using env: {'DISPLAY': ':0', 05:39:13 INFO - 'HOME': '/home/cltbld', 05:39:13 INFO - 'LANG': 'en_US.UTF-8', 05:39:13 INFO - 'LANGUAGE': 'en_US:en', 05:39:13 INFO - 'LOGNAME': 'cltbld', 05:39:13 INFO - 'MAIL': '/var/mail/cltbld', 05:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:13 INFO - 'MOZ_NO_REMOTE': '1', 05:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:13 INFO - 'NO_EM_RESTART': '1', 05:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:13 INFO - 'PWD': '/builds/slave/test', 05:39:13 INFO - 'SHELL': '/bin/bash', 05:39:13 INFO - 'SHLVL': '1', 05:39:13 INFO - 'TERM': 'linux', 05:39:13 INFO - 'TMOUT': '86400', 05:39:13 INFO - 'USER': 'cltbld', 05:39:13 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:13 INFO - '_': '/tools/buildbot/bin/python'} 05:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 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)) 05:39:13 INFO - Cleaning up... 05:39:13 INFO - Return code: 0 05:39:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:13 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 05:39:13 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 05:39:13 INFO - Using env: {'DISPLAY': ':0', 05:39:13 INFO - 'HOME': '/home/cltbld', 05:39:13 INFO - 'LANG': 'en_US.UTF-8', 05:39:13 INFO - 'LANGUAGE': 'en_US:en', 05:39:13 INFO - 'LOGNAME': 'cltbld', 05:39:13 INFO - 'MAIL': '/var/mail/cltbld', 05:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:13 INFO - 'MOZ_NO_REMOTE': '1', 05:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:13 INFO - 'NO_EM_RESTART': '1', 05:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:13 INFO - 'PWD': '/builds/slave/test', 05:39:13 INFO - 'SHELL': '/bin/bash', 05:39:13 INFO - 'SHLVL': '1', 05:39:13 INFO - 'TERM': 'linux', 05:39:13 INFO - 'TMOUT': '86400', 05:39:13 INFO - 'USER': 'cltbld', 05:39:13 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:13 INFO - '_': '/tools/buildbot/bin/python'} 05:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:39:13 INFO - Cleaning up... 05:39:13 INFO - Return code: 0 05:39:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:13 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 0x7f245ff3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fe190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', '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 05:39:13 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 05:39:13 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 05:39:13 INFO - Using env: {'DISPLAY': ':0', 05:39:13 INFO - 'HOME': '/home/cltbld', 05:39:13 INFO - 'LANG': 'en_US.UTF-8', 05:39:13 INFO - 'LANGUAGE': 'en_US:en', 05:39:13 INFO - 'LOGNAME': 'cltbld', 05:39:13 INFO - 'MAIL': '/var/mail/cltbld', 05:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:13 INFO - 'MOZ_NO_REMOTE': '1', 05:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:13 INFO - 'NO_EM_RESTART': '1', 05:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:13 INFO - 'PWD': '/builds/slave/test', 05:39:13 INFO - 'SHELL': '/bin/bash', 05:39:13 INFO - 'SHLVL': '1', 05:39:13 INFO - 'TERM': 'linux', 05:39:13 INFO - 'TMOUT': '86400', 05:39:13 INFO - 'USER': 'cltbld', 05:39:13 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:13 INFO - '_': '/tools/buildbot/bin/python'} 05:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:13 INFO - Downloading/unpacking flake8 05:39:13 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 05:39:13 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 05:39:13 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 05:39:14 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 05:39:14 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 05:39:14 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 05:39:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:39:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:39:15 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 05:39:15 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 05:39:15 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 05:39:15 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 05:39:15 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 05:39:15 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 05:39:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:39:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 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 05:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:39:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:39:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:39:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:39:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:39:16 INFO - no previously-included directories found matching 'docs/_build' 05:39:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:39:16 INFO - Running setup.py install for pep8 05:39:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:39:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:39:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:39:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:39:16 INFO - no previously-included directories found matching 'docs/_build' 05:39:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:39:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:39:16 INFO - Cleaning up... 05:39:16 INFO - Return code: 0 05:39:16 INFO - Running post-action listener: _resource_record_post_action 05:39:16 INFO - Running post-action listener: _start_resource_monitoring 05:39:16 INFO - Starting resource monitoring. 05:39:16 INFO - ##### 05:39:16 INFO - ##### Finished create-virtualenv step (success) 05:39:16 INFO - ##### 05:39:16 INFO - ##### 05:39:16 INFO - ##### Running install step. 05:39:16 INFO - ##### 05:39:16 INFO - Running pre-action listener: _resource_record_pre_action 05:39:16 INFO - Running main action method: install 05:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:39:17 INFO - Reading from file tmpfile_stdout 05:39:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:39:17 INFO - Reading from file tmpfile_stdout 05:39:17 INFO - Output received: 05:39:17 INFO - Usage: mozinstall [options] installer 05:39:17 INFO - Options: 05:39:17 INFO - -h, --help show this help message and exit 05:39:17 INFO - -d DEST, --destination=DEST 05:39:17 INFO - Directory to install application into. [default: 05:39:17 INFO - "/builds/slave/test"] 05:39:17 INFO - --app=APP Application being installed. [default: firefox] 05:39:17 INFO - mkdir: /builds/slave/test/build/application 05:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:39:34 INFO - Reading from file tmpfile_stdout 05:39:34 INFO - Output received: 05:39:34 INFO - /builds/slave/test/build/application/firefox/firefox 05:39:34 INFO - Running post-action listener: _resource_record_post_action 05:39:34 INFO - ##### 05:39:34 INFO - ##### Finished install step (success) 05:39:34 INFO - ##### 05:39:34 INFO - ##### 05:39:34 INFO - ##### Running run-tests step. 05:39:34 INFO - ##### 05:39:34 INFO - Running pre-action listener: _resource_record_pre_action 05:39:34 INFO - Running main action method: run_tests 05:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:39:34 INFO - Python 2.7.3 05:39:34 INFO - Return code: 0 05:39:34 INFO - grabbing minidump binary from tooltool 05:39: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')]} 05:39:34 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 0x2890110>, '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 0x27fc230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fc6c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:39:34 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 05:39:34 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 05:39:34 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 05:39:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:39:34 INFO - Return code: 0 05:39:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:39:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:39:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:39:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:39:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:39:35 INFO - Return code: 0 05:39:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 05:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 05:39:35 INFO - Using env: {'DISPLAY': ':0', 05:39:35 INFO - 'HOME': '/home/cltbld', 05:39:35 INFO - 'LANG': 'en_US.UTF-8', 05:39:35 INFO - 'LANGUAGE': 'en_US:en', 05:39:35 INFO - 'LOGNAME': 'cltbld', 05:39:35 INFO - 'MAIL': '/var/mail/cltbld', 05:39:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:39:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:35 INFO - 'MOZ_NO_REMOTE': '1', 05:39:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:39:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:35 INFO - 'NO_EM_RESTART': '1', 05:39:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:35 INFO - 'PWD': '/builds/slave/test', 05:39:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:39:35 INFO - 'SHELL': '/bin/bash', 05:39:35 INFO - 'SHLVL': '1', 05:39:35 INFO - 'TERM': 'linux', 05:39:35 INFO - 'TMOUT': '86400', 05:39:35 INFO - 'USER': 'cltbld', 05:39:35 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029', 05:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:35 INFO - '_': '/tools/buildbot/bin/python'} 05:39:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 05:39:35 INFO - mozversion application_buildid: 20160611030214 05:39:35 INFO - mozversion application_changeset: b6f7d0eb61b1878d3d906bd231edf225463ece3f 05:39:35 INFO - mozversion application_display_name: Nightly 05:39:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:39:35 INFO - mozversion application_name: Firefox 05:39:35 INFO - mozversion application_remotingname: firefox 05:39:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:39:35 INFO - mozversion application_vendor: Mozilla 05:39:35 INFO - mozversion application_version: 50.0a1 05:39:35 INFO - mozversion platform_buildid: 20160611030214 05:39:35 INFO - mozversion platform_changeset: b6f7d0eb61b1878d3d906bd231edf225463ece3f 05:39:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:39:35 INFO - mozversion platform_version: 50.0a1 05:39:35 INFO - using testdate: 1465648775 05:39:35 INFO - actual date: 1465648775 05:39:35 INFO - starting webserver on 'localhost:55546' 05:39:35 INFO - SUITE-START | Running 2 tests 05:39:35 INFO - TEST-START | dromaeo_css 05:39:35 INFO - operating with platform_type : linux_ 05:39:35 INFO - Initialising browser for dromaeo_css test... 05:39:35 INFO - TEST-INFO | started process 9130 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp19caCV/profile http://localhost:55546/getInfo.html) 05:39:39 INFO - PROCESS | 9130 | __metrics Screen width/height:1600/1200 05:39:39 INFO - PROCESS | 9130 | colorDepth:24 05:39:39 INFO - PROCESS | 9130 | Browser inner width/height: 1024/697 05:39:39 INFO - PROCESS | 9130 | __metrics 05:39:40 ERROR - PROCESS | 9130 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:39:40 INFO - PROCESS | 9130 | console.error: PushService: 05:39:40 INFO - PROCESS | 9130 | stateChangeProcessEnqueue: Error transitioning state 05:39:40 INFO - PROCESS | 9130 | UnknownError 05:39:41 INFO - TEST-INFO | 9130: exit 0 05:39:41 INFO - Browser initialized. 05:39:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:39:41 INFO - TEST-INFO | started process 9212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp19caCV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:53 INFO - PROCESS | 9212 | RSS: Main: 173809664 05:39:53 INFO - PROCESS | 9212 | 05:40:44 INFO - PROCESS | 9212 | 1465648844320 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] 05:42:53 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55546/tests/dromaeo/cssquery-ext.html) 05:42:54 INFO - PROCESS | 9212 | RSS: Main: 274210816 05:42:54 INFO - PROCESS | 9212 | 05:45:57 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-ext.html (next: http://localhost:55546/tests/dromaeo/cssquery-jquery.html) 05:45:57 INFO - PROCESS | 9212 | RSS: Main: 265281536 05:45:57 INFO - PROCESS | 9212 | 05:48:59 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55546/tests/dromaeo/cssquery-mootools.html) 05:49:00 INFO - PROCESS | 9212 | RSS: Main: 266227712 05:49:00 INFO - PROCESS | 9212 | 05:52:02 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55546/tests/dromaeo/cssquery-prototype.html) 05:52:02 INFO - PROCESS | 9212 | RSS: Main: 254414848 05:52:02 INFO - PROCESS | 9212 | 05:55:05 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55546/tests/dromaeo/cssquery-yui.html) 05:55:06 INFO - PROCESS | 9212 | RSS: Main: 242171904 05:55:06 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | Cycle 1(1): loaded http://localhost:55546/tests/dromaeo/cssquery-yui.html (next: http://localhost:55546/tests/dromaeo/cssquery-dojo.html) 05:58:08 INFO - PROCESS | 9212 | RSS: Main: 332656640 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | __start_tp_report 05:58:08 INFO - PROCESS | 9212 | _x_x_mozilla_page_load 05:58:08 INFO - PROCESS | 9212 | _x_x_mozilla_page_load_details 05:58:08 INFO - PROCESS | 9212 | |i|pagename|runs| 05:58:08 INFO - PROCESS | 9212 | |0;dojo.html;3144.654088050314;3158.7157155855984;3175.4601717707988;3191.5531825544444;3214.8231847248408;5275.577953763699;5352.652077614952;5422.75597598108;5523.1163013917785;5534.252875861753;5366.275552800371;5369.328833895763;5524.06090964536;5558.6942718150585;5593.916091258634;4772.976135119319;4867.221244600864;4887.535684110004;4897.04507621014;5101.464346243647;4990.700557966534;5000.799968001294;5037.647364684473;5043.117454445457;5056.216286475584;4749.168895443301;4836.879078023042;4846.442659094178;4976.303317535548;4981.227909674007;4835.975820120912;4847.030593881238;4910.23891296846;4917.655764096515;4957.553820156168;4753.881152971168;4854.927176092361;4874.585660218886;4885.169521181408;4911.582515486188;5430.8913821723345;5519.034169020405;5535.501804837584;5585.776568937237;5607.551395888319;5905.970470147622;5920.052791553331;5926.962781513218;5944.494717948979;5981.820545383602;5610.915836262459;5871.970640146815;5916.437938395846;5923.733431995527;5937.67342796146;5512.862178445531;5561.6663000220115;5606.6636001839615;5608.327000759894;5609.523190528769;6069.12642268515;6075.47075943388;6089.322621095033;6121.632702037803;6140.8771824563255;5420.376656684453;5601.327840659147;5700.23046888673;5857.238558987305;5873.676947767828;5802.941970580325;5814.127880817911;5863.824085277449;5896.262967129109;5920.733566989496;5775.047117225611;5801.593888427854;5802.912956305659;5803.622764520293;5820.679862607512;5566.304211973504;5722.484976352065;5746.540276777848;5782.537397008229;5790.942090579125;4416.6025057744955;4438.667099967515;4443.733375997386;4471.23988921884;4514.277715565494;3988.4216788566077;3997.8001099944886;4004.539477960072;4019.0756126091164;4038.737482063715;5375.489328513785;5426.457354264542;5433.918491222635;5478.945210547843;5485;5768.394318596571;5772.163036359704;5805.245318108703;5837.591368604242;5851.034579294454;5510.448955104457;5511.641743286674;5512.366077901012;5517.227588137664;5522.447755224526;4892.461829198785;5018.648694591344;5046.520580544842;5047.646664733506;5095.286659867622;6055.061465472859;6059.1820104286235;6061.4544690977145;6062.151298818169;6072.605280656743;5680.7443665036;5903.114532820111;5914.083317085859;5916.378780228014;5921.230240068763;5849.210356601974;5880.294364676266;5896.823095307148;5931.584789064724;5933.02105152645;5629.493345598916;5676.318841739017;5677.091665333527;5680.318361796611;5684.431556844283;5666.575006874584;5679.204911312337;5766.135079738073;5766.654000759968;5775.162601422854;5587.720613969204;5602.187682786055;5603.467670571289;5604.85987850307;5660.745266462936;5570.053090974625;5621.381648018633;5623.550115990793;5625.156226565884;5627.268455100811;5274.920876186937;5387.111126664056;5426.647267927572;5525.4474552544425;5528.44715528444;5630.943690563042;5691.715414229355;5691.943080569141;5699.829005129853;5704.172894930129;5532.585056120887;5536.52939500147;5538.141588053858;5538.196961440649;5538.972305138448 05:58:08 INFO - PROCESS | 9212 | |1;ext.html;585.0989476206641;606.7421345927979;607.2894713185573;618.5082859126991;628.1143587541567;33525.83237083814;36258.274834503296;36373;43207.783961080226;44394.77802610982;46094.07811843761;46801.29798053032;46934.76532617332;46945.53054469454;46969.295460568115;8867.068957759455;9012.68455604054;9028.954855225747;9029.187373136416;9029.503377314246;8149.225823546753;8351.08138104811;8352.24829765321;8622.956885215564;8847.95576022119;10631.255812093157;10879.292845965027;10885.564577416893;11317.603883864067;11319.660410187707;10795.78408431831;10867.510962006729;10867.565297388095;10867.673969780919;10873.130149588014;8155;10275.486225688686;10590.311629744043;10594.9470252649;10661.413622250773;44123.77938110321;44475.332870006976;44481.77759111184;44490.33264501035;44545.55454445446;3557.5553055868018;3650.8539658413633;3656.780593164419;3729.757565758217;3959.59595959596;32273;32518.18704532382;32602.184945376317;33106.83446582752;33137;3992.281389349916;4093.5087789465156;4157.064660451404;4222.176675548239;4230.238557059728;365834;366276;367911;368411;368632;59692.40307596869;60031.39968600345;60198;60216;60498.092528612106;63630.68184659047;63954.80225988682;63967;63998.36001639924;64020;17280.827191728175;17366.13169341528;17551.561210969827;17555.824441755674;17610.471685849447;12137.514499419924;12153.210041347284;12165.939170304091;12174.695632609255;12201.267923924594;15668.138252396227;16703.582410439838;17164.82835171632;17207;17753.289868405118;5797.739101740432;5878.265216847894;5909.172715819789;5913.940860591339;5930.614510056919;991.8512223166583;1052.9420881851418;1083.653230966099;1103.900648941599;1159.8434211381355;31969.840150799097;32351.676483234867;32747.67252327446;32842;33763.32473350519;1076.4886678827652;1111.8554587903682;1128.5937062657438;1128.638835572609;1206.7103895065122;16691.91654041722;16904.492865214062;17010.149492525572;17107.401240956508;17300.740488892676;533387;538593;538775;538842.6694398349;540261;53974.73012634912;58821.41178588245;59627.40372596305;59860.40139598635;60093.69953150206;1346.7575836349356;1348.0900392235203;1366.9884285628564;1381.2748307138631;1406.4514839212716;31848.203794905312;34018.489722653685;34178.31643367169;34718.82640586781;34739.65260347465;54114.45885541094;54308;54633.18050229329;54634.180487291924;54725.179122313995;493666;496478;502114;502793;503844;41119.588804111576;42002;42279.365809513496;42702.35946460868;42861.571384287;48810.5118948806;49874;49974.750126249135;50236;50339.74830125826;40149;42590.148197036506;42613.36079958741;42741.57258427376;43142.56857431511;41876.35542616016;42398.788006059774;42437.15125697407;42692.359614605186;42820;40368.798156009034;41383.586164137974;41423.37864931968;43103;43258.35112473252;35088.29823403569;35326;37755.05612359713;38984.80507597557;39117.41323880201;28137.859310703316;28746;28925;28986.855065724536;29124.854375727984 05:58:08 INFO - PROCESS | 9212 | |2;jquery.html;367.34429044156184;381.2888962085711;388.4193131268753;393.15864050931015;408.87120557024537;17024.14879256037;19017.809821901777;19049.619007619858;19462.902685486588;20253.59492810143;19096.33162839301;19215.32746353878;19255.90372048138;19316.51708707283;19540.218391264367;5463.972680136604;5563.777448902039;5678.943210567903;5741.684207368593;5745.540356771448;5497.45025497449;5651.632643878155;5654.37801841797;5701.002324593201;5706.572007099463;5589.692566908819;5611.158326251054;5613.522850557708;5616.213730077772;5960.672163031031;5826.766929322822;5831.650100993933;5831.708414579297;5850.561207909382;5876.882462350751;5528.087865502187;5679.091345384719;5725.828225153252;5740.655560666373;5769.3076830780155;13535;13642.317884105854;13730.65673358164;13837.792433113511;14083;2093.2987449204534;2188.1575593396656;2249.9887500562554;2317.0268487235403;2423.8182136339847;9771.218302535852;9900.504974751306;10157.23820713442;10370.896291037068;10372.740681482948;1846.8060853610311;1908.6850669639632;1945.2316335047576;1974.4767636576319;2041.061111888533;77390;80522;82911.585442073;83547;84714.57642711808;10909.290896091728;10941.507632156572;10958.178136639624;10974.286671366335;11083.944580277208;15607.141607211484;15642.983206091567;15669.529914102595;15679.372825087097;15692.215389230721;8000.959995199987;8414.242718155396;8455.365847561457;8572.571371431404;8595.828083438297;8163;8304.916950830535;8324.6670133194;8412.499312867587;8426.157384261525;4763.428388593391;4808.495108013678;4840;4880.438749543777;4891;1947.289239427627;1995.4113536506766;2002.2291417803958;2017.7893264041752;2056.383419223852;919.5815903763771;950.7955789505289;1012.8025035118228;1019.1795604538418;1029.696239609302;18955.526111847314;19177.32879349216;19350.61298774045;19435;19531.51171220731;945.6926498887889;1009.5557954499997;1025.5436330832708;1025.9435731034864;1050.9789804203874;10300.175985921109;10715.249932504807;10811.729706757236;10832.187585931088;10997.175211859143;447890;451542;465297;466748;471478.140874368;86807;91979;92096;92602.53698731598;92611;987.8370068938689;1002.3835341265069;1026.645807196516;1052.5368837711383;1059.3432072115338;16602.16989150562;16831.495055148363;17308.740368894225;17356.91321543384;17654;38815.417768734056;39399.60600394074;40020;40345.59654403418;41070.79464602658;54485.72757136189;56656;56966.43033569611;57294.713526432104;57623.711881442;8372.66509339644;8596.91403085961;8636.352273579632;8713.956430217808;8785.077566855643;9187.402818817023;11749.706257343634;12692.936535317263;12933.417996190383;13109.410076546388;11357.48891299877;11505.309681419141;11849;11944.104192185794;12180.147389682637;10325.380477171093;11224.15818813576;11490.77018459643;11526.07791376671;11569.247998880383;11062.778744425228;11611.535538578362;11775.587854425054;11913.046956243654;12037.097217708882;8431.451955622864;8447.746567602982;8461.196186362286;8589.312854971462;8708.17272359125;8855.601497932737;11552.306861588331;11944.04447644169;11958.64124076279;12013 05:58:08 INFO - PROCESS | 9212 | |3;mootools.html;1257.8113283007542;1438.1227451254733;1465.919374434406;1555.627667806595;1728.4555365060014;7986.880196797053;8325.37559683024;8329.583520823957;8542.91457085429;8549.87175192371;8217.548034858095;8435.746927592167;8438.029626592943;8441.831163376728;8442.366822488322;2239.552089582082;2274.1471948019494;2275.681404603357;2279.4871153990384;2280.0879648140713;2324.837261391703;2361.598528250193;2361.7165940087216;2361.9409514762183;2366.124533922451;2283.828712846543;2324.372419446748;2324.7675232476786;2329.6738456616085;2349.530093981202;2263.920762773303;2290.89690963907;2298.402415372007;2298.678185054094;2301.355620426283;2294.128231272106;2320.9883950580306;2327.0459111764267;2327.0459111764267;2327.511222643247;8351.749447516584;8461.365397595206;8522.74431767048;8524.06235314115;8587.184217499327;5097.3118628984985;5171.3535808024;5178;5205.817796377109;5238.030964271622;3433.4334834752235;3449.068751437098;3451.3097380523996;3490.8429120689375;3499.685028347461;1068.3643232276784;1071.2929466552039;1072.7318170457386;1088.2001728729379;1095.2606990281529;11567.710807229803;11611.825822612667;11637.88362116385;11638.941805291004;11655.184137110406;4301.849435269751;4404.823807047746;4467.218236808546;4505.954940450553;4507.549245075532;9363.4381937084;9368.39105458143;9375.859362109573;9378.202852757591;9380.686703861466;5100.515451032146;5111.948880511221;5114.41184263807;5114.74426278692;5115.590752739772;4107.507099148122;4618.907621847611;4631.722096674211;4792.496787837296;4801.231802911518;3599.4960705501253;3646.343658141507;3664.523611930432;3665.2119794244363;3724.888253352404;1124.8650161980615;1134.5802053240188;1138.0610995928391;1138.1065863297272;1139.5384869128015;757.8484303139394;939.6945992552447;968.6222373274429;984.3306551545019;1003.2776400991274;5851.882962340815;5887.676177810176;5888.277292489351;5921.6743079131065;6003.729832157564;480.5050797678397;481.9132556139929;483.2678492084495;484.8109237397418;637;4043.5951651159053;4099.200655872136;4165.770882601425;4165.895852603649;4218.282891908383;10283.794324113625;10405.84391234132;10653.094486968539;10657.680269591925;10693.358398496115;10354.430506322075;10422.739431514121;10453.372797632166;10462.319949203278;10488.370697758159;7517.624118794039;7822.882656760153;7856.882146767803;7896.84206315882;7979.0425148982495;5948.643081415129;6089.908651370144;6094;6144.846378840565;6163.106349579376;9659.613615455302;9693.369930954465;9839.950800246239;9845.261605379483;9856.85214721794;9576.473293968898;9805.656802011896;9987.300888937705;9994.10053095225;10006.899931000597;6919.930800691928;7412.147603025721;7620.352270056886;7695.538267703956;7856.332211762065;8435.114312997048;8453.619587118472;8501.319894408309;8538.786530336543;8542.316614670935;7366.521176123535;7586.241375862591;7674.501157424749;7719.884201737091;7749.41879359039;7376.18861925177;7475.327220550176;7500.737474188377;7669.616519173907;7686.039245094364;6839.213490448662;6854.760083397057;6884.070650462124;6890.621015844176;6920.446364290945;5120.283160357479;5246.658967167122;5353.304070470814;5362;5456.154296084113;4987.426445958761;4994.600431965361;5202.713850738246;5292.7353632319;5358.062339090721 05:58:08 INFO - PROCESS | 9212 | |4;prototype.html;316.1968599756618;319.281616363183;325.22920677993153;332.90345799718085;336.7087469339021;5239.921401178985;5324.3610766708;5369.355677318717;5372.435894231103;5477.247280870123;5348.973255133719;5357.491038351351;5379.731013449322;5389.595780316482;5392.649477783942;4195.265828480004;4198.790060496978;4198.958010419902;4202.642775364097;4208.684348673846;3949.6050394960416;4160.563140870196;4177.853775117872;4196.118815048828;4197.055662475949;4098.323776576861;4119.361498967664;4132.946098744816;4133.627973482386;4153.813078411464;4080.6531444827374;4112.773797441139;4133.256013917494;4134.565870583576;4189.392538081961;3661.8901432956786;3665.4318580620047;3672.4307732301536;3682.410814269704;3689.18837855674;5140.228965655144;5231.686098834082;5260.316158899357;5268.736563171826;5273.86815329616;4752.714837109785;4760.309755085528;4764.142454358214;4766.142094423002;4779.283107533863;5382.3541175059245;5484.506394424521;5535.142052981794;5536.833894983157;5557.916631250535;4017.859374921893;4027.577104404054;4029.5768944261017;4040.939385909214;4046.531615714441;5539.9169012464845;5567.248421463131;5759.827205183851;5807.680577568232;5816.389279125673;4074.694397920168;4081.734687245379;4088.7751173685733;4090.7136500444685;4094.1811637672586;4124.401961715595;4126.319157339066;4134.855280065243;4136.234796562646;4137.77242251673;4485.506594274627;4575.359449677047;4675.485696573374;4726.243800991825;4726.26742854858;5531.423544289858;5625.409332020161;5647.519960803378;5670.5747068969995;5680.721837586592;1415.7310111078862;1418.390092260338;1429.9142051476945;1430.2348243689576;1431.8424973252934;901.2744740483843;909.0591586961752;912.2838571721167;914.1589737441437;983.3509883476871;5307.177387504943;5355.598330125256;5357.973210133924;5368.624196306299;5370.301860758076;5627.268455100892;5644.6330988485615;5652.943470565324;5690.77236910519;5694.288213973254;5114.258432527263;5320.334958130234;5330.893382132335;5362.544183744425;5380.7309634518115;3012.5481177823503;3015.276333679901;3038.905993842215;3054.7322861012876;3066.2487690515522;5745.339285982165;5771.192033115367;5773.538116950634;5831.454664513907;5874.618149820333;5617.43825617435;5903.527717782567;5935.584509084322;5945.1973983512535;5995.163095618807;5886.911696324559;5902.970485147547;5924.407559244128;5928.057841535417;5943.58394912352;5502.239283429264;5716.857078573069;5760.481556659921;5766.798162064308;5768.365479797135;5438.2386465894015;5673.3759286477625;5698.914516282176;5702.971485142713;5723.109829536058;5768.711564421678;5956.702164891824;5958.1658567801405;5962.344142144448;5989.700514974321;4022.7586344819406;4023.2959232133676;4035.757854528738;4036.233115708006;4039.5960403960544;5626.168596093349;5686.800961966312;5766.279215098113;5779.162021506942;5784.392638773036;5625.8593535161945;5642.717864106696;5647.350554686263;5649.237352957298;5670.688112153871;5631.352394474524;5636.154576813511;5638.689872057076;5659.688717120598;5682.971585142048;5340.49265319794;5520.944790552043;5525;5530.55755539564;5597.180916202332;5583.664980101207;5623.184638227516;5638.295213098363;5681.176229446624;5687.60186786921;5271.815486458109;5514.393416724238;5571.353607036625;5599.832005039855;5601.943980560142 05:58:08 INFO - PROCESS | 9212 | |5;yui.html;702.6381413572014;719.4999475364622;722.4762047515552;724.6413025552349;746.7386414754835;13813.51652692156;13842.723145537084;13860.029797914163;13883.583492495241;15644.296006679699;14971.625709357271;15090.849091509082;15103.54689359321;15115.546533604009;15162.393504259844;669.8626781509803;785.140271402813;790.328221012138;809.8542262392767;815.502543448496;811.9594020298962;813.8575749243888;813.9633716482742;814.5764202614637;815.6288888555692;849.0787495567317;853.8292341531718;856.002756788344;857.0443954990203;857.4683696108422;700.2857085772534;721.7618185998634;722.4220623501178;820.8932838730926;848.8472075026493;620.4447019917194;624.9062640603901;625.6371304643296;625.8497960489495;626.1515646299245;14928.402863885432;14990.400383984628;14990.925045374703;15002;15007.399704011827;14676.633084172874;14887.627809304746;14929.477468288665;14935.701285974328;14966.251687415586;13003.169572107785;13218.801717974238;13279.203247805066;13297.601071967856;13500.662483437894;4459.620932220765;4485.192665320241;4550.931736023962;4583.1062942726185;4595.149897269016;83287.99999999939;83406.58296708539;84001;84107.5794621029;84820.57589712074;11684.824727629093;11814.881851181379;11933.820992685116;12090.032797376189;12325.198862073936;22578.661320080213;22600.66099008516;22636.434089147904;22674.43313917165;22724.318270451913;8499.83000340002;8583.742487725378;8656;8697.869531957025;8846.292296616384;4569.885752856205;4823.927641085386;4892.315075889378;5019.498050195024;5169.71566563835;3497.0732755819727;3632.1282892105705;3642.18050938542;3642.4718415829557;3645.216278500135;598.9371116032754;609.9542534309855;610.3957082488305;620.2618883528587;620.3269452643842;673.2594146438878;697.400235797214;697.5221972948547;725.9491835571565;727.2981572782185;8544.273736732448;9091.590878410487;9118.726438206864;9214.677486287948;9247.352685311964;571.7912961769008;589.2545929399313;628.7736414890636;680.618853442074;690.5752961928378;4444.355568442557;4664.580187783048;4716.858494245178;4716.976415117902;4740.95259047412;66966;69069.65465172642;69517.3048269521;70306.64846675734;87821.56089219514;97162;97382.51308743411;97975;100188.49905750425;101091;85005;85860.57069714612;86053;86779;87809.99999999872;8595.65617375323;8632.266257368194;8722.345824063093;8740.073744993533;8863.734087977371;48884.2667359997;50232.49767502278;50526.747366262935;50915.74542127266;52029.47970520398;67832;75089.62455187688;76239.61880190564;76489.23510765044;78555.60722196352;11124.332540047299;11361.334138393957;11545.884541154817;11634.952854243158;11700.824487632523;12085.818712719492;12174;12278.386080696107;12364.258144511357;12403.503859845867;11645.6506304811;11967.700807479883;12026.75946481083;12298.877011230132;12299.569515066929;11519.322077625644;11523.250988685708;11741.47163377633;11748.941255293668;11786.41067946582;11582.478788454708;11845.5854045108;11855.17013809025;11892.345920974429;11986.700332491757;9315;9490.027445579302;9627.759306017406;9730.854037189589;9950.502474876372;9405.529723513933;9407;9751.658691945748;10092.545835437277;10102.646407376 05:58:08 INFO - PROCESS | 9212 | __end_tp_report 05:58:08 INFO - PROCESS | 9212 | __start_cc_report 05:58:08 INFO - PROCESS | 9212 | _x_x_mozilla_cycle_collect,892 05:58:08 INFO - PROCESS | 9212 | __end_cc_report 05:58:08 INFO - PROCESS | 9212 | __startTimestamp1465649888879__endTimestamp 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | ------- Summary: start ------- 05:58:08 INFO - PROCESS | 9212 | Number of tests: 6 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#0] dojo.html Cycles:165 Average:5395.93 Median:5585.78 stddev:612.60 (11.0%) stddev-sans-first:588.47 05:58:08 INFO - PROCESS | 9212 | Values: 3144.7 3158.7 3175.5 3191.6 3214.8 5275.6 5352.7 5422.8 5523.1 5534.3 5366.3 5369.3 5524.1 5558.7 5593.9 4773.0 4867.2 4887.5 4897.0 5101.5 4990.7 5000.8 5037.6 5043.1 5056.2 4749.2 4836.9 4846.4 4976.3 4981.2 4836.0 4847.0 4910.2 4917.7 4957.6 4753.9 4854.9 4874.6 4885.2 4911.6 5430.9 5519.0 5535.5 5585.8 5607.6 5906.0 5920.1 5927.0 5944.5 5981.8 5610.9 5872.0 5916.4 5923.7 5937.7 5512.9 5561.7 5606.7 5608.3 5609.5 6069.1 6075.5 6089.3 6121.6 6140.9 5420.4 5601.3 5700.2 5857.2 5873.7 5802.9 5814.1 5863.8 5896.3 5920.7 5775.0 5801.6 5802.9 5803.6 5820.7 5566.3 5722.5 5746.5 5782.5 5790.9 4416.6 4438.7 4443.7 4471.2 4514.3 3988.4 3997.8 4004.5 4019.1 4038.7 5375.5 5426.5 5433.9 5478.9 5485.0 5768.4 5772.2 5805.2 5837.6 5851.0 5510.4 5511.6 5512.4 5517.2 5522.4 4892.5 5018.6 5046.5 5047.6 5095.3 6055.1 6059.2 6061.5 6062.2 6072.6 5680.7 5903.1 5914.1 5916.4 5921.2 5849.2 5880.3 5896.8 5931.6 5933.0 5629.5 5676.3 5677.1 5680.3 5684.4 5666.6 5679.2 5766.1 5766.7 5775.2 5587.7 5602.2 5603.5 5604.9 5660.7 5570.1 5621.4 5623.6 5625.2 5627.3 5274.9 5387.1 5426.6 5525.4 5528.4 5630.9 5691.7 5691.9 5699.8 5704.2 5532.6 5536.5 5538.1 5538.2 5539.0 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#1] ext.html Cycles:180 Average:63526.92 Median:41251.59 stddev:125672.74 (304.6%) stddev-sans-first:125936.43 05:58:08 INFO - PROCESS | 9212 | Values: 585.1 606.7 607.3 618.5 628.1 33525.8 36258.3 36373.0 43207.8 44394.8 46094.1 46801.3 46934.8 46945.5 46969.3 8867.1 9012.7 9029.0 9029.2 9029.5 8149.2 8351.1 8352.2 8623.0 8848.0 10631.3 10879.3 10885.6 11317.6 11319.7 10795.8 10867.5 10867.6 10867.7 10873.1 8155.0 10275.5 10590.3 10594.9 10661.4 44123.8 44475.3 44481.8 44490.3 44545.6 3557.6 3650.9 3656.8 3729.8 3959.6 32273.0 32518.2 32602.2 33106.8 33137.0 3992.3 4093.5 4157.1 4222.2 4230.2 365834.0 366276.0 367911.0 368411.0 368632.0 59692.4 60031.4 60198.0 60216.0 60498.1 63630.7 63954.8 63967.0 63998.4 64020.0 17280.8 17366.1 17551.6 17555.8 17610.5 12137.5 12153.2 12165.9 12174.7 12201.3 15668.1 16703.6 17164.8 17207.0 17753.3 5797.7 5878.3 5909.2 5913.9 5930.6 991.9 1052.9 1083.7 1103.9 1159.8 31969.8 32351.7 32747.7 32842.0 33763.3 1076.5 1111.9 1128.6 1128.6 1206.7 16691.9 16904.5 17010.1 17107.4 17300.7 533387.0 538593.0 538775.0 538842.7 540261.0 53974.7 58821.4 59627.4 59860.4 60093.7 1346.8 1348.1 1367.0 1381.3 1406.5 31848.2 34018.5 34178.3 34718.8 34739.7 54114.5 54308.0 54633.2 54634.2 54725.2 493666.0 496478.0 502114.0 502793.0 503844.0 41119.6 42002.0 42279.4 42702.4 42861.6 48810.5 49874.0 49974.8 50236.0 50339.7 40149.0 42590.1 42613.4 42741.6 43142.6 41876.4 42398.8 42437.2 42692.4 42820.0 40368.8 41383.6 41423.4 43103.0 43258.4 35088.3 35326.0 37755.1 38984.8 39117.4 28137.9 28746.0 28925.0 28986.9 29124.9 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#2] jquery.html Cycles:180 Average:27799.74 Median:2370.42 stddev:76144.69 (3212.3%) stddev-sans-first:76330.44 05:58:08 INFO - PROCESS | 9212 | Values: 367.3 381.3 388.4 393.2 408.9 17024.1 19017.8 19049.6 19462.9 20253.6 19096.3 19215.3 19255.9 19316.5 19540.2 5464.0 5563.8 5678.9 5741.7 5745.5 5497.5 5651.6 5654.4 5701.0 5706.6 5589.7 5611.2 5613.5 5616.2 5960.7 5826.8 5831.7 5831.7 5850.6 5876.9 5528.1 5679.1 5725.8 5740.7 5769.3 13535.0 13642.3 13730.7 13837.8 14083.0 2093.3 2188.2 2250.0 2317.0 2423.8 9771.2 9900.5 10157.2 10370.9 10372.7 1846.8 1908.7 1945.2 1974.5 2041.1 77390.0 80522.0 82911.6 83547.0 84714.6 10909.3 10941.5 10958.2 10974.3 11083.9 15607.1 15643.0 15669.5 15679.4 15692.2 8001.0 8414.2 8455.4 8572.6 8595.8 8163.0 8304.9 8324.7 8412.5 8426.2 4763.4 4808.5 4840.0 4880.4 4891.0 1947.3 1995.4 2002.2 2017.8 2056.4 919.6 950.8 1012.8 1019.2 1029.7 18955.5 19177.3 19350.6 19435.0 19531.5 945.7 1009.6 1025.5 1025.9 1051.0 10300.2 10715.2 10811.7 10832.2 10997.2 447890.0 451542.0 465297.0 466748.0 471478.1 86807.0 91979.0 92096.0 92602.5 92611.0 987.8 1002.4 1026.6 1052.5 1059.3 16602.2 16831.5 17308.7 17356.9 17654.0 38815.4 39399.6 40020.0 40345.6 41070.8 54485.7 56656.0 56966.4 57294.7 57623.7 8372.7 8596.9 8636.4 8714.0 8785.1 9187.4 11749.7 12692.9 12933.4 13109.4 11357.5 11505.3 11849.0 11944.1 12180.1 10325.4 11224.2 11490.8 11526.1 11569.2 11062.8 11611.5 11775.6 11913.0 12037.1 8431.5 8447.7 8461.2 8589.3 8708.2 8855.6 11552.3 11944.0 11958.6 12013.0 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#3] mootools.html Cycles:180 Average:5623.98 Median:5113.18 stddev:3159.63 (61.8%) stddev-sans-first:3151.45 05:58:08 INFO - PROCESS | 9212 | Values: 1257.8 1438.1 1465.9 1555.6 1728.5 7986.9 8325.4 8329.6 8542.9 8549.9 8217.5 8435.7 8438.0 8441.8 8442.4 2239.6 2274.1 2275.7 2279.5 2280.1 2324.8 2361.6 2361.7 2361.9 2366.1 2283.8 2324.4 2324.8 2329.7 2349.5 2263.9 2290.9 2298.4 2298.7 2301.4 2294.1 2321.0 2327.0 2327.0 2327.5 8351.7 8461.4 8522.7 8524.1 8587.2 5097.3 5171.4 5178.0 5205.8 5238.0 3433.4 3449.1 3451.3 3490.8 3499.7 1068.4 1071.3 1072.7 1088.2 1095.3 11567.7 11611.8 11637.9 11638.9 11655.2 4301.8 4404.8 4467.2 4506.0 4507.5 9363.4 9368.4 9375.9 9378.2 9380.7 5100.5 5111.9 5114.4 5114.7 5115.6 4107.5 4618.9 4631.7 4792.5 4801.2 3599.5 3646.3 3664.5 3665.2 3724.9 1124.9 1134.6 1138.1 1138.1 1139.5 757.8 939.7 968.6 984.3 1003.3 5851.9 5887.7 5888.3 5921.7 6003.7 480.5 481.9 483.3 484.8 637.0 4043.6 4099.2 4165.8 4165.9 4218.3 10283.8 10405.8 10653.1 10657.7 10693.4 10354.4 10422.7 10453.4 10462.3 10488.4 7517.6 7822.9 7856.9 7896.8 7979.0 5948.6 6089.9 6094.0 6144.8 6163.1 9659.6 9693.4 9840.0 9845.3 9856.9 9576.5 9805.7 9987.3 9994.1 10006.9 6919.9 7412.1 7620.4 7695.5 7856.3 8435.1 8453.6 8501.3 8538.8 8542.3 7366.5 7586.2 7674.5 7719.9 7749.4 7376.2 7475.3 7500.7 7669.6 7686.0 6839.2 6854.8 6884.1 6890.6 6920.4 5120.3 5246.7 5353.3 5362.0 5456.2 4987.4 4994.6 5202.7 5292.7 5358.1 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#4] prototype.html Cycles:180 Average:4703.67 Median:5371.37 stddev:1386.42 (25.8%) stddev-sans-first:1350.63 05:58:08 INFO - PROCESS | 9212 | Values: 316.2 319.3 325.2 332.9 336.7 5239.9 5324.4 5369.4 5372.4 5477.2 5349.0 5357.5 5379.7 5389.6 5392.6 4195.3 4198.8 4199.0 4202.6 4208.7 3949.6 4160.6 4177.9 4196.1 4197.1 4098.3 4119.4 4132.9 4133.6 4153.8 4080.7 4112.8 4133.3 4134.6 4189.4 3661.9 3665.4 3672.4 3682.4 3689.2 5140.2 5231.7 5260.3 5268.7 5273.9 4752.7 4760.3 4764.1 4766.1 4779.3 5382.4 5484.5 5535.1 5536.8 5557.9 4017.9 4027.6 4029.6 4040.9 4046.5 5539.9 5567.2 5759.8 5807.7 5816.4 4074.7 4081.7 4088.8 4090.7 4094.2 4124.4 4126.3 4134.9 4136.2 4137.8 4485.5 4575.4 4675.5 4726.2 4726.3 5531.4 5625.4 5647.5 5670.6 5680.7 1415.7 1418.4 1429.9 1430.2 1431.8 901.3 909.1 912.3 914.2 983.4 5307.2 5355.6 5358.0 5368.6 5370.3 5627.3 5644.6 5652.9 5690.8 5694.3 5114.3 5320.3 5330.9 5362.5 5380.7 3012.5 3015.3 3038.9 3054.7 3066.2 5745.3 5771.2 5773.5 5831.5 5874.6 5617.4 5903.5 5935.6 5945.2 5995.2 5886.9 5903.0 5924.4 5928.1 5943.6 5502.2 5716.9 5760.5 5766.8 5768.4 5438.2 5673.4 5698.9 5703.0 5723.1 5768.7 5956.7 5958.2 5962.3 5989.7 4022.8 4023.3 4035.8 4036.2 4039.6 5626.2 5686.8 5766.3 5779.2 5784.4 5625.9 5642.7 5647.4 5649.2 5670.7 5631.4 5636.2 5638.7 5659.7 5683.0 5340.5 5520.9 5525.0 5530.6 5597.2 5583.7 5623.2 5638.3 5681.2 5687.6 5271.8 5514.4 5571.4 5599.8 5601.9 05:58:08 INFO - PROCESS | 9212 | 05:58:08 INFO - PROCESS | 9212 | [#5] yui.html Cycles:180 Average:19538.31 Median:594.10 stddev:27430.96 (4617.3%) stddev-sans-first:27471.45 05:58:08 INFO - PROCESS | 9212 | Values: 702.6 719.5 722.5 724.6 746.7 13813.5 13842.7 13860.0 13883.6 15644.3 14971.6 15090.8 15103.5 15115.5 15162.4 669.9 785.1 790.3 809.9 815.5 812.0 813.9 814.0 814.6 815.6 849.1 853.8 856.0 857.0 857.5 700.3 721.8 722.4 820.9 848.8 620.4 624.9 625.6 625.8 626.2 14928.4 14990.4 14990.9 15002.0 15007.4 14676.6 14887.6 14929.5 14935.7 14966.3 13003.2 13218.8 13279.2 13297.6 13500.7 4459.6 4485.2 4550.9 4583.1 4595.1 83288.0 83406.6 84001.0 84107.6 84820.6 11684.8 11814.9 11933.8 12090.0 12325.2 22578.7 22600.7 22636.4 22674.4 22724.3 8499.8 8583.7 8656.0 8697.9 8846.3 4569.9 4823.9 4892.3 5019.5 5169.7 3497.1 3632.1 3642.2 3642.5 3645.2 598.9 610.0 610.4 620.3 620.3 673.3 697.4 697.5 725.9 727.3 8544.3 9091.6 9118.7 9214.7 9247.4 571.8 589.3 628.8 680.6 690.6 4444.4 4664.6 4716.9 4717.0 4741.0 66966.0 69069.7 69517.3 70306.6 87821.6 97162.0 97382.5 97975.0 100188.5 101091.0 85005.0 85860.6 86053.0 86779.0 87810.0 8595.7 8632.3 8722.3 8740.1 8863.7 48884.3 50232.5 50526.7 50915.7 52029.5 67832.0 75089.6 76239.6 76489.2 78555.6 11124.3 11361.3 11545.9 11635.0 11700.8 12085.8 12174.0 12278.4 12364.3 12403.5 11645.7 11967.7 12026.8 12298.9 12299.6 11519.3 11523.3 11741.5 11748.9 11786.4 11582.5 11845.6 11855.2 11892.3 11986.7 9315.0 9490.0 9627.8 9730.9 9950.5 9405.5 9407.0 9751.7 10092.5 10102.6 05:58:08 INFO - PROCESS | 9212 | -------- Summary: end -------- 05:58:08 INFO - PROCESS | 9212 | 05:58:09 INFO - TEST-INFO | 9212: exit 0 05:58:09 INFO - TEST-OK | dromaeo_css | took 1114444ms 05:58:09 INFO - TEST-START | kraken 05:58:09 INFO - operating with platform_type : linux_ 05:58:09 INFO - Initialising browser for kraken test... 05:58:09 INFO - TEST-INFO | started process 9394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgIqmfS/profile http://localhost:55546/getInfo.html) 05:58:14 INFO - PROCESS | 9394 | __metrics Screen width/height:1600/1200 05:58:14 INFO - PROCESS | 9394 | colorDepth:24 05:58:14 INFO - PROCESS | 9394 | Browser inner width/height: 1024/697 05:58:14 INFO - PROCESS | 9394 | __metrics 05:58:15 ERROR - PROCESS | 9394 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:58:15 INFO - PROCESS | 9394 | console.error: PushService: 05:58:15 INFO - PROCESS | 9394 | stateChangeProcessEnqueue: Error transitioning state 05:58:15 INFO - PROCESS | 9394 | UnknownError 05:58:16 INFO - TEST-INFO | 9394: exit 0 05:58:16 INFO - Browser initialized. 05:58:16 INFO - Running cycle 1/1 for kraken test... 05:58:16 INFO - TEST-INFO | started process 9477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgIqmfS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:28 INFO - PROCESS | 9477 | RSS: Main: 173801472 05:58:28 INFO - PROCESS | 9477 | 05:58:30 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55546/tests/kraken/driver.html?testName=audio-beat-detection) 05:58:31 INFO - PROCESS | 9477 | RSS: Main: 231731200 05:58:31 INFO - PROCESS | 9477 | 05:58:34 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55546/tests/kraken/driver.html?testName=audio-dft) 05:58:34 INFO - PROCESS | 9477 | RSS: Main: 220385280 05:58:34 INFO - PROCESS | 9477 | 05:58:37 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55546/tests/kraken/driver.html?testName=audio-fft) 05:58:38 INFO - PROCESS | 9477 | RSS: Main: 286183424 05:58:38 INFO - PROCESS | 9477 | 05:58:40 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55546/tests/kraken/driver.html?testName=audio-oscillator) 05:58:41 INFO - PROCESS | 9477 | RSS: Main: 197046272 05:58:41 INFO - PROCESS | 9477 | 05:58:43 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:58:43 INFO - PROCESS | 9477 | RSS: Main: 201195520 05:58:43 INFO - PROCESS | 9477 | 05:58:46 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55546/tests/kraken/driver.html?testName=imaging-darkroom) 05:58:47 INFO - PROCESS | 9477 | RSS: Main: 236482560 05:58:47 INFO - PROCESS | 9477 | 05:58:50 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55546/tests/kraken/driver.html?testName=imaging-desaturate) 05:58:51 INFO - PROCESS | 9477 | RSS: Main: 237469696 05:58:51 INFO - PROCESS | 9477 | 05:58:54 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55546/tests/kraken/driver.html?testName=json-parse-financial) 05:58:54 INFO - PROCESS | 9477 | RSS: Main: 237248512 05:58:54 INFO - PROCESS | 9477 | 05:58:56 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:58:57 INFO - PROCESS | 9477 | RSS: Main: 221052928 05:58:57 INFO - PROCESS | 9477 | 05:58:58 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:58:59 INFO - PROCESS | 9477 | RSS: Main: 226512896 05:58:59 INFO - PROCESS | 9477 | 05:59:01 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:59:02 INFO - PROCESS | 9477 | RSS: Main: 234418176 05:59:02 INFO - PROCESS | 9477 | 05:59:04 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:59:05 INFO - PROCESS | 9477 | RSS: Main: 238428160 05:59:05 INFO - PROCESS | 9477 | 05:59:08 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:59:08 INFO - PROCESS | 9477 | RSS: Main: 239644672 05:59:08 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | Cycle 1(1): loaded http://localhost:55546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55546/tests/kraken/driver.html?testName=ai-astar) 05:59:10 INFO - PROCESS | 9477 | RSS: Main: 282517504 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | __start_tp_report 05:59:10 INFO - PROCESS | 9477 | _x_x_mozilla_page_load 05:59:10 INFO - PROCESS | 9477 | _x_x_mozilla_page_load_details 05:59:10 INFO - PROCESS | 9477 | |i|pagename|runs| 05:59:10 INFO - PROCESS | 9477 | |0;ai-astar;112;104;111;111;109;111;110;109;110;110 05:59:10 INFO - PROCESS | 9477 | |1;audio-beat-detection;152;131;126;179;126;130;148;125;165;125 05:59:10 INFO - PROCESS | 9477 | |2;audio-dft;160;162;159;158;159;161;160;161;163;161 05:59:10 INFO - PROCESS | 9477 | |3;audio-fft;75;75;84;119;76;77;74;75;102;75 05:59:10 INFO - PROCESS | 9477 | |4;audio-oscillator;84;84;115;86;86;86;121;86;84;130 05:59:10 INFO - PROCESS | 9477 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;118;117 05:59:10 INFO - PROCESS | 9477 | |6;imaging-darkroom;145;146;145;146;146;145;145;145;146;145 05:59:10 INFO - PROCESS | 9477 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;95;95 05:59:10 INFO - PROCESS | 9477 | |8;json-parse-financial;54;53;56;55;54;53;53;54;54;53 05:59:10 INFO - PROCESS | 9477 | |9;json-stringify-tinderbox;66;66;67;66;66;66;66;66;65;67 05:59:10 INFO - PROCESS | 9477 | |10;stanford-crypto-aes;81;80;80;80;80;87;83;81;115;81 05:59:10 INFO - PROCESS | 9477 | |11;stanford-crypto-ccm;125;121;127;121;131;129;123;123;127;125 05:59:10 INFO - PROCESS | 9477 | |12;stanford-crypto-pbkdf2;157;156;159;158;156;161;161;159;158;164 05:59:10 INFO - PROCESS | 9477 | |13;stanford-crypto-sha256-iterative;64;63;63;62;63;64;63;62;66;62 05:59:10 INFO - PROCESS | 9477 | __end_tp_report 05:59:10 INFO - PROCESS | 9477 | __start_cc_report 05:59:10 INFO - PROCESS | 9477 | _x_x_mozilla_cycle_collect,1629 05:59:10 INFO - PROCESS | 9477 | __end_cc_report 05:59:10 INFO - PROCESS | 9477 | __startTimestamp1465649950581__endTimestamp 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | ------- Summary: start ------- 05:59:10 INFO - PROCESS | 9477 | Number of tests: 14 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.21 (2.0%) stddev-sans-first:2.19 05:59:10 INFO - PROCESS | 9477 | Values: 112.0 104.0 111.0 111.0 109.0 111.0 110.0 109.0 110.0 110.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:139.50 stddev:19.36 (13.9%) stddev-sans-first:20.09 05:59:10 INFO - PROCESS | 9477 | Values: 152.0 131.0 126.0 179.0 126.0 130.0 148.0 125.0 165.0 125.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.51 (0.9%) stddev-sans-first:1.59 05:59:10 INFO - PROCESS | 9477 | Values: 160.0 162.0 159.0 158.0 159.0 161.0 160.0 161.0 163.0 161.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:15.20 (20.3%) stddev-sans-first:15.83 05:59:10 INFO - PROCESS | 9477 | Values: 75.0 75.0 84.0 119.0 76.0 77.0 74.0 75.0 102.0 75.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:85.00 stddev:18.18 (21.4%) stddev-sans-first:18.74 05:59:10 INFO - PROCESS | 9477 | Values: 84.0 84.0 115.0 86.0 86.0 86.0 121.0 86.0 84.0 130.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 05:59:10 INFO - PROCESS | 9477 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 118.0 117.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:59:10 INFO - PROCESS | 9477 | Values: 145.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:59:10 INFO - PROCESS | 9477 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 05:59:10 INFO - PROCESS | 9477 | Values: 54.0 53.0 56.0 55.0 54.0 53.0 53.0 54.0 54.0 53.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:59:10 INFO - PROCESS | 9477 | Values: 66.0 66.0 67.0 66.0 66.0 66.0 66.0 66.0 65.0 67.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:81.00 stddev:10.83 (13.4%) stddev-sans-first:11.40 05:59:10 INFO - PROCESS | 9477 | Values: 81.0 80.0 80.0 80.0 80.0 87.0 83.0 81.0 115.0 81.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:126.00 stddev:3.33 (2.6%) stddev-sans-first:3.53 05:59:10 INFO - PROCESS | 9477 | Values: 125.0 121.0 127.0 121.0 131.0 129.0 123.0 123.0 127.0 125.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.00 stddev:2.51 (1.6%) stddev-sans-first:2.57 05:59:10 INFO - PROCESS | 9477 | Values: 157.0 156.0 159.0 158.0 156.0 161.0 161.0 159.0 158.0 164.0 05:59:10 INFO - PROCESS | 9477 | 05:59:10 INFO - PROCESS | 9477 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.23 (2.0%) stddev-sans-first:1.27 05:59:10 INFO - PROCESS | 9477 | Values: 64.0 63.0 63.0 62.0 63.0 64.0 63.0 62.0 66.0 62.0 05:59:10 INFO - PROCESS | 9477 | -------- Summary: end -------- 05:59:10 INFO - PROCESS | 9477 | 05:59:11 INFO - TEST-INFO | 9477: exit 0 05:59:11 INFO - TEST-OK | kraken | took 61883ms 05:59:11 INFO - SUITE-END | took 1176s 05:59:11 INFO - Completed test suite (00:19:36) 05:59:11 INFO - javascript benchmark 05:59:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.654088050314, 3158.7157155855984, 3175.4601717707988, 3191.5531825544444, 3214.8231847248408, 5275.577953763699, 5352.652077614952, 5422.75597598108, 5523.1163013917785, 5534.252875861753, 5366.275552800371, 5369.328833895763, 5524.06090964536, 5558.6942718150585, 5593.916091258634, 4772.976135119319, 4867.221244600864, 4887.535684110004, 4897.04507621014, 5101.464346243647, 4990.700557966534, 5000.799968001294, 5037.647364684473, 5043.117454445457, 5056.216286475584, 4749.168895443301, 4836.879078023042, 4846.442659094178, 4976.303317535548, 4981.227909674007, 4835.975820120912, 4847.030593881238, 4910.23891296846, 4917.655764096515, 4957.553820156168, 4753.881152971168, 4854.927176092361, 4874.585660218886, 4885.169521181408, 4911.582515486188, 5430.8913821723345, 5519.034169020405, 5535.501804837584, 5585.776568937237, 5607.551395888319, 5905.970470147622, 5920.052791553331, 5926.962781513218, 5944.494717948979, 5981.820545383602, 5610.915836262459, 5871.970640146815, 5916.437938395846, 5923.733431995527, 5937.67342796146, 5512.862178445531, 5561.6663000220115, 5606.6636001839615, 5608.327000759894, 5609.523190528769, 6069.12642268515, 6075.47075943388, 6089.322621095033, 6121.632702037803, 6140.8771824563255, 5420.376656684453, 5601.327840659147, 5700.23046888673, 5857.238558987305, 5873.676947767828, 5802.941970580325, 5814.127880817911, 5863.824085277449, 5896.262967129109, 5920.733566989496, 5775.047117225611, 5801.593888427854, 5802.912956305659, 5803.622764520293, 5820.679862607512, 5566.304211973504, 5722.484976352065, 5746.540276777848, 5782.537397008229, 5790.942090579125, 4416.6025057744955, 4438.667099967515, 4443.733375997386, 4471.23988921884, 4514.277715565494, 3988.4216788566077, 3997.8001099944886, 4004.539477960072, 4019.0756126091164, 4038.737482063715, 5375.489328513785, 5426.457354264542, 5433.918491222635, 5478.945210547843, 5485.0, 5768.394318596571, 5772.163036359704, 5805.245318108703, 5837.591368604242, 5851.034579294454, 5510.448955104457, 5511.641743286674, 5512.366077901012, 5517.227588137664, 5522.447755224526, 4892.461829198785, 5018.648694591344, 5046.520580544842, 5047.646664733506, 5095.286659867622, 6055.061465472859, 6059.1820104286235, 6061.4544690977145, 6062.151298818169, 6072.605280656743, 5680.7443665036, 5903.114532820111, 5914.083317085859, 5916.378780228014, 5921.230240068763, 5849.210356601974, 5880.294364676266, 5896.823095307148, 5931.584789064724, 5933.02105152645, 5629.493345598916, 5676.318841739017, 5677.091665333527, 5680.318361796611, 5684.431556844283, 5666.575006874584, 5679.204911312337, 5766.135079738073, 5766.654000759968, 5775.162601422854, 5587.720613969204, 5602.187682786055, 5603.467670571289, 5604.85987850307, 5660.745266462936, 5570.053090974625, 5621.381648018633, 5623.550115990793, 5625.156226565884, 5627.268455100811, 5274.920876186937, 5387.111126664056, 5426.647267927572, 5525.4474552544425, 5528.44715528444, 5630.943690563042, 5691.715414229355, 5691.943080569141, 5699.829005129853, 5704.172894930129, 5532.585056120887, 5536.52939500147, 5538.141588053858, 5538.196961440649, 5538.972305138448], "value": 5355.1457966801445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.0989476206641, 606.7421345927979, 607.2894713185573, 618.5082859126991, 628.1143587541567, 33525.83237083814, 36258.274834503296, 36373.0, 43207.783961080226, 44394.77802610982, 46094.07811843761, 46801.29798053032, 46934.76532617332, 46945.53054469454, 46969.295460568115, 8867.068957759455, 9012.68455604054, 9028.954855225747, 9029.187373136416, 9029.503377314246, 8149.225823546753, 8351.08138104811, 8352.24829765321, 8622.956885215564, 8847.95576022119, 10631.255812093157, 10879.292845965027, 10885.564577416893, 11317.603883864067, 11319.660410187707, 10795.78408431831, 10867.510962006729, 10867.565297388095, 10867.673969780919, 10873.130149588014, 8155.0, 10275.486225688686, 10590.311629744043, 10594.9470252649, 10661.413622250773, 44123.77938110321, 44475.332870006976, 44481.77759111184, 44490.33264501035, 44545.55454445446, 3557.5553055868018, 3650.8539658413633, 3656.780593164419, 3729.757565758217, 3959.59595959596, 32273.0, 32518.18704532382, 32602.184945376317, 33106.83446582752, 33137.0, 3992.281389349916, 4093.5087789465156, 4157.064660451404, 4222.176675548239, 4230.238557059728, 365834.0, 366276.0, 367911.0, 368411.0, 368632.0, 59692.40307596869, 60031.39968600345, 60198.0, 60216.0, 60498.092528612106, 63630.68184659047, 63954.80225988682, 63967.0, 63998.36001639924, 64020.0, 17280.827191728175, 17366.13169341528, 17551.561210969827, 17555.824441755674, 17610.471685849447, 12137.514499419924, 12153.210041347284, 12165.939170304091, 12174.695632609255, 12201.267923924594, 15668.138252396227, 16703.582410439838, 17164.82835171632, 17207.0, 17753.289868405118, 5797.739101740432, 5878.265216847894, 5909.172715819789, 5913.940860591339, 5930.614510056919, 991.8512223166583, 1052.9420881851418, 1083.653230966099, 1103.900648941599, 1159.8434211381355, 31969.840150799097, 32351.676483234867, 32747.67252327446, 32842.0, 33763.32473350519, 1076.4886678827652, 1111.8554587903682, 1128.5937062657438, 1128.638835572609, 1206.7103895065122, 16691.91654041722, 16904.492865214062, 17010.149492525572, 17107.401240956508, 17300.740488892676, 533387.0, 538593.0, 538775.0, 538842.6694398349, 540261.0, 53974.73012634912, 58821.41178588245, 59627.40372596305, 59860.40139598635, 60093.69953150206, 1346.7575836349356, 1348.0900392235203, 1366.9884285628564, 1381.2748307138631, 1406.4514839212716, 31848.203794905312, 34018.489722653685, 34178.31643367169, 34718.82640586781, 34739.65260347465, 54114.45885541094, 54308.0, 54633.18050229329, 54634.180487291924, 54725.179122313995, 493666.0, 496478.0, 502114.0, 502793.0, 503844.0, 41119.588804111576, 42002.0, 42279.365809513496, 42702.35946460868, 42861.571384287, 48810.5118948806, 49874.0, 49974.750126249135, 50236.0, 50339.74830125826, 40149.0, 42590.148197036506, 42613.36079958741, 42741.57258427376, 43142.56857431511, 41876.35542616016, 42398.788006059774, 42437.15125697407, 42692.359614605186, 42820.0, 40368.798156009034, 41383.586164137974, 41423.37864931968, 43103.0, 43258.35112473252, 35088.29823403569, 35326.0, 37755.05612359713, 38984.80507597557, 39117.41323880201, 28137.859310703316, 28746.0, 28925.0, 28986.855065724536, 29124.854375727984], "value": 20853.168582993978, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.34429044156184, 381.2888962085711, 388.4193131268753, 393.15864050931015, 408.87120557024537, 17024.14879256037, 19017.809821901777, 19049.619007619858, 19462.902685486588, 20253.59492810143, 19096.33162839301, 19215.32746353878, 19255.90372048138, 19316.51708707283, 19540.218391264367, 5463.972680136604, 5563.777448902039, 5678.943210567903, 5741.684207368593, 5745.540356771448, 5497.45025497449, 5651.632643878155, 5654.37801841797, 5701.002324593201, 5706.572007099463, 5589.692566908819, 5611.158326251054, 5613.522850557708, 5616.213730077772, 5960.672163031031, 5826.766929322822, 5831.650100993933, 5831.708414579297, 5850.561207909382, 5876.882462350751, 5528.087865502187, 5679.091345384719, 5725.828225153252, 5740.655560666373, 5769.3076830780155, 13535.0, 13642.317884105854, 13730.65673358164, 13837.792433113511, 14083.0, 2093.2987449204534, 2188.1575593396656, 2249.9887500562554, 2317.0268487235403, 2423.8182136339847, 9771.218302535852, 9900.504974751306, 10157.23820713442, 10370.896291037068, 10372.740681482948, 1846.8060853610311, 1908.6850669639632, 1945.2316335047576, 1974.4767636576319, 2041.061111888533, 77390.0, 80522.0, 82911.585442073, 83547.0, 84714.57642711808, 10909.290896091728, 10941.507632156572, 10958.178136639624, 10974.286671366335, 11083.944580277208, 15607.141607211484, 15642.983206091567, 15669.529914102595, 15679.372825087097, 15692.215389230721, 8000.959995199987, 8414.242718155396, 8455.365847561457, 8572.571371431404, 8595.828083438297, 8163.0, 8304.916950830535, 8324.6670133194, 8412.499312867587, 8426.157384261525, 4763.428388593391, 4808.495108013678, 4840.0, 4880.438749543777, 4891.0, 1947.289239427627, 1995.4113536506766, 2002.2291417803958, 2017.7893264041752, 2056.383419223852, 919.5815903763771, 950.7955789505289, 1012.8025035118228, 1019.1795604538418, 1029.696239609302, 18955.526111847314, 19177.32879349216, 19350.61298774045, 19435.0, 19531.51171220731, 945.6926498887889, 1009.5557954499997, 1025.5436330832708, 1025.9435731034864, 1050.9789804203874, 10300.175985921109, 10715.249932504807, 10811.729706757236, 10832.187585931088, 10997.175211859143, 447890.0, 451542.0, 465297.0, 466748.0, 471478.140874368, 86807.0, 91979.0, 92096.0, 92602.53698731598, 92611.0, 987.8370068938689, 1002.3835341265069, 1026.645807196516, 1052.5368837711383, 1059.3432072115338, 16602.16989150562, 16831.495055148363, 17308.740368894225, 17356.91321543384, 17654.0, 38815.417768734056, 39399.60600394074, 40020.0, 40345.59654403418, 41070.79464602658, 54485.72757136189, 56656.0, 56966.43033569611, 57294.713526432104, 57623.711881442, 8372.66509339644, 8596.91403085961, 8636.352273579632, 8713.956430217808, 8785.077566855643, 9187.402818817023, 11749.706257343634, 12692.936535317263, 12933.417996190383, 13109.410076546388, 11357.48891299877, 11505.309681419141, 11849.0, 11944.104192185794, 12180.147389682637, 10325.380477171093, 11224.15818813576, 11490.77018459643, 11526.07791376671, 11569.247998880383, 11062.778744425228, 11611.535538578362, 11775.587854425054, 11913.046956243654, 12037.097217708882, 8431.451955622864, 8447.746567602982, 8461.196186362286, 8589.312854971462, 8708.17272359125, 8855.601497932737, 11552.306861588331, 11944.04447644169, 11958.64124076279, 12013.0], "value": 9038.065451524995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.8113283007542, 1438.1227451254733, 1465.919374434406, 1555.627667806595, 1728.4555365060014, 7986.880196797053, 8325.37559683024, 8329.583520823957, 8542.91457085429, 8549.87175192371, 8217.548034858095, 8435.746927592167, 8438.029626592943, 8441.831163376728, 8442.366822488322, 2239.552089582082, 2274.1471948019494, 2275.681404603357, 2279.4871153990384, 2280.0879648140713, 2324.837261391703, 2361.598528250193, 2361.7165940087216, 2361.9409514762183, 2366.124533922451, 2283.828712846543, 2324.372419446748, 2324.7675232476786, 2329.6738456616085, 2349.530093981202, 2263.920762773303, 2290.89690963907, 2298.402415372007, 2298.678185054094, 2301.355620426283, 2294.128231272106, 2320.9883950580306, 2327.0459111764267, 2327.0459111764267, 2327.511222643247, 8351.749447516584, 8461.365397595206, 8522.74431767048, 8524.06235314115, 8587.184217499327, 5097.3118628984985, 5171.3535808024, 5178.0, 5205.817796377109, 5238.030964271622, 3433.4334834752235, 3449.068751437098, 3451.3097380523996, 3490.8429120689375, 3499.685028347461, 1068.3643232276784, 1071.2929466552039, 1072.7318170457386, 1088.2001728729379, 1095.2606990281529, 11567.710807229803, 11611.825822612667, 11637.88362116385, 11638.941805291004, 11655.184137110406, 4301.849435269751, 4404.823807047746, 4467.218236808546, 4505.954940450553, 4507.549245075532, 9363.4381937084, 9368.39105458143, 9375.859362109573, 9378.202852757591, 9380.686703861466, 5100.515451032146, 5111.948880511221, 5114.41184263807, 5114.74426278692, 5115.590752739772, 4107.507099148122, 4618.907621847611, 4631.722096674211, 4792.496787837296, 4801.231802911518, 3599.4960705501253, 3646.343658141507, 3664.523611930432, 3665.2119794244363, 3724.888253352404, 1124.8650161980615, 1134.5802053240188, 1138.0610995928391, 1138.1065863297272, 1139.5384869128015, 757.8484303139394, 939.6945992552447, 968.6222373274429, 984.3306551545019, 1003.2776400991274, 5851.882962340815, 5887.676177810176, 5888.277292489351, 5921.6743079131065, 6003.729832157564, 480.5050797678397, 481.9132556139929, 483.2678492084495, 484.8109237397418, 637.0, 4043.5951651159053, 4099.200655872136, 4165.770882601425, 4165.895852603649, 4218.282891908383, 10283.794324113625, 10405.84391234132, 10653.094486968539, 10657.680269591925, 10693.358398496115, 10354.430506322075, 10422.739431514121, 10453.372797632166, 10462.319949203278, 10488.370697758159, 7517.624118794039, 7822.882656760153, 7856.882146767803, 7896.84206315882, 7979.0425148982495, 5948.643081415129, 6089.908651370144, 6094.0, 6144.846378840565, 6163.106349579376, 9659.613615455302, 9693.369930954465, 9839.950800246239, 9845.261605379483, 9856.85214721794, 9576.473293968898, 9805.656802011896, 9987.300888937705, 9994.10053095225, 10006.899931000597, 6919.930800691928, 7412.147603025721, 7620.352270056886, 7695.538267703956, 7856.332211762065, 8435.114312997048, 8453.619587118472, 8501.319894408309, 8538.786530336543, 8542.316614670935, 7366.521176123535, 7586.241375862591, 7674.501157424749, 7719.884201737091, 7749.41879359039, 7376.18861925177, 7475.327220550176, 7500.737474188377, 7669.616519173907, 7686.039245094364, 6839.213490448662, 6854.760083397057, 6884.070650462124, 6890.621015844176, 6920.446364290945, 5120.283160357479, 5246.658967167122, 5353.304070470814, 5362.0, 5456.154296084113, 4987.426445958761, 4994.600431965361, 5202.713850738246, 5292.7353632319, 5358.062339090721], "value": 4449.444330331603, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.1968599756618, 319.281616363183, 325.22920677993153, 332.90345799718085, 336.7087469339021, 5239.921401178985, 5324.3610766708, 5369.355677318717, 5372.435894231103, 5477.247280870123, 5348.973255133719, 5357.491038351351, 5379.731013449322, 5389.595780316482, 5392.649477783942, 4195.265828480004, 4198.790060496978, 4198.958010419902, 4202.642775364097, 4208.684348673846, 3949.6050394960416, 4160.563140870196, 4177.853775117872, 4196.118815048828, 4197.055662475949, 4098.323776576861, 4119.361498967664, 4132.946098744816, 4133.627973482386, 4153.813078411464, 4080.6531444827374, 4112.773797441139, 4133.256013917494, 4134.565870583576, 4189.392538081961, 3661.8901432956786, 3665.4318580620047, 3672.4307732301536, 3682.410814269704, 3689.18837855674, 5140.228965655144, 5231.686098834082, 5260.316158899357, 5268.736563171826, 5273.86815329616, 4752.714837109785, 4760.309755085528, 4764.142454358214, 4766.142094423002, 4779.283107533863, 5382.3541175059245, 5484.506394424521, 5535.142052981794, 5536.833894983157, 5557.916631250535, 4017.859374921893, 4027.577104404054, 4029.5768944261017, 4040.939385909214, 4046.531615714441, 5539.9169012464845, 5567.248421463131, 5759.827205183851, 5807.680577568232, 5816.389279125673, 4074.694397920168, 4081.734687245379, 4088.7751173685733, 4090.7136500444685, 4094.1811637672586, 4124.401961715595, 4126.319157339066, 4134.855280065243, 4136.234796562646, 4137.77242251673, 4485.506594274627, 4575.359449677047, 4675.485696573374, 4726.243800991825, 4726.26742854858, 5531.423544289858, 5625.409332020161, 5647.519960803378, 5670.5747068969995, 5680.721837586592, 1415.7310111078862, 1418.390092260338, 1429.9142051476945, 1430.2348243689576, 1431.8424973252934, 901.2744740483843, 909.0591586961752, 912.2838571721167, 914.1589737441437, 983.3509883476871, 5307.177387504943, 5355.598330125256, 5357.973210133924, 5368.624196306299, 5370.301860758076, 5627.268455100892, 5644.6330988485615, 5652.943470565324, 5690.77236910519, 5694.288213973254, 5114.258432527263, 5320.334958130234, 5330.893382132335, 5362.544183744425, 5380.7309634518115, 3012.5481177823503, 3015.276333679901, 3038.905993842215, 3054.7322861012876, 3066.2487690515522, 5745.339285982165, 5771.192033115367, 5773.538116950634, 5831.454664513907, 5874.618149820333, 5617.43825617435, 5903.527717782567, 5935.584509084322, 5945.1973983512535, 5995.163095618807, 5886.911696324559, 5902.970485147547, 5924.407559244128, 5928.057841535417, 5943.58394912352, 5502.239283429264, 5716.857078573069, 5760.481556659921, 5766.798162064308, 5768.365479797135, 5438.2386465894015, 5673.3759286477625, 5698.914516282176, 5702.971485142713, 5723.109829536058, 5768.711564421678, 5956.702164891824, 5958.1658567801405, 5962.344142144448, 5989.700514974321, 4022.7586344819406, 4023.2959232133676, 4035.757854528738, 4036.233115708006, 4039.5960403960544, 5626.168596093349, 5686.800961966312, 5766.279215098113, 5779.162021506942, 5784.392638773036, 5625.8593535161945, 5642.717864106696, 5647.350554686263, 5649.237352957298, 5670.688112153871, 5631.352394474524, 5636.154576813511, 5638.689872057076, 5659.688717120598, 5682.971585142048, 5340.49265319794, 5520.944790552043, 5525.0, 5530.55755539564, 5597.180916202332, 5583.664980101207, 5623.184638227516, 5638.295213098363, 5681.176229446624, 5687.60186786921, 5271.815486458109, 5514.393416724238, 5571.353607036625, 5599.832005039855, 5601.943980560142], "value": 4257.035526513042, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6381413572014, 719.4999475364622, 722.4762047515552, 724.6413025552349, 746.7386414754835, 13813.51652692156, 13842.723145537084, 13860.029797914163, 13883.583492495241, 15644.296006679699, 14971.625709357271, 15090.849091509082, 15103.54689359321, 15115.546533604009, 15162.393504259844, 669.8626781509803, 785.140271402813, 790.328221012138, 809.8542262392767, 815.502543448496, 811.9594020298962, 813.8575749243888, 813.9633716482742, 814.5764202614637, 815.6288888555692, 849.0787495567317, 853.8292341531718, 856.002756788344, 857.0443954990203, 857.4683696108422, 700.2857085772534, 721.7618185998634, 722.4220623501178, 820.8932838730926, 848.8472075026493, 620.4447019917194, 624.9062640603901, 625.6371304643296, 625.8497960489495, 626.1515646299245, 14928.402863885432, 14990.400383984628, 14990.925045374703, 15002.0, 15007.399704011827, 14676.633084172874, 14887.627809304746, 14929.477468288665, 14935.701285974328, 14966.251687415586, 13003.169572107785, 13218.801717974238, 13279.203247805066, 13297.601071967856, 13500.662483437894, 4459.620932220765, 4485.192665320241, 4550.931736023962, 4583.1062942726185, 4595.149897269016, 83287.99999999939, 83406.58296708539, 84001.0, 84107.5794621029, 84820.57589712074, 11684.824727629093, 11814.881851181379, 11933.820992685116, 12090.032797376189, 12325.198862073936, 22578.661320080213, 22600.66099008516, 22636.434089147904, 22674.43313917165, 22724.318270451913, 8499.83000340002, 8583.742487725378, 8656.0, 8697.869531957025, 8846.292296616384, 4569.885752856205, 4823.927641085386, 4892.315075889378, 5019.498050195024, 5169.71566563835, 3497.0732755819727, 3632.1282892105705, 3642.18050938542, 3642.4718415829557, 3645.216278500135, 598.9371116032754, 609.9542534309855, 610.3957082488305, 620.2618883528587, 620.3269452643842, 673.2594146438878, 697.400235797214, 697.5221972948547, 725.9491835571565, 727.2981572782185, 8544.273736732448, 9091.590878410487, 9118.726438206864, 9214.677486287948, 9247.352685311964, 571.7912961769008, 589.2545929399313, 628.7736414890636, 680.618853442074, 690.5752961928378, 4444.355568442557, 4664.580187783048, 4716.858494245178, 4716.976415117902, 4740.95259047412, 66966.0, 69069.65465172642, 69517.3048269521, 70306.64846675734, 87821.56089219514, 97162.0, 97382.51308743411, 97975.0, 100188.49905750425, 101091.0, 85005.0, 85860.57069714612, 86053.0, 86779.0, 87809.99999999872, 8595.65617375323, 8632.266257368194, 8722.345824063093, 8740.073744993533, 8863.734087977371, 48884.2667359997, 50232.49767502278, 50526.747366262935, 50915.74542127266, 52029.47970520398, 67832.0, 75089.62455187688, 76239.61880190564, 76489.23510765044, 78555.60722196352, 11124.332540047299, 11361.334138393957, 11545.884541154817, 11634.952854243158, 11700.824487632523, 12085.818712719492, 12174.0, 12278.386080696107, 12364.258144511357, 12403.503859845867, 11645.6506304811, 11967.700807479883, 12026.75946481083, 12298.877011230132, 12299.569515066929, 11519.322077625644, 11523.250988685708, 11741.47163377633, 11748.941255293668, 11786.41067946582, 11582.478788454708, 11845.5854045108, 11855.17013809025, 11892.345920974429, 11986.700332491757, 9315.0, 9490.027445579302, 9627.759306017406, 9730.854037189589, 9950.502474876372, 9405.529723513933, 9407.0, 9751.658691945748, 10092.545835437277, 10102.646407376], "value": 7255.657033503033, "unit": "score"}], "name": "dromaeo_css", "value": 7194.920259073784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 111.0, 111.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 131.0, 126.0, 179.0, 126.0, 130.0, 148.0, 125.0, 165.0, 125.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 159.0, 158.0, 159.0, 161.0, 160.0, 161.0, 163.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 84.0, 119.0, 76.0, 77.0, 74.0, 75.0, 102.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 115.0, 86.0, 86.0, 86.0, 121.0, 86.0, 84.0, 130.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 56.0, 55.0, 54.0, 53.0, 53.0, 54.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 80.0, 80.0, 87.0, 83.0, 81.0, 115.0, 81.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 127.0, 121.0, 131.0, 129.0, 123.0, 123.0, 127.0, 125.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 159.0, 158.0, 156.0, 161.0, 161.0, 159.0, 158.0, 164.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 63.0, 64.0, 63.0, 62.0, 66.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1499.8, "alertThreshold": 2.0}]} 05:59:11 INFO - Return code: 0 05:59:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:59:11 ERROR - # TBPL SUCCESS # 05:59:11 INFO - Running post-action listener: _resource_record_post_action 05:59:11 INFO - ##### 05:59:11 INFO - ##### Finished run-tests step (success) 05:59:11 INFO - ##### 05:59:11 INFO - Running post-run listener: _resource_record_post_run 05:59:11 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 3309568; Write bytes: 306397184; Read time: 264; Write time: 267452 05:59:11 INFO - TinderboxPrint: CPU usage
13.1% 05:59:11 INFO - TinderboxPrint: I/O read bytes / time
3,309,568 / 264 05:59:11 INFO - TinderboxPrint: I/O write bytes / time
306,397,184 / 267,452 05:59:11 INFO - TinderboxPrint: CPU idle
8,279.4 (86.9%) 05:59:11 INFO - TinderboxPrint: CPU user
1,198.7 (12.6%) 05:59:11 INFO - TinderboxPrint: Swap in / out
0 / 0 05:59:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 18366464; Read time: 0; Write time: 6916 05:59:11 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 3309568; Write bytes: 288030720; Read time: 264; Write time: 260536 05:59:12 INFO - Running post-run listener: _upload_blobber_files 05:59:12 INFO - Blob upload gear active. 05:59:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:59:12 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 05:59:12 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:59:12 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:59:12 INFO - (blobuploader) - INFO - Open directory for files ... 05:59:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:59:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:59:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:59:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:59:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:59:13 INFO - (blobuploader) - INFO - Done attempting. 05:59:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:59:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:59:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:59:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:59:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:59:14 INFO - (blobuploader) - INFO - Done attempting. 05:59:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:59:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:59:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:59:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:59:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:59:17 INFO - (blobuploader) - INFO - Done attempting. 05:59:17 INFO - (blobuploader) - INFO - Iteration through files over. 05:59:17 INFO - Return code: 0 05:59:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:59:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:59:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e52e7ee906dbfaba661dcad83106b8b30f062e80bbec6acd202c60428ae0ecdf7fe3a5b369edfb7e44d5e0472b8eedc4838f13099e30620d05b85d3b573c55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80a82cb309e76ecde9637cf1cced200aea29f98d2ba4c5d59710bb8860895815ef4243cdf0d00e3fd64df476e2a419859f325199f9963ba6ee5831f90cf420b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508f2175d7518a9230594c2fe277e237bfb0c97ea71bfb40505613de388c805d6d6b02f82298a31713b6ec1eee0781283f5033ca77f92a683d3e635cbbf504aa"} 05:59:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:59:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:59:17 INFO - Contents: 05:59:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e52e7ee906dbfaba661dcad83106b8b30f062e80bbec6acd202c60428ae0ecdf7fe3a5b369edfb7e44d5e0472b8eedc4838f13099e30620d05b85d3b573c55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80a82cb309e76ecde9637cf1cced200aea29f98d2ba4c5d59710bb8860895815ef4243cdf0d00e3fd64df476e2a419859f325199f9963ba6ee5831f90cf420b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508f2175d7518a9230594c2fe277e237bfb0c97ea71bfb40505613de388c805d6d6b02f82298a31713b6ec1eee0781283f5033ca77f92a683d3e635cbbf504aa"} 05:59:17 INFO - Running post-run listener: copy_logs_to_upload_dir 05:59:17 INFO - Copying logs to upload dir... 05:59:17 INFO - mkdir: /builds/slave/test/build/upload/logs 05:59:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.802443 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-11 05:59:17.714736) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-11 05:59:17.717929) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e52e7ee906dbfaba661dcad83106b8b30f062e80bbec6acd202c60428ae0ecdf7fe3a5b369edfb7e44d5e0472b8eedc4838f13099e30620d05b85d3b573c55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80a82cb309e76ecde9637cf1cced200aea29f98d2ba4c5d59710bb8860895815ef4243cdf0d00e3fd64df476e2a419859f325199f9963ba6ee5831f90cf420b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508f2175d7518a9230594c2fe277e237bfb0c97ea71bfb40505613de388c805d6d6b02f82298a31713b6ec1eee0781283f5033ca77f92a683d3e635cbbf504aa"} build_url:https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/RTc26YESQJaHqJ9terbOSg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e52e7ee906dbfaba661dcad83106b8b30f062e80bbec6acd202c60428ae0ecdf7fe3a5b369edfb7e44d5e0472b8eedc4838f13099e30620d05b85d3b573c55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80a82cb309e76ecde9637cf1cced200aea29f98d2ba4c5d59710bb8860895815ef4243cdf0d00e3fd64df476e2a419859f325199f9963ba6ee5831f90cf420b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/508f2175d7518a9230594c2fe277e237bfb0c97ea71bfb40505613de388c805d6d6b02f82298a31713b6ec1eee0781283f5033ca77f92a683d3e635cbbf504aa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-11 05:59:17.742629) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:59:17.742955) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1465647952.739033-103605029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:59:17.828130) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-11 05:59:17.828434) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-11 05:59:17.828737) ========= ========= Total master_lag: 0.17 =========