builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1466489644.52 results: success (0) buildid: 20160620203028 builduid: 225ecab999204cee827564fb449f0f2b revision: 027db1e33f89606c4996feae0203e5f3bb5db43d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.515644) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.516262) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.516596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.537287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.537900) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-20 23:14:04-- 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% 42.9M=0s 2016-06-20 23:14:04 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.670659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.671429) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018748 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:04.723259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-20 23:14:04.723650) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 027db1e33f89606c4996feae0203e5f3bb5db43d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 027db1e33f89606c4996feae0203e5f3bb5db43d --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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-20 23:14:05,050 truncating revision to first 12 chars 2016-06-20 23:14:05,050 Setting DEBUG logging. 2016-06-20 23:14:05,050 attempt 1/10 2016-06-20 23:14:05,050 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/027db1e33f89?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-20 23:14:05,906 unpacking tar archive at: fx-team-027db1e33f89/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-20 23:14:05.980142) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:05.980541) ========= script_repo_revision: 027db1e33f89606c4996feae0203e5f3bb5db43d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:05.981027) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:05.981392) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 23:14:05.991277) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-20 23:14:05.991604) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:14:06 INFO - MultiFileLogger online at 20160620 23:14:06 in /builds/slave/test 23:14:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:14:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:14:06 INFO - {'append_to_log': False, 23:14:06 INFO - 'base_work_dir': '/builds/slave/test', 23:14:06 INFO - 'blob_upload_branch': 'Fx-Team', 23:14:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:14:06 INFO - 'branch': 'Fx-Team', 23:14:06 INFO - 'buildbot_json_path': 'buildprops.json', 23:14:06 INFO - 'config_files': ('talos/linux_config.py',), 23:14:06 INFO - 'default_actions': ('clobber', 23:14:06 INFO - 'read-buildbot-config', 23:14:06 INFO - 'download-and-extract', 23:14:06 INFO - 'populate-webroot', 23:14:06 INFO - 'create-virtualenv', 23:14:06 INFO - 'install', 23:14:06 INFO - 'run-tests'), 23:14:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:14:06 INFO - 'download_minidump_stackwalk': True, 23:14:06 INFO - 'download_symbols': 'ondemand', 23:14:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:14:06 INFO - 'tooltool.py': '/tools/tooltool.py', 23:14:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:14:06 INFO - '/tools/misc-python/virtualenv.py')}, 23:14:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:14:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:14:06 INFO - 'installer_path': 'installer.exe', 23:14:06 INFO - 'log_level': 'info', 23:14:06 INFO - 'log_name': 'talos', 23:14:06 INFO - 'log_to_console': True, 23:14:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:14:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:14:06 INFO - 'opt_config_files': (), 23:14:06 INFO - 'pip_index': False, 23:14:06 INFO - 'sps_profile': False, 23:14:06 INFO - 'sps_profile_interval': 0, 23:14:06 INFO - 'suite': 'dromaeojs', 23:14:06 INFO - 'system_bits': '32', 23:14:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:14:06 INFO - 'title': 'talos-linux64-ix-033', 23:14:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:14:06 INFO - 'use_talos_json': True, 23:14:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:14:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:14:06 INFO - 'work_dir': 'build'} 23:14:06 INFO - ##### 23:14:06 INFO - ##### Running clobber step. 23:14:06 INFO - ##### 23:14:06 INFO - Running pre-action listener: _resource_record_pre_action 23:14:06 INFO - Running main action method: clobber 23:14:06 INFO - rmtree: /builds/slave/test/build 23:14:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:14:07 INFO - Running post-action listener: _resource_record_post_action 23:14:07 INFO - ##### 23:14:07 INFO - ##### Finished clobber step (success) 23:14:07 INFO - ##### 23:14:07 INFO - ##### 23:14:07 INFO - ##### Running read-buildbot-config step. 23:14:07 INFO - ##### 23:14:07 INFO - Running pre-action listener: _resource_record_pre_action 23:14:07 INFO - Running main action method: read_buildbot_config 23:14:07 INFO - Using buildbot properties: 23:14:07 INFO - { 23:14:07 INFO - "project": "", 23:14:07 INFO - "product": "firefox", 23:14:07 INFO - "script_repo_revision": "production", 23:14:07 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:14:07 INFO - "repository": "", 23:14:07 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:14:07 INFO - "buildid": "20160620203028", 23:14:07 INFO - "pgo_build": "True", 23:14:07 INFO - "basedir": "/builds/slave/test", 23:14:07 INFO - "buildnumber": 814, 23:14:07 INFO - "slavename": "talos-linux64-ix-033", 23:14:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:14:07 INFO - "platform": "ubuntu64_hw", 23:14:07 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:14:07 INFO - "branch": "fx-team", 23:14:07 INFO - "repo_path": "integration/fx-team", 23:14:07 INFO - "revision": "027db1e33f89606c4996feae0203e5f3bb5db43d", 23:14:07 INFO - "stage_platform": "linux64-pgo", 23:14:07 INFO - "builduid": "225ecab999204cee827564fb449f0f2b", 23:14:07 INFO - "slavebuilddir": "test" 23:14:07 INFO - } 23:14:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:14:07 INFO - Running post-action listener: _resource_record_post_action 23:14:07 INFO - ##### 23:14:07 INFO - ##### Finished read-buildbot-config step (success) 23:14:07 INFO - ##### 23:14:07 INFO - ##### 23:14:07 INFO - ##### Running download-and-extract step. 23:14:07 INFO - ##### 23:14:07 INFO - Running pre-action listener: _resource_record_pre_action 23:14:07 INFO - Running main action method: download_and_extract 23:14:07 INFO - mkdir: /builds/slave/test/build/tests 23:14:07 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')]} 23:14:07 INFO - https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:14:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:14:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:14:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:08 INFO - Downloaded 1361 bytes. 23:14:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:14:08 INFO - Using the following test package requirements: 23:14:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:14:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:14:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:14:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'jsshell-linux-x86_64.zip'], 23:14:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:14:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:14:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:14:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:14:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:14:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:14:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:14:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:14:08 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')]} 23:14:08 INFO - https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:14:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:14:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:14:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:09 INFO - Downloaded 24221315 bytes. 23:14:09 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'] 23:14:09 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 23:14:10 INFO - Return code: 0 23:14:10 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 23:14:10 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')]} 23:14:10 INFO - https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:14:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:14:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:14:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:12 INFO - Downloaded 24221315 bytes. 23:14:12 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'] 23:14:12 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 23:14:13 INFO - Return code: 0 23:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:13 INFO - https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:14:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:14:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:14:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:14 INFO - Downloaded 14405384 bytes. 23:14:14 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'] 23:14:14 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 23:14:14 INFO - Return code: 0 23:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:14 INFO - https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:14:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:14:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:14:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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 23:14:16 INFO - Downloaded 60125066 bytes. 23:14:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:14:16 INFO - mkdir: /builds/slave/test/properties 23:14:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:14:16 INFO - Writing to file /builds/slave/test/properties/build_url 23:14:16 INFO - Contents: 23:14:16 INFO - build_url:https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:14:17 INFO - Running post-action listener: _resource_record_post_action 23:14:17 INFO - Running post-action listener: set_extra_try_arguments 23:14:17 INFO - ##### 23:14:17 INFO - ##### Finished download-and-extract step (success) 23:14:17 INFO - ##### 23:14:17 INFO - ##### 23:14:17 INFO - ##### Running populate-webroot step. 23:14:17 INFO - ##### 23:14:17 INFO - Running pre-action listener: _resource_record_pre_action 23:14:17 INFO - Running main action method: populate_webroot 23:14:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:14:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:14:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:14:17 INFO - '--tppagecycles', 23:14:17 INFO - '7'], 23:14:17 INFO - 'tests': ['tsvgm']}}, 23:14:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:14:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:14:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:14:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:14:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:14:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:14:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['damp', 'tps']}, 23:14:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'tests': ['damp', 'tps']}, 23:14:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['dromaeo_dom']}, 23:14:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:14:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['basic_compositor_video']}, 23:14:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:14:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'other-e10s': {'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['a11yr', 23:14:17 INFO - 'ts_paint', 23:14:17 INFO - 'tpaint', 23:14:17 INFO - 'sessionrestore', 23:14:17 INFO - 'sessionrestore_no_auto_restore', 23:14:17 INFO - 'tabpaint']}, 23:14:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['tsvgx', 23:14:17 INFO - 'tsvgr_opacity', 23:14:17 INFO - 'tart', 23:14:17 INFO - 'tscrollx', 23:14:17 INFO - 'cart']}, 23:14:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:14:17 INFO - 'tsvgr_opacity', 23:14:17 INFO - 'tart', 23:14:17 INFO - 'tscrollx', 23:14:17 INFO - 'cart']}, 23:14:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'talos_options': ['--disable-e10s'], 23:14:17 INFO - 'tests': ['tp5o']}, 23:14:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'tests': ['tp5o']}, 23:14:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'talos_options': ['--disable-e10s', 23:14:17 INFO - '--xperf_path', 23:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:14:17 INFO - 'tests': ['tp5n']}, 23:14:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:14:17 INFO - 'talos_options': ['--xperf_path', 23:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:14:17 INFO - 'tests': ['tp5n']}}, 23:14:17 INFO - 'talos.zip': {'path': '', 23:14:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:14:17 INFO - Running post-action listener: _resource_record_post_action 23:14:17 INFO - ##### 23:14:17 INFO - ##### Finished populate-webroot step (success) 23:14:17 INFO - ##### 23:14:17 INFO - ##### 23:14:17 INFO - ##### Running create-virtualenv step. 23:14:17 INFO - ##### 23:14:17 INFO - Running pre-action listener: _resource_record_pre_action 23:14:17 INFO - Running main action method: create_virtualenv 23:14:17 INFO - Creating virtualenv /builds/slave/test/build/venv 23:14:17 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 23:14:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:14:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:14:17 INFO - Using real prefix '/usr' 23:14:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:14:17 INFO - Installing distribute.............................................................................................................................................................................................done. 23:14:19 INFO - Installing pip.................done. 23:14:19 INFO - Return code: 0 23:14:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:19 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:19 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 23:14:19 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 23:14:19 INFO - Using env: {'DISPLAY': ':0', 23:14:19 INFO - 'HOME': '/home/cltbld', 23:14:19 INFO - 'LANG': 'en_US.UTF-8', 23:14:19 INFO - 'LANGUAGE': 'en_US:en', 23:14:19 INFO - 'LOGNAME': 'cltbld', 23:14:19 INFO - 'MAIL': '/var/mail/cltbld', 23:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:19 INFO - 'MOZ_NO_REMOTE': '1', 23:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:19 INFO - 'NO_EM_RESTART': '1', 23:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:19 INFO - 'PWD': '/builds/slave/test', 23:14:19 INFO - 'SHELL': '/bin/bash', 23:14:19 INFO - 'SHLVL': '1', 23:14:19 INFO - 'TERM': 'linux', 23:14:19 INFO - 'TMOUT': '86400', 23:14:19 INFO - 'USER': 'cltbld', 23:14:19 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:19 INFO - '_': '/tools/buildbot/bin/python'} 23:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:20 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 23:14:20 INFO - Cleaning up... 23:14:20 INFO - Return code: 0 23:14:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:20 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:20 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 23:14:20 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 23:14:20 INFO - Using env: {'DISPLAY': ':0', 23:14:20 INFO - 'HOME': '/home/cltbld', 23:14:20 INFO - 'LANG': 'en_US.UTF-8', 23:14:20 INFO - 'LANGUAGE': 'en_US:en', 23:14:20 INFO - 'LOGNAME': 'cltbld', 23:14:20 INFO - 'MAIL': '/var/mail/cltbld', 23:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:20 INFO - 'MOZ_NO_REMOTE': '1', 23:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:20 INFO - 'NO_EM_RESTART': '1', 23:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:20 INFO - 'PWD': '/builds/slave/test', 23:14:20 INFO - 'SHELL': '/bin/bash', 23:14:20 INFO - 'SHLVL': '1', 23:14:20 INFO - 'TERM': 'linux', 23:14:20 INFO - 'TMOUT': '86400', 23:14:20 INFO - 'USER': 'cltbld', 23:14:20 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:20 INFO - '_': '/tools/buildbot/bin/python'} 23:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:20 INFO - Downloading/unpacking psutil>=3.1.1 23:14:20 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 23:14:20 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 23:14:20 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 23:14:20 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 23:14:20 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 23:14:20 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 23:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:14:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:14:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:14:22 INFO - Installing collected packages: psutil 23:14:22 INFO - Running setup.py install for psutil 23:14:22 INFO - building 'psutil._psutil_linux' extension 23:14:22 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 23:14:22 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 23:14:22 INFO - building 'psutil._psutil_posix' extension 23:14:22 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 23:14:22 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 23:14:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:14:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:14:22 INFO - Successfully installed psutil 23:14:22 INFO - Cleaning up... 23:14:22 INFO - Return code: 0 23:14:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:22 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:22 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 23:14:22 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 23:14:22 INFO - Using env: {'DISPLAY': ':0', 23:14:22 INFO - 'HOME': '/home/cltbld', 23:14:22 INFO - 'LANG': 'en_US.UTF-8', 23:14:22 INFO - 'LANGUAGE': 'en_US:en', 23:14:22 INFO - 'LOGNAME': 'cltbld', 23:14:22 INFO - 'MAIL': '/var/mail/cltbld', 23:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:22 INFO - 'MOZ_NO_REMOTE': '1', 23:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:22 INFO - 'NO_EM_RESTART': '1', 23:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:22 INFO - 'PWD': '/builds/slave/test', 23:14:22 INFO - 'SHELL': '/bin/bash', 23:14:22 INFO - 'SHLVL': '1', 23:14:22 INFO - 'TERM': 'linux', 23:14:22 INFO - 'TMOUT': '86400', 23:14:22 INFO - 'USER': 'cltbld', 23:14:22 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:22 INFO - '_': '/tools/buildbot/bin/python'} 23:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:14:23 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 23:14:23 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 23:14:23 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 23:14:23 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 23:14:23 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 23:14:23 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 23:14:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:14:24 INFO - Installing collected packages: mozsystemmonitor 23:14:24 INFO - Running setup.py install for mozsystemmonitor 23:14:25 INFO - Successfully installed mozsystemmonitor 23:14:25 INFO - Cleaning up... 23:14:25 INFO - Return code: 0 23:14:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:25 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:25 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 23:14:25 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 23:14:25 INFO - Using env: {'DISPLAY': ':0', 23:14:25 INFO - 'HOME': '/home/cltbld', 23:14:25 INFO - 'LANG': 'en_US.UTF-8', 23:14:25 INFO - 'LANGUAGE': 'en_US:en', 23:14:25 INFO - 'LOGNAME': 'cltbld', 23:14:25 INFO - 'MAIL': '/var/mail/cltbld', 23:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:25 INFO - 'MOZ_NO_REMOTE': '1', 23:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:25 INFO - 'NO_EM_RESTART': '1', 23:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:25 INFO - 'PWD': '/builds/slave/test', 23:14:25 INFO - 'SHELL': '/bin/bash', 23:14:25 INFO - 'SHLVL': '1', 23:14:25 INFO - 'TERM': 'linux', 23:14:25 INFO - 'TMOUT': '86400', 23:14:25 INFO - 'USER': 'cltbld', 23:14:25 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:25 INFO - '_': '/tools/buildbot/bin/python'} 23:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:25 INFO - Downloading/unpacking jsonschema==2.5.1 23:14:25 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 23:14:25 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 23:14:25 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 23:14:25 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 23:14:25 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 23:14:25 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 23:14:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:14:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 INFO - Downloading functools32-3.2.3-2.tar.gz 23:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:14:27 INFO - warning: no files found matching '*.txt' 23:14:27 INFO - no previously-included directories found matching 'build' 23:14:27 INFO - no previously-included directories found matching 'dist' 23:14:27 INFO - no previously-included directories found matching '.git*' 23:14:27 INFO - Installing collected packages: jsonschema, functools32 23:14:27 INFO - Running setup.py install for functools32 23:14:27 INFO - warning: no files found matching '*.txt' 23:14:27 INFO - no previously-included directories found matching 'build' 23:14:27 INFO - no previously-included directories found matching 'dist' 23:14:27 INFO - no previously-included directories found matching '.git*' 23:14:27 INFO - Successfully installed jsonschema functools32 23:14:27 INFO - Cleaning up... 23:14:27 INFO - Return code: 0 23:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:27 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:27 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 23:14:27 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 23:14:27 INFO - Using env: {'DISPLAY': ':0', 23:14:27 INFO - 'HOME': '/home/cltbld', 23:14:27 INFO - 'LANG': 'en_US.UTF-8', 23:14:27 INFO - 'LANGUAGE': 'en_US:en', 23:14:27 INFO - 'LOGNAME': 'cltbld', 23:14:27 INFO - 'MAIL': '/var/mail/cltbld', 23:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:27 INFO - 'MOZ_NO_REMOTE': '1', 23:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:27 INFO - 'NO_EM_RESTART': '1', 23:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:27 INFO - 'PWD': '/builds/slave/test', 23:14:27 INFO - 'SHELL': '/bin/bash', 23:14:27 INFO - 'SHLVL': '1', 23:14:27 INFO - 'TERM': 'linux', 23:14:27 INFO - 'TMOUT': '86400', 23:14:27 INFO - 'USER': 'cltbld', 23:14:27 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:27 INFO - '_': '/tools/buildbot/bin/python'} 23:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:27 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 23:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 23:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:14:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:14:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 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 23:14:30 INFO - Downloading docopt-0.6.1.tar.gz 23:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 23:14:30 INFO - Running setup.py install for blobuploader 23:14:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:14:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:14:30 INFO - Running setup.py install for requests 23:14:31 INFO - Running setup.py install for docopt 23:14:31 INFO - Successfully installed blobuploader requests docopt 23:14:31 INFO - Cleaning up... 23:14:31 INFO - Return code: 0 23:14:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:14:31 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')]} 23:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:31 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')]} 23:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:31 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:31 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 23:14:31 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 23:14:31 INFO - Using env: {'DISPLAY': ':0', 23:14:31 INFO - 'HOME': '/home/cltbld', 23:14:31 INFO - 'LANG': 'en_US.UTF-8', 23:14:31 INFO - 'LANGUAGE': 'en_US:en', 23:14:31 INFO - 'LOGNAME': 'cltbld', 23:14:31 INFO - 'MAIL': '/var/mail/cltbld', 23:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:31 INFO - 'MOZ_NO_REMOTE': '1', 23:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:31 INFO - 'NO_EM_RESTART': '1', 23:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:31 INFO - 'PWD': '/builds/slave/test', 23:14:31 INFO - 'SHELL': '/bin/bash', 23:14:31 INFO - 'SHLVL': '1', 23:14:31 INFO - 'TERM': 'linux', 23:14:31 INFO - 'TMOUT': '86400', 23:14:31 INFO - 'USER': 'cltbld', 23:14:31 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:31 INFO - '_': '/tools/buildbot/bin/python'} 23:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:14:31 INFO - Running setup.py (path:/tmp/pip-6FCyUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:14:31 INFO - Running setup.py (path:/tmp/pip-UqAdg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:14:31 INFO - Running setup.py (path:/tmp/pip-7y97Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:14:31 INFO - Running setup.py (path:/tmp/pip-s4r6aV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:14:31 INFO - Running setup.py (path:/tmp/pip-env0sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:14:31 INFO - Running setup.py (path:/tmp/pip-TGe2i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:14:31 INFO - Running setup.py (path:/tmp/pip-2ubU3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:14:32 INFO - Running setup.py (path:/tmp/pip-emvvZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:14:32 INFO - Running setup.py (path:/tmp/pip-bntKlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:14:32 INFO - Running setup.py (path:/tmp/pip-W8xJw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:14:32 INFO - Running setup.py (path:/tmp/pip-65ABE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:14:32 INFO - Running setup.py (path:/tmp/pip-4RTtl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:14:32 INFO - Running setup.py (path:/tmp/pip-0pHU8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:14:32 INFO - Running setup.py (path:/tmp/pip-26g2EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:14:32 INFO - Running setup.py (path:/tmp/pip-Q09NPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:14:32 INFO - Running setup.py (path:/tmp/pip-DfuA3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:14:32 INFO - Running setup.py (path:/tmp/pip-hqShmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:14:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:14:32 INFO - Running setup.py install for manifestparser 23:14:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:14:32 INFO - Running setup.py install for mozcrash 23:14:32 INFO - Running setup.py install for mozdebug 23:14:33 INFO - Running setup.py install for mozdevice 23:14:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for mozfile 23:14:33 INFO - Running setup.py install for mozhttpd 23:14:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for mozinfo 23:14:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for mozInstall 23:14:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for mozleak 23:14:33 INFO - Running setup.py install for mozlog 23:14:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for moznetwork 23:14:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:14:33 INFO - Running setup.py install for mozprocess 23:14:33 INFO - Running setup.py install for mozprofile 23:14:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:14:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:14:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:14:34 INFO - Running setup.py install for mozrunner 23:14:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:14:34 INFO - Running setup.py install for mozscreenshot 23:14:34 INFO - Running setup.py install for moztest 23:14:34 INFO - Running setup.py install for mozversion 23:14:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:14:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:14:34 INFO - Cleaning up... 23:14:34 INFO - Return code: 0 23:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:34 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:34 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 23:14:34 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 23:14:34 INFO - Using env: {'DISPLAY': ':0', 23:14:34 INFO - 'HOME': '/home/cltbld', 23:14:34 INFO - 'LANG': 'en_US.UTF-8', 23:14:34 INFO - 'LANGUAGE': 'en_US:en', 23:14:34 INFO - 'LOGNAME': 'cltbld', 23:14:34 INFO - 'MAIL': '/var/mail/cltbld', 23:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:34 INFO - 'MOZ_NO_REMOTE': '1', 23:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:34 INFO - 'NO_EM_RESTART': '1', 23:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:34 INFO - 'PWD': '/builds/slave/test', 23:14:34 INFO - 'SHELL': '/bin/bash', 23:14:34 INFO - 'SHLVL': '1', 23:14:34 INFO - 'TERM': 'linux', 23:14:34 INFO - 'TMOUT': '86400', 23:14:34 INFO - 'USER': 'cltbld', 23:14:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:34 INFO - '_': '/tools/buildbot/bin/python'} 23:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:14:34 INFO - Running setup.py (path:/tmp/pip-_Ztwpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:14:34 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)) 23:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:14:34 INFO - Running setup.py (path:/tmp/pip-N9SNfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:14:34 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)) 23:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:14:34 INFO - Running setup.py (path:/tmp/pip-RWvKFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:14:34 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)) 23:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:14:34 INFO - Running setup.py (path:/tmp/pip-8vW_0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:14:35 INFO - Running setup.py (path:/tmp/pip-cDjw7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:14:35 INFO - Running setup.py (path:/tmp/pip-o6Tv_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:14:35 INFO - Running setup.py (path:/tmp/pip-D8lOvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:14:35 INFO - Running setup.py (path:/tmp/pip-XrD_8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:14:35 INFO - Running setup.py (path:/tmp/pip-uintrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:14:35 INFO - Running setup.py (path:/tmp/pip-eupkp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:14:35 INFO - Running setup.py (path:/tmp/pip-YPU6gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:14:35 INFO - Running setup.py (path:/tmp/pip-pipcMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:14:35 INFO - Running setup.py (path:/tmp/pip-pz4acH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:14:35 INFO - Running setup.py (path:/tmp/pip-bOGv9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:14:35 INFO - Running setup.py (path:/tmp/pip-0c1Ds0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:14:35 INFO - Running setup.py (path:/tmp/pip-8Ygfkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:14:35 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)) 23:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:14:35 INFO - Running setup.py (path:/tmp/pip-y38g_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:14:36 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)) 23:14:36 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)) 23:14:36 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)) 23:14:36 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)) 23:14:36 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)) 23:14:36 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)) 23:14:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:37 INFO - Downloading blessings-1.6.tar.gz 23:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:14:37 INFO - Installing collected packages: blessings 23:14:37 INFO - Running setup.py install for blessings 23:14:38 INFO - Successfully installed blessings 23:14:38 INFO - Cleaning up... 23:14:38 INFO - Return code: 0 23:14:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:14:38 INFO - Reading from file tmpfile_stdout 23:14:38 INFO - Current package versions: 23:14:38 INFO - argparse == 1.2.1 23:14:38 INFO - blessings == 1.6 23:14:38 INFO - blobuploader == 1.2.4 23:14:38 INFO - docopt == 0.6.1 23:14:38 INFO - functools32 == 3.2.3-2 23:14:38 INFO - jsonschema == 2.5.1 23:14:38 INFO - manifestparser == 1.1 23:14:38 INFO - mozInstall == 1.12 23:14:38 INFO - mozcrash == 0.17 23:14:38 INFO - mozdebug == 0.1 23:14:38 INFO - mozdevice == 0.48 23:14:38 INFO - mozfile == 1.2 23:14:38 INFO - mozhttpd == 0.7 23:14:38 INFO - mozinfo == 0.9 23:14:38 INFO - mozleak == 0.1 23:14:38 INFO - mozlog == 3.2 23:14:38 INFO - moznetwork == 0.27 23:14:38 INFO - mozprocess == 0.22 23:14:38 INFO - mozprofile == 0.28 23:14:38 INFO - mozrunner == 6.11 23:14:38 INFO - mozscreenshot == 0.1 23:14:38 INFO - mozsystemmonitor == 0.3 23:14:38 INFO - moztest == 0.7 23:14:38 INFO - mozversion == 1.4 23:14:38 INFO - psutil == 3.1.1 23:14:38 INFO - requests == 1.2.3 23:14:38 INFO - wsgiref == 0.1.2 23:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:14: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')]} 23:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14: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')]} 23:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14:38 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 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14:38 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 23:14:38 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 23:14:38 INFO - Using env: {'DISPLAY': ':0', 23:14:38 INFO - 'HOME': '/home/cltbld', 23:14:38 INFO - 'LANG': 'en_US.UTF-8', 23:14:38 INFO - 'LANGUAGE': 'en_US:en', 23:14:38 INFO - 'LOGNAME': 'cltbld', 23:14:38 INFO - 'MAIL': '/var/mail/cltbld', 23:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:38 INFO - 'MOZ_NO_REMOTE': '1', 23:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:38 INFO - 'NO_EM_RESTART': '1', 23:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:38 INFO - 'PWD': '/builds/slave/test', 23:14:38 INFO - 'SHELL': '/bin/bash', 23:14:38 INFO - 'SHLVL': '1', 23:14:38 INFO - 'TERM': 'linux', 23:14:38 INFO - 'TMOUT': '86400', 23:14:38 INFO - 'USER': 'cltbld', 23:14:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:38 INFO - '_': '/tools/buildbot/bin/python'} 23:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 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)) 23:14:38 INFO - Cleaning up... 23:14:38 INFO - Return code: 0 23:14:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:14: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')]} 23:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14: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')]} 23:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14: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', 'jsonschema'] in /builds/slave/test/build 23:14: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 jsonschema 23:14:38 INFO - Using env: {'DISPLAY': ':0', 23:14:38 INFO - 'HOME': '/home/cltbld', 23:14:38 INFO - 'LANG': 'en_US.UTF-8', 23:14:38 INFO - 'LANGUAGE': 'en_US:en', 23:14:38 INFO - 'LOGNAME': 'cltbld', 23:14:38 INFO - 'MAIL': '/var/mail/cltbld', 23:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:38 INFO - 'MOZ_NO_REMOTE': '1', 23:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:38 INFO - 'NO_EM_RESTART': '1', 23:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:38 INFO - 'PWD': '/builds/slave/test', 23:14:38 INFO - 'SHELL': '/bin/bash', 23:14:38 INFO - 'SHLVL': '1', 23:14:38 INFO - 'TERM': 'linux', 23:14:38 INFO - 'TMOUT': '86400', 23:14:38 INFO - 'USER': 'cltbld', 23:14:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:38 INFO - '_': '/tools/buildbot/bin/python'} 23:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:14:38 INFO - Cleaning up... 23:14:38 INFO - Return code: 0 23:14:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:14: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')]} 23:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14: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')]} 23:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:14: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f122719cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2076190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', '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 23:14: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', 'flake8'] in /builds/slave/test/build 23:14: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 flake8 23:14:38 INFO - Using env: {'DISPLAY': ':0', 23:14:38 INFO - 'HOME': '/home/cltbld', 23:14:38 INFO - 'LANG': 'en_US.UTF-8', 23:14:38 INFO - 'LANGUAGE': 'en_US:en', 23:14:38 INFO - 'LOGNAME': 'cltbld', 23:14:38 INFO - 'MAIL': '/var/mail/cltbld', 23:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:38 INFO - 'MOZ_NO_REMOTE': '1', 23:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:14:38 INFO - 'NO_EM_RESTART': '1', 23:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:38 INFO - 'PWD': '/builds/slave/test', 23:14:38 INFO - 'SHELL': '/bin/bash', 23:14:38 INFO - 'SHLVL': '1', 23:14:38 INFO - 'TERM': 'linux', 23:14:38 INFO - 'TMOUT': '86400', 23:14:38 INFO - 'USER': 'cltbld', 23:14:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:38 INFO - '_': '/tools/buildbot/bin/python'} 23:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:39 INFO - Downloading/unpacking flake8 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:14:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:14:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:14:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14: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 23:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:14:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:14:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:14:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:14:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:14:41 INFO - no previously-included directories found matching 'docs/_build' 23:14:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:14:41 INFO - Running setup.py install for pep8 23:14:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:14:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:14:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:14:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:14:41 INFO - no previously-included directories found matching 'docs/_build' 23:14:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:14:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:14:41 INFO - Cleaning up... 23:14:41 INFO - Return code: 0 23:14:41 INFO - Running post-action listener: _resource_record_post_action 23:14:41 INFO - Running post-action listener: _start_resource_monitoring 23:14:41 INFO - Starting resource monitoring. 23:14:41 INFO - ##### 23:14:41 INFO - ##### Finished create-virtualenv step (success) 23:14:41 INFO - ##### 23:14:41 INFO - ##### 23:14:41 INFO - ##### Running install step. 23:14:41 INFO - ##### 23:14:41 INFO - Running pre-action listener: _resource_record_pre_action 23:14:41 INFO - Running main action method: install 23:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:14:42 INFO - Reading from file tmpfile_stdout 23:14:42 INFO - Detecting whether we're running mozinstall >=1.0... 23:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:14:42 INFO - Reading from file tmpfile_stdout 23:14:42 INFO - Output received: 23:14:42 INFO - Usage: mozinstall [options] installer 23:14:42 INFO - Options: 23:14:42 INFO - -h, --help show this help message and exit 23:14:42 INFO - -d DEST, --destination=DEST 23:14:42 INFO - Directory to install application into. [default: 23:14:42 INFO - "/builds/slave/test"] 23:14:42 INFO - --app=APP Application being installed. [default: firefox] 23:14:42 INFO - mkdir: /builds/slave/test/build/application 23:14:42 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'] 23:14:42 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 23:14:59 INFO - Reading from file tmpfile_stdout 23:14:59 INFO - Output received: 23:14:59 INFO - /builds/slave/test/build/application/firefox/firefox 23:14:59 INFO - Running post-action listener: _resource_record_post_action 23:14:59 INFO - ##### 23:14:59 INFO - ##### Finished install step (success) 23:14:59 INFO - ##### 23:14:59 INFO - ##### 23:14:59 INFO - ##### Running run-tests step. 23:14:59 INFO - ##### 23:14:59 INFO - Running pre-action listener: _resource_record_pre_action 23:14:59 INFO - Running main action method: run_tests 23:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:14:59 INFO - Python 2.7.3 23:14:59 INFO - Return code: 0 23:14:59 INFO - grabbing minidump binary from tooltool 23:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:14:59 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 0x2108110>, '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 0x2074230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20746c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:14:59 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 23:14:59 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 23:14:59 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 23:14:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:14:59 INFO - Return code: 0 23:14:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:14:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:14:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:14:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:14:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:15:00 INFO - Return code: 0 23:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 23:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 23:15:00 INFO - Using env: {'DISPLAY': ':0', 23:15:00 INFO - 'HOME': '/home/cltbld', 23:15:00 INFO - 'LANG': 'en_US.UTF-8', 23:15:00 INFO - 'LANGUAGE': 'en_US:en', 23:15:00 INFO - 'LOGNAME': 'cltbld', 23:15:00 INFO - 'MAIL': '/var/mail/cltbld', 23:15:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:15:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:00 INFO - 'MOZ_NO_REMOTE': '1', 23:15:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:00 INFO - 'NO_EM_RESTART': '1', 23:15:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:00 INFO - 'PWD': '/builds/slave/test', 23:15:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:15:00 INFO - 'SHELL': '/bin/bash', 23:15:00 INFO - 'SHLVL': '1', 23:15:00 INFO - 'TERM': 'linux', 23:15:00 INFO - 'TMOUT': '86400', 23:15:00 INFO - 'USER': 'cltbld', 23:15:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417', 23:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:00 INFO - '_': '/tools/buildbot/bin/python'} 23:15:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 23:15:00 INFO - mozversion application_buildid: 20160620203028 23:15:00 INFO - mozversion application_changeset: 027db1e33f89606c4996feae0203e5f3bb5db43d 23:15:00 INFO - mozversion application_display_name: Nightly 23:15:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:15:00 INFO - mozversion application_name: Firefox 23:15:00 INFO - mozversion application_remotingname: firefox 23:15:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:15:00 INFO - mozversion application_vendor: Mozilla 23:15:00 INFO - mozversion application_version: 50.0a1 23:15:00 INFO - mozversion platform_buildid: 20160620203028 23:15:00 INFO - mozversion platform_changeset: 027db1e33f89606c4996feae0203e5f3bb5db43d 23:15:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:15:00 INFO - mozversion platform_version: 50.0a1 23:15:00 INFO - using testdate: 1466489700 23:15:00 INFO - actual date: 1466489700 23:15:00 INFO - starting webserver on 'localhost:44778' 23:15:00 INFO - SUITE-START | Running 2 tests 23:15:00 INFO - TEST-START | dromaeo_css 23:15:00 INFO - operating with platform_type : linux_ 23:15:00 INFO - Initialising browser for dromaeo_css test... 23:15:00 INFO - TEST-INFO | started process 14158 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnKhCoG/profile http://localhost:44778/getInfo.html) 23:15:05 INFO - PROCESS | 14158 | __metrics Screen width/height:1600/1200 23:15:05 INFO - PROCESS | 14158 | colorDepth:24 23:15:05 INFO - PROCESS | 14158 | Browser inner width/height: 1024/697 23:15:05 INFO - PROCESS | 14158 | __metrics 23:15:06 INFO - PROCESS | 14158 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:15:06 INFO - PROCESS | 14158 | console.error: PushService: 23:15:06 INFO - PROCESS | 14158 | stateChangeProcessEnqueue: Error transitioning state 23:15:06 INFO - PROCESS | 14158 | UnknownError 23:15:07 INFO - TEST-INFO | 14158: exit 0 23:15:07 INFO - Browser initialized. 23:15:07 INFO - Running cycle 1/1 for dromaeo_css test... 23:15:07 INFO - TEST-INFO | started process 14240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnKhCoG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:15:19 INFO - PROCESS | 14240 | RSS: Main: 173748224 23:15:19 INFO - PROCESS | 14240 | 23:16:10 INFO - PROCESS | 14240 | 1466489770484 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] 23:18:19 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44778/tests/dromaeo/cssquery-ext.html) 23:18:20 INFO - PROCESS | 14240 | RSS: Main: 301576192 23:18:20 INFO - PROCESS | 14240 | 23:21:23 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-ext.html (next: http://localhost:44778/tests/dromaeo/cssquery-jquery.html) 23:21:24 INFO - PROCESS | 14240 | RSS: Main: 256208896 23:21:24 INFO - PROCESS | 14240 | 23:24:26 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44778/tests/dromaeo/cssquery-mootools.html) 23:24:26 INFO - PROCESS | 14240 | RSS: Main: 261853184 23:24:26 INFO - PROCESS | 14240 | 23:27:28 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44778/tests/dromaeo/cssquery-prototype.html) 23:27:29 INFO - PROCESS | 14240 | RSS: Main: 260726784 23:27:29 INFO - PROCESS | 14240 | 23:30:32 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44778/tests/dromaeo/cssquery-yui.html) 23:30:32 INFO - PROCESS | 14240 | RSS: Main: 224051200 23:30:32 INFO - PROCESS | 14240 | 23:33:35 INFO - PROCESS | 14240 | Cycle 1(1): loaded http://localhost:44778/tests/dromaeo/cssquery-yui.html (next: http://localhost:44778/tests/dromaeo/cssquery-dojo.html) 23:33:36 INFO - PROCESS | 14240 | RSS: Main: 294375424 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | __start_tp_report 23:33:36 INFO - PROCESS | 14240 | _x_x_mozilla_page_load 23:33:36 INFO - PROCESS | 14240 | _x_x_mozilla_page_load_details 23:33:36 INFO - PROCESS | 14240 | |i|pagename|runs| 23:33:36 INFO - PROCESS | 14240 | |0;dojo.html;3067.509198528236;3074.44659961207;3094.0563128245885;3102.3795240951818;3121.5317702344646;5296.92054619181;5350.5184533391985;5418.458154184579;5490.984167928936;5505.449455054492;5401.162819762943;5428.565714742821;5451.727413629323;5502.862428439281;5540.833774986757;4839.540243676845;4887.633427492934;4942.283368911506;4949.480304568023;5148.897022059557;5041.218611115265;5087.236914462842;5095.898082038357;5105.897882042357;5106.055379754757;4842.830500932468;4875.53682400175;4889.29105279736;4966.205407134841;5054.823081192159;4915.287283343895;4917.139500587383;4940.48124946879;4942;4955.925661115086;4897.804087836482;4898.951010489885;4962.851114466572;4980.228064649985;5044.319016932704;5495.422980587061;5558.666480011172;5564.527015203713;5569.470900264469;5571.387147413781;5448;5825.097109947922;5873.089671100944;5893.0276504376725;5978.252718410199;5995.100734889757;5996.8500787480225;6001.309849367335;6014.2181516403025;6016.939830601682;5605;5614.214010038598;5614.803481878114;5616.157576363578;5619.887602247933;5829.679367634736;5918.644881307136;6005.369436209223;6007.849803754941;6079.08813677943;5801.3038435388025;5854.121881717691;5856.12158176277;5862.179294898718;5863.061910094364;5695.40198279183;5873.412658734178;5885.2349194605285;5943.138244954457;5944.3164036136395;5819.505342045889;5876.706164691834;5881.147233651097;5920.259967504062;5925.851853703692;5813.76744930198;5828.0966450200285;5845.620034697816;5864.149698293809;5864.296284445893;4421.204183246982;4460.286354183315;4504.752238626841;4516.209663308908;4542.1597004553605;3988.3618621020496;3988.66096381805;3995.92008159841;4039.6768258539246;4056.6957478188665;5584.050711379075;5594.384617692051;5605.831825045255;5608.69152196625;5611.102223644198;5792.739326730307;5808.099744539603;5947;5954;5997.820065398045;5487.42382049887;5518.33779946409;5524.861878452991;5540.196671482693;5581.972090139523;4889.48660390661;4893.877653058702;4898.8775280618265;4901.375370713199;4943.530364615356;5870.970645146833;5918.26021747273;5973.49225315853;6032.426919442646;6037.607555508878;5879.617824841372;5885.588008839341;5887.4406931341455;5890.676012819336;5892.204552385373;6008.459238668541;6020.277566692025;6021.2473440819895;6023.578349515492;6023.975924092827;5628.4090170533145;5715.428457154251;5742.59801813869;5751.798687046101;5767.423257674199;5875.882482350244;5879.176915231957;5879.823605291848;5883.2057672215415;5885.941140588539;5674.177244299636;5743.827685169452;5744.540436765133;5744.597878148489;5747.4252574742195;5610.579206559442;5788.305403351625;5793;5821.912671309849;5866.41335866427;5688.11834165705;5719.599628025998;5729.169270455844;5729.570282228766;5732;5603.299587551556;5836.182934389104;5895.823125306248;5897.498712609477;5907.763689452546;5441.075017246998;5517.613767036269;5609.354924183771;5612.803551875665;5646.463585959327 23:33:36 INFO - PROCESS | 14240 | |1;ext.html;576.3803910795896;618.5608218165103;624.0327492386799;630.6027202862198;634.0394302631516;30469.238269043286;42382;42757.358639620354;42767.78616106919;42802.78598607003;45178.32232516515;45756.54243457565;45781.771091144496;45826.77086614562;45831.54168458314;8138.877916831252;8864.778380540474;9086.59110340033;10622.36265824053;10883.564657413694;10779.622713205;10894.509747061402;10916.672499824977;10918.07196388308;10922.617708380209;10943.23397362185;10986.505607247693;10993;11003;11003.394813285266;8207.958960205191;10715.196360272972;11055.11559075272;11057;11062.280951738192;10632.415217163052;10707.785844283071;10729.302595331357;11012.284201526874;11014.229003969725;42522.36216456756;43053.354199687026;43076.56923430757;43130.56869431328;43162;3561.269939662389;3588.8385022674042;3741.8877433677035;3755.0424641716186;3755.661990420848;33173.33653326944;33339.6666033339;33748.15629609255;33800.15499612505;33848;4321.58944900234;4477.9104417911785;4507.481639611454;4565.2467342888385;4624.953750462486;314943;321467;321648;322088;322111;63414.36585634177;63968;64425;64470.67764661241;64505;67243.66378168175;67466;67478;68340;68345.65827170931;15802.36790528389;17673.646527069388;17826.732599011026;17832;17833.197506112258;13289.401976911062;13461.057725959088;13506.797398039038;13756.724865502634;13757.243351615558;19706.70439943402;19712.60574788496;20023.899880500503;20034.89982550078;20139.697904531444;6010.978133717279;7258.383037441877;7323.450741194431;7415.3326200642205;7570.621468926532;962.7208109648263;1030.253066526777;1043.12377602814;1048.6376673040118;1125.9324440533594;31290.530642040387;31359.529607055912;31622.209444764067;31868.362632747216;32066.198345041095;1123.8651361836633;1181.7459246261924;1201.483362154282;1201.7956947318976;1237.0351126121639;16532.421365252158;16679;16824.327026918785;16982.745258821127;17061.57346066358;552096;555993;559498;559646;561146;59511.70244148751;60130.39869601248;60204.096938545954;60226.69886650539;60236;1253.129075292663;1284.1588759362633;1304.9608511744664;1317.914335568185;1344.8587898270541;32060.67939320577;33377.66622333746;33535.32929341351;33640.1589960253;33804.268135613485;50804.237936431724;51401.48598513967;51536.742316288175;51644.22533661923;51647.741761290956;406416;409540;409966;411147;411903;25305.620415694146;27085.864570677808;27778.166655000383;28023.29941751391;29595.556066658588;46823.29765053459;47919.76040119777;48340;49069;49471.50528494813;40525.79737101296;40852;40975.385369218886;41016.58983410128;42152.156956861305;40382.596174037884;40545.391819122146;40883.79558102191;40891;42031.15937681168;40238.39642405308;41281.793591031856;41287.793561032006;41376;41809.581904180566;35104.47343289904;36285.27429451449;36750.44874326834;36836.26327473489;37009;26945.191644250703;27588;27776.16671499858;29208.415831683673;29723.55414668825 23:33:36 INFO - PROCESS | 14240 | |2;jquery.html;392.9351656976599;397.14019148543423;400.18562225870323;410.3659845538642;417.0710464103521;13997.720045599082;17494.825051749478;17880.731789023157;19197.71203431948;19252.614947701055;16437.506874793777;16510.091944943022;16729.330826766945;18197.818021819778;18282.360117395892;5661.23573317603;5665.773369065243;5866.5306775458075;5882.088276317157;5916.556258280624;4139.710220284582;4349.934750978738;5745.5690823188215;5813.5349172066135;5842.240508733858;6065.969670151665;6338.904916426257;6342.98512238042;6444.548881578291;6449.83875403114;6331.398517140864;6369.331220221903;6386.2975072741965;6555.541112122153;6557.737690492375;5725;5741.971290143564;5748.42515748426;5753.050746626844;5782.595218334718;22397.104115835347;22407.439814004618;23388.415289617726;23518.64722029171;23527.058917643273;2358.8466749661216;2420.1771397724856;2698.150082723955;2753.697093319733;3081;14415.855841441557;14473.710525789531;14495;14690.559283221413;15172.924135379362;1895.4787433455772;1980.722698822166;2015.4356780101596;2024.98987505063;2094.4554415851835;76482;79202.20797792064;79269;80971.99999999942;81548.06841418486;10221.540030698636;10447.791044179074;10549.630762923414;10682.198835087243;10842.349459032483;12748.681282968;14650.633734156518;14684.19236941957;14702.852971470362;14717.33771980242;8183.672653093929;8446.155384461505;8498.277646400002;8520.659173633108;8710.346723995726;8094.51432914027;8104.716334928367;8495.660173592987;8558.315334773204;8640.30877529796;6308.905366419507;6694.397504224643;6807.421369183576;6812.62530560814;6858;1861.3671351740472;1920.769507659062;1952.7949565295723;1964.705294205852;1968.340605897012;925.3013974449248;976.6923419122953;1007.3552926127285;1058.9046985771163;1071.3518321415588;20101.59796804085;20107;20722.58554828925;20777.168913243604;21107.68338474924;1006.0995409108807;1016.506994107857;1019.1184625299211;1031.3038698878227;1103.7957977774138;10905.34567925927;11471.4264286787;11482.598109066143;11564.13269004828;11745.11911606633;453492;456125;457439;458220;461072;95184;95602;95778;96446;96775.51612242035;958.1999030809352;965.0446058402272;980.6518685866557;980.9264305176997;982.1946004276567;17680.64638707193;17863.479829861746;18117.094145292947;18323;18551.72172417388;31808.363832722753;39670.80164599274;41192.588074118874;41922.79038604788;43150.35274470822;55315.44684553264;56574.43425565856;58970.70514647399;59191.81616367563;59869;7535.623218839146;7595.278448547379;7753.185915479019;8152.347812174893;8821.720850476773;11882.702932426759;12243.081768867549;12249.816252756395;13279.867201327863;13375.665608359868;11378.715532111764;11663.5917742882;11779.469923853618;12134.393280335771;12220.816687749513;10839.783204336027;10971.561137554409;11757.470913808727;11883.58407455735;12360.196587221802;11637.010854077498;11657.708557286136;11676.94161529187;11924.582639607572;12421.875781242072;7989.48068375577;8079.272865442138;8093.635786389509;8119.066307374727;8148.3888708348295;11687.240329378563;12081.456334465145;12108;12159.81760273579;12377.195482293982 23:33:36 INFO - PROCESS | 14240 | |3;mootools.html;1315.4869600855661;1469.9999999999998;1523.6571771351446;1649.9092549909758;1807.9276828926836;8629.395942284043;9046.547672616376;9047.41191822532;9315.767105822359;9340.439573625586;9055.275577953764;9286.814263714721;9286.953565232165;9288.953555232214;9295.024022477644;2005.9699104513445;2219.1012639880873;2272.534130503247;2297.6094064009158;2305.538892221554;2327.8836058197107;2330.3707998840305;2334.9182778602753;2336.1122773346106;2336.5910965581043;2268.5689718953345;2286.017012684545;2288.0504590594883;2289.3132060381904;2303.216906251874;1928.9421317360432;2176.6082105221053;2226.897685645608;2241.3276017194776;2244.764299748519;2190.9561808763897;2219.7225346831647;2235.865848049122;2239.29462219402;2239.9216027439124;9123.771905702344;9256.444613323221;9267.582958766805;9276.536173191313;9287.257019438428;5305.071612467841;5398.24424580559;5406.918896216521;5412.810551630713;5420.051490989101;3290.7860989035876;3381.1885147564653;3400.8129552874584;3450.8447119879665;3455.6198818129988;1147.6901236666054;1147.8450409194736;1148.0757989818194;1150.1431433581952;1174.5771522251982;13041.543545975845;13114.409851556607;13127.868721312856;13144.40850161745;13156.144850584682;4711.687070650913;4762.285657151455;4844.370231869834;4862.124817532878;4870.342503762017;10934.398608076632;10948.45257737126;10961;10968.451577421098;10985.780284394268;5347;5383.865403364869;5385.192221166856;5389.0299746046085;5390.730463476889;4485.125400546927;4867.829625963074;5013.824516141917;5014.974925125424;5115.079285728604;4071.857484988011;4120.052387950788;4129.97935010323;4138.627523522898;4167.5832416758085;1243.2229856339789;1269.8666640002853;1272.8599854016052;1289.078309009063;1296.7341694952513;999.5951639585979;1016.2479764973866;1020.6172685243034;1030.6392762533053;1033.813913495563;6272.5922815016875;6377.7448902044325;6387.329330420239;6404.1674582304;6474.643894585843;439.3914428516464;444.613186527723;449.37536823814924;451.719933641138;461.35871139116654;4270.701050926467;4342.218400687843;4445.82216711328;4478.216312145361;4496.775161241925;11323.547058117747;11585.131115166396;11632.069434445224;11735.706607334714;11751.118666100077;11577.884221157849;11590.652280431601;11593.304401735923;11594.47824847867;11627.418629068512;8123.147069557729;8417.579121043924;8436.957815210884;8450.408471406943;8569.228769410658;6073.423024812636;6179.073139029002;6280.623162610258;6487.3836985486305;6648.767293144911;10711.8928810714;10756.623518176826;10792.622258220925;10880.183986201226;10981.3960232188;11058.612948546763;11063.170262230526;11065.668029959113;11405.258658187191;11411.942940285246;7066.399356054793;7259.491835571459;7346.37555807741;7833.843323133619;7992.16082311349;8629.13708629132;8907.376483646083;8943.552822358726;9149.817003659757;9184.678536251198;7760.84478310442;7909.24862138096;7923.643436045277;7981.122076571689;8034.156413576724;7610.961945190239;7737.690492380242;7803.960980195063;7840.098388685146;7917.7624671259955;7207.9639601801655;7229.385502232369;7294.197638259681;7302.233265507045;7306.561606303639;5281.207818827053;5408.918866216931;5505.642133261325;5564.443555644403;5674.971625141848;5011.874703132305;5186.4035635902355;5224.138017227267;5279.47205279469;5343.812966546152 23:33:36 INFO - PROCESS | 14240 | |4;prototype.html;322.3568979885128;331.9535265062891;336.92756057447644;337.8361494675083;339.73840143089836;5170.146925757245;5302.840914772553;5323.7071961042175;5337.252784610153;5372.785088596456;5319.973400133003;5324.5740340772745;5338.599605029619;5340.198970154479;5354.330708661418;4245.617894389505;4255.914741740852;4261.637760790337;4265.104328091104;4276.42268293781;3991.2616166009234;4001.1397549526846;4149.605787450202;4194.958050419503;4204.2222188895;4105.4252404663375;4149.443958515557;4168.208040472316;4169.499660040799;4175.770332631704;4141.523724771645;4148.0874207674115;4156.085661154541;4161.97919010403;4170.47869016373;3441.328940856534;3720.6837418819164;3732.1789206374556;3732.981335093334;3743.5025989604105;5185.677652198703;5208.6093542984045;5220.973895130538;5224.242484839715;5232.764525596319;4692.765361731934;4709.9764501177615;4717.315989181549;4725.10223057618;4733.148033353995;5378.951104534617;5549.279723285795;5552.139418390156;5571.13647384656;5579.525740312079;3929.9017524561827;3937.6652984496354;3944.664703500206;3952.565217826037;3954;5791.681457519835;5840.415958404125;5864.501517371029;5876.441738034837;5879.235699359098;3902.6292502212245;4074.6536544393466;4084.1008930847297;4098.385242213632;4100.7334523255895;4091.0795071109355;4091.488563929509;4106.342985122366;4115.403266526397;4123.257813593582;4715.41057367829;4719.079779442975;4730.148573256778;4732.408448943882;4733.715977041388;5511.889762204733;5587.972060139755;5641.012822756084;5654.717264136777;5656.971715141398;1411.089847048648;1424.1953296387508;1425.5530636404058;1425.836028856674;1439.6688761584894;884.5975081338105;905.8767128760421;916.8578870275118;919.9632014719471;929.8140371925642;4685.344051832746;4735.502772208937;4768.546988036131;4822.927656085161;4836.685615434985;5044.495550444926;5067.631739430407;5081.466446023188;5120.48795120485;5141.948580514147;4653.278741795028;4862.562369386772;4887.877803054952;4892.706437613754;4900.926486102711;2683.8658067096567;2700.824446410977;2741.2735625059377;2743.190758726181;2757.9724202758116;5131.692098474104;5182.507661772125;5188.896222075613;5221.895562088737;5224.786756144944;4942.579880710108;5165.225216217597;5172.5603323717905;5174.223866419992;5238.895222095613;5150.27896094547;5211.4527974562125;5239.039942432513;5246.265522826808;5353.571714262927;5051.141305977919;5105.851183483835;5107.668001579886;5108.897822043464;5160.329157209539;4789.7605119743175;4933.284673722362;5026.346574945233;5070.8225212117395;5096.46487118862;5120.924605832817;5149.742512874266;5169.896602068013;5186.688798672092;5188.22176673502;3767.547894252707;3793.6016718244186;3876.3022655922205;4006.4791577094793;4014.1369605533782;5619.072852979212;5658.40586738403;5697.943020569907;5700.401457847032;5703.517085557868;5421.484958928895;5529.944700552943;5595;5597.356304025089;5623;5538.335399752055;5540.806071787468;5544.279243698294;5552.639078459887;5561.582881283839;5442.775375540472;5464.398916119303;5468.644538105011;5469.562435005269;5488.204210389391;5365.919511207413;5569.610127291213;5578.804741834017;5604.495595396434;5681.8863622726485;5511.53151982086;5522.86781209843;5530.562053865943;5530.834074977757;5573.7213139343685 23:33:36 INFO - PROCESS | 14240 | |5;yui.html;782.2646712090631;783.4437549339968;795.6220795122317;820.7783898347446;831.2850948184562;13924.582262532127;14069.437222511087;14069.507567235149;14489.927550362234;14627.341769620367;13575.117617354865;13615.931920340385;13772.173669579806;13773.380197891094;13796.862031379684;663.2439019517753;781.121238606569;787.4881327137377;798.2935102434359;800.6877317846045;796.2714116583319;803.8311954489565;804.2922228438995;806.4878801960739;806.7055524733489;842.9620667069996;848.1645579104577;849.2017503546647;849.3375167369462;851.093585331623;687.700850130195;820.6389188756928;840.9873851892227;842.7682387343499;847.2628812932758;613.8342647485199;614.0512907557834;614.3457218062749;614.7387360371824;615.0681717198448;14046.508372206923;14062.718745625132;14079.648008799759;14084.2957852107;14109.576712698636;13142.671433214151;13516.459341626323;13625.25061121638;13793.793093103612;13938.163710177421;12903.935480322632;12982.415791289415;12999.935000325033;13082.345882705828;13633.113847599874;4504.909901801979;4515.277555591089;4575.565321294472;4637.652176086807;4741.407324084489;76348.61825690836;76725.61637191779;77312.61343693246;79516.60241698755;82827.9999999994;10889.891101088888;11262.493187806569;11377.65867023974;11709.121815863844;11922.225055371373;18982.43052708421;19005.61988760217;19009.619807604045;19011.61976760457;19024.52438689044;8077.676892924334;8191.795205119801;8248.340132789363;8324.417290789708;8415.537145456936;4580.770961451914;4683.625309975262;4703.553162449562;4800;4894.241392584155;3393.270446853938;3497.143199916037;3502.141975216088;3505.4040813061843;3515.789052656849;609.911562823397;624.0327492386782;626.1609443345939;633.8700566383881;638.5562034386057;580.4833698008775;663.280340830195;665.5241502325861;669.5413641655482;681.5808277909149;8279.668813247403;8740.956295218482;8870.86693699595;8928.419652722681;8957.462552246887;624.9407254703234;625.3933684326196;632.5508888689083;633.395107672172;670.9127813384326;4232.343986682069;4291.077418355068;4309.6767742418815;4322.286822674287;4327.221100201931;114915;115071;115567;116752;117187.38282011641;98780.50609746906;99632;100329;100489.49755251323;100981;60979.69510152421;81907;82521.58739206266;82687.17312826915;83725.58137209275;7874;8046.718364857202;8125.187481251827;8286.875696864432;8292.958535207526;51877.7406112967;51902.48097518976;52175.73912130415;53908.73045634747;55051.44948550624;52924;73173.634131829;74319;75655;78137.60931195307;10627.574897004033;10830.40432776205;10942.945285273523;11045.723856903642;11524.366159860952;11998.220115692453;12111.515539378326;12210.26738395699;12401.62795116148;12725.045621578603;11951.05107044753;12163.574274900337;12301.261924284572;12361.134720569833;12679.556215532411;11637.359945203096;11757.470913808727;12007.879921200676;12436.378181090728;12448.68878278014;11426.314421134759;12030;12047.457864395948;12407.379631018593;12408;9000.50497222659;9255;9421.576029078571;9423.905760942303;9785.559649815918;9138;9161.358704890594;9403.85894211601;9907.917687457035;9926.205903527845 23:33:36 INFO - PROCESS | 14240 | __end_tp_report 23:33:36 INFO - PROCESS | 14240 | __start_cc_report 23:33:36 INFO - PROCESS | 14240 | _x_x_mozilla_cycle_collect,1023 23:33:36 INFO - PROCESS | 14240 | __end_cc_report 23:33:36 INFO - PROCESS | 14240 | __startTimestamp1466490816117__endTimestamp 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | ------- Summary: start ------- 23:33:36 INFO - PROCESS | 14240 | Number of tests: 6 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#0] dojo.html Cycles:165 Average:5446.90 Median:5628.41 stddev:635.28 (11.3%) stddev-sans-first:609.18 23:33:36 INFO - PROCESS | 14240 | Values: 3067.5 3074.4 3094.1 3102.4 3121.5 5296.9 5350.5 5418.5 5491.0 5505.4 5401.2 5428.6 5451.7 5502.9 5540.8 4839.5 4887.6 4942.3 4949.5 5148.9 5041.2 5087.2 5095.9 5105.9 5106.1 4842.8 4875.5 4889.3 4966.2 5054.8 4915.3 4917.1 4940.5 4942.0 4955.9 4897.8 4899.0 4962.9 4980.2 5044.3 5495.4 5558.7 5564.5 5569.5 5571.4 5448.0 5825.1 5873.1 5893.0 5978.3 5995.1 5996.9 6001.3 6014.2 6016.9 5605.0 5614.2 5614.8 5616.2 5619.9 5829.7 5918.6 6005.4 6007.8 6079.1 5801.3 5854.1 5856.1 5862.2 5863.1 5695.4 5873.4 5885.2 5943.1 5944.3 5819.5 5876.7 5881.1 5920.3 5925.9 5813.8 5828.1 5845.6 5864.1 5864.3 4421.2 4460.3 4504.8 4516.2 4542.2 3988.4 3988.7 3995.9 4039.7 4056.7 5584.1 5594.4 5605.8 5608.7 5611.1 5792.7 5808.1 5947.0 5954.0 5997.8 5487.4 5518.3 5524.9 5540.2 5582.0 4889.5 4893.9 4898.9 4901.4 4943.5 5871.0 5918.3 5973.5 6032.4 6037.6 5879.6 5885.6 5887.4 5890.7 5892.2 6008.5 6020.3 6021.2 6023.6 6024.0 5628.4 5715.4 5742.6 5751.8 5767.4 5875.9 5879.2 5879.8 5883.2 5885.9 5674.2 5743.8 5744.5 5744.6 5747.4 5610.6 5788.3 5793.0 5821.9 5866.4 5688.1 5719.6 5729.2 5729.6 5732.0 5603.3 5836.2 5895.8 5897.5 5907.8 5441.1 5517.6 5609.4 5612.8 5646.5 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#1] ext.html Cycles:180 Average:60063.00 Median:36793.36 stddev:116710.87 (317.2%) stddev-sans-first:116952.81 23:33:36 INFO - PROCESS | 14240 | Values: 576.4 618.6 624.0 630.6 634.0 30469.2 42382.0 42757.4 42767.8 42802.8 45178.3 45756.5 45781.8 45826.8 45831.5 8138.9 8864.8 9086.6 10622.4 10883.6 10779.6 10894.5 10916.7 10918.1 10922.6 10943.2 10986.5 10993.0 11003.0 11003.4 8208.0 10715.2 11055.1 11057.0 11062.3 10632.4 10707.8 10729.3 11012.3 11014.2 42522.4 43053.4 43076.6 43130.6 43162.0 3561.3 3588.8 3741.9 3755.0 3755.7 33173.3 33339.7 33748.2 33800.2 33848.0 4321.6 4477.9 4507.5 4565.2 4625.0 314943.0 321467.0 321648.0 322088.0 322111.0 63414.4 63968.0 64425.0 64470.7 64505.0 67243.7 67466.0 67478.0 68340.0 68345.7 15802.4 17673.6 17826.7 17832.0 17833.2 13289.4 13461.1 13506.8 13756.7 13757.2 19706.7 19712.6 20023.9 20034.9 20139.7 6011.0 7258.4 7323.5 7415.3 7570.6 962.7 1030.3 1043.1 1048.6 1125.9 31290.5 31359.5 31622.2 31868.4 32066.2 1123.9 1181.7 1201.5 1201.8 1237.0 16532.4 16679.0 16824.3 16982.7 17061.6 552096.0 555993.0 559498.0 559646.0 561146.0 59511.7 60130.4 60204.1 60226.7 60236.0 1253.1 1284.2 1305.0 1317.9 1344.9 32060.7 33377.7 33535.3 33640.2 33804.3 50804.2 51401.5 51536.7 51644.2 51647.7 406416.0 409540.0 409966.0 411147.0 411903.0 25305.6 27085.9 27778.2 28023.3 29595.6 46823.3 47919.8 48340.0 49069.0 49471.5 40525.8 40852.0 40975.4 41016.6 42152.2 40382.6 40545.4 40883.8 40891.0 42031.2 40238.4 41281.8 41287.8 41376.0 41809.6 35104.5 36285.3 36750.4 36836.3 37009.0 26945.2 27588.0 27776.2 29208.4 29723.6 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#2] jquery.html Cycles:180 Average:28243.62 Median:2725.92 stddev:75606.48 (2773.6%) stddev-sans-first:75789.66 23:33:36 INFO - PROCESS | 14240 | Values: 392.9 397.1 400.2 410.4 417.1 13997.7 17494.8 17880.7 19197.7 19252.6 16437.5 16510.1 16729.3 18197.8 18282.4 5661.2 5665.8 5866.5 5882.1 5916.6 4139.7 4349.9 5745.6 5813.5 5842.2 6066.0 6338.9 6343.0 6444.5 6449.8 6331.4 6369.3 6386.3 6555.5 6557.7 5725.0 5742.0 5748.4 5753.1 5782.6 22397.1 22407.4 23388.4 23518.6 23527.1 2358.8 2420.2 2698.2 2753.7 3081.0 14415.9 14473.7 14495.0 14690.6 15172.9 1895.5 1980.7 2015.4 2025.0 2094.5 76482.0 79202.2 79269.0 80972.0 81548.1 10221.5 10447.8 10549.6 10682.2 10842.3 12748.7 14650.6 14684.2 14702.9 14717.3 8183.7 8446.2 8498.3 8520.7 8710.3 8094.5 8104.7 8495.7 8558.3 8640.3 6308.9 6694.4 6807.4 6812.6 6858.0 1861.4 1920.8 1952.8 1964.7 1968.3 925.3 976.7 1007.4 1058.9 1071.4 20101.6 20107.0 20722.6 20777.2 21107.7 1006.1 1016.5 1019.1 1031.3 1103.8 10905.3 11471.4 11482.6 11564.1 11745.1 453492.0 456125.0 457439.0 458220.0 461072.0 95184.0 95602.0 95778.0 96446.0 96775.5 958.2 965.0 980.7 980.9 982.2 17680.6 17863.5 18117.1 18323.0 18551.7 31808.4 39670.8 41192.6 41922.8 43150.4 55315.4 56574.4 58970.7 59191.8 59869.0 7535.6 7595.3 7753.2 8152.3 8821.7 11882.7 12243.1 12249.8 13279.9 13375.7 11378.7 11663.6 11779.5 12134.4 12220.8 10839.8 10971.6 11757.5 11883.6 12360.2 11637.0 11657.7 11676.9 11924.6 12421.9 7989.5 8079.3 8093.6 8119.1 8148.4 11687.2 12081.5 12108.0 12159.8 12377.2 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#3] mootools.html Cycles:180 Average:6033.28 Median:2467.25 stddev:3559.68 (144.3%) stddev-sans-first:3552.00 23:33:36 INFO - PROCESS | 14240 | Values: 1315.5 1470.0 1523.7 1649.9 1807.9 8629.4 9046.5 9047.4 9315.8 9340.4 9055.3 9286.8 9287.0 9289.0 9295.0 2006.0 2219.1 2272.5 2297.6 2305.5 2327.9 2330.4 2334.9 2336.1 2336.6 2268.6 2286.0 2288.1 2289.3 2303.2 1928.9 2176.6 2226.9 2241.3 2244.8 2191.0 2219.7 2235.9 2239.3 2239.9 9123.8 9256.4 9267.6 9276.5 9287.3 5305.1 5398.2 5406.9 5412.8 5420.1 3290.8 3381.2 3400.8 3450.8 3455.6 1147.7 1147.8 1148.1 1150.1 1174.6 13041.5 13114.4 13127.9 13144.4 13156.1 4711.7 4762.3 4844.4 4862.1 4870.3 10934.4 10948.5 10961.0 10968.5 10985.8 5347.0 5383.9 5385.2 5389.0 5390.7 4485.1 4867.8 5013.8 5015.0 5115.1 4071.9 4120.1 4130.0 4138.6 4167.6 1243.2 1269.9 1272.9 1289.1 1296.7 999.6 1016.2 1020.6 1030.6 1033.8 6272.6 6377.7 6387.3 6404.2 6474.6 439.4 444.6 449.4 451.7 461.4 4270.7 4342.2 4445.8 4478.2 4496.8 11323.5 11585.1 11632.1 11735.7 11751.1 11577.9 11590.7 11593.3 11594.5 11627.4 8123.1 8417.6 8437.0 8450.4 8569.2 6073.4 6179.1 6280.6 6487.4 6648.8 10711.9 10756.6 10792.6 10880.2 10981.4 11058.6 11063.2 11065.7 11405.3 11411.9 7066.4 7259.5 7346.4 7833.8 7992.2 8629.1 8907.4 8943.6 9149.8 9184.7 7760.8 7909.2 7923.6 7981.1 8034.2 7611.0 7737.7 7804.0 7840.1 7917.8 7208.0 7229.4 7294.2 7302.2 7306.6 5281.2 5408.9 5505.6 5564.4 5675.0 5011.9 5186.4 5224.1 5279.5 5343.8 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#4] prototype.html Cycles:180 Average:4524.49 Median:5106.76 stddev:1304.14 (25.5%) stddev-sans-first:1269.08 23:33:36 INFO - PROCESS | 14240 | Values: 322.4 332.0 336.9 337.8 339.7 5170.1 5302.8 5323.7 5337.3 5372.8 5320.0 5324.6 5338.6 5340.2 5354.3 4245.6 4255.9 4261.6 4265.1 4276.4 3991.3 4001.1 4149.6 4195.0 4204.2 4105.4 4149.4 4168.2 4169.5 4175.8 4141.5 4148.1 4156.1 4162.0 4170.5 3441.3 3720.7 3732.2 3733.0 3743.5 5185.7 5208.6 5221.0 5224.2 5232.8 4692.8 4710.0 4717.3 4725.1 4733.1 5379.0 5549.3 5552.1 5571.1 5579.5 3929.9 3937.7 3944.7 3952.6 3954.0 5791.7 5840.4 5864.5 5876.4 5879.2 3902.6 4074.7 4084.1 4098.4 4100.7 4091.1 4091.5 4106.3 4115.4 4123.3 4715.4 4719.1 4730.1 4732.4 4733.7 5511.9 5588.0 5641.0 5654.7 5657.0 1411.1 1424.2 1425.6 1425.8 1439.7 884.6 905.9 916.9 920.0 929.8 4685.3 4735.5 4768.5 4822.9 4836.7 5044.5 5067.6 5081.5 5120.5 5141.9 4653.3 4862.6 4887.9 4892.7 4900.9 2683.9 2700.8 2741.3 2743.2 2758.0 5131.7 5182.5 5188.9 5221.9 5224.8 4942.6 5165.2 5172.6 5174.2 5238.9 5150.3 5211.5 5239.0 5246.3 5353.6 5051.1 5105.9 5107.7 5108.9 5160.3 4789.8 4933.3 5026.3 5070.8 5096.5 5120.9 5149.7 5169.9 5186.7 5188.2 3767.5 3793.6 3876.3 4006.5 4014.1 5619.1 5658.4 5697.9 5700.4 5703.5 5421.5 5529.9 5595.0 5597.4 5623.0 5538.3 5540.8 5544.3 5552.6 5561.6 5442.8 5464.4 5468.6 5469.6 5488.2 5365.9 5569.6 5578.8 5604.5 5681.9 5511.5 5522.9 5530.6 5530.8 5573.7 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - PROCESS | 14240 | [#5] yui.html Cycles:180 Average:20089.92 Median:52549.87 stddev:29820.72 (56.7%) stddev-sans-first:29869.13 23:33:36 INFO - PROCESS | 14240 | Values: 782.3 783.4 795.6 820.8 831.3 13924.6 14069.4 14069.5 14489.9 14627.3 13575.1 13615.9 13772.2 13773.4 13796.9 663.2 781.1 787.5 798.3 800.7 796.3 803.8 804.3 806.5 806.7 843.0 848.2 849.2 849.3 851.1 687.7 820.6 841.0 842.8 847.3 613.8 614.1 614.3 614.7 615.1 14046.5 14062.7 14079.6 14084.3 14109.6 13142.7 13516.5 13625.3 13793.8 13938.2 12903.9 12982.4 12999.9 13082.3 13633.1 4504.9 4515.3 4575.6 4637.7 4741.4 76348.6 76725.6 77312.6 79516.6 82828.0 10889.9 11262.5 11377.7 11709.1 11922.2 18982.4 19005.6 19009.6 19011.6 19024.5 8077.7 8191.8 8248.3 8324.4 8415.5 4580.8 4683.6 4703.6 4800.0 4894.2 3393.3 3497.1 3502.1 3505.4 3515.8 609.9 624.0 626.2 633.9 638.6 580.5 663.3 665.5 669.5 681.6 8279.7 8741.0 8870.9 8928.4 8957.5 624.9 625.4 632.6 633.4 670.9 4232.3 4291.1 4309.7 4322.3 4327.2 114915.0 115071.0 115567.0 116752.0 117187.4 98780.5 99632.0 100329.0 100489.5 100981.0 60979.7 81907.0 82521.6 82687.2 83725.6 7874.0 8046.7 8125.2 8286.9 8293.0 51877.7 51902.5 52175.7 53908.7 55051.4 52924.0 73173.6 74319.0 75655.0 78137.6 10627.6 10830.4 10942.9 11045.7 11524.4 11998.2 12111.5 12210.3 12401.6 12725.0 11951.1 12163.6 12301.3 12361.1 12679.6 11637.4 11757.5 12007.9 12436.4 12448.7 11426.3 12030.0 12047.5 12407.4 12408.0 9000.5 9255.0 9421.6 9423.9 9785.6 9138.0 9161.4 9403.9 9907.9 9926.2 23:33:36 INFO - PROCESS | 14240 | -------- Summary: end -------- 23:33:36 INFO - PROCESS | 14240 | 23:33:36 INFO - TEST-INFO | 14240: exit 0 23:33:36 INFO - TEST-OK | dromaeo_css | took 1116418ms 23:33:36 INFO - TEST-START | kraken 23:33:36 INFO - operating with platform_type : linux_ 23:33:36 INFO - Initialising browser for kraken test... 23:33:36 INFO - TEST-INFO | started process 14422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaq0tSw/profile http://localhost:44778/getInfo.html) 23:33:41 INFO - PROCESS | 14422 | __metrics Screen width/height:1600/1200 23:33:41 INFO - PROCESS | 14422 | colorDepth:24 23:33:41 INFO - PROCESS | 14422 | Browser inner width/height: 1024/697 23:33:41 INFO - PROCESS | 14422 | __metrics 23:33:42 INFO - PROCESS | 14422 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:33:43 INFO - TEST-INFO | 14422: exit 0 23:33:43 INFO - Browser initialized. 23:33:43 INFO - Running cycle 1/1 for kraken test... 23:33:43 INFO - TEST-INFO | started process 14505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaq0tSw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:33:55 INFO - PROCESS | 14505 | RSS: Main: 174252032 23:33:55 INFO - PROCESS | 14505 | 23:33:58 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44778/tests/kraken/driver.html?testName=audio-beat-detection) 23:33:59 INFO - PROCESS | 14505 | RSS: Main: 233078784 23:33:59 INFO - PROCESS | 14505 | 23:34:01 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44778/tests/kraken/driver.html?testName=audio-dft) 23:34:02 INFO - PROCESS | 14505 | RSS: Main: 221491200 23:34:02 INFO - PROCESS | 14505 | 23:34:05 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44778/tests/kraken/driver.html?testName=audio-fft) 23:34:06 INFO - PROCESS | 14505 | RSS: Main: 286658560 23:34:06 INFO - PROCESS | 14505 | 23:34:08 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44778/tests/kraken/driver.html?testName=audio-oscillator) 23:34:08 INFO - PROCESS | 14505 | RSS: Main: 198189056 23:34:08 INFO - PROCESS | 14505 | 23:34:11 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44778/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:34:11 INFO - PROCESS | 14505 | RSS: Main: 201961472 23:34:11 INFO - PROCESS | 14505 | 23:34:14 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44778/tests/kraken/driver.html?testName=imaging-darkroom) 23:34:15 INFO - PROCESS | 14505 | RSS: Main: 238395392 23:34:15 INFO - PROCESS | 14505 | 23:34:18 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44778/tests/kraken/driver.html?testName=imaging-desaturate) 23:34:19 INFO - PROCESS | 14505 | RSS: Main: 239157248 23:34:19 INFO - PROCESS | 14505 | 23:34:22 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44778/tests/kraken/driver.html?testName=json-parse-financial) 23:34:22 INFO - PROCESS | 14505 | RSS: Main: 238776320 23:34:22 INFO - PROCESS | 14505 | 23:34:24 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44778/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:34:24 INFO - PROCESS | 14505 | RSS: Main: 227438592 23:34:24 INFO - PROCESS | 14505 | 23:34:26 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:34:27 INFO - PROCESS | 14505 | RSS: Main: 228667392 23:34:27 INFO - PROCESS | 14505 | 23:34:29 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:34:29 INFO - PROCESS | 14505 | RSS: Main: 236924928 23:34:29 INFO - PROCESS | 14505 | 23:34:32 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:34:33 INFO - PROCESS | 14505 | RSS: Main: 239788032 23:34:33 INFO - PROCESS | 14505 | 23:34:35 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:34:36 INFO - PROCESS | 14505 | RSS: Main: 237027328 23:34:36 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | Cycle 1(1): loaded http://localhost:44778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44778/tests/kraken/driver.html?testName=ai-astar) 23:34:38 INFO - PROCESS | 14505 | RSS: Main: 280764416 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | __start_tp_report 23:34:38 INFO - PROCESS | 14505 | _x_x_mozilla_page_load 23:34:38 INFO - PROCESS | 14505 | _x_x_mozilla_page_load_details 23:34:38 INFO - PROCESS | 14505 | |i|pagename|runs| 23:34:38 INFO - PROCESS | 14505 | |0;ai-astar;109;105;109;111;110;112;108;110;111;109 23:34:38 INFO - PROCESS | 14505 | |1;audio-beat-detection;154;139;136;173;126;156;157;128;192;127 23:34:38 INFO - PROCESS | 14505 | |2;audio-dft;161;159;160;161;160;165;160;160;161;160 23:34:38 INFO - PROCESS | 14505 | |3;audio-fft;75;76;75;127;76;76;75;86;97;74 23:34:38 INFO - PROCESS | 14505 | |4;audio-oscillator;85;85;114;85;86;86;121;85;87;131 23:34:38 INFO - PROCESS | 14505 | |5;imaging-gaussian-blur;116;117;116;116;117;116;117;117;116;117 23:34:38 INFO - PROCESS | 14505 | |6;imaging-darkroom;146;146;146;146;146;145;145;146;145;146 23:34:38 INFO - PROCESS | 14505 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 23:34:38 INFO - PROCESS | 14505 | |8;json-parse-financial;53;54;55;55;55;53;55;54;53;54 23:34:38 INFO - PROCESS | 14505 | |9;json-stringify-tinderbox;61;62;63;63;63;62;62;63;62;63 23:34:38 INFO - PROCESS | 14505 | |10;stanford-crypto-aes;79;79;81;80;79;82;81;93;118;80 23:34:38 INFO - PROCESS | 14505 | |11;stanford-crypto-ccm;132;134;124;132;132;121;119;123;131;123 23:34:38 INFO - PROCESS | 14505 | |12;stanford-crypto-pbkdf2;162;160;160;161;160;160;159;158;163;159 23:34:38 INFO - PROCESS | 14505 | |13;stanford-crypto-sha256-iterative;63;64;63;63;62;63;65;63;62;64 23:34:38 INFO - PROCESS | 14505 | __end_tp_report 23:34:38 INFO - PROCESS | 14505 | __start_cc_report 23:34:38 INFO - PROCESS | 14505 | _x_x_mozilla_cycle_collect,1632 23:34:38 INFO - PROCESS | 14505 | __end_cc_report 23:34:38 INFO - PROCESS | 14505 | __startTimestamp1466490878329__endTimestamp 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | ------- Summary: start ------- 23:34:38 INFO - PROCESS | 14505 | Number of tests: 14 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:1.96 (1.8%) stddev-sans-first:2.07 23:34:38 INFO - PROCESS | 14505 | Values: 109.0 105.0 109.0 111.0 110.0 112.0 108.0 110.0 111.0 109.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#1] audio-beat-detection Cycles:10 Average:148.80 Median:155.00 stddev:21.82 (14.1%) stddev-sans-first:23.06 23:34:38 INFO - PROCESS | 14505 | Values: 154.0 139.0 136.0 173.0 126.0 156.0 157.0 128.0 192.0 127.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.64 (1.0%) stddev-sans-first:1.73 23:34:38 INFO - PROCESS | 14505 | Values: 161.0 159.0 160.0 161.0 160.0 165.0 160.0 160.0 161.0 160.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.00 stddev:16.85 (22.2%) stddev-sans-first:17.58 23:34:38 INFO - PROCESS | 14505 | Values: 75.0 76.0 75.0 127.0 76.0 76.0 75.0 86.0 97.0 74.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:85.00 stddev:18.06 (21.3%) stddev-sans-first:18.67 23:34:38 INFO - PROCESS | 14505 | Values: 85.0 85.0 114.0 85.0 86.0 86.0 121.0 85.0 87.0 131.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 23:34:38 INFO - PROCESS | 14505 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 23:34:38 INFO - PROCESS | 14505 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 23:34:38 INFO - PROCESS | 14505 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.83 23:34:38 INFO - PROCESS | 14505 | Values: 53.0 54.0 55.0 55.0 55.0 53.0 55.0 54.0 53.0 54.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 23:34:38 INFO - PROCESS | 14505 | Values: 61.0 62.0 63.0 63.0 63.0 62.0 62.0 63.0 62.0 63.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:80.50 stddev:12.25 (15.2%) stddev-sans-first:12.79 23:34:38 INFO - PROCESS | 14505 | Values: 79.0 79.0 81.0 80.0 79.0 82.0 81.0 93.0 118.0 80.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:131.50 stddev:5.59 (4.2%) stddev-sans-first:5.64 23:34:38 INFO - PROCESS | 14505 | Values: 132.0 134.0 124.0 132.0 132.0 121.0 119.0 123.0 131.0 123.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.20 Median:160.00 stddev:1.48 (0.9%) stddev-sans-first:1.41 23:34:38 INFO - PROCESS | 14505 | Values: 162.0 160.0 160.0 161.0 160.0 160.0 159.0 158.0 163.0 159.0 23:34:38 INFO - PROCESS | 14505 | 23:34:38 INFO - PROCESS | 14505 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 23:34:38 INFO - PROCESS | 14505 | Values: 63.0 64.0 63.0 63.0 62.0 63.0 65.0 63.0 62.0 64.0 23:34:38 INFO - PROCESS | 14505 | -------- Summary: end -------- 23:34:38 INFO - PROCESS | 14505 | 23:34:39 INFO - TEST-INFO | 14505: exit 0 23:34:39 INFO - TEST-OK | kraken | took 62463ms 23:34:39 INFO - SUITE-END | took 1178s 23:34:39 INFO - Completed test suite (00:19:39) 23:34:39 INFO - javascript benchmark 23:34:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3067.509198528236, 3074.44659961207, 3094.0563128245885, 3102.3795240951818, 3121.5317702344646, 5296.92054619181, 5350.5184533391985, 5418.458154184579, 5490.984167928936, 5505.449455054492, 5401.162819762943, 5428.565714742821, 5451.727413629323, 5502.862428439281, 5540.833774986757, 4839.540243676845, 4887.633427492934, 4942.283368911506, 4949.480304568023, 5148.897022059557, 5041.218611115265, 5087.236914462842, 5095.898082038357, 5105.897882042357, 5106.055379754757, 4842.830500932468, 4875.53682400175, 4889.29105279736, 4966.205407134841, 5054.823081192159, 4915.287283343895, 4917.139500587383, 4940.48124946879, 4942.0, 4955.925661115086, 4897.804087836482, 4898.951010489885, 4962.851114466572, 4980.228064649985, 5044.319016932704, 5495.422980587061, 5558.666480011172, 5564.527015203713, 5569.470900264469, 5571.387147413781, 5448.0, 5825.097109947922, 5873.089671100944, 5893.0276504376725, 5978.252718410199, 5995.100734889757, 5996.8500787480225, 6001.309849367335, 6014.2181516403025, 6016.939830601682, 5605.0, 5614.214010038598, 5614.803481878114, 5616.157576363578, 5619.887602247933, 5829.679367634736, 5918.644881307136, 6005.369436209223, 6007.849803754941, 6079.08813677943, 5801.3038435388025, 5854.121881717691, 5856.12158176277, 5862.179294898718, 5863.061910094364, 5695.40198279183, 5873.412658734178, 5885.2349194605285, 5943.138244954457, 5944.3164036136395, 5819.505342045889, 5876.706164691834, 5881.147233651097, 5920.259967504062, 5925.851853703692, 5813.76744930198, 5828.0966450200285, 5845.620034697816, 5864.149698293809, 5864.296284445893, 4421.204183246982, 4460.286354183315, 4504.752238626841, 4516.209663308908, 4542.1597004553605, 3988.3618621020496, 3988.66096381805, 3995.92008159841, 4039.6768258539246, 4056.6957478188665, 5584.050711379075, 5594.384617692051, 5605.831825045255, 5608.69152196625, 5611.102223644198, 5792.739326730307, 5808.099744539603, 5947.0, 5954.0, 5997.820065398045, 5487.42382049887, 5518.33779946409, 5524.861878452991, 5540.196671482693, 5581.972090139523, 4889.48660390661, 4893.877653058702, 4898.8775280618265, 4901.375370713199, 4943.530364615356, 5870.970645146833, 5918.26021747273, 5973.49225315853, 6032.426919442646, 6037.607555508878, 5879.617824841372, 5885.588008839341, 5887.4406931341455, 5890.676012819336, 5892.204552385373, 6008.459238668541, 6020.277566692025, 6021.2473440819895, 6023.578349515492, 6023.975924092827, 5628.4090170533145, 5715.428457154251, 5742.59801813869, 5751.798687046101, 5767.423257674199, 5875.882482350244, 5879.176915231957, 5879.823605291848, 5883.2057672215415, 5885.941140588539, 5674.177244299636, 5743.827685169452, 5744.540436765133, 5744.597878148489, 5747.4252574742195, 5610.579206559442, 5788.305403351625, 5793.0, 5821.912671309849, 5866.41335866427, 5688.11834165705, 5719.599628025998, 5729.169270455844, 5729.570282228766, 5732.0, 5603.299587551556, 5836.182934389104, 5895.823125306248, 5897.498712609477, 5907.763689452546, 5441.075017246998, 5517.613767036269, 5609.354924183771, 5612.803551875665, 5646.463585959327], "value": 5402.544499136788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.3803910795896, 618.5608218165103, 624.0327492386799, 630.6027202862198, 634.0394302631516, 30469.238269043286, 42382.0, 42757.358639620354, 42767.78616106919, 42802.78598607003, 45178.32232516515, 45756.54243457565, 45781.771091144496, 45826.77086614562, 45831.54168458314, 8138.877916831252, 8864.778380540474, 9086.59110340033, 10622.36265824053, 10883.564657413694, 10779.622713205, 10894.509747061402, 10916.672499824977, 10918.07196388308, 10922.617708380209, 10943.23397362185, 10986.505607247693, 10993.0, 11003.0, 11003.394813285266, 8207.958960205191, 10715.196360272972, 11055.11559075272, 11057.0, 11062.280951738192, 10632.415217163052, 10707.785844283071, 10729.302595331357, 11012.284201526874, 11014.229003969725, 42522.36216456756, 43053.354199687026, 43076.56923430757, 43130.56869431328, 43162.0, 3561.269939662389, 3588.8385022674042, 3741.8877433677035, 3755.0424641716186, 3755.661990420848, 33173.33653326944, 33339.6666033339, 33748.15629609255, 33800.15499612505, 33848.0, 4321.58944900234, 4477.9104417911785, 4507.481639611454, 4565.2467342888385, 4624.953750462486, 314943.0, 321467.0, 321648.0, 322088.0, 322111.0, 63414.36585634177, 63968.0, 64425.0, 64470.67764661241, 64505.0, 67243.66378168175, 67466.0, 67478.0, 68340.0, 68345.65827170931, 15802.36790528389, 17673.646527069388, 17826.732599011026, 17832.0, 17833.197506112258, 13289.401976911062, 13461.057725959088, 13506.797398039038, 13756.724865502634, 13757.243351615558, 19706.70439943402, 19712.60574788496, 20023.899880500503, 20034.89982550078, 20139.697904531444, 6010.978133717279, 7258.383037441877, 7323.450741194431, 7415.3326200642205, 7570.621468926532, 962.7208109648263, 1030.253066526777, 1043.12377602814, 1048.6376673040118, 1125.9324440533594, 31290.530642040387, 31359.529607055912, 31622.209444764067, 31868.362632747216, 32066.198345041095, 1123.8651361836633, 1181.7459246261924, 1201.483362154282, 1201.7956947318976, 1237.0351126121639, 16532.421365252158, 16679.0, 16824.327026918785, 16982.745258821127, 17061.57346066358, 552096.0, 555993.0, 559498.0, 559646.0, 561146.0, 59511.70244148751, 60130.39869601248, 60204.096938545954, 60226.69886650539, 60236.0, 1253.129075292663, 1284.1588759362633, 1304.9608511744664, 1317.914335568185, 1344.8587898270541, 32060.67939320577, 33377.66622333746, 33535.32929341351, 33640.1589960253, 33804.268135613485, 50804.237936431724, 51401.48598513967, 51536.742316288175, 51644.22533661923, 51647.741761290956, 406416.0, 409540.0, 409966.0, 411147.0, 411903.0, 25305.620415694146, 27085.864570677808, 27778.166655000383, 28023.29941751391, 29595.556066658588, 46823.29765053459, 47919.76040119777, 48340.0, 49069.0, 49471.50528494813, 40525.79737101296, 40852.0, 40975.385369218886, 41016.58983410128, 42152.156956861305, 40382.596174037884, 40545.391819122146, 40883.79558102191, 40891.0, 42031.15937681168, 40238.39642405308, 41281.793591031856, 41287.793561032006, 41376.0, 41809.581904180566, 35104.47343289904, 36285.27429451449, 36750.44874326834, 36836.26327473489, 37009.0, 26945.191644250703, 27588.0, 27776.16671499858, 29208.415831683673, 29723.55414668825], "value": 20856.813699295944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.9351656976599, 397.14019148543423, 400.18562225870323, 410.3659845538642, 417.0710464103521, 13997.720045599082, 17494.825051749478, 17880.731789023157, 19197.71203431948, 19252.614947701055, 16437.506874793777, 16510.091944943022, 16729.330826766945, 18197.818021819778, 18282.360117395892, 5661.23573317603, 5665.773369065243, 5866.5306775458075, 5882.088276317157, 5916.556258280624, 4139.710220284582, 4349.934750978738, 5745.5690823188215, 5813.5349172066135, 5842.240508733858, 6065.969670151665, 6338.904916426257, 6342.98512238042, 6444.548881578291, 6449.83875403114, 6331.398517140864, 6369.331220221903, 6386.2975072741965, 6555.541112122153, 6557.737690492375, 5725.0, 5741.971290143564, 5748.42515748426, 5753.050746626844, 5782.595218334718, 22397.104115835347, 22407.439814004618, 23388.415289617726, 23518.64722029171, 23527.058917643273, 2358.8466749661216, 2420.1771397724856, 2698.150082723955, 2753.697093319733, 3081.0, 14415.855841441557, 14473.710525789531, 14495.0, 14690.559283221413, 15172.924135379362, 1895.4787433455772, 1980.722698822166, 2015.4356780101596, 2024.98987505063, 2094.4554415851835, 76482.0, 79202.20797792064, 79269.0, 80971.99999999942, 81548.06841418486, 10221.540030698636, 10447.791044179074, 10549.630762923414, 10682.198835087243, 10842.349459032483, 12748.681282968, 14650.633734156518, 14684.19236941957, 14702.852971470362, 14717.33771980242, 8183.672653093929, 8446.155384461505, 8498.277646400002, 8520.659173633108, 8710.346723995726, 8094.51432914027, 8104.716334928367, 8495.660173592987, 8558.315334773204, 8640.30877529796, 6308.905366419507, 6694.397504224643, 6807.421369183576, 6812.62530560814, 6858.0, 1861.3671351740472, 1920.769507659062, 1952.7949565295723, 1964.705294205852, 1968.340605897012, 925.3013974449248, 976.6923419122953, 1007.3552926127285, 1058.9046985771163, 1071.3518321415588, 20101.59796804085, 20107.0, 20722.58554828925, 20777.168913243604, 21107.68338474924, 1006.0995409108807, 1016.506994107857, 1019.1184625299211, 1031.3038698878227, 1103.7957977774138, 10905.34567925927, 11471.4264286787, 11482.598109066143, 11564.13269004828, 11745.11911606633, 453492.0, 456125.0, 457439.0, 458220.0, 461072.0, 95184.0, 95602.0, 95778.0, 96446.0, 96775.51612242035, 958.1999030809352, 965.0446058402272, 980.6518685866557, 980.9264305176997, 982.1946004276567, 17680.64638707193, 17863.479829861746, 18117.094145292947, 18323.0, 18551.72172417388, 31808.363832722753, 39670.80164599274, 41192.588074118874, 41922.79038604788, 43150.35274470822, 55315.44684553264, 56574.43425565856, 58970.70514647399, 59191.81616367563, 59869.0, 7535.623218839146, 7595.278448547379, 7753.185915479019, 8152.347812174893, 8821.720850476773, 11882.702932426759, 12243.081768867549, 12249.816252756395, 13279.867201327863, 13375.665608359868, 11378.715532111764, 11663.5917742882, 11779.469923853618, 12134.393280335771, 12220.816687749513, 10839.783204336027, 10971.561137554409, 11757.470913808727, 11883.58407455735, 12360.196587221802, 11637.010854077498, 11657.708557286136, 11676.94161529187, 11924.582639607572, 12421.875781242072, 7989.48068375577, 8079.272865442138, 8093.635786389509, 8119.066307374727, 8148.3888708348295, 11687.240329378563, 12081.456334465145, 12108.0, 12159.81760273579, 12377.195482293982], "value": 9425.66852900461, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.4869600855661, 1469.9999999999998, 1523.6571771351446, 1649.9092549909758, 1807.9276828926836, 8629.395942284043, 9046.547672616376, 9047.41191822532, 9315.767105822359, 9340.439573625586, 9055.275577953764, 9286.814263714721, 9286.953565232165, 9288.953555232214, 9295.024022477644, 2005.9699104513445, 2219.1012639880873, 2272.534130503247, 2297.6094064009158, 2305.538892221554, 2327.8836058197107, 2330.3707998840305, 2334.9182778602753, 2336.1122773346106, 2336.5910965581043, 2268.5689718953345, 2286.017012684545, 2288.0504590594883, 2289.3132060381904, 2303.216906251874, 1928.9421317360432, 2176.6082105221053, 2226.897685645608, 2241.3276017194776, 2244.764299748519, 2190.9561808763897, 2219.7225346831647, 2235.865848049122, 2239.29462219402, 2239.9216027439124, 9123.771905702344, 9256.444613323221, 9267.582958766805, 9276.536173191313, 9287.257019438428, 5305.071612467841, 5398.24424580559, 5406.918896216521, 5412.810551630713, 5420.051490989101, 3290.7860989035876, 3381.1885147564653, 3400.8129552874584, 3450.8447119879665, 3455.6198818129988, 1147.6901236666054, 1147.8450409194736, 1148.0757989818194, 1150.1431433581952, 1174.5771522251982, 13041.543545975845, 13114.409851556607, 13127.868721312856, 13144.40850161745, 13156.144850584682, 4711.687070650913, 4762.285657151455, 4844.370231869834, 4862.124817532878, 4870.342503762017, 10934.398608076632, 10948.45257737126, 10961.0, 10968.451577421098, 10985.780284394268, 5347.0, 5383.865403364869, 5385.192221166856, 5389.0299746046085, 5390.730463476889, 4485.125400546927, 4867.829625963074, 5013.824516141917, 5014.974925125424, 5115.079285728604, 4071.857484988011, 4120.052387950788, 4129.97935010323, 4138.627523522898, 4167.5832416758085, 1243.2229856339789, 1269.8666640002853, 1272.8599854016052, 1289.078309009063, 1296.7341694952513, 999.5951639585979, 1016.2479764973866, 1020.6172685243034, 1030.6392762533053, 1033.813913495563, 6272.5922815016875, 6377.7448902044325, 6387.329330420239, 6404.1674582304, 6474.643894585843, 439.3914428516464, 444.613186527723, 449.37536823814924, 451.719933641138, 461.35871139116654, 4270.701050926467, 4342.218400687843, 4445.82216711328, 4478.216312145361, 4496.775161241925, 11323.547058117747, 11585.131115166396, 11632.069434445224, 11735.706607334714, 11751.118666100077, 11577.884221157849, 11590.652280431601, 11593.304401735923, 11594.47824847867, 11627.418629068512, 8123.147069557729, 8417.579121043924, 8436.957815210884, 8450.408471406943, 8569.228769410658, 6073.423024812636, 6179.073139029002, 6280.623162610258, 6487.3836985486305, 6648.767293144911, 10711.8928810714, 10756.623518176826, 10792.622258220925, 10880.183986201226, 10981.3960232188, 11058.612948546763, 11063.170262230526, 11065.668029959113, 11405.258658187191, 11411.942940285246, 7066.399356054793, 7259.491835571459, 7346.37555807741, 7833.843323133619, 7992.16082311349, 8629.13708629132, 8907.376483646083, 8943.552822358726, 9149.817003659757, 9184.678536251198, 7760.84478310442, 7909.24862138096, 7923.643436045277, 7981.122076571689, 8034.156413576724, 7610.961945190239, 7737.690492380242, 7803.960980195063, 7840.098388685146, 7917.7624671259955, 7207.9639601801655, 7229.385502232369, 7294.197638259681, 7302.233265507045, 7306.561606303639, 5281.207818827053, 5408.918866216931, 5505.642133261325, 5564.443555644403, 5674.971625141848, 5011.874703132305, 5186.4035635902355, 5224.138017227267, 5279.47205279469, 5343.812966546152], "value": 4679.865983434082, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3568979885128, 331.9535265062891, 336.92756057447644, 337.8361494675083, 339.73840143089836, 5170.146925757245, 5302.840914772553, 5323.7071961042175, 5337.252784610153, 5372.785088596456, 5319.973400133003, 5324.5740340772745, 5338.599605029619, 5340.198970154479, 5354.330708661418, 4245.617894389505, 4255.914741740852, 4261.637760790337, 4265.104328091104, 4276.42268293781, 3991.2616166009234, 4001.1397549526846, 4149.605787450202, 4194.958050419503, 4204.2222188895, 4105.4252404663375, 4149.443958515557, 4168.208040472316, 4169.499660040799, 4175.770332631704, 4141.523724771645, 4148.0874207674115, 4156.085661154541, 4161.97919010403, 4170.47869016373, 3441.328940856534, 3720.6837418819164, 3732.1789206374556, 3732.981335093334, 3743.5025989604105, 5185.677652198703, 5208.6093542984045, 5220.973895130538, 5224.242484839715, 5232.764525596319, 4692.765361731934, 4709.9764501177615, 4717.315989181549, 4725.10223057618, 4733.148033353995, 5378.951104534617, 5549.279723285795, 5552.139418390156, 5571.13647384656, 5579.525740312079, 3929.9017524561827, 3937.6652984496354, 3944.664703500206, 3952.565217826037, 3954.0, 5791.681457519835, 5840.415958404125, 5864.501517371029, 5876.441738034837, 5879.235699359098, 3902.6292502212245, 4074.6536544393466, 4084.1008930847297, 4098.385242213632, 4100.7334523255895, 4091.0795071109355, 4091.488563929509, 4106.342985122366, 4115.403266526397, 4123.257813593582, 4715.41057367829, 4719.079779442975, 4730.148573256778, 4732.408448943882, 4733.715977041388, 5511.889762204733, 5587.972060139755, 5641.012822756084, 5654.717264136777, 5656.971715141398, 1411.089847048648, 1424.1953296387508, 1425.5530636404058, 1425.836028856674, 1439.6688761584894, 884.5975081338105, 905.8767128760421, 916.8578870275118, 919.9632014719471, 929.8140371925642, 4685.344051832746, 4735.502772208937, 4768.546988036131, 4822.927656085161, 4836.685615434985, 5044.495550444926, 5067.631739430407, 5081.466446023188, 5120.48795120485, 5141.948580514147, 4653.278741795028, 4862.562369386772, 4887.877803054952, 4892.706437613754, 4900.926486102711, 2683.8658067096567, 2700.824446410977, 2741.2735625059377, 2743.190758726181, 2757.9724202758116, 5131.692098474104, 5182.507661772125, 5188.896222075613, 5221.895562088737, 5224.786756144944, 4942.579880710108, 5165.225216217597, 5172.5603323717905, 5174.223866419992, 5238.895222095613, 5150.27896094547, 5211.4527974562125, 5239.039942432513, 5246.265522826808, 5353.571714262927, 5051.141305977919, 5105.851183483835, 5107.668001579886, 5108.897822043464, 5160.329157209539, 4789.7605119743175, 4933.284673722362, 5026.346574945233, 5070.8225212117395, 5096.46487118862, 5120.924605832817, 5149.742512874266, 5169.896602068013, 5186.688798672092, 5188.22176673502, 3767.547894252707, 3793.6016718244186, 3876.3022655922205, 4006.4791577094793, 4014.1369605533782, 5619.072852979212, 5658.40586738403, 5697.943020569907, 5700.401457847032, 5703.517085557868, 5421.484958928895, 5529.944700552943, 5595.0, 5597.356304025089, 5623.0, 5538.335399752055, 5540.806071787468, 5544.279243698294, 5552.639078459887, 5561.582881283839, 5442.775375540472, 5464.398916119303, 5468.644538105011, 5469.562435005269, 5488.204210389391, 5365.919511207413, 5569.610127291213, 5578.804741834017, 5604.495595396434, 5681.8863622726485, 5511.53151982086, 5522.86781209843, 5530.562053865943, 5530.834074977757, 5573.7213139343685], "value": 4111.575874279415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.2646712090631, 783.4437549339968, 795.6220795122317, 820.7783898347446, 831.2850948184562, 13924.582262532127, 14069.437222511087, 14069.507567235149, 14489.927550362234, 14627.341769620367, 13575.117617354865, 13615.931920340385, 13772.173669579806, 13773.380197891094, 13796.862031379684, 663.2439019517753, 781.121238606569, 787.4881327137377, 798.2935102434359, 800.6877317846045, 796.2714116583319, 803.8311954489565, 804.2922228438995, 806.4878801960739, 806.7055524733489, 842.9620667069996, 848.1645579104577, 849.2017503546647, 849.3375167369462, 851.093585331623, 687.700850130195, 820.6389188756928, 840.9873851892227, 842.7682387343499, 847.2628812932758, 613.8342647485199, 614.0512907557834, 614.3457218062749, 614.7387360371824, 615.0681717198448, 14046.508372206923, 14062.718745625132, 14079.648008799759, 14084.2957852107, 14109.576712698636, 13142.671433214151, 13516.459341626323, 13625.25061121638, 13793.793093103612, 13938.163710177421, 12903.935480322632, 12982.415791289415, 12999.935000325033, 13082.345882705828, 13633.113847599874, 4504.909901801979, 4515.277555591089, 4575.565321294472, 4637.652176086807, 4741.407324084489, 76348.61825690836, 76725.61637191779, 77312.61343693246, 79516.60241698755, 82827.9999999994, 10889.891101088888, 11262.493187806569, 11377.65867023974, 11709.121815863844, 11922.225055371373, 18982.43052708421, 19005.61988760217, 19009.619807604045, 19011.61976760457, 19024.52438689044, 8077.676892924334, 8191.795205119801, 8248.340132789363, 8324.417290789708, 8415.537145456936, 4580.770961451914, 4683.625309975262, 4703.553162449562, 4800.0, 4894.241392584155, 3393.270446853938, 3497.143199916037, 3502.141975216088, 3505.4040813061843, 3515.789052656849, 609.911562823397, 624.0327492386782, 626.1609443345939, 633.8700566383881, 638.5562034386057, 580.4833698008775, 663.280340830195, 665.5241502325861, 669.5413641655482, 681.5808277909149, 8279.668813247403, 8740.956295218482, 8870.86693699595, 8928.419652722681, 8957.462552246887, 624.9407254703234, 625.3933684326196, 632.5508888689083, 633.395107672172, 670.9127813384326, 4232.343986682069, 4291.077418355068, 4309.6767742418815, 4322.286822674287, 4327.221100201931, 114915.0, 115071.0, 115567.0, 116752.0, 117187.38282011641, 98780.50609746906, 99632.0, 100329.0, 100489.49755251323, 100981.0, 60979.69510152421, 81907.0, 82521.58739206266, 82687.17312826915, 83725.58137209275, 7874.0, 8046.718364857202, 8125.187481251827, 8286.875696864432, 8292.958535207526, 51877.7406112967, 51902.48097518976, 52175.73912130415, 53908.73045634747, 55051.44948550624, 52924.0, 73173.634131829, 74319.0, 75655.0, 78137.60931195307, 10627.574897004033, 10830.40432776205, 10942.945285273523, 11045.723856903642, 11524.366159860952, 11998.220115692453, 12111.515539378326, 12210.26738395699, 12401.62795116148, 12725.045621578603, 11951.05107044753, 12163.574274900337, 12301.261924284572, 12361.134720569833, 12679.556215532411, 11637.359945203096, 11757.470913808727, 12007.879921200676, 12436.378181090728, 12448.68878278014, 11426.314421134759, 12030.0, 12047.457864395948, 12407.379631018593, 12408.0, 9000.50497222659, 9255.0, 9421.576029078571, 9423.905760942303, 9785.559649815918, 9138.0, 9161.358704890594, 9403.85894211601, 9907.917687457035, 9926.205903527845], "value": 7174.166247850715, "unit": "score"}], "name": "dromaeo_css", "value": 7261.669955424075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 109.0, 111.0, 110.0, 112.0, 108.0, 110.0, 111.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 139.0, 136.0, 173.0, 126.0, 156.0, 157.0, 128.0, 192.0, 127.0], "value": 148.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 160.0, 165.0, 160.0, 160.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 127.0, 76.0, 76.0, 75.0, 86.0, 97.0, 74.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 114.0, 85.0, 86.0, 86.0, 121.0, 85.0, 87.0, 131.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 55.0, 55.0, 53.0, 55.0, 54.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 81.0, 80.0, 79.0, 82.0, 81.0, 93.0, 118.0, 80.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 134.0, 124.0, 132.0, 132.0, 121.0, 119.0, 123.0, 131.0, 123.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 161.0, 160.0, 160.0, 159.0, 158.0, 163.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 62.0, 63.0, 65.0, 63.0, 62.0, 64.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1508.4, "alertThreshold": 2.0}]} 23:34:39 INFO - Return code: 0 23:34:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:34:39 INFO - # TBPL SUCCESS # 23:34:39 INFO - Running post-action listener: _resource_record_post_action 23:34:39 INFO - ##### 23:34:39 INFO - ##### Finished run-tests step (success) 23:34:39 INFO - ##### 23:34:39 INFO - Running post-run listener: _resource_record_post_run 23:34:39 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 564019200; Read time: 12; Write time: 1415428 23:34:39 INFO - TinderboxPrint: CPU usage
13.4% 23:34:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:34:39 INFO - TinderboxPrint: I/O write bytes / time
564,019,200 / 1,415,428 23:34:39 INFO - TinderboxPrint: CPU idle
8,274.0 (86.6%) 23:34:39 INFO - TinderboxPrint: CPU user
1,216.0 (12.7%) 23:34:39 INFO - TinderboxPrint: Swap in / out
0 / 0 23:34:39 INFO - install - Wall time: 17s; CPU: 18.0%; Read bytes: 0; Write bytes: 273682432; Read time: 0; Write time: 734912 23:34:39 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290336768; Read time: 12; Write time: 680516 23:34:40 INFO - Running post-run listener: _upload_blobber_files 23:34:40 INFO - Blob upload gear active. 23:34:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:34:40 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 23:34:40 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:34:40 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:34:40 INFO - (blobuploader) - INFO - Open directory for files ... 23:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:34:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:44 INFO - (blobuploader) - INFO - Done attempting. 23:34:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:34:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:45 INFO - (blobuploader) - INFO - Done attempting. 23:34:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:45 INFO - (blobuploader) - INFO - Done attempting. 23:34:45 INFO - (blobuploader) - INFO - Iteration through files over. 23:34:45 INFO - Return code: 0 23:34:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:34:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:34:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5779e6146c682ba94a4209b30d5578699f85bc15f84d7bc158ad5e014617714e017181a64aae87a4b358b0858559dbeb374a873426d1ae82636d4c08d559151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a8b1152966f94d91ba67b4efc13071a22dfe984baf893387ecc146a5ec680b3ef5d53507b34cf926f957f5e2649b365c1ab62a3fa8170bdebe01532daa8aa4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16d063d73418ddb159ec97e06187927c4b093ae378d975b317c2ab0745f270aede91e1dbd24746959be38d9df2756cc3fde2eaf8eaa6c6f363d365d5a7093816"} 23:34:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:34:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:34:45 INFO - Contents: 23:34:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5779e6146c682ba94a4209b30d5578699f85bc15f84d7bc158ad5e014617714e017181a64aae87a4b358b0858559dbeb374a873426d1ae82636d4c08d559151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a8b1152966f94d91ba67b4efc13071a22dfe984baf893387ecc146a5ec680b3ef5d53507b34cf926f957f5e2649b365c1ab62a3fa8170bdebe01532daa8aa4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16d063d73418ddb159ec97e06187927c4b093ae378d975b317c2ab0745f270aede91e1dbd24746959be38d9df2756cc3fde2eaf8eaa6c6f363d365d5a7093816"} 23:34:45 INFO - Running post-run listener: copy_logs_to_upload_dir 23:34:45 INFO - Copying logs to upload dir... 23:34:45 INFO - mkdir: /builds/slave/test/build/upload/logs 23:34:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.750095 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-20 23:34:45.773042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 23:34:45.776727) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5779e6146c682ba94a4209b30d5578699f85bc15f84d7bc158ad5e014617714e017181a64aae87a4b358b0858559dbeb374a873426d1ae82636d4c08d559151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a8b1152966f94d91ba67b4efc13071a22dfe984baf893387ecc146a5ec680b3ef5d53507b34cf926f957f5e2649b365c1ab62a3fa8170bdebe01532daa8aa4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16d063d73418ddb159ec97e06187927c4b093ae378d975b317c2ab0745f270aede91e1dbd24746959be38d9df2756cc3fde2eaf8eaa6c6f363d365d5a7093816"} build_url:https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/NTvU2dyrRhaM0Py_fAIzmg/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/Fx-Team/sha512/b5779e6146c682ba94a4209b30d5578699f85bc15f84d7bc158ad5e014617714e017181a64aae87a4b358b0858559dbeb374a873426d1ae82636d4c08d559151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a8b1152966f94d91ba67b4efc13071a22dfe984baf893387ecc146a5ec680b3ef5d53507b34cf926f957f5e2649b365c1ab62a3fa8170bdebe01532daa8aa4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16d063d73418ddb159ec97e06187927c4b093ae378d975b317c2ab0745f270aede91e1dbd24746959be38d9df2756cc3fde2eaf8eaa6c6f363d365d5a7093816"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 23:34:45.806657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:34:45.807524) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1466479569.441050-572606417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 23:34:45.890937) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 23:34:45.891693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 23:34:45.892354) ========= ========= Total master_lag: 0.20 =========