builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1465481362.62 results: success (0) buildid: 20160609043019 builduid: 59348f7d24ef462488ba01a59869c56a revision: cb493102af8a50946ac601a86cc3d076703faa57 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.616161) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.616683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.617031) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.632334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.632752) ========= 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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-09 07:09:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.1M=0s 2016-06-09 07:09:22 (70.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103289 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.784168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.784529) ========= 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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013515 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.886721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:22.887082) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb493102af8a50946ac601a86cc3d076703faa57 --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 cb493102af8a50946ac601a86cc3d076703faa57 --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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-09 07:09:23,128 truncating revision to first 12 chars 2016-06-09 07:09:23,128 Setting DEBUG logging. 2016-06-09 07:09:23,128 attempt 1/10 2016-06-09 07:09:23,128 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb493102af8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 07:09:23,441 unpacking tar archive at: mozilla-central-cb493102af8a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658920 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:23.559126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:23.559487) ========= script_repo_revision: cb493102af8a50946ac601a86cc3d076703faa57 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:23.559881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:23.560144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 07:09:23.571107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-09 07:09:23.571508) ========= /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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:09:23 INFO - MultiFileLogger online at 20160609 07:09:23 in /builds/slave/test 07:09:23 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 07:09:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:09:23 INFO - {'append_to_log': False, 07:09:23 INFO - 'base_work_dir': '/builds/slave/test', 07:09:23 INFO - 'blob_upload_branch': 'Firefox', 07:09:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:09:23 INFO - 'branch': 'Firefox', 07:09:23 INFO - 'buildbot_json_path': 'buildprops.json', 07:09:23 INFO - 'config_files': ('talos/linux_config.py',), 07:09:23 INFO - 'default_actions': ('clobber', 07:09:23 INFO - 'read-buildbot-config', 07:09:23 INFO - 'download-and-extract', 07:09:23 INFO - 'populate-webroot', 07:09:23 INFO - 'create-virtualenv', 07:09:23 INFO - 'install', 07:09:23 INFO - 'run-tests'), 07:09:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:09:23 INFO - 'download_minidump_stackwalk': True, 07:09:23 INFO - 'download_symbols': 'ondemand', 07:09:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:09:23 INFO - 'tooltool.py': '/tools/tooltool.py', 07:09:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:09:23 INFO - '/tools/misc-python/virtualenv.py')}, 07:09:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:09:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:09:23 INFO - 'installer_path': 'installer.exe', 07:09:23 INFO - 'log_level': 'info', 07:09:23 INFO - 'log_name': 'talos', 07:09:23 INFO - 'log_to_console': True, 07:09:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:09:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:09:23 INFO - 'opt_config_files': (), 07:09:23 INFO - 'pip_index': False, 07:09:23 INFO - 'sps_profile': False, 07:09:23 INFO - 'sps_profile_interval': 0, 07:09:23 INFO - 'suite': 'dromaeojs', 07:09:23 INFO - 'system_bits': '32', 07:09:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:09:23 INFO - 'title': 'talos-linux64-ix-032', 07:09:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:09:23 INFO - 'use_talos_json': True, 07:09:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:09:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:09:23 INFO - 'work_dir': 'build'} 07:09:23 INFO - ##### 07:09:23 INFO - ##### Running clobber step. 07:09:23 INFO - ##### 07:09:23 INFO - Running pre-action listener: _resource_record_pre_action 07:09:23 INFO - Running main action method: clobber 07:09:23 INFO - rmtree: /builds/slave/test/build 07:09:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:09:24 INFO - Running post-action listener: _resource_record_post_action 07:09:24 INFO - ##### 07:09:24 INFO - ##### Finished clobber step (success) 07:09:24 INFO - ##### 07:09:24 INFO - ##### 07:09:24 INFO - ##### Running read-buildbot-config step. 07:09:24 INFO - ##### 07:09:24 INFO - Running pre-action listener: _resource_record_pre_action 07:09:24 INFO - Running main action method: read_buildbot_config 07:09:24 INFO - Using buildbot properties: 07:09:24 INFO - { 07:09:24 INFO - "project": "", 07:09:24 INFO - "product": "firefox", 07:09:24 INFO - "script_repo_revision": "production", 07:09:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:09:24 INFO - "repository": "", 07:09:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:09:24 INFO - "buildid": "20160609043019", 07:09:24 INFO - "pgo_build": "True", 07:09:24 INFO - "basedir": "/builds/slave/test", 07:09:24 INFO - "buildnumber": 782, 07:09:24 INFO - "slavename": "talos-linux64-ix-032", 07:09:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:09:24 INFO - "platform": "ubuntu64_hw", 07:09:24 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:09:24 INFO - "branch": "mozilla-central", 07:09:24 INFO - "repo_path": "mozilla-central", 07:09:24 INFO - "revision": "cb493102af8a50946ac601a86cc3d076703faa57", 07:09:24 INFO - "stage_platform": "linux64-pgo", 07:09:24 INFO - "builduid": "59348f7d24ef462488ba01a59869c56a", 07:09:24 INFO - "slavebuilddir": "test" 07:09:24 INFO - } 07:09:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:09:24 INFO - Running post-action listener: _resource_record_post_action 07:09:24 INFO - ##### 07:09:24 INFO - ##### Finished read-buildbot-config step (success) 07:09:24 INFO - ##### 07:09:24 INFO - ##### 07:09:24 INFO - ##### Running download-and-extract step. 07:09:24 INFO - ##### 07:09:24 INFO - Running pre-action listener: _resource_record_pre_action 07:09:24 INFO - Running main action method: download_and_extract 07:09:24 INFO - mkdir: /builds/slave/test/build/tests 07:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:24 INFO - https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:09:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:09:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:09:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:25 INFO - Downloaded 1361 bytes. 07:09:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:09:25 INFO - Using the following test package requirements: 07:09:25 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:09:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:09:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:09:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'jsshell-linux-x86_64.zip'], 07:09:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:09:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:09:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:09:25 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:09:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:09:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:09:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:09: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')]} 07:09:25 INFO - https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:09:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:09:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:09:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:27 INFO - Downloaded 24452300 bytes. 07:09:27 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'] 07:09:27 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 07:09:29 INFO - Return code: 0 07:09:29 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 07:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:29 INFO - https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:09:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:09:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:09:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:31 INFO - Downloaded 24452300 bytes. 07:09:31 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'] 07:09:31 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 07:09:32 INFO - Return code: 0 07:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:32 INFO - https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:09:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:09:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:09:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:33 INFO - Downloaded 10833377 bytes. 07:09:33 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'] 07:09:33 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 07:09:34 INFO - Return code: 0 07:09: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')]} 07:09:34 INFO - https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:09:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:09:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:09:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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 07:09:35 INFO - Downloaded 59334645 bytes. 07:09:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:09:35 INFO - mkdir: /builds/slave/test/properties 07:09:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:09:35 INFO - Writing to file /builds/slave/test/properties/build_url 07:09:35 INFO - Contents: 07:09:35 INFO - build_url:https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:09:36 INFO - Running post-action listener: _resource_record_post_action 07:09:36 INFO - Running post-action listener: set_extra_try_arguments 07:09:36 INFO - ##### 07:09:36 INFO - ##### Finished download-and-extract step (success) 07:09:36 INFO - ##### 07:09:36 INFO - ##### 07:09:36 INFO - ##### Running populate-webroot step. 07:09:36 INFO - ##### 07:09:36 INFO - Running pre-action listener: _resource_record_pre_action 07:09:36 INFO - Running main action method: populate_webroot 07:09:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:09:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:09:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:09:36 INFO - '--tppagecycles', 07:09:36 INFO - '7'], 07:09:36 INFO - 'tests': ['tsvgm']}}, 07:09:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:09:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:09:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:09:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:09:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['damp', 'tps']}, 07:09:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'tests': ['damp', 'tps']}, 07:09:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['dromaeo_dom']}, 07:09:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:09:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'other-e10s': {'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['a11yr', 07:09:36 INFO - 'ts_paint', 07:09:36 INFO - 'tpaint', 07:09:36 INFO - 'sessionrestore', 07:09:36 INFO - 'sessionrestore_no_auto_restore', 07:09:36 INFO - 'tabpaint']}, 07:09:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['tsvgx', 07:09:36 INFO - 'tsvgr_opacity', 07:09:36 INFO - 'tart', 07:09:36 INFO - 'tscrollx', 07:09:36 INFO - 'cart']}, 07:09:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:09:36 INFO - 'tsvgr_opacity', 07:09:36 INFO - 'tart', 07:09:36 INFO - 'tscrollx', 07:09:36 INFO - 'cart']}, 07:09:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'talos_options': ['--disable-e10s'], 07:09:36 INFO - 'tests': ['tp5o']}, 07:09:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'tests': ['tp5o']}, 07:09:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'talos_options': ['--disable-e10s', 07:09:36 INFO - '--xperf_path', 07:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:36 INFO - 'tests': ['tp5n']}, 07:09:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:36 INFO - 'talos_options': ['--xperf_path', 07:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:36 INFO - 'tests': ['tp5n']}}, 07:09:36 INFO - 'talos.zip': {'path': '', 07:09:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:09:36 INFO - Running post-action listener: _resource_record_post_action 07:09:36 INFO - ##### 07:09:36 INFO - ##### Finished populate-webroot step (success) 07:09:36 INFO - ##### 07:09:36 INFO - ##### 07:09:36 INFO - ##### Running create-virtualenv step. 07:09:36 INFO - ##### 07:09:36 INFO - Running pre-action listener: _resource_record_pre_action 07:09:36 INFO - Running main action method: create_virtualenv 07:09:36 INFO - Creating virtualenv /builds/slave/test/build/venv 07:09:36 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 07:09:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:09:36 INFO - Using real prefix '/usr' 07:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:09:36 INFO - Installing distribute.............................................................................................................................................................................................done. 07:09:38 INFO - Installing pip.................done. 07:09:38 INFO - Return code: 0 07:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:38 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:38 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 07:09:38 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 07:09:38 INFO - Using env: {'DISPLAY': ':0', 07:09:38 INFO - 'HOME': '/home/cltbld', 07:09:38 INFO - 'LANG': 'en_US.UTF-8', 07:09:38 INFO - 'LANGUAGE': 'en_US:en', 07:09:38 INFO - 'LOGNAME': 'cltbld', 07:09:38 INFO - 'MAIL': '/var/mail/cltbld', 07:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:38 INFO - 'MOZ_NO_REMOTE': '1', 07:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:38 INFO - 'NO_EM_RESTART': '1', 07:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:38 INFO - 'PWD': '/builds/slave/test', 07:09:38 INFO - 'SHELL': '/bin/bash', 07:09:38 INFO - 'SHLVL': '1', 07:09:38 INFO - 'TERM': 'linux', 07:09:38 INFO - 'TMOUT': '86400', 07:09:38 INFO - 'USER': 'cltbld', 07:09:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:38 INFO - '_': '/tools/buildbot/bin/python'} 07:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:38 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 07:09:38 INFO - Cleaning up... 07:09:38 INFO - Return code: 0 07:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:38 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:38 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 07:09:38 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 07:09:38 INFO - Using env: {'DISPLAY': ':0', 07:09:38 INFO - 'HOME': '/home/cltbld', 07:09:38 INFO - 'LANG': 'en_US.UTF-8', 07:09:38 INFO - 'LANGUAGE': 'en_US:en', 07:09:38 INFO - 'LOGNAME': 'cltbld', 07:09:38 INFO - 'MAIL': '/var/mail/cltbld', 07:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:38 INFO - 'MOZ_NO_REMOTE': '1', 07:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:38 INFO - 'NO_EM_RESTART': '1', 07:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:38 INFO - 'PWD': '/builds/slave/test', 07:09:38 INFO - 'SHELL': '/bin/bash', 07:09:38 INFO - 'SHLVL': '1', 07:09:38 INFO - 'TERM': 'linux', 07:09:38 INFO - 'TMOUT': '86400', 07:09:38 INFO - 'USER': 'cltbld', 07:09:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:38 INFO - '_': '/tools/buildbot/bin/python'} 07:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:39 INFO - Downloading/unpacking psutil>=3.1.1 07:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:41 INFO - Installing collected packages: psutil 07:09:41 INFO - Running setup.py install for psutil 07:09:41 INFO - building 'psutil._psutil_linux' extension 07:09:41 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 07:09:41 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 07:09:41 INFO - building 'psutil._psutil_posix' extension 07:09:41 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 07:09:41 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 07:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:41 INFO - Successfully installed psutil 07:09:41 INFO - Cleaning up... 07:09:41 INFO - Return code: 0 07:09:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:09: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')]} 07:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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')]} 07:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:09:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:09:41 INFO - Using env: {'DISPLAY': ':0', 07:09:41 INFO - 'HOME': '/home/cltbld', 07:09:41 INFO - 'LANG': 'en_US.UTF-8', 07:09:41 INFO - 'LANGUAGE': 'en_US:en', 07:09:41 INFO - 'LOGNAME': 'cltbld', 07:09:41 INFO - 'MAIL': '/var/mail/cltbld', 07:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:41 INFO - 'MOZ_NO_REMOTE': '1', 07:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:41 INFO - 'NO_EM_RESTART': '1', 07:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:41 INFO - 'PWD': '/builds/slave/test', 07:09:41 INFO - 'SHELL': '/bin/bash', 07:09:41 INFO - 'SHLVL': '1', 07:09:41 INFO - 'TERM': 'linux', 07:09:41 INFO - 'TMOUT': '86400', 07:09:41 INFO - 'USER': 'cltbld', 07:09:41 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:41 INFO - '_': '/tools/buildbot/bin/python'} 07:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:09:43 INFO - Installing collected packages: mozsystemmonitor 07:09:43 INFO - Running setup.py install for mozsystemmonitor 07:09:43 INFO - Successfully installed mozsystemmonitor 07:09:43 INFO - Cleaning up... 07:09:44 INFO - Return code: 0 07:09:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:09: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')]} 07:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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')]} 07:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:44 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:44 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 07:09:44 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 07:09:44 INFO - Using env: {'DISPLAY': ':0', 07:09:44 INFO - 'HOME': '/home/cltbld', 07:09:44 INFO - 'LANG': 'en_US.UTF-8', 07:09:44 INFO - 'LANGUAGE': 'en_US:en', 07:09:44 INFO - 'LOGNAME': 'cltbld', 07:09:44 INFO - 'MAIL': '/var/mail/cltbld', 07:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:44 INFO - 'MOZ_NO_REMOTE': '1', 07:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:44 INFO - 'NO_EM_RESTART': '1', 07:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:44 INFO - 'PWD': '/builds/slave/test', 07:09:44 INFO - 'SHELL': '/bin/bash', 07:09:44 INFO - 'SHLVL': '1', 07:09:44 INFO - 'TERM': 'linux', 07:09:44 INFO - 'TMOUT': '86400', 07:09:44 INFO - 'USER': 'cltbld', 07:09:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:44 INFO - '_': '/tools/buildbot/bin/python'} 07:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:44 INFO - Downloading/unpacking jsonschema==2.5.1 07:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:09:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 INFO - Downloading functools32-3.2.3-2.tar.gz 07:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:09:46 INFO - warning: no files found matching '*.txt' 07:09:46 INFO - no previously-included directories found matching 'build' 07:09:46 INFO - no previously-included directories found matching 'dist' 07:09:46 INFO - no previously-included directories found matching '.git*' 07:09:46 INFO - Installing collected packages: jsonschema, functools32 07:09:46 INFO - Running setup.py install for functools32 07:09:46 INFO - warning: no files found matching '*.txt' 07:09:46 INFO - no previously-included directories found matching 'build' 07:09:46 INFO - no previously-included directories found matching 'dist' 07:09:46 INFO - no previously-included directories found matching '.git*' 07:09:46 INFO - Successfully installed jsonschema functools32 07:09:46 INFO - Cleaning up... 07:09:46 INFO - Return code: 0 07:09:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:09: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')]} 07:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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')]} 07:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:46 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:46 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 07:09:46 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 07:09:46 INFO - Using env: {'DISPLAY': ':0', 07:09:46 INFO - 'HOME': '/home/cltbld', 07:09:46 INFO - 'LANG': 'en_US.UTF-8', 07:09:46 INFO - 'LANGUAGE': 'en_US:en', 07:09:46 INFO - 'LOGNAME': 'cltbld', 07:09:46 INFO - 'MAIL': '/var/mail/cltbld', 07:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:46 INFO - 'MOZ_NO_REMOTE': '1', 07:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:46 INFO - 'NO_EM_RESTART': '1', 07:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:46 INFO - 'PWD': '/builds/slave/test', 07:09:46 INFO - 'SHELL': '/bin/bash', 07:09:46 INFO - 'SHLVL': '1', 07:09:46 INFO - 'TERM': 'linux', 07:09:46 INFO - 'TMOUT': '86400', 07:09:46 INFO - 'USER': 'cltbld', 07:09:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:46 INFO - '_': '/tools/buildbot/bin/python'} 07:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:46 INFO - Downloading/unpacking blobuploader==1.2.4 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:46 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 07:09:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:09:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:09:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:49 INFO - Downloading docopt-0.6.1.tar.gz 07:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:09:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:09:49 INFO - Running setup.py install for blobuploader 07:09:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:09:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:09:49 INFO - Running setup.py install for requests 07:09:49 INFO - Running setup.py install for docopt 07:09:49 INFO - Successfully installed blobuploader requests docopt 07:09:49 INFO - Cleaning up... 07:09:50 INFO - Return code: 0 07:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:50 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')]} 07:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:50 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')]} 07:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:50 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:50 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 07:09:50 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 07:09:50 INFO - Using env: {'DISPLAY': ':0', 07:09:50 INFO - 'HOME': '/home/cltbld', 07:09:50 INFO - 'LANG': 'en_US.UTF-8', 07:09:50 INFO - 'LANGUAGE': 'en_US:en', 07:09:50 INFO - 'LOGNAME': 'cltbld', 07:09:50 INFO - 'MAIL': '/var/mail/cltbld', 07:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:50 INFO - 'MOZ_NO_REMOTE': '1', 07:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:50 INFO - 'NO_EM_RESTART': '1', 07:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:50 INFO - 'PWD': '/builds/slave/test', 07:09:50 INFO - 'SHELL': '/bin/bash', 07:09:50 INFO - 'SHLVL': '1', 07:09:50 INFO - 'TERM': 'linux', 07:09:50 INFO - 'TMOUT': '86400', 07:09:50 INFO - 'USER': 'cltbld', 07:09:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:50 INFO - '_': '/tools/buildbot/bin/python'} 07:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:50 INFO - Running setup.py (path:/tmp/pip-TPHYxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:50 INFO - Running setup.py (path:/tmp/pip-Xrj8vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:50 INFO - Running setup.py (path:/tmp/pip-_Q6s5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:50 INFO - Running setup.py (path:/tmp/pip-9DoF0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:50 INFO - Running setup.py (path:/tmp/pip-TASP7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:50 INFO - Running setup.py (path:/tmp/pip-0acV2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:50 INFO - Running setup.py (path:/tmp/pip-b67qyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:50 INFO - Running setup.py (path:/tmp/pip-ezpI0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:50 INFO - Running setup.py (path:/tmp/pip-m84Y2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:50 INFO - Running setup.py (path:/tmp/pip-8i7MJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:50 INFO - Running setup.py (path:/tmp/pip-8jbYKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:51 INFO - Running setup.py (path:/tmp/pip-9ghFkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:51 INFO - Running setup.py (path:/tmp/pip-k1cinj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:51 INFO - Running setup.py (path:/tmp/pip-2cL6Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:51 INFO - Running setup.py (path:/tmp/pip-R_upsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:51 INFO - Running setup.py (path:/tmp/pip-_zJQka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:51 INFO - Running setup.py (path:/tmp/pip-0A7Bqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:09:51 INFO - Running setup.py install for manifestparser 07:09:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:09:51 INFO - Running setup.py install for mozcrash 07:09:51 INFO - Running setup.py install for mozdebug 07:09:51 INFO - Running setup.py install for mozdevice 07:09:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:09:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:09:51 INFO - Running setup.py install for mozfile 07:09:51 INFO - Running setup.py install for mozhttpd 07:09:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozinfo 07:09:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozInstall 07:09:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozleak 07:09:52 INFO - Running setup.py install for mozlog 07:09:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for moznetwork 07:09:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozprocess 07:09:52 INFO - Running setup.py install for mozprofile 07:09:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozrunner 07:09:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:09:52 INFO - Running setup.py install for mozscreenshot 07:09:53 INFO - Running setup.py install for moztest 07:09:53 INFO - Running setup.py install for mozversion 07:09:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:09:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:09:53 INFO - Cleaning up... 07:09:53 INFO - Return code: 0 07:09:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:53 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')]} 07:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:53 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')]} 07:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:53 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:53 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 07:09:53 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 07:09:53 INFO - Using env: {'DISPLAY': ':0', 07:09:53 INFO - 'HOME': '/home/cltbld', 07:09:53 INFO - 'LANG': 'en_US.UTF-8', 07:09:53 INFO - 'LANGUAGE': 'en_US:en', 07:09:53 INFO - 'LOGNAME': 'cltbld', 07:09:53 INFO - 'MAIL': '/var/mail/cltbld', 07:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:53 INFO - 'MOZ_NO_REMOTE': '1', 07:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:53 INFO - 'NO_EM_RESTART': '1', 07:09:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:53 INFO - 'PWD': '/builds/slave/test', 07:09:53 INFO - 'SHELL': '/bin/bash', 07:09:53 INFO - 'SHLVL': '1', 07:09:53 INFO - 'TERM': 'linux', 07:09:53 INFO - 'TMOUT': '86400', 07:09:53 INFO - 'USER': 'cltbld', 07:09:53 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:53 INFO - '_': '/tools/buildbot/bin/python'} 07:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:53 INFO - Running setup.py (path:/tmp/pip-IM4IZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:53 INFO - Running setup.py (path:/tmp/pip-v6GBts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:53 INFO - Running setup.py (path:/tmp/pip-y4i6mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:53 INFO - Running setup.py (path:/tmp/pip-69h0x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:53 INFO - Running setup.py (path:/tmp/pip-ClEwMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:53 INFO - Running setup.py (path:/tmp/pip-4_VdaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:53 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)) 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:53 INFO - Running setup.py (path:/tmp/pip-FPVidE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:54 INFO - Running setup.py (path:/tmp/pip-X3tX2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:54 INFO - Running setup.py (path:/tmp/pip-izCnzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:54 INFO - Running setup.py (path:/tmp/pip-82aJCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:54 INFO - Running setup.py (path:/tmp/pip-zBCv4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:54 INFO - Running setup.py (path:/tmp/pip-YWFUvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:54 INFO - Running setup.py (path:/tmp/pip-eCei1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:54 INFO - Running setup.py (path:/tmp/pip-NKPSXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:54 INFO - Running setup.py (path:/tmp/pip-GN33En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:54 INFO - Running setup.py (path:/tmp/pip-nf8bQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:54 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)) 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:54 INFO - Running setup.py (path:/tmp/pip-71E8Vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:54 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)) 07:09:54 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)) 07:09:54 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)) 07:09:54 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)) 07:09:54 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)) 07:09:54 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)) 07:09:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:09: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 07:09: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 07:09: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 07:09: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 07:09: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 07:09: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 07:09:56 INFO - Downloading blessings-1.6.tar.gz 07:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:09:56 INFO - Installing collected packages: blessings 07:09:56 INFO - Running setup.py install for blessings 07:09:56 INFO - Successfully installed blessings 07:09:56 INFO - Cleaning up... 07:09:57 INFO - Return code: 0 07:09:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:09:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:09:57 INFO - Reading from file tmpfile_stdout 07:09:57 INFO - Current package versions: 07:09:57 INFO - argparse == 1.2.1 07:09:57 INFO - blessings == 1.6 07:09:57 INFO - blobuploader == 1.2.4 07:09:57 INFO - docopt == 0.6.1 07:09:57 INFO - functools32 == 3.2.3-2 07:09:57 INFO - jsonschema == 2.5.1 07:09:57 INFO - manifestparser == 1.1 07:09:57 INFO - mozInstall == 1.12 07:09:57 INFO - mozcrash == 0.17 07:09:57 INFO - mozdebug == 0.1 07:09:57 INFO - mozdevice == 0.48 07:09:57 INFO - mozfile == 1.2 07:09:57 INFO - mozhttpd == 0.7 07:09:57 INFO - mozinfo == 0.9 07:09:57 INFO - mozleak == 0.1 07:09:57 INFO - mozlog == 3.2 07:09:57 INFO - moznetwork == 0.27 07:09:57 INFO - mozprocess == 0.22 07:09:57 INFO - mozprofile == 0.28 07:09:57 INFO - mozrunner == 6.11 07:09:57 INFO - mozscreenshot == 0.1 07:09:57 INFO - mozsystemmonitor == 0.3 07:09:57 INFO - moztest == 0.7 07:09:57 INFO - mozversion == 1.4 07:09:57 INFO - psutil == 3.1.1 07:09:57 INFO - requests == 1.2.3 07:09:57 INFO - wsgiref == 0.1.2 07:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:57 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')]} 07:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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')]} 07:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:57 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 07:09:57 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 07:09:57 INFO - Using env: {'DISPLAY': ':0', 07:09:57 INFO - 'HOME': '/home/cltbld', 07:09:57 INFO - 'LANG': 'en_US.UTF-8', 07:09:57 INFO - 'LANGUAGE': 'en_US:en', 07:09:57 INFO - 'LOGNAME': 'cltbld', 07:09:57 INFO - 'MAIL': '/var/mail/cltbld', 07:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:57 INFO - 'MOZ_NO_REMOTE': '1', 07:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:57 INFO - 'NO_EM_RESTART': '1', 07:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:57 INFO - 'PWD': '/builds/slave/test', 07:09:57 INFO - 'SHELL': '/bin/bash', 07:09:57 INFO - 'SHLVL': '1', 07:09:57 INFO - 'TERM': 'linux', 07:09:57 INFO - 'TMOUT': '86400', 07:09:57 INFO - 'USER': 'cltbld', 07:09:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:57 INFO - '_': '/tools/buildbot/bin/python'} 07:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 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)) 07:09:57 INFO - Cleaning up... 07:09:57 INFO - Return code: 0 07:09:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:09:57 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')]} 07:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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')]} 07:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:57 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 07:09:57 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 07:09:57 INFO - Using env: {'DISPLAY': ':0', 07:09:57 INFO - 'HOME': '/home/cltbld', 07:09:57 INFO - 'LANG': 'en_US.UTF-8', 07:09:57 INFO - 'LANGUAGE': 'en_US:en', 07:09:57 INFO - 'LOGNAME': 'cltbld', 07:09:57 INFO - 'MAIL': '/var/mail/cltbld', 07:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:57 INFO - 'MOZ_NO_REMOTE': '1', 07:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:57 INFO - 'NO_EM_RESTART': '1', 07:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:57 INFO - 'PWD': '/builds/slave/test', 07:09:57 INFO - 'SHELL': '/bin/bash', 07:09:57 INFO - 'SHLVL': '1', 07:09:57 INFO - 'TERM': 'linux', 07:09:57 INFO - 'TMOUT': '86400', 07:09:57 INFO - 'USER': 'cltbld', 07:09:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:57 INFO - '_': '/tools/buildbot/bin/python'} 07:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:09:57 INFO - Cleaning up... 07:09:57 INFO - Return code: 0 07:09:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:09:57 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')]} 07:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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')]} 07:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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 0x7fa321bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd08350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, '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': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', '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 07:09:57 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 07:09:57 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 07:09:57 INFO - Using env: {'DISPLAY': ':0', 07:09:57 INFO - 'HOME': '/home/cltbld', 07:09:57 INFO - 'LANG': 'en_US.UTF-8', 07:09:57 INFO - 'LANGUAGE': 'en_US:en', 07:09:57 INFO - 'LOGNAME': 'cltbld', 07:09:57 INFO - 'MAIL': '/var/mail/cltbld', 07:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:57 INFO - 'MOZ_NO_REMOTE': '1', 07:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:57 INFO - 'NO_EM_RESTART': '1', 07:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:57 INFO - 'PWD': '/builds/slave/test', 07:09:57 INFO - 'SHELL': '/bin/bash', 07:09:57 INFO - 'SHLVL': '1', 07:09:57 INFO - 'TERM': 'linux', 07:09:57 INFO - 'TMOUT': '86400', 07:09:57 INFO - 'USER': 'cltbld', 07:09:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:57 INFO - '_': '/tools/buildbot/bin/python'} 07:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:57 INFO - Downloading/unpacking flake8 07:09:57 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 07:09:57 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 07:09:57 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 07:09:57 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 07:09:57 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 07:09:57 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 07:09:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:09:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:09:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:10:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:10: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 07:10: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 07:10: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 07:10: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 07:10: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 07:10: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 07:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:10:00 INFO - no previously-included directories found matching 'docs/_build' 07:10:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:10:00 INFO - Running setup.py install for pep8 07:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:10:00 INFO - no previously-included directories found matching 'docs/_build' 07:10:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:10:00 INFO - Cleaning up... 07:10:00 INFO - Return code: 0 07:10:00 INFO - Running post-action listener: _resource_record_post_action 07:10:00 INFO - Running post-action listener: _start_resource_monitoring 07:10:00 INFO - Starting resource monitoring. 07:10:00 INFO - ##### 07:10:00 INFO - ##### Finished create-virtualenv step (success) 07:10:00 INFO - ##### 07:10:00 INFO - ##### 07:10:00 INFO - ##### Running install step. 07:10:00 INFO - ##### 07:10:00 INFO - Running pre-action listener: _resource_record_pre_action 07:10:00 INFO - Running main action method: install 07:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:10:00 INFO - Reading from file tmpfile_stdout 07:10:00 INFO - Detecting whether we're running mozinstall >=1.0... 07:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:10:01 INFO - Reading from file tmpfile_stdout 07:10:01 INFO - Output received: 07:10:01 INFO - Usage: mozinstall [options] installer 07:10:01 INFO - Options: 07:10:01 INFO - -h, --help show this help message and exit 07:10:01 INFO - -d DEST, --destination=DEST 07:10:01 INFO - Directory to install application into. [default: 07:10:01 INFO - "/builds/slave/test"] 07:10:01 INFO - --app=APP Application being installed. [default: firefox] 07:10:01 INFO - mkdir: /builds/slave/test/build/application 07:10:01 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'] 07:10:01 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 07:10:18 INFO - Reading from file tmpfile_stdout 07:10:18 INFO - Output received: 07:10:18 INFO - /builds/slave/test/build/application/firefox/firefox 07:10:18 INFO - Running post-action listener: _resource_record_post_action 07:10:18 INFO - ##### 07:10:18 INFO - ##### Finished install step (success) 07:10:18 INFO - ##### 07:10:18 INFO - ##### 07:10:18 INFO - ##### Running run-tests step. 07:10:18 INFO - ##### 07:10:18 INFO - Running pre-action listener: _resource_record_pre_action 07:10:18 INFO - Running main action method: run_tests 07:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:10:18 INFO - Python 2.7.3 07:10:18 INFO - Return code: 0 07:10:18 INFO - grabbing minidump binary from tooltool 07:10:18 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')]} 07:10:18 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 0xd971c0>, '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 0xd980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd98550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:10:18 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 07:10:18 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 07:10:18 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 07:10:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:10:18 INFO - Return code: 0 07:10:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:10:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:10:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:10:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:10:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:10:19 INFO - Return code: 0 07:10:19 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/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 07:10:19 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/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 07:10:19 INFO - Using env: {'DISPLAY': ':0', 07:10:19 INFO - 'HOME': '/home/cltbld', 07:10:19 INFO - 'LANG': 'en_US.UTF-8', 07:10:19 INFO - 'LANGUAGE': 'en_US:en', 07:10:19 INFO - 'LOGNAME': 'cltbld', 07:10:19 INFO - 'MAIL': '/var/mail/cltbld', 07:10:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:10:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:19 INFO - 'MOZ_NO_REMOTE': '1', 07:10:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:10:19 INFO - 'NO_EM_RESTART': '1', 07:10:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:19 INFO - 'PWD': '/builds/slave/test', 07:10:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:10:19 INFO - 'SHELL': '/bin/bash', 07:10:19 INFO - 'SHLVL': '1', 07:10:19 INFO - 'TERM': 'linux', 07:10:19 INFO - 'TMOUT': '86400', 07:10:19 INFO - 'USER': 'cltbld', 07:10:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465481358.883973-1650758648', 07:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:19 INFO - '_': '/tools/buildbot/bin/python'} 07:10:19 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/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 07:10:20 INFO - mozversion application_buildid: 20160609043019 07:10:20 INFO - mozversion application_changeset: cb493102af8a50946ac601a86cc3d076703faa57 07:10:20 INFO - mozversion application_display_name: Nightly 07:10:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:10:20 INFO - mozversion application_name: Firefox 07:10:20 INFO - mozversion application_remotingname: firefox 07:10:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:10:20 INFO - mozversion application_vendor: Mozilla 07:10:20 INFO - mozversion application_version: 50.0a1 07:10:20 INFO - mozversion platform_buildid: 20160609043019 07:10:20 INFO - mozversion platform_changeset: cb493102af8a50946ac601a86cc3d076703faa57 07:10:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:10:20 INFO - mozversion platform_version: 50.0a1 07:10:20 INFO - using testdate: 1465481420 07:10:20 INFO - actual date: 1465481420 07:10:20 INFO - starting webserver on 'localhost:36965' 07:10:20 INFO - SUITE-START | Running 2 tests 07:10:20 INFO - TEST-START | dromaeo_css 07:10:20 INFO - operating with platform_type : linux_ 07:10:20 INFO - Initialising browser for dromaeo_css test... 07:10:20 INFO - TEST-INFO | started process 3125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRLOiI/profile http://localhost:36965/getInfo.html) 07:10:25 INFO - PROCESS | 3125 | __metrics Screen width/height:1600/1200 07:10:25 INFO - PROCESS | 3125 | colorDepth:24 07:10:25 INFO - PROCESS | 3125 | Browser inner width/height: 1024/697 07:10:25 INFO - PROCESS | 3125 | __metrics 07:10:26 ERROR - PROCESS | 3125 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:10:26 INFO - PROCESS | 3125 | console.error: PushService: 07:10:26 INFO - PROCESS | 3125 | stateChangeProcessEnqueue: Error transitioning state 07:10:26 INFO - PROCESS | 3125 | UnknownError 07:10:27 INFO - TEST-INFO | 3125: exit 0 07:10:27 INFO - Browser initialized. 07:10:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:10:27 INFO - TEST-INFO | started process 3212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRLOiI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:10:39 INFO - PROCESS | 3212 | RSS: Main: 172838912 07:10:39 INFO - PROCESS | 3212 | 07:11:30 INFO - PROCESS | 3212 | 1465481490143 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] 07:13:39 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36965/tests/dromaeo/cssquery-ext.html) 07:13:40 INFO - PROCESS | 3212 | RSS: Main: 301400064 07:13:40 INFO - PROCESS | 3212 | 07:16:43 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-ext.html (next: http://localhost:36965/tests/dromaeo/cssquery-jquery.html) 07:16:43 INFO - PROCESS | 3212 | RSS: Main: 265289728 07:16:43 INFO - PROCESS | 3212 | 07:19:46 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36965/tests/dromaeo/cssquery-mootools.html) 07:19:46 INFO - PROCESS | 3212 | RSS: Main: 261464064 07:19:46 INFO - PROCESS | 3212 | 07:22:48 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36965/tests/dromaeo/cssquery-prototype.html) 07:22:49 INFO - PROCESS | 3212 | RSS: Main: 252919808 07:22:49 INFO - PROCESS | 3212 | 07:25:52 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36965/tests/dromaeo/cssquery-yui.html) 07:25:52 INFO - PROCESS | 3212 | RSS: Main: 226824192 07:25:52 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | Cycle 1(1): loaded http://localhost:36965/tests/dromaeo/cssquery-yui.html (next: http://localhost:36965/tests/dromaeo/cssquery-dojo.html) 07:28:55 INFO - PROCESS | 3212 | RSS: Main: 303259648 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | __start_tp_report 07:28:55 INFO - PROCESS | 3212 | _x_x_mozilla_page_load 07:28:55 INFO - PROCESS | 3212 | _x_x_mozilla_page_load_details 07:28:55 INFO - PROCESS | 3212 | |i|pagename|runs| 07:28:55 INFO - PROCESS | 3212 | |0;dojo.html;3081.4761490546607;3104.4101620692068;3116.7506599472035;3119.53207018947;3134.6551879293274;5308.177232528964;5320.388155362135;5392.379876314225;5408.513233808956;5409.6213265071465;5270.762815673294;5317.813876514323;5352.411234764173;5411.161270003156;5449.373322067963;4832.73419961902;4851.660383773137;4855.5387238212315;4907.533784290486;5063.164577844651;4876.609871210295;4997.175466048097;5005.6746311489815;5010.974945125269;5042.168042273021;4821.879453013667;4844.563989240985;4869.099216644932;4877.756112194394;4901.705897646117;4824.8311309104365;4875.634327425457;4893.4617192108835;4910.042541704369;4970.602351811846;4864.270359446076;4868.3606459210605;4872.415310162768;4888.095702295087;4913.803447862081;5258.921116183222;5321.760520776575;5341.1187154119525;5353.009693206769;5371.4897084776885;5639.548836093103;5694.345150307685;5698.173764804122;5779.566532510079;5780.71096445176;5827.562932780059;5835.4748072673665;5840.036393994986;5843.386444423317;5846.678432686201;5265.315508983807;5384.838454846361;5441.401445840955;5444.7822087116865;5449.863753406157;5914.201582786355;5971.671558064261;6021.668808215502;6024.819255422344;6027.276726792813;5695.03184458643;5745.913811292834;5748.626339287933;5752.626079304831;5754.971225143848;5622.437756224344;5689.459501347365;5748.913766293509;5861.443162899518;5865.120231965154;5743.368229494752;5803.622764520377;5804.071348584206;5810.767569297181;5857.384974577693;5778.075507918713;5820.214271073435;5826.271716035496;5837.357890632027;5837.532997360286;4378.452693413324;4393.912121757547;4417.072414792865;4464.062546865194;4510.436195475566;3969.563348031714;3969.868587452562;3970.06703424695;3997.720159588801;4032.838686452509;5364.329458817648;5413.2150838128255;5415.6479828811;5417.133258678593;5422.213779002022;5614.831555053355;5744.770209191585;5783.190353350535;5828.912566311508;5834.708264586839;5491.2312276281355;5501.834944951578;5513.2005859151;5514.944850551524;5549.889002219933;5049.82325618609;5060.544550990428;5062.772175252123;5071;5105.030044291572;5777.30672319316;5852.824415267549;5871.7651293947765;5881.059030555176;6006.369331220247;5778.682172480554;5787;5787.102999035156;5789.652620842763;5791.189233507228;5889.705514724161;5914.260717410324;5917.615355001911;5926.644401335761;5927.436893495284;5500.4499550044675;5604.747786349542;5646.378898321099;5668.489835914787;5700.857478563069;5669.801556945487;5792.304923409218;5794.1308803679785;5795.0148474760235;5799.420057994335;5523.6409633373705;5602.691851948019;5639.125935480007;5661.433856614306;5661.971690141523;5537.667739935617;5569.415211402745;5596.104623260258;5620.662760234399;5623.325200975909;5516.089845175501;5541.778328866962;5543.25166102571;5549.278593782944;5552.083906155439;5509.3664228614225;5628.718564071698;5665.886682266414;5713.885722285614;5714.828555143353;5439.401665816836;5492.697901615449;5528.944710552843;5551.555875529867;5579.832605021856 07:28:55 INFO - PROCESS | 3212 | |1;ext.html;573.7418161827179;606.1332294818409;622.0979579609566;625.8685676008035;642.6593905230229;29890.25274368139;38866.22267554647;40386.7980660097;40390.394144087826;40400.79799600998;44355.1128977421;44813.55186448134;44907.550924490744;44924;44942.32586511205;7964.8805267921025;8880.422772519782;9025.774355641095;10454.68635940923;10732.946335268312;10587.04716575508;10711;10715.571377144905;10716.839247411295;10730.463476826166;10798.568057277738;10801.297915635498;10837.620683276087;10838.241323107386;10847;7950.363970882345;10510.527026283797;10796.298240614335;10812.837807432894;11090.944545277303;10631.415272160028;10702.090322322612;10705.946470267676;10707.66154230721;10719;41157.58842411597;41531.377029344585;41615.37576936348;41616.37575436371;41641.58358416407;3513.2270900402127;3586.6413358664186;3875.534935807721;3961.742486738353;4147.087640719037;31411.09893216554;32114.678853211404;32419.189520261945;32430.189245268823;32443.51334729981;4313.827446902152;4373.956260437387;4456.442944631921;4510.639148868083;4595.172868883599;332308.3384583086;334429.99999999756;335096;337502;338284;55337;55710.44289557052;55719;55766.72116639472;55794;59498.70250648719;59766.10350844654;59829;59855;59968;15348.769768453481;16812.91593542049;17040.91479542619;17056.744148837777;17095.230714617872;12890.097693161577;13203.07578469498;13227.867721322857;13662.931685341708;13703.657408564866;16230.43193488246;16252.268647910872;16558.6688266234;16603.501894943172;16665.25006374716;5257.369115706139;5998.850028749316;6005.729742161613;6078.361772013964;6184.474319682878;994.5275993902983;1016.4511163971373;1054.6361505280665;1063.2025980514613;1070.7858428314369;31779.36441271208;32071.198220044687;32327.838360808517;32577.348453030805;32892;1062.4528367890543;1083.230906056693;1149.7930372532858;1168.6435637130758;1225.993870030644;16279.837201627832;16660.666786664442;16815.159242037847;17063.573410664834;17109.914450427917;535912;536112;537989;538204;538381;57436.71281643565;57955;58005;58095.419045809846;58113.41886581165;1224.9265044097383;1282.5190553542423;1323.2523624152132;1342.2080972226433;1376.5801430563774;30118.69881301159;32717.836410817792;35501.822490888415;36396;36424;55661.443385565624;56214.15678764904;56650.716746416;56711.14933276087;56741.43258567527;470208;474690;487773;493515;495340;33180.83409582936;43946.34080488859;44508;44622.55377446184;49076.546172690505;48778.512214877395;48959.26561101658;49238.81470075084;50016.249756254416;50420.74789626028;40985.795071024455;41407.792961035004;41899;42416.151676965674;42422.575774241865;38747.41878871763;41420.378694320214;41602.58397415987;41623.37564936589;41685.16629667329;41937.58062419337;42631.360529592705;43012.35481467718;43020.35469468023;43045.569544304155;35658.28683426369;36447.635523645484;36658.63341366552;37534.43698344582;37764.81117594394;27871.44257114887;28072;28095.370609631853;28227.15318540447;28456.15035022717 07:28:55 INFO - PROCESS | 3212 | |2;jquery.html;383.4459206446685;389.72329645951373;391.63773509503704;398.44814931320116;414.0022545664946;14122.15267083973;15473.76789348158;17882.91058544707;19305.613887722255;19343.129559169818;14694.412223511046;15104.093754374746;17815.91092044541;17823.55441113973;18247;5521;5721.542276617861;5764.769409223626;5768.509676677488;5811.6803575803315;5614.719264036782;5659.122835960433;5711.8000869969555;5732.369439361667;5763.0779075347955;4010.498687664042;4185.979070104645;6108.328083910788;6391.073294372313;6408.423241908227;6431.260405053432;6509.869802603945;6513.739450422001;6523.412892839643;6538.1827271591055;5571.637843540157;5704.77180912763;5756.510696590753;5758.1362795581;5769.1923130761725;18972.81027189738;20088.39734807958;21146.154153833828;21395.465113372135;21924.89037554818;2134.7224860768;2244.304265677629;2295.9426014349606;2847.731800499812;2901.6227890374325;10080.193584513221;10198.847017294747;10597.84103238452;10827.891721082768;14506.70986580273;1846.344547685579;1883.1149359800872;1906.3232552443822;1981.6631172700675;2002.1891134090718;77996;79332;80272.19727802706;80416.59791701063;81046.59476702637;10741.731456713489;10956.835647465294;11010.39442830636;11048.447577621087;11199.888001120047;15140.091594504365;15293.15887626169;15296.923515382352;15297.158656273788;15310.081395116329;7364.631768411665;9718.173955213886;9996.750081247881;10310.793784124275;10322.948385258025;8294.917050829414;8699.347548933856;8794.120587941155;8797.560121994004;8816.51509167002;8365.749027529184;8374.371922105867;8600.268977137013;8654.307655387554;8782.165694259034;3117.298607813224;3151.815263890887;3313.519539666735;3423.8459269332443;3429.1941393772445;908.5956749246521;916.445550441973;942.5852624845046;1029.902159294866;1082.6427278997912;18539.81460185408;18750.624987500174;18886.622267554845;18918.43244702661;19207.32774352891;981.0483830684225;990.9256805739599;1005.8189525885265;1016.8983101689772;1048.6434612231878;9358.251339892793;10421.374717516821;10497.212709046851;10522;10537.89462105369;491038;497711;500407;510766;512106;61144.38855611387;91376;92630;93013;93199.53400233091;962.7737481691937;969.6024629901707;989.4706332112286;990.9554070066864;991.9454430006275;16405.91797041007;16501.83498165003;16544.50366489007;16658.75011874847;16665.500034998968;39549;39874.401883971186;40772;40805.183896321316;42042.369364458944;58708;58926.10738926073;59013.409865900794;60002;60022.39977600168;8653.005778326044;8719.38964272495;8842.557872106241;8848.955755221183;8946.329025323257;9655.372400793925;12853.16454430459;12902.096853220184;12927.15973461722;13233.933830331172;10564.366138031743;10749;10957.123430125412;10990.450477475933;11001.559937602406;11025.503852326825;11158.609448669258;11436.885631143914;11452.599159029394;11563.248388854698;11115.499802508744;11161.44192790341;11321.320720756781;11502.884971150515;11779.092111502152;7653.655585498777;7800.726974556091;8694.652213911626;8818.603162857558;8939.821203576023;11635.47640356203;11923.940380298043;11936.28382297065;12036.819447708116;12043.397830108635 07:28:55 INFO - PROCESS | 3212 | |3;mootools.html;1296.7082406458553;1435.6913263648314;1499.0930487055332;1584.3583348743755;1760.7622970898924;8502.787430314245;8920.821583568333;8955.417897836644;9193.218576420995;9197;8909.242714369286;9128.041555636662;9135.497547634894;9142.725718228447;9151.542422878845;2071.6788897720803;2287.965680514785;2347.7182738071415;2358.0921345282104;2388.8208384371155;2345.554344674515;2361.0791791201445;2361.917332893349;2363.61000434928;2364.4798144408287;2294.724633044029;2326.523062772136;2354.799842013431;2355.8350915435926;2359.23324919401;1842.456475339778;2204.5039866030174;2279.760625134362;2282.611955967473;2285.1773361589812;2301.493671392291;2313.560423519543;2327.0226504867996;2328.988355058231;2329.114936324203;9084.591193396313;9139.862902056475;9152.084791520794;9157.81684366309;9165.770855728593;5320.4945530174955;5453.400125986138;5460.426655201187;5460.890782184334;5470.5350045246205;3267.297531030816;3366.5118557809224;3421.0763093964492;3427.280271143063;3478.3391155680592;1165.8076417391121;1166.7258194324327;1180.3390101543164;1182.8403165572624;1210.1347536511437;12803.975681945516;12820.487180512768;12836.80744788829;12852.550160744422;12869.034822388265;4451.5325890781005;4654.627629789608;4729.888476208157;4754.477007529169;4764.499727528629;10639.521221545048;10645.520951557199;10648.36109833397;10662.840057399146;10681.626143084955;5392.514673679309;5399.271098401744;5401.972990135024;5404.243405923174;5406.702631355234;4299.613034826881;4795.688280261772;4926.45808961014;4946.431160416598;4980.800767969241;3831.8658846940225;3911.7652940823073;3934.09515811365;3968.980155099264;3978.980105099456;1228.1709845854014;1235.9382030898419;1240.0699475393455;1242.8384310039637;1261.2810697902291;825.5418242875141;972.1202311907593;988.9258305627106;991.3939418142511;1012.4836333469837;5722.484976352148;6004.519638428916;6096.207493025879;6127.4485296322655;6213.5339849511465;448.8496353721475;453.88425951382186;464.54010529575476;467.05887636412655;467.2663917649258;3893.7469064511283;3929.5088113985753;4001.419794129893;4070.898227544335;4095.774732389747;10996.450177491093;11459.484323205475;11470.54117835294;11552.826707599394;11564.363959982282;11062.170337224741;11403;11419.200655954004;11424.42877856104;11428.428578571038;8373.995120585569;8465.195806398382;8493.915060849436;8527.829443411098;8566.14338566151;5764.510016648632;5826.271716035496;5862.677552734641;5913.113033044906;6035.849103772265;10318.329308594917;10508.527116279938;10626.52180651888;10628.681139565826;10675.786484270426;10231.693049208536;10589.782175049968;10661.680149595524;10699.358038517714;10842.728931776768;7106.928930710626;7670.539767613961;7883.172266911892;7960.761177164694;8013.719519816778;8751.256143227642;8790.560471976503;8821.382503224713;8855.512946787989;8860.64557417696;7657.0811502620045;7738.535687858746;7894.21057894206;7919.445638805229;8129.146439623991;7827;7902.0912595052305;7946.086200087064;7972.36221102322;7990.280874721303;6609.10777045112;7274.418046556157;7328;7352.338289553966;7358.77923662291;4929.507049295042;4967.329410529533;5054.924176137288;5077.517635824591;5098.082345177903;4884.462709101925;4924.212126059813;5070.2141168119;5073.492650735045;5109.029284435946 07:28:55 INFO - PROCESS | 3212 | |4;prototype.html;331.15555333898567;337.09153830427;340.7138004076575;345.7320576553171;346.71049673522606;5323.121684921988;5376.596755243353;5383.596230282735;5387.865303367418;5392.946070539293;5275.815346462875;5319.468053194679;5320.244319374607;5326.147816349385;5339.199120131982;4134.111166099288;4173.728707633994;4246.070211554018;4250.469830860888;4273.487181538219;4175.164967006595;4180.393842892779;4236.322188449849;4237.330481796479;4258.53156152823;4168.124693814302;4191.455110835587;4197.496300443951;4200.663946884257;4226.197022565718;3999.8200080996426;4046.271671099201;4152.896177595554;4155.709100362976;4159.272127377727;3689.2805902848954;3693.501377314055;3709.2025214578725;3717.6654101130757;3734.533183352081;4924.316247645006;5071.923921141186;5146.8198613048735;5153.098207813618;5186.559142472895;4564.726116432991;4785.9521404785855;4801.423829140491;4826.166056898392;4827.131116399082;5538.113901775697;5542.9168562471195;5560.638558493685;5569.498745112919;5581.246531718207;3968.503937007874;4107.213362187428;4137.710360274782;4155.2728272552185;4166.208420400114;5716.428357164292;5736.196932429463;5736.913946290851;5773.769049238025;5776.942230577724;4155.625993660525;4174.874753757332;4186.5604111568455;4208.705390622627;4212.705110642226;3894.746841455353;4251.532331443539;4292.785360731951;4296.892577685583;4306.117245964632;4663.48701642819;4688.203005489075;4688.601468875184;4704.788284527204;4707.458642256116;5565.610407271533;5576.442355764472;5578.163275508625;5604.915926261109;5627.43725627434;1367;1415.7310111078862;1427.3862239237021;1430.6494908747216;1432.7994080828691;904.9049849765811;906.777839429344;921.8156368726281;928.8653145293895;928.9814203715888;4741.245739076495;4773.952260477351;4833.589144922721;4841.830535931225;4847.466778654345;5075.314832497639;5087.465816089331;5115.87210319745;5121.28302037715;5147.716875571805;4772.0217355442055;4794.568488836021;4822.903541929212;4893.290472881484;5225.634205605647;2988.44713727961;3050.8322042287887;3061.234691327168;3062.724354808078;3064.862081206351;5877.588568800143;5878.941210587839;5888.5289176865745;5890.352061273256;5898.321693005273;5693.377387444559;5755.223044888887;5853.590248682552;5863.918309000329;5882.49998750111;5807.216025836543;5817.185594016925;5826.912596311144;5854.853628659318;5871.530277577784;5477.589180811376;5639.520640745583;5712.428757124254;5738.96698594257;5753.194552762533;5638.238837757014;5655.321361436654;5692.402297758676;5709.143628455765;5729.455701708331;5824.679642619697;5868.4424979626865;5876.6180198286975;5941.970290148522;5947.375525569752;3844.8654297099465;4062.410950412233;4071.6946229033533;4076.735012224196;4122.113745544722;5757.36668966405;5769.8557536061935;5772.249607550992;5772.826815195551;5797.971010144923;5596.244506991667;5598.636088654224;5623.581859800325;5633.1831884375715;5641.774329026793;5376.462353764592;5547.1679248113105;5567.359753628384;5606.411326810626;5608.327000759935;5484.698341591251;5501.174823776466;5520.66875987442;5522.5581953442825;5536.667799932017;5410.891782164414;5470.097433923358;5640.294963129609;5658.151277308437;5663.631863928831;5515.34539638099;5522.502974732293;5526.203983705232;5558.916616250679;5561.443855614406 07:28:55 INFO - PROCESS | 3212 | |5;yui.html;730.9305615966484;733.0946281342544;744.3449764207496;775.1628241499182;816.7263966571198;14779.482718104868;16114.677706445864;16164.595885102864;16173;16177.35290588375;14926.552203433886;14932.477363292286;15256.084634921912;15266.313015914282;16068.196590170503;803.9316658084069;819.4837252530897;826.330672155553;826.8718348655968;826.9131741167181;689.3313485918651;792.1128336263364;817.9795505112361;826.4586695714316;827.9503229806231;857.4126723194601;859.6690274244398;860.5611138319471;861.1044513705739;861.2205953611991;722.8120688620996;851.4337965253161;853.7566793463864;854.3250831842837;855.9358048146382;615.9322474527798;627.6861569215392;633.3634697129355;634.5050860328951;635.1425575473121;15541.766873496907;15541.922290388477;15554.688906221812;15575.221238938007;15621.984571002962;13593.252371119584;14016.579502614937;14075.859241407557;14113.78829317561;14170.716585668331;13026.081949146665;13275.203487790763;13335.333233338295;13437.932810335984;13460.730785384238;4417.801198946056;4418.403515525395;4459.420275364182;4574.794134263966;4578.931316030262;77481.61259193724;77554.12229388508;78058.2194178051;78139;78278.21721782823;10873.619423320304;10940.4529773511;10951.780964380669;11188.888111118948;11390;19965.301214457424;20632;20809.58380832375;20820.791792081884;20823.1670733172;8854.29165666745;9078.45529268246;9092.636294548276;9163.31275154366;9168.083191680778;4789.664723469394;4873.390826146731;4904.730239836843;4940.135476291634;4972.8508144755715;3680.8343624536424;3781.5840257571645;3786.1291902862495;3795.81020948957;3797.35444974355;618.709206672872;631.0597210156925;644.2944975252092;651.1502489251477;652.768267265114;649.376598465469;708.143146792387;731.4696844787486;741.8812989921587;752.0824593995317;9808.607655693835;9848.261380396356;9858;9918.652847150315;9924.15644670211;659.6536818170403;673.8079647300594;723.6671131279566;728.5519405565539;744.129368638694;4029.9194016119513;4083.1629515949753;4248.383984322256;4365.454318210223;4389.956100439019;103746;109415.45292273647;109728.4513577427;110826.44586777015;113058;95057.52471237599;95114;95766.52116739511;97358.51320743351;98480.01519984708;82049.58975205086;83843;83867.5806620963;83948.58025709832;84205;8225.218604232588;8267.173282671925;8315.168483151636;8454.36592255571;8489.363297752816;50749.98500030053;50930.745346273034;51019.72450688762;51590.484095158565;52652.47347526629;43818.780906095264;62888;63689;63995;72155.63922180356;11793.882061179278;11839.940800295943;11927.642170734893;13384.866151338361;13421.530246441327;15193.620159495658;15292.311845966735;15421.383144674088;15736.370545178523;16294;13754.93122534421;13984.720305593628;14728.926355368154;14874.702505950037;15158.924205378902;14435.494757683431;14521.636959075686;14859.331330089955;14959.476418325306;15283.388664453298;13109;13338.19970801755;13378.397972091474;13817.240051796845;13870.306484675524;11241.437928103725;11334.376609286568;11591.362475063952;11629.709257268636;11749.236299640495;10821.705117742003;11098.778024439627;11433.485493152662;11728.70678233051;11739.413029348669 07:28:55 INFO - PROCESS | 3212 | __end_tp_report 07:28:55 INFO - PROCESS | 3212 | __start_cc_report 07:28:55 INFO - PROCESS | 3212 | _x_x_mozilla_cycle_collect,1057 07:28:55 INFO - PROCESS | 3212 | __end_cc_report 07:28:55 INFO - PROCESS | 3212 | __startTimestamp1465482535783__endTimestamp 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | ------- Summary: start ------- 07:28:55 INFO - PROCESS | 3212 | Number of tests: 6 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#0] dojo.html Cycles:165 Average:5358.27 Median:5541.78 stddev:602.70 (10.9%) stddev-sans-first:577.48 07:28:55 INFO - PROCESS | 3212 | Values: 3081.5 3104.4 3116.8 3119.5 3134.7 5308.2 5320.4 5392.4 5408.5 5409.6 5270.8 5317.8 5352.4 5411.2 5449.4 4832.7 4851.7 4855.5 4907.5 5063.2 4876.6 4997.2 5005.7 5011.0 5042.2 4821.9 4844.6 4869.1 4877.8 4901.7 4824.8 4875.6 4893.5 4910.0 4970.6 4864.3 4868.4 4872.4 4888.1 4913.8 5258.9 5321.8 5341.1 5353.0 5371.5 5639.5 5694.3 5698.2 5779.6 5780.7 5827.6 5835.5 5840.0 5843.4 5846.7 5265.3 5384.8 5441.4 5444.8 5449.9 5914.2 5971.7 6021.7 6024.8 6027.3 5695.0 5745.9 5748.6 5752.6 5755.0 5622.4 5689.5 5748.9 5861.4 5865.1 5743.4 5803.6 5804.1 5810.8 5857.4 5778.1 5820.2 5826.3 5837.4 5837.5 4378.5 4393.9 4417.1 4464.1 4510.4 3969.6 3969.9 3970.1 3997.7 4032.8 5364.3 5413.2 5415.6 5417.1 5422.2 5614.8 5744.8 5783.2 5828.9 5834.7 5491.2 5501.8 5513.2 5514.9 5549.9 5049.8 5060.5 5062.8 5071.0 5105.0 5777.3 5852.8 5871.8 5881.1 6006.4 5778.7 5787.0 5787.1 5789.7 5791.2 5889.7 5914.3 5917.6 5926.6 5927.4 5500.4 5604.7 5646.4 5668.5 5700.9 5669.8 5792.3 5794.1 5795.0 5799.4 5523.6 5602.7 5639.1 5661.4 5662.0 5537.7 5569.4 5596.1 5620.7 5623.3 5516.1 5541.8 5543.3 5549.3 5552.1 5509.4 5628.7 5665.9 5713.9 5714.8 5439.4 5492.7 5528.9 5551.6 5579.8 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#1] ext.html Cycles:180 Average:61844.15 Median:21370.88 stddev:122049.26 (571.1%) stddev-sans-first:122304.95 07:28:55 INFO - PROCESS | 3212 | Values: 573.7 606.1 622.1 625.9 642.7 29890.3 38866.2 40386.8 40390.4 40400.8 44355.1 44813.6 44907.6 44924.0 44942.3 7964.9 8880.4 9025.8 10454.7 10732.9 10587.0 10711.0 10715.6 10716.8 10730.5 10798.6 10801.3 10837.6 10838.2 10847.0 7950.4 10510.5 10796.3 10812.8 11090.9 10631.4 10702.1 10705.9 10707.7 10719.0 41157.6 41531.4 41615.4 41616.4 41641.6 3513.2 3586.6 3875.5 3961.7 4147.1 31411.1 32114.7 32419.2 32430.2 32443.5 4313.8 4374.0 4456.4 4510.6 4595.2 332308.3 334430.0 335096.0 337502.0 338284.0 55337.0 55710.4 55719.0 55766.7 55794.0 59498.7 59766.1 59829.0 59855.0 59968.0 15348.8 16812.9 17040.9 17056.7 17095.2 12890.1 13203.1 13227.9 13662.9 13703.7 16230.4 16252.3 16558.7 16603.5 16665.3 5257.4 5998.9 6005.7 6078.4 6184.5 994.5 1016.5 1054.6 1063.2 1070.8 31779.4 32071.2 32327.8 32577.3 32892.0 1062.5 1083.2 1149.8 1168.6 1226.0 16279.8 16660.7 16815.2 17063.6 17109.9 535912.0 536112.0 537989.0 538204.0 538381.0 57436.7 57955.0 58005.0 58095.4 58113.4 1224.9 1282.5 1323.3 1342.2 1376.6 30118.7 32717.8 35501.8 36396.0 36424.0 55661.4 56214.2 56650.7 56711.1 56741.4 470208.0 474690.0 487773.0 493515.0 495340.0 33180.8 43946.3 44508.0 44622.6 49076.5 48778.5 48959.3 49238.8 50016.2 50420.7 40985.8 41407.8 41899.0 42416.2 42422.6 38747.4 41420.4 41602.6 41623.4 41685.2 41937.6 42631.4 43012.4 43020.4 43045.6 35658.3 36447.6 36658.6 37534.4 37764.8 27871.4 28072.0 28095.4 28227.2 28456.2 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#2] jquery.html Cycles:180 Average:29115.02 Median:386.58 stddev:82656.34 (21381.2%) stddev-sans-first:82860.06 07:28:55 INFO - PROCESS | 3212 | Values: 383.4 389.7 391.6 398.4 414.0 14122.2 15473.8 17882.9 19305.6 19343.1 14694.4 15104.1 17815.9 17823.6 18247.0 5521.0 5721.5 5764.8 5768.5 5811.7 5614.7 5659.1 5711.8 5732.4 5763.1 4010.5 4186.0 6108.3 6391.1 6408.4 6431.3 6509.9 6513.7 6523.4 6538.2 5571.6 5704.8 5756.5 5758.1 5769.2 18972.8 20088.4 21146.2 21395.5 21924.9 2134.7 2244.3 2295.9 2847.7 2901.6 10080.2 10198.8 10597.8 10827.9 14506.7 1846.3 1883.1 1906.3 1981.7 2002.2 77996.0 79332.0 80272.2 80416.6 81046.6 10741.7 10956.8 11010.4 11048.4 11199.9 15140.1 15293.2 15296.9 15297.2 15310.1 7364.6 9718.2 9996.8 10310.8 10322.9 8294.9 8699.3 8794.1 8797.6 8816.5 8365.7 8374.4 8600.3 8654.3 8782.2 3117.3 3151.8 3313.5 3423.8 3429.2 908.6 916.4 942.6 1029.9 1082.6 18539.8 18750.6 18886.6 18918.4 19207.3 981.0 990.9 1005.8 1016.9 1048.6 9358.3 10421.4 10497.2 10522.0 10537.9 491038.0 497711.0 500407.0 510766.0 512106.0 61144.4 91376.0 92630.0 93013.0 93199.5 962.8 969.6 989.5 991.0 991.9 16405.9 16501.8 16544.5 16658.8 16665.5 39549.0 39874.4 40772.0 40805.2 42042.4 58708.0 58926.1 59013.4 60002.0 60022.4 8653.0 8719.4 8842.6 8849.0 8946.3 9655.4 12853.2 12902.1 12927.2 13233.9 10564.4 10749.0 10957.1 10990.5 11001.6 11025.5 11158.6 11436.9 11452.6 11563.2 11115.5 11161.4 11321.3 11502.9 11779.1 7653.7 7800.7 8694.7 8818.6 8939.8 11635.5 11923.9 11936.3 12036.8 12043.4 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#3] mootools.html Cycles:180 Average:5916.83 Median:2559.58 stddev:3481.97 (136.0%) stddev-sans-first:3474.42 07:28:55 INFO - PROCESS | 3212 | Values: 1296.7 1435.7 1499.1 1584.4 1760.8 8502.8 8920.8 8955.4 9193.2 9197.0 8909.2 9128.0 9135.5 9142.7 9151.5 2071.7 2288.0 2347.7 2358.1 2388.8 2345.6 2361.1 2361.9 2363.6 2364.5 2294.7 2326.5 2354.8 2355.8 2359.2 1842.5 2204.5 2279.8 2282.6 2285.2 2301.5 2313.6 2327.0 2329.0 2329.1 9084.6 9139.9 9152.1 9157.8 9165.8 5320.5 5453.4 5460.4 5460.9 5470.5 3267.3 3366.5 3421.1 3427.3 3478.3 1165.8 1166.7 1180.3 1182.8 1210.1 12804.0 12820.5 12836.8 12852.6 12869.0 4451.5 4654.6 4729.9 4754.5 4764.5 10639.5 10645.5 10648.4 10662.8 10681.6 5392.5 5399.3 5402.0 5404.2 5406.7 4299.6 4795.7 4926.5 4946.4 4980.8 3831.9 3911.8 3934.1 3969.0 3979.0 1228.2 1235.9 1240.1 1242.8 1261.3 825.5 972.1 988.9 991.4 1012.5 5722.5 6004.5 6096.2 6127.4 6213.5 448.8 453.9 464.5 467.1 467.3 3893.7 3929.5 4001.4 4070.9 4095.8 10996.5 11459.5 11470.5 11552.8 11564.4 11062.2 11403.0 11419.2 11424.4 11428.4 8374.0 8465.2 8493.9 8527.8 8566.1 5764.5 5826.3 5862.7 5913.1 6035.8 10318.3 10508.5 10626.5 10628.7 10675.8 10231.7 10589.8 10661.7 10699.4 10842.7 7106.9 7670.5 7883.2 7960.8 8013.7 8751.3 8790.6 8821.4 8855.5 8860.6 7657.1 7738.5 7894.2 7919.4 8129.1 7827.0 7902.1 7946.1 7972.4 7990.3 6609.1 7274.4 7328.0 7352.3 7358.8 4929.5 4967.3 5054.9 5077.5 5098.1 4884.5 4924.2 5070.2 5073.5 5109.0 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#4] prototype.html Cycles:180 Average:4666.05 Median:5297.64 stddev:1359.93 (25.7%) stddev-sans-first:1324.25 07:28:55 INFO - PROCESS | 3212 | Values: 331.2 337.1 340.7 345.7 346.7 5323.1 5376.6 5383.6 5387.9 5392.9 5275.8 5319.5 5320.2 5326.1 5339.2 4134.1 4173.7 4246.1 4250.5 4273.5 4175.2 4180.4 4236.3 4237.3 4258.5 4168.1 4191.5 4197.5 4200.7 4226.2 3999.8 4046.3 4152.9 4155.7 4159.3 3689.3 3693.5 3709.2 3717.7 3734.5 4924.3 5071.9 5146.8 5153.1 5186.6 4564.7 4786.0 4801.4 4826.2 4827.1 5538.1 5542.9 5560.6 5569.5 5581.2 3968.5 4107.2 4137.7 4155.3 4166.2 5716.4 5736.2 5736.9 5773.8 5776.9 4155.6 4174.9 4186.6 4208.7 4212.7 3894.7 4251.5 4292.8 4296.9 4306.1 4663.5 4688.2 4688.6 4704.8 4707.5 5565.6 5576.4 5578.2 5604.9 5627.4 1367.0 1415.7 1427.4 1430.6 1432.8 904.9 906.8 921.8 928.9 929.0 4741.2 4774.0 4833.6 4841.8 4847.5 5075.3 5087.5 5115.9 5121.3 5147.7 4772.0 4794.6 4822.9 4893.3 5225.6 2988.4 3050.8 3061.2 3062.7 3064.9 5877.6 5878.9 5888.5 5890.4 5898.3 5693.4 5755.2 5853.6 5863.9 5882.5 5807.2 5817.2 5826.9 5854.9 5871.5 5477.6 5639.5 5712.4 5739.0 5753.2 5638.2 5655.3 5692.4 5709.1 5729.5 5824.7 5868.4 5876.6 5942.0 5947.4 3844.9 4062.4 4071.7 4076.7 4122.1 5757.4 5769.9 5772.2 5772.8 5798.0 5596.2 5598.6 5623.6 5633.2 5641.8 5376.5 5547.2 5567.4 5606.4 5608.3 5484.7 5501.2 5520.7 5522.6 5536.7 5410.9 5470.1 5640.3 5658.2 5663.6 5515.3 5522.5 5526.2 5558.9 5561.4 07:28:55 INFO - PROCESS | 3212 | 07:28:55 INFO - PROCESS | 3212 | [#5] yui.html Cycles:180 Average:20299.54 Median:4684.30 stddev:28567.57 (609.9%) stddev-sans-first:28609.92 07:28:55 INFO - PROCESS | 3212 | Values: 730.9 733.1 744.3 775.2 816.7 14779.5 16114.7 16164.6 16173.0 16177.4 14926.6 14932.5 15256.1 15266.3 16068.2 803.9 819.5 826.3 826.9 826.9 689.3 792.1 818.0 826.5 828.0 857.4 859.7 860.6 861.1 861.2 722.8 851.4 853.8 854.3 855.9 615.9 627.7 633.4 634.5 635.1 15541.8 15541.9 15554.7 15575.2 15622.0 13593.3 14016.6 14075.9 14113.8 14170.7 13026.1 13275.2 13335.3 13437.9 13460.7 4417.8 4418.4 4459.4 4574.8 4578.9 77481.6 77554.1 78058.2 78139.0 78278.2 10873.6 10940.5 10951.8 11188.9 11390.0 19965.3 20632.0 20809.6 20820.8 20823.2 8854.3 9078.5 9092.6 9163.3 9168.1 4789.7 4873.4 4904.7 4940.1 4972.9 3680.8 3781.6 3786.1 3795.8 3797.4 618.7 631.1 644.3 651.2 652.8 649.4 708.1 731.5 741.9 752.1 9808.6 9848.3 9858.0 9918.7 9924.2 659.7 673.8 723.7 728.6 744.1 4029.9 4083.2 4248.4 4365.5 4390.0 103746.0 109415.5 109728.5 110826.4 113058.0 95057.5 95114.0 95766.5 97358.5 98480.0 82049.6 83843.0 83867.6 83948.6 84205.0 8225.2 8267.2 8315.2 8454.4 8489.4 50750.0 50930.7 51019.7 51590.5 52652.5 43818.8 62888.0 63689.0 63995.0 72155.6 11793.9 11839.9 11927.6 13384.9 13421.5 15193.6 15292.3 15421.4 15736.4 16294.0 13754.9 13984.7 14728.9 14874.7 15158.9 14435.5 14521.6 14859.3 14959.5 15283.4 13109.0 13338.2 13378.4 13817.2 13870.3 11241.4 11334.4 11591.4 11629.7 11749.2 10821.7 11098.8 11433.5 11728.7 11739.4 07:28:55 INFO - PROCESS | 3212 | -------- Summary: end -------- 07:28:55 INFO - PROCESS | 3212 | 07:28:56 INFO - TEST-INFO | 3212: exit 0 07:28:56 INFO - TEST-OK | dromaeo_css | took 1116496ms 07:28:56 INFO - TEST-START | kraken 07:28:56 INFO - operating with platform_type : linux_ 07:28:56 INFO - Initialising browser for kraken test... 07:28:56 INFO - TEST-INFO | started process 3399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvRVB5_/profile http://localhost:36965/getInfo.html) 07:29:01 INFO - PROCESS | 3399 | __metrics Screen width/height:1600/1200 07:29:01 INFO - PROCESS | 3399 | colorDepth:24 07:29:01 INFO - PROCESS | 3399 | Browser inner width/height: 1024/697 07:29:01 INFO - PROCESS | 3399 | __metrics 07:29:02 ERROR - PROCESS | 3399 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:29:02 INFO - PROCESS | 3399 | console.error: PushService: 07:29:02 INFO - PROCESS | 3399 | stateChangeProcessEnqueue: Error transitioning state 07:29:02 INFO - PROCESS | 3399 | UnknownError 07:29:03 INFO - TEST-INFO | 3399: exit 0 07:29:03 INFO - Browser initialized. 07:29:03 INFO - Running cycle 1/1 for kraken test... 07:29:03 INFO - TEST-INFO | started process 3481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvRVB5_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:29:15 INFO - PROCESS | 3481 | RSS: Main: 176967680 07:29:15 INFO - PROCESS | 3481 | 07:29:18 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36965/tests/kraken/driver.html?testName=audio-beat-detection) 07:29:19 INFO - PROCESS | 3481 | RSS: Main: 235499520 07:29:19 INFO - PROCESS | 3481 | 07:29:21 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36965/tests/kraken/driver.html?testName=audio-dft) 07:29:22 INFO - PROCESS | 3481 | RSS: Main: 223547392 07:29:22 INFO - PROCESS | 3481 | 07:29:25 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36965/tests/kraken/driver.html?testName=audio-fft) 07:29:26 INFO - PROCESS | 3481 | RSS: Main: 289505280 07:29:26 INFO - PROCESS | 3481 | 07:29:28 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36965/tests/kraken/driver.html?testName=audio-oscillator) 07:29:28 INFO - PROCESS | 3481 | RSS: Main: 200015872 07:29:28 INFO - PROCESS | 3481 | 07:29:30 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36965/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:29:31 INFO - PROCESS | 3481 | RSS: Main: 204587008 07:29:31 INFO - PROCESS | 3481 | 07:29:34 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36965/tests/kraken/driver.html?testName=imaging-darkroom) 07:29:35 INFO - PROCESS | 3481 | RSS: Main: 240521216 07:29:35 INFO - PROCESS | 3481 | 07:29:38 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36965/tests/kraken/driver.html?testName=imaging-desaturate) 07:29:39 INFO - PROCESS | 3481 | RSS: Main: 240930816 07:29:39 INFO - PROCESS | 3481 | 07:29:41 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36965/tests/kraken/driver.html?testName=json-parse-financial) 07:29:42 INFO - PROCESS | 3481 | RSS: Main: 240795648 07:29:42 INFO - PROCESS | 3481 | 07:29:44 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36965/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:29:44 INFO - PROCESS | 3481 | RSS: Main: 224608256 07:29:44 INFO - PROCESS | 3481 | 07:29:46 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:29:46 INFO - PROCESS | 3481 | RSS: Main: 229658624 07:29:46 INFO - PROCESS | 3481 | 07:29:49 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:29:49 INFO - PROCESS | 3481 | RSS: Main: 234278912 07:29:49 INFO - PROCESS | 3481 | 07:29:52 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:29:52 INFO - PROCESS | 3481 | RSS: Main: 239173632 07:29:52 INFO - PROCESS | 3481 | 07:29:55 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:29:56 INFO - PROCESS | 3481 | RSS: Main: 239296512 07:29:56 INFO - PROCESS | 3481 | 07:29:57 INFO - PROCESS | 3481 | Cycle 1(1): loaded http://localhost:36965/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36965/tests/kraken/driver.html?testName=ai-astar) 07:29:58 INFO - PROCESS | 3481 | RSS: Main: 281001984 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | __start_tp_report 07:29:58 INFO - PROCESS | 3481 | _x_x_mozilla_page_load 07:29:58 INFO - PROCESS | 3481 | _x_x_mozilla_page_load_details 07:29:58 INFO - PROCESS | 3481 | |i|pagename|runs| 07:29:58 INFO - PROCESS | 3481 | |0;ai-astar;109;104;109;109;110;109;109;110;110;109 07:29:58 INFO - PROCESS | 3481 | |1;audio-beat-detection;149;132;132;168;126;127;155;127;202;125 07:29:58 INFO - PROCESS | 3481 | |2;audio-dft;159;162;161;162;160;161;160;160;161;163 07:29:58 INFO - PROCESS | 3481 | |3;audio-fft;74;74;76;118;76;78;77;74;102;75 07:29:58 INFO - PROCESS | 3481 | |4;audio-oscillator;85;84;111;85;84;84;113;84;86;133 07:29:58 INFO - PROCESS | 3481 | |5;imaging-gaussian-blur;117;117;118;117;118;118;117;117;118;118 07:29:58 INFO - PROCESS | 3481 | |6;imaging-darkroom;147;146;146;146;147;146;146;146;147;146 07:29:58 INFO - PROCESS | 3481 | |7;imaging-desaturate;94;95;95;95;95;96;95;95;95;94 07:29:58 INFO - PROCESS | 3481 | |8;json-parse-financial;52;58;55;53;53;53;53;53;55;54 07:29:58 INFO - PROCESS | 3481 | |9;json-stringify-tinderbox;61;62;61;60;62;61;62;62;62;62 07:29:58 INFO - PROCESS | 3481 | |10;stanford-crypto-aes;89;84;83;81;85;84;84;83;132;89 07:29:58 INFO - PROCESS | 3481 | |11;stanford-crypto-ccm;124;132;130;127;120;122;119;123;147;141 07:29:58 INFO - PROCESS | 3481 | |12;stanford-crypto-pbkdf2;167;170;166;171;165;173;161;165;166;164 07:29:58 INFO - PROCESS | 3481 | |13;stanford-crypto-sha256-iterative;63;64;65;65;64;64;64;65;64;63 07:29:58 INFO - PROCESS | 3481 | __end_tp_report 07:29:58 INFO - PROCESS | 3481 | __start_cc_report 07:29:58 INFO - PROCESS | 3481 | _x_x_mozilla_cycle_collect,1657 07:29:58 INFO - PROCESS | 3481 | __end_cc_report 07:29:58 INFO - PROCESS | 3481 | __startTimestamp1465482598211__endTimestamp 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | ------- Summary: start ------- 07:29:58 INFO - PROCESS | 3481 | Number of tests: 14 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.00 stddev:1.75 (1.6%) stddev-sans-first:1.86 07:29:58 INFO - PROCESS | 3481 | Values: 109.0 104.0 109.0 109.0 110.0 109.0 109.0 110.0 110.0 109.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:140.50 stddev:25.02 (17.8%) stddev-sans-first:26.48 07:29:58 INFO - PROCESS | 3481 | Values: 149.0 132.0 132.0 168.0 126.0 127.0 155.0 127.0 202.0 125.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.05 07:29:58 INFO - PROCESS | 3481 | Values: 159.0 162.0 161.0 162.0 160.0 161.0 160.0 160.0 161.0 163.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.50 stddev:15.09 (20.0%) stddev-sans-first:15.69 07:29:58 INFO - PROCESS | 3481 | Values: 74.0 74.0 76.0 118.0 76.0 78.0 77.0 74.0 102.0 75.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:17.60 (21.0%) stddev-sans-first:18.30 07:29:58 INFO - PROCESS | 3481 | Values: 85.0 84.0 111.0 85.0 84.0 84.0 113.0 84.0 86.0 133.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 07:29:58 INFO - PROCESS | 3481 | Values: 117.0 117.0 118.0 117.0 118.0 118.0 117.0 117.0 118.0 118.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 07:29:58 INFO - PROCESS | 3481 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 07:29:58 INFO - PROCESS | 3481 | Values: 94.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 94.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:1.73 (3.2%) stddev-sans-first:1.69 07:29:58 INFO - PROCESS | 3481 | Values: 52.0 58.0 55.0 53.0 53.0 53.0 53.0 53.0 55.0 54.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 07:29:58 INFO - PROCESS | 3481 | Values: 61.0 62.0 61.0 60.0 62.0 61.0 62.0 62.0 62.0 62.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:84.00 stddev:15.18 (18.1%) stddev-sans-first:16.10 07:29:58 INFO - PROCESS | 3481 | Values: 89.0 84.0 83.0 81.0 85.0 84.0 84.0 83.0 132.0 89.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:128.50 stddev:9.25 (7.2%) stddev-sans-first:9.67 07:29:58 INFO - PROCESS | 3481 | Values: 124.0 132.0 130.0 127.0 120.0 122.0 119.0 123.0 147.0 141.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:166.50 stddev:3.58 (2.2%) stddev-sans-first:3.80 07:29:58 INFO - PROCESS | 3481 | Values: 167.0 170.0 166.0 171.0 165.0 173.0 161.0 165.0 166.0 164.0 07:29:58 INFO - PROCESS | 3481 | 07:29:58 INFO - PROCESS | 3481 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 07:29:58 INFO - PROCESS | 3481 | Values: 63.0 64.0 65.0 65.0 64.0 64.0 64.0 65.0 64.0 63.0 07:29:58 INFO - PROCESS | 3481 | -------- Summary: end -------- 07:29:58 INFO - PROCESS | 3481 | 07:29:59 INFO - TEST-INFO | 3481: exit 0 07:29:59 INFO - TEST-OK | kraken | took 62744ms 07:29:59 INFO - SUITE-END | took 1179s 07:29:59 INFO - Completed test suite (00:19:39) 07:29:59 INFO - javascript benchmark 07:29:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3081.4761490546607, 3104.4101620692068, 3116.7506599472035, 3119.53207018947, 3134.6551879293274, 5308.177232528964, 5320.388155362135, 5392.379876314225, 5408.513233808956, 5409.6213265071465, 5270.762815673294, 5317.813876514323, 5352.411234764173, 5411.161270003156, 5449.373322067963, 4832.73419961902, 4851.660383773137, 4855.5387238212315, 4907.533784290486, 5063.164577844651, 4876.609871210295, 4997.175466048097, 5005.6746311489815, 5010.974945125269, 5042.168042273021, 4821.879453013667, 4844.563989240985, 4869.099216644932, 4877.756112194394, 4901.705897646117, 4824.8311309104365, 4875.634327425457, 4893.4617192108835, 4910.042541704369, 4970.602351811846, 4864.270359446076, 4868.3606459210605, 4872.415310162768, 4888.095702295087, 4913.803447862081, 5258.921116183222, 5321.760520776575, 5341.1187154119525, 5353.009693206769, 5371.4897084776885, 5639.548836093103, 5694.345150307685, 5698.173764804122, 5779.566532510079, 5780.71096445176, 5827.562932780059, 5835.4748072673665, 5840.036393994986, 5843.386444423317, 5846.678432686201, 5265.315508983807, 5384.838454846361, 5441.401445840955, 5444.7822087116865, 5449.863753406157, 5914.201582786355, 5971.671558064261, 6021.668808215502, 6024.819255422344, 6027.276726792813, 5695.03184458643, 5745.913811292834, 5748.626339287933, 5752.626079304831, 5754.971225143848, 5622.437756224344, 5689.459501347365, 5748.913766293509, 5861.443162899518, 5865.120231965154, 5743.368229494752, 5803.622764520377, 5804.071348584206, 5810.767569297181, 5857.384974577693, 5778.075507918713, 5820.214271073435, 5826.271716035496, 5837.357890632027, 5837.532997360286, 4378.452693413324, 4393.912121757547, 4417.072414792865, 4464.062546865194, 4510.436195475566, 3969.563348031714, 3969.868587452562, 3970.06703424695, 3997.720159588801, 4032.838686452509, 5364.329458817648, 5413.2150838128255, 5415.6479828811, 5417.133258678593, 5422.213779002022, 5614.831555053355, 5744.770209191585, 5783.190353350535, 5828.912566311508, 5834.708264586839, 5491.2312276281355, 5501.834944951578, 5513.2005859151, 5514.944850551524, 5549.889002219933, 5049.82325618609, 5060.544550990428, 5062.772175252123, 5071.0, 5105.030044291572, 5777.30672319316, 5852.824415267549, 5871.7651293947765, 5881.059030555176, 6006.369331220247, 5778.682172480554, 5787.0, 5787.102999035156, 5789.652620842763, 5791.189233507228, 5889.705514724161, 5914.260717410324, 5917.615355001911, 5926.644401335761, 5927.436893495284, 5500.4499550044675, 5604.747786349542, 5646.378898321099, 5668.489835914787, 5700.857478563069, 5669.801556945487, 5792.304923409218, 5794.1308803679785, 5795.0148474760235, 5799.420057994335, 5523.6409633373705, 5602.691851948019, 5639.125935480007, 5661.433856614306, 5661.971690141523, 5537.667739935617, 5569.415211402745, 5596.104623260258, 5620.662760234399, 5623.325200975909, 5516.089845175501, 5541.778328866962, 5543.25166102571, 5549.278593782944, 5552.083906155439, 5509.3664228614225, 5628.718564071698, 5665.886682266414, 5713.885722285614, 5714.828555143353, 5439.401665816836, 5492.697901615449, 5528.944710552843, 5551.555875529867, 5579.832605021856], "value": 5317.8750676355085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7418161827179, 606.1332294818409, 622.0979579609566, 625.8685676008035, 642.6593905230229, 29890.25274368139, 38866.22267554647, 40386.7980660097, 40390.394144087826, 40400.79799600998, 44355.1128977421, 44813.55186448134, 44907.550924490744, 44924.0, 44942.32586511205, 7964.8805267921025, 8880.422772519782, 9025.774355641095, 10454.68635940923, 10732.946335268312, 10587.04716575508, 10711.0, 10715.571377144905, 10716.839247411295, 10730.463476826166, 10798.568057277738, 10801.297915635498, 10837.620683276087, 10838.241323107386, 10847.0, 7950.363970882345, 10510.527026283797, 10796.298240614335, 10812.837807432894, 11090.944545277303, 10631.415272160028, 10702.090322322612, 10705.946470267676, 10707.66154230721, 10719.0, 41157.58842411597, 41531.377029344585, 41615.37576936348, 41616.37575436371, 41641.58358416407, 3513.2270900402127, 3586.6413358664186, 3875.534935807721, 3961.742486738353, 4147.087640719037, 31411.09893216554, 32114.678853211404, 32419.189520261945, 32430.189245268823, 32443.51334729981, 4313.827446902152, 4373.956260437387, 4456.442944631921, 4510.639148868083, 4595.172868883599, 332308.3384583086, 334429.99999999756, 335096.0, 337502.0, 338284.0, 55337.0, 55710.44289557052, 55719.0, 55766.72116639472, 55794.0, 59498.70250648719, 59766.10350844654, 59829.0, 59855.0, 59968.0, 15348.769768453481, 16812.91593542049, 17040.91479542619, 17056.744148837777, 17095.230714617872, 12890.097693161577, 13203.07578469498, 13227.867721322857, 13662.931685341708, 13703.657408564866, 16230.43193488246, 16252.268647910872, 16558.6688266234, 16603.501894943172, 16665.25006374716, 5257.369115706139, 5998.850028749316, 6005.729742161613, 6078.361772013964, 6184.474319682878, 994.5275993902983, 1016.4511163971373, 1054.6361505280665, 1063.2025980514613, 1070.7858428314369, 31779.36441271208, 32071.198220044687, 32327.838360808517, 32577.348453030805, 32892.0, 1062.4528367890543, 1083.230906056693, 1149.7930372532858, 1168.6435637130758, 1225.993870030644, 16279.837201627832, 16660.666786664442, 16815.159242037847, 17063.573410664834, 17109.914450427917, 535912.0, 536112.0, 537989.0, 538204.0, 538381.0, 57436.71281643565, 57955.0, 58005.0, 58095.419045809846, 58113.41886581165, 1224.9265044097383, 1282.5190553542423, 1323.2523624152132, 1342.2080972226433, 1376.5801430563774, 30118.69881301159, 32717.836410817792, 35501.822490888415, 36396.0, 36424.0, 55661.443385565624, 56214.15678764904, 56650.716746416, 56711.14933276087, 56741.43258567527, 470208.0, 474690.0, 487773.0, 493515.0, 495340.0, 33180.83409582936, 43946.34080488859, 44508.0, 44622.55377446184, 49076.546172690505, 48778.512214877395, 48959.26561101658, 49238.81470075084, 50016.249756254416, 50420.74789626028, 40985.795071024455, 41407.792961035004, 41899.0, 42416.151676965674, 42422.575774241865, 38747.41878871763, 41420.378694320214, 41602.58397415987, 41623.37564936589, 41685.16629667329, 41937.58062419337, 42631.360529592705, 43012.35481467718, 43020.35469468023, 43045.569544304155, 35658.28683426369, 36447.635523645484, 36658.63341366552, 37534.43698344582, 37764.81117594394, 27871.44257114887, 28072.0, 28095.370609631853, 28227.15318540447, 28456.15035022717], "value": 20777.394070778897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.4459206446685, 389.72329645951373, 391.63773509503704, 398.44814931320116, 414.0022545664946, 14122.15267083973, 15473.76789348158, 17882.91058544707, 19305.613887722255, 19343.129559169818, 14694.412223511046, 15104.093754374746, 17815.91092044541, 17823.55441113973, 18247.0, 5521.0, 5721.542276617861, 5764.769409223626, 5768.509676677488, 5811.6803575803315, 5614.719264036782, 5659.122835960433, 5711.8000869969555, 5732.369439361667, 5763.0779075347955, 4010.498687664042, 4185.979070104645, 6108.328083910788, 6391.073294372313, 6408.423241908227, 6431.260405053432, 6509.869802603945, 6513.739450422001, 6523.412892839643, 6538.1827271591055, 5571.637843540157, 5704.77180912763, 5756.510696590753, 5758.1362795581, 5769.1923130761725, 18972.81027189738, 20088.39734807958, 21146.154153833828, 21395.465113372135, 21924.89037554818, 2134.7224860768, 2244.304265677629, 2295.9426014349606, 2847.731800499812, 2901.6227890374325, 10080.193584513221, 10198.847017294747, 10597.84103238452, 10827.891721082768, 14506.70986580273, 1846.344547685579, 1883.1149359800872, 1906.3232552443822, 1981.6631172700675, 2002.1891134090718, 77996.0, 79332.0, 80272.19727802706, 80416.59791701063, 81046.59476702637, 10741.731456713489, 10956.835647465294, 11010.39442830636, 11048.447577621087, 11199.888001120047, 15140.091594504365, 15293.15887626169, 15296.923515382352, 15297.158656273788, 15310.081395116329, 7364.631768411665, 9718.173955213886, 9996.750081247881, 10310.793784124275, 10322.948385258025, 8294.917050829414, 8699.347548933856, 8794.120587941155, 8797.560121994004, 8816.51509167002, 8365.749027529184, 8374.371922105867, 8600.268977137013, 8654.307655387554, 8782.165694259034, 3117.298607813224, 3151.815263890887, 3313.519539666735, 3423.8459269332443, 3429.1941393772445, 908.5956749246521, 916.445550441973, 942.5852624845046, 1029.902159294866, 1082.6427278997912, 18539.81460185408, 18750.624987500174, 18886.622267554845, 18918.43244702661, 19207.32774352891, 981.0483830684225, 990.9256805739599, 1005.8189525885265, 1016.8983101689772, 1048.6434612231878, 9358.251339892793, 10421.374717516821, 10497.212709046851, 10522.0, 10537.89462105369, 491038.0, 497711.0, 500407.0, 510766.0, 512106.0, 61144.38855611387, 91376.0, 92630.0, 93013.0, 93199.53400233091, 962.7737481691937, 969.6024629901707, 989.4706332112286, 990.9554070066864, 991.9454430006275, 16405.91797041007, 16501.83498165003, 16544.50366489007, 16658.75011874847, 16665.500034998968, 39549.0, 39874.401883971186, 40772.0, 40805.183896321316, 42042.369364458944, 58708.0, 58926.10738926073, 59013.409865900794, 60002.0, 60022.39977600168, 8653.005778326044, 8719.38964272495, 8842.557872106241, 8848.955755221183, 8946.329025323257, 9655.372400793925, 12853.16454430459, 12902.096853220184, 12927.15973461722, 13233.933830331172, 10564.366138031743, 10749.0, 10957.123430125412, 10990.450477475933, 11001.559937602406, 11025.503852326825, 11158.609448669258, 11436.885631143914, 11452.599159029394, 11563.248388854698, 11115.499802508744, 11161.44192790341, 11321.320720756781, 11502.884971150515, 11779.092111502152, 7653.655585498777, 7800.726974556091, 8694.652213911626, 8818.603162857558, 8939.821203576023, 11635.47640356203, 11923.940380298043, 11936.28382297065, 12036.819447708116, 12043.397830108635], "value": 9444.508900151211, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.7082406458553, 1435.6913263648314, 1499.0930487055332, 1584.3583348743755, 1760.7622970898924, 8502.787430314245, 8920.821583568333, 8955.417897836644, 9193.218576420995, 9197.0, 8909.242714369286, 9128.041555636662, 9135.497547634894, 9142.725718228447, 9151.542422878845, 2071.6788897720803, 2287.965680514785, 2347.7182738071415, 2358.0921345282104, 2388.8208384371155, 2345.554344674515, 2361.0791791201445, 2361.917332893349, 2363.61000434928, 2364.4798144408287, 2294.724633044029, 2326.523062772136, 2354.799842013431, 2355.8350915435926, 2359.23324919401, 1842.456475339778, 2204.5039866030174, 2279.760625134362, 2282.611955967473, 2285.1773361589812, 2301.493671392291, 2313.560423519543, 2327.0226504867996, 2328.988355058231, 2329.114936324203, 9084.591193396313, 9139.862902056475, 9152.084791520794, 9157.81684366309, 9165.770855728593, 5320.4945530174955, 5453.400125986138, 5460.426655201187, 5460.890782184334, 5470.5350045246205, 3267.297531030816, 3366.5118557809224, 3421.0763093964492, 3427.280271143063, 3478.3391155680592, 1165.8076417391121, 1166.7258194324327, 1180.3390101543164, 1182.8403165572624, 1210.1347536511437, 12803.975681945516, 12820.487180512768, 12836.80744788829, 12852.550160744422, 12869.034822388265, 4451.5325890781005, 4654.627629789608, 4729.888476208157, 4754.477007529169, 4764.499727528629, 10639.521221545048, 10645.520951557199, 10648.36109833397, 10662.840057399146, 10681.626143084955, 5392.514673679309, 5399.271098401744, 5401.972990135024, 5404.243405923174, 5406.702631355234, 4299.613034826881, 4795.688280261772, 4926.45808961014, 4946.431160416598, 4980.800767969241, 3831.8658846940225, 3911.7652940823073, 3934.09515811365, 3968.980155099264, 3978.980105099456, 1228.1709845854014, 1235.9382030898419, 1240.0699475393455, 1242.8384310039637, 1261.2810697902291, 825.5418242875141, 972.1202311907593, 988.9258305627106, 991.3939418142511, 1012.4836333469837, 5722.484976352148, 6004.519638428916, 6096.207493025879, 6127.4485296322655, 6213.5339849511465, 448.8496353721475, 453.88425951382186, 464.54010529575476, 467.05887636412655, 467.2663917649258, 3893.7469064511283, 3929.5088113985753, 4001.419794129893, 4070.898227544335, 4095.774732389747, 10996.450177491093, 11459.484323205475, 11470.54117835294, 11552.826707599394, 11564.363959982282, 11062.170337224741, 11403.0, 11419.200655954004, 11424.42877856104, 11428.428578571038, 8373.995120585569, 8465.195806398382, 8493.915060849436, 8527.829443411098, 8566.14338566151, 5764.510016648632, 5826.271716035496, 5862.677552734641, 5913.113033044906, 6035.849103772265, 10318.329308594917, 10508.527116279938, 10626.52180651888, 10628.681139565826, 10675.786484270426, 10231.693049208536, 10589.782175049968, 10661.680149595524, 10699.358038517714, 10842.728931776768, 7106.928930710626, 7670.539767613961, 7883.172266911892, 7960.761177164694, 8013.719519816778, 8751.256143227642, 8790.560471976503, 8821.382503224713, 8855.512946787989, 8860.64557417696, 7657.0811502620045, 7738.535687858746, 7894.21057894206, 7919.445638805229, 8129.146439623991, 7827.0, 7902.0912595052305, 7946.086200087064, 7972.36221102322, 7990.280874721303, 6609.10777045112, 7274.418046556157, 7328.0, 7352.338289553966, 7358.77923662291, 4929.507049295042, 4967.329410529533, 5054.924176137288, 5077.517635824591, 5098.082345177903, 4884.462709101925, 4924.212126059813, 5070.2141168119, 5073.492650735045, 5109.029284435946], "value": 4604.61442439957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.15555333898567, 337.09153830427, 340.7138004076575, 345.7320576553171, 346.71049673522606, 5323.121684921988, 5376.596755243353, 5383.596230282735, 5387.865303367418, 5392.946070539293, 5275.815346462875, 5319.468053194679, 5320.244319374607, 5326.147816349385, 5339.199120131982, 4134.111166099288, 4173.728707633994, 4246.070211554018, 4250.469830860888, 4273.487181538219, 4175.164967006595, 4180.393842892779, 4236.322188449849, 4237.330481796479, 4258.53156152823, 4168.124693814302, 4191.455110835587, 4197.496300443951, 4200.663946884257, 4226.197022565718, 3999.8200080996426, 4046.271671099201, 4152.896177595554, 4155.709100362976, 4159.272127377727, 3689.2805902848954, 3693.501377314055, 3709.2025214578725, 3717.6654101130757, 3734.533183352081, 4924.316247645006, 5071.923921141186, 5146.8198613048735, 5153.098207813618, 5186.559142472895, 4564.726116432991, 4785.9521404785855, 4801.423829140491, 4826.166056898392, 4827.131116399082, 5538.113901775697, 5542.9168562471195, 5560.638558493685, 5569.498745112919, 5581.246531718207, 3968.503937007874, 4107.213362187428, 4137.710360274782, 4155.2728272552185, 4166.208420400114, 5716.428357164292, 5736.196932429463, 5736.913946290851, 5773.769049238025, 5776.942230577724, 4155.625993660525, 4174.874753757332, 4186.5604111568455, 4208.705390622627, 4212.705110642226, 3894.746841455353, 4251.532331443539, 4292.785360731951, 4296.892577685583, 4306.117245964632, 4663.48701642819, 4688.203005489075, 4688.601468875184, 4704.788284527204, 4707.458642256116, 5565.610407271533, 5576.442355764472, 5578.163275508625, 5604.915926261109, 5627.43725627434, 1367.0, 1415.7310111078862, 1427.3862239237021, 1430.6494908747216, 1432.7994080828691, 904.9049849765811, 906.777839429344, 921.8156368726281, 928.8653145293895, 928.9814203715888, 4741.245739076495, 4773.952260477351, 4833.589144922721, 4841.830535931225, 4847.466778654345, 5075.314832497639, 5087.465816089331, 5115.87210319745, 5121.28302037715, 5147.716875571805, 4772.0217355442055, 4794.568488836021, 4822.903541929212, 4893.290472881484, 5225.634205605647, 2988.44713727961, 3050.8322042287887, 3061.234691327168, 3062.724354808078, 3064.862081206351, 5877.588568800143, 5878.941210587839, 5888.5289176865745, 5890.352061273256, 5898.321693005273, 5693.377387444559, 5755.223044888887, 5853.590248682552, 5863.918309000329, 5882.49998750111, 5807.216025836543, 5817.185594016925, 5826.912596311144, 5854.853628659318, 5871.530277577784, 5477.589180811376, 5639.520640745583, 5712.428757124254, 5738.96698594257, 5753.194552762533, 5638.238837757014, 5655.321361436654, 5692.402297758676, 5709.143628455765, 5729.455701708331, 5824.679642619697, 5868.4424979626865, 5876.6180198286975, 5941.970290148522, 5947.375525569752, 3844.8654297099465, 4062.410950412233, 4071.6946229033533, 4076.735012224196, 4122.113745544722, 5757.36668966405, 5769.8557536061935, 5772.249607550992, 5772.826815195551, 5797.971010144923, 5596.244506991667, 5598.636088654224, 5623.581859800325, 5633.1831884375715, 5641.774329026793, 5376.462353764592, 5547.1679248113105, 5567.359753628384, 5606.411326810626, 5608.327000759935, 5484.698341591251, 5501.174823776466, 5520.66875987442, 5522.5581953442825, 5536.667799932017, 5410.891782164414, 5470.097433923358, 5640.294963129609, 5658.151277308437, 5663.631863928831, 5515.34539638099, 5522.502974732293, 5526.203983705232, 5558.916616250679, 5561.443855614406], "value": 4232.92774110296, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9305615966484, 733.0946281342544, 744.3449764207496, 775.1628241499182, 816.7263966571198, 14779.482718104868, 16114.677706445864, 16164.595885102864, 16173.0, 16177.35290588375, 14926.552203433886, 14932.477363292286, 15256.084634921912, 15266.313015914282, 16068.196590170503, 803.9316658084069, 819.4837252530897, 826.330672155553, 826.8718348655968, 826.9131741167181, 689.3313485918651, 792.1128336263364, 817.9795505112361, 826.4586695714316, 827.9503229806231, 857.4126723194601, 859.6690274244398, 860.5611138319471, 861.1044513705739, 861.2205953611991, 722.8120688620996, 851.4337965253161, 853.7566793463864, 854.3250831842837, 855.9358048146382, 615.9322474527798, 627.6861569215392, 633.3634697129355, 634.5050860328951, 635.1425575473121, 15541.766873496907, 15541.922290388477, 15554.688906221812, 15575.221238938007, 15621.984571002962, 13593.252371119584, 14016.579502614937, 14075.859241407557, 14113.78829317561, 14170.716585668331, 13026.081949146665, 13275.203487790763, 13335.333233338295, 13437.932810335984, 13460.730785384238, 4417.801198946056, 4418.403515525395, 4459.420275364182, 4574.794134263966, 4578.931316030262, 77481.61259193724, 77554.12229388508, 78058.2194178051, 78139.0, 78278.21721782823, 10873.619423320304, 10940.4529773511, 10951.780964380669, 11188.888111118948, 11390.0, 19965.301214457424, 20632.0, 20809.58380832375, 20820.791792081884, 20823.1670733172, 8854.29165666745, 9078.45529268246, 9092.636294548276, 9163.31275154366, 9168.083191680778, 4789.664723469394, 4873.390826146731, 4904.730239836843, 4940.135476291634, 4972.8508144755715, 3680.8343624536424, 3781.5840257571645, 3786.1291902862495, 3795.81020948957, 3797.35444974355, 618.709206672872, 631.0597210156925, 644.2944975252092, 651.1502489251477, 652.768267265114, 649.376598465469, 708.143146792387, 731.4696844787486, 741.8812989921587, 752.0824593995317, 9808.607655693835, 9848.261380396356, 9858.0, 9918.652847150315, 9924.15644670211, 659.6536818170403, 673.8079647300594, 723.6671131279566, 728.5519405565539, 744.129368638694, 4029.9194016119513, 4083.1629515949753, 4248.383984322256, 4365.454318210223, 4389.956100439019, 103746.0, 109415.45292273647, 109728.4513577427, 110826.44586777015, 113058.0, 95057.52471237599, 95114.0, 95766.52116739511, 97358.51320743351, 98480.01519984708, 82049.58975205086, 83843.0, 83867.5806620963, 83948.58025709832, 84205.0, 8225.218604232588, 8267.173282671925, 8315.168483151636, 8454.36592255571, 8489.363297752816, 50749.98500030053, 50930.745346273034, 51019.72450688762, 51590.484095158565, 52652.47347526629, 43818.780906095264, 62888.0, 63689.0, 63995.0, 72155.63922180356, 11793.882061179278, 11839.940800295943, 11927.642170734893, 13384.866151338361, 13421.530246441327, 15193.620159495658, 15292.311845966735, 15421.383144674088, 15736.370545178523, 16294.0, 13754.93122534421, 13984.720305593628, 14728.926355368154, 14874.702505950037, 15158.924205378902, 14435.494757683431, 14521.636959075686, 14859.331330089955, 14959.476418325306, 15283.388664453298, 13109.0, 13338.19970801755, 13378.397972091474, 13817.240051796845, 13870.306484675524, 11241.437928103725, 11334.376609286568, 11591.362475063952, 11629.709257268636, 11749.236299640495, 10821.705117742003, 11098.778024439627, 11433.485493152662, 11728.70678233051, 11739.413029348669], "value": 7587.915728938573, "unit": "score"}], "name": "dromaeo_css", "value": 7324.062136719117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 104.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 132.0, 132.0, 168.0, 126.0, 127.0, 155.0, 127.0, 202.0, 125.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 161.0, 163.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 118.0, 76.0, 78.0, 77.0, 74.0, 102.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 111.0, 85.0, 84.0, 84.0, 113.0, 84.0, 86.0, 133.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 55.0, 53.0, 53.0, 53.0, 53.0, 53.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 60.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 83.0, 81.0, 85.0, 84.0, 84.0, 83.0, 132.0, 89.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 132.0, 130.0, 127.0, 120.0, 122.0, 119.0, 123.0, 147.0, 141.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 166.0, 171.0, 165.0, 173.0, 161.0, 165.0, 166.0, 164.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 65.0, 64.0, 63.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1514.1999999999998, "alertThreshold": 2.0}]} 07:29:59 INFO - Return code: 0 07:29:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:29:59 ERROR - # TBPL SUCCESS # 07:29:59 INFO - Running post-action listener: _resource_record_post_action 07:29:59 INFO - ##### 07:29:59 INFO - ##### Finished run-tests step (success) 07:29:59 INFO - ##### 07:29:59 INFO - Running post-run listener: _resource_record_post_run 07:29:59 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 542318592; Read time: 28; Write time: 1353824 07:29:59 INFO - TinderboxPrint: CPU usage
13.3% 07:29:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 28 07:29:59 INFO - TinderboxPrint: I/O write bytes / time
542,318,592 / 1,353,824 07:29:59 INFO - TinderboxPrint: CPU idle
8,289.4 (86.7%) 07:29:59 INFO - TinderboxPrint: CPU user
1,208.5 (12.6%) 07:29:59 INFO - TinderboxPrint: Swap in / out
0 / 0 07:29:59 INFO - install - Wall time: 18s; CPU: 18.0%; Read bytes: 0; Write bytes: 248184832; Read time: 0; Write time: 810888 07:29:59 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293572608; Read time: 28; Write time: 542916 07:30:00 INFO - Running post-run listener: _upload_blobber_files 07:30:00 INFO - Blob upload gear active. 07:30:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:30:00 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 07:30:00 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'] 07:30:00 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 07:30:00 INFO - (blobuploader) - INFO - Open directory for files ... 07:30:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:30:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:30:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:04 INFO - (blobuploader) - INFO - Done attempting. 07:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:05 INFO - (blobuploader) - INFO - Done attempting. 07:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:06 INFO - (blobuploader) - INFO - Done attempting. 07:30:06 INFO - (blobuploader) - INFO - Iteration through files over. 07:30:06 INFO - Return code: 0 07:30:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:30:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:30:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ebcc81a6f9e07aada64f88d34d1d254cdde948fe1664af65aa8c2a86ec2428215c35f8aad3b38c336d6380a73ecbf0dfe16a478962b182fcce018727d2138", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef05409aa88f174d6eb7762a74312f5c6aafb782f5a474123b5f48695fdd8b7dab1517919eb6eae75000aac5802a3604d399b7acf029cd6c7a72a293f09574ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53247a640717e29cc51eb45c2ca4c7cfd78fcc4f1bd7cf84a9a19dfb3d936e6c13a741263d919468094001c65400f6904203583c56f70557e9db1d171d653f"} 07:30:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:30:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:30:06 INFO - Contents: 07:30:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ebcc81a6f9e07aada64f88d34d1d254cdde948fe1664af65aa8c2a86ec2428215c35f8aad3b38c336d6380a73ecbf0dfe16a478962b182fcce018727d2138", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef05409aa88f174d6eb7762a74312f5c6aafb782f5a474123b5f48695fdd8b7dab1517919eb6eae75000aac5802a3604d399b7acf029cd6c7a72a293f09574ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53247a640717e29cc51eb45c2ca4c7cfd78fcc4f1bd7cf84a9a19dfb3d936e6c13a741263d919468094001c65400f6904203583c56f70557e9db1d171d653f"} 07:30:06 INFO - Running post-run listener: copy_logs_to_upload_dir 07:30:06 INFO - Copying logs to upload dir... 07:30:06 INFO - mkdir: /builds/slave/test/build/upload/logs 07:30:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.673577 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-09 07:30:06.262206) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 07:30:06.266046) ========= 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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 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/5e0ebcc81a6f9e07aada64f88d34d1d254cdde948fe1664af65aa8c2a86ec2428215c35f8aad3b38c336d6380a73ecbf0dfe16a478962b182fcce018727d2138", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef05409aa88f174d6eb7762a74312f5c6aafb782f5a474123b5f48695fdd8b7dab1517919eb6eae75000aac5802a3604d399b7acf029cd6c7a72a293f09574ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53247a640717e29cc51eb45c2ca4c7cfd78fcc4f1bd7cf84a9a19dfb3d936e6c13a741263d919468094001c65400f6904203583c56f70557e9db1d171d653f"} build_url:https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011543 build_url: 'https://queue.taskcluster.net/v1/task/IH9ihtsxTrWMhn9vcUxUiw/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/5e0ebcc81a6f9e07aada64f88d34d1d254cdde948fe1664af65aa8c2a86ec2428215c35f8aad3b38c336d6380a73ecbf0dfe16a478962b182fcce018727d2138", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef05409aa88f174d6eb7762a74312f5c6aafb782f5a474123b5f48695fdd8b7dab1517919eb6eae75000aac5802a3604d399b7acf029cd6c7a72a293f09574ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53247a640717e29cc51eb45c2ca4c7cfd78fcc4f1bd7cf84a9a19dfb3d936e6c13a741263d919468094001c65400f6904203583c56f70557e9db1d171d653f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 07:30:06.292125) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:30:06.292493) ========= 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=745a4373a2a16df560f216e000000264-1465481358.883973-1650758648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 07:30:06.375728) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 07:30:06.376143) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 07:30:06.376535) ========= ========= Total master_lag: 0.26 =========