builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1468338918.08 results: success (0) buildid: 20160712073205 builduid: 46c0d356ff7a4e3ebe735f0fc3f4a391 revision: 94c926911767cbaf285badaccc65b0365ae5bae0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.081745) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.082385) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.082662) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.108605) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.108940) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 08:55:18-- 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% 46.6M=0s 2016-07-12 08:55:18 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113274 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.236862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.237211) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018076 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.287097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.287466) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 94c926911767cbaf285badaccc65b0365ae5bae0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 94c926911767cbaf285badaccc65b0365ae5bae0 --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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 08:55:18,477 truncating revision to first 12 chars 2016-07-12 08:55:18,477 Setting DEBUG logging. 2016-07-12 08:55:18,477 attempt 1/10 2016-07-12 08:55:18,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/94c926911767?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 08:55:18,824 unpacking tar archive at: mozilla-central-94c926911767/testing/mozharness/ program finished with exit code 0 elapsedTime=0.668433 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.973130) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.973697) ========= script_repo_revision: 94c926911767cbaf285badaccc65b0365ae5bae0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.974223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.974590) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 08:55:18.985645) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-12 08:55:18.985924) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:55:19 INFO - MultiFileLogger online at 20160712 08:55:19 in /builds/slave/test 08:55:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:55:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:55:19 INFO - {'append_to_log': False, 08:55:19 INFO - 'base_work_dir': '/builds/slave/test', 08:55:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:55:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:55:19 INFO - 'branch': 'Firefox-Non-PGO', 08:55:19 INFO - 'buildbot_json_path': 'buildprops.json', 08:55:19 INFO - 'config_files': ('talos/linux_config.py',), 08:55:19 INFO - 'default_actions': ('clobber', 08:55:19 INFO - 'read-buildbot-config', 08:55:19 INFO - 'download-and-extract', 08:55:19 INFO - 'populate-webroot', 08:55:19 INFO - 'create-virtualenv', 08:55:19 INFO - 'install', 08:55:19 INFO - 'run-tests'), 08:55:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:55:19 INFO - 'download_minidump_stackwalk': True, 08:55:19 INFO - 'download_symbols': 'ondemand', 08:55:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:55:19 INFO - 'tooltool.py': '/tools/tooltool.py', 08:55:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:55:19 INFO - '/tools/misc-python/virtualenv.py')}, 08:55:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:55:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:55:19 INFO - 'installer_path': 'installer.exe', 08:55:19 INFO - 'log_level': 'info', 08:55:19 INFO - 'log_name': 'talos', 08:55:19 INFO - 'log_to_console': True, 08:55:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:55:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:55:19 INFO - 'opt_config_files': (), 08:55:19 INFO - 'pip_index': False, 08:55:19 INFO - 'sps_profile': False, 08:55:19 INFO - 'sps_profile_interval': 0, 08:55:19 INFO - 'suite': 'dromaeojs', 08:55:19 INFO - 'system_bits': '32', 08:55:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:55:19 INFO - 'title': 'talos-linux64-ix-011', 08:55:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:55:19 INFO - 'use_talos_json': True, 08:55:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:55:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:55:19 INFO - 'work_dir': 'build'} 08:55:19 INFO - ##### 08:55:19 INFO - ##### Running clobber step. 08:55:19 INFO - ##### 08:55:19 INFO - Running pre-action listener: _resource_record_pre_action 08:55:19 INFO - Running main action method: clobber 08:55:19 INFO - rmtree: /builds/slave/test/build 08:55:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:55:19 INFO - Running post-action listener: _resource_record_post_action 08:55:19 INFO - ##### 08:55:19 INFO - ##### Finished clobber step (success) 08:55:19 INFO - ##### 08:55:19 INFO - ##### 08:55:19 INFO - ##### Running read-buildbot-config step. 08:55:19 INFO - ##### 08:55:19 INFO - Running pre-action listener: _resource_record_pre_action 08:55:19 INFO - Running main action method: read_buildbot_config 08:55:19 INFO - Using buildbot properties: 08:55:19 INFO - { 08:55:19 INFO - "project": "", 08:55:19 INFO - "product": "firefox", 08:55:19 INFO - "script_repo_revision": "production", 08:55:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:55:19 INFO - "repository": "", 08:55:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:55:19 INFO - "buildid": "20160712073205", 08:55:19 INFO - "pgo_build": "False", 08:55:19 INFO - "basedir": "/builds/slave/test", 08:55:19 INFO - "buildnumber": 555, 08:55:19 INFO - "slavename": "talos-linux64-ix-011", 08:55:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:55:19 INFO - "platform": "ubuntu64_hw", 08:55:19 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:55:19 INFO - "branch": "mozilla-central", 08:55:19 INFO - "repo_path": "mozilla-central", 08:55:19 INFO - "revision": "94c926911767cbaf285badaccc65b0365ae5bae0", 08:55:19 INFO - "stage_platform": "linux64", 08:55:19 INFO - "builduid": "46c0d356ff7a4e3ebe735f0fc3f4a391", 08:55:19 INFO - "slavebuilddir": "test" 08:55:19 INFO - } 08:55:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:55:19 INFO - Running post-action listener: _resource_record_post_action 08:55:19 INFO - ##### 08:55:19 INFO - ##### Finished read-buildbot-config step (success) 08:55:19 INFO - ##### 08:55:19 INFO - ##### 08:55:19 INFO - ##### Running download-and-extract step. 08:55:19 INFO - ##### 08:55:19 INFO - Running pre-action listener: _resource_record_pre_action 08:55:19 INFO - Running main action method: download_and_extract 08:55:19 INFO - mkdir: /builds/slave/test/build/tests 08:55: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')]} 08:55:19 INFO - https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:55:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:55:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:55:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:20 INFO - Downloaded 1361 bytes. 08:55:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:55:20 INFO - Using the following test package requirements: 08:55:20 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:55:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:55:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:55:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'jsshell-linux-x86_64.zip'], 08:55:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:55:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:55:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:55:20 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:55:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:55:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:55:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:55:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:20 INFO - https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:55:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:55:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:55:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:22 INFO - Downloaded 22700490 bytes. 08:55:22 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'] 08:55:22 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 08:55:23 INFO - Return code: 0 08:55:23 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 08:55: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')]} 08:55:23 INFO - https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:55:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:55:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:55:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:25 INFO - Downloaded 22700490 bytes. 08:55:25 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'] 08:55:25 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 08:55:26 INFO - Return code: 0 08:55:26 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')]} 08:55:26 INFO - https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:55:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:55:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:55:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:27 INFO - Downloaded 14403317 bytes. 08:55:27 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'] 08:55:27 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 08:55:27 INFO - Return code: 0 08:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:27 INFO - https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BprjVChcQQSZ02p7sONkPg/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 08:55:30 INFO - Downloaded 56661997 bytes. 08:55:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:55:30 INFO - mkdir: /builds/slave/test/properties 08:55:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:55:30 INFO - Writing to file /builds/slave/test/properties/build_url 08:55:30 INFO - Contents: 08:55:30 INFO - build_url:https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:55:30 INFO - Running post-action listener: _resource_record_post_action 08:55:30 INFO - Running post-action listener: set_extra_try_arguments 08:55:30 INFO - ##### 08:55:30 INFO - ##### Finished download-and-extract step (success) 08:55:30 INFO - ##### 08:55:30 INFO - ##### 08:55:30 INFO - ##### Running populate-webroot step. 08:55:30 INFO - ##### 08:55:30 INFO - Running pre-action listener: _resource_record_pre_action 08:55:30 INFO - Running main action method: populate_webroot 08:55:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:55:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:55:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:55:30 INFO - '--tppagecycles', 08:55:30 INFO - '7'], 08:55:30 INFO - 'tests': ['tsvgm']}}, 08:55:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:55:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:55:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:55:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:55:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['damp', 'tps']}, 08:55:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'tests': ['damp', 'tps']}, 08:55:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['dromaeo_dom']}, 08:55:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:55:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['basic_compositor_video']}, 08:55:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:55:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'other-e10s': {'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['a11yr', 08:55:30 INFO - 'ts_paint', 08:55:30 INFO - 'tpaint', 08:55:30 INFO - 'sessionrestore', 08:55:30 INFO - 'sessionrestore_no_auto_restore', 08:55:30 INFO - 'tabpaint']}, 08:55:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['tsvgx', 08:55:30 INFO - 'tsvgr_opacity', 08:55:30 INFO - 'tart', 08:55:30 INFO - 'tscrollx', 08:55:30 INFO - 'cart']}, 08:55:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:55:30 INFO - 'tsvgr_opacity', 08:55:30 INFO - 'tart', 08:55:30 INFO - 'tscrollx', 08:55:30 INFO - 'cart']}, 08:55:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'talos_options': ['--disable-e10s'], 08:55:30 INFO - 'tests': ['tp5o']}, 08:55:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'tests': ['tp5o']}, 08:55:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'talos_options': ['--disable-e10s', 08:55:30 INFO - '--xperf_path', 08:55:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:30 INFO - 'tests': ['tp5n']}, 08:55:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:30 INFO - 'talos_options': ['--xperf_path', 08:55:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:30 INFO - 'tests': ['tp5n']}}, 08:55:30 INFO - 'talos.zip': {'path': '', 08:55:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:55:30 INFO - Running post-action listener: _resource_record_post_action 08:55:30 INFO - ##### 08:55:30 INFO - ##### Finished populate-webroot step (success) 08:55:30 INFO - ##### 08:55:30 INFO - ##### 08:55:30 INFO - ##### Running create-virtualenv step. 08:55:30 INFO - ##### 08:55:30 INFO - Running pre-action listener: _resource_record_pre_action 08:55:30 INFO - Running main action method: create_virtualenv 08:55:30 INFO - Creating virtualenv /builds/slave/test/build/venv 08:55:30 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 08:55:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:55:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:55:31 INFO - Using real prefix '/usr' 08:55:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:55:31 INFO - Installing distribute.............................................................................................................................................................................................done. 08:55:33 INFO - Installing pip.................done. 08:55:33 INFO - Return code: 0 08:55:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:55:33 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')]} 08:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:33 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')]} 08:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:33 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:33 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 08:55:33 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 08:55:33 INFO - Using env: {'DISPLAY': ':0', 08:55:33 INFO - 'HOME': '/home/cltbld', 08:55:33 INFO - 'LANG': 'en_US.UTF-8', 08:55:33 INFO - 'LANGUAGE': 'en_US:en', 08:55:33 INFO - 'LOGNAME': 'cltbld', 08:55:33 INFO - 'MAIL': '/var/mail/cltbld', 08:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:33 INFO - 'MOZ_NO_REMOTE': '1', 08:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:33 INFO - 'NO_EM_RESTART': '1', 08:55:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:33 INFO - 'PWD': '/builds/slave/test', 08:55:33 INFO - 'SHELL': '/bin/bash', 08:55:33 INFO - 'SHLVL': '1', 08:55:33 INFO - 'TERM': 'linux', 08:55:33 INFO - 'TMOUT': '86400', 08:55:33 INFO - 'USER': 'cltbld', 08:55:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:33 INFO - '_': '/tools/buildbot/bin/python'} 08:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:33 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 08:55:33 INFO - Cleaning up... 08:55:33 INFO - Return code: 0 08:55:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:55:33 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')]} 08:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:33 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')]} 08:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:33 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:33 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 08:55:33 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 08:55:33 INFO - Using env: {'DISPLAY': ':0', 08:55:33 INFO - 'HOME': '/home/cltbld', 08:55:33 INFO - 'LANG': 'en_US.UTF-8', 08:55:33 INFO - 'LANGUAGE': 'en_US:en', 08:55:33 INFO - 'LOGNAME': 'cltbld', 08:55:33 INFO - 'MAIL': '/var/mail/cltbld', 08:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:33 INFO - 'MOZ_NO_REMOTE': '1', 08:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:33 INFO - 'NO_EM_RESTART': '1', 08:55:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:33 INFO - 'PWD': '/builds/slave/test', 08:55:33 INFO - 'SHELL': '/bin/bash', 08:55:33 INFO - 'SHLVL': '1', 08:55:33 INFO - 'TERM': 'linux', 08:55:33 INFO - 'TMOUT': '86400', 08:55:33 INFO - 'USER': 'cltbld', 08:55:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:33 INFO - '_': '/tools/buildbot/bin/python'} 08:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:33 INFO - Downloading/unpacking psutil>=3.1.1 08:55:33 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 08:55:33 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 08:55:33 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 08:55:33 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 08:55:33 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 08:55:33 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 08:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:55:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:35 INFO - Installing collected packages: psutil 08:55:35 INFO - Running setup.py install for psutil 08:55:36 INFO - building 'psutil._psutil_linux' extension 08:55:36 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 08:55:36 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 08:55:36 INFO - building 'psutil._psutil_posix' extension 08:55:36 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 08:55:36 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 08:55:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:36 INFO - Successfully installed psutil 08:55:36 INFO - Cleaning up... 08:55:36 INFO - Return code: 0 08:55:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:55:36 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')]} 08:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:36 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')]} 08:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:36 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:36 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 08:55:36 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 08:55:36 INFO - Using env: {'DISPLAY': ':0', 08:55:36 INFO - 'HOME': '/home/cltbld', 08:55:36 INFO - 'LANG': 'en_US.UTF-8', 08:55:36 INFO - 'LANGUAGE': 'en_US:en', 08:55:36 INFO - 'LOGNAME': 'cltbld', 08:55:36 INFO - 'MAIL': '/var/mail/cltbld', 08:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:36 INFO - 'MOZ_NO_REMOTE': '1', 08:55:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:36 INFO - 'NO_EM_RESTART': '1', 08:55:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:36 INFO - 'PWD': '/builds/slave/test', 08:55:36 INFO - 'SHELL': '/bin/bash', 08:55:36 INFO - 'SHLVL': '1', 08:55:36 INFO - 'TERM': 'linux', 08:55:36 INFO - 'TMOUT': '86400', 08:55:36 INFO - 'USER': 'cltbld', 08:55:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:36 INFO - '_': '/tools/buildbot/bin/python'} 08:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:55:38 INFO - Installing collected packages: mozsystemmonitor 08:55:38 INFO - Running setup.py install for mozsystemmonitor 08:55:38 INFO - Successfully installed mozsystemmonitor 08:55:38 INFO - Cleaning up... 08:55:38 INFO - Return code: 0 08:55:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:55:38 INFO - Using env: {'DISPLAY': ':0', 08:55:38 INFO - 'HOME': '/home/cltbld', 08:55:38 INFO - 'LANG': 'en_US.UTF-8', 08:55:38 INFO - 'LANGUAGE': 'en_US:en', 08:55:38 INFO - 'LOGNAME': 'cltbld', 08:55:38 INFO - 'MAIL': '/var/mail/cltbld', 08:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:38 INFO - 'MOZ_NO_REMOTE': '1', 08:55:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:38 INFO - 'NO_EM_RESTART': '1', 08:55:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:38 INFO - 'PWD': '/builds/slave/test', 08:55:38 INFO - 'SHELL': '/bin/bash', 08:55:38 INFO - 'SHLVL': '1', 08:55:38 INFO - 'TERM': 'linux', 08:55:38 INFO - 'TMOUT': '86400', 08:55:38 INFO - 'USER': 'cltbld', 08:55:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:38 INFO - '_': '/tools/buildbot/bin/python'} 08:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:39 INFO - Downloading/unpacking jsonschema==2.5.1 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:55:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:41 INFO - Downloading functools32-3.2.3-2.tar.gz 08:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:55:41 INFO - warning: no files found matching '*.txt' 08:55:41 INFO - no previously-included directories found matching 'build' 08:55:41 INFO - no previously-included directories found matching 'dist' 08:55:41 INFO - no previously-included directories found matching '.git*' 08:55:41 INFO - Installing collected packages: jsonschema, functools32 08:55:41 INFO - Running setup.py install for functools32 08:55:41 INFO - warning: no files found matching '*.txt' 08:55:41 INFO - no previously-included directories found matching 'build' 08:55:41 INFO - no previously-included directories found matching 'dist' 08:55:41 INFO - no previously-included directories found matching '.git*' 08:55:41 INFO - Successfully installed jsonschema functools32 08:55:41 INFO - Cleaning up... 08:55:41 INFO - Return code: 0 08:55:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:55:41 INFO - Using env: {'DISPLAY': ':0', 08:55:41 INFO - 'HOME': '/home/cltbld', 08:55:41 INFO - 'LANG': 'en_US.UTF-8', 08:55:41 INFO - 'LANGUAGE': 'en_US:en', 08:55:41 INFO - 'LOGNAME': 'cltbld', 08:55:41 INFO - 'MAIL': '/var/mail/cltbld', 08:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:41 INFO - 'MOZ_NO_REMOTE': '1', 08:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:41 INFO - 'NO_EM_RESTART': '1', 08:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:41 INFO - 'PWD': '/builds/slave/test', 08:55:41 INFO - 'SHELL': '/bin/bash', 08:55:41 INFO - 'SHLVL': '1', 08:55:41 INFO - 'TERM': 'linux', 08:55:41 INFO - 'TMOUT': '86400', 08:55:41 INFO - 'USER': 'cltbld', 08:55:41 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:41 INFO - '_': '/tools/buildbot/bin/python'} 08:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:55:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:55:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:44 INFO - Downloading docopt-0.6.1.tar.gz 08:55:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:55:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:55:44 INFO - Running setup.py install for blobuploader 08:55:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:55:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:55:44 INFO - Running setup.py install for requests 08:55:44 INFO - Running setup.py install for docopt 08:55:44 INFO - Successfully installed blobuploader requests docopt 08:55:44 INFO - Cleaning up... 08:55:44 INFO - Return code: 0 08:55:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:44 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:44 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 08:55:44 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 08:55:44 INFO - Using env: {'DISPLAY': ':0', 08:55:44 INFO - 'HOME': '/home/cltbld', 08:55:44 INFO - 'LANG': 'en_US.UTF-8', 08:55:44 INFO - 'LANGUAGE': 'en_US:en', 08:55:44 INFO - 'LOGNAME': 'cltbld', 08:55:44 INFO - 'MAIL': '/var/mail/cltbld', 08:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:44 INFO - 'MOZ_NO_REMOTE': '1', 08:55:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:44 INFO - 'NO_EM_RESTART': '1', 08:55:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:44 INFO - 'PWD': '/builds/slave/test', 08:55:44 INFO - 'SHELL': '/bin/bash', 08:55:44 INFO - 'SHLVL': '1', 08:55:44 INFO - 'TERM': 'linux', 08:55:44 INFO - 'TMOUT': '86400', 08:55:44 INFO - 'USER': 'cltbld', 08:55:44 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:44 INFO - '_': '/tools/buildbot/bin/python'} 08:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:55:45 INFO - Running setup.py (path:/tmp/pip-YQlE3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:55:45 INFO - Running setup.py (path:/tmp/pip-oSWUxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:55:45 INFO - Running setup.py (path:/tmp/pip-ZONKzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:55:45 INFO - Running setup.py (path:/tmp/pip-MAYhzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:55:45 INFO - Running setup.py (path:/tmp/pip-hGuo_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:55:45 INFO - Running setup.py (path:/tmp/pip-ssc1Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:55:45 INFO - Running setup.py (path:/tmp/pip-eh4ZlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:55:45 INFO - Running setup.py (path:/tmp/pip-bLEI3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:55:45 INFO - Running setup.py (path:/tmp/pip-T0vwuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:55:45 INFO - Running setup.py (path:/tmp/pip-2De9Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:55:45 INFO - Running setup.py (path:/tmp/pip-LV5Twp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:55:45 INFO - Running setup.py (path:/tmp/pip-wgXNU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:55:46 INFO - Running setup.py (path:/tmp/pip-S5RQMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:55:46 INFO - Running setup.py (path:/tmp/pip-6nc12R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:46 INFO - Running setup.py (path:/tmp/pip-0BmDox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:55:46 INFO - Running setup.py (path:/tmp/pip-3G14AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:55:46 INFO - Running setup.py (path:/tmp/pip-L25Lxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:55:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:55:46 INFO - Running setup.py install for manifestparser 08:55:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:55:46 INFO - Running setup.py install for mozcrash 08:55:46 INFO - Running setup.py install for mozdebug 08:55:46 INFO - Running setup.py install for mozdevice 08:55:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:55:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:55:46 INFO - Running setup.py install for mozfile 08:55:46 INFO - Running setup.py install for mozhttpd 08:55:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:55:46 INFO - Running setup.py install for mozinfo 08:55:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for mozInstall 08:55:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for mozleak 08:55:47 INFO - Running setup.py install for mozlog 08:55:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for moznetwork 08:55:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for mozprocess 08:55:47 INFO - Running setup.py install for mozprofile 08:55:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for mozrunner 08:55:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:55:47 INFO - Running setup.py install for mozscreenshot 08:55:47 INFO - Running setup.py install for moztest 08:55:48 INFO - Running setup.py install for mozversion 08:55:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:55:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:55:48 INFO - Cleaning up... 08:55:48 INFO - Return code: 0 08:55:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:48 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')]} 08:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:48 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')]} 08:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:48 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:48 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 08:55:48 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 08:55:48 INFO - Using env: {'DISPLAY': ':0', 08:55:48 INFO - 'HOME': '/home/cltbld', 08:55:48 INFO - 'LANG': 'en_US.UTF-8', 08:55:48 INFO - 'LANGUAGE': 'en_US:en', 08:55:48 INFO - 'LOGNAME': 'cltbld', 08:55:48 INFO - 'MAIL': '/var/mail/cltbld', 08:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:48 INFO - 'MOZ_NO_REMOTE': '1', 08:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:48 INFO - 'NO_EM_RESTART': '1', 08:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:48 INFO - 'PWD': '/builds/slave/test', 08:55:48 INFO - 'SHELL': '/bin/bash', 08:55:48 INFO - 'SHLVL': '1', 08:55:48 INFO - 'TERM': 'linux', 08:55:48 INFO - 'TMOUT': '86400', 08:55:48 INFO - 'USER': 'cltbld', 08:55:48 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:48 INFO - '_': '/tools/buildbot/bin/python'} 08:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:55:48 INFO - Running setup.py (path:/tmp/pip-cyRDcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:55:48 INFO - Running setup.py (path:/tmp/pip-BWUOpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:55:48 INFO - Running setup.py (path:/tmp/pip-bz39CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:55:48 INFO - Running setup.py (path:/tmp/pip-VlTUu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:55:48 INFO - Running setup.py (path:/tmp/pip-bmfgNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:55:48 INFO - Running setup.py (path:/tmp/pip-3n3Gh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:55:48 INFO - Running setup.py (path:/tmp/pip-HxqKyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:55:48 INFO - Running setup.py (path:/tmp/pip-TaMKb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:55:48 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)) 08:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:55:48 INFO - Running setup.py (path:/tmp/pip-4mXNgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:55:49 INFO - Running setup.py (path:/tmp/pip-8CIapd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:55:49 INFO - Running setup.py (path:/tmp/pip-yWFeXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:55:49 INFO - Running setup.py (path:/tmp/pip-Xh_zWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:55:49 INFO - Running setup.py (path:/tmp/pip-alODYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:55:49 INFO - Running setup.py (path:/tmp/pip-lk42Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:49 INFO - Running setup.py (path:/tmp/pip-7wJSfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:55:49 INFO - Running setup.py (path:/tmp/pip-iCRLOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:55:49 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)) 08:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:55:49 INFO - Running setup.py (path:/tmp/pip-PJ6pil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:55:49 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)) 08:55:49 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)) 08:55:49 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)) 08:55:49 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)) 08:55:49 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)) 08:55:49 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)) 08:55:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:51 INFO - Downloading blessings-1.6.tar.gz 08:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:55:51 INFO - Installing collected packages: blessings 08:55:51 INFO - Running setup.py install for blessings 08:55:51 INFO - Successfully installed blessings 08:55:51 INFO - Cleaning up... 08:55:51 INFO - Return code: 0 08:55:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:52 INFO - Reading from file tmpfile_stdout 08:55:52 INFO - Current package versions: 08:55:52 INFO - argparse == 1.2.1 08:55:52 INFO - blessings == 1.6 08:55:52 INFO - blobuploader == 1.2.4 08:55:52 INFO - docopt == 0.6.1 08:55:52 INFO - functools32 == 3.2.3-2 08:55:52 INFO - jsonschema == 2.5.1 08:55:52 INFO - manifestparser == 1.1 08:55:52 INFO - mozInstall == 1.12 08:55:52 INFO - mozcrash == 0.17 08:55:52 INFO - mozdebug == 0.1 08:55:52 INFO - mozdevice == 0.48 08:55:52 INFO - mozfile == 1.2 08:55:52 INFO - mozhttpd == 0.7 08:55:52 INFO - mozinfo == 0.9 08:55:52 INFO - mozleak == 0.1 08:55:52 INFO - mozlog == 3.2 08:55:52 INFO - moznetwork == 0.27 08:55:52 INFO - mozprocess == 0.23 08:55:52 INFO - mozprofile == 0.28 08:55:52 INFO - mozrunner == 6.12 08:55:52 INFO - mozscreenshot == 0.1 08:55:52 INFO - mozsystemmonitor == 0.3 08:55:52 INFO - moztest == 0.7 08:55:52 INFO - mozversion == 1.4 08:55:52 INFO - psutil == 3.1.1 08:55:52 INFO - requests == 1.2.3 08:55:52 INFO - wsgiref == 0.1.2 08:55:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:52 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')]} 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:52 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')]} 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:52 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:52 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 08:55:52 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 08:55:52 INFO - Using env: {'DISPLAY': ':0', 08:55:52 INFO - 'HOME': '/home/cltbld', 08:55:52 INFO - 'LANG': 'en_US.UTF-8', 08:55:52 INFO - 'LANGUAGE': 'en_US:en', 08:55:52 INFO - 'LOGNAME': 'cltbld', 08:55:52 INFO - 'MAIL': '/var/mail/cltbld', 08:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:52 INFO - 'MOZ_NO_REMOTE': '1', 08:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:52 INFO - 'NO_EM_RESTART': '1', 08:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:52 INFO - 'PWD': '/builds/slave/test', 08:55:52 INFO - 'SHELL': '/bin/bash', 08:55:52 INFO - 'SHLVL': '1', 08:55:52 INFO - 'TERM': 'linux', 08:55:52 INFO - 'TMOUT': '86400', 08:55:52 INFO - 'USER': 'cltbld', 08:55:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:52 INFO - '_': '/tools/buildbot/bin/python'} 08:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 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)) 08:55:52 INFO - Cleaning up... 08:55:52 INFO - Return code: 0 08:55:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:55:52 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')]} 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:52 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')]} 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:52 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 0x1064030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1325180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', '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 08:55:52 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 08:55:52 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 08:55:52 INFO - Using env: {'DISPLAY': ':0', 08:55:52 INFO - 'HOME': '/home/cltbld', 08:55:52 INFO - 'LANG': 'en_US.UTF-8', 08:55:52 INFO - 'LANGUAGE': 'en_US:en', 08:55:52 INFO - 'LOGNAME': 'cltbld', 08:55:52 INFO - 'MAIL': '/var/mail/cltbld', 08:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:52 INFO - 'MOZ_NO_REMOTE': '1', 08:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:55:52 INFO - 'NO_EM_RESTART': '1', 08:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:52 INFO - 'PWD': '/builds/slave/test', 08:55:52 INFO - 'SHELL': '/bin/bash', 08:55:52 INFO - 'SHLVL': '1', 08:55:52 INFO - 'TERM': 'linux', 08:55:52 INFO - 'TMOUT': '86400', 08:55:52 INFO - 'USER': 'cltbld', 08:55:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:52 INFO - '_': '/tools/buildbot/bin/python'} 08:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:55:52 INFO - Cleaning up... 08:55:52 INFO - Return code: 0 08:55:52 INFO - Running post-action listener: _resource_record_post_action 08:55:52 INFO - Running post-action listener: _start_resource_monitoring 08:55:52 INFO - Starting resource monitoring. 08:55:52 INFO - ##### 08:55:52 INFO - ##### Finished create-virtualenv step (success) 08:55:52 INFO - ##### 08:55:52 INFO - ##### 08:55:52 INFO - ##### Running install step. 08:55:52 INFO - ##### 08:55:52 INFO - Running pre-action listener: _resource_record_pre_action 08:55:52 INFO - Running main action method: install 08:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:52 INFO - Reading from file tmpfile_stdout 08:55:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:55:52 INFO - Reading from file tmpfile_stdout 08:55:52 INFO - Output received: 08:55:52 INFO - Usage: mozinstall [options] installer 08:55:52 INFO - Options: 08:55:52 INFO - -h, --help show this help message and exit 08:55:52 INFO - -d DEST, --destination=DEST 08:55:52 INFO - Directory to install application into. [default: 08:55:52 INFO - "/builds/slave/test"] 08:55:52 INFO - --app=APP Application being installed. [default: firefox] 08:55:52 INFO - mkdir: /builds/slave/test/build/application 08:55:52 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'] 08:55:52 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 08:56:09 INFO - Reading from file tmpfile_stdout 08:56:09 INFO - Output received: 08:56:09 INFO - /builds/slave/test/build/application/firefox/firefox 08:56:09 INFO - Running post-action listener: _resource_record_post_action 08:56:09 INFO - ##### 08:56:09 INFO - ##### Finished install step (success) 08:56:09 INFO - ##### 08:56:09 INFO - ##### 08:56:09 INFO - ##### Running run-tests step. 08:56:09 INFO - ##### 08:56:09 INFO - Running pre-action listener: _resource_record_pre_action 08:56:09 INFO - Running main action method: run_tests 08:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:56:09 INFO - Python 2.7.3 08:56:09 INFO - Return code: 0 08:56:09 INFO - grabbing minidump binary from tooltool 08:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:56:09 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 0x13233b0>, '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 0x13242b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1324740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:56:09 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 08:56:09 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 08:56:09 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 08:56:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:56:09 INFO - Return code: 0 08:56:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:56:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:56:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:56:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:56:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:56:09 INFO - Using env: {'DISPLAY': ':0', 08:56:09 INFO - 'HOME': '/home/cltbld', 08:56:09 INFO - 'LANG': 'en_US.UTF-8', 08:56:09 INFO - 'LANGUAGE': 'en_US:en', 08:56:09 INFO - 'LOGNAME': 'cltbld', 08:56:09 INFO - 'MAIL': '/var/mail/cltbld', 08:56:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:56:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:09 INFO - 'MOZ_NO_REMOTE': '1', 08:56:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:56:09 INFO - 'NO_EM_RESTART': '1', 08:56:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:09 INFO - 'PWD': '/builds/slave/test', 08:56:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:56:09 INFO - 'SHELL': '/bin/bash', 08:56:09 INFO - 'SHLVL': '1', 08:56:09 INFO - 'TERM': 'linux', 08:56:09 INFO - 'TMOUT': '86400', 08:56:09 INFO - 'USER': 'cltbld', 08:56:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855', 08:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:09 INFO - '_': '/tools/buildbot/bin/python'} 08:56:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:56:09 INFO - mozversion application_buildid: 20160712073205 08:56:09 INFO - mozversion application_changeset: 94c926911767cbaf285badaccc65b0365ae5bae0 08:56:09 INFO - mozversion application_display_name: Nightly 08:56:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:56:09 INFO - mozversion application_name: Firefox 08:56:09 INFO - mozversion application_remotingname: firefox 08:56:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:56:09 INFO - mozversion application_vendor: Mozilla 08:56:09 INFO - mozversion application_version: 50.0a1 08:56:09 INFO - mozversion platform_buildid: 20160712073205 08:56:09 INFO - mozversion platform_changeset: 94c926911767cbaf285badaccc65b0365ae5bae0 08:56:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:56:09 INFO - mozversion platform_version: 50.0a1 08:56:09 INFO - using testdate: 1468338969 08:56:09 INFO - actual date: 1468338969 08:56:09 INFO - starting webserver on 'localhost:46608' 08:56:09 INFO - SUITE-START | Running 2 tests 08:56:09 INFO - TEST-START | dromaeo_css 08:56:09 INFO - operating with platform_type : linux_ 08:56:09 INFO - Initialising browser for dromaeo_css test... 08:56:09 INFO - TEST-INFO | started process 25580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeEqZmY/profile http://localhost:46608/getInfo.html) 08:56:14 INFO - PROCESS | 25580 | __metrics Screen width/height:1600/1200 08:56:14 INFO - PROCESS | 25580 | colorDepth:24 08:56:14 INFO - PROCESS | 25580 | Browser inner width/height: 1024/697 08:56:14 INFO - PROCESS | 25580 | __metrics 08:56:15 INFO - PROCESS | 25580 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:56:15 INFO - PROCESS | 25580 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:56:16 INFO - PROCESS | 25580 | console.error: PushService: 08:56:16 INFO - PROCESS | 25580 | stateChangeProcessEnqueue: Error transitioning state 08:56:16 INFO - PROCESS | 25580 | UnknownError 08:56:16 INFO - TEST-INFO | 25580: exit 0 08:56:16 INFO - Browser initialized. 08:56:16 INFO - Running cycle 1/1 for dromaeo_css test... 08:56:16 INFO - TEST-INFO | started process 25664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeEqZmY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:56:28 INFO - PROCESS | 25664 | RSS: Main: 158871552 08:56:28 INFO - PROCESS | 25664 | 08:57:19 INFO - PROCESS | 25664 | 1468339039892 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] 08:59:33 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46608/tests/dromaeo/cssquery-ext.html) 08:59:34 INFO - PROCESS | 25664 | RSS: Main: 277958656 08:59:34 INFO - PROCESS | 25664 | 09:02:37 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-ext.html (next: http://localhost:46608/tests/dromaeo/cssquery-jquery.html) 09:02:37 INFO - PROCESS | 25664 | RSS: Main: 251932672 09:02:37 INFO - PROCESS | 25664 | 09:05:39 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46608/tests/dromaeo/cssquery-mootools.html) 09:05:40 INFO - PROCESS | 25664 | RSS: Main: 245723136 09:05:40 INFO - PROCESS | 25664 | 09:08:42 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46608/tests/dromaeo/cssquery-prototype.html) 09:08:43 INFO - PROCESS | 25664 | RSS: Main: 242954240 09:08:43 INFO - PROCESS | 25664 | 09:11:46 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46608/tests/dromaeo/cssquery-yui.html) 09:11:46 INFO - PROCESS | 25664 | RSS: Main: 215932928 09:11:46 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | Cycle 1(1): loaded http://localhost:46608/tests/dromaeo/cssquery-yui.html (next: http://localhost:46608/tests/dromaeo/cssquery-dojo.html) 09:14:50 INFO - PROCESS | 25664 | RSS: Main: 277221376 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | __start_tp_report 09:14:50 INFO - PROCESS | 25664 | _x_x_mozilla_page_load 09:14:50 INFO - PROCESS | 25664 | _x_x_mozilla_page_load_details 09:14:50 INFO - PROCESS | 25664 | |i|pagename|runs| 09:14:50 INFO - PROCESS | 25664 | |0;dojo.html;2628.566286562717;2652.151311580295;2678.879450424731;2688.4354285600034;2693.272816339588;4598.195315819727;4676.3453116563705;4677.275022371529;4749.905001899964;4794.808207671694;4643.442786865572;4659.394278743767;4671.369365135705;4706.788194531246;4713.952860471394;4075.1034772350035;4226.577342265772;4325.048489332342;4350.021245219832;4451.1542806866755;4350.978245108786;4384.21084204843;4398.516163222043;4415.094905544372;4440;4206.390073439365;4258.701890867641;4304.096139810612;4355.324924636687;4363.541828108035;4269.573042695737;4283.4431523902;4310;4351.738895666239;4362.258416069276;4342.131573685244;4363.061941682554;4368.912621747579;4404.801783919731;4405.163019026375;4562.156001139767;4576.794044267983;4593.517680643551;4641.118187544355;4797.856064318076;4965.801367945278;4971.353724015891;5011.674241174312;5014.323066386028;5020.974895125538;4878.95121048792;5030.2957585938;5034.3455350804525;5035.622328325354;5069.5690866276045;4597.402337696111;4654.534546545318;4654.930176047362;4723.4331880174595;4725.054989002213;5111.003354345941;5126.307948426989;5175.042617115846;5181.145111056709;5190.584753219733;4918.803247870045;4964.702117872939;4969.652124351261;4970.3290055842;4978.0292842896015;4915.0415668944925;5011.123053465629;5062.291279220912;5068.214426763858;5104.770285337168;5031.018951304533;5040.31955685985;5040.974795126074;5045.646804723708;5090.77091530882;4829.855104346805;5013.3983921928875;5026.321446604735;5032.220005899091;5036.597072234213;4176.373543968369;4204.411382406465;4253.659707223414;4262.829486820492;4305.031367942188;3723.8138093095235;3749.437584362313;3756.1360886996144;3791.2986097571484;3794.6964242860504;4371.868843934687;4379.343098535245;4379.759113248738;4382.386465894777;4424.292113261863;4919.704817710949;4959.107360675041;5008.1486147355035;5010.248462730547;5028.84913452597;4283.721558098714;4292.12011537634;4298.161858437575;4320.956790432118;4341;4415.249407600716;4465.26323156682;4499.5275496072445;4499.730016199039;4505.7071290366675;4692.859214223647;4724.031573527487;4751.429828420612;4761;4761.976190119027;5030.346055012825;5030.974845125751;5031.119554078095;5046.924296135634;5050.595952323732;4606.262997920317;4659.34769132315;4718.8584342469785;4736.692115012514;4778.876963913547;4906.558409742997;4937;4958.801647934042;4963.801447942042;5015.799368025384;5002.724850133278;5012.6741761786825;5017.523335283142;5020.49795020495;5039.244113383022;4775.785089671042;4853.0779151961015;4866.6350023747655;4870.318155458168;4949;4907.656464047483;4930.704157750546;4932.605391568738;4938.08645400602;4967.801287948586;4858.805647774191;4864.975675121602;4910.631702622389;4912.263160525949;4912.852614421573;4736.71579705205;4768.451628062817;4779.641526885567;4861.975690121527;4920.188168952082;4717.740524271198;4725.692829966041;4732.219183834629;4780.856574302777;4833.3958255218095 09:14:50 INFO - PROCESS | 25664 | |1;ext.html;572.747990884011;579.6116601876745;579.8086631411634;580.2022219448259;589.4194218694588;27910.162695119154;40283.19433611326;41069.38395924064;41483;42496.57503424965;38636.80681596588;39109.41335879957;42623.36064959028;42697.359539606856;42728.78635606825;7377.114746230433;7801.453898227141;7941.08677502086;7941.205879412041;7941.841163176733;10060.899391006105;10330.690079297634;10334;10670.893291067068;10671.893281067167;9906;9946.850797238047;9960.05379488951;9967.152792012688;9982.800343993153;10288.279820412574;10346.844797328045;10354.844677329846;10361.222908281872;10363.585456581728;10142.645007424704;10198.847017294747;10215.795684086277;10215.897841021644;10217.948910255476;39333.60666393328;39700.40449393261;39704;39712.60287397147;39741.40387894184;3061.800982936102;3091.979646716578;3159.952600710991;3317.386283537529;3347.3807345641135;29637.70362296371;29956.187034332575;30111.548326775115;30787.230319241975;30938.845305773328;3333.749968752353;3365.915852103717;3380.340833537461;3423.5378223939697;3433.5021421893684;278865;279797;279981;280843;281555.99999999796;56732;57062;57320.14019789707;57345.426545734845;57406.712966435734;57918.36653067744;58680.70659646674;58828.11757823636;58876.70561647164;58896.705516472146;13434.664133396744;13760.587182384545;13786.034977551475;13790.483046864836;13796.448142074405;9409.341346105844;11144.832827507595;11686.24039437434;12046;12134.817977730341;17549.561260968578;17646.117694115244;17675.646487070444;17690.469285921186;17729.37947171843;4943.541655211721;5681.715914204273;5844.532437404998;5850.297964244318;5864.354920958691;827.7516744976483;930.0606387548489;954.355809828363;954.5227386306847;1001.3391161833155;29201.85399072991;29588.260293492836;29619.85190074036;29628.703712962593;30037.699623003926;989.0850962859327;1039.2361614213546;1068.9465526723632;1088.080571916729;1095.1129585036147;15305.387784488496;15641.60895977587;15713.292901819446;15742.291596878167;15790.210489475481;415895;416873;420917;421261;422928;51450;51719.741401293504;51761;51770;51891.22163167556;1071.8713754349528;1154.4112502623723;1210.7215340471564;1212.745323482061;1213.8968187704145;28409.857950710113;28759.568606471337;30056.398872022874;30305.545416819205;30746.69253307528;44570.3314450277;49048.2642760366;49493.752531237114;49612.25581616206;49639.75180124076;362324;364985;364998;365199;365230;22426.77573224247;25306.367340816625;26166.73833261719;26177.607335889596;27420.725792741818;40484.3927341096;40725.1854962905;40739;41286.79356603318;41398.58601414068;35436;35631.28737425185;35692.28615427729;35865.28269434649;35952.4607130888;35165;35353.469697955064;35371.46942795911;35484;35665;34701.30597388089;35507.82246088753;35896.102597435274;36015.63984360123;36155.45766813447;30361.392772144874;31206.21984450407;31825.363492730477;31898.20254493656;32010.19974500563;24246.636300455153;24738.752612474364;24870.026419221405;25056.24831255065;25258.115965941102 09:14:50 INFO - PROCESS | 25664 | |2;jquery.html;346.4197469239027;371.3464302827024;372.8489961565565;376.6986410871302;391.90398352403645;12980.54568090117;16385.918070409647;16900.492985210447;18300.359487417943;18310.176042078107;17056.403025894095;17527.298908043696;17658.646827063483;17670;17677.027763473037;1420.5667271482193;1428.278719246779;1693.5596744846337;6392.744290228386;6857.759978400757;6761;6762.560433571826;6779.220389655179;6828.624425656587;6829.351211634911;6544.836379090513;6842.589444633313;6848.14398200225;6849.7602583909575;6853.246142924274;5272.657277276965;5581.581381396411;6706.027625994252;6743.224529179133;6744.595324280534;5456.563474921997;5477.808276710296;5479.863003424906;5482.259894914176;5494.670319780786;12531.874681253254;17214.741778873326;20482.078306476094;21293.14827406902;21299.36101916945;1990.512324480497;2017.6065667194757;2027.0878104852875;2057.146284292017;2342.4963632818854;8797.516136612483;8889.955550222272;9249.35254532183;9307.3019523536;9366.391184572982;1614.8869579129466;1650.6946214950153;1811.5199472139893;1821.4262507310286;1848.112905805221;66071.33928660749;68348.31651683469;75580;78946.21053789445;79195.60402197952;9232.399894006869;9657.32398732082;10302.175825933908;10409.479526023668;10427.947860260801;12935.417906194243;13775.104618199784;13791.793123102962;14213.502527411698;14240.074395164282;6568.113304703899;6666.166729158856;6746.797596072126;6791.320867913169;6802.727890884309;6078.878422431527;6145.139680444741;6168.136460895478;6177.227846519185;6188.535859810442;4447.421835161408;4491.595756381876;4499.302608095751;4560.156371071363;4577.198990176706;1598.9680206395808;1627.9511614651578;1736.1406103978359;1756.8594512438974;1845.1696736468375;814.8411059843393;854.7734850264685;888.595688961521;924.5885580916428;937.7139972308375;16929.322827087024;17016.149192540324;17300.22149003298;17356.47930562085;18019;874.1608056266056;912.479886464722;919.5815903763771;937.2829785214317;951.2532661860406;9481.525923703924;9767.853482197772;9980.151687106532;9995.850062249072;10105.242106842017;359659;362882;370781;372560;372710;49176.75411622919;58815.11777323343;82296.58851705703;83510.58244708859;83723;908.1508789282043;913.2511340700695;946.5172761891347;970.9611615535441;971.1162841813915;16395.83604163943;16420.261088251296;16618.086005269826;16796.161217001543;16863.15684215809;36853.63146368609;37856;38084.238315232986;38924.02689932774;39356.606433935296;49079.7546012282;51221.743891280305;52759.47240527699;52866.2070068957;53416.732916336725;7493.175750667531;7835.138134805053;7903.170167132598;7979.241972012649;8017.398695098008;10626.415547144672;11972.401379931143;12059.698507537381;12177.512899483922;12196.512139514674;10497.317674351143;10536.315139515908;10640.499482621732;10661.25371224038;10861.511231994737;10507.369557826554;10681.092107170978;10735.570577177137;10881.347119172875;10897.346159230472;9456.574454149442;10341.9482902585;10526.263161578616;10704.89295107039;10812.945935270272;7764.301212890867;7792.571408572583;7915.84168316642;7954.284114429498;8113.878291825509;9538.23694104484;9885.406875587487;10520.526576303932;10798.082163016232;11009.174311926477 09:14:50 INFO - PROCESS | 25664 | |3;mootools.html;1198.6224339333114;1369.2674419185737;1374.9037567370285;1482.3329501724227;1705.8123606403294;6334.809955701325;7138.643067846607;7361.153467351263;7367.557946523212;7624.69501219952;7332.9266707333045;7455.962720186405;7476.700931962715;7480.7755767326935;7481.476296659236;2171.8479706420558;2195.560887822434;2198.0877935656763;2200.2299195281685;2204.9228277010307;2151.236311109557;2178.8474806763534;2192.7697591752794;2196.8681879087308;2196.9011394487206;2151.193302511558;2157.255746767363;2170.4573856535867;2172.576347612216;2192.4080498265457;2117.1743020222125;2121.130336562002;2124.691919671639;2125.0543508138962;2125.7236559247276;2031.1672214392029;2081.198738485679;2083.645780217367;2088.853780235384;2092.4350425385214;7395.963020184919;7452.85094298111;7515.1357593876855;7521.210272921374;7697.30724234816;4470.552944705536;4540.29625408059;4566.49768525462;4601.378813860153;4601.907961840778;2821.9717802821915;2932.941341173165;2968.717971792676;2975.747061499775;2993.117030475993;878.8110556230378;884.663827745459;889.617464490269;893.5934149961752;900.446225571269;10019.398836069859;10098.798024039477;10123.797524049478;10128.64549740763;10145.34055286404;3842.3083844907624;3946.3291240489184;4073.816678249486;4091.959080409217;4097.569755175723;8014.438989270695;8062.879056814152;8067.878981815277;8069;8076.71731489395;4477.932831007537;4487.685861989629;4490.236659767847;4490.708103973231;4496.168208881368;3600.71194304455;4025.6980726445636;4187.43469631602;4196.5803419658405;4207.179599977975;3340.6325304216516;3352.3965686176234;3430.3139372125675;3455.101673564841;3457.6888080072913;991.965281215154;993;999.5951639585979;1034.4052170002278;1042.1246153231323;776.2354081229984;794.2851433709708;797.9840403191904;798.4929569723267;798.9800254993671;4813.278008298783;4924.3105965164905;5068;5111.693298402108;5228.137357336073;814.678202110175;868.2316150207113;882.2853488674194;885.5749240364661;888.7644774134859;3240.3843269778667;3317.4692049272003;3321.0534997525588;3351.3632409842053;3430.091025878144;8895.288376929959;9098.636054557875;9241.306901982243;9253.167214950687;9253.629854805868;9090.136437038604;9156.954215228881;9164.770880728036;9166.129217724305;9203.171714545722;6933.757318493828;6958.130233720785;7145.21402645719;7151.249118842551;7155.248698886646;4722.244440889442;4745.762711864324;4773.832915847928;4789.928151077667;4905.975470122627;8225.95887020561;8537.701180458655;8547.102554231964;8707.738767836974;8749.956250218707;8567;8677.783055423664;8711.433756805787;8799.648014079365;8826.735197944072;5903.822885313448;6070;6263.843403914939;6476.4495017924;6479.125318081999;6656.034874943203;6734.56225345351;6859.622720750406;7090.4682148840075;7107.644617768988;6542.967285163544;6637.1703537057865;6659.367360100783;6711.496637752286;6726.8654626908165;6279.591826531261;6372.267189273108;6417;6458.838529036624;6531.771388001397;5936.168936349001;5947.702614869153;5992.8801423972145;5997.670128142994;5998.940010599838;4330.913381732411;4455.68810183284;4506.233940230103;4535.297028960516;4537.795799189111;4352.804123814372;4429.40203072594;4458.955410445984;4557.886052848706;4577.313402989578 09:14:50 INFO - PROCESS | 25664 | |4;prototype.html;294.3641733854874;294.991150265492;303.535590546464;303.8602242968235;305.9877604895804;4882.707037577743;4898.755062246892;4903.455411545361;4921.286413470049;4924.187509061005;4810.951890481094;4845;4847.757612119389;4847.788052986753;4857.222844344906;3631.1466805300734;3746.9437958430644;3758.3422900992355;3762.3039737648623;3766.924661506782;3741.2143449875557;3770.1140232045445;3854.9229015419673;3877.9612203878023;3908.6286802753693;3783.0114209774247;3803.353429916921;3817.675497582709;3823;3866.2074274773604;3591.6587924147166;3782.149016471286;3787.2046870157023;3788.8105594720155;3845.9615403845883;3305.6198537168293;3307.3054658521737;3329.6337402885665;3336.2326664867214;3345.3643807676463;4689.1793936061395;4690.554397332248;4695.765211739399;4716.127516409475;4756.31033500144;4113.670906327487;4268.679849011337;4268.829246830123;4275.444192254987;4279.465066866642;5000.349954505928;5006.5494105530315;5008.29883816266;5008.348914641109;5050.696958182521;3593.694535964446;3599.1541987632886;3603;3621.94567081494;3627.6372362763514;5091.898162036776;5230.404726558399;5231.712255825966;5242.951409718034;5251.159814429673;3674.595794462607;3676.6690997810324;3691.2248427830455;3693.3167364037204;3704.481372607837;3749.7937613430977;3749.981250093732;3751.0997360633796;3759.473673685686;3760.341940160461;4157.625813676784;4266.743995360288;4276.273033584298;4291.31338985767;4295.763732994716;5003.674761140514;5007.1487847065355;5025.296458495814;5027.4469808321055;5059.342285502861;1276.5851098392873;1299.3438313651545;1307.7842156044146;1308.1366298243113;1311.3115614302376;811.5658122904218;835.5362773660555;838.9706360277361;854.3464249848871;858.9570521473902;4340.804663790137;4433.202023635777;4456.596172205745;4493.741752309294;4498.190325741398;5144.9742751286;5168.302279192261;5169.663971841818;5183.637145399861;5189.195674670432;4368.104538569585;4444.2000439920475;4458.197524445631;4465;4491.169133710208;2694.9730502694724;2749.463854548344;2752.3394385347374;2755.9724402756115;2762.267998980272;4831.130396528659;4839.41926968766;4853.95146048535;4869.050535145683;4872.293517439952;5045.5459008689395;5241.475852414805;5261.5264626183825;5265.710385928811;5272.630915835932;4913.508649135058;4928.408590969107;4928.704277743347;4940.6788558743565;4951.752412379366;5159.303494028258;5197.584193264454;5229.757550318826;5230.5093048481;5230.921536177037;4941.407031156141;5104.770285337095;5108.489151084862;5116.771974725964;5128.333316668809;4875.68308059975;4897.530740777681;4906.82826101099;4917.557419832232;4939;3721.944170837494;3737.8878633641034;3759.699224062014;3765.2281282337603;3772.358699021228;5127.230915362726;5160.793568257378;5248.6588371755715;5254.579633629224;5260.316158899319;5029.64792464524;5055.750191164896;5058.342415485963;5067.847964561069;5071.2139618359215;5029.170186919264;5056.924146137737;5060.78541150114;5114.411842638145;5137.229415587692;4797.6011994003;4977.352944117241;4993.251012348177;4994.301937341322;5029.295898574275;4842.273658951185;4962.404511458648;5077.949220507748;5090.981803639213;5092.592592592658;4940.135476291706;5002.224655178453;5021.899562008813;5028.823991160292;5030.723310217973 09:14:50 INFO - PROCESS | 25664 | |5;yui.html;662.6951602262959;683.6650041479674;701.6035939694073;711.2070041903279;735.9963200183998;12250.754984900308;12533.185342952702;12547.498100075985;12549.623511294665;12718.68203294917;12826.551070712527;12888.93555532221;12922.418491167897;13890.4443822247;14590;785.0461689047805;799.2087833045273;804.0873608454417;804.3605333759675;804.3846457460048;618.659737144571;765.9961700191519;792.4254915186501;794.5987276425426;796.9123396426388;822.7449490657885;822.9341652667803;824.2375802382802;825.4345773145385;825.7275099217273;651.5178767711907;817.2726273616486;819.2053707903355;819.512390127873;820.3929092471558;585.1193953100599;601.6420229963165;603.3905755187293;606.4420732925719;606.760329669778;12869.22784632925;12878.162919410208;12892.419841107172;12900.967922566266;12924.870751292461;12232.633021009384;12484.063695222803;12534.623961281177;12772.700913835506;13225.537106201236;10794.136469082454;10948.562057517689;11915.94042029793;11981.64055078349;11983.94008029963;3256.2998955224502;3287.6547962464074;3353.8658453661824;3398.6601339865815;3401.846916888746;62545;63701.362986370004;65169.34830651728;65638.67180664114;65843;10264.076233139054;10374.948125259476;10396.844047339297;10446.05985461312;10827.621033263955;19003;19019.90490047541;19067.523311917037;19096;19097.904510477358;6301.0233413820015;6364.459020983269;6455.709493072823;6463.062855885964;6469.838254043687;3521.4893840393;3548.556430446194;3564.697000754913;3637.308911306843;3645.198056427582;2817.7323154300307;2917.9562306565417;2923.166897434221;2924.400497897926;2925.8683359248885;499.5229555774227;531.2615464504343;558.4359796605377;564.3932772269826;567.2172402085096;455.68330010642285;516.9431362550116;575.6517307029271;583.6760597868139;588.4204059001879;8150.633221505046;8488.151184881586;8489.787755306043;8737.694180703646;9026.368154229272;469.868436837686;566.8979583674978;581.3227589857853;585.6193474241777;605.8121982185537;4312.374705667723;4350.521442641307;4371.956280437155;4375.518692943773;4393.1872603568445;107136.4643176779;108460.45769771101;108559;110745.33881991777;111912.4404377973;66709.33290667128;68735.31264687389;71347;82060.58969705114;82324.76512852313;69597.65201173961;72381.63809181025;72723.63638181775;74046.25953740609;75315.24684753192;8464.95767521183;8571.357148213785;8582.184692454206;8709.346798989975;8747.993980692047;30587.847060764554;37849.81075094607;39082.73981888099;42581.78709106435;45231;39643.405348920365;40141;57411;62611;63301.40383154051;11063.612773552886;11318.151138664469;11477.426128693698;11738.94130529342;12061.819072714092;12979.156354836905;12998.155119917175;13242.933785331012;13259.734805304033;13981.510647127301;12177.69555761113;12515.37423128858;12617.49530018789;12676.936615316865;12737.363131843556;12427.751444970869;13032.087753857139;13392.933035334761;13517.391717372939;13518.662033449242;11324.547018119183;11339.489722962322;11881.762364752483;11889.524419023488;12246;9976.900230997888;10098.798024039625;10250.282480226313;10346.689599312032;10574.365538067741;9810.16613587853;9916.752081198028;10014.147382734218;10029.498525073572;10057.849132262876 09:14:50 INFO - PROCESS | 25664 | __end_tp_report 09:14:50 INFO - PROCESS | 25664 | __start_cc_report 09:14:50 INFO - PROCESS | 25664 | _x_x_mozilla_cycle_collect,1149 09:14:50 INFO - PROCESS | 25664 | __end_cc_report 09:14:50 INFO - PROCESS | 25664 | __startTimestamp1468340090365__endTimestamp 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | ------- Summary: start ------- 09:14:50 INFO - PROCESS | 25664 | Number of tests: 6 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#0] dojo.html Cycles:165 Average:4639.15 Median:4749.91 stddev:476.04 (10.0%) stddev-sans-first:450.62 09:14:50 INFO - PROCESS | 25664 | Values: 2628.6 2652.2 2678.9 2688.4 2693.3 4598.2 4676.3 4677.3 4749.9 4794.8 4643.4 4659.4 4671.4 4706.8 4714.0 4075.1 4226.6 4325.0 4350.0 4451.2 4351.0 4384.2 4398.5 4415.1 4440.0 4206.4 4258.7 4304.1 4355.3 4363.5 4269.6 4283.4 4310.0 4351.7 4362.3 4342.1 4363.1 4368.9 4404.8 4405.2 4562.2 4576.8 4593.5 4641.1 4797.9 4965.8 4971.4 5011.7 5014.3 5021.0 4879.0 5030.3 5034.3 5035.6 5069.6 4597.4 4654.5 4654.9 4723.4 4725.1 5111.0 5126.3 5175.0 5181.1 5190.6 4918.8 4964.7 4969.7 4970.3 4978.0 4915.0 5011.1 5062.3 5068.2 5104.8 5031.0 5040.3 5041.0 5045.6 5090.8 4829.9 5013.4 5026.3 5032.2 5036.6 4176.4 4204.4 4253.7 4262.8 4305.0 3723.8 3749.4 3756.1 3791.3 3794.7 4371.9 4379.3 4379.8 4382.4 4424.3 4919.7 4959.1 5008.1 5010.2 5028.8 4283.7 4292.1 4298.2 4321.0 4341.0 4415.2 4465.3 4499.5 4499.7 4505.7 4692.9 4724.0 4751.4 4761.0 4762.0 5030.3 5031.0 5031.1 5046.9 5050.6 4606.3 4659.3 4718.9 4736.7 4778.9 4906.6 4937.0 4958.8 4963.8 5015.8 5002.7 5012.7 5017.5 5020.5 5039.2 4775.8 4853.1 4866.6 4870.3 4949.0 4907.7 4930.7 4932.6 4938.1 4967.8 4858.8 4865.0 4910.6 4912.3 4912.9 4736.7 4768.5 4779.6 4862.0 4920.2 4717.7 4725.7 4732.2 4780.9 4833.4 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#1] ext.html Cycles:180 Average:51161.19 Median:34933.15 stddev:94777.51 (271.3%) stddev-sans-first:94967.28 09:14:50 INFO - PROCESS | 25664 | Values: 572.7 579.6 579.8 580.2 589.4 27910.2 40283.2 41069.4 41483.0 42496.6 38636.8 39109.4 42623.4 42697.4 42728.8 7377.1 7801.5 7941.1 7941.2 7941.8 10060.9 10330.7 10334.0 10670.9 10671.9 9906.0 9946.9 9960.1 9967.2 9982.8 10288.3 10346.8 10354.8 10361.2 10363.6 10142.6 10198.8 10215.8 10215.9 10217.9 39333.6 39700.4 39704.0 39712.6 39741.4 3061.8 3092.0 3160.0 3317.4 3347.4 29637.7 29956.2 30111.5 30787.2 30938.8 3333.7 3365.9 3380.3 3423.5 3433.5 278865.0 279797.0 279981.0 280843.0 281556.0 56732.0 57062.0 57320.1 57345.4 57406.7 57918.4 58680.7 58828.1 58876.7 58896.7 13434.7 13760.6 13786.0 13790.5 13796.4 9409.3 11144.8 11686.2 12046.0 12134.8 17549.6 17646.1 17675.6 17690.5 17729.4 4943.5 5681.7 5844.5 5850.3 5864.4 827.8 930.1 954.4 954.5 1001.3 29201.9 29588.3 29619.9 29628.7 30037.7 989.1 1039.2 1068.9 1088.1 1095.1 15305.4 15641.6 15713.3 15742.3 15790.2 415895.0 416873.0 420917.0 421261.0 422928.0 51450.0 51719.7 51761.0 51770.0 51891.2 1071.9 1154.4 1210.7 1212.7 1213.9 28409.9 28759.6 30056.4 30305.5 30746.7 44570.3 49048.3 49493.8 49612.3 49639.8 362324.0 364985.0 364998.0 365199.0 365230.0 22426.8 25306.4 26166.7 26177.6 27420.7 40484.4 40725.2 40739.0 41286.8 41398.6 35436.0 35631.3 35692.3 35865.3 35952.5 35165.0 35353.5 35371.5 35484.0 35665.0 34701.3 35507.8 35896.1 36015.6 36155.5 30361.4 31206.2 31825.4 31898.2 32010.2 24246.6 24738.8 24870.0 25056.2 25258.1 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#2] jquery.html Cycles:180 Average:23801.50 Median:4495.45 stddev:60900.31 (1354.7%) stddev-sans-first:61045.68 09:14:50 INFO - PROCESS | 25664 | Values: 346.4 371.3 372.8 376.7 391.9 12980.5 16385.9 16900.5 18300.4 18310.2 17056.4 17527.3 17658.6 17670.0 17677.0 1420.6 1428.3 1693.6 6392.7 6857.8 6761.0 6762.6 6779.2 6828.6 6829.4 6544.8 6842.6 6848.1 6849.8 6853.2 5272.7 5581.6 6706.0 6743.2 6744.6 5456.6 5477.8 5479.9 5482.3 5494.7 12531.9 17214.7 20482.1 21293.1 21299.4 1990.5 2017.6 2027.1 2057.1 2342.5 8797.5 8890.0 9249.4 9307.3 9366.4 1614.9 1650.7 1811.5 1821.4 1848.1 66071.3 68348.3 75580.0 78946.2 79195.6 9232.4 9657.3 10302.2 10409.5 10427.9 12935.4 13775.1 13791.8 14213.5 14240.1 6568.1 6666.2 6746.8 6791.3 6802.7 6078.9 6145.1 6168.1 6177.2 6188.5 4447.4 4491.6 4499.3 4560.2 4577.2 1599.0 1628.0 1736.1 1756.9 1845.2 814.8 854.8 888.6 924.6 937.7 16929.3 17016.1 17300.2 17356.5 18019.0 874.2 912.5 919.6 937.3 951.3 9481.5 9767.9 9980.2 9995.9 10105.2 359659.0 362882.0 370781.0 372560.0 372710.0 49176.8 58815.1 82296.6 83510.6 83723.0 908.2 913.3 946.5 971.0 971.1 16395.8 16420.3 16618.1 16796.2 16863.2 36853.6 37856.0 38084.2 38924.0 39356.6 49079.8 51221.7 52759.5 52866.2 53416.7 7493.2 7835.1 7903.2 7979.2 8017.4 10626.4 11972.4 12059.7 12177.5 12196.5 10497.3 10536.3 10640.5 10661.3 10861.5 10507.4 10681.1 10735.6 10881.3 10897.3 9456.6 10341.9 10526.3 10704.9 10812.9 7764.3 7792.6 7915.8 7954.3 8113.9 9538.2 9885.4 10520.5 10798.1 11009.2 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#3] mootools.html Cycles:180 Average:4899.38 Median:5169.92 stddev:2705.62 (52.3%) stddev-sans-first:2698.92 09:14:50 INFO - PROCESS | 25664 | Values: 1198.6 1369.3 1374.9 1482.3 1705.8 6334.8 7138.6 7361.2 7367.6 7624.7 7332.9 7456.0 7476.7 7480.8 7481.5 2171.8 2195.6 2198.1 2200.2 2204.9 2151.2 2178.8 2192.8 2196.9 2196.9 2151.2 2157.3 2170.5 2172.6 2192.4 2117.2 2121.1 2124.7 2125.1 2125.7 2031.2 2081.2 2083.6 2088.9 2092.4 7396.0 7452.9 7515.1 7521.2 7697.3 4470.6 4540.3 4566.5 4601.4 4601.9 2822.0 2932.9 2968.7 2975.7 2993.1 878.8 884.7 889.6 893.6 900.4 10019.4 10098.8 10123.8 10128.6 10145.3 3842.3 3946.3 4073.8 4092.0 4097.6 8014.4 8062.9 8067.9 8069.0 8076.7 4477.9 4487.7 4490.2 4490.7 4496.2 3600.7 4025.7 4187.4 4196.6 4207.2 3340.6 3352.4 3430.3 3455.1 3457.7 992.0 993.0 999.6 1034.4 1042.1 776.2 794.3 798.0 798.5 799.0 4813.3 4924.3 5068.0 5111.7 5228.1 814.7 868.2 882.3 885.6 888.8 3240.4 3317.5 3321.1 3351.4 3430.1 8895.3 9098.6 9241.3 9253.2 9253.6 9090.1 9157.0 9164.8 9166.1 9203.2 6933.8 6958.1 7145.2 7151.2 7155.2 4722.2 4745.8 4773.8 4789.9 4906.0 8226.0 8537.7 8547.1 8707.7 8750.0 8567.0 8677.8 8711.4 8799.6 8826.7 5903.8 6070.0 6263.8 6476.4 6479.1 6656.0 6734.6 6859.6 7090.5 7107.6 6543.0 6637.2 6659.4 6711.5 6726.9 6279.6 6372.3 6417.0 6458.8 6531.8 5936.2 5947.7 5992.9 5997.7 5998.9 4330.9 4455.7 4506.2 4535.3 4537.8 4352.8 4429.4 4459.0 4557.9 4577.3 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#4] prototype.html Cycles:180 Average:4201.18 Median:4846.38 stddev:1215.29 (25.1%) stddev-sans-first:1182.79 09:14:50 INFO - PROCESS | 25664 | Values: 294.4 295.0 303.5 303.9 306.0 4882.7 4898.8 4903.5 4921.3 4924.2 4811.0 4845.0 4847.8 4847.8 4857.2 3631.1 3746.9 3758.3 3762.3 3766.9 3741.2 3770.1 3854.9 3878.0 3908.6 3783.0 3803.4 3817.7 3823.0 3866.2 3591.7 3782.1 3787.2 3788.8 3846.0 3305.6 3307.3 3329.6 3336.2 3345.4 4689.2 4690.6 4695.8 4716.1 4756.3 4113.7 4268.7 4268.8 4275.4 4279.5 5000.3 5006.5 5008.3 5008.3 5050.7 3593.7 3599.2 3603.0 3621.9 3627.6 5091.9 5230.4 5231.7 5243.0 5251.2 3674.6 3676.7 3691.2 3693.3 3704.5 3749.8 3750.0 3751.1 3759.5 3760.3 4157.6 4266.7 4276.3 4291.3 4295.8 5003.7 5007.1 5025.3 5027.4 5059.3 1276.6 1299.3 1307.8 1308.1 1311.3 811.6 835.5 839.0 854.3 859.0 4340.8 4433.2 4456.6 4493.7 4498.2 5145.0 5168.3 5169.7 5183.6 5189.2 4368.1 4444.2 4458.2 4465.0 4491.2 2695.0 2749.5 2752.3 2756.0 2762.3 4831.1 4839.4 4854.0 4869.1 4872.3 5045.5 5241.5 5261.5 5265.7 5272.6 4913.5 4928.4 4928.7 4940.7 4951.8 5159.3 5197.6 5229.8 5230.5 5230.9 4941.4 5104.8 5108.5 5116.8 5128.3 4875.7 4897.5 4906.8 4917.6 4939.0 3721.9 3737.9 3759.7 3765.2 3772.4 5127.2 5160.8 5248.7 5254.6 5260.3 5029.6 5055.8 5058.3 5067.8 5071.2 5029.2 5056.9 5060.8 5114.4 5137.2 4797.6 4977.4 4993.3 4994.3 5029.3 4842.3 4962.4 5077.9 5091.0 5092.6 4940.1 5002.2 5021.9 5028.8 5030.7 09:14:50 INFO - PROCESS | 25664 | 09:14:50 INFO - PROCESS | 25664 | [#5] yui.html Cycles:180 Average:17544.73 Median:39892.20 stddev:25260.89 (63.3%) stddev-sans-first:25299.95 09:14:50 INFO - PROCESS | 25664 | Values: 662.7 683.7 701.6 711.2 736.0 12250.8 12533.2 12547.5 12549.6 12718.7 12826.6 12888.9 12922.4 13890.4 14590.0 785.0 799.2 804.1 804.4 804.4 618.7 766.0 792.4 794.6 796.9 822.7 822.9 824.2 825.4 825.7 651.5 817.3 819.2 819.5 820.4 585.1 601.6 603.4 606.4 606.8 12869.2 12878.2 12892.4 12901.0 12924.9 12232.6 12484.1 12534.6 12772.7 13225.5 10794.1 10948.6 11915.9 11981.6 11983.9 3256.3 3287.7 3353.9 3398.7 3401.8 62545.0 63701.4 65169.3 65638.7 65843.0 10264.1 10374.9 10396.8 10446.1 10827.6 19003.0 19019.9 19067.5 19096.0 19097.9 6301.0 6364.5 6455.7 6463.1 6469.8 3521.5 3548.6 3564.7 3637.3 3645.2 2817.7 2918.0 2923.2 2924.4 2925.9 499.5 531.3 558.4 564.4 567.2 455.7 516.9 575.7 583.7 588.4 8150.6 8488.2 8489.8 8737.7 9026.4 469.9 566.9 581.3 585.6 605.8 4312.4 4350.5 4372.0 4375.5 4393.2 107136.5 108460.5 108559.0 110745.3 111912.4 66709.3 68735.3 71347.0 82060.6 82324.8 69597.7 72381.6 72723.6 74046.3 75315.2 8465.0 8571.4 8582.2 8709.3 8748.0 30587.8 37849.8 39082.7 42581.8 45231.0 39643.4 40141.0 57411.0 62611.0 63301.4 11063.6 11318.2 11477.4 11738.9 12061.8 12979.2 12998.2 13242.9 13259.7 13981.5 12177.7 12515.4 12617.5 12676.9 12737.4 12427.8 13032.1 13392.9 13517.4 13518.7 11324.5 11339.5 11881.8 11889.5 12246.0 9976.9 10098.8 10250.3 10346.7 10574.4 9810.2 9916.8 10014.1 10029.5 10057.8 09:14:50 INFO - PROCESS | 25664 | -------- Summary: end -------- 09:14:50 INFO - PROCESS | 25664 | 09:14:51 INFO - TEST-INFO | 25664: exit 0 09:14:51 INFO - TEST-OK | dromaeo_css | took 1121696ms 09:14:51 INFO - TEST-START | kraken 09:14:51 INFO - operating with platform_type : linux_ 09:14:51 INFO - Initialising browser for kraken test... 09:14:51 INFO - TEST-INFO | started process 25855 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp0RXYG/profile http://localhost:46608/getInfo.html) 09:14:56 INFO - PROCESS | 25855 | __metrics Screen width/height:1600/1200 09:14:56 INFO - PROCESS | 25855 | colorDepth:24 09:14:56 INFO - PROCESS | 25855 | Browser inner width/height: 1024/697 09:14:56 INFO - PROCESS | 25855 | __metrics 09:14:57 INFO - PROCESS | 25855 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:14:57 INFO - PROCESS | 25855 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:14:58 INFO - TEST-INFO | 25855: exit 0 09:14:58 INFO - Browser initialized. 09:14:58 INFO - Running cycle 1/1 for kraken test... 09:14:58 INFO - TEST-INFO | started process 25937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp0RXYG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:10 INFO - PROCESS | 25937 | RSS: Main: 159260672 09:15:10 INFO - PROCESS | 25937 | 09:15:12 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46608/tests/kraken/driver.html?testName=audio-beat-detection) 09:15:13 INFO - PROCESS | 25937 | RSS: Main: 217239552 09:15:13 INFO - PROCESS | 25937 | 09:15:16 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46608/tests/kraken/driver.html?testName=audio-dft) 09:15:17 INFO - PROCESS | 25937 | RSS: Main: 203710464 09:15:17 INFO - PROCESS | 25937 | 09:15:20 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46608/tests/kraken/driver.html?testName=audio-fft) 09:15:21 INFO - PROCESS | 25937 | RSS: Main: 271515648 09:15:21 INFO - PROCESS | 25937 | 09:15:23 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46608/tests/kraken/driver.html?testName=audio-oscillator) 09:15:23 INFO - PROCESS | 25937 | RSS: Main: 182054912 09:15:23 INFO - PROCESS | 25937 | 09:15:26 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46608/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:15:26 INFO - PROCESS | 25937 | RSS: Main: 185303040 09:15:26 INFO - PROCESS | 25937 | 09:15:29 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46608/tests/kraken/driver.html?testName=imaging-darkroom) 09:15:30 INFO - PROCESS | 25937 | RSS: Main: 221962240 09:15:30 INFO - PROCESS | 25937 | 09:15:33 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46608/tests/kraken/driver.html?testName=imaging-desaturate) 09:15:34 INFO - PROCESS | 25937 | RSS: Main: 223055872 09:15:34 INFO - PROCESS | 25937 | 09:15:37 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46608/tests/kraken/driver.html?testName=json-parse-financial) 09:15:38 INFO - PROCESS | 25937 | RSS: Main: 222990336 09:15:38 INFO - PROCESS | 25937 | 09:15:39 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46608/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:15:40 INFO - PROCESS | 25937 | RSS: Main: 209682432 09:15:40 INFO - PROCESS | 25937 | 09:15:42 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:15:42 INFO - PROCESS | 25937 | RSS: Main: 212992000 09:15:42 INFO - PROCESS | 25937 | 09:15:44 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:15:45 INFO - PROCESS | 25937 | RSS: Main: 209494016 09:15:45 INFO - PROCESS | 25937 | 09:15:48 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:15:49 INFO - PROCESS | 25937 | RSS: Main: 223903744 09:15:49 INFO - PROCESS | 25937 | 09:15:51 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:15:52 INFO - PROCESS | 25937 | RSS: Main: 223916032 09:15:52 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | Cycle 1(1): loaded http://localhost:46608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46608/tests/kraken/driver.html?testName=ai-astar) 09:15:54 INFO - PROCESS | 25937 | RSS: Main: 265998336 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | __start_tp_report 09:15:54 INFO - PROCESS | 25937 | _x_x_mozilla_page_load 09:15:54 INFO - PROCESS | 25937 | _x_x_mozilla_page_load_details 09:15:54 INFO - PROCESS | 25937 | |i|pagename|runs| 09:15:54 INFO - PROCESS | 25937 | |0;ai-astar;104;117;116;116;114;114;113;114;115;114 09:15:54 INFO - PROCESS | 25937 | |1;audio-beat-detection;156;122;124;175;124;127;151;122;172;122 09:15:54 INFO - PROCESS | 25937 | |2;audio-dft;161;162;161;161;160;163;162;161;160;162 09:15:54 INFO - PROCESS | 25937 | |3;audio-fft;75;75;76;113;75;78;76;74;105;75 09:15:54 INFO - PROCESS | 25937 | |4;audio-oscillator;87;86;115;85;86;85;125;85;85;128 09:15:54 INFO - PROCESS | 25937 | |5;imaging-gaussian-blur;117;117;117;116;117;116;118;117;116;117 09:15:54 INFO - PROCESS | 25937 | |6;imaging-darkroom;147;146;146;146;146;146;146;146;145;146 09:15:54 INFO - PROCESS | 25937 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;95 09:15:54 INFO - PROCESS | 25937 | |8;json-parse-financial;63;62;62;62;62;62;63;63;62;62 09:15:54 INFO - PROCESS | 25937 | |9;json-stringify-tinderbox;64;65;64;64;65;65;64;65;64;64 09:15:54 INFO - PROCESS | 25937 | |10;stanford-crypto-aes;85;78;84;82;78;79;79;78;78;82 09:15:54 INFO - PROCESS | 25937 | |11;stanford-crypto-ccm;127;129;126;130;129;128;137;126;165;132 09:15:54 INFO - PROCESS | 25937 | |12;stanford-crypto-pbkdf2;166;166;167;165;165;167;170;167;169;165 09:15:54 INFO - PROCESS | 25937 | |13;stanford-crypto-sha256-iterative;67;67;68;66;67;68;67;67;66;65 09:15:54 INFO - PROCESS | 25937 | __end_tp_report 09:15:54 INFO - PROCESS | 25937 | __start_cc_report 09:15:54 INFO - PROCESS | 25937 | _x_x_mozilla_cycle_collect,1894 09:15:54 INFO - PROCESS | 25937 | __end_cc_report 09:15:54 INFO - PROCESS | 25937 | __startTimestamp1468340154539__endTimestamp 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | ------- Summary: start ------- 09:15:54 INFO - PROCESS | 25937 | Number of tests: 14 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.50 stddev:3.62 (3.2%) stddev-sans-first:1.30 09:15:54 INFO - PROCESS | 25937 | Values: 104.0 117.0 116.0 116.0 114.0 114.0 113.0 114.0 115.0 114.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:139.00 stddev:21.80 (15.7%) stddev-sans-first:22.29 09:15:54 INFO - PROCESS | 25937 | Values: 156.0 122.0 124.0 175.0 124.0 127.0 151.0 122.0 172.0 122.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 09:15:54 INFO - PROCESS | 25937 | Values: 161.0 162.0 161.0 161.0 160.0 163.0 162.0 161.0 160.0 162.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:14.29 (19.1%) stddev-sans-first:14.92 09:15:54 INFO - PROCESS | 25937 | Values: 75.0 75.0 76.0 113.0 75.0 78.0 76.0 74.0 105.0 75.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:85.00 stddev:18.22 (21.4%) stddev-sans-first:18.98 09:15:54 INFO - PROCESS | 25937 | Values: 87.0 86.0 115.0 85.0 86.0 85.0 125.0 85.0 85.0 128.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 09:15:54 INFO - PROCESS | 25937 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 118.0 117.0 116.0 117.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 09:15:54 INFO - PROCESS | 25937 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:15:54 INFO - PROCESS | 25937 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 09:15:54 INFO - PROCESS | 25937 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 63.0 63.0 62.0 62.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:15:54 INFO - PROCESS | 25937 | Values: 64.0 65.0 64.0 64.0 65.0 65.0 64.0 65.0 64.0 64.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:2.71 (3.4%) stddev-sans-first:2.28 09:15:54 INFO - PROCESS | 25937 | Values: 85.0 78.0 84.0 82.0 78.0 79.0 79.0 78.0 78.0 82.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#11] stanford-crypto-ccm Cycles:10 Average:132.90 Median:129.50 stddev:11.74 (9.1%) stddev-sans-first:12.26 09:15:54 INFO - PROCESS | 25937 | Values: 127.0 129.0 126.0 130.0 129.0 128.0 137.0 126.0 165.0 132.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:167.00 stddev:1.70 (1.0%) stddev-sans-first:1.79 09:15:54 INFO - PROCESS | 25937 | Values: 166.0 166.0 167.0 165.0 165.0 167.0 170.0 167.0 169.0 165.0 09:15:54 INFO - PROCESS | 25937 | 09:15:54 INFO - PROCESS | 25937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 09:15:54 INFO - PROCESS | 25937 | Values: 67.0 67.0 68.0 66.0 67.0 68.0 67.0 67.0 66.0 65.0 09:15:54 INFO - PROCESS | 25937 | -------- Summary: end -------- 09:15:54 INFO - PROCESS | 25937 | 09:15:55 INFO - TEST-INFO | 25937: exit 0 09:15:55 INFO - TEST-OK | kraken | took 64475ms 09:15:55 INFO - SUITE-END | took 1186s 09:15:56 INFO - Completed test suite (00:19:46) 09:15:56 INFO - javascript benchmark 09:15:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2628.566286562717, 2652.151311580295, 2678.879450424731, 2688.4354285600034, 2693.272816339588, 4598.195315819727, 4676.3453116563705, 4677.275022371529, 4749.905001899964, 4794.808207671694, 4643.442786865572, 4659.394278743767, 4671.369365135705, 4706.788194531246, 4713.952860471394, 4075.1034772350035, 4226.577342265772, 4325.048489332342, 4350.021245219832, 4451.1542806866755, 4350.978245108786, 4384.21084204843, 4398.516163222043, 4415.094905544372, 4440.0, 4206.390073439365, 4258.701890867641, 4304.096139810612, 4355.324924636687, 4363.541828108035, 4269.573042695737, 4283.4431523902, 4310.0, 4351.738895666239, 4362.258416069276, 4342.131573685244, 4363.061941682554, 4368.912621747579, 4404.801783919731, 4405.163019026375, 4562.156001139767, 4576.794044267983, 4593.517680643551, 4641.118187544355, 4797.856064318076, 4965.801367945278, 4971.353724015891, 5011.674241174312, 5014.323066386028, 5020.974895125538, 4878.95121048792, 5030.2957585938, 5034.3455350804525, 5035.622328325354, 5069.5690866276045, 4597.402337696111, 4654.534546545318, 4654.930176047362, 4723.4331880174595, 4725.054989002213, 5111.003354345941, 5126.307948426989, 5175.042617115846, 5181.145111056709, 5190.584753219733, 4918.803247870045, 4964.702117872939, 4969.652124351261, 4970.3290055842, 4978.0292842896015, 4915.0415668944925, 5011.123053465629, 5062.291279220912, 5068.214426763858, 5104.770285337168, 5031.018951304533, 5040.31955685985, 5040.974795126074, 5045.646804723708, 5090.77091530882, 4829.855104346805, 5013.3983921928875, 5026.321446604735, 5032.220005899091, 5036.597072234213, 4176.373543968369, 4204.411382406465, 4253.659707223414, 4262.829486820492, 4305.031367942188, 3723.8138093095235, 3749.437584362313, 3756.1360886996144, 3791.2986097571484, 3794.6964242860504, 4371.868843934687, 4379.343098535245, 4379.759113248738, 4382.386465894777, 4424.292113261863, 4919.704817710949, 4959.107360675041, 5008.1486147355035, 5010.248462730547, 5028.84913452597, 4283.721558098714, 4292.12011537634, 4298.161858437575, 4320.956790432118, 4341.0, 4415.249407600716, 4465.26323156682, 4499.5275496072445, 4499.730016199039, 4505.7071290366675, 4692.859214223647, 4724.031573527487, 4751.429828420612, 4761.0, 4761.976190119027, 5030.346055012825, 5030.974845125751, 5031.119554078095, 5046.924296135634, 5050.595952323732, 4606.262997920317, 4659.34769132315, 4718.8584342469785, 4736.692115012514, 4778.876963913547, 4906.558409742997, 4937.0, 4958.801647934042, 4963.801447942042, 5015.799368025384, 5002.724850133278, 5012.6741761786825, 5017.523335283142, 5020.49795020495, 5039.244113383022, 4775.785089671042, 4853.0779151961015, 4866.6350023747655, 4870.318155458168, 4949.0, 4907.656464047483, 4930.704157750546, 4932.605391568738, 4938.08645400602, 4967.801287948586, 4858.805647774191, 4864.975675121602, 4910.631702622389, 4912.263160525949, 4912.852614421573, 4736.71579705205, 4768.451628062817, 4779.641526885567, 4861.975690121527, 4920.188168952082, 4717.740524271198, 4725.692829966041, 4732.219183834629, 4780.856574302777, 4833.3958255218095], "value": 4609.688320291621, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.747990884011, 579.6116601876745, 579.8086631411634, 580.2022219448259, 589.4194218694588, 27910.162695119154, 40283.19433611326, 41069.38395924064, 41483.0, 42496.57503424965, 38636.80681596588, 39109.41335879957, 42623.36064959028, 42697.359539606856, 42728.78635606825, 7377.114746230433, 7801.453898227141, 7941.08677502086, 7941.205879412041, 7941.841163176733, 10060.899391006105, 10330.690079297634, 10334.0, 10670.893291067068, 10671.893281067167, 9906.0, 9946.850797238047, 9960.05379488951, 9967.152792012688, 9982.800343993153, 10288.279820412574, 10346.844797328045, 10354.844677329846, 10361.222908281872, 10363.585456581728, 10142.645007424704, 10198.847017294747, 10215.795684086277, 10215.897841021644, 10217.948910255476, 39333.60666393328, 39700.40449393261, 39704.0, 39712.60287397147, 39741.40387894184, 3061.800982936102, 3091.979646716578, 3159.952600710991, 3317.386283537529, 3347.3807345641135, 29637.70362296371, 29956.187034332575, 30111.548326775115, 30787.230319241975, 30938.845305773328, 3333.749968752353, 3365.915852103717, 3380.340833537461, 3423.5378223939697, 3433.5021421893684, 278865.0, 279797.0, 279981.0, 280843.0, 281555.99999999796, 56732.0, 57062.0, 57320.14019789707, 57345.426545734845, 57406.712966435734, 57918.36653067744, 58680.70659646674, 58828.11757823636, 58876.70561647164, 58896.705516472146, 13434.664133396744, 13760.587182384545, 13786.034977551475, 13790.483046864836, 13796.448142074405, 9409.341346105844, 11144.832827507595, 11686.24039437434, 12046.0, 12134.817977730341, 17549.561260968578, 17646.117694115244, 17675.646487070444, 17690.469285921186, 17729.37947171843, 4943.541655211721, 5681.715914204273, 5844.532437404998, 5850.297964244318, 5864.354920958691, 827.7516744976483, 930.0606387548489, 954.355809828363, 954.5227386306847, 1001.3391161833155, 29201.85399072991, 29588.260293492836, 29619.85190074036, 29628.703712962593, 30037.699623003926, 989.0850962859327, 1039.2361614213546, 1068.9465526723632, 1088.080571916729, 1095.1129585036147, 15305.387784488496, 15641.60895977587, 15713.292901819446, 15742.291596878167, 15790.210489475481, 415895.0, 416873.0, 420917.0, 421261.0, 422928.0, 51450.0, 51719.741401293504, 51761.0, 51770.0, 51891.22163167556, 1071.8713754349528, 1154.4112502623723, 1210.7215340471564, 1212.745323482061, 1213.8968187704145, 28409.857950710113, 28759.568606471337, 30056.398872022874, 30305.545416819205, 30746.69253307528, 44570.3314450277, 49048.2642760366, 49493.752531237114, 49612.25581616206, 49639.75180124076, 362324.0, 364985.0, 364998.0, 365199.0, 365230.0, 22426.77573224247, 25306.367340816625, 26166.73833261719, 26177.607335889596, 27420.725792741818, 40484.3927341096, 40725.1854962905, 40739.0, 41286.79356603318, 41398.58601414068, 35436.0, 35631.28737425185, 35692.28615427729, 35865.28269434649, 35952.4607130888, 35165.0, 35353.469697955064, 35371.46942795911, 35484.0, 35665.0, 34701.30597388089, 35507.82246088753, 35896.102597435274, 36015.63984360123, 36155.45766813447, 30361.392772144874, 31206.21984450407, 31825.363492730477, 31898.20254493656, 32010.19974500563, 24246.636300455153, 24738.752612474364, 24870.026419221405, 25056.24831255065, 25258.115965941102], "value": 18387.08573566412, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.4197469239027, 371.3464302827024, 372.8489961565565, 376.6986410871302, 391.90398352403645, 12980.54568090117, 16385.918070409647, 16900.492985210447, 18300.359487417943, 18310.176042078107, 17056.403025894095, 17527.298908043696, 17658.646827063483, 17670.0, 17677.027763473037, 1420.5667271482193, 1428.278719246779, 1693.5596744846337, 6392.744290228386, 6857.759978400757, 6761.0, 6762.560433571826, 6779.220389655179, 6828.624425656587, 6829.351211634911, 6544.836379090513, 6842.589444633313, 6848.14398200225, 6849.7602583909575, 6853.246142924274, 5272.657277276965, 5581.581381396411, 6706.027625994252, 6743.224529179133, 6744.595324280534, 5456.563474921997, 5477.808276710296, 5479.863003424906, 5482.259894914176, 5494.670319780786, 12531.874681253254, 17214.741778873326, 20482.078306476094, 21293.14827406902, 21299.36101916945, 1990.512324480497, 2017.6065667194757, 2027.0878104852875, 2057.146284292017, 2342.4963632818854, 8797.516136612483, 8889.955550222272, 9249.35254532183, 9307.3019523536, 9366.391184572982, 1614.8869579129466, 1650.6946214950153, 1811.5199472139893, 1821.4262507310286, 1848.112905805221, 66071.33928660749, 68348.31651683469, 75580.0, 78946.21053789445, 79195.60402197952, 9232.399894006869, 9657.32398732082, 10302.175825933908, 10409.479526023668, 10427.947860260801, 12935.417906194243, 13775.104618199784, 13791.793123102962, 14213.502527411698, 14240.074395164282, 6568.113304703899, 6666.166729158856, 6746.797596072126, 6791.320867913169, 6802.727890884309, 6078.878422431527, 6145.139680444741, 6168.136460895478, 6177.227846519185, 6188.535859810442, 4447.421835161408, 4491.595756381876, 4499.302608095751, 4560.156371071363, 4577.198990176706, 1598.9680206395808, 1627.9511614651578, 1736.1406103978359, 1756.8594512438974, 1845.1696736468375, 814.8411059843393, 854.7734850264685, 888.595688961521, 924.5885580916428, 937.7139972308375, 16929.322827087024, 17016.149192540324, 17300.22149003298, 17356.47930562085, 18019.0, 874.1608056266056, 912.479886464722, 919.5815903763771, 937.2829785214317, 951.2532661860406, 9481.525923703924, 9767.853482197772, 9980.151687106532, 9995.850062249072, 10105.242106842017, 359659.0, 362882.0, 370781.0, 372560.0, 372710.0, 49176.75411622919, 58815.11777323343, 82296.58851705703, 83510.58244708859, 83723.0, 908.1508789282043, 913.2511340700695, 946.5172761891347, 970.9611615535441, 971.1162841813915, 16395.83604163943, 16420.261088251296, 16618.086005269826, 16796.161217001543, 16863.15684215809, 36853.63146368609, 37856.0, 38084.238315232986, 38924.02689932774, 39356.606433935296, 49079.7546012282, 51221.743891280305, 52759.47240527699, 52866.2070068957, 53416.732916336725, 7493.175750667531, 7835.138134805053, 7903.170167132598, 7979.241972012649, 8017.398695098008, 10626.415547144672, 11972.401379931143, 12059.698507537381, 12177.512899483922, 12196.512139514674, 10497.317674351143, 10536.315139515908, 10640.499482621732, 10661.25371224038, 10861.511231994737, 10507.369557826554, 10681.092107170978, 10735.570577177137, 10881.347119172875, 10897.346159230472, 9456.574454149442, 10341.9482902585, 10526.263161578616, 10704.89295107039, 10812.945935270272, 7764.301212890867, 7792.571408572583, 7915.84168316642, 7954.284114429498, 8113.878291825509, 9538.23694104484, 9885.406875587487, 10520.526576303932, 10798.082163016232, 11009.174311926477], "value": 8303.234033642577, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.6224339333114, 1369.2674419185737, 1374.9037567370285, 1482.3329501724227, 1705.8123606403294, 6334.809955701325, 7138.643067846607, 7361.153467351263, 7367.557946523212, 7624.69501219952, 7332.9266707333045, 7455.962720186405, 7476.700931962715, 7480.7755767326935, 7481.476296659236, 2171.8479706420558, 2195.560887822434, 2198.0877935656763, 2200.2299195281685, 2204.9228277010307, 2151.236311109557, 2178.8474806763534, 2192.7697591752794, 2196.8681879087308, 2196.9011394487206, 2151.193302511558, 2157.255746767363, 2170.4573856535867, 2172.576347612216, 2192.4080498265457, 2117.1743020222125, 2121.130336562002, 2124.691919671639, 2125.0543508138962, 2125.7236559247276, 2031.1672214392029, 2081.198738485679, 2083.645780217367, 2088.853780235384, 2092.4350425385214, 7395.963020184919, 7452.85094298111, 7515.1357593876855, 7521.210272921374, 7697.30724234816, 4470.552944705536, 4540.29625408059, 4566.49768525462, 4601.378813860153, 4601.907961840778, 2821.9717802821915, 2932.941341173165, 2968.717971792676, 2975.747061499775, 2993.117030475993, 878.8110556230378, 884.663827745459, 889.617464490269, 893.5934149961752, 900.446225571269, 10019.398836069859, 10098.798024039477, 10123.797524049478, 10128.64549740763, 10145.34055286404, 3842.3083844907624, 3946.3291240489184, 4073.816678249486, 4091.959080409217, 4097.569755175723, 8014.438989270695, 8062.879056814152, 8067.878981815277, 8069.0, 8076.71731489395, 4477.932831007537, 4487.685861989629, 4490.236659767847, 4490.708103973231, 4496.168208881368, 3600.71194304455, 4025.6980726445636, 4187.43469631602, 4196.5803419658405, 4207.179599977975, 3340.6325304216516, 3352.3965686176234, 3430.3139372125675, 3455.101673564841, 3457.6888080072913, 991.965281215154, 993.0, 999.5951639585979, 1034.4052170002278, 1042.1246153231323, 776.2354081229984, 794.2851433709708, 797.9840403191904, 798.4929569723267, 798.9800254993671, 4813.278008298783, 4924.3105965164905, 5068.0, 5111.693298402108, 5228.137357336073, 814.678202110175, 868.2316150207113, 882.2853488674194, 885.5749240364661, 888.7644774134859, 3240.3843269778667, 3317.4692049272003, 3321.0534997525588, 3351.3632409842053, 3430.091025878144, 8895.288376929959, 9098.636054557875, 9241.306901982243, 9253.167214950687, 9253.629854805868, 9090.136437038604, 9156.954215228881, 9164.770880728036, 9166.129217724305, 9203.171714545722, 6933.757318493828, 6958.130233720785, 7145.21402645719, 7151.249118842551, 7155.248698886646, 4722.244440889442, 4745.762711864324, 4773.832915847928, 4789.928151077667, 4905.975470122627, 8225.95887020561, 8537.701180458655, 8547.102554231964, 8707.738767836974, 8749.956250218707, 8567.0, 8677.783055423664, 8711.433756805787, 8799.648014079365, 8826.735197944072, 5903.822885313448, 6070.0, 6263.843403914939, 6476.4495017924, 6479.125318081999, 6656.034874943203, 6734.56225345351, 6859.622720750406, 7090.4682148840075, 7107.644617768988, 6542.967285163544, 6637.1703537057865, 6659.367360100783, 6711.496637752286, 6726.8654626908165, 6279.591826531261, 6372.267189273108, 6417.0, 6458.838529036624, 6531.771388001397, 5936.168936349001, 5947.702614869153, 5992.8801423972145, 5997.670128142994, 5998.940010599838, 4330.913381732411, 4455.68810183284, 4506.233940230103, 4535.297028960516, 4537.795799189111, 4352.804123814372, 4429.40203072594, 4458.955410445984, 4557.886052848706, 4577.313402989578], "value": 3964.2667662886206, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3641733854874, 294.991150265492, 303.535590546464, 303.8602242968235, 305.9877604895804, 4882.707037577743, 4898.755062246892, 4903.455411545361, 4921.286413470049, 4924.187509061005, 4810.951890481094, 4845.0, 4847.757612119389, 4847.788052986753, 4857.222844344906, 3631.1466805300734, 3746.9437958430644, 3758.3422900992355, 3762.3039737648623, 3766.924661506782, 3741.2143449875557, 3770.1140232045445, 3854.9229015419673, 3877.9612203878023, 3908.6286802753693, 3783.0114209774247, 3803.353429916921, 3817.675497582709, 3823.0, 3866.2074274773604, 3591.6587924147166, 3782.149016471286, 3787.2046870157023, 3788.8105594720155, 3845.9615403845883, 3305.6198537168293, 3307.3054658521737, 3329.6337402885665, 3336.2326664867214, 3345.3643807676463, 4689.1793936061395, 4690.554397332248, 4695.765211739399, 4716.127516409475, 4756.31033500144, 4113.670906327487, 4268.679849011337, 4268.829246830123, 4275.444192254987, 4279.465066866642, 5000.349954505928, 5006.5494105530315, 5008.29883816266, 5008.348914641109, 5050.696958182521, 3593.694535964446, 3599.1541987632886, 3603.0, 3621.94567081494, 3627.6372362763514, 5091.898162036776, 5230.404726558399, 5231.712255825966, 5242.951409718034, 5251.159814429673, 3674.595794462607, 3676.6690997810324, 3691.2248427830455, 3693.3167364037204, 3704.481372607837, 3749.7937613430977, 3749.981250093732, 3751.0997360633796, 3759.473673685686, 3760.341940160461, 4157.625813676784, 4266.743995360288, 4276.273033584298, 4291.31338985767, 4295.763732994716, 5003.674761140514, 5007.1487847065355, 5025.296458495814, 5027.4469808321055, 5059.342285502861, 1276.5851098392873, 1299.3438313651545, 1307.7842156044146, 1308.1366298243113, 1311.3115614302376, 811.5658122904218, 835.5362773660555, 838.9706360277361, 854.3464249848871, 858.9570521473902, 4340.804663790137, 4433.202023635777, 4456.596172205745, 4493.741752309294, 4498.190325741398, 5144.9742751286, 5168.302279192261, 5169.663971841818, 5183.637145399861, 5189.195674670432, 4368.104538569585, 4444.2000439920475, 4458.197524445631, 4465.0, 4491.169133710208, 2694.9730502694724, 2749.463854548344, 2752.3394385347374, 2755.9724402756115, 2762.267998980272, 4831.130396528659, 4839.41926968766, 4853.95146048535, 4869.050535145683, 4872.293517439952, 5045.5459008689395, 5241.475852414805, 5261.5264626183825, 5265.710385928811, 5272.630915835932, 4913.508649135058, 4928.408590969107, 4928.704277743347, 4940.6788558743565, 4951.752412379366, 5159.303494028258, 5197.584193264454, 5229.757550318826, 5230.5093048481, 5230.921536177037, 4941.407031156141, 5104.770285337095, 5108.489151084862, 5116.771974725964, 5128.333316668809, 4875.68308059975, 4897.530740777681, 4906.82826101099, 4917.557419832232, 4939.0, 3721.944170837494, 3737.8878633641034, 3759.699224062014, 3765.2281282337603, 3772.358699021228, 5127.230915362726, 5160.793568257378, 5248.6588371755715, 5254.579633629224, 5260.316158899319, 5029.64792464524, 5055.750191164896, 5058.342415485963, 5067.847964561069, 5071.2139618359215, 5029.170186919264, 5056.924146137737, 5060.78541150114, 5114.411842638145, 5137.229415587692, 4797.6011994003, 4977.352944117241, 4993.251012348177, 4994.301937341322, 5029.295898574275, 4842.273658951185, 4962.404511458648, 5077.949220507748, 5090.981803639213, 5092.592592592658, 4940.135476291706, 5002.224655178453, 5021.899562008813, 5028.823991160292, 5030.723310217973], "value": 3813.4092724774237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.6951602262959, 683.6650041479674, 701.6035939694073, 711.2070041903279, 735.9963200183998, 12250.754984900308, 12533.185342952702, 12547.498100075985, 12549.623511294665, 12718.68203294917, 12826.551070712527, 12888.93555532221, 12922.418491167897, 13890.4443822247, 14590.0, 785.0461689047805, 799.2087833045273, 804.0873608454417, 804.3605333759675, 804.3846457460048, 618.659737144571, 765.9961700191519, 792.4254915186501, 794.5987276425426, 796.9123396426388, 822.7449490657885, 822.9341652667803, 824.2375802382802, 825.4345773145385, 825.7275099217273, 651.5178767711907, 817.2726273616486, 819.2053707903355, 819.512390127873, 820.3929092471558, 585.1193953100599, 601.6420229963165, 603.3905755187293, 606.4420732925719, 606.760329669778, 12869.22784632925, 12878.162919410208, 12892.419841107172, 12900.967922566266, 12924.870751292461, 12232.633021009384, 12484.063695222803, 12534.623961281177, 12772.700913835506, 13225.537106201236, 10794.136469082454, 10948.562057517689, 11915.94042029793, 11981.64055078349, 11983.94008029963, 3256.2998955224502, 3287.6547962464074, 3353.8658453661824, 3398.6601339865815, 3401.846916888746, 62545.0, 63701.362986370004, 65169.34830651728, 65638.67180664114, 65843.0, 10264.076233139054, 10374.948125259476, 10396.844047339297, 10446.05985461312, 10827.621033263955, 19003.0, 19019.90490047541, 19067.523311917037, 19096.0, 19097.904510477358, 6301.0233413820015, 6364.459020983269, 6455.709493072823, 6463.062855885964, 6469.838254043687, 3521.4893840393, 3548.556430446194, 3564.697000754913, 3637.308911306843, 3645.198056427582, 2817.7323154300307, 2917.9562306565417, 2923.166897434221, 2924.400497897926, 2925.8683359248885, 499.5229555774227, 531.2615464504343, 558.4359796605377, 564.3932772269826, 567.2172402085096, 455.68330010642285, 516.9431362550116, 575.6517307029271, 583.6760597868139, 588.4204059001879, 8150.633221505046, 8488.151184881586, 8489.787755306043, 8737.694180703646, 9026.368154229272, 469.868436837686, 566.8979583674978, 581.3227589857853, 585.6193474241777, 605.8121982185537, 4312.374705667723, 4350.521442641307, 4371.956280437155, 4375.518692943773, 4393.1872603568445, 107136.4643176779, 108460.45769771101, 108559.0, 110745.33881991777, 111912.4404377973, 66709.33290667128, 68735.31264687389, 71347.0, 82060.58969705114, 82324.76512852313, 69597.65201173961, 72381.63809181025, 72723.63638181775, 74046.25953740609, 75315.24684753192, 8464.95767521183, 8571.357148213785, 8582.184692454206, 8709.346798989975, 8747.993980692047, 30587.847060764554, 37849.81075094607, 39082.73981888099, 42581.78709106435, 45231.0, 39643.405348920365, 40141.0, 57411.0, 62611.0, 63301.40383154051, 11063.612773552886, 11318.151138664469, 11477.426128693698, 11738.94130529342, 12061.819072714092, 12979.156354836905, 12998.155119917175, 13242.933785331012, 13259.734805304033, 13981.510647127301, 12177.69555761113, 12515.37423128858, 12617.49530018789, 12676.936615316865, 12737.363131843556, 12427.751444970869, 13032.087753857139, 13392.933035334761, 13517.391717372939, 13518.662033449242, 11324.547018119183, 11339.489722962322, 11881.762364752483, 11889.524419023488, 12246.0, 9976.900230997888, 10098.798024039625, 10250.282480226313, 10346.689599312032, 10574.365538067741, 9810.16613587853, 9916.752081198028, 10014.147382734218, 10029.498525073572, 10057.849132262876], "value": 6586.88777201746, "unit": "score"}], "name": "dromaeo_css", "value": 6421.063954150134, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 116.0, 116.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 124.0, 175.0, 124.0, 127.0, 151.0, 122.0, 172.0, 122.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 160.0, 163.0, 162.0, 161.0, 160.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 113.0, 75.0, 78.0, 76.0, 74.0, 105.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 115.0, 85.0, 86.0, 85.0, 125.0, 85.0, 85.0, 128.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 118.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 84.0, 82.0, 78.0, 79.0, 79.0, 78.0, 78.0, 82.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 126.0, 130.0, 129.0, 128.0, 137.0, 126.0, 165.0, 132.0], "value": 132.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 165.0, 165.0, 167.0, 170.0, 167.0, 169.0, 165.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 67.0, 68.0, 67.0, 67.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1524.7, "alertThreshold": 2.0}]} 09:15:56 INFO - Return code: 0 09:15:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:15:56 INFO - # TBPL SUCCESS # 09:15:56 INFO - Running post-action listener: _resource_record_post_action 09:15:56 INFO - ##### 09:15:56 INFO - ##### Finished run-tests step (success) 09:15:56 INFO - ##### 09:15:56 INFO - Running post-run listener: _resource_record_post_run 09:15:56 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 558268416; Read time: 12; Write time: 655984 09:15:56 INFO - TinderboxPrint: CPU usage
13.0% 09:15:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:15:56 INFO - TinderboxPrint: I/O write bytes / time
558,268,416 / 655,984 09:15:56 INFO - TinderboxPrint: CPU idle
8,355.4 (87.0%) 09:15:56 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 09:15:56 INFO - TinderboxPrint: Swap in / out
0 / 0 09:15:56 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 245968896; Read time: 0; Write time: 330700 09:15:56 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 296570880; Read time: 0; Write time: 322340 09:15:56 INFO - Running post-run listener: _upload_blobber_files 09:15:56 INFO - Blob upload gear active. 09:15:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:15:56 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 09:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:15:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:15:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:15:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:15:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:15:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:15:58 INFO - (blobuploader) - INFO - Done attempting. 09:15:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:15:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:15:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:15:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:15:58 INFO - (blobuploader) - INFO - Done attempting. 09:15:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:15:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:02 INFO - (blobuploader) - INFO - Done attempting. 09:16:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:16:02 INFO - Return code: 0 09:16:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:16:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:16:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/826a02d73f420da0cf212a8310382cf39b19e427ea42b03556d7948d0bcdb3b6ae94807b7cf991e4cc43f785e4dfd9a2082d89a64ef2e727b305995d757ebdbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0664dc8998f8862ed86b4dfa5cdbe9f8dc4242fa0ff1482255a40809bbc326cf7a96204500f162278c89dc3e9daaa34fd3931066a44492849c61126b1d1e52a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a11c75605fc01afbd1a0877fe1f2143f4f1f0d9c3c268d962259d340df1b9b3669ceeaf5eb2516ddbdcfa844949009ced04e24e93bbd182e50db0aa9987b0db"} 09:16:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:16:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:16:02 INFO - Contents: 09:16:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/826a02d73f420da0cf212a8310382cf39b19e427ea42b03556d7948d0bcdb3b6ae94807b7cf991e4cc43f785e4dfd9a2082d89a64ef2e727b305995d757ebdbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0664dc8998f8862ed86b4dfa5cdbe9f8dc4242fa0ff1482255a40809bbc326cf7a96204500f162278c89dc3e9daaa34fd3931066a44492849c61126b1d1e52a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a11c75605fc01afbd1a0877fe1f2143f4f1f0d9c3c268d962259d340df1b9b3669ceeaf5eb2516ddbdcfa844949009ced04e24e93bbd182e50db0aa9987b0db"} 09:16:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:16:02 INFO - Copying logs to upload dir... 09:16:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:16:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.464858 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-12 09:16:02.468611) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:16:02.471639) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/826a02d73f420da0cf212a8310382cf39b19e427ea42b03556d7948d0bcdb3b6ae94807b7cf991e4cc43f785e4dfd9a2082d89a64ef2e727b305995d757ebdbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0664dc8998f8862ed86b4dfa5cdbe9f8dc4242fa0ff1482255a40809bbc326cf7a96204500f162278c89dc3e9daaa34fd3931066a44492849c61126b1d1e52a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a11c75605fc01afbd1a0877fe1f2143f4f1f0d9c3c268d962259d340df1b9b3669ceeaf5eb2516ddbdcfa844949009ced04e24e93bbd182e50db0aa9987b0db"} build_url:https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/BprjVChcQQSZ02p7sONkPg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/826a02d73f420da0cf212a8310382cf39b19e427ea42b03556d7948d0bcdb3b6ae94807b7cf991e4cc43f785e4dfd9a2082d89a64ef2e727b305995d757ebdbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0664dc8998f8862ed86b4dfa5cdbe9f8dc4242fa0ff1482255a40809bbc326cf7a96204500f162278c89dc3e9daaa34fd3931066a44492849c61126b1d1e52a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a11c75605fc01afbd1a0877fe1f2143f4f1f0d9c3c268d962259d340df1b9b3669ceeaf5eb2516ddbdcfa844949009ced04e24e93bbd182e50db0aa9987b0db"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:16:02.496622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:16:02.496929) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468338559.488918-41957855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004576 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:16:02.582022) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:16:02.582346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:16:02.582632) ========= ========= Total master_lag: 0.18 =========