builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1471536909.89 results: success (0) buildid: 20160818053002 builduid: cbeedfa291004b609a65846e2785f8a5 revision: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:09.892852) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:09.893496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:09.893797) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:09.909785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:09.910270) ========= 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=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 09:15:10-- 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% 45.9M=0s 2016-08-18 09:15:10 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097732 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:10.022342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:10.022657) ========= 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=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018991 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:10.074629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 09:15:10.075119) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 --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=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 09:15:10,351 truncating revision to first 12 chars 2016-08-18 09:15:10,351 Setting DEBUG logging. 2016-08-18 09:15:10,351 attempt 1/10 2016-08-18 09:15:10,351 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a6f67ab4a4ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 09:15:11,575 unpacking tar archive at: fx-team-a6f67ab4a4ec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.566200 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 09:15:11.656059) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:11.656400) ========= script_repo_revision: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:11.656809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:11.657084) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 09:15:11.666974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-18 09:15:11.667396) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:12 INFO - MultiFileLogger online at 20160818 09:15:12 in /builds/slave/test 09:15:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:15:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:12 INFO - {'append_to_log': False, 09:15:12 INFO - 'base_work_dir': '/builds/slave/test', 09:15:12 INFO - 'blob_upload_branch': 'Fx-Team', 09:15:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:12 INFO - 'branch': 'Fx-Team', 09:15:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:12 INFO - 'config_files': ('talos/linux_config.py',), 09:15:12 INFO - 'default_actions': ('clobber', 09:15:12 INFO - 'read-buildbot-config', 09:15:12 INFO - 'download-and-extract', 09:15:12 INFO - 'populate-webroot', 09:15:12 INFO - 'create-virtualenv', 09:15:12 INFO - 'install', 09:15:12 INFO - 'run-tests'), 09:15:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:12 INFO - 'download_minidump_stackwalk': True, 09:15:12 INFO - 'download_symbols': 'ondemand', 09:15:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:12 INFO - 'installer_path': 'installer.exe', 09:15:12 INFO - 'log_level': 'info', 09:15:12 INFO - 'log_name': 'talos', 09:15:12 INFO - 'log_to_console': True, 09:15:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:12 INFO - 'opt_config_files': (), 09:15:12 INFO - 'pip_index': False, 09:15:12 INFO - 'sps_profile': False, 09:15:12 INFO - 'sps_profile_interval': 0, 09:15:12 INFO - 'suite': 'dromaeojs', 09:15:12 INFO - 'system_bits': '32', 09:15:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:12 INFO - 'title': 'talos-linux64-ix-049', 09:15:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:12 INFO - 'use_talos_json': True, 09:15:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:12 INFO - 'work_dir': 'build'} 09:15:12 INFO - [mozharness: 2016-08-18 16:15:12.043424Z] Running clobber step. 09:15:12 INFO - Running pre-action listener: _resource_record_pre_action 09:15:12 INFO - Running main action method: clobber 09:15:12 INFO - rmtree: /builds/slave/test/build 09:15:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:12 INFO - Running post-action listener: _resource_record_post_action 09:15:12 INFO - [mozharness: 2016-08-18 16:15:12.498462Z] Finished clobber step (success) 09:15:12 INFO - [mozharness: 2016-08-18 16:15:12.498867Z] Running read-buildbot-config step. 09:15:12 INFO - Running pre-action listener: _resource_record_pre_action 09:15:12 INFO - Running main action method: read_buildbot_config 09:15:12 INFO - Using buildbot properties: 09:15:12 INFO - { 09:15:12 INFO - "project": "", 09:15:12 INFO - "product": "firefox", 09:15:12 INFO - "script_repo_revision": "production", 09:15:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:15:12 INFO - "repository": "", 09:15:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 09:15:12 INFO - "buildid": "20160818053002", 09:15:12 INFO - "pgo_build": "True", 09:15:12 INFO - "basedir": "/builds/slave/test", 09:15:12 INFO - "buildnumber": 369, 09:15:12 INFO - "slavename": "talos-linux64-ix-049", 09:15:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:15:12 INFO - "platform": "ubuntu64_hw", 09:15:12 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 09:15:12 INFO - "branch": "fx-team", 09:15:12 INFO - "repo_path": "integration/fx-team", 09:15:12 INFO - "revision": "a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388", 09:15:12 INFO - "stage_platform": "linux64-pgo", 09:15:12 INFO - "builduid": "cbeedfa291004b609a65846e2785f8a5", 09:15:12 INFO - "slavebuilddir": "test" 09:15:12 INFO - } 09:15:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:15:12 INFO - Running post-action listener: _resource_record_post_action 09:15:12 INFO - [mozharness: 2016-08-18 16:15:12.501254Z] Finished read-buildbot-config step (success) 09:15:12 INFO - [mozharness: 2016-08-18 16:15:12.501342Z] Running download-and-extract step. 09:15:12 INFO - Running pre-action listener: _resource_record_pre_action 09:15:12 INFO - Running main action method: download_and_extract 09:15:12 INFO - mkdir: /builds/slave/test/build/tests 09:15:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:12 INFO - trying https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:15:12 INFO - Downloading https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:13 INFO - Downloaded 1361 bytes. 09:15:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:15:13 INFO - Using the following test package requirements: 09:15:13 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:15:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'jsshell-linux-x86_64.zip'], 09:15:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:15:13 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:15:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:13 INFO - trying https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:15:13 INFO - Downloading https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:15:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:13 INFO - Downloaded 21907088 bytes. 09:15:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:15:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:18 INFO - trying https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:15:18 INFO - Downloading https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:15:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:18 INFO - Downloaded 21907088 bytes. 09:15:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:23 INFO - trying https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:23 INFO - Downloading https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:24 INFO - Downloaded 14413981 bytes. 09:15:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 09:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:24 INFO - trying https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:15:24 INFO - Downloading https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:15:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:15:25 INFO - Downloaded 59604716 bytes. 09:15:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:15:25 INFO - mkdir: /builds/slave/test/properties 09:15:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:25 INFO - Contents: 09:15:25 INFO - build_url:https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:15:26 INFO - Running post-action listener: _resource_record_post_action 09:15:26 INFO - Running post-action listener: set_extra_try_arguments 09:15:26 INFO - [mozharness: 2016-08-18 16:15:26.499458Z] Finished download-and-extract step (success) 09:15:26 INFO - [mozharness: 2016-08-18 16:15:26.499680Z] Running populate-webroot step. 09:15:26 INFO - Running pre-action listener: _resource_record_pre_action 09:15:26 INFO - Running main action method: populate_webroot 09:15:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:26 INFO - '--tppagecycles', 09:15:26 INFO - '7'], 09:15:26 INFO - 'tests': ['tsvgm']}}, 09:15:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['damp', 'tps']}, 09:15:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'tests': ['damp', 'tps']}, 09:15:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['dromaeo_dom']}, 09:15:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['basic_compositor_video']}, 09:15:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['a11yr', 09:15:26 INFO - 'ts_paint', 09:15:26 INFO - 'tpaint', 09:15:26 INFO - 'sessionrestore', 09:15:26 INFO - 'sessionrestore_no_auto_restore', 09:15:26 INFO - 'tabpaint']}, 09:15:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['tsvgx', 09:15:26 INFO - 'tsvgr_opacity', 09:15:26 INFO - 'tart', 09:15:26 INFO - 'tscrollx', 09:15:26 INFO - 'cart']}, 09:15:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:26 INFO - 'tsvgr_opacity', 09:15:26 INFO - 'tart', 09:15:26 INFO - 'tscrollx', 09:15:26 INFO - 'cart']}, 09:15:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'talos_options': ['--disable-e10s'], 09:15:26 INFO - 'tests': ['tp5o']}, 09:15:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'tests': ['tp5o']}, 09:15:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'talos_options': ['--disable-e10s', 09:15:26 INFO - '--xperf_path', 09:15:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:26 INFO - 'tests': ['tp5n']}, 09:15:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:26 INFO - 'talos_options': ['--xperf_path', 09:15:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:26 INFO - 'tests': ['tp5n']}}, 09:15:26 INFO - 'talos.zip': {'path': '', 09:15:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:26 INFO - Running post-action listener: _resource_record_post_action 09:15:26 INFO - [mozharness: 2016-08-18 16:15:26.514917Z] Finished populate-webroot step (success) 09:15:26 INFO - [mozharness: 2016-08-18 16:15:26.515003Z] Running create-virtualenv step. 09:15:26 INFO - Running pre-action listener: _resource_record_pre_action 09:15:26 INFO - Running main action method: create_virtualenv 09:15:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:15:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:26 INFO - Using real prefix '/usr' 09:15:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:29 INFO - Installing pip.................done. 09:15:29 INFO - Return code: 0 09:15:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:29 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:29 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 09:15:29 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 09:15:29 INFO - Using env: {'DISPLAY': ':0', 09:15:29 INFO - 'HOME': '/home/cltbld', 09:15:29 INFO - 'LANG': 'en_US.UTF-8', 09:15:29 INFO - 'LANGUAGE': 'en_US:en', 09:15:29 INFO - 'LOGNAME': 'cltbld', 09:15:29 INFO - 'MAIL': '/var/mail/cltbld', 09:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:29 INFO - 'MOZ_NO_REMOTE': '1', 09:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:29 INFO - 'NO_EM_RESTART': '1', 09:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:29 INFO - 'PWD': '/builds/slave/test', 09:15:29 INFO - 'SHELL': '/bin/bash', 09:15:29 INFO - 'SHLVL': '1', 09:15:29 INFO - 'TERM': 'linux', 09:15:29 INFO - 'TMOUT': '86400', 09:15:29 INFO - 'USER': 'cltbld', 09:15:29 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:29 INFO - '_': '/tools/buildbot/bin/python'} 09:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:29 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 09:15:29 INFO - Cleaning up... 09:15:29 INFO - Return code: 0 09:15:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:29 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:29 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 09:15:29 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 09:15:29 INFO - Using env: {'DISPLAY': ':0', 09:15:29 INFO - 'HOME': '/home/cltbld', 09:15:29 INFO - 'LANG': 'en_US.UTF-8', 09:15:29 INFO - 'LANGUAGE': 'en_US:en', 09:15:29 INFO - 'LOGNAME': 'cltbld', 09:15:29 INFO - 'MAIL': '/var/mail/cltbld', 09:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:29 INFO - 'MOZ_NO_REMOTE': '1', 09:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:29 INFO - 'NO_EM_RESTART': '1', 09:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:29 INFO - 'PWD': '/builds/slave/test', 09:15:29 INFO - 'SHELL': '/bin/bash', 09:15:29 INFO - 'SHLVL': '1', 09:15:29 INFO - 'TERM': 'linux', 09:15:29 INFO - 'TMOUT': '86400', 09:15:29 INFO - 'USER': 'cltbld', 09:15:29 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:29 INFO - '_': '/tools/buildbot/bin/python'} 09:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:29 INFO - Downloading/unpacking psutil>=3.1.1 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:31 INFO - Installing collected packages: psutil 09:15:31 INFO - Running setup.py install for psutil 09:15:31 INFO - building 'psutil._psutil_linux' extension 09:15:31 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 09:15:31 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 09:15:31 INFO - building 'psutil._psutil_posix' extension 09:15:31 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 09:15:31 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 09:15:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:32 INFO - Successfully installed psutil 09:15:32 INFO - Cleaning up... 09:15:32 INFO - Return code: 0 09:15:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:32 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:32 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 09:15:32 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 09:15:32 INFO - Using env: {'DISPLAY': ':0', 09:15:32 INFO - 'HOME': '/home/cltbld', 09:15:32 INFO - 'LANG': 'en_US.UTF-8', 09:15:32 INFO - 'LANGUAGE': 'en_US:en', 09:15:32 INFO - 'LOGNAME': 'cltbld', 09:15:32 INFO - 'MAIL': '/var/mail/cltbld', 09:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:32 INFO - 'MOZ_NO_REMOTE': '1', 09:15:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:32 INFO - 'NO_EM_RESTART': '1', 09:15:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:32 INFO - 'PWD': '/builds/slave/test', 09:15:32 INFO - 'SHELL': '/bin/bash', 09:15:32 INFO - 'SHLVL': '1', 09:15:32 INFO - 'TERM': 'linux', 09:15:32 INFO - 'TMOUT': '86400', 09:15:32 INFO - 'USER': 'cltbld', 09:15:32 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:32 INFO - '_': '/tools/buildbot/bin/python'} 09:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15:32 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 09:15:32 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 09:15:32 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 09:15:32 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 09:15:32 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 09:15:32 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 09:15:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:34 INFO - Installing collected packages: mozsystemmonitor 09:15:34 INFO - Running setup.py install for mozsystemmonitor 09:15:34 INFO - Successfully installed mozsystemmonitor 09:15:34 INFO - Cleaning up... 09:15:34 INFO - Return code: 0 09:15:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:34 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:34 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 09:15:34 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 09:15:34 INFO - Using env: {'DISPLAY': ':0', 09:15:34 INFO - 'HOME': '/home/cltbld', 09:15:34 INFO - 'LANG': 'en_US.UTF-8', 09:15:34 INFO - 'LANGUAGE': 'en_US:en', 09:15:34 INFO - 'LOGNAME': 'cltbld', 09:15:34 INFO - 'MAIL': '/var/mail/cltbld', 09:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:34 INFO - 'MOZ_NO_REMOTE': '1', 09:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:34 INFO - 'NO_EM_RESTART': '1', 09:15:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:34 INFO - 'PWD': '/builds/slave/test', 09:15:34 INFO - 'SHELL': '/bin/bash', 09:15:34 INFO - 'SHLVL': '1', 09:15:34 INFO - 'TERM': 'linux', 09:15:34 INFO - 'TMOUT': '86400', 09:15:34 INFO - 'USER': 'cltbld', 09:15:34 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:34 INFO - '_': '/tools/buildbot/bin/python'} 09:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:34 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:34 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 09:15:34 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 09:15:34 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 09:15:34 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 09:15:34 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 09:15:34 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 09:15:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:36 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:36 INFO - warning: no files found matching '*.txt' 09:15:36 INFO - no previously-included directories found matching 'build' 09:15:36 INFO - no previously-included directories found matching 'dist' 09:15:36 INFO - no previously-included directories found matching '.git*' 09:15:36 INFO - Installing collected packages: jsonschema, functools32 09:15:36 INFO - Running setup.py install for functools32 09:15:36 INFO - warning: no files found matching '*.txt' 09:15:36 INFO - no previously-included directories found matching 'build' 09:15:36 INFO - no previously-included directories found matching 'dist' 09:15:36 INFO - no previously-included directories found matching '.git*' 09:15:36 INFO - Successfully installed jsonschema functools32 09:15:36 INFO - Cleaning up... 09:15:36 INFO - Return code: 0 09:15:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:15:36 INFO - Using env: {'DISPLAY': ':0', 09:15:36 INFO - 'HOME': '/home/cltbld', 09:15:36 INFO - 'LANG': 'en_US.UTF-8', 09:15:36 INFO - 'LANGUAGE': 'en_US:en', 09:15:36 INFO - 'LOGNAME': 'cltbld', 09:15:36 INFO - 'MAIL': '/var/mail/cltbld', 09:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:36 INFO - 'MOZ_NO_REMOTE': '1', 09:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:36 INFO - 'NO_EM_RESTART': '1', 09:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:36 INFO - 'PWD': '/builds/slave/test', 09:15:36 INFO - 'SHELL': '/bin/bash', 09:15:36 INFO - 'SHLVL': '1', 09:15:36 INFO - 'TERM': 'linux', 09:15:36 INFO - 'TMOUT': '86400', 09:15:36 INFO - 'USER': 'cltbld', 09:15:36 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:36 INFO - '_': '/tools/buildbot/bin/python'} 09:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:37 INFO - Cleaning up... 09:15:37 INFO - Return code: 0 09:15:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:15:37 INFO - Using env: {'DISPLAY': ':0', 09:15:37 INFO - 'HOME': '/home/cltbld', 09:15:37 INFO - 'LANG': 'en_US.UTF-8', 09:15:37 INFO - 'LANGUAGE': 'en_US:en', 09:15:37 INFO - 'LOGNAME': 'cltbld', 09:15:37 INFO - 'MAIL': '/var/mail/cltbld', 09:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:37 INFO - 'MOZ_NO_REMOTE': '1', 09:15:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:37 INFO - 'NO_EM_RESTART': '1', 09:15:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:37 INFO - 'PWD': '/builds/slave/test', 09:15:37 INFO - 'SHELL': '/bin/bash', 09:15:37 INFO - 'SHLVL': '1', 09:15:37 INFO - 'TERM': 'linux', 09:15:37 INFO - 'TMOUT': '86400', 09:15:37 INFO - 'USER': 'cltbld', 09:15:37 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:37 INFO - '_': '/tools/buildbot/bin/python'} 09:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - Downloading docopt-0.6.1.tar.gz 09:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:40 INFO - Running setup.py install for blobuploader 09:15:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:40 INFO - Running setup.py install for requests 09:15:40 INFO - Running setup.py install for docopt 09:15:40 INFO - Successfully installed blobuploader requests docopt 09:15:40 INFO - Cleaning up... 09:15:40 INFO - Return code: 0 09:15:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:40 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:40 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 09:15:40 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 09:15:40 INFO - Using env: {'DISPLAY': ':0', 09:15:40 INFO - 'HOME': '/home/cltbld', 09:15:40 INFO - 'LANG': 'en_US.UTF-8', 09:15:40 INFO - 'LANGUAGE': 'en_US:en', 09:15:40 INFO - 'LOGNAME': 'cltbld', 09:15:40 INFO - 'MAIL': '/var/mail/cltbld', 09:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:40 INFO - 'MOZ_NO_REMOTE': '1', 09:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:40 INFO - 'NO_EM_RESTART': '1', 09:15:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:40 INFO - 'PWD': '/builds/slave/test', 09:15:40 INFO - 'SHELL': '/bin/bash', 09:15:40 INFO - 'SHLVL': '1', 09:15:40 INFO - 'TERM': 'linux', 09:15:40 INFO - 'TMOUT': '86400', 09:15:40 INFO - 'USER': 'cltbld', 09:15:40 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:40 INFO - '_': '/tools/buildbot/bin/python'} 09:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:40 INFO - Running setup.py (path:/tmp/pip-XPIxOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:40 INFO - Running setup.py (path:/tmp/pip-OQ1rW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:40 INFO - Running setup.py (path:/tmp/pip-r1agi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:41 INFO - Running setup.py (path:/tmp/pip-lI2Hkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:41 INFO - Running setup.py (path:/tmp/pip-9Mn9yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:41 INFO - Running setup.py (path:/tmp/pip-GNrQzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:41 INFO - Running setup.py (path:/tmp/pip-o7Lyxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:41 INFO - Running setup.py (path:/tmp/pip-fbIk5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:41 INFO - Running setup.py (path:/tmp/pip-8XLkbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:41 INFO - Running setup.py (path:/tmp/pip-vNNrVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:41 INFO - Running setup.py (path:/tmp/pip-VXUGbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:41 INFO - Running setup.py (path:/tmp/pip-wIq7My-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:41 INFO - Running setup.py (path:/tmp/pip-0plFB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:41 INFO - Running setup.py (path:/tmp/pip-A9GmMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:41 INFO - Running setup.py (path:/tmp/pip-cCHD6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:41 INFO - Running setup.py (path:/tmp/pip-7MUsfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:41 INFO - Running setup.py (path:/tmp/pip-aSS0FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:42 INFO - Running setup.py install for manifestparser 09:15:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Running setup.py install for mozcrash 09:15:42 INFO - Running setup.py install for mozdebug 09:15:42 INFO - Running setup.py install for mozdevice 09:15:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Running setup.py install for mozfile 09:15:42 INFO - Running setup.py install for mozhttpd 09:15:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Running setup.py install for mozinfo 09:15:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Running setup.py install for mozInstall 09:15:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:42 INFO - Running setup.py install for mozleak 09:15:42 INFO - Running setup.py install for mozlog 09:15:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Running setup.py install for moznetwork 09:15:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Running setup.py install for mozprocess 09:15:43 INFO - Running setup.py install for mozprofile 09:15:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Running setup.py install for mozrunner 09:15:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Running setup.py install for mozscreenshot 09:15:43 INFO - Running setup.py install for moztest 09:15:43 INFO - Running setup.py install for mozversion 09:15:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:43 INFO - Cleaning up... 09:15:43 INFO - Return code: 0 09:15:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:43 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:43 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 09:15:43 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 09:15:43 INFO - Using env: {'DISPLAY': ':0', 09:15:43 INFO - 'HOME': '/home/cltbld', 09:15:43 INFO - 'LANG': 'en_US.UTF-8', 09:15:43 INFO - 'LANGUAGE': 'en_US:en', 09:15:43 INFO - 'LOGNAME': 'cltbld', 09:15:43 INFO - 'MAIL': '/var/mail/cltbld', 09:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:43 INFO - 'MOZ_NO_REMOTE': '1', 09:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:43 INFO - 'NO_EM_RESTART': '1', 09:15:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:43 INFO - 'PWD': '/builds/slave/test', 09:15:43 INFO - 'SHELL': '/bin/bash', 09:15:43 INFO - 'SHLVL': '1', 09:15:43 INFO - 'TERM': 'linux', 09:15:43 INFO - 'TMOUT': '86400', 09:15:43 INFO - 'USER': 'cltbld', 09:15:43 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:43 INFO - '_': '/tools/buildbot/bin/python'} 09:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:44 INFO - Running setup.py (path:/tmp/pip-aebLe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:44 INFO - Running setup.py (path:/tmp/pip-izupcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:44 INFO - Running setup.py (path:/tmp/pip-cbTQ1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:44 INFO - Running setup.py (path:/tmp/pip-AygXj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:44 INFO - Running setup.py (path:/tmp/pip-qENs8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:44 INFO - Running setup.py (path:/tmp/pip-v3TGl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:44 INFO - Running setup.py (path:/tmp/pip-EZv6kV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:44 INFO - Running setup.py (path:/tmp/pip-tU7lWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:44 INFO - Running setup.py (path:/tmp/pip-pc9ee2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:44 INFO - Running setup.py (path:/tmp/pip-zQ3zBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:44 INFO - Running setup.py (path:/tmp/pip-65dlEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:44 INFO - Running setup.py (path:/tmp/pip-FacmlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:44 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)) 09:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:44 INFO - Running setup.py (path:/tmp/pip-sfnaZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:45 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)) 09:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:45 INFO - Running setup.py (path:/tmp/pip-2iaQlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:45 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)) 09:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:45 INFO - Running setup.py (path:/tmp/pip-gNb6gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:45 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)) 09:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:45 INFO - Running setup.py (path:/tmp/pip-_KTWaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:45 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)) 09:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:45 INFO - Running setup.py (path:/tmp/pip-LonuMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:45 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)) 09:15:45 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)) 09:15:45 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)) 09:15:45 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)) 09:15:45 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)) 09:15:45 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)) 09:15:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:45 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 09:15:45 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 09:15:45 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 09:15:45 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 09:15:45 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 09:15:45 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 09:15:47 INFO - Downloading blessings-1.6.tar.gz 09:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:47 INFO - Installing collected packages: blessings 09:15:47 INFO - Running setup.py install for blessings 09:15:47 INFO - Successfully installed blessings 09:15:47 INFO - Cleaning up... 09:15:47 INFO - Return code: 0 09:15:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:47 INFO - Reading from file tmpfile_stdout 09:15:47 INFO - Current package versions: 09:15:47 INFO - argparse == 1.2.1 09:15:47 INFO - blessings == 1.6 09:15:47 INFO - blobuploader == 1.2.4 09:15:47 INFO - docopt == 0.6.1 09:15:47 INFO - functools32 == 3.2.3-2 09:15:47 INFO - jsonschema == 2.5.1 09:15:47 INFO - manifestparser == 1.1 09:15:47 INFO - mozInstall == 1.12 09:15:47 INFO - mozcrash == 0.17 09:15:47 INFO - mozdebug == 0.1 09:15:47 INFO - mozdevice == 0.48 09:15:47 INFO - mozfile == 1.2 09:15:47 INFO - mozhttpd == 0.7 09:15:47 INFO - mozinfo == 0.9 09:15:47 INFO - mozleak == 0.1 09:15:47 INFO - mozlog == 3.3 09:15:47 INFO - moznetwork == 0.27 09:15:47 INFO - mozprocess == 0.23 09:15:47 INFO - mozprofile == 0.28 09:15:47 INFO - mozrunner == 6.12 09:15:47 INFO - mozscreenshot == 0.1 09:15:47 INFO - mozsystemmonitor == 0.3 09:15:47 INFO - moztest == 0.7 09:15:47 INFO - mozversion == 1.4 09:15:47 INFO - psutil == 3.1.1 09:15:47 INFO - requests == 1.2.3 09:15:47 INFO - wsgiref == 0.1.2 09:15:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:47 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:47 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 09:15:47 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 09:15:47 INFO - Using env: {'DISPLAY': ':0', 09:15:47 INFO - 'HOME': '/home/cltbld', 09:15:47 INFO - 'LANG': 'en_US.UTF-8', 09:15:47 INFO - 'LANGUAGE': 'en_US:en', 09:15:47 INFO - 'LOGNAME': 'cltbld', 09:15:47 INFO - 'MAIL': '/var/mail/cltbld', 09:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:47 INFO - 'MOZ_NO_REMOTE': '1', 09:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:47 INFO - 'NO_EM_RESTART': '1', 09:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:47 INFO - 'PWD': '/builds/slave/test', 09:15:47 INFO - 'SHELL': '/bin/bash', 09:15:47 INFO - 'SHLVL': '1', 09:15:47 INFO - 'TERM': 'linux', 09:15:47 INFO - 'TMOUT': '86400', 09:15:47 INFO - 'USER': 'cltbld', 09:15:47 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:47 INFO - '_': '/tools/buildbot/bin/python'} 09:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 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)) 09:15:47 INFO - Cleaning up... 09:15:47 INFO - Return code: 0 09:15:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:47 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 0x7f3bad3f7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ed850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, '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': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', '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 09:15:47 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 09:15:47 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 09:15:47 INFO - Using env: {'DISPLAY': ':0', 09:15:47 INFO - 'HOME': '/home/cltbld', 09:15:47 INFO - 'LANG': 'en_US.UTF-8', 09:15:47 INFO - 'LANGUAGE': 'en_US:en', 09:15:47 INFO - 'LOGNAME': 'cltbld', 09:15:47 INFO - 'MAIL': '/var/mail/cltbld', 09:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:47 INFO - 'MOZ_NO_REMOTE': '1', 09:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:47 INFO - 'NO_EM_RESTART': '1', 09:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:47 INFO - 'PWD': '/builds/slave/test', 09:15:47 INFO - 'SHELL': '/bin/bash', 09:15:47 INFO - 'SHLVL': '1', 09:15:47 INFO - 'TERM': 'linux', 09:15:47 INFO - 'TMOUT': '86400', 09:15:47 INFO - 'USER': 'cltbld', 09:15:47 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:47 INFO - '_': '/tools/buildbot/bin/python'} 09:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:15:48 INFO - Cleaning up... 09:15:48 INFO - Return code: 0 09:15:48 INFO - Running post-action listener: _resource_record_post_action 09:15:48 INFO - Running post-action listener: _start_resource_monitoring 09:15:48 INFO - Starting resource monitoring. 09:15:48 INFO - [mozharness: 2016-08-18 16:15:48.142042Z] Finished create-virtualenv step (success) 09:15:48 INFO - [mozharness: 2016-08-18 16:15:48.142501Z] Running install step. 09:15:48 INFO - Running pre-action listener: _resource_record_pre_action 09:15:48 INFO - Running main action method: install 09:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:48 INFO - Reading from file tmpfile_stdout 09:15:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:15:48 INFO - Reading from file tmpfile_stdout 09:15:48 INFO - Output received: 09:15:48 INFO - Usage: mozinstall [options] installer 09:15:48 INFO - Options: 09:15:48 INFO - -h, --help show this help message and exit 09:15:48 INFO - -d DEST, --destination=DEST 09:15:48 INFO - Directory to install application into. [default: 09:15:48 INFO - "/builds/slave/test"] 09:15:48 INFO - --app=APP Application being installed. [default: firefox] 09:15:48 INFO - mkdir: /builds/slave/test/build/application 09:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:16:05 INFO - Reading from file tmpfile_stdout 09:16:05 INFO - Output received: 09:16:05 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:05 INFO - Running post-action listener: _resource_record_post_action 09:16:05 INFO - [mozharness: 2016-08-18 16:16:05.405202Z] Finished install step (success) 09:16:05 INFO - [mozharness: 2016-08-18 16:16:05.405449Z] Running run-tests step. 09:16:05 INFO - Running pre-action listener: _resource_record_pre_action 09:16:05 INFO - Running main action method: run_tests 09:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:05 INFO - Python 2.7.3 09:16:05 INFO - Return code: 0 09:16:05 INFO - grabbing minidump binary from tooltool 09:16:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:05 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 0x19e8370>, '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 0x19eb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ebd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:05 INFO - Return code: 0 09:16:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 09:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 09:16:05 INFO - Using env: {'DISPLAY': ':0', 09:16:05 INFO - 'HOME': '/home/cltbld', 09:16:05 INFO - 'LANG': 'en_US.UTF-8', 09:16:05 INFO - 'LANGUAGE': 'en_US:en', 09:16:05 INFO - 'LOGNAME': 'cltbld', 09:16:05 INFO - 'MAIL': '/var/mail/cltbld', 09:16:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:05 INFO - 'MOZ_NO_REMOTE': '1', 09:16:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:05 INFO - 'NO_EM_RESTART': '1', 09:16:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:05 INFO - 'PWD': '/builds/slave/test', 09:16:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:05 INFO - 'SHELL': '/bin/bash', 09:16:05 INFO - 'SHLVL': '1', 09:16:05 INFO - 'TERM': 'linux', 09:16:05 INFO - 'TMOUT': '86400', 09:16:05 INFO - 'USER': 'cltbld', 09:16:05 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161', 09:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:05 INFO - '_': '/tools/buildbot/bin/python'} 09:16:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 09:16:05 INFO - mozversion application_buildid: 20160818053002 09:16:05 INFO - mozversion application_changeset: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 09:16:05 INFO - mozversion application_display_name: Nightly 09:16:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:05 INFO - mozversion application_name: Firefox 09:16:05 INFO - mozversion application_remotingname: firefox 09:16:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:16:05 INFO - mozversion application_vendor: Mozilla 09:16:05 INFO - mozversion application_version: 51.0a1 09:16:05 INFO - mozversion platform_buildid: 20160818053002 09:16:05 INFO - mozversion platform_changeset: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 09:16:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:16:05 INFO - mozversion platform_version: 51.0a1 09:16:05 INFO - using testdate: 1471536965 09:16:05 INFO - actual date: 1471536965 09:16:05 INFO - starting webserver on 'localhost:53231' 09:16:05 INFO - SUITE-START | Running 2 tests 09:16:05 INFO - TEST-START | dromaeo_css 09:16:05 INFO - operating with platform_type : linux_ 09:16:05 INFO - Initialising browser for dromaeo_css test... 09:16:05 INFO - TEST-INFO | started process 23414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsastaZ/profile http://localhost:53231/getInfo.html) 09:16:09 INFO - PROCESS | 23414 | __metrics Screen width/height:1600/1200 09:16:09 INFO - PROCESS | 23414 | colorDepth:24 09:16:09 INFO - PROCESS | 23414 | Browser inner width/height: 1024/697 09:16:09 INFO - PROCESS | 23414 | __metrics 09:16:10 INFO - PROCESS | 23414 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:10 INFO - PROCESS | 23414 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:10 INFO - PROCESS | 23414 | console.error: PushService: 09:16:10 INFO - PROCESS | 23414 | stateChangeProcessEnqueue: Error transitioning state 09:16:10 INFO - PROCESS | 23414 | UnknownError 09:16:11 INFO - TEST-INFO | 23414: exit 0 09:16:11 INFO - Browser initialized. 09:16:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:11 INFO - TEST-INFO | started process 23494 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsastaZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:23 INFO - PROCESS | 23494 | RSS: Main: 155906048 09:16:23 INFO - PROCESS | 23494 | 09:17:14 INFO - PROCESS | 23494 | 1471537034947 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] 09:19:24 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53231/tests/dromaeo/cssquery-ext.html) 09:19:25 INFO - PROCESS | 23494 | RSS: Main: 254107648 09:19:25 INFO - PROCESS | 23494 | 09:22:27 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-ext.html (next: http://localhost:53231/tests/dromaeo/cssquery-jquery.html) 09:22:28 INFO - PROCESS | 23494 | RSS: Main: 241975296 09:22:28 INFO - PROCESS | 23494 | 09:25:30 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53231/tests/dromaeo/cssquery-mootools.html) 09:25:30 INFO - PROCESS | 23494 | RSS: Main: 187461632 09:25:30 INFO - PROCESS | 23494 | 09:28:33 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53231/tests/dromaeo/cssquery-prototype.html) 09:28:33 INFO - PROCESS | 23494 | RSS: Main: 211681280 09:28:33 INFO - PROCESS | 23494 | 09:31:36 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53231/tests/dromaeo/cssquery-yui.html) 09:31:36 INFO - PROCESS | 23494 | RSS: Main: 183312384 09:31:36 INFO - PROCESS | 23494 | 09:34:39 INFO - PROCESS | 23494 | Cycle 1(1): loaded http://localhost:53231/tests/dromaeo/cssquery-yui.html (next: http://localhost:53231/tests/dromaeo/cssquery-dojo.html) 09:34:40 INFO - PROCESS | 23494 | RSS: Main: 275537920 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | __start_tp_report 09:34:40 INFO - PROCESS | 23494 | _x_x_mozilla_page_load 09:34:40 INFO - PROCESS | 23494 | _x_x_mozilla_page_load_details 09:34:40 INFO - PROCESS | 23494 | |i|pagename|runs| 09:34:40 INFO - PROCESS | 23494 | |0;dojo.html;3018.833964131973;3033.6966303369654;3046.2841232310416;3053.5267033609784;3065.494193458078;5195.324607800981;5256.001359741646;5344.706041167739;5390.245365648808;5458.617896747224;5337.092694241978;5378.058839703046;5425.9186112208345;5466.262054622624;5492.450754924505;4836.903261934759;4839.637027222955;4870.537298956593;4919.4096708395045;4935.753212339387;4960.751962401883;4993.600511959053;5016.799328026875;5024.271480635306;5028.547430731233;4943.431505376856;4994.975025124888;4997.975010124945;4998.475160108191;5008.649394542384;4740.360051393052;4748.976255118737;4799.13615549201;4818.68678535894;4853.757312134379;4828.710277383333;4847.248676455155;4848.7090774553635;4905.558499735041;4943.728094954776;5407.945920540784;5408;5436;5442.183672449125;5461.836144915659;5561.58288128392;5726.282115365374;5783.537317014629;5790.594658373915;5797.043487824504;5778.566607504454;5786.276715410574;5818.592698511064;5827;5833.445822646841;5123.257127716461;5385.703786291752;5393.7033463159905;5396.163594642837;5404.054290499186;5845.327787304429;5933.258342707161;5939.792107276225;6013.428724271187;6020.759169633253;5731.312242530923;5733.226014487991;5737.3688894221605;5761.654300741969;5782.855428614234;5549.361823390281;5815.476607105381;5852.707364631751;5863.5309175265875;5866.50134738552;5605.747741351567;5653.23681303027;5664.830055098271;5666.320041594952;5714.314282286069;5627.296587926509;5665.5467562594895;5668.631538949955;5675.3757086720425;5676.631018983753;4253.404523366731;4360.542143074995;4369.628581570539;4382.780860956939;4413.44831896013;3860.7104467165077;3911.2568611963634;3920.2355540669296;3927.489426374553;3968.1865217630316;5499.340079190522;5504.889902201933;5506.339239291311;5547.7780888764;5552.972235138879;5594;5807.390224026501;5808.5933984621515;5815.273090863642;5819;5385.838424847261;5397.865053373697;5423.023855705932;5469.945300546943;5480.342358916955;4972.975135124301;4979.45226025137;4987.202047672355;4988.5260900214425;5032.748362581856;5741.483266506035;5977.462028417463;5998.88002239944;6003.219581454383;6004.639721616717;5666.490015898589;5709.115087161496;5718.485336319751;5735.36910939788;5772.595918285848;5953.434423729739;5957.791477298274;5957.880842383041;5958.553108516792;5967.49276311501;5470.234167216672;5585.860353491195;5653.660780353192;5663.207150998781;5663.348714897675;5610.887782244414;5630.4369563043365;5635.8591035224445;5731.484166425042;5747.827565173052;5529.668219906818;5575.442455754554;5575.553955683455;5576.470235327637;5579.609427340048;5439.102548079522;5512.834614961557;5561.277033985556;5562.638428502134;5572;5414.75633596497;5432.728363581726;5452.91820622683;5524.14375771756;5547.8890422192135;5606.271184745957;5607.102863541977;5648.124540696199;5651.208830763779;5663.433656634304;5348.064088784369;5490.368607610017;5490.670559766427;5497.890042199054;5502.972485137549 09:34:40 INFO - PROCESS | 23494 | |1;ext.html;567.5994507725928;572.3732512898376;602.4216751918158;602.7950496831077;610.9389061093891;32474.837625811895;43229;44141.77929110358;44157;44214.557854421444;46292.53707462925;46525.25383819344;46699;46764.76617616907;46773.29840052402;8016.278534931855;8822.4706517609;9049.773755656128;9051.592678329458;10481.895181048169;10432.530536125854;10912.072473839733;10918.454077296143;10919.672409827717;10926.945365273203;10949.178811589163;10971.780564388708;10989.835152472719;10993.505292261867;10999.835002474969;10930;10955.4522273886;10969.890301096966;10978;10994.615188468406;10347.327423717434;10375;10400.843987340197;10407.063364297173;10411.479426028669;43663.38019718795;44083.338749918774;44102.33846492305;44151.558484415065;44175.558244417785;3570.107473131717;3596.3886139356373;3671.561751921748;3727.366347720867;3804.904877378088;32581.348373032408;32744.508832367294;32930.74315879975;32964.6703532964;33169.33661326784;4363.869083927487;4452.866414007585;4509.075639493896;4510.774461276956;4553.658475614309;345384;346981;348383;348586.2570687156;350323;58293.8341233173;58724;58808;59013.40986590165;59014;61335.69332153311;61841;61998;62043.37956620376;62117.378826212065;17318.134093295284;17529.581731562477;17554.29782808699;17563.73654395185;17568.824311756718;11729.35488548121;11979.880201198052;12223.44994475233;12239;13044.608661740163;16817.915910420368;16852.831471685127;16856.325746969986;17004.659906801793;17176.828231717773;5838.3510306058715;5874.823755287348;5926.614770039933;5962.165296858443;6082.635041897501;962.6678795815433;1069.2461814420815;1070.3791800755544;1089.2974031749477;1114.6043154679971;33370.49944250838;33737;33834.66165338315;34124;34497.827510862786;1119.2780656476527;1120.467777805536;1186.5194596188558;1208.2327721896659;1208.4078801387257;17823.376181833573;17970.281188752564;18219.362322318655;18483.815161848208;19423.611527769648;537267;540187;543439;544339;545434;62461.375386245556;62732;62769.68615156895;62818.37181628125;62874.68562657249;1277.2591896699892;1327.9136856104321;1328.3889410871107;1384.940520501817;1440.9279536023157;25839.980902743122;35875.82062089673;35976.88944721201;36117.07492435653;36366.818165909004;56711.71644141753;57357;57404;57503.1374529374;57555.42444575668;479250;485244;487122;487307;487986;25326.493470130863;25654.871725641253;25977;28194.718052819208;28648.57027144553;39970.201340939144;44234.55765442304;52177;52657;53412.46587534231;41248;41876.790616046725;42318;42415;44343.55656443523;40101.59898400979;40496.595034050464;40831.59168408396;41168.853893263346;41265.793671032654;40128.598714012485;41005.51453126517;41488.377674334304;41617;41645;34705;36935.445968309956;36943.2737925622;37769.81115094407;37779.62220377871;28531.714682852904;28596.571051433828;28788.568171477025;28961.710382896745;29083.854580727806 09:34:40 INFO - PROCESS | 23494 | |2;jquery.html;377.78277490443;389.34589888986494;393.9094008378072;402.2598418909207;418.4998926283091;14308.427662893484;17996.55008624785;20061.498462538442;20188.394348169542;20215.2924647637;19390.321338753147;19425.514362140955;19507;19680.70478942817;19783.70324445131;5773;5857.092150716646;5935.198748168985;5992.370801065891;6038.69806509675;5934.31755348134;5934.821955341346;5975.342712301644;5975.880482390371;5979.850503737398;5702.287214098238;5844.444777746128;5915.763369465238;5948.167256584082;5956.821295361146;5902.380250073745;5918.437748413894;5944.524438044946;5944.524438044946;5945.4946329562035;5097.872553186163;5220.112580861262;5258.0535503609335;5264.657797243167;5267.815626453094;23353.299401017997;23566.528669426687;23612.881935590383;23715;23718.05127794886;2376.120835290936;2383.332666853284;2442.3894026493376;2713.891444342224;2802.2574017885277;14128.505502307473;14930;15026;15265.465708700141;15357.385704571916;1884.1992153334777;1962.5878565501262;2007.688808234726;2030.8781473111512;2052.5073982244303;81956;83855.99999999939;85083.17125729295;85867.57066214691;86506.13493865107;10496.370217786956;10597.9470102649;10882.292650977819;11029;11321.773564528663;15251.542453726406;15280.15959122259;15280.847191528164;15304;15308.770368444482;8108.2297181767635;8421.578921054046;8575.699850505202;8787.692430764893;8820.426672266281;8337;8402.957985210158;8428.620712067972;8586.184312490303;8694.434861733967;6652.634105124264;6772.93227067723;6781.491388145811;6808.285130061272;6845.520813543004;1912.9713054304198;1924.6054558815408;1925.8170473804967;1984.186483541741;1994.321930543622;1013.7668336282696;1026.8767747870304;1035.7980193862275;1071.8723334181643;1093.0326660905157;20480.38558843237;21021.05705201273;21067.473313167295;21212.363629091153;21275.148994040064;1032.736652153711;1038.7818558102883;1086.4241951765578;1095.950682219302;1097.7036200225893;11599.478023488964;11852;12024.21842580247;12070;12177;430142;443919;465325;469882;469894;84360;85860.14139858646;85975;86113;86611.56694216614;1026.045777426985;1060.4432672846663;1081.2053140941402;1084.2356138922041;1085.9022687958122;17750.91124544369;18046;18589.44231673052;18672.626547469245;18913;40686.79656601698;41182.176356473305;41545.79227103845;41599.7920010398;41610.79194604007;53643;53741.73129134329;53939.730301348245;54000;56189.71905140448;5470.671759694431;6525.249596296487;7822.96602883243;8371.288440482627;8652.091530389218;13287.269200194081;13755.724885502033;13927.303634818016;13931.58205253844;14013.649658758613;12048.879511205127;12094.274343539415;12225.449854756735;12281.877181228074;12524.941678661764;11521.82717259225;11764.882351176379;11854.407279636156;11869.762604747684;12176.817347739614;12011.339376334385;12300.323482208207;12387.69030774238;12690.428930697955;12954.611361659165;8123.553204573569;8188.2630563249595;8264.297534709376;8478.703245386381;8508.957455212685;11285.266457680225;11416.401703761647;11640.767184656428;11662.766744665229;11998.40007999614 09:34:40 INFO - PROCESS | 23494 | |3;mootools.html;1340.8860246879015;1475.1960181700972;1509.4037855047256;1613.5240104169268;1777.9199936002879;8579.485230886143;8945.955270223649;8946.597403116868;9308.581113849876;9342.766430839216;9102.499362535058;9210.67762628308;9248.953755231214;9252.814943701122;9275.907240927589;2076.1799089359693;2361.8819059047064;2369.395804069964;2395.089865850974;2395.8562486250794;2407.3379820549403;2422.2975337152293;2425.417899704067;2425.7695518925675;2430.4045508850268;2414.33605758417;2414.6498757680124;2417.2023232333286;2424.9757502424927;2453;2231.977680223193;2242.9439264018365;2243.270936945491;2277.9202727904444;2363.161077817367;2407.2176542623715;2423.478952025323;2424.0425032112394;2428.477877256398;2438.451348446603;9348.205402540792;9391.530423478798;9395.859062114074;9396.624135034659;9403.811923761556;5355.437679043722;5525.889482210333;5528.226048353234;5530.198121272393;5535;3342.866285348583;3443.707284880788;3549.0949807798847;3555.2711694102645;3564.7861128332124;1183.9112066594953;1201.0123246027838;2132.2323963373174;2146.162996431393;2193;12780.360981950958;12840.165389249669;12845.807312890314;12933.159344642661;12989.285589292585;4464.486584042812;4580.748058856795;4759.381280433521;4760;4785.928211076767;9975;9975.800483990279;9984.900150998397;9985.20118390527;10008.49957502122;5447.945520544823;5449.809256675997;5451.04606693827;5452.727363631802;5454.7272636368025;4418.381426600278;4748.358971538798;4791.736454495036;5070.239464080418;5102.515261050195;3916.4516967624554;3987.4616926715103;4058.9594104059174;4064.837406503707;4102.261592913304;1215.3740823475916;1234.703671118925;1235.950561977511;1242.3353505874313;1250.143651598658;765.3609236287651;902.0438335364535;906.1074841281333;910.7267819654078;917.655879045358;6068.423499767603;6075.149479072933;6120.571559990847;6296.622202667854;6412.871742565123;960.4045491795132;978.5596481583174;999.4453078541479;1038.4029183219527;1047.9371237725761;4012.337964235927;4122.632103688933;4341.739495630272;4353.281708518059;4380.255356589388;11266.7183320416;11579.363135027654;11619;11670.474828632732;11709.824352634718;11495.597654082067;11521.308721476738;11523.596674116534;11524.423778811026;11537.942310288563;8062.7581172564915;8338.958305208434;8344.08215096339;8418.663253469915;8594.140585941355;6115.113308570321;6236.220472440945;6236.875262494816;6320.62076275425;6343.365663433619;9973.152282056106;10233.437160956219;10238.129758970274;10307.536160872629;10368.377897325883;10490.895091048991;10491.580336786443;10550.314229575053;10597.205209609156;10598.31110977784;7247.13034435871;7850.882236766339;7885.093214280431;7941.404394670307;8003.159668234752;8505.192006759347;8699.782505437415;8705.173008564176;8707.172818582225;8750.212480876751;7750.901644659558;7899.447038707235;7968;8039.67841286342;8090.110087890445;7599.164091949991;7804.180561041009;7867.252611001945;7891.210878912063;7909.604519774103;7094.9290507094265;7140.46446516503;7141.714331426892;7159.60622165765;7170.39051680613;5178.689278643294;5230.555402790806;5242.501962313574;5291.920621190762;5549.611527193219;5088.592912566912;5144.331236939174;5206.921896171636;5229.6339256251695;5504.9174262385295 09:34:40 INFO - PROCESS | 23494 | |4;prototype.html;313.1123265542189;327.1543061186835;327.6952434236159;330.99338013239736;336.56078817143623;5279.20811878218;5285.339332583427;5322.707251101193;5338.27933229014;5339.466053394659;4973.5026497350245;5044.697318160913;5191.896162076756;5213.530782229599;5243.580513558915;4117.258893399187;4125.855595054174;4126.958730412687;4139.544650088488;4153.50157981042;4123.649489793371;4127.17456508698;4134.359174327984;4135.855245066423;4137.407098267174;3879.467610293932;3911.5110611173604;4073.8981525461954;4074.6740260779065;4098.262312783698;4085.8161382737553;4092.6112019358116;4094.6314831665295;4105;4105.7331273467125;3627.6553727395853;3631.8728844490315;3644.4715516249994;3652.214773823614;3654.4152935530456;5035.219540971156;5113.287048838643;5114.161640289638;5129.384473863123;5170.302009228743;4647.23320652092;4683.274092515666;4691.741954192518;4693.78877950493;4746.169420351424;5432.2123292122815;5444.7822087116465;5456.908618276321;5484.643498172606;5484.862878428071;3772.2266935278203;3923.6664883484937;4023.295923213426;4026.295398305314;4040.939385909214;5663.631863928831;5709.829484955574;5711.314642242916;5723.8282851514105;5730.541556675497;4059.289624315792;4062.106336605942;4085.080856807195;4101.405296232029;4105.651019663303;3892.3577609694084;4141.710080294411;4143.97928010358;4144.958550414518;4150.294449943517;4652.744099074515;4662.7202367858035;4667.603253723471;4676.321933319649;4699.95300047002;5567.972160139173;5572.693501857356;5581.358143813513;5591;5597.776088956478;1353.4180302470033;1407.4440595964536;1409.457358916804;1414.4342263094845;1423.665438621923;890.7639475538988;927.1933417926447;928.7399528132133;933.3140141995627;937.5124935033745;4737.697133288373;4750.266152854183;4795.880102997453;4840.75796210188;4852.8301509447;4692.694974826625;5011.599072074226;5017.197248440232;5060.898782024413;5079.466656001066;4810.58087864081;4813.229883218668;4824.95175048245;4839.225723884232;4860.73265970368;2648.8278261912915;2649.947001059968;2671.7728993035807;2695.1644990053146;2729.9044533441233;4995.575376093073;5074.517920797518;5090.872728181751;5151.175811870083;5254.663288811632;5022.924656130161;5055.595552355802;5056.205047208188;5056.873578160576;5110.616703747234;5084.313617661568;5117.744112794346;5120.873407850192;5167.328247327899;5197.194434862602;4960.3551538301235;5298.311219541435;5653.773849046157;5668.291463567054;5707.458986073654;5610.270664813711;5617;5618.381977982501;5625.746841392229;5643.516852584849;5723.170140329712;5927.9703601481715;5974.61165024272;5978.461938425563;5979.701014949148;4028;4054.9189016220103;4068.637006602821;4086.060206152661;4100.118474527991;5420.88871781276;5509.016753968488;5697.176903390875;5701.8859622806485;5715.428457154251;5573.972130139324;5586.608937374344;5614.466625670555;5638.238837757014;5662.886742265049;5257.63196576236;5601.187827764869;5625.606207565431;5628.521575665951;5652.265205523256;5464.398916119303;5486.177073439016;5510.504054635102;5518.172274158908;5540.146824385364;5645.209670646031;5657.462541058593;5674.120511320752;5690.459406356389;5699.715014249188;5234.476552344735;5496.945030549643;5499.202615620633;5583.916241256303;5584.776608935597 09:34:40 INFO - PROCESS | 23494 | |5;yui.html;728.9384584744076;745.8918456823757;759.8632246195684;779.9025121859768;842.7513883404397;16390.34438622455;17052.31790728371;17222.483325500223;17242.396516121953;17271.568210794736;15438.228088595553;15484.535463936072;15564.221788910565;15630.374785008615;15670.764938525932;666.5967089910582;778.8286576953061;798.9600519974007;820.6389188756958;825.4675734151468;819.4591569564088;819.7868554175927;822.5147163173726;825.405707890318;826.574314228173;877.5919197573127;878.9208971192592;879.0418443896152;879.2174964281794;879.3844308983737;873.3929918706501;874.5321253129576;875.03745879532;875.2123089219722;875.4659657608853;512.5515174222555;616.6022915219703;633.9778107766252;635.348767513298;635.6313338263795;13985.230812305319;13986.650333741636;13990.440382384693;14016.509422170175;14028.579142625738;14206.715865682629;14853.925730371388;15295.770563441558;15373.154476503787;15590.922045389814;13137.86862131376;13610.455581776718;13801.51694690681;14022.92988535061;14388.208648524327;4503.144402563503;4584.15193189261;4595.747233902134;4657.417822772153;4789.137955168068;84948;85460;86438;87887.56056219742;91059.99999999933;11852.762944741056;11899.583514577122;11907.166498345206;12078.577249796215;12221.450034748279;22789.658155127687;22805.543889122124;22852.31443056711;22926.426839328815;22940.54118917612;8681.30549556034;8685.174908383693;8957.686480973265;9078.682246121354;9166.26669866409;4897.877553061202;4966.701997880139;5000.524950129732;5063.367079115111;5158.4067832199025;3421.0934102462866;3502.212002299513;3523.2777280657406;3528.7177025837873;3543;586.1325238646826;588;588.8881112588595;608.504069183614;612.5497759147023;641.4355367276767;678.1413755729881;707.4163814852767;711.9110111236096;715.2454160860342;9760.219182465386;10039.598416063422;10274.948625256826;10322;10471.94764026175;690.5194747296657;697.2434908124742;703.7079611960979;709.9467539934423;718.0175998261783;4346.413234213404;4441.333799930037;4712.8655870485445;4767.094252092161;4850.151223535867;72380;81414.59292703499;125571.74428255783;126320.36839815926;129133;105322;106397.46801265945;106431.27490537794;107417;111911;93313.53343233105;94150;95183.52408238054;96573;99031.00968990217;9418.529073546433;9647.903520964981;9661.661841835503;9674.467904265333;9702.5148742564;54348;55117.44882551123;55576.72211638916;55930;56041;75120.24879751132;76381.61809190919;80590.5970470144;80968;83215.58392208;11958.64124076279;12031.45858436355;12154.569658364158;12165.69585760363;12482.251064936132;12740.872591273968;13086.738265234451;13627;13706.177629342272;13762.449502019806;12426.81359779586;13016.769549896133;13029.15310504814;13143.868561314264;13256.469741210629;11777.248834672357;13048.869511304765;13165.078444509176;13186.538471153848;13206.207627542379;11715;12082.75834483323;12086;12515.74968500643;12525.123241373016;10209.897901021192;10494.580216791242;10836.241463097507;10942.23404361687;11028.613998510014;9119.680811171576;9991.550380232762;10145.492725363554;11569.421528923353;12321.137520373488 09:34:40 INFO - PROCESS | 23494 | __end_tp_report 09:34:40 INFO - PROCESS | 23494 | __start_cc_report 09:34:40 INFO - PROCESS | 23494 | _x_x_mozilla_cycle_collect,980 09:34:40 INFO - PROCESS | 23494 | __end_cc_report 09:34:40 INFO - PROCESS | 23494 | __startTimestamp1471538080160__endTimestamp 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | ------- Summary: start ------- 09:34:40 INFO - PROCESS | 23494 | Number of tests: 6 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#0] dojo.html Cycles:165 Average:5334.02 Median:5506.34 stddev:612.37 (11.1%) stddev-sans-first:586.70 09:34:40 INFO - PROCESS | 23494 | Values: 3018.8 3033.7 3046.3 3053.5 3065.5 5195.3 5256.0 5344.7 5390.2 5458.6 5337.1 5378.1 5425.9 5466.3 5492.5 4836.9 4839.6 4870.5 4919.4 4935.8 4960.8 4993.6 5016.8 5024.3 5028.5 4943.4 4995.0 4998.0 4998.5 5008.6 4740.4 4749.0 4799.1 4818.7 4853.8 4828.7 4847.2 4848.7 4905.6 4943.7 5407.9 5408.0 5436.0 5442.2 5461.8 5561.6 5726.3 5783.5 5790.6 5797.0 5778.6 5786.3 5818.6 5827.0 5833.4 5123.3 5385.7 5393.7 5396.2 5404.1 5845.3 5933.3 5939.8 6013.4 6020.8 5731.3 5733.2 5737.4 5761.7 5782.9 5549.4 5815.5 5852.7 5863.5 5866.5 5605.7 5653.2 5664.8 5666.3 5714.3 5627.3 5665.5 5668.6 5675.4 5676.6 4253.4 4360.5 4369.6 4382.8 4413.4 3860.7 3911.3 3920.2 3927.5 3968.2 5499.3 5504.9 5506.3 5547.8 5553.0 5594.0 5807.4 5808.6 5815.3 5819.0 5385.8 5397.9 5423.0 5469.9 5480.3 4973.0 4979.5 4987.2 4988.5 5032.7 5741.5 5977.5 5998.9 6003.2 6004.6 5666.5 5709.1 5718.5 5735.4 5772.6 5953.4 5957.8 5957.9 5958.6 5967.5 5470.2 5585.9 5653.7 5663.2 5663.3 5610.9 5630.4 5635.9 5731.5 5747.8 5529.7 5575.4 5575.6 5576.5 5579.6 5439.1 5512.8 5561.3 5562.6 5572.0 5414.8 5432.7 5452.9 5524.1 5547.9 5606.3 5607.1 5648.1 5651.2 5663.4 5348.1 5490.4 5490.7 5497.9 5503.0 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#1] ext.html Cycles:180 Average:62502.12 Median:36939.36 stddev:123482.11 (334.3%) stddev-sans-first:123740.95 09:34:40 INFO - PROCESS | 23494 | Values: 567.6 572.4 602.4 602.8 610.9 32474.8 43229.0 44141.8 44157.0 44214.6 46292.5 46525.3 46699.0 46764.8 46773.3 8016.3 8822.5 9049.8 9051.6 10481.9 10432.5 10912.1 10918.5 10919.7 10926.9 10949.2 10971.8 10989.8 10993.5 10999.8 10930.0 10955.5 10969.9 10978.0 10994.6 10347.3 10375.0 10400.8 10407.1 10411.5 43663.4 44083.3 44102.3 44151.6 44175.6 3570.1 3596.4 3671.6 3727.4 3804.9 32581.3 32744.5 32930.7 32964.7 33169.3 4363.9 4452.9 4509.1 4510.8 4553.7 345384.0 346981.0 348383.0 348586.3 350323.0 58293.8 58724.0 58808.0 59013.4 59014.0 61335.7 61841.0 61998.0 62043.4 62117.4 17318.1 17529.6 17554.3 17563.7 17568.8 11729.4 11979.9 12223.4 12239.0 13044.6 16817.9 16852.8 16856.3 17004.7 17176.8 5838.4 5874.8 5926.6 5962.2 6082.6 962.7 1069.2 1070.4 1089.3 1114.6 33370.5 33737.0 33834.7 34124.0 34497.8 1119.3 1120.5 1186.5 1208.2 1208.4 17823.4 17970.3 18219.4 18483.8 19423.6 537267.0 540187.0 543439.0 544339.0 545434.0 62461.4 62732.0 62769.7 62818.4 62874.7 1277.3 1327.9 1328.4 1384.9 1440.9 25840.0 35875.8 35976.9 36117.1 36366.8 56711.7 57357.0 57404.0 57503.1 57555.4 479250.0 485244.0 487122.0 487307.0 487986.0 25326.5 25654.9 25977.0 28194.7 28648.6 39970.2 44234.6 52177.0 52657.0 53412.5 41248.0 41876.8 42318.0 42415.0 44343.6 40101.6 40496.6 40831.6 41168.9 41265.8 40128.6 41005.5 41488.4 41617.0 41645.0 34705.0 36935.4 36943.3 37769.8 37779.6 28531.7 28596.6 28788.6 28961.7 29083.9 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#2] jquery.html Cycles:180 Average:28233.99 Median:23459.91 stddev:75225.32 (320.7%) stddev-sans-first:75407.27 09:34:40 INFO - PROCESS | 23494 | Values: 377.8 389.3 393.9 402.3 418.5 14308.4 17996.6 20061.5 20188.4 20215.3 19390.3 19425.5 19507.0 19680.7 19783.7 5773.0 5857.1 5935.2 5992.4 6038.7 5934.3 5934.8 5975.3 5975.9 5979.9 5702.3 5844.4 5915.8 5948.2 5956.8 5902.4 5918.4 5944.5 5944.5 5945.5 5097.9 5220.1 5258.1 5264.7 5267.8 23353.3 23566.5 23612.9 23715.0 23718.1 2376.1 2383.3 2442.4 2713.9 2802.3 14128.5 14930.0 15026.0 15265.5 15357.4 1884.2 1962.6 2007.7 2030.9 2052.5 81956.0 83856.0 85083.2 85867.6 86506.1 10496.4 10597.9 10882.3 11029.0 11321.8 15251.5 15280.2 15280.8 15304.0 15308.8 8108.2 8421.6 8575.7 8787.7 8820.4 8337.0 8403.0 8428.6 8586.2 8694.4 6652.6 6772.9 6781.5 6808.3 6845.5 1913.0 1924.6 1925.8 1984.2 1994.3 1013.8 1026.9 1035.8 1071.9 1093.0 20480.4 21021.1 21067.5 21212.4 21275.1 1032.7 1038.8 1086.4 1096.0 1097.7 11599.5 11852.0 12024.2 12070.0 12177.0 430142.0 443919.0 465325.0 469882.0 469894.0 84360.0 85860.1 85975.0 86113.0 86611.6 1026.0 1060.4 1081.2 1084.2 1085.9 17750.9 18046.0 18589.4 18672.6 18913.0 40686.8 41182.2 41545.8 41599.8 41610.8 53643.0 53741.7 53939.7 54000.0 56189.7 5470.7 6525.2 7823.0 8371.3 8652.1 13287.3 13755.7 13927.3 13931.6 14013.6 12048.9 12094.3 12225.4 12281.9 12524.9 11521.8 11764.9 11854.4 11869.8 12176.8 12011.3 12300.3 12387.7 12690.4 12954.6 8123.6 8188.3 8264.3 8478.7 8509.0 11285.3 11416.4 11640.8 11662.8 11998.4 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#3] mootools.html Cycles:180 Average:5987.45 Median:5079.42 stddev:3403.58 (67.0%) stddev-sans-first:3395.21 09:34:40 INFO - PROCESS | 23494 | Values: 1340.9 1475.2 1509.4 1613.5 1777.9 8579.5 8946.0 8946.6 9308.6 9342.8 9102.5 9210.7 9249.0 9252.8 9275.9 2076.2 2361.9 2369.4 2395.1 2395.9 2407.3 2422.3 2425.4 2425.8 2430.4 2414.3 2414.6 2417.2 2425.0 2453.0 2232.0 2242.9 2243.3 2277.9 2363.2 2407.2 2423.5 2424.0 2428.5 2438.5 9348.2 9391.5 9395.9 9396.6 9403.8 5355.4 5525.9 5528.2 5530.2 5535.0 3342.9 3443.7 3549.1 3555.3 3564.8 1183.9 1201.0 2132.2 2146.2 2193.0 12780.4 12840.2 12845.8 12933.2 12989.3 4464.5 4580.7 4759.4 4760.0 4785.9 9975.0 9975.8 9984.9 9985.2 10008.5 5447.9 5449.8 5451.0 5452.7 5454.7 4418.4 4748.4 4791.7 5070.2 5102.5 3916.5 3987.5 4059.0 4064.8 4102.3 1215.4 1234.7 1236.0 1242.3 1250.1 765.4 902.0 906.1 910.7 917.7 6068.4 6075.1 6120.6 6296.6 6412.9 960.4 978.6 999.4 1038.4 1047.9 4012.3 4122.6 4341.7 4353.3 4380.3 11266.7 11579.4 11619.0 11670.5 11709.8 11495.6 11521.3 11523.6 11524.4 11537.9 8062.8 8339.0 8344.1 8418.7 8594.1 6115.1 6236.2 6236.9 6320.6 6343.4 9973.2 10233.4 10238.1 10307.5 10368.4 10490.9 10491.6 10550.3 10597.2 10598.3 7247.1 7850.9 7885.1 7941.4 8003.2 8505.2 8699.8 8705.2 8707.2 8750.2 7750.9 7899.4 7968.0 8039.7 8090.1 7599.2 7804.2 7867.3 7891.2 7909.6 7094.9 7140.5 7141.7 7159.6 7170.4 5178.7 5230.6 5242.5 5291.9 5549.6 5088.6 5144.3 5206.9 5229.6 5504.9 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#4] prototype.html Cycles:180 Average:4543.30 Median:5056.54 stddev:1328.65 (26.3%) stddev-sans-first:1293.89 09:34:40 INFO - PROCESS | 23494 | Values: 313.1 327.2 327.7 331.0 336.6 5279.2 5285.3 5322.7 5338.3 5339.5 4973.5 5044.7 5191.9 5213.5 5243.6 4117.3 4125.9 4127.0 4139.5 4153.5 4123.6 4127.2 4134.4 4135.9 4137.4 3879.5 3911.5 4073.9 4074.7 4098.3 4085.8 4092.6 4094.6 4105.0 4105.7 3627.7 3631.9 3644.5 3652.2 3654.4 5035.2 5113.3 5114.2 5129.4 5170.3 4647.2 4683.3 4691.7 4693.8 4746.2 5432.2 5444.8 5456.9 5484.6 5484.9 3772.2 3923.7 4023.3 4026.3 4040.9 5663.6 5709.8 5711.3 5723.8 5730.5 4059.3 4062.1 4085.1 4101.4 4105.7 3892.4 4141.7 4144.0 4145.0 4150.3 4652.7 4662.7 4667.6 4676.3 4700.0 5568.0 5572.7 5581.4 5591.0 5597.8 1353.4 1407.4 1409.5 1414.4 1423.7 890.8 927.2 928.7 933.3 937.5 4737.7 4750.3 4795.9 4840.8 4852.8 4692.7 5011.6 5017.2 5060.9 5079.5 4810.6 4813.2 4825.0 4839.2 4860.7 2648.8 2649.9 2671.8 2695.2 2729.9 4995.6 5074.5 5090.9 5151.2 5254.7 5022.9 5055.6 5056.2 5056.9 5110.6 5084.3 5117.7 5120.9 5167.3 5197.2 4960.4 5298.3 5653.8 5668.3 5707.5 5610.3 5617.0 5618.4 5625.7 5643.5 5723.2 5928.0 5974.6 5978.5 5979.7 4028.0 4054.9 4068.6 4086.1 4100.1 5420.9 5509.0 5697.2 5701.9 5715.4 5574.0 5586.6 5614.5 5638.2 5662.9 5257.6 5601.2 5625.6 5628.5 5652.3 5464.4 5486.2 5510.5 5518.2 5540.1 5645.2 5657.5 5674.1 5690.5 5699.7 5234.5 5496.9 5499.2 5583.9 5584.8 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - PROCESS | 23494 | [#5] yui.html Cycles:180 Average:21676.31 Median:4685.14 stddev:31722.77 (677.1%) stddev-sans-first:31772.77 09:34:40 INFO - PROCESS | 23494 | Values: 728.9 745.9 759.9 779.9 842.8 16390.3 17052.3 17222.5 17242.4 17271.6 15438.2 15484.5 15564.2 15630.4 15670.8 666.6 778.8 799.0 820.6 825.5 819.5 819.8 822.5 825.4 826.6 877.6 878.9 879.0 879.2 879.4 873.4 874.5 875.0 875.2 875.5 512.6 616.6 634.0 635.3 635.6 13985.2 13986.7 13990.4 14016.5 14028.6 14206.7 14853.9 15295.8 15373.2 15590.9 13137.9 13610.5 13801.5 14022.9 14388.2 4503.1 4584.2 4595.7 4657.4 4789.1 84948.0 85460.0 86438.0 87887.6 91060.0 11852.8 11899.6 11907.2 12078.6 12221.5 22789.7 22805.5 22852.3 22926.4 22940.5 8681.3 8685.2 8957.7 9078.7 9166.3 4897.9 4966.7 5000.5 5063.4 5158.4 3421.1 3502.2 3523.3 3528.7 3543.0 586.1 588.0 588.9 608.5 612.5 641.4 678.1 707.4 711.9 715.2 9760.2 10039.6 10274.9 10322.0 10471.9 690.5 697.2 703.7 709.9 718.0 4346.4 4441.3 4712.9 4767.1 4850.2 72380.0 81414.6 125571.7 126320.4 129133.0 105322.0 106397.5 106431.3 107417.0 111911.0 93313.5 94150.0 95183.5 96573.0 99031.0 9418.5 9647.9 9661.7 9674.5 9702.5 54348.0 55117.4 55576.7 55930.0 56041.0 75120.2 76381.6 80590.6 80968.0 83215.6 11958.6 12031.5 12154.6 12165.7 12482.3 12740.9 13086.7 13627.0 13706.2 13762.4 12426.8 13016.8 13029.2 13143.9 13256.5 11777.2 13048.9 13165.1 13186.5 13206.2 11715.0 12082.8 12086.0 12515.7 12525.1 10209.9 10494.6 10836.2 10942.2 11028.6 9119.7 9991.6 10145.5 11569.4 12321.1 09:34:40 INFO - PROCESS | 23494 | -------- Summary: end -------- 09:34:40 INFO - PROCESS | 23494 | 09:34:40 INFO - TEST-INFO | 23494: exit 0 09:34:40 INFO - TEST-OK | dromaeo_css | took 1115266ms 09:34:40 INFO - TEST-START | kraken 09:34:40 INFO - operating with platform_type : linux_ 09:34:40 INFO - Initialising browser for kraken test... 09:34:40 INFO - TEST-INFO | started process 23801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfEtndd/profile http://localhost:53231/getInfo.html) 09:34:44 INFO - PROCESS | 23801 | __metrics Screen width/height:1600/1200 09:34:44 INFO - PROCESS | 23801 | colorDepth:24 09:34:44 INFO - PROCESS | 23801 | Browser inner width/height: 1024/697 09:34:44 INFO - PROCESS | 23801 | __metrics 09:34:45 INFO - PROCESS | 23801 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:45 INFO - PROCESS | 23801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:46 INFO - TEST-INFO | 23801: exit 0 09:34:46 INFO - Browser initialized. 09:34:46 INFO - Running cycle 1/1 for kraken test... 09:34:46 INFO - TEST-INFO | started process 23882 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfEtndd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:58 INFO - PROCESS | 23882 | RSS: Main: 156372992 09:34:58 INFO - PROCESS | 23882 | 09:35:00 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53231/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:01 INFO - PROCESS | 23882 | RSS: Main: 204247040 09:35:01 INFO - PROCESS | 23882 | 09:35:04 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53231/tests/kraken/driver.html?testName=audio-dft) 09:35:04 INFO - PROCESS | 23882 | RSS: Main: 194387968 09:35:04 INFO - PROCESS | 23882 | 09:35:08 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53231/tests/kraken/driver.html?testName=audio-fft) 09:35:08 INFO - PROCESS | 23882 | RSS: Main: 275292160 09:35:08 INFO - PROCESS | 23882 | 09:35:10 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53231/tests/kraken/driver.html?testName=audio-oscillator) 09:35:11 INFO - PROCESS | 23882 | RSS: Main: 186269696 09:35:11 INFO - PROCESS | 23882 | 09:35:13 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:14 INFO - PROCESS | 23882 | RSS: Main: 190627840 09:35:14 INFO - PROCESS | 23882 | 09:35:17 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53231/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:17 INFO - PROCESS | 23882 | RSS: Main: 225906688 09:35:17 INFO - PROCESS | 23882 | 09:35:21 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53231/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:21 INFO - PROCESS | 23882 | RSS: Main: 225841152 09:35:21 INFO - PROCESS | 23882 | 09:35:24 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53231/tests/kraken/driver.html?testName=json-parse-financial) 09:35:25 INFO - PROCESS | 23882 | RSS: Main: 225951744 09:35:25 INFO - PROCESS | 23882 | 09:35:26 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:27 INFO - PROCESS | 23882 | RSS: Main: 188477440 09:35:27 INFO - PROCESS | 23882 | 09:35:28 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:29 INFO - PROCESS | 23882 | RSS: Main: 196616192 09:35:29 INFO - PROCESS | 23882 | 09:35:31 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:32 INFO - PROCESS | 23882 | RSS: Main: 193294336 09:35:32 INFO - PROCESS | 23882 | 09:35:34 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:35 INFO - PROCESS | 23882 | RSS: Main: 187244544 09:35:35 INFO - PROCESS | 23882 | 09:35:38 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:38 INFO - PROCESS | 23882 | RSS: Main: 191471616 09:35:38 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | Cycle 1(1): loaded http://localhost:53231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53231/tests/kraken/driver.html?testName=ai-astar) 09:35:40 INFO - PROCESS | 23882 | RSS: Main: 242053120 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | __start_tp_report 09:35:40 INFO - PROCESS | 23882 | _x_x_mozilla_page_load 09:35:40 INFO - PROCESS | 23882 | _x_x_mozilla_page_load_details 09:35:40 INFO - PROCESS | 23882 | |i|pagename|runs| 09:35:40 INFO - PROCESS | 23882 | |0;ai-astar;104;111;112;111;108;112;111;110;111;110 09:35:40 INFO - PROCESS | 23882 | |1;audio-beat-detection;144;117;119;179;119;117;146;117;162;118 09:35:40 INFO - PROCESS | 23882 | |2;audio-dft;162;163;161;161;161;161;162;163;162;161 09:35:40 INFO - PROCESS | 23882 | |3;audio-fft;74;75;74;116;76;76;74;74;110;76 09:35:40 INFO - PROCESS | 23882 | |4;audio-oscillator;85;85;127;84;85;86;117;85;85;129 09:35:40 INFO - PROCESS | 23882 | |5;imaging-gaussian-blur;117;117;116;116;117;117;117;117;117;117 09:35:40 INFO - PROCESS | 23882 | |6;imaging-darkroom;145;145;146;145;145;145;146;146;146;147 09:35:40 INFO - PROCESS | 23882 | |7;imaging-desaturate;96;95;95;95;95;95;95;96;96;95 09:35:40 INFO - PROCESS | 23882 | |8;json-parse-financial;50;49;52;53;51;54;49;54;51;50 09:35:40 INFO - PROCESS | 23882 | |9;json-stringify-tinderbox;62;62;62;62;62;63;62;61;63;63 09:35:40 INFO - PROCESS | 23882 | |10;stanford-crypto-aes;80;87;79;87;78;79;78;78;77;78 09:35:40 INFO - PROCESS | 23882 | |11;stanford-crypto-ccm;126;132;121;121;122;126;124;130;142;122 09:35:40 INFO - PROCESS | 23882 | |12;stanford-crypto-pbkdf2;164;160;160;160;159;159;160;163;161;160 09:35:40 INFO - PROCESS | 23882 | |13;stanford-crypto-sha256-iterative;64;68;64;65;63;66;63;63;63;64 09:35:40 INFO - PROCESS | 23882 | __end_tp_report 09:35:40 INFO - PROCESS | 23882 | __start_cc_report 09:35:40 INFO - PROCESS | 23882 | _x_x_mozilla_cycle_collect,1697 09:35:40 INFO - PROCESS | 23882 | __end_cc_report 09:35:40 INFO - PROCESS | 23882 | __startTimestamp1471538140624__endTimestamp 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | ------- Summary: start ------- 09:35:40 INFO - PROCESS | 23882 | Number of tests: 14 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.40 (2.2%) stddev-sans-first:1.22 09:35:40 INFO - PROCESS | 23882 | Values: 104.0 111.0 112.0 111.0 108.0 112.0 111.0 110.0 111.0 110.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:131.50 stddev:22.67 (17.2%) stddev-sans-first:23.74 09:35:40 INFO - PROCESS | 23882 | Values: 144.0 117.0 119.0 179.0 119.0 117.0 146.0 117.0 162.0 118.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 09:35:40 INFO - PROCESS | 23882 | Values: 162.0 163.0 161.0 161.0 161.0 161.0 162.0 163.0 162.0 161.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#3] audio-fft Cycles:10 Average:82.50 Median:74.50 stddev:16.16 (21.7%) stddev-sans-first:16.85 09:35:40 INFO - PROCESS | 23882 | Values: 74.0 75.0 74.0 116.0 76.0 76.0 74.0 74.0 110.0 76.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:85.00 stddev:19.25 (22.6%) stddev-sans-first:19.93 09:35:40 INFO - PROCESS | 23882 | Values: 85.0 85.0 127.0 84.0 85.0 86.0 117.0 85.0 85.0 129.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:35:40 INFO - PROCESS | 23882 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 09:35:40 INFO - PROCESS | 23882 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 147.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:35:40 INFO - PROCESS | 23882 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:1.89 (3.7%) stddev-sans-first:1.94 09:35:40 INFO - PROCESS | 23882 | Values: 50.0 49.0 52.0 53.0 51.0 54.0 49.0 54.0 51.0 50.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 09:35:40 INFO - PROCESS | 23882 | Values: 62.0 62.0 62.0 62.0 62.0 63.0 62.0 61.0 63.0 63.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:3.73 (4.7%) stddev-sans-first:3.95 09:35:40 INFO - PROCESS | 23882 | Values: 80.0 87.0 79.0 87.0 78.0 79.0 78.0 78.0 77.0 78.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:126.00 stddev:6.59 (5.2%) stddev-sans-first:6.98 09:35:40 INFO - PROCESS | 23882 | Values: 126.0 132.0 121.0 121.0 122.0 126.0 124.0 130.0 142.0 122.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:160.00 stddev:1.65 (1.0%) stddev-sans-first:1.20 09:35:40 INFO - PROCESS | 23882 | Values: 164.0 160.0 160.0 160.0 159.0 159.0 160.0 163.0 161.0 160.0 09:35:40 INFO - PROCESS | 23882 | 09:35:40 INFO - PROCESS | 23882 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.64 (2.6%) stddev-sans-first:1.73 09:35:40 INFO - PROCESS | 23882 | Values: 64.0 68.0 64.0 65.0 63.0 66.0 63.0 63.0 63.0 64.0 09:35:40 INFO - PROCESS | 23882 | -------- Summary: end -------- 09:35:40 INFO - PROCESS | 23882 | 09:35:41 INFO - TEST-INFO | 23882: exit 0 09:35:41 INFO - TEST-OK | kraken | took 60696ms 09:35:41 INFO - SUITE-END | took 1175s 09:35:41 INFO - Completed test suite (00:19:36) 09:35:41 INFO - javascript benchmark 09:35:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3018.833964131973, 3033.6966303369654, 3046.2841232310416, 3053.5267033609784, 3065.494193458078, 5195.324607800981, 5256.001359741646, 5344.706041167739, 5390.245365648808, 5458.617896747224, 5337.092694241978, 5378.058839703046, 5425.9186112208345, 5466.262054622624, 5492.450754924505, 4836.903261934759, 4839.637027222955, 4870.537298956593, 4919.4096708395045, 4935.753212339387, 4960.751962401883, 4993.600511959053, 5016.799328026875, 5024.271480635306, 5028.547430731233, 4943.431505376856, 4994.975025124888, 4997.975010124945, 4998.475160108191, 5008.649394542384, 4740.360051393052, 4748.976255118737, 4799.13615549201, 4818.68678535894, 4853.757312134379, 4828.710277383333, 4847.248676455155, 4848.7090774553635, 4905.558499735041, 4943.728094954776, 5407.945920540784, 5408.0, 5436.0, 5442.183672449125, 5461.836144915659, 5561.58288128392, 5726.282115365374, 5783.537317014629, 5790.594658373915, 5797.043487824504, 5778.566607504454, 5786.276715410574, 5818.592698511064, 5827.0, 5833.445822646841, 5123.257127716461, 5385.703786291752, 5393.7033463159905, 5396.163594642837, 5404.054290499186, 5845.327787304429, 5933.258342707161, 5939.792107276225, 6013.428724271187, 6020.759169633253, 5731.312242530923, 5733.226014487991, 5737.3688894221605, 5761.654300741969, 5782.855428614234, 5549.361823390281, 5815.476607105381, 5852.707364631751, 5863.5309175265875, 5866.50134738552, 5605.747741351567, 5653.23681303027, 5664.830055098271, 5666.320041594952, 5714.314282286069, 5627.296587926509, 5665.5467562594895, 5668.631538949955, 5675.3757086720425, 5676.631018983753, 4253.404523366731, 4360.542143074995, 4369.628581570539, 4382.780860956939, 4413.44831896013, 3860.7104467165077, 3911.2568611963634, 3920.2355540669296, 3927.489426374553, 3968.1865217630316, 5499.340079190522, 5504.889902201933, 5506.339239291311, 5547.7780888764, 5552.972235138879, 5594.0, 5807.390224026501, 5808.5933984621515, 5815.273090863642, 5819.0, 5385.838424847261, 5397.865053373697, 5423.023855705932, 5469.945300546943, 5480.342358916955, 4972.975135124301, 4979.45226025137, 4987.202047672355, 4988.5260900214425, 5032.748362581856, 5741.483266506035, 5977.462028417463, 5998.88002239944, 6003.219581454383, 6004.639721616717, 5666.490015898589, 5709.115087161496, 5718.485336319751, 5735.36910939788, 5772.595918285848, 5953.434423729739, 5957.791477298274, 5957.880842383041, 5958.553108516792, 5967.49276311501, 5470.234167216672, 5585.860353491195, 5653.660780353192, 5663.207150998781, 5663.348714897675, 5610.887782244414, 5630.4369563043365, 5635.8591035224445, 5731.484166425042, 5747.827565173052, 5529.668219906818, 5575.442455754554, 5575.553955683455, 5576.470235327637, 5579.609427340048, 5439.102548079522, 5512.834614961557, 5561.277033985556, 5562.638428502134, 5572.0, 5414.75633596497, 5432.728363581726, 5452.91820622683, 5524.14375771756, 5547.8890422192135, 5606.271184745957, 5607.102863541977, 5648.124540696199, 5651.208830763779, 5663.433656634304, 5348.064088784369, 5490.368607610017, 5490.670559766427, 5497.890042199054, 5502.972485137549], "value": 5291.803650250721, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.5994507725928, 572.3732512898376, 602.4216751918158, 602.7950496831077, 610.9389061093891, 32474.837625811895, 43229.0, 44141.77929110358, 44157.0, 44214.557854421444, 46292.53707462925, 46525.25383819344, 46699.0, 46764.76617616907, 46773.29840052402, 8016.278534931855, 8822.4706517609, 9049.773755656128, 9051.592678329458, 10481.895181048169, 10432.530536125854, 10912.072473839733, 10918.454077296143, 10919.672409827717, 10926.945365273203, 10949.178811589163, 10971.780564388708, 10989.835152472719, 10993.505292261867, 10999.835002474969, 10930.0, 10955.4522273886, 10969.890301096966, 10978.0, 10994.615188468406, 10347.327423717434, 10375.0, 10400.843987340197, 10407.063364297173, 10411.479426028669, 43663.38019718795, 44083.338749918774, 44102.33846492305, 44151.558484415065, 44175.558244417785, 3570.107473131717, 3596.3886139356373, 3671.561751921748, 3727.366347720867, 3804.904877378088, 32581.348373032408, 32744.508832367294, 32930.74315879975, 32964.6703532964, 33169.33661326784, 4363.869083927487, 4452.866414007585, 4509.075639493896, 4510.774461276956, 4553.658475614309, 345384.0, 346981.0, 348383.0, 348586.2570687156, 350323.0, 58293.8341233173, 58724.0, 58808.0, 59013.40986590165, 59014.0, 61335.69332153311, 61841.0, 61998.0, 62043.37956620376, 62117.378826212065, 17318.134093295284, 17529.581731562477, 17554.29782808699, 17563.73654395185, 17568.824311756718, 11729.35488548121, 11979.880201198052, 12223.44994475233, 12239.0, 13044.608661740163, 16817.915910420368, 16852.831471685127, 16856.325746969986, 17004.659906801793, 17176.828231717773, 5838.3510306058715, 5874.823755287348, 5926.614770039933, 5962.165296858443, 6082.635041897501, 962.6678795815433, 1069.2461814420815, 1070.3791800755544, 1089.2974031749477, 1114.6043154679971, 33370.49944250838, 33737.0, 33834.66165338315, 34124.0, 34497.827510862786, 1119.2780656476527, 1120.467777805536, 1186.5194596188558, 1208.2327721896659, 1208.4078801387257, 17823.376181833573, 17970.281188752564, 18219.362322318655, 18483.815161848208, 19423.611527769648, 537267.0, 540187.0, 543439.0, 544339.0, 545434.0, 62461.375386245556, 62732.0, 62769.68615156895, 62818.37181628125, 62874.68562657249, 1277.2591896699892, 1327.9136856104321, 1328.3889410871107, 1384.940520501817, 1440.9279536023157, 25839.980902743122, 35875.82062089673, 35976.88944721201, 36117.07492435653, 36366.818165909004, 56711.71644141753, 57357.0, 57404.0, 57503.1374529374, 57555.42444575668, 479250.0, 485244.0, 487122.0, 487307.0, 487986.0, 25326.493470130863, 25654.871725641253, 25977.0, 28194.718052819208, 28648.57027144553, 39970.201340939144, 44234.55765442304, 52177.0, 52657.0, 53412.46587534231, 41248.0, 41876.790616046725, 42318.0, 42415.0, 44343.55656443523, 40101.59898400979, 40496.595034050464, 40831.59168408396, 41168.853893263346, 41265.793671032654, 40128.598714012485, 41005.51453126517, 41488.377674334304, 41617.0, 41645.0, 34705.0, 36935.445968309956, 36943.2737925622, 37769.81115094407, 37779.62220377871, 28531.714682852904, 28596.571051433828, 28788.568171477025, 28961.710382896745, 29083.854580727806], "value": 20843.00733173237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.78277490443, 389.34589888986494, 393.9094008378072, 402.2598418909207, 418.4998926283091, 14308.427662893484, 17996.55008624785, 20061.498462538442, 20188.394348169542, 20215.2924647637, 19390.321338753147, 19425.514362140955, 19507.0, 19680.70478942817, 19783.70324445131, 5773.0, 5857.092150716646, 5935.198748168985, 5992.370801065891, 6038.69806509675, 5934.31755348134, 5934.821955341346, 5975.342712301644, 5975.880482390371, 5979.850503737398, 5702.287214098238, 5844.444777746128, 5915.763369465238, 5948.167256584082, 5956.821295361146, 5902.380250073745, 5918.437748413894, 5944.524438044946, 5944.524438044946, 5945.4946329562035, 5097.872553186163, 5220.112580861262, 5258.0535503609335, 5264.657797243167, 5267.815626453094, 23353.299401017997, 23566.528669426687, 23612.881935590383, 23715.0, 23718.05127794886, 2376.120835290936, 2383.332666853284, 2442.3894026493376, 2713.891444342224, 2802.2574017885277, 14128.505502307473, 14930.0, 15026.0, 15265.465708700141, 15357.385704571916, 1884.1992153334777, 1962.5878565501262, 2007.688808234726, 2030.8781473111512, 2052.5073982244303, 81956.0, 83855.99999999939, 85083.17125729295, 85867.57066214691, 86506.13493865107, 10496.370217786956, 10597.9470102649, 10882.292650977819, 11029.0, 11321.773564528663, 15251.542453726406, 15280.15959122259, 15280.847191528164, 15304.0, 15308.770368444482, 8108.2297181767635, 8421.578921054046, 8575.699850505202, 8787.692430764893, 8820.426672266281, 8337.0, 8402.957985210158, 8428.620712067972, 8586.184312490303, 8694.434861733967, 6652.634105124264, 6772.93227067723, 6781.491388145811, 6808.285130061272, 6845.520813543004, 1912.9713054304198, 1924.6054558815408, 1925.8170473804967, 1984.186483541741, 1994.321930543622, 1013.7668336282696, 1026.8767747870304, 1035.7980193862275, 1071.8723334181643, 1093.0326660905157, 20480.38558843237, 21021.05705201273, 21067.473313167295, 21212.363629091153, 21275.148994040064, 1032.736652153711, 1038.7818558102883, 1086.4241951765578, 1095.950682219302, 1097.7036200225893, 11599.478023488964, 11852.0, 12024.21842580247, 12070.0, 12177.0, 430142.0, 443919.0, 465325.0, 469882.0, 469894.0, 84360.0, 85860.14139858646, 85975.0, 86113.0, 86611.56694216614, 1026.045777426985, 1060.4432672846663, 1081.2053140941402, 1084.2356138922041, 1085.9022687958122, 17750.91124544369, 18046.0, 18589.44231673052, 18672.626547469245, 18913.0, 40686.79656601698, 41182.176356473305, 41545.79227103845, 41599.7920010398, 41610.79194604007, 53643.0, 53741.73129134329, 53939.730301348245, 54000.0, 56189.71905140448, 5470.671759694431, 6525.249596296487, 7822.96602883243, 8371.288440482627, 8652.091530389218, 13287.269200194081, 13755.724885502033, 13927.303634818016, 13931.58205253844, 14013.649658758613, 12048.879511205127, 12094.274343539415, 12225.449854756735, 12281.877181228074, 12524.941678661764, 11521.82717259225, 11764.882351176379, 11854.407279636156, 11869.762604747684, 12176.817347739614, 12011.339376334385, 12300.323482208207, 12387.69030774238, 12690.428930697955, 12954.611361659165, 8123.553204573569, 8188.2630563249595, 8264.297534709376, 8478.703245386381, 8508.957455212685, 11285.266457680225, 11416.401703761647, 11640.767184656428, 11662.766744665229, 11998.40007999614], "value": 9550.53237963902, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.8860246879015, 1475.1960181700972, 1509.4037855047256, 1613.5240104169268, 1777.9199936002879, 8579.485230886143, 8945.955270223649, 8946.597403116868, 9308.581113849876, 9342.766430839216, 9102.499362535058, 9210.67762628308, 9248.953755231214, 9252.814943701122, 9275.907240927589, 2076.1799089359693, 2361.8819059047064, 2369.395804069964, 2395.089865850974, 2395.8562486250794, 2407.3379820549403, 2422.2975337152293, 2425.417899704067, 2425.7695518925675, 2430.4045508850268, 2414.33605758417, 2414.6498757680124, 2417.2023232333286, 2424.9757502424927, 2453.0, 2231.977680223193, 2242.9439264018365, 2243.270936945491, 2277.9202727904444, 2363.161077817367, 2407.2176542623715, 2423.478952025323, 2424.0425032112394, 2428.477877256398, 2438.451348446603, 9348.205402540792, 9391.530423478798, 9395.859062114074, 9396.624135034659, 9403.811923761556, 5355.437679043722, 5525.889482210333, 5528.226048353234, 5530.198121272393, 5535.0, 3342.866285348583, 3443.707284880788, 3549.0949807798847, 3555.2711694102645, 3564.7861128332124, 1183.9112066594953, 1201.0123246027838, 2132.2323963373174, 2146.162996431393, 2193.0, 12780.360981950958, 12840.165389249669, 12845.807312890314, 12933.159344642661, 12989.285589292585, 4464.486584042812, 4580.748058856795, 4759.381280433521, 4760.0, 4785.928211076767, 9975.0, 9975.800483990279, 9984.900150998397, 9985.20118390527, 10008.49957502122, 5447.945520544823, 5449.809256675997, 5451.04606693827, 5452.727363631802, 5454.7272636368025, 4418.381426600278, 4748.358971538798, 4791.736454495036, 5070.239464080418, 5102.515261050195, 3916.4516967624554, 3987.4616926715103, 4058.9594104059174, 4064.837406503707, 4102.261592913304, 1215.3740823475916, 1234.703671118925, 1235.950561977511, 1242.3353505874313, 1250.143651598658, 765.3609236287651, 902.0438335364535, 906.1074841281333, 910.7267819654078, 917.655879045358, 6068.423499767603, 6075.149479072933, 6120.571559990847, 6296.622202667854, 6412.871742565123, 960.4045491795132, 978.5596481583174, 999.4453078541479, 1038.4029183219527, 1047.9371237725761, 4012.337964235927, 4122.632103688933, 4341.739495630272, 4353.281708518059, 4380.255356589388, 11266.7183320416, 11579.363135027654, 11619.0, 11670.474828632732, 11709.824352634718, 11495.597654082067, 11521.308721476738, 11523.596674116534, 11524.423778811026, 11537.942310288563, 8062.7581172564915, 8338.958305208434, 8344.08215096339, 8418.663253469915, 8594.140585941355, 6115.113308570321, 6236.220472440945, 6236.875262494816, 6320.62076275425, 6343.365663433619, 9973.152282056106, 10233.437160956219, 10238.129758970274, 10307.536160872629, 10368.377897325883, 10490.895091048991, 10491.580336786443, 10550.314229575053, 10597.205209609156, 10598.31110977784, 7247.13034435871, 7850.882236766339, 7885.093214280431, 7941.404394670307, 8003.159668234752, 8505.192006759347, 8699.782505437415, 8705.173008564176, 8707.172818582225, 8750.212480876751, 7750.901644659558, 7899.447038707235, 7968.0, 8039.67841286342, 8090.110087890445, 7599.164091949991, 7804.180561041009, 7867.252611001945, 7891.210878912063, 7909.604519774103, 7094.9290507094265, 7140.46446516503, 7141.714331426892, 7159.60622165765, 7170.39051680613, 5178.689278643294, 5230.555402790806, 5242.501962313574, 5291.920621190762, 5549.611527193219, 5088.592912566912, 5144.331236939174, 5206.921896171636, 5229.6339256251695, 5504.9174262385295], "value": 4806.348629790403, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.1123265542189, 327.1543061186835, 327.6952434236159, 330.99338013239736, 336.56078817143623, 5279.20811878218, 5285.339332583427, 5322.707251101193, 5338.27933229014, 5339.466053394659, 4973.5026497350245, 5044.697318160913, 5191.896162076756, 5213.530782229599, 5243.580513558915, 4117.258893399187, 4125.855595054174, 4126.958730412687, 4139.544650088488, 4153.50157981042, 4123.649489793371, 4127.17456508698, 4134.359174327984, 4135.855245066423, 4137.407098267174, 3879.467610293932, 3911.5110611173604, 4073.8981525461954, 4074.6740260779065, 4098.262312783698, 4085.8161382737553, 4092.6112019358116, 4094.6314831665295, 4105.0, 4105.7331273467125, 3627.6553727395853, 3631.8728844490315, 3644.4715516249994, 3652.214773823614, 3654.4152935530456, 5035.219540971156, 5113.287048838643, 5114.161640289638, 5129.384473863123, 5170.302009228743, 4647.23320652092, 4683.274092515666, 4691.741954192518, 4693.78877950493, 4746.169420351424, 5432.2123292122815, 5444.7822087116465, 5456.908618276321, 5484.643498172606, 5484.862878428071, 3772.2266935278203, 3923.6664883484937, 4023.295923213426, 4026.295398305314, 4040.939385909214, 5663.631863928831, 5709.829484955574, 5711.314642242916, 5723.8282851514105, 5730.541556675497, 4059.289624315792, 4062.106336605942, 4085.080856807195, 4101.405296232029, 4105.651019663303, 3892.3577609694084, 4141.710080294411, 4143.97928010358, 4144.958550414518, 4150.294449943517, 4652.744099074515, 4662.7202367858035, 4667.603253723471, 4676.321933319649, 4699.95300047002, 5567.972160139173, 5572.693501857356, 5581.358143813513, 5591.0, 5597.776088956478, 1353.4180302470033, 1407.4440595964536, 1409.457358916804, 1414.4342263094845, 1423.665438621923, 890.7639475538988, 927.1933417926447, 928.7399528132133, 933.3140141995627, 937.5124935033745, 4737.697133288373, 4750.266152854183, 4795.880102997453, 4840.75796210188, 4852.8301509447, 4692.694974826625, 5011.599072074226, 5017.197248440232, 5060.898782024413, 5079.466656001066, 4810.58087864081, 4813.229883218668, 4824.95175048245, 4839.225723884232, 4860.73265970368, 2648.8278261912915, 2649.947001059968, 2671.7728993035807, 2695.1644990053146, 2729.9044533441233, 4995.575376093073, 5074.517920797518, 5090.872728181751, 5151.175811870083, 5254.663288811632, 5022.924656130161, 5055.595552355802, 5056.205047208188, 5056.873578160576, 5110.616703747234, 5084.313617661568, 5117.744112794346, 5120.873407850192, 5167.328247327899, 5197.194434862602, 4960.3551538301235, 5298.311219541435, 5653.773849046157, 5668.291463567054, 5707.458986073654, 5610.270664813711, 5617.0, 5618.381977982501, 5625.746841392229, 5643.516852584849, 5723.170140329712, 5927.9703601481715, 5974.61165024272, 5978.461938425563, 5979.701014949148, 4028.0, 4054.9189016220103, 4068.637006602821, 4086.060206152661, 4100.118474527991, 5420.88871781276, 5509.016753968488, 5697.176903390875, 5701.8859622806485, 5715.428457154251, 5573.972130139324, 5586.608937374344, 5614.466625670555, 5638.238837757014, 5662.886742265049, 5257.63196576236, 5601.187827764869, 5625.606207565431, 5628.521575665951, 5652.265205523256, 5464.398916119303, 5486.177073439016, 5510.504054635102, 5518.172274158908, 5540.146824385364, 5645.209670646031, 5657.462541058593, 5674.120511320752, 5690.459406356389, 5699.715014249188, 5234.476552344735, 5496.945030549643, 5499.202615620633, 5583.916241256303, 5584.776608935597], "value": 4121.212094903602, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.9384584744076, 745.8918456823757, 759.8632246195684, 779.9025121859768, 842.7513883404397, 16390.34438622455, 17052.31790728371, 17222.483325500223, 17242.396516121953, 17271.568210794736, 15438.228088595553, 15484.535463936072, 15564.221788910565, 15630.374785008615, 15670.764938525932, 666.5967089910582, 778.8286576953061, 798.9600519974007, 820.6389188756958, 825.4675734151468, 819.4591569564088, 819.7868554175927, 822.5147163173726, 825.405707890318, 826.574314228173, 877.5919197573127, 878.9208971192592, 879.0418443896152, 879.2174964281794, 879.3844308983737, 873.3929918706501, 874.5321253129576, 875.03745879532, 875.2123089219722, 875.4659657608853, 512.5515174222555, 616.6022915219703, 633.9778107766252, 635.348767513298, 635.6313338263795, 13985.230812305319, 13986.650333741636, 13990.440382384693, 14016.509422170175, 14028.579142625738, 14206.715865682629, 14853.925730371388, 15295.770563441558, 15373.154476503787, 15590.922045389814, 13137.86862131376, 13610.455581776718, 13801.51694690681, 14022.92988535061, 14388.208648524327, 4503.144402563503, 4584.15193189261, 4595.747233902134, 4657.417822772153, 4789.137955168068, 84948.0, 85460.0, 86438.0, 87887.56056219742, 91059.99999999933, 11852.762944741056, 11899.583514577122, 11907.166498345206, 12078.577249796215, 12221.450034748279, 22789.658155127687, 22805.543889122124, 22852.31443056711, 22926.426839328815, 22940.54118917612, 8681.30549556034, 8685.174908383693, 8957.686480973265, 9078.682246121354, 9166.26669866409, 4897.877553061202, 4966.701997880139, 5000.524950129732, 5063.367079115111, 5158.4067832199025, 3421.0934102462866, 3502.212002299513, 3523.2777280657406, 3528.7177025837873, 3543.0, 586.1325238646826, 588.0, 588.8881112588595, 608.504069183614, 612.5497759147023, 641.4355367276767, 678.1413755729881, 707.4163814852767, 711.9110111236096, 715.2454160860342, 9760.219182465386, 10039.598416063422, 10274.948625256826, 10322.0, 10471.94764026175, 690.5194747296657, 697.2434908124742, 703.7079611960979, 709.9467539934423, 718.0175998261783, 4346.413234213404, 4441.333799930037, 4712.8655870485445, 4767.094252092161, 4850.151223535867, 72380.0, 81414.59292703499, 125571.74428255783, 126320.36839815926, 129133.0, 105322.0, 106397.46801265945, 106431.27490537794, 107417.0, 111911.0, 93313.53343233105, 94150.0, 95183.52408238054, 96573.0, 99031.00968990217, 9418.529073546433, 9647.903520964981, 9661.661841835503, 9674.467904265333, 9702.5148742564, 54348.0, 55117.44882551123, 55576.72211638916, 55930.0, 56041.0, 75120.24879751132, 76381.61809190919, 80590.5970470144, 80968.0, 83215.58392208, 11958.64124076279, 12031.45858436355, 12154.569658364158, 12165.69585760363, 12482.251064936132, 12740.872591273968, 13086.738265234451, 13627.0, 13706.177629342272, 13762.449502019806, 12426.81359779586, 13016.769549896133, 13029.15310504814, 13143.868561314264, 13256.469741210629, 11777.248834672357, 13048.869511304765, 13165.078444509176, 13186.538471153848, 13206.207627542379, 11715.0, 12082.75834483323, 12086.0, 12515.74968500643, 12525.123241373016, 10209.897901021192, 10494.580216791242, 10836.241463097507, 10942.23404361687, 11028.613998510014, 9119.680811171576, 9991.550380232762, 10145.492725363554, 11569.421528923353, 12321.137520373488], "value": 7678.958204158441, "unit": "score"}], "name": "dromaeo_css", "value": 7369.933224019251, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 111.0, 108.0, 112.0, 111.0, 110.0, 111.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 119.0, 179.0, 119.0, 117.0, 146.0, 117.0, 162.0, 118.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 161.0, 161.0, 161.0, 162.0, 163.0, 162.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 116.0, 76.0, 76.0, 74.0, 74.0, 110.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 127.0, 84.0, 85.0, 86.0, 117.0, 85.0, 85.0, 129.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 147.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 53.0, 51.0, 54.0, 49.0, 54.0, 51.0, 50.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 79.0, 87.0, 78.0, 79.0, 78.0, 78.0, 77.0, 78.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 121.0, 121.0, 122.0, 126.0, 124.0, 130.0, 142.0, 122.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 160.0, 160.0, 159.0, 159.0, 160.0, 163.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 64.0, 65.0, 63.0, 66.0, 63.0, 63.0, 63.0, 64.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1487.5999999999995, "alertThreshold": 2.0}]} 09:35:41 INFO - Return code: 0 09:35:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:35:41 INFO - # TBPL SUCCESS # 09:35:41 INFO - Running post-action listener: _resource_record_post_action 09:35:41 INFO - [mozharness: 2016-08-18 16:35:41.941624Z] Finished run-tests step (success) 09:35:41 INFO - Running post-run listener: _resource_record_post_run 09:35:42 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 409473024; Read time: 260; Write time: 261740 09:35:42 INFO - TinderboxPrint: CPU usage
13.0% 09:35:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 260 09:35:42 INFO - TinderboxPrint: I/O write bytes / time
409,473,024 / 261,740 09:35:42 INFO - TinderboxPrint: CPU idle
8,279.9 (87.0%) 09:35:42 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 09:35:42 INFO - TinderboxPrint: Swap in / out
0 / 0 09:35:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118001664; Read time: 92; Write time: 87844 09:35:42 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291467264; Read time: 168; Write time: 173896 09:35:42 INFO - Running post-run listener: _upload_blobber_files 09:35:42 INFO - Blob upload gear active. 09:35:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:35:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:35:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:35:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:43 INFO - (blobuploader) - INFO - Done attempting. 09:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:44 INFO - (blobuploader) - INFO - Done attempting. 09:35:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:49 INFO - (blobuploader) - INFO - Done attempting. 09:35:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:49 INFO - Return code: 0 09:35:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32bdd5af3bfe6519210c32471a3434fdaac6cbdc36f1b030d4e25f40791a61dcb252764501f6912922e9b766346516cbc2b29d9b958b470dd74bf94e3301829d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5cd048e954fc95ed673060010c8489fbaf0faf33cee6253fb02cadd02119390b6fd9590a825be320b18641693410fc69e28bfdac44d8014baf9b809b6c76394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b036a60717af87941d48e88820ab6de4bb4d458b888cf3b91d9b4bde589b292b156b14f98bc1e8a4e1f3de3dfce42fdbaebe4fbfd4715fa91b98a4b805f461c7"} 09:35:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:49 INFO - Contents: 09:35:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32bdd5af3bfe6519210c32471a3434fdaac6cbdc36f1b030d4e25f40791a61dcb252764501f6912922e9b766346516cbc2b29d9b958b470dd74bf94e3301829d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5cd048e954fc95ed673060010c8489fbaf0faf33cee6253fb02cadd02119390b6fd9590a825be320b18641693410fc69e28bfdac44d8014baf9b809b6c76394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b036a60717af87941d48e88820ab6de4bb4d458b888cf3b91d9b4bde589b292b156b14f98bc1e8a4e1f3de3dfce42fdbaebe4fbfd4715fa91b98a4b805f461c7"} 09:35:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:49 INFO - Copying logs to upload dir... 09:35:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.192737 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-18 09:35:48.877914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 09:35:48.882989) ========= 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=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32bdd5af3bfe6519210c32471a3434fdaac6cbdc36f1b030d4e25f40791a61dcb252764501f6912922e9b766346516cbc2b29d9b958b470dd74bf94e3301829d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5cd048e954fc95ed673060010c8489fbaf0faf33cee6253fb02cadd02119390b6fd9590a825be320b18641693410fc69e28bfdac44d8014baf9b809b6c76394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b036a60717af87941d48e88820ab6de4bb4d458b888cf3b91d9b4bde589b292b156b14f98bc1e8a4e1f3de3dfce42fdbaebe4fbfd4715fa91b98a4b805f461c7"} build_url:https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/VN0EGphUQeO1b11I9V2auA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32bdd5af3bfe6519210c32471a3434fdaac6cbdc36f1b030d4e25f40791a61dcb252764501f6912922e9b766346516cbc2b29d9b958b470dd74bf94e3301829d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5cd048e954fc95ed673060010c8489fbaf0faf33cee6253fb02cadd02119390b6fd9590a825be320b18641693410fc69e28bfdac44d8014baf9b809b6c76394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b036a60717af87941d48e88820ab6de4bb4d458b888cf3b91d9b4bde589b292b156b14f98bc1e8a4e1f3de3dfce42fdbaebe4fbfd4715fa91b98a4b805f461c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 09:35:48.911961) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:35:48.912429) ========= 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=db7e7c15f3ca076d53731445000001ba-1471536909.330288-1083335161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004385 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 09:35:48.992842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 09:35:48.995794) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 09:35:48.996118) ========= ========= Total master_lag: 0.17 =========