builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1468473677.96 results: success (0) buildid: 20160713193238 builduid: 004ed187fec5449ea2030cbe672ec6cb revision: c3792b13e44350100962143db01c7ff6e15a7ee1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:17.959943) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:17.960382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:17.960694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.002523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.002818) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 22:21: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-07-13 22:21:18 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.150319 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.172160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.172500) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015115 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.223859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.224560) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c3792b13e44350100962143db01c7ff6e15a7ee1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c3792b13e44350100962143db01c7ff6e15a7ee1 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 22:21:18,315 truncating revision to first 12 chars 2016-07-13 22:21:18,315 Setting DEBUG logging. 2016-07-13 22:21:18,315 attempt 1/10 2016-07-13 22:21:18,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c3792b13e443?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 22:21:18,629 unpacking tar archive at: mozilla-inbound-c3792b13e443/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685370 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.925321) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.925654) ========= script_repo_revision: c3792b13e44350100962143db01c7ff6e15a7ee1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.934544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.934883) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:21:18.945636) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-13 22:21:18.945941) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:21:19 INFO - MultiFileLogger online at 20160713 22:21:19 in /builds/slave/test 22:21:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:21:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:21:19 INFO - {'append_to_log': False, 22:21:19 INFO - 'base_work_dir': '/builds/slave/test', 22:21:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:21:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:21:19 INFO - 'branch': 'Mozilla-Inbound', 22:21:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:21:19 INFO - 'config_files': ('talos/linux_config.py',), 22:21:19 INFO - 'default_actions': ('clobber', 22:21:19 INFO - 'read-buildbot-config', 22:21:19 INFO - 'download-and-extract', 22:21:19 INFO - 'populate-webroot', 22:21:19 INFO - 'create-virtualenv', 22:21:19 INFO - 'install', 22:21:19 INFO - 'run-tests'), 22:21:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:21:19 INFO - 'download_minidump_stackwalk': True, 22:21:19 INFO - 'download_symbols': 'ondemand', 22:21:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:21:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:21:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:21:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:21:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:21:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:21:19 INFO - 'installer_path': 'installer.exe', 22:21:19 INFO - 'log_level': 'info', 22:21:19 INFO - 'log_name': 'talos', 22:21:19 INFO - 'log_to_console': True, 22:21:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:21:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:21:19 INFO - 'opt_config_files': (), 22:21:19 INFO - 'pip_index': False, 22:21:19 INFO - 'sps_profile': False, 22:21:19 INFO - 'sps_profile_interval': 0, 22:21:19 INFO - 'suite': 'dromaeojs', 22:21:19 INFO - 'system_bits': '32', 22:21:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:21:19 INFO - 'title': 'talos-linux64-ix-069', 22:21:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:21:19 INFO - 'use_talos_json': True, 22:21:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:21:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:21:19 INFO - 'work_dir': 'build'} 22:21:19 INFO - ##### 22:21:19 INFO - ##### Running clobber step. 22:21:19 INFO - ##### 22:21:19 INFO - Running pre-action listener: _resource_record_pre_action 22:21:19 INFO - Running main action method: clobber 22:21:19 INFO - rmtree: /builds/slave/test/build 22:21:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:21:20 INFO - Running post-action listener: _resource_record_post_action 22:21:20 INFO - ##### 22:21:20 INFO - ##### Finished clobber step (success) 22:21:20 INFO - ##### 22:21:20 INFO - ##### 22:21:20 INFO - ##### Running read-buildbot-config step. 22:21:20 INFO - ##### 22:21:20 INFO - Running pre-action listener: _resource_record_pre_action 22:21:20 INFO - Running main action method: read_buildbot_config 22:21:20 INFO - Using buildbot properties: 22:21:20 INFO - { 22:21:20 INFO - "project": "", 22:21:20 INFO - "product": "firefox", 22:21:20 INFO - "script_repo_revision": "production", 22:21:20 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:21:20 INFO - "repository": "", 22:21:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:21:20 INFO - "buildid": "20160713193238", 22:21:20 INFO - "pgo_build": "True", 22:21:20 INFO - "basedir": "/builds/slave/test", 22:21:20 INFO - "buildnumber": 1335, 22:21:20 INFO - "slavename": "talos-linux64-ix-069", 22:21:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:21:20 INFO - "platform": "ubuntu64_hw", 22:21:20 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:21:20 INFO - "branch": "mozilla-inbound", 22:21:20 INFO - "repo_path": "integration/mozilla-inbound", 22:21:20 INFO - "revision": "c3792b13e44350100962143db01c7ff6e15a7ee1", 22:21:20 INFO - "stage_platform": "linux64-pgo", 22:21:20 INFO - "builduid": "004ed187fec5449ea2030cbe672ec6cb", 22:21:20 INFO - "slavebuilddir": "test" 22:21:20 INFO - } 22:21:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:21:20 INFO - Running post-action listener: _resource_record_post_action 22:21:20 INFO - ##### 22:21:20 INFO - ##### Finished read-buildbot-config step (success) 22:21:20 INFO - ##### 22:21:20 INFO - ##### 22:21:20 INFO - ##### Running download-and-extract step. 22:21:20 INFO - ##### 22:21:20 INFO - Running pre-action listener: _resource_record_pre_action 22:21:20 INFO - Running main action method: download_and_extract 22:21:20 INFO - mkdir: /builds/slave/test/build/tests 22:21: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')]} 22:21:20 INFO - https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:20 INFO - Downloaded 1361 bytes. 22:21:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:21:20 INFO - Using the following test package requirements: 22:21:20 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:21:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:21:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:21:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'jsshell-linux-x86_64.zip'], 22:21:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:21:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:21:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:21:20 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:21:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:21:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:21:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:21: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')]} 22:21:20 INFO - https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:22 INFO - Downloaded 24327007 bytes. 22:21: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'] 22:21: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 22:21:23 INFO - Return code: 0 22:21: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 22:21: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')]} 22:21:23 INFO - https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:21:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:21:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:21:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:31 INFO - Downloaded 24327007 bytes. 22:21:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:21:32 INFO - Return code: 0 22:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:32 INFO - https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:21:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:33 INFO - Downloaded 14403317 bytes. 22:21:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:21:33 INFO - Return code: 0 22:21: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')]} 22:21:33 INFO - https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:21:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:21:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:21:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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 22:21:35 INFO - Downloaded 60818180 bytes. 22:21:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:21:35 INFO - mkdir: /builds/slave/test/properties 22:21:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:35 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:35 INFO - Contents: 22:21:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:21:35 INFO - Running post-action listener: _resource_record_post_action 22:21:35 INFO - Running post-action listener: set_extra_try_arguments 22:21:35 INFO - ##### 22:21:35 INFO - ##### Finished download-and-extract step (success) 22:21:35 INFO - ##### 22:21:35 INFO - ##### 22:21:35 INFO - ##### Running populate-webroot step. 22:21:35 INFO - ##### 22:21:35 INFO - Running pre-action listener: _resource_record_pre_action 22:21:35 INFO - Running main action method: populate_webroot 22:21:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:35 INFO - '--tppagecycles', 22:21:35 INFO - '7'], 22:21:35 INFO - 'tests': ['tsvgm']}}, 22:21:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['damp', 'tps']}, 22:21:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'tests': ['damp', 'tps']}, 22:21:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['dromaeo_dom']}, 22:21:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['basic_compositor_video']}, 22:21:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:21:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['a11yr', 22:21:35 INFO - 'ts_paint', 22:21:35 INFO - 'tpaint', 22:21:35 INFO - 'sessionrestore', 22:21:35 INFO - 'sessionrestore_no_auto_restore', 22:21:35 INFO - 'tabpaint']}, 22:21:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['tsvgx', 22:21:35 INFO - 'tsvgr_opacity', 22:21:35 INFO - 'tart', 22:21:35 INFO - 'tscrollx', 22:21:35 INFO - 'cart']}, 22:21:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:35 INFO - 'tsvgr_opacity', 22:21:35 INFO - 'tart', 22:21:35 INFO - 'tscrollx', 22:21:35 INFO - 'cart']}, 22:21:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'talos_options': ['--disable-e10s'], 22:21:35 INFO - 'tests': ['tp5o']}, 22:21:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'tests': ['tp5o']}, 22:21:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'talos_options': ['--disable-e10s', 22:21:35 INFO - '--xperf_path', 22:21:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:35 INFO - 'tests': ['tp5n']}, 22:21:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:35 INFO - 'talos_options': ['--xperf_path', 22:21:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:35 INFO - 'tests': ['tp5n']}}, 22:21:35 INFO - 'talos.zip': {'path': '', 22:21:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:35 INFO - Running post-action listener: _resource_record_post_action 22:21:35 INFO - ##### 22:21:35 INFO - ##### Finished populate-webroot step (success) 22:21:35 INFO - ##### 22:21:35 INFO - ##### 22:21:35 INFO - ##### Running create-virtualenv step. 22:21:35 INFO - ##### 22:21:35 INFO - Running pre-action listener: _resource_record_pre_action 22:21:35 INFO - Running main action method: create_virtualenv 22:21:35 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:35 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 22:21:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:35 INFO - Using real prefix '/usr' 22:21:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:36 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:38 INFO - Installing pip.................done. 22:21:38 INFO - Return code: 0 22:21:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:21:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:21:38 INFO - Using env: {'DISPLAY': ':0', 22:21:38 INFO - 'HOME': '/home/cltbld', 22:21:38 INFO - 'LANG': 'en_US.UTF-8', 22:21:38 INFO - 'LANGUAGE': 'en_US:en', 22:21:38 INFO - 'LOGNAME': 'cltbld', 22:21:38 INFO - 'MAIL': '/var/mail/cltbld', 22:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:38 INFO - 'MOZ_NO_REMOTE': '1', 22:21:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:38 INFO - 'NO_EM_RESTART': '1', 22:21:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:38 INFO - 'PWD': '/builds/slave/test', 22:21:38 INFO - 'SHELL': '/bin/bash', 22:21:38 INFO - 'SHLVL': '1', 22:21:38 INFO - 'TERM': 'linux', 22:21:38 INFO - 'TMOUT': '86400', 22:21:38 INFO - 'USER': 'cltbld', 22:21:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:38 INFO - '_': '/tools/buildbot/bin/python'} 22:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:21:38 INFO - Cleaning up... 22:21:38 INFO - Return code: 0 22:21:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:21:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:21:38 INFO - Using env: {'DISPLAY': ':0', 22:21:38 INFO - 'HOME': '/home/cltbld', 22:21:38 INFO - 'LANG': 'en_US.UTF-8', 22:21:38 INFO - 'LANGUAGE': 'en_US:en', 22:21:38 INFO - 'LOGNAME': 'cltbld', 22:21:38 INFO - 'MAIL': '/var/mail/cltbld', 22:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:38 INFO - 'MOZ_NO_REMOTE': '1', 22:21:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:38 INFO - 'NO_EM_RESTART': '1', 22:21:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:38 INFO - 'PWD': '/builds/slave/test', 22:21:38 INFO - 'SHELL': '/bin/bash', 22:21:38 INFO - 'SHLVL': '1', 22:21:38 INFO - 'TERM': 'linux', 22:21:38 INFO - 'TMOUT': '86400', 22:21:38 INFO - 'USER': 'cltbld', 22:21:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:38 INFO - '_': '/tools/buildbot/bin/python'} 22:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:38 INFO - Downloading/unpacking psutil>=3.1.1 22:21:38 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 22:21:38 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 22:21:38 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 22:21:38 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 22:21:38 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 22:21:38 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 22:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:41 INFO - Installing collected packages: psutil 22:21:41 INFO - Running setup.py install for psutil 22:21:41 INFO - building 'psutil._psutil_linux' extension 22:21:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:21:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:21:41 INFO - building 'psutil._psutil_posix' extension 22:21:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:21:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:21:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:41 INFO - Successfully installed psutil 22:21:41 INFO - Cleaning up... 22:21:42 INFO - Return code: 0 22:21:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:42 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')]} 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 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')]} 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 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 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:42 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 22:21:42 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 22:21:42 INFO - Using env: {'DISPLAY': ':0', 22:21:42 INFO - 'HOME': '/home/cltbld', 22:21:42 INFO - 'LANG': 'en_US.UTF-8', 22:21:42 INFO - 'LANGUAGE': 'en_US:en', 22:21:42 INFO - 'LOGNAME': 'cltbld', 22:21:42 INFO - 'MAIL': '/var/mail/cltbld', 22:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:42 INFO - 'MOZ_NO_REMOTE': '1', 22:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:42 INFO - 'NO_EM_RESTART': '1', 22:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:42 INFO - 'PWD': '/builds/slave/test', 22:21:42 INFO - 'SHELL': '/bin/bash', 22:21:42 INFO - 'SHLVL': '1', 22:21:42 INFO - 'TERM': 'linux', 22:21:42 INFO - 'TMOUT': '86400', 22:21:42 INFO - 'USER': 'cltbld', 22:21:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:42 INFO - '_': '/tools/buildbot/bin/python'} 22:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:44 INFO - Installing collected packages: mozsystemmonitor 22:21:44 INFO - Running setup.py install for mozsystemmonitor 22:21:44 INFO - Successfully installed mozsystemmonitor 22:21:44 INFO - Cleaning up... 22:21:44 INFO - Return code: 0 22:21:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:21:44 INFO - Using env: {'DISPLAY': ':0', 22:21:44 INFO - 'HOME': '/home/cltbld', 22:21:44 INFO - 'LANG': 'en_US.UTF-8', 22:21:44 INFO - 'LANGUAGE': 'en_US:en', 22:21:44 INFO - 'LOGNAME': 'cltbld', 22:21:44 INFO - 'MAIL': '/var/mail/cltbld', 22:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:44 INFO - 'MOZ_NO_REMOTE': '1', 22:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:44 INFO - 'NO_EM_RESTART': '1', 22:21:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:44 INFO - 'PWD': '/builds/slave/test', 22:21:44 INFO - 'SHELL': '/bin/bash', 22:21:44 INFO - 'SHLVL': '1', 22:21:44 INFO - 'TERM': 'linux', 22:21:44 INFO - 'TMOUT': '86400', 22:21:44 INFO - 'USER': 'cltbld', 22:21:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:44 INFO - '_': '/tools/buildbot/bin/python'} 22:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:44 INFO - Downloading/unpacking jsonschema==2.5.1 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:47 INFO - warning: no files found matching '*.txt' 22:21:47 INFO - no previously-included directories found matching 'build' 22:21:47 INFO - no previously-included directories found matching 'dist' 22:21:47 INFO - no previously-included directories found matching '.git*' 22:21:47 INFO - Installing collected packages: jsonschema, functools32 22:21:47 INFO - Running setup.py install for functools32 22:21:47 INFO - warning: no files found matching '*.txt' 22:21:47 INFO - no previously-included directories found matching 'build' 22:21:47 INFO - no previously-included directories found matching 'dist' 22:21:47 INFO - no previously-included directories found matching '.git*' 22:21:47 INFO - Successfully installed jsonschema functools32 22:21:47 INFO - Cleaning up... 22:21:48 INFO - Return code: 0 22:21:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:48 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 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:48 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 22:21:48 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 22:21:48 INFO - Using env: {'DISPLAY': ':0', 22:21:48 INFO - 'HOME': '/home/cltbld', 22:21:48 INFO - 'LANG': 'en_US.UTF-8', 22:21:48 INFO - 'LANGUAGE': 'en_US:en', 22:21:48 INFO - 'LOGNAME': 'cltbld', 22:21:48 INFO - 'MAIL': '/var/mail/cltbld', 22:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:48 INFO - 'MOZ_NO_REMOTE': '1', 22:21:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:48 INFO - 'NO_EM_RESTART': '1', 22:21:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:48 INFO - 'PWD': '/builds/slave/test', 22:21:48 INFO - 'SHELL': '/bin/bash', 22:21:48 INFO - 'SHLVL': '1', 22:21:48 INFO - 'TERM': 'linux', 22:21:48 INFO - 'TMOUT': '86400', 22:21:48 INFO - 'USER': 'cltbld', 22:21:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:48 INFO - '_': '/tools/buildbot/bin/python'} 22:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:48 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:50 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 INFO - Downloading docopt-0.6.1.tar.gz 22:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:50 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:50 INFO - Running setup.py install for blobuploader 22:21:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:50 INFO - Running setup.py install for requests 22:21:51 INFO - Running setup.py install for docopt 22:21:51 INFO - Successfully installed blobuploader requests docopt 22:21:51 INFO - Cleaning up... 22:21:51 INFO - Return code: 0 22:21:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:51 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')]} 22:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:51 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')]} 22:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:51 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 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:51 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 22:21:51 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 22:21:51 INFO - Using env: {'DISPLAY': ':0', 22:21:51 INFO - 'HOME': '/home/cltbld', 22:21:51 INFO - 'LANG': 'en_US.UTF-8', 22:21:51 INFO - 'LANGUAGE': 'en_US:en', 22:21:51 INFO - 'LOGNAME': 'cltbld', 22:21:51 INFO - 'MAIL': '/var/mail/cltbld', 22:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:51 INFO - 'MOZ_NO_REMOTE': '1', 22:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:51 INFO - 'NO_EM_RESTART': '1', 22:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:51 INFO - 'PWD': '/builds/slave/test', 22:21:51 INFO - 'SHELL': '/bin/bash', 22:21:51 INFO - 'SHLVL': '1', 22:21:51 INFO - 'TERM': 'linux', 22:21:51 INFO - 'TMOUT': '86400', 22:21:51 INFO - 'USER': 'cltbld', 22:21:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:51 INFO - '_': '/tools/buildbot/bin/python'} 22:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:51 INFO - Running setup.py (path:/tmp/pip-K06dYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:51 INFO - Running setup.py (path:/tmp/pip-0dzM7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:51 INFO - Running setup.py (path:/tmp/pip-FUD4_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:51 INFO - Running setup.py (path:/tmp/pip-fqjSPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:52 INFO - Running setup.py (path:/tmp/pip-oKPSPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:52 INFO - Running setup.py (path:/tmp/pip-Om8Evw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:52 INFO - Running setup.py (path:/tmp/pip-_yXhLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:52 INFO - Running setup.py (path:/tmp/pip-9RZZi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:52 INFO - Running setup.py (path:/tmp/pip-65OE2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:52 INFO - Running setup.py (path:/tmp/pip-St5KSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:52 INFO - Running setup.py (path:/tmp/pip-7n8BWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:52 INFO - Running setup.py (path:/tmp/pip-pQ0nWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:52 INFO - Running setup.py (path:/tmp/pip-5LBzGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:52 INFO - Running setup.py (path:/tmp/pip-AZLfW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:52 INFO - Running setup.py (path:/tmp/pip-PeDLuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:52 INFO - Running setup.py (path:/tmp/pip-qXHOcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:52 INFO - Running setup.py (path:/tmp/pip-Lr9ou5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:52 INFO - Running setup.py install for manifestparser 22:21:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for mozcrash 22:21:53 INFO - Running setup.py install for mozdebug 22:21:53 INFO - Running setup.py install for mozdevice 22:21:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for mozfile 22:21:53 INFO - Running setup.py install for mozhttpd 22:21:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for mozinfo 22:21:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for mozInstall 22:21:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for mozleak 22:21:53 INFO - Running setup.py install for mozlog 22:21:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:53 INFO - Running setup.py install for moznetwork 22:21:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Running setup.py install for mozprocess 22:21:54 INFO - Running setup.py install for mozprofile 22:21:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Running setup.py install for mozrunner 22:21:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Running setup.py install for mozscreenshot 22:21:54 INFO - Running setup.py install for moztest 22:21:54 INFO - Running setup.py install for mozversion 22:21:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:54 INFO - Cleaning up... 22:21:54 INFO - Return code: 0 22:21:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:54 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 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:54 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 22:21:54 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 22:21:54 INFO - Using env: {'DISPLAY': ':0', 22:21:54 INFO - 'HOME': '/home/cltbld', 22:21:54 INFO - 'LANG': 'en_US.UTF-8', 22:21:54 INFO - 'LANGUAGE': 'en_US:en', 22:21:54 INFO - 'LOGNAME': 'cltbld', 22:21:54 INFO - 'MAIL': '/var/mail/cltbld', 22:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:54 INFO - 'MOZ_NO_REMOTE': '1', 22:21:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:54 INFO - 'NO_EM_RESTART': '1', 22:21:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:54 INFO - 'PWD': '/builds/slave/test', 22:21:54 INFO - 'SHELL': '/bin/bash', 22:21:54 INFO - 'SHLVL': '1', 22:21:54 INFO - 'TERM': 'linux', 22:21:54 INFO - 'TMOUT': '86400', 22:21:54 INFO - 'USER': 'cltbld', 22:21:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:54 INFO - '_': '/tools/buildbot/bin/python'} 22:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:54 INFO - Running setup.py (path:/tmp/pip-TOGkwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:54 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:55 INFO - Running setup.py (path:/tmp/pip-qvpjUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:55 INFO - Running setup.py (path:/tmp/pip-B8T2P3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:55 INFO - Running setup.py (path:/tmp/pip-d6Tsfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:55 INFO - Running setup.py (path:/tmp/pip-Q4_tC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:55 INFO - Running setup.py (path:/tmp/pip-gfiltD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:55 INFO - Running setup.py (path:/tmp/pip-OsRUm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:55 INFO - Running setup.py (path:/tmp/pip-cffqgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:55 INFO - Running setup.py (path:/tmp/pip-dW82cO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:55 INFO - Running setup.py (path:/tmp/pip-TD7D6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:55 INFO - Running setup.py (path:/tmp/pip-Mb9sQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:55 INFO - Running setup.py (path:/tmp/pip-GPVleZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:55 INFO - Running setup.py (path:/tmp/pip-swAeSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:55 INFO - Running setup.py (path:/tmp/pip-IxjlhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:55 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)) 22:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:55 INFO - Running setup.py (path:/tmp/pip-dGzkHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:56 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)) 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:56 INFO - Running setup.py (path:/tmp/pip-mnZ3e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:56 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)) 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:56 INFO - Running setup.py (path:/tmp/pip-jvR_yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:56 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)) 22:21:56 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)) 22:21:56 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)) 22:21:56 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)) 22:21:56 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)) 22:21:56 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)) 22:21:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:56 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 22:21:56 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 22:21:56 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 22:21:56 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 22:21:56 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 22:21:56 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 22:21:58 INFO - Downloading blessings-1.6.tar.gz 22:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:58 INFO - Installing collected packages: blessings 22:21:58 INFO - Running setup.py install for blessings 22:21:58 INFO - Successfully installed blessings 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:58 INFO - Reading from file tmpfile_stdout 22:21:58 INFO - Current package versions: 22:21:58 INFO - argparse == 1.2.1 22:21:58 INFO - blessings == 1.6 22:21:58 INFO - blobuploader == 1.2.4 22:21:58 INFO - docopt == 0.6.1 22:21:58 INFO - functools32 == 3.2.3-2 22:21:58 INFO - jsonschema == 2.5.1 22:21:58 INFO - manifestparser == 1.1 22:21:58 INFO - mozInstall == 1.12 22:21:58 INFO - mozcrash == 0.17 22:21:58 INFO - mozdebug == 0.1 22:21:58 INFO - mozdevice == 0.48 22:21:58 INFO - mozfile == 1.2 22:21:58 INFO - mozhttpd == 0.7 22:21:58 INFO - mozinfo == 0.9 22:21:58 INFO - mozleak == 0.1 22:21:58 INFO - mozlog == 3.2 22:21:58 INFO - moznetwork == 0.27 22:21:58 INFO - mozprocess == 0.23 22:21:58 INFO - mozprofile == 0.28 22:21:58 INFO - mozrunner == 6.12 22:21:58 INFO - mozscreenshot == 0.1 22:21:58 INFO - mozsystemmonitor == 0.3 22:21:58 INFO - moztest == 0.7 22:21:58 INFO - mozversion == 1.4 22:21:58 INFO - psutil == 3.1.1 22:21:58 INFO - requests == 1.2.3 22:21:58 INFO - wsgiref == 0.1.2 22:21:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 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 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:58 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 22:21:58 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 22:21:58 INFO - Using env: {'DISPLAY': ':0', 22:21:58 INFO - 'HOME': '/home/cltbld', 22:21:58 INFO - 'LANG': 'en_US.UTF-8', 22:21:58 INFO - 'LANGUAGE': 'en_US:en', 22:21:58 INFO - 'LOGNAME': 'cltbld', 22:21:58 INFO - 'MAIL': '/var/mail/cltbld', 22:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:58 INFO - 'MOZ_NO_REMOTE': '1', 22:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:58 INFO - 'NO_EM_RESTART': '1', 22:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:58 INFO - 'PWD': '/builds/slave/test', 22:21:58 INFO - 'SHELL': '/bin/bash', 22:21:58 INFO - 'SHLVL': '1', 22:21:58 INFO - 'TERM': 'linux', 22:21:58 INFO - 'TMOUT': '86400', 22:21:58 INFO - 'USER': 'cltbld', 22:21:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:58 INFO - '_': '/tools/buildbot/bin/python'} 22:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', '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 22:21:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:21:58 INFO - Using env: {'DISPLAY': ':0', 22:21:58 INFO - 'HOME': '/home/cltbld', 22:21:58 INFO - 'LANG': 'en_US.UTF-8', 22:21:58 INFO - 'LANGUAGE': 'en_US:en', 22:21:58 INFO - 'LOGNAME': 'cltbld', 22:21:58 INFO - 'MAIL': '/var/mail/cltbld', 22:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:58 INFO - 'MOZ_NO_REMOTE': '1', 22:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:58 INFO - 'NO_EM_RESTART': '1', 22:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:58 INFO - 'PWD': '/builds/slave/test', 22:21:58 INFO - 'SHELL': '/bin/bash', 22:21:58 INFO - 'SHLVL': '1', 22:21:58 INFO - 'TERM': 'linux', 22:21:58 INFO - 'TMOUT': '86400', 22:21:58 INFO - 'USER': 'cltbld', 22:21:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:58 INFO - '_': '/tools/buildbot/bin/python'} 22:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Running post-action listener: _resource_record_post_action 22:21:58 INFO - Running post-action listener: _start_resource_monitoring 22:21:59 INFO - Starting resource monitoring. 22:21:59 INFO - ##### 22:21:59 INFO - ##### Finished create-virtualenv step (success) 22:21:59 INFO - ##### 22:21:59 INFO - ##### 22:21:59 INFO - ##### Running install step. 22:21:59 INFO - ##### 22:21:59 INFO - Running pre-action listener: _resource_record_pre_action 22:21:59 INFO - Running main action method: install 22:21:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:59 INFO - Reading from file tmpfile_stdout 22:21:59 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:59 INFO - Reading from file tmpfile_stdout 22:21:59 INFO - Output received: 22:21:59 INFO - Usage: mozinstall [options] installer 22:21:59 INFO - Options: 22:21:59 INFO - -h, --help show this help message and exit 22:21:59 INFO - -d DEST, --destination=DEST 22:21:59 INFO - Directory to install application into. [default: 22:21:59 INFO - "/builds/slave/test"] 22:21:59 INFO - --app=APP Application being installed. [default: firefox] 22:21:59 INFO - mkdir: /builds/slave/test/build/application 22:21:59 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'] 22:21:59 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 22:22:16 INFO - Reading from file tmpfile_stdout 22:22:16 INFO - Output received: 22:22:16 INFO - /builds/slave/test/build/application/firefox/firefox 22:22:16 INFO - Running post-action listener: _resource_record_post_action 22:22:16 INFO - ##### 22:22:16 INFO - ##### Finished install step (success) 22:22:16 INFO - ##### 22:22:16 INFO - ##### 22:22:16 INFO - ##### Running run-tests step. 22:22:16 INFO - ##### 22:22:16 INFO - Running pre-action listener: _resource_record_pre_action 22:22:16 INFO - Running main action method: run_tests 22:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:16 INFO - Python 2.7.3 22:22:16 INFO - Return code: 0 22:22:16 INFO - grabbing minidump binary from tooltool 22:22:16 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')]} 22:22:16 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 0xe6d3b0>, '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 0xe6e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe6e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:16 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 22:22:16 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 22:22:16 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 22:22:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:16 INFO - Return code: 0 22:22:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:22:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:22:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 22:22:16 INFO - Using env: {'DISPLAY': ':0', 22:22:16 INFO - 'HOME': '/home/cltbld', 22:22:16 INFO - 'LANG': 'en_US.UTF-8', 22:22:16 INFO - 'LANGUAGE': 'en_US:en', 22:22:16 INFO - 'LOGNAME': 'cltbld', 22:22:16 INFO - 'MAIL': '/var/mail/cltbld', 22:22:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:16 INFO - 'MOZ_NO_REMOTE': '1', 22:22:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:16 INFO - 'NO_EM_RESTART': '1', 22:22:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:16 INFO - 'PWD': '/builds/slave/test', 22:22:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:16 INFO - 'SHELL': '/bin/bash', 22:22:16 INFO - 'SHLVL': '1', 22:22:16 INFO - 'TERM': 'linux', 22:22:16 INFO - 'TMOUT': '86400', 22:22:16 INFO - 'USER': 'cltbld', 22:22:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520', 22:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:16 INFO - '_': '/tools/buildbot/bin/python'} 22:22:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:22:16 INFO - mozversion application_buildid: 20160713193238 22:22:16 INFO - mozversion application_changeset: c3792b13e44350100962143db01c7ff6e15a7ee1 22:22:16 INFO - mozversion application_display_name: Nightly 22:22:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:16 INFO - mozversion application_name: Firefox 22:22:16 INFO - mozversion application_remotingname: firefox 22:22:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:22:16 INFO - mozversion application_vendor: Mozilla 22:22:16 INFO - mozversion application_version: 50.0a1 22:22:16 INFO - mozversion platform_buildid: 20160713193238 22:22:16 INFO - mozversion platform_changeset: c3792b13e44350100962143db01c7ff6e15a7ee1 22:22:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:22:16 INFO - mozversion platform_version: 50.0a1 22:22:16 INFO - using testdate: 1468473736 22:22:16 INFO - actual date: 1468473736 22:22:16 INFO - starting webserver on 'localhost:47286' 22:22:16 INFO - SUITE-START | Running 2 tests 22:22:16 INFO - TEST-START | dromaeo_css 22:22:16 INFO - operating with platform_type : linux_ 22:22:16 INFO - Initialising browser for dromaeo_css test... 22:22:16 INFO - TEST-INFO | started process 12299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjrOYbn/profile http://localhost:47286/getInfo.html) 22:22:20 INFO - PROCESS | 12299 | __metrics Screen width/height:1600/1200 22:22:20 INFO - PROCESS | 12299 | colorDepth:24 22:22:20 INFO - PROCESS | 12299 | Browser inner width/height: 1024/697 22:22:20 INFO - PROCESS | 12299 | __metrics 22:22:21 INFO - PROCESS | 12299 | 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" 22:22:21 INFO - PROCESS | 12299 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:22:22 INFO - PROCESS | 12299 | console.error: PushService: 22:22:22 INFO - PROCESS | 12299 | stateChangeProcessEnqueue: Error transitioning state 22:22:22 INFO - PROCESS | 12299 | UnknownError 22:22:22 INFO - TEST-INFO | 12299: exit 0 22:22:22 INFO - Browser initialized. 22:22:22 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:22 INFO - TEST-INFO | started process 12382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjrOYbn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:34 INFO - PROCESS | 12382 | RSS: Main: 157655040 22:22:34 INFO - PROCESS | 12382 | 22:23:25 INFO - PROCESS | 12382 | 1468473805846 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] 22:25:33 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47286/tests/dromaeo/cssquery-ext.html) 22:25:34 INFO - PROCESS | 12382 | RSS: Main: 248672256 22:25:34 INFO - PROCESS | 12382 | 22:28:37 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-ext.html (next: http://localhost:47286/tests/dromaeo/cssquery-jquery.html) 22:28:37 INFO - PROCESS | 12382 | RSS: Main: 236998656 22:28:37 INFO - PROCESS | 12382 | 22:31:39 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47286/tests/dromaeo/cssquery-mootools.html) 22:31:40 INFO - PROCESS | 12382 | RSS: Main: 243515392 22:31:40 INFO - PROCESS | 12382 | 22:34:42 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47286/tests/dromaeo/cssquery-prototype.html) 22:34:42 INFO - PROCESS | 12382 | RSS: Main: 234790912 22:34:42 INFO - PROCESS | 12382 | 22:37:45 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47286/tests/dromaeo/cssquery-yui.html) 22:37:46 INFO - PROCESS | 12382 | RSS: Main: 225206272 22:37:46 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | Cycle 1(1): loaded http://localhost:47286/tests/dromaeo/cssquery-yui.html (next: http://localhost:47286/tests/dromaeo/cssquery-dojo.html) 22:40:48 INFO - PROCESS | 12382 | RSS: Main: 292646912 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | __start_tp_report 22:40:48 INFO - PROCESS | 12382 | _x_x_mozilla_page_load 22:40:48 INFO - PROCESS | 12382 | _x_x_mozilla_page_load_details 22:40:48 INFO - PROCESS | 12382 | |i|pagename|runs| 22:40:48 INFO - PROCESS | 12382 | |0;dojo.html;3028.6214223222096;3052.3284877326982;3080.3993221321853;3087.5986121804153;3096.659367469578;5183.403908550517;5228.764705588258;5334.119870221413;5384.380796208432;5430.972845135774;5267.288915996349;5285;5385.138377859544;5432.239486471897;5475.0418676731515;4761.190597598416;4769.165396055694;4788.712677239359;4794.256890182028;4975.552200301972;4870.178225686335;4874.098291816008;4885.340479035338;4939.234418665113;4969.080720066781;4635.490096089427;4792.616590672737;4826.782794774226;4829.565339119461;4831.95168048322;4852.611791056707;4883.19427294496;4883.682560633583;4893.804247830082;4934.777934992934;4779.091972525209;4864.975675121602;4891.828785992473;4900.534449227353;4927.482614325516;5240.528352448298;5300.522952934216;5310.70791106489;5378.220158077096;5378.381486129067;5719.456651618131;5740.5981581289325;5745.3105627324985;5767.538596912237;5770.278715160605;5516.172574113915;5549.6115271930985;5730.799422020251;5792.507636850831;5796.275465566804;5202.219667049935;5311.707856067915;5312.548433383167;5313.495217954288;5314.813981510667;5819.883602328015;5825.854353641108;5828.242328497268;5828.475437210672;5832.1834943108;5536.612437129443;5539.584531160179;5573.359063707644;5590.86022849432;5593.216949627055;5736.225609542742;5740.770369185186;5749.683767392834;5757.539396848242;5763;5687.40282270364;5695.487406133468;5696.060150075274;5699.316082070178;5732;5445.101558242845;5587.776488940478;5610.046292130348;5646.20953066571;5693.459121383545;4307.440032795716;4320.43834301545;4338.219120558267;4338.6312163466455;4375;3869.2261547690573;3882.5340959085092;3887.7667339959694;3915.941260881089;3919;5245.449227831099;5252.501012403815;5269.55208807248;5302.3637163540625;5331.893362132736;5633.8591535211945;5643.322801263875;5650.4349565043985;5653.717314134276;5714.399988001283;5316.46835316473;5322;5322.866928326823;5324.9733751331;5368.141097424394;4732.337472753818;4742.1227072991605;4743.833965811249;4753.096911586857;4782.593479554207;5798.144593729966;5836.562257830679;5842.970785146047;5844.240248767718;5857.09215071656;5606.523445507014;5680.233168522197;5805.10020946754;5807.8548036299435;5817.592768506164;5830.591858569859;5852.122181672783;5855.648661080349;5884.911726324194;5920.763169473345;5622.634528755618;5636.2954630671165;5638.633488823213;5643.887122257614;5644.604877658689;5490.752916118864;5603;5691.26013618227;5702.230198923092;5709.857253568694;5457.754401052041;5570.387257401601;5581.49766521015;5616.943830561642;5633.295838020247;5542.168674698827;5556.638818476786;5558.388577256418;5558.610897237155;5565.7217139143695;5317.361916570037;5393.784248630011;5447.918281225864;5518.227448157181;5556.249906262603;5413.971345444353;5507.476789704971;5529;5567.081431563746;5570;5336.359636843604;5380.1391777317;5381.461853814744;5386.596005299697;5388.46115388458 22:40:48 INFO - PROCESS | 12382 | |1;ext.html;606.7300051477092;616.5221952986435;643.1381948189427;646.0986923242077;652.3085529338904;33202.66797332026;43328.56671433289;44462.33306500405;44585;44601.77699111508;47046.52953470464;47078;47107;47317.29024064633;47493;8035.357171426301;9021.503817290046;9176.8623470648;9178.357514973955;10786.784264314709;11054.502547385347;11156.497957591928;11168.609098681547;11178.105751539857;11179.329240245612;11156.10751139911;11194.832077518844;11201.551937922513;11222.326660400362;11226.887731122706;8158.714444994397;10738.838917416246;10930.890691093067;10971.177161712903;11147.609833655824;10937.890621093768;10962.671119866416;10980.341179529174;11019.779604407948;11022.944885275523;43502.34746478805;43807.78096109499;43870.12259754819;43878.34182487265;43895.78052109751;2767.5571908494544;3517.788932664023;3775.981120094382;4119.340905455113;4384.16700826845;33124;33816.66183338184;34136;34178.316433671185;34294.48558271603;4561;4579.656525760548;4719.4100737407825;4779;4786.760661966922;356346;357554;358213;359246;361961;59893.70053149707;60531;60841.39158608446;60976;60995.69502152461;61888.071678924854;62336;62442.375576244565;62569.68715156395;62617.37382626207;16717.414890478776;17314.65370692604;17389.82610173882;17392.47822565325;17397.739033914502;13480;13501.52744653952;13521.18872867631;13532.594022179153;13535.32323383827;16811.243494042796;17423.39018134359;17423.47729568115;17453;17511.562210944572;5584.52531534816;6750.569794873842;6809.897851532231;6851.862962740817;7036.824079398056;970.96601618943;1006.2151521812998;1045.1951996962448;1098.6319582939805;1117.9720506987228;33011.66988330134;33215.83392083024;33261.3347733044;33476.83261583725;33498.49752253718;1038.413296487482;1065.0893486069454;1070.8393740938766;1125.2517076144436;1186.1519013904926;17219.65560688779;17631.8539294948;17642.294308227785;17712.291508339524;17751.822481775274;506229;507834;509285;509699;514593;59216;59277;59361;59695.403045969855;59709.402905971256;1234.5617305856472;1321.6695826043297;1370.8354997400377;1390.6731917999261;1405.416752047909;29817.403651927274;34164;34421.65578344184;34585.135371615914;34998.47502287519;56380;57828.710856447135;57848;63164;63169.05246421399;488105;499572;499837;500618;501442;24544.87727561422;27508.862455687595;28024.299392514535;28342.85828570844;28589;51064.48935510597;51325;51414.48585514097;52106.739466302424;52613.7369313151;42263;43163.35254971241;43323.133537329704;43396.56603434052;44316.778416107714;42801.571984279755;43063.13873722571;43319.35020974625;43819.12361752811;44272.33591496194;42133.367999480644;42488;42618.57381426146;43299.35050974301;43319.783401082794;37398.626013739515;37701.81149094237;38046.42930355992;38313.319186119086;38837.41743873901;28570.720537652065;28710.554733432124;29043.12870613885;29103;29149.7085029147 22:40:48 INFO - PROCESS | 12382 | |2;jquery.html;367.45983404395565;369.5620689482963;370.4628288980978;380.7924681048828;395.8950878017324;11804.58683946062;12420.627381178556;12437.937810310947;12439.378031098431;18886.24455021799;20659.586808263826;22255;22569.501727232266;22665.20671776484;23007;6414.486841052716;6530.346965303479;6614.272430032716;6615.768448104317;6616.801495955105;6410.9358906411035;6415.1981002374705;6445.645489498076;6451.06459563363;6455.031745238205;6400.583962042476;6482.060101285311;6538.6076835389795;6550.967245163767;6606.603603783764;6388.456981156607;6500.674966251716;6523.673816309166;6548.6398248096575;6598;6244.656543890084;6314.684265786692;6359.87280254397;6369.71336289868;6372.044193370985;26931.73068269312;27196.728032719617;27550.5867411989;27927.022554210504;28122.43755124886;2741.0817376179;2875;2904.3174853909313;3015.929345082486;3048.832314222717;16082.276297566637;16514.2568584414;17013.914930425268;17187.3984410545;17246.310147594082;1917.3864363403716;1976.8813871167777;2131.1688441507827;2209.2397264371075;2249.3701763506247;73395;81720.59139704323;96275.0372496273;106647.46676266646;108897;17348.826511734973;18184.90907545454;18547.629047418977;18652.533686657942;18749.906250468932;27756.444871102463;29976.85011574972;33296.50055249173;33326.8333658335;33385;10112.646057387956;10470.424126672953;10606.363618182933;10775.191860610486;10830.133589312836;8883.73348799537;8984.191422771983;8991.640334386682;9134.954325228331;9310.767230819149;9251.814963700823;9252.90747092534;9304.813903722023;9334.579943902541;9355.578998945064;3528.2237907660274;3578.624244454347;3596.3346780845627;3600.279944011208;3755.3803622402543;895.6865097216053;990.64336838738;1036.4817591204398;1066.3069005146563;1111.216592302441;18216.726749098772;19851.503712407015;19990.800091999186;20544.280950166973;20764.403312613;980.127686359141;983.5229913491951;988.8566157907208;1004.969850904474;1075.876274228458;10826.512806923707;11230.494627741771;11243.943780281046;11339.659810205707;11391.658250252505;461999;466194;467631;470809;472404;55516.72241638766;57942;73494.63252683703;91811.99999999866;93169.61473467421;976.4043933200742;983.4984158079288;996.4868092932146;1004.0712341084613;1004.1364426593124;17580.945143291443;17720.822791771916;18144.72782908231;18155.36456224026;18521.259149634403;34002.41622435151;35797.642023580476;42398.36402454027;42532.36201456919;42714.57285427106;58921;60020.69198782349;60778.08832867422;61692.38307617046;61713.38286617077;8327.875081873897;8381.245687888118;8449.704260350858;8529.104444033288;8538.402311838112;12839.358032098544;13804.37880295369;13840.23878686682;14122.082064665763;14206.78689819633;12258.632241032783;12307.384630768247;12331.198472099284;12431.564895228623;12476.376181190722;11708.414579271173;11720.589779358022;11874.346910919983;12229.571964981184;12242.75514489723;12094.637160885188;12280.201786883825;12458;12471.314077725812;12553;8313.83372332562;8399.790005249917;8555.61499732501;8564.828703426021;8823.602937867683;11774;11837.881621184022;11871.76256474883;11904.107191960464;12313.692157696129 22:40:48 INFO - PROCESS | 12382 | |3;mootools.html;1511.53142525817;1536.101380692295;1704.4971733338664;1802.053921691113;1837.751903493028;8506.362022848289;9015.098490150982;9092.590833412496;9095.590698418571;9107.908920910797;7514.211007844166;7732.806679833007;7735.4585179037485;7942.245486678771;9163.450192988425;2246.225052356934;2354.7174339079334;2356.3637817789186;2356.7761062699014;2368.810495160392;2381.3808409813487;2404.230646193219;2417.7703118203744;2445.327534927891;2488.253523942819;2119.0782009825775;2320.385097949036;2419.52819200256;2437.536867995075;2440.8779561021965;2382.821288403368;2410.6866107405926;2423.575874222004;2426.8786560671983;2427.1990243219784;2335.3344296875307;2351.7648235176516;2373.6795532603223;2377.3699969508093;2379.1553998330514;8692.43499172558;8692.521911294876;8738.388312818106;8756.605952732143;8759.430637008574;5372.650777699437;5497.725113744336;5503.862403439906;5507.421720719307;5508.03609368359;3488.8081155536433;3499.9475007874903;3539.788105720365;3605.170810713524;3648.5986541480415;1121.545773961547;1135.7444574970648;1145.9656210313703;1147.4836323654306;1162.4071723421116;12101.818472722829;12304;12320.137590368679;12325.075619328496;12333.938330308381;4631.675782695246;4719.551642593948;4813.687110337817;4844.539768721966;4888.340074090023;10521.158307335394;10524.947375263075;10533.36799792015;10533.894661053444;10536.789264214673;5455.34535855692;5468.945310546844;5471.945280547223;5472.5621950243885;5474.698891560923;4903.987298163589;4996.800127994839;5007.474215207423;5137;5144.048351055067;4092.202020606013;4155.206286836935;4168.687348448879;4215.304474761691;4320.503142138694;1302.4724986380531;1305.0068331469747;1317.6244770240435;1331.4341404902877;1331.5539294336313;822.6257053040852;849.2356878808998;850.1243718969471;974.206022092007;988.6292640259902;5769.221155144086;5883.676397798162;5938.82183534486;5999.670018148957;6059.394060593905;988.2094324540338;988.945607991553;998.3676688614169;999.6001599360314;1005.1054561440324;3999.960020389564;4073.1853629274265;4129.97935010323;4159.729617574848;4227.725197862129;10856.674299771019;11104.111671066295;11113.999740023437;11141.498632561392;11187.160962927814;10865.674029779118;11054.447277636085;11063.059639930696;11070;11073.169512286611;8305.211004954519;8447.02859171203;8465.999999999876;8470.83058338842;8474.1949514796;5973.30777907558;5993.130996005642;6039.184710064085;6137.232845894263;6303.243610766737;10397.792044158923;10481.951606505416;10602.734931626772;10638.84041739359;10655.30740501865;8717.869231961653;10459.529321180413;10484.790304194026;10512.57949682004;10560.84158737635;7181.317774811385;7367.963160184165;7376.668049937658;7551.131619863786;7553.055868016498;8530;8575.914240857512;8585.527795971031;8619.8707019396;8634.654613815377;7733.92266077332;7815.413843961839;7832;7895.447318687637;7908.209179082046;7911.406644501801;7935.880961785462;7981.241782030698;8011.238932301422;8092.028956525254;7246.384057355184;7312;7326.597037162794;7327.706891724271;7335;5246.186841039645;5350.839474815762;5437.048516509674;5494.697791621339;5526.55787536988;5159.148740457783;5259.289995850511;5298.072837253542;5300.390455097713;5306.442823503631 22:40:48 INFO - PROCESS | 12382 | |4;prototype.html;300.8796481407437;302.6217228464418;304.274305780713;315.44009383344576;316.7545152506808;5135.152699804527;5265.262863199146;5408.945910540899;5434.103372943465;5478.7534560944805;5376.193570964348;5388.299521062257;5394.865128371793;5401.081816091264;5409.350877894658;4201.831926722943;4204.369344598304;4208.705390622657;4209.452771139755;4210.452641156646;3987.302222111119;4073.4500842386196;4164.396162556428;4213.7893105344765;4241.745495270278;4118.670506359484;4134.855280065199;4139.523954745197;4143.399207114967;4156.542780294165;4120.072983578701;4120.278951183531;4125.381192821086;4127.711060225785;4154.771487568182;3441.174118211636;3637.418013117915;3657.2685462907257;3661.139632186434;3662.963370366289;5160.458151894072;5160.948390516084;5179.326687530634;5192.792288308463;5208.192730126836;4670.276107203389;4714.175019371596;4717.551832575934;4722.173619616553;4737.313089602023;5414.620976531644;5523.502884740353;5552.944470555323;5568.693721845297;5580.693061881595;3929.567747547796;3933.3706606943038;3939.409088636699;3945.4673619061346;3970.563238043813;5486.259354987105;5681.715914204315;5815.418458154193;5832.1834943108;5839.503642190419;3855.248226595787;3860.6911447084167;3868.3230434674383;3927.626875446885;4024.5170579530645;4030.556638769733;4035.0921042765144;4041.8585349512628;4043.4137050127565;4067.450894129314;4571.428571428572;4668.486466488752;4673.883152921204;4677.579017888406;4684.8360307389075;5591.67945302703;5597.44025597437;5604.467575580314;5610.4670056344585;5620.5503559715125;1367.712780316125;1385.9722805544034;1387.7571425000585;1388.340538244326;1389.9027068105136;841.9747407577782;877.368294827723;877.9473231605996;896.6996056321075;896.9596368163449;4820.879478013007;4873.7824289947985;4879.316895634614;4880.438749543848;4886.21820508724;4924.630652701062;5019;5074.416442109205;5092.9236061459105;5102.413222479462;4600.596817970484;4789.108302220603;4823.421189457287;4834.419869615669;4878.390201224847;2689.655172413793;2711.25440503863;2715.2804506805714;2726.945461090767;2743.8079334446797;5199.1161502544655;5207.218917162456;5229.6989324697015;5265.842024739264;5268.683878967274;5005.74971251436;5111.948880511147;5144.331236939174;5167.586593072545;5176.197689358156;5105.289727941107;5133.947540754137;5142.768575414116;5189.532942035235;5196.818111366084;4909.288153217692;5045.697258164522;5065.290859279778;5100.265909590757;5104.489551044866;4769.66612337133;5038.345015148007;5084.627150669373;5093.592512599057;5114.693118412907;5259.4477579853565;5690.2887139107615;5859.9121013183985;5879.559033072622;5883.705814709162;3892.8832135035996;4004.279229738675;4008.699347548887;4010.3583426651594;4022.919541609093;5608.803691870929;5666.9716651416475;5701.572382071278;5727.856803579777;5759.280089988752;5546.168074788814;5586.888262234814;5587.385387607279;5592.804251851166;5616.21373007771;5491.341039075336;5551.472610102034;5556.305461817273;5570.832875013756;5578.330600327987;5420.7560659769615;5490;5522.530584900329;5542.612017158922;5577.776888924398;5637.43625637433;5637.9273296052925;5651.745671444713;5667.036603777286;5669.801556945487;5479.917801232905;5551.417101204315;5556.111022236423;5563.193336966198;5581.720913954205 22:40:48 INFO - PROCESS | 12382 | |5;yui.html;749.6551586270315;755.6372940988329;790.9327707144893;835.7242110103665;867.067902005344;14691.706165876689;15307.081575105474;15727.292271847766;15740.21298935053;15774.605634859134;15642.452514162007;16172.35310587578;16251.187440627951;16273.349066037345;16304.347826086972;682.5290549520839;803.7869964459423;808.5027707959621;834.1991687979547;834.4993004197494;821.5645707774889;823.4400607586839;824.7855557555049;825.5170725125779;826.1655727714991;865.4115201662867;869.3393021303791;869.3610196505564;871.2811930157613;878.7012415778634;704.5842952657931;843.2958479669514;873.8121303919656;874.226309715906;876.2464280718577;634.8285962790067;635.0759644716971;635.5646382228189;636.3382082634055;636.424036247197;16152.919235403866;16158.676826463523;16183.514494565065;16183.83816161835;16192.676146477004;14078.436862525487;14467.348969296407;14513;14708;14770.630734231623;13498;13522.985776066835;13529.32353382327;13653.317334133253;14196.148231106166;4630.72215667061;4638.8608341749805;4665.5567721066445;4728.929066064012;4764.023375208074;83210;84477;85080;85257.57371213104;85423;11277.887221127849;11279.548818047351;11409.372484513266;11484.655460336204;11544.88455115455;21535.46161345979;21801.454963625718;21826.345209643736;21901;21904.89047554752;8359.456635318686;8481.406301558955;8543.530105844238;8591.871121933176;8651.394402391898;4977.502249774993;5002.474740152305;5022.472640372708;5071.442141364447;5160.7419629018395;3622.42041273395;3706.4810926470313;3761.360568703327;3764.0778009387327;3766.3597188478025;702.8348338140532;718.1346477494607;718.9029481019995;723.352599423513;725.122601652007;582.0074290368071;612.3937302071007;640.6060272932207;702.9929700703029;716.5270921192092;9564.426134431957;9698.466584337788;9795.167410770022;9943.850842237372;10107.39355638664;673.2493270004009;681.7034589953386;711.3597762014228;720.9387202087777;739.3235189801271;4500.774961251925;4550.294704320836;4567.9086418271445;4740.218695096029;4752.14461396945;111994.44002779934;114124;114854.42572787082;117504.41247793706;117837.41081294538;102433.48783256185;106137;106492.46753766337;106717;106926.46536767422;56324.71837640786;57126.7143664279;58813.705931470926;91092.54453727689;92931;8823.779405514913;8839.867401988846;8855.690050848447;9098.772530686521;9244.075592440702;53898.461015389345;54207.728961354944;54971.72514137404;55135;56354.43645563492;74896.25103748892;78283.60858195901;80774;81175.59412202901;81319.18680813115;12203.14577979533;12393.752124957631;12507.124501284823;12542.12205145631;12664.303463309607;13297.202167869958;14286.928565357106;14372.28138593087;14464.349104290122;15140.16729079867;12962.157459765462;12970;13067.085304028627;13340.399682014528;13351.265680387673;13183.40674669623;13211.801822972471;13386.866131338562;13414.396352164371;13597.388117534932;11970.58102966392;12076.456559455022;12333.93833030829;12336.383180840743;12386.5664701735;10858.565657373616;11214.83177752318;11312.549642573667;11407.486663100346;11632.825507617223;11146.944265278622;11385.601503947653;11447.542098316308;11505.194636375374;11582.594609188638 22:40:48 INFO - PROCESS | 12382 | __end_tp_report 22:40:48 INFO - PROCESS | 12382 | __start_cc_report 22:40:48 INFO - PROCESS | 12382 | _x_x_mozilla_cycle_collect,852 22:40:48 INFO - PROCESS | 12382 | __end_cc_report 22:40:48 INFO - PROCESS | 12382 | __startTimestamp1468474848711__endTimestamp 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | ------- Summary: start ------- 22:40:48 INFO - PROCESS | 12382 | Number of tests: 6 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#0] dojo.html Cycles:165 Average:5271.07 Median:5445.10 stddev:599.36 (11.0%) stddev-sans-first:574.81 22:40:48 INFO - PROCESS | 12382 | Values: 3028.6 3052.3 3080.4 3087.6 3096.7 5183.4 5228.8 5334.1 5384.4 5431.0 5267.3 5285.0 5385.1 5432.2 5475.0 4761.2 4769.2 4788.7 4794.3 4975.6 4870.2 4874.1 4885.3 4939.2 4969.1 4635.5 4792.6 4826.8 4829.6 4832.0 4852.6 4883.2 4883.7 4893.8 4934.8 4779.1 4865.0 4891.8 4900.5 4927.5 5240.5 5300.5 5310.7 5378.2 5378.4 5719.5 5740.6 5745.3 5767.5 5770.3 5516.2 5549.6 5730.8 5792.5 5796.3 5202.2 5311.7 5312.5 5313.5 5314.8 5819.9 5825.9 5828.2 5828.5 5832.2 5536.6 5539.6 5573.4 5590.9 5593.2 5736.2 5740.8 5749.7 5757.5 5763.0 5687.4 5695.5 5696.1 5699.3 5732.0 5445.1 5587.8 5610.0 5646.2 5693.5 4307.4 4320.4 4338.2 4338.6 4375.0 3869.2 3882.5 3887.8 3915.9 3919.0 5245.4 5252.5 5269.6 5302.4 5331.9 5633.9 5643.3 5650.4 5653.7 5714.4 5316.5 5322.0 5322.9 5325.0 5368.1 4732.3 4742.1 4743.8 4753.1 4782.6 5798.1 5836.6 5843.0 5844.2 5857.1 5606.5 5680.2 5805.1 5807.9 5817.6 5830.6 5852.1 5855.6 5884.9 5920.8 5622.6 5636.3 5638.6 5643.9 5644.6 5490.8 5603.0 5691.3 5702.2 5709.9 5457.8 5570.4 5581.5 5616.9 5633.3 5542.2 5556.6 5558.4 5558.6 5565.7 5317.4 5393.8 5447.9 5518.2 5556.2 5414.0 5507.5 5529.0 5567.1 5570.0 5336.4 5380.1 5381.5 5386.6 5388.5 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#1] ext.html Cycles:180 Average:62651.48 Median:38179.87 stddev:121896.99 (319.3%) stddev-sans-first:122149.93 22:40:48 INFO - PROCESS | 12382 | Values: 606.7 616.5 643.1 646.1 652.3 33202.7 43328.6 44462.3 44585.0 44601.8 47046.5 47078.0 47107.0 47317.3 47493.0 8035.4 9021.5 9176.9 9178.4 10786.8 11054.5 11156.5 11168.6 11178.1 11179.3 11156.1 11194.8 11201.6 11222.3 11226.9 8158.7 10738.8 10930.9 10971.2 11147.6 10937.9 10962.7 10980.3 11019.8 11022.9 43502.3 43807.8 43870.1 43878.3 43895.8 2767.6 3517.8 3776.0 4119.3 4384.2 33124.0 33816.7 34136.0 34178.3 34294.5 4561.0 4579.7 4719.4 4779.0 4786.8 356346.0 357554.0 358213.0 359246.0 361961.0 59893.7 60531.0 60841.4 60976.0 60995.7 61888.1 62336.0 62442.4 62569.7 62617.4 16717.4 17314.7 17389.8 17392.5 17397.7 13480.0 13501.5 13521.2 13532.6 13535.3 16811.2 17423.4 17423.5 17453.0 17511.6 5584.5 6750.6 6809.9 6851.9 7036.8 971.0 1006.2 1045.2 1098.6 1118.0 33011.7 33215.8 33261.3 33476.8 33498.5 1038.4 1065.1 1070.8 1125.3 1186.2 17219.7 17631.9 17642.3 17712.3 17751.8 506229.0 507834.0 509285.0 509699.0 514593.0 59216.0 59277.0 59361.0 59695.4 59709.4 1234.6 1321.7 1370.8 1390.7 1405.4 29817.4 34164.0 34421.7 34585.1 34998.5 56380.0 57828.7 57848.0 63164.0 63169.1 488105.0 499572.0 499837.0 500618.0 501442.0 24544.9 27508.9 28024.3 28342.9 28589.0 51064.5 51325.0 51414.5 52106.7 52613.7 42263.0 43163.4 43323.1 43396.6 44316.8 42801.6 43063.1 43319.4 43819.1 44272.3 42133.4 42488.0 42618.6 43299.4 43319.8 37398.6 37701.8 38046.4 38313.3 38837.4 28570.7 28710.6 29043.1 29103.0 29149.7 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#2] jquery.html Cycles:180 Average:29652.55 Median:18172.67 stddev:77045.92 (424.0%) stddev-sans-first:77230.68 22:40:48 INFO - PROCESS | 12382 | Values: 367.5 369.6 370.5 380.8 395.9 11804.6 12420.6 12437.9 12439.4 18886.2 20659.6 22255.0 22569.5 22665.2 23007.0 6414.5 6530.3 6614.3 6615.8 6616.8 6410.9 6415.2 6445.6 6451.1 6455.0 6400.6 6482.1 6538.6 6551.0 6606.6 6388.5 6500.7 6523.7 6548.6 6598.0 6244.7 6314.7 6359.9 6369.7 6372.0 26931.7 27196.7 27550.6 27927.0 28122.4 2741.1 2875.0 2904.3 3015.9 3048.8 16082.3 16514.3 17013.9 17187.4 17246.3 1917.4 1976.9 2131.2 2209.2 2249.4 73395.0 81720.6 96275.0 106647.5 108897.0 17348.8 18184.9 18547.6 18652.5 18749.9 27756.4 29976.9 33296.5 33326.8 33385.0 10112.6 10470.4 10606.4 10775.2 10830.1 8883.7 8984.2 8991.6 9135.0 9310.8 9251.8 9252.9 9304.8 9334.6 9355.6 3528.2 3578.6 3596.3 3600.3 3755.4 895.7 990.6 1036.5 1066.3 1111.2 18216.7 19851.5 19990.8 20544.3 20764.4 980.1 983.5 988.9 1005.0 1075.9 10826.5 11230.5 11243.9 11339.7 11391.7 461999.0 466194.0 467631.0 470809.0 472404.0 55516.7 57942.0 73494.6 91812.0 93169.6 976.4 983.5 996.5 1004.1 1004.1 17580.9 17720.8 18144.7 18155.4 18521.3 34002.4 35797.6 42398.4 42532.4 42714.6 58921.0 60020.7 60778.1 61692.4 61713.4 8327.9 8381.2 8449.7 8529.1 8538.4 12839.4 13804.4 13840.2 14122.1 14206.8 12258.6 12307.4 12331.2 12431.6 12476.4 11708.4 11720.6 11874.3 12229.6 12242.8 12094.6 12280.2 12458.0 12471.3 12553.0 8313.8 8399.8 8555.6 8564.8 8823.6 11774.0 11837.9 11871.8 11904.1 12313.7 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#3] mootools.html Cycles:180 Average:5892.50 Median:4950.39 stddev:3305.19 (66.8%) stddev-sans-first:3298.07 22:40:48 INFO - PROCESS | 12382 | Values: 1511.5 1536.1 1704.5 1802.1 1837.8 8506.4 9015.1 9092.6 9095.6 9107.9 7514.2 7732.8 7735.5 7942.2 9163.5 2246.2 2354.7 2356.4 2356.8 2368.8 2381.4 2404.2 2417.8 2445.3 2488.3 2119.1 2320.4 2419.5 2437.5 2440.9 2382.8 2410.7 2423.6 2426.9 2427.2 2335.3 2351.8 2373.7 2377.4 2379.2 8692.4 8692.5 8738.4 8756.6 8759.4 5372.7 5497.7 5503.9 5507.4 5508.0 3488.8 3499.9 3539.8 3605.2 3648.6 1121.5 1135.7 1146.0 1147.5 1162.4 12101.8 12304.0 12320.1 12325.1 12333.9 4631.7 4719.6 4813.7 4844.5 4888.3 10521.2 10524.9 10533.4 10533.9 10536.8 5455.3 5468.9 5471.9 5472.6 5474.7 4904.0 4996.8 5007.5 5137.0 5144.0 4092.2 4155.2 4168.7 4215.3 4320.5 1302.5 1305.0 1317.6 1331.4 1331.6 822.6 849.2 850.1 974.2 988.6 5769.2 5883.7 5938.8 5999.7 6059.4 988.2 988.9 998.4 999.6 1005.1 4000.0 4073.2 4130.0 4159.7 4227.7 10856.7 11104.1 11114.0 11141.5 11187.2 10865.7 11054.4 11063.1 11070.0 11073.2 8305.2 8447.0 8466.0 8470.8 8474.2 5973.3 5993.1 6039.2 6137.2 6303.2 10397.8 10482.0 10602.7 10638.8 10655.3 8717.9 10459.5 10484.8 10512.6 10560.8 7181.3 7368.0 7376.7 7551.1 7553.1 8530.0 8575.9 8585.5 8619.9 8634.7 7733.9 7815.4 7832.0 7895.4 7908.2 7911.4 7935.9 7981.2 8011.2 8092.0 7246.4 7312.0 7326.6 7327.7 7335.0 5246.2 5350.8 5437.0 5494.7 5526.6 5159.1 5259.3 5298.1 5300.4 5306.4 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#4] prototype.html Cycles:180 Average:4529.63 Median:5093.26 stddev:1326.75 (26.0%) stddev-sans-first:1291.95 22:40:48 INFO - PROCESS | 12382 | Values: 300.9 302.6 304.3 315.4 316.8 5135.2 5265.3 5408.9 5434.1 5478.8 5376.2 5388.3 5394.9 5401.1 5409.4 4201.8 4204.4 4208.7 4209.5 4210.5 3987.3 4073.5 4164.4 4213.8 4241.7 4118.7 4134.9 4139.5 4143.4 4156.5 4120.1 4120.3 4125.4 4127.7 4154.8 3441.2 3637.4 3657.3 3661.1 3663.0 5160.5 5160.9 5179.3 5192.8 5208.2 4670.3 4714.2 4717.6 4722.2 4737.3 5414.6 5523.5 5552.9 5568.7 5580.7 3929.6 3933.4 3939.4 3945.5 3970.6 5486.3 5681.7 5815.4 5832.2 5839.5 3855.2 3860.7 3868.3 3927.6 4024.5 4030.6 4035.1 4041.9 4043.4 4067.5 4571.4 4668.5 4673.9 4677.6 4684.8 5591.7 5597.4 5604.5 5610.5 5620.6 1367.7 1386.0 1387.8 1388.3 1389.9 842.0 877.4 877.9 896.7 897.0 4820.9 4873.8 4879.3 4880.4 4886.2 4924.6 5019.0 5074.4 5092.9 5102.4 4600.6 4789.1 4823.4 4834.4 4878.4 2689.7 2711.3 2715.3 2726.9 2743.8 5199.1 5207.2 5229.7 5265.8 5268.7 5005.7 5111.9 5144.3 5167.6 5176.2 5105.3 5133.9 5142.8 5189.5 5196.8 4909.3 5045.7 5065.3 5100.3 5104.5 4769.7 5038.3 5084.6 5093.6 5114.7 5259.4 5690.3 5859.9 5879.6 5883.7 3892.9 4004.3 4008.7 4010.4 4022.9 5608.8 5667.0 5701.6 5727.9 5759.3 5546.2 5586.9 5587.4 5592.8 5616.2 5491.3 5551.5 5556.3 5570.8 5578.3 5420.8 5490.0 5522.5 5542.6 5577.8 5637.4 5637.9 5651.7 5667.0 5669.8 5479.9 5551.4 5556.1 5563.2 5581.7 22:40:48 INFO - PROCESS | 12382 | 22:40:48 INFO - PROCESS | 12382 | [#5] yui.html Cycles:180 Average:21130.77 Median:4652.21 stddev:30575.29 (657.2%) stddev-sans-first:30622.77 22:40:48 INFO - PROCESS | 12382 | Values: 749.7 755.6 790.9 835.7 867.1 14691.7 15307.1 15727.3 15740.2 15774.6 15642.5 16172.4 16251.2 16273.3 16304.3 682.5 803.8 808.5 834.2 834.5 821.6 823.4 824.8 825.5 826.2 865.4 869.3 869.4 871.3 878.7 704.6 843.3 873.8 874.2 876.2 634.8 635.1 635.6 636.3 636.4 16152.9 16158.7 16183.5 16183.8 16192.7 14078.4 14467.3 14513.0 14708.0 14770.6 13498.0 13523.0 13529.3 13653.3 14196.1 4630.7 4638.9 4665.6 4728.9 4764.0 83210.0 84477.0 85080.0 85257.6 85423.0 11277.9 11279.5 11409.4 11484.7 11544.9 21535.5 21801.5 21826.3 21901.0 21904.9 8359.5 8481.4 8543.5 8591.9 8651.4 4977.5 5002.5 5022.5 5071.4 5160.7 3622.4 3706.5 3761.4 3764.1 3766.4 702.8 718.1 718.9 723.4 725.1 582.0 612.4 640.6 703.0 716.5 9564.4 9698.5 9795.2 9943.9 10107.4 673.2 681.7 711.4 720.9 739.3 4500.8 4550.3 4567.9 4740.2 4752.1 111994.4 114124.0 114854.4 117504.4 117837.4 102433.5 106137.0 106492.5 106717.0 106926.5 56324.7 57126.7 58813.7 91092.5 92931.0 8823.8 8839.9 8855.7 9098.8 9244.1 53898.5 54207.7 54971.7 55135.0 56354.4 74896.3 78283.6 80774.0 81175.6 81319.2 12203.1 12393.8 12507.1 12542.1 12664.3 13297.2 14286.9 14372.3 14464.3 15140.2 12962.2 12970.0 13067.1 13340.4 13351.3 13183.4 13211.8 13386.9 13414.4 13597.4 11970.6 12076.5 12333.9 12336.4 12386.6 10858.6 11214.8 11312.5 11407.5 11632.8 11146.9 11385.6 11447.5 11505.2 11582.6 22:40:48 INFO - PROCESS | 12382 | -------- Summary: end -------- 22:40:48 INFO - PROCESS | 12382 | 22:40:49 INFO - TEST-INFO | 12382: exit 0 22:40:49 INFO - TEST-OK | dromaeo_css | took 1112711ms 22:40:49 INFO - TEST-START | kraken 22:40:49 INFO - operating with platform_type : linux_ 22:40:49 INFO - Initialising browser for kraken test... 22:40:49 INFO - TEST-INFO | started process 12576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI0A6nn/profile http://localhost:47286/getInfo.html) 22:40:53 INFO - PROCESS | 12576 | __metrics Screen width/height:1600/1200 22:40:53 INFO - PROCESS | 12576 | colorDepth:24 22:40:53 INFO - PROCESS | 12576 | Browser inner width/height: 1024/697 22:40:53 INFO - PROCESS | 12576 | __metrics 22:40:54 INFO - PROCESS | 12576 | 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" 22:40:54 INFO - PROCESS | 12576 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:40:55 INFO - TEST-INFO | 12576: exit 0 22:40:55 INFO - Browser initialized. 22:40:55 INFO - Running cycle 1/1 for kraken test... 22:40:55 INFO - TEST-INFO | started process 12660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI0A6nn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:41:07 INFO - PROCESS | 12660 | RSS: Main: 157700096 22:41:07 INFO - PROCESS | 12660 | 22:41:09 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47286/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:10 INFO - PROCESS | 12660 | RSS: Main: 215953408 22:41:10 INFO - PROCESS | 12660 | 22:41:13 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47286/tests/kraken/driver.html?testName=audio-dft) 22:41:13 INFO - PROCESS | 12660 | RSS: Main: 203988992 22:41:13 INFO - PROCESS | 12660 | 22:41:16 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47286/tests/kraken/driver.html?testName=audio-fft) 22:41:17 INFO - PROCESS | 12660 | RSS: Main: 269639680 22:41:17 INFO - PROCESS | 12660 | 22:41:19 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47286/tests/kraken/driver.html?testName=audio-oscillator) 22:41:20 INFO - PROCESS | 12660 | RSS: Main: 180211712 22:41:20 INFO - PROCESS | 12660 | 22:41:22 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:22 INFO - PROCESS | 12660 | RSS: Main: 185749504 22:41:22 INFO - PROCESS | 12660 | 22:41:25 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47286/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:26 INFO - PROCESS | 12660 | RSS: Main: 220069888 22:41:26 INFO - PROCESS | 12660 | 22:41:29 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47286/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:30 INFO - PROCESS | 12660 | RSS: Main: 221765632 22:41:30 INFO - PROCESS | 12660 | 22:41:33 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47286/tests/kraken/driver.html?testName=json-parse-financial) 22:41:33 INFO - PROCESS | 12660 | RSS: Main: 221392896 22:41:33 INFO - PROCESS | 12660 | 22:41:35 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:35 INFO - PROCESS | 12660 | RSS: Main: 209100800 22:41:35 INFO - PROCESS | 12660 | 22:41:37 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:38 INFO - PROCESS | 12660 | RSS: Main: 212496384 22:41:38 INFO - PROCESS | 12660 | 22:41:40 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:40 INFO - PROCESS | 12660 | RSS: Main: 211820544 22:41:40 INFO - PROCESS | 12660 | 22:41:43 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:44 INFO - PROCESS | 12660 | RSS: Main: 224534528 22:41:44 INFO - PROCESS | 12660 | 22:41:46 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:47 INFO - PROCESS | 12660 | RSS: Main: 224141312 22:41:47 INFO - PROCESS | 12660 | 22:41:48 INFO - PROCESS | 12660 | Cycle 1(1): loaded http://localhost:47286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47286/tests/kraken/driver.html?testName=ai-astar) 22:41:49 INFO - PROCESS | 12660 | RSS: Main: 266977280 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | __start_tp_report 22:41:49 INFO - PROCESS | 12660 | _x_x_mozilla_page_load 22:41:49 INFO - PROCESS | 12660 | _x_x_mozilla_page_load_details 22:41:49 INFO - PROCESS | 12660 | |i|pagename|runs| 22:41:49 INFO - PROCESS | 12660 | |0;ai-astar;101;110;112;110;112;111;110;110;109;110 22:41:49 INFO - PROCESS | 12660 | |1;audio-beat-detection;141;116;119;176;120;117;147;117;146;118 22:41:49 INFO - PROCESS | 12660 | |2;audio-dft;160;159;160;160;159;160;158;159;162;161 22:41:49 INFO - PROCESS | 12660 | |3;audio-fft;75;74;74;116;75;75;73;75;100;75 22:41:49 INFO - PROCESS | 12660 | |4;audio-oscillator;83;84;115;83;84;83;117;84;83;130 22:41:49 INFO - PROCESS | 12660 | |5;imaging-gaussian-blur;117;117;116;117;116;117;118;117;117;117 22:41:49 INFO - PROCESS | 12660 | |6;imaging-darkroom;146;146;146;146;146;147;147;145;146;145 22:41:49 INFO - PROCESS | 12660 | |7;imaging-desaturate;96;96;96;96;95;95;95;95;96;95 22:41:49 INFO - PROCESS | 12660 | |8;json-parse-financial;52;53;53;53;52;53;53;53;55;51 22:41:49 INFO - PROCESS | 12660 | |9;json-stringify-tinderbox;62;63;62;63;63;62;63;63;63;63 22:41:49 INFO - PROCESS | 12660 | |10;stanford-crypto-aes;80;80;74;75;83;75;74;74;77;78 22:41:49 INFO - PROCESS | 12660 | |11;stanford-crypto-ccm;121;118;119;118;120;119;119;127;209;129 22:41:49 INFO - PROCESS | 12660 | |12;stanford-crypto-pbkdf2;157;154;155;153;153;154;154;155;156;155 22:41:49 INFO - PROCESS | 12660 | |13;stanford-crypto-sha256-iterative;63;62;63;61;61;62;61;61;60;61 22:41:49 INFO - PROCESS | 12660 | __end_tp_report 22:41:49 INFO - PROCESS | 12660 | __start_cc_report 22:41:49 INFO - PROCESS | 12660 | _x_x_mozilla_cycle_collect,1669 22:41:49 INFO - PROCESS | 12660 | __end_cc_report 22:41:49 INFO - PROCESS | 12660 | __startTimestamp1468474909190__endTimestamp 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | ------- Summary: start ------- 22:41:49 INFO - PROCESS | 12660 | Number of tests: 14 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:3.14 (2.9%) stddev-sans-first:1.01 22:41:49 INFO - PROCESS | 12660 | Values: 101.0 110.0 112.0 110.0 112.0 111.0 110.0 110.0 109.0 110.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:130.50 stddev:20.14 (15.4%) stddev-sans-first:21.08 22:41:49 INFO - PROCESS | 12660 | Values: 141.0 116.0 119.0 176.0 120.0 117.0 147.0 117.0 146.0 118.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 22:41:49 INFO - PROCESS | 12660 | Values: 160.0 159.0 160.0 160.0 159.0 160.0 158.0 159.0 162.0 161.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:14.63 (19.5%) stddev-sans-first:15.35 22:41:49 INFO - PROCESS | 12660 | Values: 75.0 74.0 74.0 116.0 75.0 75.0 73.0 75.0 100.0 75.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:83.00 stddev:18.40 (22.2%) stddev-sans-first:19.03 22:41:49 INFO - PROCESS | 12660 | Values: 83.0 84.0 115.0 83.0 84.0 83.0 117.0 84.0 83.0 130.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 22:41:49 INFO - PROCESS | 12660 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 118.0 117.0 117.0 117.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 22:41:49 INFO - PROCESS | 12660 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 147.0 145.0 146.0 145.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:41:49 INFO - PROCESS | 12660 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.03 (1.9%) stddev-sans-first:1.05 22:41:49 INFO - PROCESS | 12660 | Values: 52.0 53.0 53.0 53.0 52.0 53.0 53.0 53.0 55.0 51.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#9] json-stringify-tinderbox Cycles:10 Average:62.70 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 22:41:49 INFO - PROCESS | 12660 | Values: 62.0 63.0 62.0 63.0 63.0 62.0 63.0 63.0 63.0 63.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.50 stddev:3.16 (4.1%) stddev-sans-first:3.16 22:41:49 INFO - PROCESS | 12660 | Values: 80.0 80.0 74.0 75.0 83.0 75.0 74.0 74.0 77.0 78.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:120.50 stddev:28.05 (23.3%) stddev-sans-first:29.57 22:41:49 INFO - PROCESS | 12660 | Values: 121.0 118.0 119.0 118.0 120.0 119.0 119.0 127.0 209.0 129.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.00 stddev:1.26 (0.8%) stddev-sans-first:1.00 22:41:49 INFO - PROCESS | 12660 | Values: 157.0 154.0 155.0 153.0 153.0 154.0 154.0 155.0 156.0 155.0 22:41:49 INFO - PROCESS | 12660 | 22:41:49 INFO - PROCESS | 12660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:0.97 (1.6%) stddev-sans-first:0.87 22:41:49 INFO - PROCESS | 12660 | Values: 63.0 62.0 63.0 61.0 61.0 62.0 61.0 61.0 60.0 61.0 22:41:49 INFO - PROCESS | 12660 | -------- Summary: end -------- 22:41:49 INFO - PROCESS | 12660 | 22:41:50 INFO - TEST-INFO | 12660: exit 0 22:41:50 INFO - TEST-OK | kraken | took 60741ms 22:41:50 INFO - SUITE-END | took 1173s 22:41:50 INFO - Completed test suite (00:19:33) 22:41:50 INFO - javascript benchmark 22:41:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3028.6214223222096, 3052.3284877326982, 3080.3993221321853, 3087.5986121804153, 3096.659367469578, 5183.403908550517, 5228.764705588258, 5334.119870221413, 5384.380796208432, 5430.972845135774, 5267.288915996349, 5285.0, 5385.138377859544, 5432.239486471897, 5475.0418676731515, 4761.190597598416, 4769.165396055694, 4788.712677239359, 4794.256890182028, 4975.552200301972, 4870.178225686335, 4874.098291816008, 4885.340479035338, 4939.234418665113, 4969.080720066781, 4635.490096089427, 4792.616590672737, 4826.782794774226, 4829.565339119461, 4831.95168048322, 4852.611791056707, 4883.19427294496, 4883.682560633583, 4893.804247830082, 4934.777934992934, 4779.091972525209, 4864.975675121602, 4891.828785992473, 4900.534449227353, 4927.482614325516, 5240.528352448298, 5300.522952934216, 5310.70791106489, 5378.220158077096, 5378.381486129067, 5719.456651618131, 5740.5981581289325, 5745.3105627324985, 5767.538596912237, 5770.278715160605, 5516.172574113915, 5549.6115271930985, 5730.799422020251, 5792.507636850831, 5796.275465566804, 5202.219667049935, 5311.707856067915, 5312.548433383167, 5313.495217954288, 5314.813981510667, 5819.883602328015, 5825.854353641108, 5828.242328497268, 5828.475437210672, 5832.1834943108, 5536.612437129443, 5539.584531160179, 5573.359063707644, 5590.86022849432, 5593.216949627055, 5736.225609542742, 5740.770369185186, 5749.683767392834, 5757.539396848242, 5763.0, 5687.40282270364, 5695.487406133468, 5696.060150075274, 5699.316082070178, 5732.0, 5445.101558242845, 5587.776488940478, 5610.046292130348, 5646.20953066571, 5693.459121383545, 4307.440032795716, 4320.43834301545, 4338.219120558267, 4338.6312163466455, 4375.0, 3869.2261547690573, 3882.5340959085092, 3887.7667339959694, 3915.941260881089, 3919.0, 5245.449227831099, 5252.501012403815, 5269.55208807248, 5302.3637163540625, 5331.893362132736, 5633.8591535211945, 5643.322801263875, 5650.4349565043985, 5653.717314134276, 5714.399988001283, 5316.46835316473, 5322.0, 5322.866928326823, 5324.9733751331, 5368.141097424394, 4732.337472753818, 4742.1227072991605, 4743.833965811249, 4753.096911586857, 4782.593479554207, 5798.144593729966, 5836.562257830679, 5842.970785146047, 5844.240248767718, 5857.09215071656, 5606.523445507014, 5680.233168522197, 5805.10020946754, 5807.8548036299435, 5817.592768506164, 5830.591858569859, 5852.122181672783, 5855.648661080349, 5884.911726324194, 5920.763169473345, 5622.634528755618, 5636.2954630671165, 5638.633488823213, 5643.887122257614, 5644.604877658689, 5490.752916118864, 5603.0, 5691.26013618227, 5702.230198923092, 5709.857253568694, 5457.754401052041, 5570.387257401601, 5581.49766521015, 5616.943830561642, 5633.295838020247, 5542.168674698827, 5556.638818476786, 5558.388577256418, 5558.610897237155, 5565.7217139143695, 5317.361916570037, 5393.784248630011, 5447.918281225864, 5518.227448157181, 5556.249906262603, 5413.971345444353, 5507.476789704971, 5529.0, 5567.081431563746, 5570.0, 5336.359636843604, 5380.1391777317, 5381.461853814744, 5386.596005299697, 5388.46115388458], "value": 5230.634078707131, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.7300051477092, 616.5221952986435, 643.1381948189427, 646.0986923242077, 652.3085529338904, 33202.66797332026, 43328.56671433289, 44462.33306500405, 44585.0, 44601.77699111508, 47046.52953470464, 47078.0, 47107.0, 47317.29024064633, 47493.0, 8035.357171426301, 9021.503817290046, 9176.8623470648, 9178.357514973955, 10786.784264314709, 11054.502547385347, 11156.497957591928, 11168.609098681547, 11178.105751539857, 11179.329240245612, 11156.10751139911, 11194.832077518844, 11201.551937922513, 11222.326660400362, 11226.887731122706, 8158.714444994397, 10738.838917416246, 10930.890691093067, 10971.177161712903, 11147.609833655824, 10937.890621093768, 10962.671119866416, 10980.341179529174, 11019.779604407948, 11022.944885275523, 43502.34746478805, 43807.78096109499, 43870.12259754819, 43878.34182487265, 43895.78052109751, 2767.5571908494544, 3517.788932664023, 3775.981120094382, 4119.340905455113, 4384.16700826845, 33124.0, 33816.66183338184, 34136.0, 34178.316433671185, 34294.48558271603, 4561.0, 4579.656525760548, 4719.4100737407825, 4779.0, 4786.760661966922, 356346.0, 357554.0, 358213.0, 359246.0, 361961.0, 59893.70053149707, 60531.0, 60841.39158608446, 60976.0, 60995.69502152461, 61888.071678924854, 62336.0, 62442.375576244565, 62569.68715156395, 62617.37382626207, 16717.414890478776, 17314.65370692604, 17389.82610173882, 17392.47822565325, 17397.739033914502, 13480.0, 13501.52744653952, 13521.18872867631, 13532.594022179153, 13535.32323383827, 16811.243494042796, 17423.39018134359, 17423.47729568115, 17453.0, 17511.562210944572, 5584.52531534816, 6750.569794873842, 6809.897851532231, 6851.862962740817, 7036.824079398056, 970.96601618943, 1006.2151521812998, 1045.1951996962448, 1098.6319582939805, 1117.9720506987228, 33011.66988330134, 33215.83392083024, 33261.3347733044, 33476.83261583725, 33498.49752253718, 1038.413296487482, 1065.0893486069454, 1070.8393740938766, 1125.2517076144436, 1186.1519013904926, 17219.65560688779, 17631.8539294948, 17642.294308227785, 17712.291508339524, 17751.822481775274, 506229.0, 507834.0, 509285.0, 509699.0, 514593.0, 59216.0, 59277.0, 59361.0, 59695.403045969855, 59709.402905971256, 1234.5617305856472, 1321.6695826043297, 1370.8354997400377, 1390.6731917999261, 1405.416752047909, 29817.403651927274, 34164.0, 34421.65578344184, 34585.135371615914, 34998.47502287519, 56380.0, 57828.710856447135, 57848.0, 63164.0, 63169.05246421399, 488105.0, 499572.0, 499837.0, 500618.0, 501442.0, 24544.87727561422, 27508.862455687595, 28024.299392514535, 28342.85828570844, 28589.0, 51064.48935510597, 51325.0, 51414.48585514097, 52106.739466302424, 52613.7369313151, 42263.0, 43163.35254971241, 43323.133537329704, 43396.56603434052, 44316.778416107714, 42801.571984279755, 43063.13873722571, 43319.35020974625, 43819.12361752811, 44272.33591496194, 42133.367999480644, 42488.0, 42618.57381426146, 43299.35050974301, 43319.783401082794, 37398.626013739515, 37701.81149094237, 38046.42930355992, 38313.319186119086, 38837.41743873901, 28570.720537652065, 28710.554733432124, 29043.12870613885, 29103.0, 29149.7085029147], "value": 21171.30274153877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.45983404395565, 369.5620689482963, 370.4628288980978, 380.7924681048828, 395.8950878017324, 11804.58683946062, 12420.627381178556, 12437.937810310947, 12439.378031098431, 18886.24455021799, 20659.586808263826, 22255.0, 22569.501727232266, 22665.20671776484, 23007.0, 6414.486841052716, 6530.346965303479, 6614.272430032716, 6615.768448104317, 6616.801495955105, 6410.9358906411035, 6415.1981002374705, 6445.645489498076, 6451.06459563363, 6455.031745238205, 6400.583962042476, 6482.060101285311, 6538.6076835389795, 6550.967245163767, 6606.603603783764, 6388.456981156607, 6500.674966251716, 6523.673816309166, 6548.6398248096575, 6598.0, 6244.656543890084, 6314.684265786692, 6359.87280254397, 6369.71336289868, 6372.044193370985, 26931.73068269312, 27196.728032719617, 27550.5867411989, 27927.022554210504, 28122.43755124886, 2741.0817376179, 2875.0, 2904.3174853909313, 3015.929345082486, 3048.832314222717, 16082.276297566637, 16514.2568584414, 17013.914930425268, 17187.3984410545, 17246.310147594082, 1917.3864363403716, 1976.8813871167777, 2131.1688441507827, 2209.2397264371075, 2249.3701763506247, 73395.0, 81720.59139704323, 96275.0372496273, 106647.46676266646, 108897.0, 17348.826511734973, 18184.90907545454, 18547.629047418977, 18652.533686657942, 18749.906250468932, 27756.444871102463, 29976.85011574972, 33296.50055249173, 33326.8333658335, 33385.0, 10112.646057387956, 10470.424126672953, 10606.363618182933, 10775.191860610486, 10830.133589312836, 8883.73348799537, 8984.191422771983, 8991.640334386682, 9134.954325228331, 9310.767230819149, 9251.814963700823, 9252.90747092534, 9304.813903722023, 9334.579943902541, 9355.578998945064, 3528.2237907660274, 3578.624244454347, 3596.3346780845627, 3600.279944011208, 3755.3803622402543, 895.6865097216053, 990.64336838738, 1036.4817591204398, 1066.3069005146563, 1111.216592302441, 18216.726749098772, 19851.503712407015, 19990.800091999186, 20544.280950166973, 20764.403312613, 980.127686359141, 983.5229913491951, 988.8566157907208, 1004.969850904474, 1075.876274228458, 10826.512806923707, 11230.494627741771, 11243.943780281046, 11339.659810205707, 11391.658250252505, 461999.0, 466194.0, 467631.0, 470809.0, 472404.0, 55516.72241638766, 57942.0, 73494.63252683703, 91811.99999999866, 93169.61473467421, 976.4043933200742, 983.4984158079288, 996.4868092932146, 1004.0712341084613, 1004.1364426593124, 17580.945143291443, 17720.822791771916, 18144.72782908231, 18155.36456224026, 18521.259149634403, 34002.41622435151, 35797.642023580476, 42398.36402454027, 42532.36201456919, 42714.57285427106, 58921.0, 60020.69198782349, 60778.08832867422, 61692.38307617046, 61713.38286617077, 8327.875081873897, 8381.245687888118, 8449.704260350858, 8529.104444033288, 8538.402311838112, 12839.358032098544, 13804.37880295369, 13840.23878686682, 14122.082064665763, 14206.78689819633, 12258.632241032783, 12307.384630768247, 12331.198472099284, 12431.564895228623, 12476.376181190722, 11708.414579271173, 11720.589779358022, 11874.346910919983, 12229.571964981184, 12242.75514489723, 12094.637160885188, 12280.201786883825, 12458.0, 12471.314077725812, 12553.0, 8313.83372332562, 8399.790005249917, 8555.61499732501, 8564.828703426021, 8823.602937867683, 11774.0, 11837.881621184022, 11871.76256474883, 11904.107191960464, 12313.692157696129], "value": 10439.4882662413, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.53142525817, 1536.101380692295, 1704.4971733338664, 1802.053921691113, 1837.751903493028, 8506.362022848289, 9015.098490150982, 9092.590833412496, 9095.590698418571, 9107.908920910797, 7514.211007844166, 7732.806679833007, 7735.4585179037485, 7942.245486678771, 9163.450192988425, 2246.225052356934, 2354.7174339079334, 2356.3637817789186, 2356.7761062699014, 2368.810495160392, 2381.3808409813487, 2404.230646193219, 2417.7703118203744, 2445.327534927891, 2488.253523942819, 2119.0782009825775, 2320.385097949036, 2419.52819200256, 2437.536867995075, 2440.8779561021965, 2382.821288403368, 2410.6866107405926, 2423.575874222004, 2426.8786560671983, 2427.1990243219784, 2335.3344296875307, 2351.7648235176516, 2373.6795532603223, 2377.3699969508093, 2379.1553998330514, 8692.43499172558, 8692.521911294876, 8738.388312818106, 8756.605952732143, 8759.430637008574, 5372.650777699437, 5497.725113744336, 5503.862403439906, 5507.421720719307, 5508.03609368359, 3488.8081155536433, 3499.9475007874903, 3539.788105720365, 3605.170810713524, 3648.5986541480415, 1121.545773961547, 1135.7444574970648, 1145.9656210313703, 1147.4836323654306, 1162.4071723421116, 12101.818472722829, 12304.0, 12320.137590368679, 12325.075619328496, 12333.938330308381, 4631.675782695246, 4719.551642593948, 4813.687110337817, 4844.539768721966, 4888.340074090023, 10521.158307335394, 10524.947375263075, 10533.36799792015, 10533.894661053444, 10536.789264214673, 5455.34535855692, 5468.945310546844, 5471.945280547223, 5472.5621950243885, 5474.698891560923, 4903.987298163589, 4996.800127994839, 5007.474215207423, 5137.0, 5144.048351055067, 4092.202020606013, 4155.206286836935, 4168.687348448879, 4215.304474761691, 4320.503142138694, 1302.4724986380531, 1305.0068331469747, 1317.6244770240435, 1331.4341404902877, 1331.5539294336313, 822.6257053040852, 849.2356878808998, 850.1243718969471, 974.206022092007, 988.6292640259902, 5769.221155144086, 5883.676397798162, 5938.82183534486, 5999.670018148957, 6059.394060593905, 988.2094324540338, 988.945607991553, 998.3676688614169, 999.6001599360314, 1005.1054561440324, 3999.960020389564, 4073.1853629274265, 4129.97935010323, 4159.729617574848, 4227.725197862129, 10856.674299771019, 11104.111671066295, 11113.999740023437, 11141.498632561392, 11187.160962927814, 10865.674029779118, 11054.447277636085, 11063.059639930696, 11070.0, 11073.169512286611, 8305.211004954519, 8447.02859171203, 8465.999999999876, 8470.83058338842, 8474.1949514796, 5973.30777907558, 5993.130996005642, 6039.184710064085, 6137.232845894263, 6303.243610766737, 10397.792044158923, 10481.951606505416, 10602.734931626772, 10638.84041739359, 10655.30740501865, 8717.869231961653, 10459.529321180413, 10484.790304194026, 10512.57949682004, 10560.84158737635, 7181.317774811385, 7367.963160184165, 7376.668049937658, 7551.131619863786, 7553.055868016498, 8530.0, 8575.914240857512, 8585.527795971031, 8619.8707019396, 8634.654613815377, 7733.92266077332, 7815.413843961839, 7832.0, 7895.447318687637, 7908.209179082046, 7911.406644501801, 7935.880961785462, 7981.241782030698, 8011.238932301422, 8092.028956525254, 7246.384057355184, 7312.0, 7326.597037162794, 7327.706891724271, 7335.0, 5246.186841039645, 5350.839474815762, 5437.048516509674, 5494.697791621339, 5526.55787536988, 5159.148740457783, 5259.289995850511, 5298.072837253542, 5300.390455097713, 5306.442823503631], "value": 4734.385170430304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8796481407437, 302.6217228464418, 304.274305780713, 315.44009383344576, 316.7545152506808, 5135.152699804527, 5265.262863199146, 5408.945910540899, 5434.103372943465, 5478.7534560944805, 5376.193570964348, 5388.299521062257, 5394.865128371793, 5401.081816091264, 5409.350877894658, 4201.831926722943, 4204.369344598304, 4208.705390622657, 4209.452771139755, 4210.452641156646, 3987.302222111119, 4073.4500842386196, 4164.396162556428, 4213.7893105344765, 4241.745495270278, 4118.670506359484, 4134.855280065199, 4139.523954745197, 4143.399207114967, 4156.542780294165, 4120.072983578701, 4120.278951183531, 4125.381192821086, 4127.711060225785, 4154.771487568182, 3441.174118211636, 3637.418013117915, 3657.2685462907257, 3661.139632186434, 3662.963370366289, 5160.458151894072, 5160.948390516084, 5179.326687530634, 5192.792288308463, 5208.192730126836, 4670.276107203389, 4714.175019371596, 4717.551832575934, 4722.173619616553, 4737.313089602023, 5414.620976531644, 5523.502884740353, 5552.944470555323, 5568.693721845297, 5580.693061881595, 3929.567747547796, 3933.3706606943038, 3939.409088636699, 3945.4673619061346, 3970.563238043813, 5486.259354987105, 5681.715914204315, 5815.418458154193, 5832.1834943108, 5839.503642190419, 3855.248226595787, 3860.6911447084167, 3868.3230434674383, 3927.626875446885, 4024.5170579530645, 4030.556638769733, 4035.0921042765144, 4041.8585349512628, 4043.4137050127565, 4067.450894129314, 4571.428571428572, 4668.486466488752, 4673.883152921204, 4677.579017888406, 4684.8360307389075, 5591.67945302703, 5597.44025597437, 5604.467575580314, 5610.4670056344585, 5620.5503559715125, 1367.712780316125, 1385.9722805544034, 1387.7571425000585, 1388.340538244326, 1389.9027068105136, 841.9747407577782, 877.368294827723, 877.9473231605996, 896.6996056321075, 896.9596368163449, 4820.879478013007, 4873.7824289947985, 4879.316895634614, 4880.438749543848, 4886.21820508724, 4924.630652701062, 5019.0, 5074.416442109205, 5092.9236061459105, 5102.413222479462, 4600.596817970484, 4789.108302220603, 4823.421189457287, 4834.419869615669, 4878.390201224847, 2689.655172413793, 2711.25440503863, 2715.2804506805714, 2726.945461090767, 2743.8079334446797, 5199.1161502544655, 5207.218917162456, 5229.6989324697015, 5265.842024739264, 5268.683878967274, 5005.74971251436, 5111.948880511147, 5144.331236939174, 5167.586593072545, 5176.197689358156, 5105.289727941107, 5133.947540754137, 5142.768575414116, 5189.532942035235, 5196.818111366084, 4909.288153217692, 5045.697258164522, 5065.290859279778, 5100.265909590757, 5104.489551044866, 4769.66612337133, 5038.345015148007, 5084.627150669373, 5093.592512599057, 5114.693118412907, 5259.4477579853565, 5690.2887139107615, 5859.9121013183985, 5879.559033072622, 5883.705814709162, 3892.8832135035996, 4004.279229738675, 4008.699347548887, 4010.3583426651594, 4022.919541609093, 5608.803691870929, 5666.9716651416475, 5701.572382071278, 5727.856803579777, 5759.280089988752, 5546.168074788814, 5586.888262234814, 5587.385387607279, 5592.804251851166, 5616.21373007771, 5491.341039075336, 5551.472610102034, 5556.305461817273, 5570.832875013756, 5578.330600327987, 5420.7560659769615, 5490.0, 5522.530584900329, 5542.612017158922, 5577.776888924398, 5637.43625637433, 5637.9273296052925, 5651.745671444713, 5667.036603777286, 5669.801556945487, 5479.917801232905, 5551.417101204315, 5556.111022236423, 5563.193336966198, 5581.720913954205], "value": 4098.609102421576, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.6551586270315, 755.6372940988329, 790.9327707144893, 835.7242110103665, 867.067902005344, 14691.706165876689, 15307.081575105474, 15727.292271847766, 15740.21298935053, 15774.605634859134, 15642.452514162007, 16172.35310587578, 16251.187440627951, 16273.349066037345, 16304.347826086972, 682.5290549520839, 803.7869964459423, 808.5027707959621, 834.1991687979547, 834.4993004197494, 821.5645707774889, 823.4400607586839, 824.7855557555049, 825.5170725125779, 826.1655727714991, 865.4115201662867, 869.3393021303791, 869.3610196505564, 871.2811930157613, 878.7012415778634, 704.5842952657931, 843.2958479669514, 873.8121303919656, 874.226309715906, 876.2464280718577, 634.8285962790067, 635.0759644716971, 635.5646382228189, 636.3382082634055, 636.424036247197, 16152.919235403866, 16158.676826463523, 16183.514494565065, 16183.83816161835, 16192.676146477004, 14078.436862525487, 14467.348969296407, 14513.0, 14708.0, 14770.630734231623, 13498.0, 13522.985776066835, 13529.32353382327, 13653.317334133253, 14196.148231106166, 4630.72215667061, 4638.8608341749805, 4665.5567721066445, 4728.929066064012, 4764.023375208074, 83210.0, 84477.0, 85080.0, 85257.57371213104, 85423.0, 11277.887221127849, 11279.548818047351, 11409.372484513266, 11484.655460336204, 11544.88455115455, 21535.46161345979, 21801.454963625718, 21826.345209643736, 21901.0, 21904.89047554752, 8359.456635318686, 8481.406301558955, 8543.530105844238, 8591.871121933176, 8651.394402391898, 4977.502249774993, 5002.474740152305, 5022.472640372708, 5071.442141364447, 5160.7419629018395, 3622.42041273395, 3706.4810926470313, 3761.360568703327, 3764.0778009387327, 3766.3597188478025, 702.8348338140532, 718.1346477494607, 718.9029481019995, 723.352599423513, 725.122601652007, 582.0074290368071, 612.3937302071007, 640.6060272932207, 702.9929700703029, 716.5270921192092, 9564.426134431957, 9698.466584337788, 9795.167410770022, 9943.850842237372, 10107.39355638664, 673.2493270004009, 681.7034589953386, 711.3597762014228, 720.9387202087777, 739.3235189801271, 4500.774961251925, 4550.294704320836, 4567.9086418271445, 4740.218695096029, 4752.14461396945, 111994.44002779934, 114124.0, 114854.42572787082, 117504.41247793706, 117837.41081294538, 102433.48783256185, 106137.0, 106492.46753766337, 106717.0, 106926.46536767422, 56324.71837640786, 57126.7143664279, 58813.705931470926, 91092.54453727689, 92931.0, 8823.779405514913, 8839.867401988846, 8855.690050848447, 9098.772530686521, 9244.075592440702, 53898.461015389345, 54207.728961354944, 54971.72514137404, 55135.0, 56354.43645563492, 74896.25103748892, 78283.60858195901, 80774.0, 81175.59412202901, 81319.18680813115, 12203.14577979533, 12393.752124957631, 12507.124501284823, 12542.12205145631, 12664.303463309607, 13297.202167869958, 14286.928565357106, 14372.28138593087, 14464.349104290122, 15140.16729079867, 12962.157459765462, 12970.0, 13067.085304028627, 13340.399682014528, 13351.265680387673, 13183.40674669623, 13211.801822972471, 13386.866131338562, 13414.396352164371, 13597.388117534932, 11970.58102966392, 12076.456559455022, 12333.93833030829, 12336.383180840743, 12386.5664701735, 10858.565657373616, 11214.83177752318, 11312.549642573667, 11407.486663100346, 11632.825507617223, 11146.944265278622, 11385.601503947653, 11447.542098316308, 11505.194636375374, 11582.594609188638], "value": 7686.6480146773265, "unit": "score"}], "name": "dromaeo_css", "value": 7460.673682596912, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 112.0, 110.0, 112.0, 111.0, 110.0, 110.0, 109.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 119.0, 176.0, 120.0, 117.0, 147.0, 117.0, 146.0, 118.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 159.0, 160.0, 158.0, 159.0, 162.0, 161.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 116.0, 75.0, 75.0, 73.0, 75.0, 100.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 83.0, 84.0, 83.0, 117.0, 84.0, 83.0, 130.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 145.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0, 53.0, 55.0, 51.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 74.0, 75.0, 83.0, 75.0, 74.0, 74.0, 77.0, 78.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 118.0, 119.0, 118.0, 120.0, 119.0, 119.0, 127.0, 209.0, 129.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 154.0, 155.0, 153.0, 153.0, 154.0, 154.0, 155.0, 156.0, 155.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0, 60.0, 61.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1473.6999999999998, "alertThreshold": 2.0}]} 22:41:50 INFO - Return code: 0 22:41:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:50 INFO - # TBPL SUCCESS # 22:41:50 INFO - Running post-action listener: _resource_record_post_action 22:41:50 INFO - ##### 22:41:50 INFO - ##### Finished run-tests step (success) 22:41:50 INFO - ##### 22:41:50 INFO - Running post-run listener: _resource_record_post_run 22:41:50 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293732352; Read time: 16; Write time: 342780 22:41:50 INFO - TinderboxPrint: CPU usage
13.0% 22:41:50 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 22:41:50 INFO - TinderboxPrint: I/O write bytes / time
293,732,352 / 342,780 22:41:50 INFO - TinderboxPrint: CPU idle
8,265.8 (86.9%) 22:41:50 INFO - TinderboxPrint: CPU user
1,197.4 (12.6%) 22:41:50 INFO - TinderboxPrint: Swap in / out
0 / 0 22:41:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1138688; Read time: 0; Write time: 48 22:41:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292593664; Read time: 8; Write time: 342732 22:41:51 INFO - Running post-run listener: _upload_blobber_files 22:41:51 INFO - Blob upload gear active. 22:41:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:51 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 22:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:41:51 INFO - (blobuploader) - INFO - Open directory for files ... 22:41:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:41:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:41:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:52 INFO - (blobuploader) - INFO - Done attempting. 22:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:55 INFO - (blobuploader) - INFO - Done attempting. 22:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:56 INFO - (blobuploader) - INFO - Done attempting. 22:41:56 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:56 INFO - Return code: 0 22:41:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/00d587b3e281d88aaf843cbb0da004571445a81d4716df90b171ad10b9b56bb3b12a9153c8d659ebf22d7c0cc8686dafcf8cd6ece62173310f557038448d1eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b66a22bd934d3362de0cf1888cbad9c7c1a510c6b0c3b14877b42854b9c6807b04b48f31cd661143d62afdd778433cfebbee87ce5124a600b31f69efb0cf657a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1cf014acbe776b609b0eba8e56e0e2557c9d5484af92942a205c51ab79d037c077f6c2495f79e4ed7df7c8bcde66a98f03ae40c0b2e8a4ebca667470a77663d1"} 22:41:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:56 INFO - Contents: 22:41:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/00d587b3e281d88aaf843cbb0da004571445a81d4716df90b171ad10b9b56bb3b12a9153c8d659ebf22d7c0cc8686dafcf8cd6ece62173310f557038448d1eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b66a22bd934d3362de0cf1888cbad9c7c1a510c6b0c3b14877b42854b9c6807b04b48f31cd661143d62afdd778433cfebbee87ce5124a600b31f69efb0cf657a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1cf014acbe776b609b0eba8e56e0e2557c9d5484af92942a205c51ab79d037c077f6c2495f79e4ed7df7c8bcde66a98f03ae40c0b2e8a4ebca667470a77663d1"} 22:41:56 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:56 INFO - Copying logs to upload dir... 22:41:56 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.348357 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-13 22:41:56.297609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 22:41:56.302364) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/00d587b3e281d88aaf843cbb0da004571445a81d4716df90b171ad10b9b56bb3b12a9153c8d659ebf22d7c0cc8686dafcf8cd6ece62173310f557038448d1eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b66a22bd934d3362de0cf1888cbad9c7c1a510c6b0c3b14877b42854b9c6807b04b48f31cd661143d62afdd778433cfebbee87ce5124a600b31f69efb0cf657a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1cf014acbe776b609b0eba8e56e0e2557c9d5484af92942a205c51ab79d037c077f6c2495f79e4ed7df7c8bcde66a98f03ae40c0b2e8a4ebca667470a77663d1"} build_url:https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/Xtlh7AloTIeqnzM4vIrBZA/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/Mozilla-Inbound/sha512/00d587b3e281d88aaf843cbb0da004571445a81d4716df90b171ad10b9b56bb3b12a9153c8d659ebf22d7c0cc8686dafcf8cd6ece62173310f557038448d1eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b66a22bd934d3362de0cf1888cbad9c7c1a510c6b0c3b14877b42854b9c6807b04b48f31cd661143d62afdd778433cfebbee87ce5124a600b31f69efb0cf657a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1cf014acbe776b609b0eba8e56e0e2557c9d5484af92942a205c51ab79d037c077f6c2495f79e4ed7df7c8bcde66a98f03ae40c0b2e8a4ebca667470a77663d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 22:41:56.332117) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:41:56.332674) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468473639.721071-1804839520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:41:56.412298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 22:41:56.412644) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 22:41:56.412986) ========= ========= Total master_lag: 0.17 =========