builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-057 starttime: 1468969689.89 results: success (0) buildid: 20160719133037 builduid: 0dfb97c2c35c4c9d994fad9affb772ea revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.890617) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.891087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.891891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.944407) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.944759) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 16:08:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-07-19 16:08:10 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171008 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.159961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.160323) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.116567 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.289953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.290265) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 16:08:10,309 truncating revision to first 12 chars 2016-07-19 16:08:10,309 Setting DEBUG logging. 2016-07-19 16:08:10,309 attempt 1/10 2016-07-19 16:08:10,309 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1ebb63485643?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 16:08:10,621 unpacking tar archive at: mozilla-inbound-1ebb63485643/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664434 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.967064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.967398) ========= script_repo_revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.967849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.968116) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.979195) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-19 16:08:10.979504) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:11 INFO - MultiFileLogger online at 20160719 16:08:11 in /builds/slave/test 16:08:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:08:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:11 INFO - {'append_to_log': False, 16:08:11 INFO - 'base_work_dir': '/builds/slave/test', 16:08:11 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:08:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:11 INFO - 'branch': 'Mozilla-Inbound', 16:08:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:11 INFO - 'config_files': ('talos/linux_config.py',), 16:08:11 INFO - 'default_actions': ('clobber', 16:08:11 INFO - 'read-buildbot-config', 16:08:11 INFO - 'download-and-extract', 16:08:11 INFO - 'populate-webroot', 16:08:11 INFO - 'create-virtualenv', 16:08:11 INFO - 'install', 16:08:11 INFO - 'run-tests'), 16:08:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:11 INFO - 'download_minidump_stackwalk': True, 16:08:11 INFO - 'download_symbols': 'ondemand', 16:08:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:11 INFO - 'installer_path': 'installer.exe', 16:08:11 INFO - 'log_level': 'info', 16:08:11 INFO - 'log_name': 'talos', 16:08:11 INFO - 'log_to_console': True, 16:08:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:11 INFO - 'opt_config_files': (), 16:08:11 INFO - 'pip_index': False, 16:08:11 INFO - 'sps_profile': False, 16:08:11 INFO - 'sps_profile_interval': 0, 16:08:11 INFO - 'suite': 'dromaeojs-e10s', 16:08:11 INFO - 'system_bits': '32', 16:08:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:11 INFO - 'title': 'talos-linux64-ix-057', 16:08:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:11 INFO - 'use_talos_json': True, 16:08:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:11 INFO - 'work_dir': 'build'} 16:08:11 INFO - ##### 16:08:11 INFO - ##### Running clobber step. 16:08:11 INFO - ##### 16:08:11 INFO - Running pre-action listener: _resource_record_pre_action 16:08:11 INFO - Running main action method: clobber 16:08:11 INFO - rmtree: /builds/slave/test/build 16:08:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:13 INFO - Running post-action listener: _resource_record_post_action 16:08:13 INFO - ##### 16:08:13 INFO - ##### Finished clobber step (success) 16:08:13 INFO - ##### 16:08:13 INFO - ##### 16:08:13 INFO - ##### Running read-buildbot-config step. 16:08:13 INFO - ##### 16:08:13 INFO - Running pre-action listener: _resource_record_pre_action 16:08:13 INFO - Running main action method: read_buildbot_config 16:08:13 INFO - Using buildbot properties: 16:08:13 INFO - { 16:08:13 INFO - "project": "", 16:08:13 INFO - "product": "firefox", 16:08:13 INFO - "script_repo_revision": "production", 16:08:13 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 16:08:13 INFO - "repository": "", 16:08:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 16:08:13 INFO - "buildid": "20160719133037", 16:08:13 INFO - "pgo_build": "True", 16:08:13 INFO - "basedir": "/builds/slave/test", 16:08:13 INFO - "buildnumber": 1323, 16:08:13 INFO - "slavename": "talos-linux64-ix-057", 16:08:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:08:13 INFO - "platform": "ubuntu64_hw", 16:08:13 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:08:13 INFO - "branch": "mozilla-inbound", 16:08:13 INFO - "repo_path": "integration/mozilla-inbound", 16:08:13 INFO - "revision": "1ebb6348564337c00d889bd858eedf3c922e68c0", 16:08:13 INFO - "stage_platform": "linux64-pgo", 16:08:13 INFO - "builduid": "0dfb97c2c35c4c9d994fad9affb772ea", 16:08:13 INFO - "slavebuilddir": "test" 16:08:13 INFO - } 16:08:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:08:13 INFO - Running post-action listener: _resource_record_post_action 16:08:13 INFO - ##### 16:08:13 INFO - ##### Finished read-buildbot-config step (success) 16:08:13 INFO - ##### 16:08:13 INFO - ##### 16:08:13 INFO - ##### Running download-and-extract step. 16:08:13 INFO - ##### 16:08:13 INFO - Running pre-action listener: _resource_record_pre_action 16:08:13 INFO - Running main action method: download_and_extract 16:08:13 INFO - mkdir: /builds/slave/test/build/tests 16:08: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')]} 16:08:13 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:08:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:14 INFO - Downloaded 1361 bytes. 16:08:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:14 INFO - Using the following test package requirements: 16:08:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:08:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:14 INFO - u'jsshell-linux-x86_64.zip'], 16:08:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:15 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:15 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:08:15 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:08:15 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:15 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:15 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:15 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:18 INFO - Downloaded 24336238 bytes. 16:08:18 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'] 16:08:18 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 16:08:19 INFO - Return code: 0 16:08:19 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 16:08: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')]} 16:08:19 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:21 INFO - Downloaded 24336238 bytes. 16:08:21 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'] 16:08:21 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 16:08:22 INFO - Return code: 0 16:08: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')]} 16:08:22 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:08:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:23 INFO - Downloaded 14403317 bytes. 16:08:23 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'] 16:08:23 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 16:08:23 INFO - Return code: 0 16:08:23 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')]} 16:08:23 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:08:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:08:25 INFO - Downloaded 60888591 bytes. 16:08:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:25 INFO - mkdir: /builds/slave/test/properties 16:08:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:25 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:25 INFO - Contents: 16:08:25 INFO - build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:26 INFO - Running post-action listener: _resource_record_post_action 16:08:26 INFO - Running post-action listener: set_extra_try_arguments 16:08:26 INFO - ##### 16:08:26 INFO - ##### Finished download-and-extract step (success) 16:08:26 INFO - ##### 16:08:26 INFO - ##### 16:08:26 INFO - ##### Running populate-webroot step. 16:08:26 INFO - ##### 16:08:26 INFO - Running pre-action listener: _resource_record_pre_action 16:08:26 INFO - Running main action method: populate_webroot 16:08:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:08:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:08:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:08:26 INFO - '--tppagecycles', 16:08:26 INFO - '7'], 16:08:26 INFO - 'tests': ['tsvgm']}}, 16:08:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['damp', 'tps']}, 16:08:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'tests': ['damp', 'tps']}, 16:08:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['dromaeo_dom']}, 16:08:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['basic_compositor_video']}, 16:08:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:08:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['a11yr', 16:08:26 INFO - 'ts_paint', 16:08:26 INFO - 'tpaint', 16:08:26 INFO - 'sessionrestore', 16:08:26 INFO - 'sessionrestore_no_auto_restore', 16:08:26 INFO - 'tabpaint']}, 16:08:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['tsvgx', 16:08:26 INFO - 'tsvgr_opacity', 16:08:26 INFO - 'tart', 16:08:26 INFO - 'tscrollx', 16:08:26 INFO - 'cart']}, 16:08:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:26 INFO - 'tsvgr_opacity', 16:08:26 INFO - 'tart', 16:08:26 INFO - 'tscrollx', 16:08:26 INFO - 'cart']}, 16:08:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'talos_options': ['--disable-e10s'], 16:08:26 INFO - 'tests': ['tp5o']}, 16:08:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'tests': ['tp5o']}, 16:08:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'talos_options': ['--disable-e10s', 16:08:26 INFO - '--xperf_path', 16:08:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:26 INFO - 'tests': ['tp5n']}, 16:08:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:26 INFO - 'talos_options': ['--xperf_path', 16:08:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:26 INFO - 'tests': ['tp5n']}}, 16:08:26 INFO - 'talos.zip': {'path': '', 16:08:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:08:26 INFO - Running post-action listener: _resource_record_post_action 16:08:26 INFO - ##### 16:08:26 INFO - ##### Finished populate-webroot step (success) 16:08:26 INFO - ##### 16:08:26 INFO - ##### 16:08:26 INFO - ##### Running create-virtualenv step. 16:08:26 INFO - ##### 16:08:26 INFO - Running pre-action listener: _resource_record_pre_action 16:08:26 INFO - Running main action method: create_virtualenv 16:08:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:26 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 16:08:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:08:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:27 INFO - Using real prefix '/usr' 16:08:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:28 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:30 INFO - Installing pip.................done. 16:08:30 INFO - Return code: 0 16:08:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08:30 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')]} 16:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:30 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')]} 16:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:30 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:30 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 16:08:30 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 16:08:30 INFO - Using env: {'DISPLAY': ':0', 16:08:30 INFO - 'HOME': '/home/cltbld', 16:08:30 INFO - 'LANG': 'en_US.UTF-8', 16:08:30 INFO - 'LANGUAGE': 'en_US:en', 16:08:30 INFO - 'LOGNAME': 'cltbld', 16:08:30 INFO - 'MAIL': '/var/mail/cltbld', 16:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:30 INFO - 'MOZ_NO_REMOTE': '1', 16:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:30 INFO - 'NO_EM_RESTART': '1', 16:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:30 INFO - 'PWD': '/builds/slave/test', 16:08:30 INFO - 'SHELL': '/bin/bash', 16:08:30 INFO - 'SHLVL': '1', 16:08:30 INFO - 'TERM': 'linux', 16:08:30 INFO - 'TMOUT': '86400', 16:08:30 INFO - 'USER': 'cltbld', 16:08:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:30 INFO - '_': '/tools/buildbot/bin/python'} 16:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:30 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 16:08:30 INFO - Cleaning up... 16:08:30 INFO - Return code: 0 16:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08:30 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')]} 16:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:30 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')]} 16:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:30 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:30 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 16:08:30 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 16:08:30 INFO - Using env: {'DISPLAY': ':0', 16:08:30 INFO - 'HOME': '/home/cltbld', 16:08:30 INFO - 'LANG': 'en_US.UTF-8', 16:08:30 INFO - 'LANGUAGE': 'en_US:en', 16:08:30 INFO - 'LOGNAME': 'cltbld', 16:08:30 INFO - 'MAIL': '/var/mail/cltbld', 16:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:30 INFO - 'MOZ_NO_REMOTE': '1', 16:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:30 INFO - 'NO_EM_RESTART': '1', 16:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:30 INFO - 'PWD': '/builds/slave/test', 16:08:30 INFO - 'SHELL': '/bin/bash', 16:08:30 INFO - 'SHLVL': '1', 16:08:30 INFO - 'TERM': 'linux', 16:08:30 INFO - 'TMOUT': '86400', 16:08:30 INFO - 'USER': 'cltbld', 16:08:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:30 INFO - '_': '/tools/buildbot/bin/python'} 16:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:30 INFO - Downloading/unpacking psutil>=3.1.1 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:33 INFO - Installing collected packages: psutil 16:08:33 INFO - Running setup.py install for psutil 16:08:33 INFO - building 'psutil._psutil_linux' extension 16:08:33 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 16:08:34 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 16:08:34 INFO - building 'psutil._psutil_posix' extension 16:08:34 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 16:08:35 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 16:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:35 INFO - Successfully installed psutil 16:08:35 INFO - Cleaning up... 16:08:35 INFO - Return code: 0 16:08:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:35 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 16:08:35 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 16:08:35 INFO - Using env: {'DISPLAY': ':0', 16:08:35 INFO - 'HOME': '/home/cltbld', 16:08:35 INFO - 'LANG': 'en_US.UTF-8', 16:08:35 INFO - 'LANGUAGE': 'en_US:en', 16:08:35 INFO - 'LOGNAME': 'cltbld', 16:08:35 INFO - 'MAIL': '/var/mail/cltbld', 16:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:35 INFO - 'MOZ_NO_REMOTE': '1', 16:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:35 INFO - 'NO_EM_RESTART': '1', 16:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:35 INFO - 'PWD': '/builds/slave/test', 16:08:35 INFO - 'SHELL': '/bin/bash', 16:08:35 INFO - 'SHLVL': '1', 16:08:35 INFO - 'TERM': 'linux', 16:08:35 INFO - 'TMOUT': '86400', 16:08:35 INFO - 'USER': 'cltbld', 16:08:35 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:35 INFO - '_': '/tools/buildbot/bin/python'} 16:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:35 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 16:08:35 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 16:08:35 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 16:08:35 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 16:08:35 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 16:08:35 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 16:08:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:37 INFO - Installing collected packages: mozsystemmonitor 16:08:37 INFO - Running setup.py install for mozsystemmonitor 16:08:37 INFO - Successfully installed mozsystemmonitor 16:08:37 INFO - Cleaning up... 16:08:37 INFO - Return code: 0 16:08:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08:37 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')]} 16:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:37 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')]} 16:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:37 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:37 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 16:08:37 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 16:08:37 INFO - Using env: {'DISPLAY': ':0', 16:08:37 INFO - 'HOME': '/home/cltbld', 16:08:37 INFO - 'LANG': 'en_US.UTF-8', 16:08:37 INFO - 'LANGUAGE': 'en_US:en', 16:08:37 INFO - 'LOGNAME': 'cltbld', 16:08:37 INFO - 'MAIL': '/var/mail/cltbld', 16:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:37 INFO - 'MOZ_NO_REMOTE': '1', 16:08:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:37 INFO - 'NO_EM_RESTART': '1', 16:08:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:37 INFO - 'PWD': '/builds/slave/test', 16:08:37 INFO - 'SHELL': '/bin/bash', 16:08:37 INFO - 'SHLVL': '1', 16:08:37 INFO - 'TERM': 'linux', 16:08:37 INFO - 'TMOUT': '86400', 16:08:37 INFO - 'USER': 'cltbld', 16:08:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:37 INFO - '_': '/tools/buildbot/bin/python'} 16:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:37 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:39 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:40 INFO - warning: no files found matching '*.txt' 16:08:40 INFO - no previously-included directories found matching 'build' 16:08:40 INFO - no previously-included directories found matching 'dist' 16:08:40 INFO - no previously-included directories found matching '.git*' 16:08:40 INFO - Installing collected packages: jsonschema, functools32 16:08:40 INFO - Running setup.py install for functools32 16:08:40 INFO - warning: no files found matching '*.txt' 16:08:40 INFO - no previously-included directories found matching 'build' 16:08:40 INFO - no previously-included directories found matching 'dist' 16:08:40 INFO - no previously-included directories found matching '.git*' 16:08:40 INFO - Successfully installed jsonschema functools32 16:08:40 INFO - Cleaning up... 16:08:40 INFO - Return code: 0 16:08:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:08:40 INFO - Using env: {'DISPLAY': ':0', 16:08:40 INFO - 'HOME': '/home/cltbld', 16:08:40 INFO - 'LANG': 'en_US.UTF-8', 16:08:40 INFO - 'LANGUAGE': 'en_US:en', 16:08:40 INFO - 'LOGNAME': 'cltbld', 16:08:40 INFO - 'MAIL': '/var/mail/cltbld', 16:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:40 INFO - 'MOZ_NO_REMOTE': '1', 16:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:40 INFO - 'NO_EM_RESTART': '1', 16:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:40 INFO - 'PWD': '/builds/slave/test', 16:08:40 INFO - 'SHELL': '/bin/bash', 16:08:40 INFO - 'SHLVL': '1', 16:08:40 INFO - 'TERM': 'linux', 16:08:40 INFO - 'TMOUT': '86400', 16:08:40 INFO - 'USER': 'cltbld', 16:08:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:40 INFO - '_': '/tools/buildbot/bin/python'} 16:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:43 INFO - Downloading docopt-0.6.1.tar.gz 16:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:43 INFO - Running setup.py install for blobuploader 16:08:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:43 INFO - Running setup.py install for requests 16:08:43 INFO - Running setup.py install for docopt 16:08:43 INFO - Successfully installed blobuploader requests docopt 16:08:43 INFO - Cleaning up... 16:08:43 INFO - Return code: 0 16:08:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:43 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:43 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 16:08:43 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 16:08:43 INFO - Using env: {'DISPLAY': ':0', 16:08:43 INFO - 'HOME': '/home/cltbld', 16:08:43 INFO - 'LANG': 'en_US.UTF-8', 16:08:43 INFO - 'LANGUAGE': 'en_US:en', 16:08:43 INFO - 'LOGNAME': 'cltbld', 16:08:43 INFO - 'MAIL': '/var/mail/cltbld', 16:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:43 INFO - 'MOZ_NO_REMOTE': '1', 16:08:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:43 INFO - 'NO_EM_RESTART': '1', 16:08:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:43 INFO - 'PWD': '/builds/slave/test', 16:08:43 INFO - 'SHELL': '/bin/bash', 16:08:43 INFO - 'SHLVL': '1', 16:08:43 INFO - 'TERM': 'linux', 16:08:43 INFO - 'TMOUT': '86400', 16:08:43 INFO - 'USER': 'cltbld', 16:08:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:43 INFO - '_': '/tools/buildbot/bin/python'} 16:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:43 INFO - Running setup.py (path:/tmp/pip-xp9seu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:44 INFO - Running setup.py (path:/tmp/pip-kA53Q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:44 INFO - Running setup.py (path:/tmp/pip-osaFbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:44 INFO - Running setup.py (path:/tmp/pip-awQIfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:44 INFO - Running setup.py (path:/tmp/pip-7m2u6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:44 INFO - Running setup.py (path:/tmp/pip-58nhHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:44 INFO - Running setup.py (path:/tmp/pip-7pp6Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:44 INFO - Running setup.py (path:/tmp/pip-bvO5FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:44 INFO - Running setup.py (path:/tmp/pip-IAuRv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:44 INFO - Running setup.py (path:/tmp/pip-hixnAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:44 INFO - Running setup.py (path:/tmp/pip-UMj3Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:44 INFO - Running setup.py (path:/tmp/pip-cMpeMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:44 INFO - Running setup.py (path:/tmp/pip-r0iWSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:44 INFO - Running setup.py (path:/tmp/pip-7GqqbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:44 INFO - Running setup.py (path:/tmp/pip-rMC25n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:45 INFO - Running setup.py (path:/tmp/pip-YWEMy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:45 INFO - Running setup.py (path:/tmp/pip-sDhhmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:45 INFO - Running setup.py install for manifestparser 16:08:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozcrash 16:08:45 INFO - Running setup.py install for mozdebug 16:08:45 INFO - Running setup.py install for mozdevice 16:08:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozfile 16:08:45 INFO - Running setup.py install for mozhttpd 16:08:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozinfo 16:08:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozInstall 16:08:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozleak 16:08:46 INFO - Running setup.py install for mozlog 16:08:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for moznetwork 16:08:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozprocess 16:08:46 INFO - Running setup.py install for mozprofile 16:08:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozrunner 16:08:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozscreenshot 16:08:46 INFO - Running setup.py install for moztest 16:08:46 INFO - Running setup.py install for mozversion 16:08:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:46 INFO - Cleaning up... 16:08:46 INFO - Return code: 0 16:08:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:46 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:46 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 16:08:46 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 16:08:46 INFO - Using env: {'DISPLAY': ':0', 16:08:46 INFO - 'HOME': '/home/cltbld', 16:08:46 INFO - 'LANG': 'en_US.UTF-8', 16:08:46 INFO - 'LANGUAGE': 'en_US:en', 16:08:46 INFO - 'LOGNAME': 'cltbld', 16:08:46 INFO - 'MAIL': '/var/mail/cltbld', 16:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:46 INFO - 'MOZ_NO_REMOTE': '1', 16:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:46 INFO - 'NO_EM_RESTART': '1', 16:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:46 INFO - 'PWD': '/builds/slave/test', 16:08:46 INFO - 'SHELL': '/bin/bash', 16:08:46 INFO - 'SHLVL': '1', 16:08:46 INFO - 'TERM': 'linux', 16:08:46 INFO - 'TMOUT': '86400', 16:08:46 INFO - 'USER': 'cltbld', 16:08:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:46 INFO - '_': '/tools/buildbot/bin/python'} 16:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 INFO - Running setup.py (path:/tmp/pip-f3MQK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 INFO - Running setup.py (path:/tmp/pip-F47sFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:47 INFO - Running setup.py (path:/tmp/pip-I4l2bK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:47 INFO - Running setup.py (path:/tmp/pip-VlcwFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:47 INFO - Running setup.py (path:/tmp/pip-qckSI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:47 INFO - Running setup.py (path:/tmp/pip-PTRWFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:47 INFO - Running setup.py (path:/tmp/pip-HMXSXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:47 INFO - Running setup.py (path:/tmp/pip-fRjFbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:47 INFO - Running setup.py (path:/tmp/pip-SU5vQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:47 INFO - Running setup.py (path:/tmp/pip-gxGoFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:47 INFO - Running setup.py (path:/tmp/pip-j_EK43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:47 INFO - Running setup.py (path:/tmp/pip-Ii9oUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 INFO - Running setup.py (path:/tmp/pip-3a4YOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 INFO - Running setup.py (path:/tmp/pip-Bovo7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 INFO - Running setup.py (path:/tmp/pip-Cy8jts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:48 INFO - Running setup.py (path:/tmp/pip-14ajzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:48 INFO - Running setup.py (path:/tmp/pip-NYHzHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:48 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)) 16:08:48 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)) 16:08:48 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)) 16:08:48 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)) 16:08:48 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)) 16:08:48 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)) 16:08:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:50 INFO - Downloading blessings-1.6.tar.gz 16:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:50 INFO - Installing collected packages: blessings 16:08:50 INFO - Running setup.py install for blessings 16:08:50 INFO - Successfully installed blessings 16:08:50 INFO - Cleaning up... 16:08:50 INFO - Return code: 0 16:08:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:50 INFO - Reading from file tmpfile_stdout 16:08:50 INFO - Current package versions: 16:08:50 INFO - argparse == 1.2.1 16:08:50 INFO - blessings == 1.6 16:08:50 INFO - blobuploader == 1.2.4 16:08:50 INFO - docopt == 0.6.1 16:08:50 INFO - functools32 == 3.2.3-2 16:08:50 INFO - jsonschema == 2.5.1 16:08:50 INFO - manifestparser == 1.1 16:08:50 INFO - mozInstall == 1.12 16:08:50 INFO - mozcrash == 0.17 16:08:50 INFO - mozdebug == 0.1 16:08:50 INFO - mozdevice == 0.48 16:08:50 INFO - mozfile == 1.2 16:08:50 INFO - mozhttpd == 0.7 16:08:50 INFO - mozinfo == 0.9 16:08:50 INFO - mozleak == 0.1 16:08:50 INFO - mozlog == 3.2 16:08:50 INFO - moznetwork == 0.27 16:08:50 INFO - mozprocess == 0.23 16:08:50 INFO - mozprofile == 0.28 16:08:50 INFO - mozrunner == 6.12 16:08:50 INFO - mozscreenshot == 0.1 16:08:50 INFO - mozsystemmonitor == 0.3 16:08:50 INFO - moztest == 0.7 16:08:50 INFO - mozversion == 1.4 16:08:50 INFO - psutil == 3.1.1 16:08:50 INFO - requests == 1.2.3 16:08:50 INFO - wsgiref == 0.1.2 16:08:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:50 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 16:08:50 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 16:08:50 INFO - Using env: {'DISPLAY': ':0', 16:08:50 INFO - 'HOME': '/home/cltbld', 16:08:50 INFO - 'LANG': 'en_US.UTF-8', 16:08:50 INFO - 'LANGUAGE': 'en_US:en', 16:08:50 INFO - 'LOGNAME': 'cltbld', 16:08:50 INFO - 'MAIL': '/var/mail/cltbld', 16:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:50 INFO - 'MOZ_NO_REMOTE': '1', 16:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:50 INFO - 'NO_EM_RESTART': '1', 16:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:50 INFO - 'PWD': '/builds/slave/test', 16:08:50 INFO - 'SHELL': '/bin/bash', 16:08:50 INFO - 'SHLVL': '1', 16:08:50 INFO - 'TERM': 'linux', 16:08:50 INFO - 'TMOUT': '86400', 16:08:50 INFO - 'USER': 'cltbld', 16:08:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:50 INFO - '_': '/tools/buildbot/bin/python'} 16:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 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)) 16:08:50 INFO - Cleaning up... 16:08:50 INFO - Return code: 0 16:08:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 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 0x1936030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b65400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', '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 16:08:50 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 16:08:50 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 16:08:50 INFO - Using env: {'DISPLAY': ':0', 16:08:50 INFO - 'HOME': '/home/cltbld', 16:08:50 INFO - 'LANG': 'en_US.UTF-8', 16:08:50 INFO - 'LANGUAGE': 'en_US:en', 16:08:50 INFO - 'LOGNAME': 'cltbld', 16:08:50 INFO - 'MAIL': '/var/mail/cltbld', 16:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:50 INFO - 'MOZ_NO_REMOTE': '1', 16:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:50 INFO - 'NO_EM_RESTART': '1', 16:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:50 INFO - 'PWD': '/builds/slave/test', 16:08:50 INFO - 'SHELL': '/bin/bash', 16:08:50 INFO - 'SHLVL': '1', 16:08:50 INFO - 'TERM': 'linux', 16:08:50 INFO - 'TMOUT': '86400', 16:08:50 INFO - 'USER': 'cltbld', 16:08:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:50 INFO - '_': '/tools/buildbot/bin/python'} 16:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:51 INFO - Cleaning up... 16:08:51 INFO - Return code: 0 16:08:51 INFO - Running post-action listener: _resource_record_post_action 16:08:51 INFO - Running post-action listener: _start_resource_monitoring 16:08:51 INFO - Starting resource monitoring. 16:08:51 INFO - ##### 16:08:51 INFO - ##### Finished create-virtualenv step (success) 16:08:51 INFO - ##### 16:08:51 INFO - ##### 16:08:51 INFO - ##### Running install step. 16:08:51 INFO - ##### 16:08:51 INFO - Running pre-action listener: _resource_record_pre_action 16:08:51 INFO - Running main action method: install 16:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:51 INFO - Reading from file tmpfile_stdout 16:08:51 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:51 INFO - Reading from file tmpfile_stdout 16:08:51 INFO - Output received: 16:08:51 INFO - Usage: mozinstall [options] installer 16:08:51 INFO - Options: 16:08:51 INFO - -h, --help show this help message and exit 16:08:51 INFO - -d DEST, --destination=DEST 16:08:51 INFO - Directory to install application into. [default: 16:08:51 INFO - "/builds/slave/test"] 16:08:51 INFO - --app=APP Application being installed. [default: firefox] 16:08:51 INFO - mkdir: /builds/slave/test/build/application 16:08:51 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'] 16:08:51 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 16:09:08 INFO - Reading from file tmpfile_stdout 16:09:08 INFO - Output received: 16:09:08 INFO - /builds/slave/test/build/application/firefox/firefox 16:09:08 INFO - Running post-action listener: _resource_record_post_action 16:09:08 INFO - ##### 16:09:08 INFO - ##### Finished install step (success) 16:09:08 INFO - ##### 16:09:08 INFO - ##### 16:09:08 INFO - ##### Running run-tests step. 16:09:08 INFO - ##### 16:09:08 INFO - Running pre-action listener: _resource_record_pre_action 16:09:08 INFO - Running main action method: run_tests 16:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:09:08 INFO - Python 2.7.3 16:09:08 INFO - Return code: 0 16:09:08 INFO - grabbing minidump binary from tooltool 16:09: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')]} 16:09:08 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 0x1bf4570>, '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 0x1bf5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bf5900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:09:08 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 16:09:08 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 16:09:08 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 16:09:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:09:08 INFO - Return code: 0 16:09:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:09:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:09:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:09:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:09:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:09:08 INFO - Using env: {'DISPLAY': ':0', 16:09:08 INFO - 'HOME': '/home/cltbld', 16:09:08 INFO - 'LANG': 'en_US.UTF-8', 16:09:08 INFO - 'LANGUAGE': 'en_US:en', 16:09:08 INFO - 'LOGNAME': 'cltbld', 16:09:08 INFO - 'MAIL': '/var/mail/cltbld', 16:09:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:08 INFO - 'MOZ_NO_REMOTE': '1', 16:09:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:08 INFO - 'NO_EM_RESTART': '1', 16:09:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:08 INFO - 'PWD': '/builds/slave/test', 16:09:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:08 INFO - 'SHELL': '/bin/bash', 16:09:08 INFO - 'SHLVL': '1', 16:09:08 INFO - 'TERM': 'linux', 16:09:08 INFO - 'TMOUT': '86400', 16:09:08 INFO - 'USER': 'cltbld', 16:09:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709', 16:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:08 INFO - '_': '/tools/buildbot/bin/python'} 16:09:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:09:09 INFO - mozversion application_buildid: 20160719133037 16:09:09 INFO - mozversion application_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:09:09 INFO - mozversion application_display_name: Nightly 16:09:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:09:09 INFO - mozversion application_name: Firefox 16:09:09 INFO - mozversion application_remotingname: firefox 16:09:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:09:09 INFO - mozversion application_vendor: Mozilla 16:09:09 INFO - mozversion application_version: 50.0a1 16:09:09 INFO - mozversion platform_buildid: 20160719133037 16:09:09 INFO - mozversion platform_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:09:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:09:09 INFO - mozversion platform_version: 50.0a1 16:09:09 INFO - using testdate: 1468969749 16:09:09 INFO - actual date: 1468969749 16:09:09 INFO - starting webserver on 'localhost:56958' 16:09:09 INFO - SUITE-START | Running 2 tests 16:09:09 INFO - TEST-START | dromaeo_css 16:09:09 INFO - operating with platform_type : linux_ 16:09:09 INFO - Initialising browser for dromaeo_css test... 16:09:09 INFO - TEST-INFO | started process 3288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6h1jnP/profile http://localhost:56958/getInfo.html) 16:09:15 INFO - PROCESS | 3288 | __metrics Screen width/height:1600/1200 16:09:15 INFO - PROCESS | 3288 | colorDepth:24 16:09:15 INFO - PROCESS | 3288 | Browser inner width/height: 1024/697 16:09:15 INFO - PROCESS | 3288 | __metrics 16:09:16 ERROR - PROCESS | 3288 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:09:16 INFO - TEST-INFO | 3288: exit 0 16:09:16 INFO - Browser initialized. 16:09:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:16 INFO - TEST-INFO | started process 3416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6h1jnP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:28 INFO - PROCESS | 3416 | RSS: Main: 167165952 16:09:28 INFO - PROCESS | 3416 | 16:10:18 INFO - PROCESS | 3416 | 1468969818252 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] 16:12:26 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56958/tests/dromaeo/cssquery-ext.html) 16:12:27 INFO - PROCESS | 3416 | RSS: Main: 173215744 16:12:27 INFO - PROCESS | 3416 | 16:15:30 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-ext.html (next: http://localhost:56958/tests/dromaeo/cssquery-jquery.html) 16:15:30 INFO - PROCESS | 3416 | RSS: Main: 170213376 16:15:30 INFO - PROCESS | 3416 | 16:18:32 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56958/tests/dromaeo/cssquery-mootools.html) 16:18:33 INFO - PROCESS | 3416 | RSS: Main: 173314048 16:18:33 INFO - PROCESS | 3416 | 16:21:35 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56958/tests/dromaeo/cssquery-prototype.html) 16:21:35 INFO - PROCESS | 3416 | RSS: Main: 167460864 16:21:35 INFO - PROCESS | 3416 | 16:24:38 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56958/tests/dromaeo/cssquery-yui.html) 16:24:38 INFO - PROCESS | 3416 | RSS: Main: 169508864 16:24:38 INFO - PROCESS | 3416 | 16:27:41 INFO - PROCESS | 3416 | Cycle 1(1): loaded http://localhost:56958/tests/dromaeo/cssquery-yui.html (next: http://localhost:56958/tests/dromaeo/cssquery-dojo.html) 16:27:42 INFO - PROCESS | 3416 | RSS: Main: 170639360 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | __start_tp_report 16:27:42 INFO - PROCESS | 3416 | _x_x_mozilla_page_load 16:27:42 INFO - PROCESS | 3416 | _x_x_mozilla_page_load_details 16:27:42 INFO - PROCESS | 3416 | |i|pagename|runs| 16:27:42 INFO - PROCESS | 3416 | |0;dojo.html;3089.567460555522;3113.097201811474;3130.4208721386544;3131.8747250109996;3136.576562164107;5363.275957745703;5422.539084177844;5427.674339539631;5502;5546.528545073664;5410.8917821643545;5420.376656684482;5442.911417716463;5516.034693928566;5526.834194974157;4901.117798796224;5002.424721157077;5086;5129.692218466904;5136.743162841862;5022.82420115296;5196.506331898464;5210.140326846066;5211;5213.556847667953;4984.900301993976;5024.447310795809;5045.344105266328;5070.163423035195;5129.794808207667;4958.727270000149;4960.479149689303;4991.425986011583;5003;5019.42276638184;4943.975280123613;4984.90030199394;4985.626078044161;4988.725620090893;5043.924341134849;5348.866278343034;5394.8921021579745;5477.397486276506;5489.149181876815;5504.697241651707;5826.2134611827705;5870.677112758796;5877.000909845294;5905.084711869667;5927.051671732545;5931.881362372728;5936.614120082181;5940.376260492672;5942.940570594325;5944.97027514864;5367.1680889462195;5482.150266708666;5500.147477141009;5537;5537.280153580049;6116.449519543263;6120.326764055951;6120.387961203844;6124.448799608057;6132.264128304543;5850.2102216200265;5850.765969361178;5860.179574859523;5861.120831875252;5871.031279838864;5755.107958266475;5764.106563482667;5808.506276966421;5841.299044114733;5941.881162376728;5722.914156287659;5766.077427611562;5816.621919575214;5840.036393995028;5892.587518873637;5850.0932355484965;5893.057110862327;5898.380670029585;5903.852403689943;5957.136215248851;4382.101669157815;4459.777011149429;4468.530804265571;4485.349624304457;4509.391232183679;3949.289127956938;3974.3839704845795;3986.8039588124025;4005.078831868628;4036.29364861154;5654.208410822488;5673.74468148926;5684.37471878093;5696.715164241854;5711.114777209539;5731.598788084794;5940.287165540163;5944.524438044946;5953.583249172516;5963.373845746221;5582.190582365615;5635.830925072254;5643.068893632505;5713.285839270091;5739.426057394311;5116.155834287375;5116.411612664591;5118.027574760784;5118.59051275897;5123.769430375642;5891.145783861316;6136.815895523141;6138.846528836725;6141.508679305645;6144.815655530341;5752.482276595127;5813.709314534341;5822.301323841166;5871;5920.674362909909;5983.252093488314;6001.189839371629;6038.698065096815;6053.636781793107;6066.3023752269055;5706.28671416073;5716.142578613241;5753.769849205984;5769.1634712967225;5774.451427114417;5912.083627037816;5992.280926288873;6007.098935159761;6014.127951447103;6076.270847498328;5864.560157988082;5866.0027795275755;5872.55955803325;5878.030125029322;5902.6458412495385;5680.488756011979;5854.092615644582;5854.151148083419;5870.354261031198;5878.941210587839;5635.464630860061;5714;5725.456081672234;5726.9713651431475;5753.280839895013;5814.796482123105;5815.302163740378;5837.416258374129;5837.445442682938;5859.794907178229;5460.208269800777;5598;5727.369989301257;5730.341010783646;5732.684702341411 16:27:42 INFO - PROCESS | 3416 | |1;ext.html;551.1322815892293;598.267122774601;599.5653151465189;608.8812681527104;634.4448607468465;41149.794251028776;42163.36754948674;42345.78827105868;42376.788116059455;42447.87760611965;42515.14969700612;42984.35523467151;43039.139217215714;43179;43227.13545729091;8838.955805220981;8989.505577193251;8993.505357205384;8994.055624159468;9002.189802917736;8915.420497667663;8981.281497480186;9017.23353514952;9018.774530636754;9026.729198124067;9054.456732596065;9073.682421115262;9103.95448022759;9113.68102116426;9121.270298376146;8965.820683586324;8985.505797181153;9047.276217902552;9049.140331668481;9085.364024518287;8897.688580899638;8917.687880924137;8951.641934322619;8962.86555701665;8966.417182883091;40539.59460405388;41152.794236028625;41306;41514.1697166058;41539.1692166155;3687.8340474678434;3846.307664620367;3921.156951255494;4262.339337402707;4423.668224883115;32773.67226327754;33289.33421331584;33652.32695346104;33660.83169584136;34151.829240853636;4429.512753597101;4501.130511867879;4588.38056862326;4673.696209746356;4689.671722979393;357544;358042;359148;361909;362288.9999999974;56688;56992.14511782327;57006.7149664249;57018.7149064252;57330.426695733346;34832.82583587066;35339.64660353415;35450.29099418049;37810.81094594509;37924.24151516954;17523.56191095233;17540.386086486913;17716.291348346178;17719.29122835072;17790.199441025186;13486.460541578423;13623.250721210428;13663.248521331423;13678.794818077737;13689.452421903012;19331.121314476353;19424.849172867274;19495.15954112635;19538.802881858806;19569.104148146704;7066.328698773608;7077;7407.629618519052;7465.096400417859;7506.099268087755;1015.7562185075528;1134.9375784331776;1143.1712008793559;1160.7040204747734;1186.0926391310481;32306.676933230367;32502.187445314055;32530.349393012482;32687.67312326846;33169;1129.8192289233684;1140.4981808004452;1168.4041139019162;1188.2592002716062;1209.1052621060353;16709.33162673504;17912.839243240058;19350.612987740165;19386.515337116685;19689;636891;642275;644309;644362;645344;59036;59246.70376648089;59709.402905971256;59763;59779.10331345033;1346.8047133165637;1370;1378.3797291218991;1390.819193504858;1402.7404930087762;32077;35002;35039.299214016086;35097.64902351046;35217.47173792344;53369.733151335546;54118.729406352715;54158.45841541692;54173.18740218821;54210.186847196535;552814;556754;557892;561704;562603;42978.35532466953;43688.56311436845;44011.33982990194;44971;45056.97243623979;47313;48634.513654864415;48683;48927.75536122297;49089.509104908495;40288.3956740655;40977;41546;41756.373654395815;43330;41598.37602435905;41735.37396939109;42369.36445953375;42556.574434255264;43050.35424468573;41483.792581038106;41831.117599707475;42478.362824557036;42605.78697106495;42693.57306426896;34507.48238776471;36307.45538816867;36797.63202367942;37370.813145935186;37454;28522.42955140927;29083.854580726962;29313;29440.41119177647;29446.11661650154 16:27:42 INFO - PROCESS | 3416 | |2;jquery.html;388.3824718697269;389.8830350894731;398.551629416906;406.65231227300654;415.660170529793;13707.520236791714;17259.48221553354;18609.255629774794;18643.906780466114;18782;18785.906070469664;18941;19196.23215071399;19448.708269375937;19567.21731130753;5644.633098848582;5822.912656310159;5852.67810270435;5872.177895094669;5886.852828679296;5434.755436005389;5789.739461724233;5821.912671309934;5828.213191219194;5831.154482600021;5652.09566469365;5720.427957204288;5759.942400576004;5765.625234359774;5790.478856902878;5649.067903795869;5714.828555143332;5749.252597162342;5751.7699292028265;5776.537876969854;5382.91925621116;5559.972200138974;5581.330240371181;5606.943930560683;5629.662220266797;13618.114822536503;13703.520376786864;13969.860301397059;13992.510262140879;13995;2150.709654196695;2459.8770061497034;2523.6719226500622;2557.9360515987064;2793.427347393779;10058.04448577384;10156.746081347952;10765.192610554239;12684.30236337001;13685.384157712926;1868.9720653640443;1929.9903500482408;1957.2562426278066;1970.7339509166222;2009.8693584916934;80098;83376.58311708464;84076;84688;84747.15252847454;9238.491882946502;9715.29982253117;9807.362521436084;10694.358338499715;11023.228374013903;15378.38486460529;15409.229538523254;15423.305951232222;15436.922815386075;15441.459548915735;7963.124056353797;8153.551554664432;8295.129011453831;8368.790780230422;8452.661893524313;7821.100573434014;7847.764567062997;7850.293473587404;8077.7576672699915;8149.470284431493;4562.885927851764;4648.2562789953445;4713.128071306819;4765.4996225395835;4887.975560122177;1930.4015755115959;2041.3365656161807;2043.2031507712004;2056.475598722316;2109.8101170894693;904.6924045824451;958.6500927161675;983.4246965525105;1009.0918173643779;1072.286929192094;19119;19434.125464353856;19492;19493.707594386095;19730.802691972898;1007.6171054999201;1008.3193844155312;1027.7584767579613;1044.7962647283707;1056.155075939261;10345.793084138275;10858.945705271422;10891.83662245067;10928.235023548434;11001.834972475419;465502;479957;480543;481858;484152;91011;92021.5398923001;92275;92585.53707231421;96213;957.9089986451276;975.6048800235916;991.3011327014437;1023.8515415264745;1029.613894789454;16624;17406;17922.64154716872;18081.909590451964;18392.63214735671;41270.9682257934;42153;42167.57832421759;42309.36535951902;42320.788396057826;57824;60329.69835150796;61004;62007.37992620197;62242;8034.168531021214;8054.903918143249;8209.220124088202;8261.793455163668;8328.3337333012;8917.375783695079;11847;12611.369431528203;13104.541341053018;13136.080474366703;10705.323669082729;10780.083692885879;11037.558497660324;11717;11889.405529723652;10420.843687344848;11020.283681560673;11516.769664606829;11519.654410367702;11985;11084.168687348643;11470.942645286721;11681.88318116808;11968.640940771791;12134.635960921187;7786.18245084258;8300.83398332042;8370.121137280497;8560.315174786127;8633.223009929137;8448.803311139025;11495.712607184887;11511.827322590001;11577.363245021605;11788.174827761974 16:27:42 INFO - PROCESS | 3416 | |3;mootools.html;1498.3557070459701;1524.359768897063;1683.5201967439282;1770.6104656975476;1836.109486898854;7874.803129921755;8604.311655067595;8695.478271303718;9237.4457532548;9263.25893928485;8785.736427907157;9105.863412048824;9129.178373946343;9197.95401022994;9200.493972831491;2302.9078836846547;2338.3218866528687;2353.105819788478;2358.363241924679;2361.138184562631;2254.0983606557425;2310.191432998454;2314.6296592545195;2322.7096612923383;2325.2210509479346;2283.6802847601266;2289.1530133850415;2292.9656055159185;2344.495933374324;2350.4711439925964;2239.944001399962;2240.249516412004;2242.125571027301;2253.8422310438277;2254.729432468106;2153.558520503293;2161.1787520742173;2174.717286752728;2175.7389113306504;2179.2808373236794;9104;9111.681091161778;9219.032001639865;9228.215601673866;9270.675526356545;5305.602079843989;5381.515663590296;5431.049566325877;5433.538149257318;5443.972780136113;3161.44674681932;3162.0988018414873;3267.232200432896;3304.619968703582;3343.4316166251797;1167.0663469224671;1172.3259126002497;1180.144395313391;1182.154759347071;1193.0634451955173;12770.74458510834;12829.743405131938;12873.03452241095;12893.290869002201;12900.290484023375;4446.133004064176;4656.604188643935;4671;4735.12400205963;4776.593989510861;10724;10729.83905241422;10747.193960452998;10837.133029357632;10862.185336099825;5322.840314790563;5338.46615338463;5353.544948679328;5360.812371566976;5406.216098665672;4229.915401691949;4856.538628830255;4935.531124543163;4945.109880221595;4989.550940415307;3851.326017946848;4024.5170579530645;4044.8786536403954;4073.816678249486;4142.420061191436;1248.6129299917054;1262.8673989231181;1267.524678245658;1270.5489551209373;1279.9552015679405;831.0775039705921;930.4882314726873;988.5502096546054;996.5415908682088;1004.492731170754;5999.850003749942;6093.421124993119;6303.905441418382;6349.650769207646;6532.608043517404;999.5551979369111;1018.332991890313;1021.7649940513724;1024.9795004099876;1027.4708525109575;4105.2405305018665;4152.190322887008;4217.768022758778;4222.472190976128;4233.386159006988;11229.21395502307;11437.313761174357;11557.30656160633;11561.07511399086;11563.826542601868;11191.496382662799;11364.261323013978;11562.190646654653;11581.652550423501;11606;8422.620982055822;8584.527850968132;8599.26906212979;8616.39685222041;8637.438566493283;6043.54673399488;6078.192117298103;6093.542984276109;6170.598911070766;6211.9068213977735;10481.580736770444;10555.63055293061;10573.62992295266;10606.681799546026;10658.786824263316;10590.735231619272;10642.148628109888;10644.680659579915;10867.29362591429;10900.945495272474;7297.306755858185;7771.883421748791;7818.921810782047;8043.919560804317;8151.79620509492;8696.695615653422;8824.86762698573;9043.366964312436;9109.453432794053;9133.77165570866;7968.920310796818;8005.1594582568;8133.471324363897;8152.673893044449;8412.242898139197;7894.36845052383;7902.920970790218;7933.761987140395;8029.7189598363775;8307;7030.894536581853;7041.295870412918;7105.680244389118;7116.110486189226;7128.501004929605;5382.085045542189;5452.100403433389;5471.151971444432;5514.5864060194845;5649.915251271316;4832.710037397767;4888.535589119028;4907.4847141051105;4921.778519966681;4998.950010499849 16:27:42 INFO - PROCESS | 3416 | |4;prototype.html;321.9050380137859;335.4130272023958;337.20251604954285;339.7536785830272;345.06831554802034;5420.512153906147;5507.917381239285;5606.439356064391;5687.658740475569;5727.885442291152;5491.780328786854;5554.694491802949;5562;5564.137558678412;5585.776568937237;4326.480822301321;4368.781560921957;4386.320120381346;4422.226110430678;4424.336349547561;4100.118474527976;4318.963448772303;4343.739375637456;4413.867583972486;4435.223835828733;4300.35494675798;4322.1787860306595;4323.286657701476;4323.286657701476;4334.306510958231;4304.139172165564;4325.978370108145;4336.327869180251;4349.478062632505;4365.476142862877;3716.925661486755;3753.474513568103;3778.149916268845;3825.9617403825882;3831.4444405561317;5408.891822163535;5414.43148469409;5425.321834770653;5429.185622156708;5484.972575137099;4951.108800415924;4952.653314268003;4959.305697202359;4971.304017437562;4974.353334066548;5583.3300003999375;5865.85335366615;5872.799324368187;5880.441358070976;5919.5264378850015;4185.623293903533;4191.94594648623;4193.287141186006;4196;4196.9370459443135;6142.6007309525185;6167.87664246717;6185.938140618581;6188.659623720693;6214.161088253073;4127.545969943304;4205.852795152155;4232.153569286155;4257.148570285955;4291.120320334324;4287.563666171805;4306.332518459643;4310;4322.092360604308;4328.826846926151;4962.9007419851405;4967.5777558907175;4979.825706100341;4980.701157930535;4992.625553083533;5771.163181338682;5935.436133567304;5947.256592925884;5974.641521508636;5991.9700401498585;1483.807105076333;1503.1356969742442;1508.1177511155877;1509.5244997825869;1511.1084460168554;891.8706787515774;934.5794392523392;945.9668911588061;961.8990006049404;965.9855102173473;5054.685781696712;5063.518965698253;5080.466551012164;5082.313887625197;5140.177571588602;5317.282166907496;5331.680099193984;5344.5724342052545;5349.277847490617;5380.784768609212;5021.723805190676;5062.582476906472;5105.693658380509;5432.266644003088;5857.912131318034;3242.610886693612;3245.626752923444;3248.805071695706;3253.186703324169;3259.2340799912004;6011.849703757353;6324.9051264231075;6335.208098987627;6336.6831658416895;6359.491240700733;6111.84720381994;6114.174586430775;6123.9387606124255;6136.938630613726;6173.81478555644;6194.566380353332;6195.411435913581;6198.349173336825;6211.3167551569295;6230.532710046765;5819.682780814895;6031.788887388921;6042.516598672008;6051.576389652682;6082.756689732361;5923.170756094237;5936.732847021808;5950.583459157991;5955.374685657944;5985.850353741192;5989.940100599112;6158.3225845157895;6278.4303924019;6314.589551679126;6362.681865906594;4154.982029402816;4259.679499355209;4296.76367799774;4303.160883627663;4319.222539942841;6030.4572588467245;6093.587590949082;6101.359357267601;6127.632342059491;6138.07928810682;5769.68266745333;5835.56233282497;5967.522598192048;5967.850803729942;5978.192943952511;5848.152017957287;5861.912071319019;5864.413558644102;5900.262467191601;5944.405559444021;5763.625364351304;5793.188953546422;5796.7392716841305;5851.736671849691;5883.970580147072;5587.944120558743;5917.497012753965;5928.555358348055;5944.72188479479;5958.582899197015;5849.590528663123;5876.676782776988;5888.852778680567;5929.140274660236;5941.881162376641 16:27:42 INFO - PROCESS | 3416 | |5;yui.html;773.1765669561917;841.7180244618053;850.8043150075479;853.4111463090463;862.831747809177;15075.095494270337;16060.196990150474;16121.516354509355;16126.03243805371;16249.431269905555;15119.622009449742;15288.847111528881;15334.233288335567;15355.846441535608;15360.462383816568;661.8808614449397;787.4330482052912;806.4596720197452;813.9755807325789;824.5011767880436;808.8827120067587;814.4339683919678;819.4837252530926;823.370121856777;823.8393513264916;885.6147575804546;885.7785553611598;886.6674996876171;888.2094935507404;888.8888888888889;862.7239283429305;866.1252135343317;869.3132425383939;871.6861929705301;874.1739056591524;637.7704026550438;638.7891995641473;641.1760887259849;642.6529673976094;642.8971364581691;15238;15260.618484537865;15371.769423458658;15381.538553843402;15389.846101539066;15221.695566088616;15257.771133433007;15383.923080384526;15470.690586188326;15486.612834679223;11673.94163029188;11788.528458861636;11790.941045294805;11888.524459021628;12541.247525148521;3910.980445097785;4003.799810009488;4072.694547908889;4077.8572749953905;4142.772147531885;70001;70902.64548677276;72440;72658;72685.63657181786;11989.880101198876;12093.87906120945;12247;12324;12759.744805103846;22900.083996639947;22953.885230574073;22966.42583935415;23024.65463018056;23238;8407.537585432738;8440.493570385797;8702.6518939243;8785.648574056966;8880.555972201364;4612.285095810154;4656.557627025427;4675.854415668183;4735.928961065587;4807.663463557587;3308.4871844864083;3420.110771199506;3423.4522476404154;3424.760266781301;3720.497732806091;635.5995722694673;642.6786606696652;643.5816719132602;657.3065415986181;672.5930811858755;610.6916007416315;671.9160104986877;688.6763221285987;732.2274999875183;750.6926032947121;10099.44453055089;10158.187345012382;10206.642767503101;10215.284930054824;10384.169266458664;687.1547995964892;702.4380495603497;715.8047102329348;742.688071010177;754.5171090502083;4597.666721579076;4888.340074089952;4915.523782460619;5022.321986531844;5312.1766126250495;98898;117219.41390293164;118360.40819795846;130020.3498982499;132976;84949;85759;85853;98738.50630746801;103179;95137.524312378;95614;96465;98648.50675746576;99233;10122.291439599157;10248.28262021681;10468.214883883587;10540.15678745714;10550.314229575053;53060.93878122493;53631.46368536264;54956.17565736437;57988.420115798304;58380;80711.17510887091;86257.05904655649;89000.10999889918;90183;91386.62920056071;12987.595500690442;13308;13757.724845503235;13895.652608684864;14557.781633275297;15006.77489837675;15643;16373.508794736177;16456.259468323715;16616.63059414902;14025.719485610434;14190.148591084568;15119;15241.161736104621;15387.923060384626;14603.853961460249;14705.41178352854;15020.549383518512;15229.238538073274;15260.389584416944;11680.415979201176;13538.93230533841;13543.390547425193;14092.929535352258;14651.780223296872;11978.984936546114;12116.21244619097;12239.20445171061;12288.877111228774;12300.876991229974;10203.591856325664;11160.134701572373;12251.264924104582;12664.936675316565;12834 16:27:42 INFO - PROCESS | 3416 | __end_tp_report 16:27:42 INFO - PROCESS | 3416 | __start_cc_report 16:27:42 INFO - PROCESS | 3416 | _x_x_mozilla_cycle_collect,365 16:27:42 INFO - PROCESS | 3416 | __end_cc_report 16:27:42 INFO - PROCESS | 3416 | __startTimestamp1468970862033__endTimestamp 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | ------- Summary: start ------- 16:27:42 INFO - PROCESS | 3416 | Number of tests: 6 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#0] dojo.html Cycles:165 Average:5483.83 Median:5725.46 stddev:634.66 (11.1%) stddev-sans-first:608.18 16:27:42 INFO - PROCESS | 3416 | Values: 3089.6 3113.1 3130.4 3131.9 3136.6 5363.3 5422.5 5427.7 5502.0 5546.5 5410.9 5420.4 5442.9 5516.0 5526.8 4901.1 5002.4 5086.0 5129.7 5136.7 5022.8 5196.5 5210.1 5211.0 5213.6 4984.9 5024.4 5045.3 5070.2 5129.8 4958.7 4960.5 4991.4 5003.0 5019.4 4944.0 4984.9 4985.6 4988.7 5043.9 5348.9 5394.9 5477.4 5489.1 5504.7 5826.2 5870.7 5877.0 5905.1 5927.1 5931.9 5936.6 5940.4 5942.9 5945.0 5367.2 5482.2 5500.1 5537.0 5537.3 6116.4 6120.3 6120.4 6124.4 6132.3 5850.2 5850.8 5860.2 5861.1 5871.0 5755.1 5764.1 5808.5 5841.3 5941.9 5722.9 5766.1 5816.6 5840.0 5892.6 5850.1 5893.1 5898.4 5903.9 5957.1 4382.1 4459.8 4468.5 4485.3 4509.4 3949.3 3974.4 3986.8 4005.1 4036.3 5654.2 5673.7 5684.4 5696.7 5711.1 5731.6 5940.3 5944.5 5953.6 5963.4 5582.2 5635.8 5643.1 5713.3 5739.4 5116.2 5116.4 5118.0 5118.6 5123.8 5891.1 6136.8 6138.8 6141.5 6144.8 5752.5 5813.7 5822.3 5871.0 5920.7 5983.3 6001.2 6038.7 6053.6 6066.3 5706.3 5716.1 5753.8 5769.2 5774.5 5912.1 5992.3 6007.1 6014.1 6076.3 5864.6 5866.0 5872.6 5878.0 5902.6 5680.5 5854.1 5854.2 5870.4 5878.9 5635.5 5714.0 5725.5 5727.0 5753.3 5814.8 5815.3 5837.4 5837.4 5859.8 5460.2 5598.0 5727.4 5730.3 5732.7 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#1] ext.html Cycles:180 Average:67039.94 Median:42412.33 stddev:142371.15 (335.7%) stddev-sans-first:142683.01 16:27:42 INFO - PROCESS | 3416 | Values: 551.1 598.3 599.6 608.9 634.4 41149.8 42163.4 42345.8 42376.8 42447.9 42515.1 42984.4 43039.1 43179.0 43227.1 8839.0 8989.5 8993.5 8994.1 9002.2 8915.4 8981.3 9017.2 9018.8 9026.7 9054.5 9073.7 9104.0 9113.7 9121.3 8965.8 8985.5 9047.3 9049.1 9085.4 8897.7 8917.7 8951.6 8962.9 8966.4 40539.6 41152.8 41306.0 41514.2 41539.2 3687.8 3846.3 3921.2 4262.3 4423.7 32773.7 33289.3 33652.3 33660.8 34151.8 4429.5 4501.1 4588.4 4673.7 4689.7 357544.0 358042.0 359148.0 361909.0 362289.0 56688.0 56992.1 57006.7 57018.7 57330.4 34832.8 35339.6 35450.3 37810.8 37924.2 17523.6 17540.4 17716.3 17719.3 17790.2 13486.5 13623.3 13663.2 13678.8 13689.5 19331.1 19424.8 19495.2 19538.8 19569.1 7066.3 7077.0 7407.6 7465.1 7506.1 1015.8 1134.9 1143.2 1160.7 1186.1 32306.7 32502.2 32530.3 32687.7 33169.0 1129.8 1140.5 1168.4 1188.3 1209.1 16709.3 17912.8 19350.6 19386.5 19689.0 636891.0 642275.0 644309.0 644362.0 645344.0 59036.0 59246.7 59709.4 59763.0 59779.1 1346.8 1370.0 1378.4 1390.8 1402.7 32077.0 35002.0 35039.3 35097.6 35217.5 53369.7 54118.7 54158.5 54173.2 54210.2 552814.0 556754.0 557892.0 561704.0 562603.0 42978.4 43688.6 44011.3 44971.0 45057.0 47313.0 48634.5 48683.0 48927.8 49089.5 40288.4 40977.0 41546.0 41756.4 43330.0 41598.4 41735.4 42369.4 42556.6 43050.4 41483.8 41831.1 42478.4 42605.8 42693.6 34507.5 36307.5 36797.6 37370.8 37454.0 28522.4 29083.9 29313.0 29440.4 29446.1 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#2] jquery.html Cycles:180 Average:28491.06 Median:394.22 stddev:79100.38 (20065.2%) stddev-sans-first:79294.13 16:27:42 INFO - PROCESS | 3416 | Values: 388.4 389.9 398.6 406.7 415.7 13707.5 17259.5 18609.3 18643.9 18782.0 18785.9 18941.0 19196.2 19448.7 19567.2 5644.6 5822.9 5852.7 5872.2 5886.9 5434.8 5789.7 5821.9 5828.2 5831.2 5652.1 5720.4 5759.9 5765.6 5790.5 5649.1 5714.8 5749.3 5751.8 5776.5 5382.9 5560.0 5581.3 5606.9 5629.7 13618.1 13703.5 13969.9 13992.5 13995.0 2150.7 2459.9 2523.7 2557.9 2793.4 10058.0 10156.7 10765.2 12684.3 13685.4 1869.0 1930.0 1957.3 1970.7 2009.9 80098.0 83376.6 84076.0 84688.0 84747.2 9238.5 9715.3 9807.4 10694.4 11023.2 15378.4 15409.2 15423.3 15436.9 15441.5 7963.1 8153.6 8295.1 8368.8 8452.7 7821.1 7847.8 7850.3 8077.8 8149.5 4562.9 4648.3 4713.1 4765.5 4888.0 1930.4 2041.3 2043.2 2056.5 2109.8 904.7 958.7 983.4 1009.1 1072.3 19119.0 19434.1 19492.0 19493.7 19730.8 1007.6 1008.3 1027.8 1044.8 1056.2 10345.8 10858.9 10891.8 10928.2 11001.8 465502.0 479957.0 480543.0 481858.0 484152.0 91011.0 92021.5 92275.0 92585.5 96213.0 957.9 975.6 991.3 1023.9 1029.6 16624.0 17406.0 17922.6 18081.9 18392.6 41271.0 42153.0 42167.6 42309.4 42320.8 57824.0 60329.7 61004.0 62007.4 62242.0 8034.2 8054.9 8209.2 8261.8 8328.3 8917.4 11847.0 12611.4 13104.5 13136.1 10705.3 10780.1 11037.6 11717.0 11889.4 10420.8 11020.3 11516.8 11519.7 11985.0 11084.2 11470.9 11681.9 11968.6 12134.6 7786.2 8300.8 8370.1 8560.3 8633.2 8448.8 11495.7 11511.8 11577.4 11788.2 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#3] mootools.html Cycles:180 Average:5982.04 Median:4339.76 stddev:3480.51 (80.2%) stddev-sans-first:3473.97 16:27:42 INFO - PROCESS | 3416 | Values: 1498.4 1524.4 1683.5 1770.6 1836.1 7874.8 8604.3 8695.5 9237.4 9263.3 8785.7 9105.9 9129.2 9198.0 9200.5 2302.9 2338.3 2353.1 2358.4 2361.1 2254.1 2310.2 2314.6 2322.7 2325.2 2283.7 2289.2 2293.0 2344.5 2350.5 2239.9 2240.2 2242.1 2253.8 2254.7 2153.6 2161.2 2174.7 2175.7 2179.3 9104.0 9111.7 9219.0 9228.2 9270.7 5305.6 5381.5 5431.0 5433.5 5444.0 3161.4 3162.1 3267.2 3304.6 3343.4 1167.1 1172.3 1180.1 1182.2 1193.1 12770.7 12829.7 12873.0 12893.3 12900.3 4446.1 4656.6 4671.0 4735.1 4776.6 10724.0 10729.8 10747.2 10837.1 10862.2 5322.8 5338.5 5353.5 5360.8 5406.2 4229.9 4856.5 4935.5 4945.1 4989.6 3851.3 4024.5 4044.9 4073.8 4142.4 1248.6 1262.9 1267.5 1270.5 1280.0 831.1 930.5 988.6 996.5 1004.5 5999.9 6093.4 6303.9 6349.7 6532.6 999.6 1018.3 1021.8 1025.0 1027.5 4105.2 4152.2 4217.8 4222.5 4233.4 11229.2 11437.3 11557.3 11561.1 11563.8 11191.5 11364.3 11562.2 11581.7 11606.0 8422.6 8584.5 8599.3 8616.4 8637.4 6043.5 6078.2 6093.5 6170.6 6211.9 10481.6 10555.6 10573.6 10606.7 10658.8 10590.7 10642.1 10644.7 10867.3 10900.9 7297.3 7771.9 7818.9 8043.9 8151.8 8696.7 8824.9 9043.4 9109.5 9133.8 7968.9 8005.2 8133.5 8152.7 8412.2 7894.4 7902.9 7933.8 8029.7 8307.0 7030.9 7041.3 7105.7 7116.1 7128.5 5382.1 5452.1 5471.2 5514.6 5649.9 4832.7 4888.5 4907.5 4921.8 4999.0 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#4] prototype.html Cycles:180 Average:4905.32 Median:5499.85 stddev:1454.27 (26.4%) stddev-sans-first:1417.08 16:27:42 INFO - PROCESS | 3416 | Values: 321.9 335.4 337.2 339.8 345.1 5420.5 5507.9 5606.4 5687.7 5727.9 5491.8 5554.7 5562.0 5564.1 5585.8 4326.5 4368.8 4386.3 4422.2 4424.3 4100.1 4319.0 4343.7 4413.9 4435.2 4300.4 4322.2 4323.3 4323.3 4334.3 4304.1 4326.0 4336.3 4349.5 4365.5 3716.9 3753.5 3778.1 3826.0 3831.4 5408.9 5414.4 5425.3 5429.2 5485.0 4951.1 4952.7 4959.3 4971.3 4974.4 5583.3 5865.9 5872.8 5880.4 5919.5 4185.6 4191.9 4193.3 4196.0 4196.9 6142.6 6167.9 6185.9 6188.7 6214.2 4127.5 4205.9 4232.2 4257.1 4291.1 4287.6 4306.3 4310.0 4322.1 4328.8 4962.9 4967.6 4979.8 4980.7 4992.6 5771.2 5935.4 5947.3 5974.6 5992.0 1483.8 1503.1 1508.1 1509.5 1511.1 891.9 934.6 946.0 961.9 966.0 5054.7 5063.5 5080.5 5082.3 5140.2 5317.3 5331.7 5344.6 5349.3 5380.8 5021.7 5062.6 5105.7 5432.3 5857.9 3242.6 3245.6 3248.8 3253.2 3259.2 6011.8 6324.9 6335.2 6336.7 6359.5 6111.8 6114.2 6123.9 6136.9 6173.8 6194.6 6195.4 6198.3 6211.3 6230.5 5819.7 6031.8 6042.5 6051.6 6082.8 5923.2 5936.7 5950.6 5955.4 5985.9 5989.9 6158.3 6278.4 6314.6 6362.7 4155.0 4259.7 4296.8 4303.2 4319.2 6030.5 6093.6 6101.4 6127.6 6138.1 5769.7 5835.6 5967.5 5967.9 5978.2 5848.2 5861.9 5864.4 5900.3 5944.4 5763.6 5793.2 5796.7 5851.7 5884.0 5587.9 5917.5 5928.6 5944.7 5958.6 5849.6 5876.7 5888.9 5929.1 5941.9 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - PROCESS | 3416 | [#5] yui.html Cycles:180 Average:21735.31 Median:3957.39 stddev:31144.48 (787.0%) stddev-sans-first:31192.07 16:27:42 INFO - PROCESS | 3416 | Values: 773.2 841.7 850.8 853.4 862.8 15075.1 16060.2 16121.5 16126.0 16249.4 15119.6 15288.8 15334.2 15355.8 15360.5 661.9 787.4 806.5 814.0 824.5 808.9 814.4 819.5 823.4 823.8 885.6 885.8 886.7 888.2 888.9 862.7 866.1 869.3 871.7 874.2 637.8 638.8 641.2 642.7 642.9 15238.0 15260.6 15371.8 15381.5 15389.8 15221.7 15257.8 15383.9 15470.7 15486.6 11673.9 11788.5 11790.9 11888.5 12541.2 3911.0 4003.8 4072.7 4077.9 4142.8 70001.0 70902.6 72440.0 72658.0 72685.6 11989.9 12093.9 12247.0 12324.0 12759.7 22900.1 22953.9 22966.4 23024.7 23238.0 8407.5 8440.5 8702.7 8785.6 8880.6 4612.3 4656.6 4675.9 4735.9 4807.7 3308.5 3420.1 3423.5 3424.8 3720.5 635.6 642.7 643.6 657.3 672.6 610.7 671.9 688.7 732.2 750.7 10099.4 10158.2 10206.6 10215.3 10384.2 687.2 702.4 715.8 742.7 754.5 4597.7 4888.3 4915.5 5022.3 5312.2 98898.0 117219.4 118360.4 130020.3 132976.0 84949.0 85759.0 85853.0 98738.5 103179.0 95137.5 95614.0 96465.0 98648.5 99233.0 10122.3 10248.3 10468.2 10540.2 10550.3 53060.9 53631.5 54956.2 57988.4 58380.0 80711.2 86257.1 89000.1 90183.0 91386.6 12987.6 13308.0 13757.7 13895.7 14557.8 15006.8 15643.0 16373.5 16456.3 16616.6 14025.7 14190.1 15119.0 15241.2 15387.9 14603.9 14705.4 15020.5 15229.2 15260.4 11680.4 13538.9 13543.4 14092.9 14651.8 11979.0 12116.2 12239.2 12288.9 12300.9 10203.6 11160.1 12251.3 12664.9 12834.0 16:27:42 INFO - PROCESS | 3416 | -------- Summary: end -------- 16:27:42 INFO - PROCESS | 3416 | 16:27:42 INFO - TEST-INFO | 3416: exit 0 16:27:42 INFO - TEST-OK | dromaeo_css | took 1113602ms 16:27:42 INFO - TEST-START | kraken 16:27:42 INFO - operating with platform_type : linux_ 16:27:42 INFO - Initialising browser for kraken test... 16:27:42 INFO - TEST-INFO | started process 3665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdaiYxV/profile http://localhost:56958/getInfo.html) 16:27:47 INFO - PROCESS | 3665 | __metrics Screen width/height:1600/1200 16:27:47 INFO - PROCESS | 3665 | colorDepth:24 16:27:47 INFO - PROCESS | 3665 | Browser inner width/height: 1024/697 16:27:47 INFO - PROCESS | 3665 | __metrics 16:27:48 ERROR - PROCESS | 3665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:27:48 INFO - TEST-INFO | 3665: exit 0 16:27:48 INFO - Browser initialized. 16:27:48 INFO - Running cycle 1/1 for kraken test... 16:27:48 INFO - TEST-INFO | started process 3787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdaiYxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:00 INFO - PROCESS | 3787 | RSS: Main: 167260160 16:28:00 INFO - PROCESS | 3787 | 16:28:03 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56958/tests/kraken/driver.html?testName=audio-beat-detection) 16:28:04 INFO - PROCESS | 3787 | RSS: Main: 172740608 16:28:04 INFO - PROCESS | 3787 | 16:28:06 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56958/tests/kraken/driver.html?testName=audio-dft) 16:28:07 INFO - PROCESS | 3787 | RSS: Main: 171499520 16:28:07 INFO - PROCESS | 3787 | 16:28:10 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56958/tests/kraken/driver.html?testName=audio-fft) 16:28:10 INFO - PROCESS | 3787 | RSS: Main: 167657472 16:28:10 INFO - PROCESS | 3787 | 16:28:12 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56958/tests/kraken/driver.html?testName=audio-oscillator) 16:28:13 INFO - PROCESS | 3787 | RSS: Main: 168103936 16:28:13 INFO - PROCESS | 3787 | 16:28:15 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56958/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:16 INFO - PROCESS | 3787 | RSS: Main: 168951808 16:28:16 INFO - PROCESS | 3787 | 16:28:19 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56958/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:20 INFO - PROCESS | 3787 | RSS: Main: 169046016 16:28:20 INFO - PROCESS | 3787 | 16:28:23 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56958/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:24 INFO - PROCESS | 3787 | RSS: Main: 166686720 16:28:24 INFO - PROCESS | 3787 | 16:28:27 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56958/tests/kraken/driver.html?testName=json-parse-financial) 16:28:27 INFO - PROCESS | 3787 | RSS: Main: 168271872 16:28:27 INFO - PROCESS | 3787 | 16:28:29 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56958/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:29 INFO - PROCESS | 3787 | RSS: Main: 168394752 16:28:29 INFO - PROCESS | 3787 | 16:28:31 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:32 INFO - PROCESS | 3787 | RSS: Main: 168677376 16:28:32 INFO - PROCESS | 3787 | 16:28:34 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:35 INFO - PROCESS | 3787 | RSS: Main: 168984576 16:28:35 INFO - PROCESS | 3787 | 16:28:38 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:38 INFO - PROCESS | 3787 | RSS: Main: 169639936 16:28:38 INFO - PROCESS | 3787 | 16:28:41 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:41 INFO - PROCESS | 3787 | RSS: Main: 169897984 16:28:41 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | Cycle 1(1): loaded http://localhost:56958/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56958/tests/kraken/driver.html?testName=ai-astar) 16:28:43 INFO - PROCESS | 3787 | RSS: Main: 170082304 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | __start_tp_report 16:28:43 INFO - PROCESS | 3787 | _x_x_mozilla_page_load 16:28:43 INFO - PROCESS | 3787 | _x_x_mozilla_page_load_details 16:28:43 INFO - PROCESS | 3787 | |i|pagename|runs| 16:28:43 INFO - PROCESS | 3787 | |0;ai-astar;103;112;108;111;113;115;113;112;112;112 16:28:43 INFO - PROCESS | 3787 | |1;audio-beat-detection;145;119;145;180;119;146;149;118;177;117 16:28:43 INFO - PROCESS | 3787 | |2;audio-dft;163;164;162;161;162;165;177;166;163;168 16:28:43 INFO - PROCESS | 3787 | |3;audio-fft;75;75;76;153;79;75;75;75;171;75 16:28:43 INFO - PROCESS | 3787 | |4;audio-oscillator;85;84;148;86;84;85;149;85;83;141 16:28:43 INFO - PROCESS | 3787 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;118;118 16:28:43 INFO - PROCESS | 3787 | |6;imaging-darkroom;148;148;147;147;147;147;146;146;148;146 16:28:43 INFO - PROCESS | 3787 | |7;imaging-desaturate;95;97;96;95;96;96;96;96;96;95 16:28:43 INFO - PROCESS | 3787 | |8;json-parse-financial;55;53;53;52;52;52;57;53;54;52 16:28:43 INFO - PROCESS | 3787 | |9;json-stringify-tinderbox;61;62;61;62;62;61;61;62;64;62 16:28:43 INFO - PROCESS | 3787 | |10;stanford-crypto-aes;76;82;76;77;77;83;77;78;86;76 16:28:43 INFO - PROCESS | 3787 | |11;stanford-crypto-ccm;134;136;137;127;126;124;134;122;161;124 16:28:43 INFO - PROCESS | 3787 | |12;stanford-crypto-pbkdf2;162;159;161;158;158;164;157;158;154;160 16:28:43 INFO - PROCESS | 3787 | |13;stanford-crypto-sha256-iterative;65;67;69;64;71;64;65;65;63;63 16:28:43 INFO - PROCESS | 3787 | __end_tp_report 16:28:43 INFO - PROCESS | 3787 | __start_cc_report 16:28:43 INFO - PROCESS | 3787 | _x_x_mozilla_cycle_collect,880 16:28:43 INFO - PROCESS | 3787 | __end_cc_report 16:28:43 INFO - PROCESS | 3787 | __startTimestamp1468970923920__endTimestamp 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | ------- Summary: start ------- 16:28:43 INFO - PROCESS | 3787 | Number of tests: 14 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.35 (3.0%) stddev-sans-first:1.87 16:28:43 INFO - PROCESS | 3787 | Values: 103.0 112.0 108.0 111.0 113.0 115.0 113.0 112.0 112.0 112.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:145.50 stddev:23.59 (16.2%) stddev-sans-first:24.99 16:28:43 INFO - PROCESS | 3787 | Values: 145.0 119.0 145.0 180.0 119.0 146.0 149.0 118.0 177.0 117.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.50 stddev:4.68 (2.8%) stddev-sans-first:4.90 16:28:43 INFO - PROCESS | 3787 | Values: 163.0 164.0 162.0 161.0 162.0 165.0 177.0 166.0 163.0 168.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#3] audio-fft Cycles:10 Average:92.90 Median:75.00 stddev:36.69 (48.9%) stddev-sans-first:38.34 16:28:43 INFO - PROCESS | 3787 | Values: 75.0 75.0 76.0 153.0 79.0 75.0 75.0 75.0 171.0 75.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:84.50 stddev:29.75 (35.2%) stddev-sans-first:30.84 16:28:43 INFO - PROCESS | 3787 | Values: 85.0 84.0 148.0 86.0 84.0 85.0 149.0 85.0 83.0 141.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:28:43 INFO - PROCESS | 3787 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 16:28:43 INFO - PROCESS | 3787 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 146.0 146.0 148.0 146.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 16:28:43 INFO - PROCESS | 3787 | Values: 95.0 97.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:1.64 (3.1%) stddev-sans-first:1.62 16:28:43 INFO - PROCESS | 3787 | Values: 55.0 53.0 53.0 52.0 52.0 52.0 57.0 53.0 54.0 52.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 16:28:43 INFO - PROCESS | 3787 | Values: 61.0 62.0 61.0 62.0 62.0 61.0 61.0 62.0 64.0 62.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:77.50 stddev:3.55 (4.6%) stddev-sans-first:3.62 16:28:43 INFO - PROCESS | 3787 | Values: 76.0 82.0 76.0 77.0 77.0 83.0 77.0 78.0 86.0 76.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:134.00 stddev:11.43 (8.5%) stddev-sans-first:12.11 16:28:43 INFO - PROCESS | 3787 | Values: 134.0 136.0 137.0 127.0 126.0 124.0 134.0 122.0 161.0 124.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.10 Median:159.50 stddev:2.81 (1.8%) stddev-sans-first:2.77 16:28:43 INFO - PROCESS | 3787 | Values: 162.0 159.0 161.0 158.0 158.0 164.0 157.0 158.0 154.0 160.0 16:28:43 INFO - PROCESS | 3787 | 16:28:43 INFO - PROCESS | 3787 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.00 stddev:2.63 (4.1%) stddev-sans-first:2.78 16:28:43 INFO - PROCESS | 3787 | Values: 65.0 67.0 69.0 64.0 71.0 64.0 65.0 65.0 63.0 63.0 16:28:43 INFO - PROCESS | 3787 | -------- Summary: end -------- 16:28:43 INFO - PROCESS | 3787 | 16:28:44 INFO - TEST-INFO | 3787: exit 0 16:28:44 INFO - TEST-OK | kraken | took 62118ms 16:28:44 INFO - SUITE-END | took 1175s 16:28:45 INFO - Completed test suite (00:19:35) 16:28:45 INFO - javascript benchmark 16:28:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7444.289176939825, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3089.567460555522, 3113.097201811474, 3130.4208721386544, 3131.8747250109996, 3136.576562164107, 5363.275957745703, 5422.539084177844, 5427.674339539631, 5502.0, 5546.528545073664, 5410.8917821643545, 5420.376656684482, 5442.911417716463, 5516.034693928566, 5526.834194974157, 4901.117798796224, 5002.424721157077, 5086.0, 5129.692218466904, 5136.743162841862, 5022.82420115296, 5196.506331898464, 5210.140326846066, 5211.0, 5213.556847667953, 4984.900301993976, 5024.447310795809, 5045.344105266328, 5070.163423035195, 5129.794808207667, 4958.727270000149, 4960.479149689303, 4991.425986011583, 5003.0, 5019.42276638184, 4943.975280123613, 4984.90030199394, 4985.626078044161, 4988.725620090893, 5043.924341134849, 5348.866278343034, 5394.8921021579745, 5477.397486276506, 5489.149181876815, 5504.697241651707, 5826.2134611827705, 5870.677112758796, 5877.000909845294, 5905.084711869667, 5927.051671732545, 5931.881362372728, 5936.614120082181, 5940.376260492672, 5942.940570594325, 5944.97027514864, 5367.1680889462195, 5482.150266708666, 5500.147477141009, 5537.0, 5537.280153580049, 6116.449519543263, 6120.326764055951, 6120.387961203844, 6124.448799608057, 6132.264128304543, 5850.2102216200265, 5850.765969361178, 5860.179574859523, 5861.120831875252, 5871.031279838864, 5755.107958266475, 5764.106563482667, 5808.506276966421, 5841.299044114733, 5941.881162376728, 5722.914156287659, 5766.077427611562, 5816.621919575214, 5840.036393995028, 5892.587518873637, 5850.0932355484965, 5893.057110862327, 5898.380670029585, 5903.852403689943, 5957.136215248851, 4382.101669157815, 4459.777011149429, 4468.530804265571, 4485.349624304457, 4509.391232183679, 3949.289127956938, 3974.3839704845795, 3986.8039588124025, 4005.078831868628, 4036.29364861154, 5654.208410822488, 5673.74468148926, 5684.37471878093, 5696.715164241854, 5711.114777209539, 5731.598788084794, 5940.287165540163, 5944.524438044946, 5953.583249172516, 5963.373845746221, 5582.190582365615, 5635.830925072254, 5643.068893632505, 5713.285839270091, 5739.426057394311, 5116.155834287375, 5116.411612664591, 5118.027574760784, 5118.59051275897, 5123.769430375642, 5891.145783861316, 6136.815895523141, 6138.846528836725, 6141.508679305645, 6144.815655530341, 5752.482276595127, 5813.709314534341, 5822.301323841166, 5871.0, 5920.674362909909, 5983.252093488314, 6001.189839371629, 6038.698065096815, 6053.636781793107, 6066.3023752269055, 5706.28671416073, 5716.142578613241, 5753.769849205984, 5769.1634712967225, 5774.451427114417, 5912.083627037816, 5992.280926288873, 6007.098935159761, 6014.127951447103, 6076.270847498328, 5864.560157988082, 5866.0027795275755, 5872.55955803325, 5878.030125029322, 5902.6458412495385, 5680.488756011979, 5854.092615644582, 5854.151148083419, 5870.354261031198, 5878.941210587839, 5635.464630860061, 5714.0, 5725.456081672234, 5726.9713651431475, 5753.280839895013, 5814.796482123105, 5815.302163740378, 5837.416258374129, 5837.445442682938, 5859.794907178229, 5460.208269800777, 5598.0, 5727.369989301257, 5730.341010783646, 5732.684702341411], "value": 5439.577505539222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.1322815892293, 598.267122774601, 599.5653151465189, 608.8812681527104, 634.4448607468465, 41149.794251028776, 42163.36754948674, 42345.78827105868, 42376.788116059455, 42447.87760611965, 42515.14969700612, 42984.35523467151, 43039.139217215714, 43179.0, 43227.13545729091, 8838.955805220981, 8989.505577193251, 8993.505357205384, 8994.055624159468, 9002.189802917736, 8915.420497667663, 8981.281497480186, 9017.23353514952, 9018.774530636754, 9026.729198124067, 9054.456732596065, 9073.682421115262, 9103.95448022759, 9113.68102116426, 9121.270298376146, 8965.820683586324, 8985.505797181153, 9047.276217902552, 9049.140331668481, 9085.364024518287, 8897.688580899638, 8917.687880924137, 8951.641934322619, 8962.86555701665, 8966.417182883091, 40539.59460405388, 41152.794236028625, 41306.0, 41514.1697166058, 41539.1692166155, 3687.8340474678434, 3846.307664620367, 3921.156951255494, 4262.339337402707, 4423.668224883115, 32773.67226327754, 33289.33421331584, 33652.32695346104, 33660.83169584136, 34151.829240853636, 4429.512753597101, 4501.130511867879, 4588.38056862326, 4673.696209746356, 4689.671722979393, 357544.0, 358042.0, 359148.0, 361909.0, 362288.9999999974, 56688.0, 56992.14511782327, 57006.7149664249, 57018.7149064252, 57330.426695733346, 34832.82583587066, 35339.64660353415, 35450.29099418049, 37810.81094594509, 37924.24151516954, 17523.56191095233, 17540.386086486913, 17716.291348346178, 17719.29122835072, 17790.199441025186, 13486.460541578423, 13623.250721210428, 13663.248521331423, 13678.794818077737, 13689.452421903012, 19331.121314476353, 19424.849172867274, 19495.15954112635, 19538.802881858806, 19569.104148146704, 7066.328698773608, 7077.0, 7407.629618519052, 7465.096400417859, 7506.099268087755, 1015.7562185075528, 1134.9375784331776, 1143.1712008793559, 1160.7040204747734, 1186.0926391310481, 32306.676933230367, 32502.187445314055, 32530.349393012482, 32687.67312326846, 33169.0, 1129.8192289233684, 1140.4981808004452, 1168.4041139019162, 1188.2592002716062, 1209.1052621060353, 16709.33162673504, 17912.839243240058, 19350.612987740165, 19386.515337116685, 19689.0, 636891.0, 642275.0, 644309.0, 644362.0, 645344.0, 59036.0, 59246.70376648089, 59709.402905971256, 59763.0, 59779.10331345033, 1346.8047133165637, 1370.0, 1378.3797291218991, 1390.819193504858, 1402.7404930087762, 32077.0, 35002.0, 35039.299214016086, 35097.64902351046, 35217.47173792344, 53369.733151335546, 54118.729406352715, 54158.45841541692, 54173.18740218821, 54210.186847196535, 552814.0, 556754.0, 557892.0, 561704.0, 562603.0, 42978.35532466953, 43688.56311436845, 44011.33982990194, 44971.0, 45056.97243623979, 47313.0, 48634.513654864415, 48683.0, 48927.75536122297, 49089.509104908495, 40288.3956740655, 40977.0, 41546.0, 41756.373654395815, 43330.0, 41598.37602435905, 41735.37396939109, 42369.36445953375, 42556.574434255264, 43050.35424468573, 41483.792581038106, 41831.117599707475, 42478.362824557036, 42605.78697106495, 42693.57306426896, 34507.48238776471, 36307.45538816867, 36797.63202367942, 37370.813145935186, 37454.0, 28522.42955140927, 29083.854580726962, 29313.0, 29440.41119177647, 29446.11661650154], "value": 20852.746854904402, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.3824718697269, 389.8830350894731, 398.551629416906, 406.65231227300654, 415.660170529793, 13707.520236791714, 17259.48221553354, 18609.255629774794, 18643.906780466114, 18782.0, 18785.906070469664, 18941.0, 19196.23215071399, 19448.708269375937, 19567.21731130753, 5644.633098848582, 5822.912656310159, 5852.67810270435, 5872.177895094669, 5886.852828679296, 5434.755436005389, 5789.739461724233, 5821.912671309934, 5828.213191219194, 5831.154482600021, 5652.09566469365, 5720.427957204288, 5759.942400576004, 5765.625234359774, 5790.478856902878, 5649.067903795869, 5714.828555143332, 5749.252597162342, 5751.7699292028265, 5776.537876969854, 5382.91925621116, 5559.972200138974, 5581.330240371181, 5606.943930560683, 5629.662220266797, 13618.114822536503, 13703.520376786864, 13969.860301397059, 13992.510262140879, 13995.0, 2150.709654196695, 2459.8770061497034, 2523.6719226500622, 2557.9360515987064, 2793.427347393779, 10058.04448577384, 10156.746081347952, 10765.192610554239, 12684.30236337001, 13685.384157712926, 1868.9720653640443, 1929.9903500482408, 1957.2562426278066, 1970.7339509166222, 2009.8693584916934, 80098.0, 83376.58311708464, 84076.0, 84688.0, 84747.15252847454, 9238.491882946502, 9715.29982253117, 9807.362521436084, 10694.358338499715, 11023.228374013903, 15378.38486460529, 15409.229538523254, 15423.305951232222, 15436.922815386075, 15441.459548915735, 7963.124056353797, 8153.551554664432, 8295.129011453831, 8368.790780230422, 8452.661893524313, 7821.100573434014, 7847.764567062997, 7850.293473587404, 8077.7576672699915, 8149.470284431493, 4562.885927851764, 4648.2562789953445, 4713.128071306819, 4765.4996225395835, 4887.975560122177, 1930.4015755115959, 2041.3365656161807, 2043.2031507712004, 2056.475598722316, 2109.8101170894693, 904.6924045824451, 958.6500927161675, 983.4246965525105, 1009.0918173643779, 1072.286929192094, 19119.0, 19434.125464353856, 19492.0, 19493.707594386095, 19730.802691972898, 1007.6171054999201, 1008.3193844155312, 1027.7584767579613, 1044.7962647283707, 1056.155075939261, 10345.793084138275, 10858.945705271422, 10891.83662245067, 10928.235023548434, 11001.834972475419, 465502.0, 479957.0, 480543.0, 481858.0, 484152.0, 91011.0, 92021.5398923001, 92275.0, 92585.53707231421, 96213.0, 957.9089986451276, 975.6048800235916, 991.3011327014437, 1023.8515415264745, 1029.613894789454, 16624.0, 17406.0, 17922.64154716872, 18081.909590451964, 18392.63214735671, 41270.9682257934, 42153.0, 42167.57832421759, 42309.36535951902, 42320.788396057826, 57824.0, 60329.69835150796, 61004.0, 62007.37992620197, 62242.0, 8034.168531021214, 8054.903918143249, 8209.220124088202, 8261.793455163668, 8328.3337333012, 8917.375783695079, 11847.0, 12611.369431528203, 13104.541341053018, 13136.080474366703, 10705.323669082729, 10780.083692885879, 11037.558497660324, 11717.0, 11889.405529723652, 10420.843687344848, 11020.283681560673, 11516.769664606829, 11519.654410367702, 11985.0, 11084.168687348643, 11470.942645286721, 11681.88318116808, 11968.640940771791, 12134.635960921187, 7786.18245084258, 8300.83398332042, 8370.121137280497, 8560.315174786127, 8633.223009929137, 8448.803311139025, 11495.712607184887, 11511.827322590001, 11577.363245021605, 11788.174827761974], "value": 9062.800789335299, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.3557070459701, 1524.359768897063, 1683.5201967439282, 1770.6104656975476, 1836.109486898854, 7874.803129921755, 8604.311655067595, 8695.478271303718, 9237.4457532548, 9263.25893928485, 8785.736427907157, 9105.863412048824, 9129.178373946343, 9197.95401022994, 9200.493972831491, 2302.9078836846547, 2338.3218866528687, 2353.105819788478, 2358.363241924679, 2361.138184562631, 2254.0983606557425, 2310.191432998454, 2314.6296592545195, 2322.7096612923383, 2325.2210509479346, 2283.6802847601266, 2289.1530133850415, 2292.9656055159185, 2344.495933374324, 2350.4711439925964, 2239.944001399962, 2240.249516412004, 2242.125571027301, 2253.8422310438277, 2254.729432468106, 2153.558520503293, 2161.1787520742173, 2174.717286752728, 2175.7389113306504, 2179.2808373236794, 9104.0, 9111.681091161778, 9219.032001639865, 9228.215601673866, 9270.675526356545, 5305.602079843989, 5381.515663590296, 5431.049566325877, 5433.538149257318, 5443.972780136113, 3161.44674681932, 3162.0988018414873, 3267.232200432896, 3304.619968703582, 3343.4316166251797, 1167.0663469224671, 1172.3259126002497, 1180.144395313391, 1182.154759347071, 1193.0634451955173, 12770.74458510834, 12829.743405131938, 12873.03452241095, 12893.290869002201, 12900.290484023375, 4446.133004064176, 4656.604188643935, 4671.0, 4735.12400205963, 4776.593989510861, 10724.0, 10729.83905241422, 10747.193960452998, 10837.133029357632, 10862.185336099825, 5322.840314790563, 5338.46615338463, 5353.544948679328, 5360.812371566976, 5406.216098665672, 4229.915401691949, 4856.538628830255, 4935.531124543163, 4945.109880221595, 4989.550940415307, 3851.326017946848, 4024.5170579530645, 4044.8786536403954, 4073.816678249486, 4142.420061191436, 1248.6129299917054, 1262.8673989231181, 1267.524678245658, 1270.5489551209373, 1279.9552015679405, 831.0775039705921, 930.4882314726873, 988.5502096546054, 996.5415908682088, 1004.492731170754, 5999.850003749942, 6093.421124993119, 6303.905441418382, 6349.650769207646, 6532.608043517404, 999.5551979369111, 1018.332991890313, 1021.7649940513724, 1024.9795004099876, 1027.4708525109575, 4105.2405305018665, 4152.190322887008, 4217.768022758778, 4222.472190976128, 4233.386159006988, 11229.21395502307, 11437.313761174357, 11557.30656160633, 11561.07511399086, 11563.826542601868, 11191.496382662799, 11364.261323013978, 11562.190646654653, 11581.652550423501, 11606.0, 8422.620982055822, 8584.527850968132, 8599.26906212979, 8616.39685222041, 8637.438566493283, 6043.54673399488, 6078.192117298103, 6093.542984276109, 6170.598911070766, 6211.9068213977735, 10481.580736770444, 10555.63055293061, 10573.62992295266, 10606.681799546026, 10658.786824263316, 10590.735231619272, 10642.148628109888, 10644.680659579915, 10867.29362591429, 10900.945495272474, 7297.306755858185, 7771.883421748791, 7818.921810782047, 8043.919560804317, 8151.79620509492, 8696.695615653422, 8824.86762698573, 9043.366964312436, 9109.453432794053, 9133.77165570866, 7968.920310796818, 8005.1594582568, 8133.471324363897, 8152.673893044449, 8412.242898139197, 7894.36845052383, 7902.920970790218, 7933.761987140395, 8029.7189598363775, 8307.0, 7030.894536581853, 7041.295870412918, 7105.680244389118, 7116.110486189226, 7128.501004929605, 5382.085045542189, 5452.100403433389, 5471.151971444432, 5514.5864060194845, 5649.915251271316, 4832.710037397767, 4888.535589119028, 4907.4847141051105, 4921.778519966681, 4998.950010499849], "value": 4746.340980089705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.9050380137859, 335.4130272023958, 337.20251604954285, 339.7536785830272, 345.06831554802034, 5420.512153906147, 5507.917381239285, 5606.439356064391, 5687.658740475569, 5727.885442291152, 5491.780328786854, 5554.694491802949, 5562.0, 5564.137558678412, 5585.776568937237, 4326.480822301321, 4368.781560921957, 4386.320120381346, 4422.226110430678, 4424.336349547561, 4100.118474527976, 4318.963448772303, 4343.739375637456, 4413.867583972486, 4435.223835828733, 4300.35494675798, 4322.1787860306595, 4323.286657701476, 4323.286657701476, 4334.306510958231, 4304.139172165564, 4325.978370108145, 4336.327869180251, 4349.478062632505, 4365.476142862877, 3716.925661486755, 3753.474513568103, 3778.149916268845, 3825.9617403825882, 3831.4444405561317, 5408.891822163535, 5414.43148469409, 5425.321834770653, 5429.185622156708, 5484.972575137099, 4951.108800415924, 4952.653314268003, 4959.305697202359, 4971.304017437562, 4974.353334066548, 5583.3300003999375, 5865.85335366615, 5872.799324368187, 5880.441358070976, 5919.5264378850015, 4185.623293903533, 4191.94594648623, 4193.287141186006, 4196.0, 4196.9370459443135, 6142.6007309525185, 6167.87664246717, 6185.938140618581, 6188.659623720693, 6214.161088253073, 4127.545969943304, 4205.852795152155, 4232.153569286155, 4257.148570285955, 4291.120320334324, 4287.563666171805, 4306.332518459643, 4310.0, 4322.092360604308, 4328.826846926151, 4962.9007419851405, 4967.5777558907175, 4979.825706100341, 4980.701157930535, 4992.625553083533, 5771.163181338682, 5935.436133567304, 5947.256592925884, 5974.641521508636, 5991.9700401498585, 1483.807105076333, 1503.1356969742442, 1508.1177511155877, 1509.5244997825869, 1511.1084460168554, 891.8706787515774, 934.5794392523392, 945.9668911588061, 961.8990006049404, 965.9855102173473, 5054.685781696712, 5063.518965698253, 5080.466551012164, 5082.313887625197, 5140.177571588602, 5317.282166907496, 5331.680099193984, 5344.5724342052545, 5349.277847490617, 5380.784768609212, 5021.723805190676, 5062.582476906472, 5105.693658380509, 5432.266644003088, 5857.912131318034, 3242.610886693612, 3245.626752923444, 3248.805071695706, 3253.186703324169, 3259.2340799912004, 6011.849703757353, 6324.9051264231075, 6335.208098987627, 6336.6831658416895, 6359.491240700733, 6111.84720381994, 6114.174586430775, 6123.9387606124255, 6136.938630613726, 6173.81478555644, 6194.566380353332, 6195.411435913581, 6198.349173336825, 6211.3167551569295, 6230.532710046765, 5819.682780814895, 6031.788887388921, 6042.516598672008, 6051.576389652682, 6082.756689732361, 5923.170756094237, 5936.732847021808, 5950.583459157991, 5955.374685657944, 5985.850353741192, 5989.940100599112, 6158.3225845157895, 6278.4303924019, 6314.589551679126, 6362.681865906594, 4154.982029402816, 4259.679499355209, 4296.76367799774, 4303.160883627663, 4319.222539942841, 6030.4572588467245, 6093.587590949082, 6101.359357267601, 6127.632342059491, 6138.07928810682, 5769.68266745333, 5835.56233282497, 5967.522598192048, 5967.850803729942, 5978.192943952511, 5848.152017957287, 5861.912071319019, 5864.413558644102, 5900.262467191601, 5944.405559444021, 5763.625364351304, 5793.188953546422, 5796.7392716841305, 5851.736671849691, 5883.970580147072, 5587.944120558743, 5917.497012753965, 5928.555358348055, 5944.72188479479, 5958.582899197015, 5849.590528663123, 5876.676782776988, 5888.852778680567, 5929.140274660236, 5941.881162376641], "value": 4435.932626396223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.1765669561917, 841.7180244618053, 850.8043150075479, 853.4111463090463, 862.831747809177, 15075.095494270337, 16060.196990150474, 16121.516354509355, 16126.03243805371, 16249.431269905555, 15119.622009449742, 15288.847111528881, 15334.233288335567, 15355.846441535608, 15360.462383816568, 661.8808614449397, 787.4330482052912, 806.4596720197452, 813.9755807325789, 824.5011767880436, 808.8827120067587, 814.4339683919678, 819.4837252530926, 823.370121856777, 823.8393513264916, 885.6147575804546, 885.7785553611598, 886.6674996876171, 888.2094935507404, 888.8888888888889, 862.7239283429305, 866.1252135343317, 869.3132425383939, 871.6861929705301, 874.1739056591524, 637.7704026550438, 638.7891995641473, 641.1760887259849, 642.6529673976094, 642.8971364581691, 15238.0, 15260.618484537865, 15371.769423458658, 15381.538553843402, 15389.846101539066, 15221.695566088616, 15257.771133433007, 15383.923080384526, 15470.690586188326, 15486.612834679223, 11673.94163029188, 11788.528458861636, 11790.941045294805, 11888.524459021628, 12541.247525148521, 3910.980445097785, 4003.799810009488, 4072.694547908889, 4077.8572749953905, 4142.772147531885, 70001.0, 70902.64548677276, 72440.0, 72658.0, 72685.63657181786, 11989.880101198876, 12093.87906120945, 12247.0, 12324.0, 12759.744805103846, 22900.083996639947, 22953.885230574073, 22966.42583935415, 23024.65463018056, 23238.0, 8407.537585432738, 8440.493570385797, 8702.6518939243, 8785.648574056966, 8880.555972201364, 4612.285095810154, 4656.557627025427, 4675.854415668183, 4735.928961065587, 4807.663463557587, 3308.4871844864083, 3420.110771199506, 3423.4522476404154, 3424.760266781301, 3720.497732806091, 635.5995722694673, 642.6786606696652, 643.5816719132602, 657.3065415986181, 672.5930811858755, 610.6916007416315, 671.9160104986877, 688.6763221285987, 732.2274999875183, 750.6926032947121, 10099.44453055089, 10158.187345012382, 10206.642767503101, 10215.284930054824, 10384.169266458664, 687.1547995964892, 702.4380495603497, 715.8047102329348, 742.688071010177, 754.5171090502083, 4597.666721579076, 4888.340074089952, 4915.523782460619, 5022.321986531844, 5312.1766126250495, 98898.0, 117219.41390293164, 118360.40819795846, 130020.3498982499, 132976.0, 84949.0, 85759.0, 85853.0, 98738.50630746801, 103179.0, 95137.524312378, 95614.0, 96465.0, 98648.50675746576, 99233.0, 10122.291439599157, 10248.28262021681, 10468.214883883587, 10540.15678745714, 10550.314229575053, 53060.93878122493, 53631.46368536264, 54956.17565736437, 57988.420115798304, 58380.0, 80711.17510887091, 86257.05904655649, 89000.10999889918, 90183.0, 91386.62920056071, 12987.595500690442, 13308.0, 13757.724845503235, 13895.652608684864, 14557.781633275297, 15006.77489837675, 15643.0, 16373.508794736177, 16456.259468323715, 16616.63059414902, 14025.719485610434, 14190.148591084568, 15119.0, 15241.161736104621, 15387.923060384626, 14603.853961460249, 14705.41178352854, 15020.549383518512, 15229.238538073274, 15260.389584416944, 11680.415979201176, 13538.93230533841, 13543.390547425193, 14092.929535352258, 14651.780223296872, 11978.984936546114, 12116.21244619097, 12239.20445171061, 12288.877111228774, 12300.876991229974, 10203.591856325664, 11160.134701572373, 12251.264924104582, 12664.936675316565, 12834.0], "value": 7862.514866272296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 108.0, 111.0, 113.0, 115.0, 113.0, 112.0, 112.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 145.0, 180.0, 119.0, 146.0, 149.0, 118.0, 177.0, 117.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 161.0, 162.0, 165.0, 177.0, 166.0, 163.0, 168.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 153.0, 79.0, 75.0, 75.0, 75.0, 171.0, 75.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 148.0, 86.0, 84.0, 85.0, 149.0, 85.0, 83.0, 141.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 148.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 52.0, 52.0, 52.0, 57.0, 53.0, 54.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 62.0, 61.0, 61.0, 62.0, 64.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 76.0, 77.0, 77.0, 83.0, 77.0, 78.0, 86.0, 76.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 137.0, 127.0, 126.0, 124.0, 134.0, 122.0, 161.0, 124.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 158.0, 158.0, 164.0, 157.0, 158.0, 154.0, 160.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 64.0, 71.0, 64.0, 65.0, 65.0, 63.0, 63.0], "value": 65.6, "unit": "score"}]}]} 16:28:45 INFO - Return code: 0 16:28:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:45 ERROR - # TBPL SUCCESS # 16:28:45 INFO - Running post-action listener: _resource_record_post_action 16:28:45 INFO - ##### 16:28:45 INFO - ##### Finished run-tests step (success) 16:28:45 INFO - ##### 16:28:45 INFO - Running post-run listener: _resource_record_post_run 16:28:45 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7827456; Write bytes: 437923840; Read time: 1852; Write time: 397344 16:28:45 INFO - TinderboxPrint: CPU usage
13.3% 16:28:45 INFO - TinderboxPrint: I/O read bytes / time
7,827,456 / 1,852 16:28:45 INFO - TinderboxPrint: I/O write bytes / time
437,923,840 / 397,344 16:28:45 INFO - TinderboxPrint: CPU idle
8,248.1 (86.6%) 16:28:45 INFO - TinderboxPrint: CPU user
1,212.4 (12.7%) 16:28:45 INFO - TinderboxPrint: Swap in / out
0 / 0 16:28:45 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 94531584; Read time: 88; Write time: 78064 16:28:45 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5451776; Write bytes: 336515072; Read time: 1316; Write time: 307420 16:28:45 INFO - Running post-run listener: _upload_blobber_files 16:28:45 INFO - Blob upload gear active. 16:28:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:45 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 16:28:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:28:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:52 INFO - (blobuploader) - INFO - Done attempting. 16:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:28:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:53 INFO - (blobuploader) - INFO - Done attempting. 16:28:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:28:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:54 INFO - (blobuploader) - INFO - Done attempting. 16:28:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:54 INFO - Return code: 0 16:28:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8c9ba858a745ae10cbeecea2d3de3733d458d1fd412e24c18ab5273fdf88e42fa889ab4c43a7bfb7481e0717860689d544443b0ca7892bfd31e841a88ec32fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d6acc8895d819fc915781d5f99fe3516bb7f039bcb114a2fcdb5446c93a504a9a4b562adc8df8d7fa6c4e6ee37c72917cd1de88dbd73ee8ae4fc8b207e29296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06ae2a6b5bb324b87ce3c8bba89d2839b57bdac2f7111fe78ea9500d0b3f5d8eb366e43e420ff9aaf01676bec167eea1960276a2bc3b5095bc2a4b482a69288c"} 16:28:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:54 INFO - Contents: 16:28:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8c9ba858a745ae10cbeecea2d3de3733d458d1fd412e24c18ab5273fdf88e42fa889ab4c43a7bfb7481e0717860689d544443b0ca7892bfd31e841a88ec32fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d6acc8895d819fc915781d5f99fe3516bb7f039bcb114a2fcdb5446c93a504a9a4b562adc8df8d7fa6c4e6ee37c72917cd1de88dbd73ee8ae4fc8b207e29296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06ae2a6b5bb324b87ce3c8bba89d2839b57bdac2f7111fe78ea9500d0b3f5d8eb366e43e420ff9aaf01676bec167eea1960276a2bc3b5095bc2a4b482a69288c"} 16:28:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:54 INFO - Copying logs to upload dir... 16:28:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.138208 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-19 16:28:54.127391) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:54.130706) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8c9ba858a745ae10cbeecea2d3de3733d458d1fd412e24c18ab5273fdf88e42fa889ab4c43a7bfb7481e0717860689d544443b0ca7892bfd31e841a88ec32fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d6acc8895d819fc915781d5f99fe3516bb7f039bcb114a2fcdb5446c93a504a9a4b562adc8df8d7fa6c4e6ee37c72917cd1de88dbd73ee8ae4fc8b207e29296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06ae2a6b5bb324b87ce3c8bba89d2839b57bdac2f7111fe78ea9500d0b3f5d8eb366e43e420ff9aaf01676bec167eea1960276a2bc3b5095bc2a4b482a69288c"} build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b8c9ba858a745ae10cbeecea2d3de3733d458d1fd412e24c18ab5273fdf88e42fa889ab4c43a7bfb7481e0717860689d544443b0ca7892bfd31e841a88ec32fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d6acc8895d819fc915781d5f99fe3516bb7f039bcb114a2fcdb5446c93a504a9a4b562adc8df8d7fa6c4e6ee37c72917cd1de88dbd73ee8ae4fc8b207e29296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06ae2a6b5bb324b87ce3c8bba89d2839b57bdac2f7111fe78ea9500d0b3f5d8eb366e43e420ff9aaf01676bec167eea1960276a2bc3b5095bc2a4b482a69288c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:54.155464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:54.155747) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468967979.179865-467632709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004391 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:54.239315) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:28:54.239668) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:28:54.240011) ========= ========= Total master_lag: 0.17 =========