builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1470337616.93 results: success (0) buildid: 20160804105222 builduid: 06958df2395a4e0f8c6316723072aee0 revision: d912055f8e8ad75d189bd480017fdb5a33ee4d7e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:56.927530) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:56.927992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:56.928272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:56.945833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:56.946162) ========= 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=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-04 12:06:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 58.6K=0.2s 2016-08-04 12:06:57 (58.6 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.274881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:57.236085) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:57.236440) ========= 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=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021947 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:57.286285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:57.286679) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d912055f8e8ad75d189bd480017fdb5a33ee4d7e --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 d912055f8e8ad75d189bd480017fdb5a33ee4d7e --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=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-04 12:06:57,819 truncating revision to first 12 chars 2016-08-04 12:06:57,819 Setting DEBUG logging. 2016-08-04 12:06:57,819 attempt 1/10 2016-08-04 12:06:57,819 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d912055f8e8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 12:06:58,146 unpacking tar archive at: fx-team-d912055f8e8a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.764960 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:58.065140) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:58.065487) ========= script_repo_revision: d912055f8e8ad75d189bd480017fdb5a33ee4d7e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:58.065895) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:58.066180) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 12:06:58.076006) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-04 12:06:58.076440) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:06:58 INFO - MultiFileLogger online at 20160804 12:06:58 in /builds/slave/test 12:06:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:06:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:06:58 INFO - {'append_to_log': False, 12:06:58 INFO - 'base_work_dir': '/builds/slave/test', 12:06:58 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:06:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:06:58 INFO - 'branch': 'Fx-Team-Non-PGO', 12:06:58 INFO - 'buildbot_json_path': 'buildprops.json', 12:06:58 INFO - 'config_files': ('talos/linux_config.py',), 12:06:58 INFO - 'default_actions': ('clobber', 12:06:58 INFO - 'read-buildbot-config', 12:06:58 INFO - 'download-and-extract', 12:06:58 INFO - 'populate-webroot', 12:06:58 INFO - 'create-virtualenv', 12:06:58 INFO - 'install', 12:06:58 INFO - 'run-tests'), 12:06:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:06:58 INFO - 'download_minidump_stackwalk': True, 12:06:58 INFO - 'download_symbols': 'ondemand', 12:06:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:06:58 INFO - 'tooltool.py': '/tools/tooltool.py', 12:06:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:06:58 INFO - '/tools/misc-python/virtualenv.py')}, 12:06:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:06:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:06:58 INFO - 'installer_path': 'installer.exe', 12:06:58 INFO - 'log_level': 'info', 12:06:58 INFO - 'log_name': 'talos', 12:06:58 INFO - 'log_to_console': True, 12:06:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:06:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:06:58 INFO - 'opt_config_files': (), 12:06:58 INFO - 'pip_index': False, 12:06:58 INFO - 'sps_profile': False, 12:06:58 INFO - 'sps_profile_interval': 0, 12:06:58 INFO - 'suite': 'dromaeojs', 12:06:58 INFO - 'system_bits': '32', 12:06:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:06:58 INFO - 'title': 'talos-linux64-ix-032', 12:06:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:06:58 INFO - 'use_talos_json': True, 12:06:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:06:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:06:58 INFO - 'work_dir': 'build'} 12:06:58 INFO - [mozharness: 2016-08-04 19:06:58.693937Z] Running clobber step. 12:06:58 INFO - Running pre-action listener: _resource_record_pre_action 12:06:58 INFO - Running main action method: clobber 12:06:58 INFO - rmtree: /builds/slave/test/build 12:06:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:59 INFO - Running post-action listener: _resource_record_post_action 12:06:59 INFO - [mozharness: 2016-08-04 19:06:59.679862Z] Finished clobber step (success) 12:06:59 INFO - [mozharness: 2016-08-04 19:06:59.679963Z] Running read-buildbot-config step. 12:06:59 INFO - Running pre-action listener: _resource_record_pre_action 12:06:59 INFO - Running main action method: read_buildbot_config 12:06:59 INFO - Using buildbot properties: 12:06:59 INFO - { 12:06:59 INFO - "project": "", 12:06:59 INFO - "product": "firefox", 12:06:59 INFO - "script_repo_revision": "production", 12:06:59 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:06:59 INFO - "repository": "", 12:06:59 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:06:59 INFO - "buildid": "20160804105222", 12:06:59 INFO - "pgo_build": "False", 12:06:59 INFO - "basedir": "/builds/slave/test", 12:06:59 INFO - "buildnumber": 3213, 12:06:59 INFO - "slavename": "talos-linux64-ix-032", 12:06:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:06:59 INFO - "platform": "ubuntu64_hw", 12:06:59 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 12:06:59 INFO - "branch": "fx-team", 12:06:59 INFO - "repo_path": "integration/fx-team", 12:06:59 INFO - "revision": "d912055f8e8ad75d189bd480017fdb5a33ee4d7e", 12:06:59 INFO - "stage_platform": "linux64", 12:06:59 INFO - "builduid": "06958df2395a4e0f8c6316723072aee0", 12:06:59 INFO - "slavebuilddir": "test" 12:06:59 INFO - } 12:06:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:06:59 INFO - Running post-action listener: _resource_record_post_action 12:06:59 INFO - [mozharness: 2016-08-04 19:06:59.682457Z] Finished read-buildbot-config step (success) 12:06:59 INFO - [mozharness: 2016-08-04 19:06:59.682545Z] Running download-and-extract step. 12:06:59 INFO - Running pre-action listener: _resource_record_pre_action 12:06:59 INFO - Running main action method: download_and_extract 12:06:59 INFO - mkdir: /builds/slave/test/build/tests 12:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:59 INFO - trying https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:06:59 INFO - Downloading https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:00 INFO - Downloaded 1361 bytes. 12:07:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:07:00 INFO - Using the following test package requirements: 12:07:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:07:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:07:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:07:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'jsshell-linux-x86_64.zip'], 12:07:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:07:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:07:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:07:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:07:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:07:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:07:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:07:00 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')]} 12:07:00 INFO - trying https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:07:00 INFO - Downloading https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:01 INFO - Downloaded 20249623 bytes. 12:07:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:07:02 INFO - Return code: 0 12:07:02 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 12:07:02 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')]} 12:07:02 INFO - trying https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:07:02 INFO - Downloading https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:03 INFO - Downloaded 20249623 bytes. 12:07:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:07:04 INFO - Return code: 0 12:07:04 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')]} 12:07:04 INFO - trying https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:07:04 INFO - Downloading https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:05 INFO - Downloaded 14413723 bytes. 12:07:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:07:06 INFO - Return code: 0 12:07:06 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')]} 12:07:06 INFO - trying https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:06 INFO - Downloading https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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 12:07:07 INFO - Downloaded 55515866 bytes. 12:07:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:07 INFO - mkdir: /builds/slave/test/properties 12:07:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:07 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:07 INFO - Contents: 12:07:07 INFO - build_url:https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:07 INFO - Running post-action listener: _resource_record_post_action 12:07:07 INFO - Running post-action listener: set_extra_try_arguments 12:07:07 INFO - [mozharness: 2016-08-04 19:07:07.971530Z] Finished download-and-extract step (success) 12:07:07 INFO - [mozharness: 2016-08-04 19:07:07.971850Z] Running populate-webroot step. 12:07:07 INFO - Running pre-action listener: _resource_record_pre_action 12:07:07 INFO - Running main action method: populate_webroot 12:07:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:07 INFO - '--tppagecycles', 12:07:07 INFO - '7'], 12:07:07 INFO - 'tests': ['tsvgm']}}, 12:07:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['damp', 'tps']}, 12:07:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'tests': ['damp', 'tps']}, 12:07:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['dromaeo_dom']}, 12:07:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['basic_compositor_video']}, 12:07:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['a11yr', 12:07:07 INFO - 'ts_paint', 12:07:07 INFO - 'tpaint', 12:07:07 INFO - 'sessionrestore', 12:07:07 INFO - 'sessionrestore_no_auto_restore', 12:07:07 INFO - 'tabpaint']}, 12:07:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['tsvgx', 12:07:07 INFO - 'tsvgr_opacity', 12:07:07 INFO - 'tart', 12:07:07 INFO - 'tscrollx', 12:07:07 INFO - 'cart']}, 12:07:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:07 INFO - 'tsvgr_opacity', 12:07:07 INFO - 'tart', 12:07:07 INFO - 'tscrollx', 12:07:07 INFO - 'cart']}, 12:07:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'talos_options': ['--disable-e10s'], 12:07:07 INFO - 'tests': ['tp5o']}, 12:07:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'tests': ['tp5o']}, 12:07:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'talos_options': ['--disable-e10s', 12:07:07 INFO - '--xperf_path', 12:07:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:07 INFO - 'tests': ['tp5n']}, 12:07:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:07 INFO - 'talos_options': ['--xperf_path', 12:07:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:07 INFO - 'tests': ['tp5n']}}, 12:07:07 INFO - 'talos.zip': {'path': '', 12:07:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:07 INFO - Running post-action listener: _resource_record_post_action 12:07:07 INFO - [mozharness: 2016-08-04 19:07:07.994576Z] Finished populate-webroot step (success) 12:07:07 INFO - [mozharness: 2016-08-04 19:07:07.994666Z] Running create-virtualenv step. 12:07:07 INFO - Running pre-action listener: _resource_record_pre_action 12:07:07 INFO - Running main action method: create_virtualenv 12:07:07 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:07 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 12:07:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:08 INFO - Using real prefix '/usr' 12:07:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:08 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:10 INFO - Installing pip.................done. 12:07:10 INFO - Return code: 0 12:07:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:10 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:10 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 12:07:10 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 12:07:10 INFO - Using env: {'DISPLAY': ':0', 12:07:10 INFO - 'HOME': '/home/cltbld', 12:07:10 INFO - 'LANG': 'en_US.UTF-8', 12:07:10 INFO - 'LANGUAGE': 'en_US:en', 12:07:10 INFO - 'LOGNAME': 'cltbld', 12:07:10 INFO - 'MAIL': '/var/mail/cltbld', 12:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:10 INFO - 'MOZ_NO_REMOTE': '1', 12:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:10 INFO - 'NO_EM_RESTART': '1', 12:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:10 INFO - 'PWD': '/builds/slave/test', 12:07:10 INFO - 'SHELL': '/bin/bash', 12:07:10 INFO - 'SHLVL': '1', 12:07:10 INFO - 'TERM': 'linux', 12:07:10 INFO - 'TMOUT': '86400', 12:07:10 INFO - 'USER': 'cltbld', 12:07:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:10 INFO - '_': '/tools/buildbot/bin/python'} 12:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:10 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 12:07:10 INFO - Cleaning up... 12:07:10 INFO - Return code: 0 12:07:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:10 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:10 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 12:07:10 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 12:07:10 INFO - Using env: {'DISPLAY': ':0', 12:07:10 INFO - 'HOME': '/home/cltbld', 12:07:10 INFO - 'LANG': 'en_US.UTF-8', 12:07:10 INFO - 'LANGUAGE': 'en_US:en', 12:07:10 INFO - 'LOGNAME': 'cltbld', 12:07:10 INFO - 'MAIL': '/var/mail/cltbld', 12:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:10 INFO - 'MOZ_NO_REMOTE': '1', 12:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:10 INFO - 'NO_EM_RESTART': '1', 12:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:10 INFO - 'PWD': '/builds/slave/test', 12:07:10 INFO - 'SHELL': '/bin/bash', 12:07:10 INFO - 'SHLVL': '1', 12:07:10 INFO - 'TERM': 'linux', 12:07:10 INFO - 'TMOUT': '86400', 12:07:10 INFO - 'USER': 'cltbld', 12:07:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:10 INFO - '_': '/tools/buildbot/bin/python'} 12:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:11 INFO - Downloading/unpacking psutil>=3.1.1 12:07:11 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 12:07:11 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 12:07:11 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 12:07:11 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 12:07:11 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 12:07:11 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 12:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:13 INFO - Installing collected packages: psutil 12:07:13 INFO - Running setup.py install for psutil 12:07:13 INFO - building 'psutil._psutil_linux' extension 12:07:13 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 12:07:13 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 12:07:13 INFO - building 'psutil._psutil_posix' extension 12:07:13 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 12:07:13 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 12:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:13 INFO - Successfully installed psutil 12:07:13 INFO - Cleaning up... 12:07:13 INFO - Return code: 0 12:07:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:13 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:13 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 12:07:13 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 12:07:13 INFO - Using env: {'DISPLAY': ':0', 12:07:13 INFO - 'HOME': '/home/cltbld', 12:07:13 INFO - 'LANG': 'en_US.UTF-8', 12:07:13 INFO - 'LANGUAGE': 'en_US:en', 12:07:13 INFO - 'LOGNAME': 'cltbld', 12:07:13 INFO - 'MAIL': '/var/mail/cltbld', 12:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:13 INFO - 'MOZ_NO_REMOTE': '1', 12:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:13 INFO - 'NO_EM_RESTART': '1', 12:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:13 INFO - 'PWD': '/builds/slave/test', 12:07:13 INFO - 'SHELL': '/bin/bash', 12:07:13 INFO - 'SHLVL': '1', 12:07:13 INFO - 'TERM': 'linux', 12:07:13 INFO - 'TMOUT': '86400', 12:07:13 INFO - 'USER': 'cltbld', 12:07:13 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:13 INFO - '_': '/tools/buildbot/bin/python'} 12:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07:13 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 12:07:13 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 12:07:13 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 12:07:13 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 12:07:13 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 12:07:13 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 12:07:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:15 INFO - Installing collected packages: mozsystemmonitor 12:07:15 INFO - Running setup.py install for mozsystemmonitor 12:07:16 INFO - Successfully installed mozsystemmonitor 12:07:16 INFO - Cleaning up... 12:07:16 INFO - Return code: 0 12:07:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:16 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:16 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 12:07:16 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 12:07:16 INFO - Using env: {'DISPLAY': ':0', 12:07:16 INFO - 'HOME': '/home/cltbld', 12:07:16 INFO - 'LANG': 'en_US.UTF-8', 12:07:16 INFO - 'LANGUAGE': 'en_US:en', 12:07:16 INFO - 'LOGNAME': 'cltbld', 12:07:16 INFO - 'MAIL': '/var/mail/cltbld', 12:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:16 INFO - 'MOZ_NO_REMOTE': '1', 12:07:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:16 INFO - 'NO_EM_RESTART': '1', 12:07:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:16 INFO - 'PWD': '/builds/slave/test', 12:07:16 INFO - 'SHELL': '/bin/bash', 12:07:16 INFO - 'SHLVL': '1', 12:07:16 INFO - 'TERM': 'linux', 12:07:16 INFO - 'TMOUT': '86400', 12:07:16 INFO - 'USER': 'cltbld', 12:07:16 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:16 INFO - '_': '/tools/buildbot/bin/python'} 12:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:16 INFO - Downloading/unpacking jsonschema==2.5.1 12:07:16 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 12:07:16 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 12:07:16 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 12:07:16 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 12:07:16 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 12:07:16 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 12:07:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:18 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 12:07:18 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 12:07:18 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 12:07:18 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 12:07:18 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 12:07:18 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 12:07:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:18 INFO - warning: no files found matching '*.txt' 12:07:18 INFO - no previously-included directories found matching 'build' 12:07:18 INFO - no previously-included directories found matching 'dist' 12:07:18 INFO - no previously-included directories found matching '.git*' 12:07:18 INFO - Installing collected packages: jsonschema, functools32 12:07:18 INFO - Running setup.py install for functools32 12:07:18 INFO - warning: no files found matching '*.txt' 12:07:18 INFO - no previously-included directories found matching 'build' 12:07:18 INFO - no previously-included directories found matching 'dist' 12:07:18 INFO - no previously-included directories found matching '.git*' 12:07:18 INFO - Successfully installed jsonschema functools32 12:07:18 INFO - Cleaning up... 12:07:18 INFO - Return code: 0 12:07:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:18 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:18 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 12:07:18 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 12:07:18 INFO - Using env: {'DISPLAY': ':0', 12:07:18 INFO - 'HOME': '/home/cltbld', 12:07:18 INFO - 'LANG': 'en_US.UTF-8', 12:07:18 INFO - 'LANGUAGE': 'en_US:en', 12:07:18 INFO - 'LOGNAME': 'cltbld', 12:07:18 INFO - 'MAIL': '/var/mail/cltbld', 12:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:18 INFO - 'MOZ_NO_REMOTE': '1', 12:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:18 INFO - 'NO_EM_RESTART': '1', 12:07:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:18 INFO - 'PWD': '/builds/slave/test', 12:07:18 INFO - 'SHELL': '/bin/bash', 12:07:18 INFO - 'SHLVL': '1', 12:07:18 INFO - 'TERM': 'linux', 12:07:18 INFO - 'TMOUT': '86400', 12:07:18 INFO - 'USER': 'cltbld', 12:07:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:18 INFO - '_': '/tools/buildbot/bin/python'} 12:07:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:07:18 INFO - Cleaning up... 12:07:18 INFO - Return code: 0 12:07:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:18 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:18 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 12:07:18 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 12:07:18 INFO - Using env: {'DISPLAY': ':0', 12:07:18 INFO - 'HOME': '/home/cltbld', 12:07:18 INFO - 'LANG': 'en_US.UTF-8', 12:07:18 INFO - 'LANGUAGE': 'en_US:en', 12:07:18 INFO - 'LOGNAME': 'cltbld', 12:07:18 INFO - 'MAIL': '/var/mail/cltbld', 12:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:18 INFO - 'MOZ_NO_REMOTE': '1', 12:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:18 INFO - 'NO_EM_RESTART': '1', 12:07:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:18 INFO - 'PWD': '/builds/slave/test', 12:07:18 INFO - 'SHELL': '/bin/bash', 12:07:18 INFO - 'SHLVL': '1', 12:07:18 INFO - 'TERM': 'linux', 12:07:18 INFO - 'TMOUT': '86400', 12:07:18 INFO - 'USER': 'cltbld', 12:07:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:18 INFO - '_': '/tools/buildbot/bin/python'} 12:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:19 INFO - Downloading/unpacking blobuploader==1.2.4 12:07:19 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 12:07:19 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 12:07:19 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 12:07:19 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 12:07:19 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 12:07:19 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 12:07:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:07:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:07:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 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 12:07:21 INFO - Downloading docopt-0.6.1.tar.gz 12:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:07:21 INFO - Installing collected packages: blobuploader, requests, docopt 12:07:21 INFO - Running setup.py install for blobuploader 12:07:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:07:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:07:21 INFO - Running setup.py install for requests 12:07:22 INFO - Running setup.py install for docopt 12:07:22 INFO - Successfully installed blobuploader requests docopt 12:07:22 INFO - Cleaning up... 12:07:22 INFO - Return code: 0 12:07:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:22 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:22 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 12:07:22 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 12:07:22 INFO - Using env: {'DISPLAY': ':0', 12:07:22 INFO - 'HOME': '/home/cltbld', 12:07:22 INFO - 'LANG': 'en_US.UTF-8', 12:07:22 INFO - 'LANGUAGE': 'en_US:en', 12:07:22 INFO - 'LOGNAME': 'cltbld', 12:07:22 INFO - 'MAIL': '/var/mail/cltbld', 12:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:22 INFO - 'MOZ_NO_REMOTE': '1', 12:07:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:22 INFO - 'NO_EM_RESTART': '1', 12:07:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:22 INFO - 'PWD': '/builds/slave/test', 12:07:22 INFO - 'SHELL': '/bin/bash', 12:07:22 INFO - 'SHLVL': '1', 12:07:22 INFO - 'TERM': 'linux', 12:07:22 INFO - 'TMOUT': '86400', 12:07:22 INFO - 'USER': 'cltbld', 12:07:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:22 INFO - '_': '/tools/buildbot/bin/python'} 12:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:22 INFO - Running setup.py (path:/tmp/pip-Qr9i_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:22 INFO - Running setup.py (path:/tmp/pip-cQOLtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:22 INFO - Running setup.py (path:/tmp/pip-kSjrlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:22 INFO - Running setup.py (path:/tmp/pip-wlAEGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:22 INFO - Running setup.py (path:/tmp/pip-GauPvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:22 INFO - Running setup.py (path:/tmp/pip-tBz6hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:23 INFO - Running setup.py (path:/tmp/pip-xmjgsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:23 INFO - Running setup.py (path:/tmp/pip-WOujft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:23 INFO - Running setup.py (path:/tmp/pip-1hEGNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:23 INFO - Running setup.py (path:/tmp/pip-y0K5X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:23 INFO - Running setup.py (path:/tmp/pip-Esj6B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:23 INFO - Running setup.py (path:/tmp/pip-uaG4zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:23 INFO - Running setup.py (path:/tmp/pip-BgBtAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:23 INFO - Running setup.py (path:/tmp/pip-lc58Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:23 INFO - Running setup.py (path:/tmp/pip-Q09Zak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:23 INFO - Running setup.py (path:/tmp/pip-ipqmV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:23 INFO - Running setup.py (path:/tmp/pip-Xv5FJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:07:23 INFO - Running setup.py install for manifestparser 12:07:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:07:23 INFO - Running setup.py install for mozcrash 12:07:24 INFO - Running setup.py install for mozdebug 12:07:24 INFO - Running setup.py install for mozdevice 12:07:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for mozfile 12:07:24 INFO - Running setup.py install for mozhttpd 12:07:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for mozinfo 12:07:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for mozInstall 12:07:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for mozleak 12:07:24 INFO - Running setup.py install for mozlog 12:07:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for moznetwork 12:07:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:24 INFO - Running setup.py install for mozprocess 12:07:25 INFO - Running setup.py install for mozprofile 12:07:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:25 INFO - Running setup.py install for mozrunner 12:07:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:25 INFO - Running setup.py install for mozscreenshot 12:07:25 INFO - Running setup.py install for moztest 12:07:25 INFO - Running setup.py install for mozversion 12:07:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:25 INFO - Cleaning up... 12:07:25 INFO - Return code: 0 12:07:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:25 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:25 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 12:07:25 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 12:07:25 INFO - Using env: {'DISPLAY': ':0', 12:07:25 INFO - 'HOME': '/home/cltbld', 12:07:25 INFO - 'LANG': 'en_US.UTF-8', 12:07:25 INFO - 'LANGUAGE': 'en_US:en', 12:07:25 INFO - 'LOGNAME': 'cltbld', 12:07:25 INFO - 'MAIL': '/var/mail/cltbld', 12:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:25 INFO - 'MOZ_NO_REMOTE': '1', 12:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:25 INFO - 'NO_EM_RESTART': '1', 12:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:25 INFO - 'PWD': '/builds/slave/test', 12:07:25 INFO - 'SHELL': '/bin/bash', 12:07:25 INFO - 'SHLVL': '1', 12:07:25 INFO - 'TERM': 'linux', 12:07:25 INFO - 'TMOUT': '86400', 12:07:25 INFO - 'USER': 'cltbld', 12:07:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:25 INFO - '_': '/tools/buildbot/bin/python'} 12:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:25 INFO - Running setup.py (path:/tmp/pip-GEdCLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:25 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)) 12:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:25 INFO - Running setup.py (path:/tmp/pip-AuKLXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:25 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)) 12:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:25 INFO - Running setup.py (path:/tmp/pip-sA1HlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:26 INFO - Running setup.py (path:/tmp/pip-xSuK3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:26 INFO - Running setup.py (path:/tmp/pip-5Bsoqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:26 INFO - Running setup.py (path:/tmp/pip-ksys35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:26 INFO - Running setup.py (path:/tmp/pip-ooWvX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:26 INFO - Running setup.py (path:/tmp/pip-aHPzfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:26 INFO - Running setup.py (path:/tmp/pip-aMcvz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:26 INFO - Running setup.py (path:/tmp/pip-3LeuBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:26 INFO - Running setup.py (path:/tmp/pip-ekIepP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:26 INFO - Running setup.py (path:/tmp/pip-stBhzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:26 INFO - Running setup.py (path:/tmp/pip-ynJ0JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:26 INFO - Running setup.py (path:/tmp/pip-QkFOUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:26 INFO - Running setup.py (path:/tmp/pip-gyvIZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:26 INFO - Running setup.py (path:/tmp/pip-ZP2szc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:26 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)) 12:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:27 INFO - Running setup.py (path:/tmp/pip-3epDq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:27 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)) 12:07:27 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)) 12:07:27 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)) 12:07:27 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)) 12:07:27 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)) 12:07:27 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)) 12:07:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:28 INFO - Downloading blessings-1.6.tar.gz 12:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:07:29 INFO - Installing collected packages: blessings 12:07:29 INFO - Running setup.py install for blessings 12:07:29 INFO - Successfully installed blessings 12:07:29 INFO - Cleaning up... 12:07:29 INFO - Return code: 0 12:07:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:29 INFO - Reading from file tmpfile_stdout 12:07:29 INFO - Current package versions: 12:07:29 INFO - argparse == 1.2.1 12:07:29 INFO - blessings == 1.6 12:07:29 INFO - blobuploader == 1.2.4 12:07:29 INFO - docopt == 0.6.1 12:07:29 INFO - functools32 == 3.2.3-2 12:07:29 INFO - jsonschema == 2.5.1 12:07:29 INFO - manifestparser == 1.1 12:07:29 INFO - mozInstall == 1.12 12:07:29 INFO - mozcrash == 0.17 12:07:29 INFO - mozdebug == 0.1 12:07:29 INFO - mozdevice == 0.48 12:07:29 INFO - mozfile == 1.2 12:07:29 INFO - mozhttpd == 0.7 12:07:29 INFO - mozinfo == 0.9 12:07:29 INFO - mozleak == 0.1 12:07:29 INFO - mozlog == 3.3 12:07:29 INFO - moznetwork == 0.27 12:07:29 INFO - mozprocess == 0.23 12:07:29 INFO - mozprofile == 0.28 12:07:29 INFO - mozrunner == 6.12 12:07:29 INFO - mozscreenshot == 0.1 12:07:29 INFO - mozsystemmonitor == 0.3 12:07:29 INFO - moztest == 0.7 12:07:29 INFO - mozversion == 1.4 12:07:29 INFO - psutil == 3.1.1 12:07:29 INFO - requests == 1.2.3 12:07:29 INFO - wsgiref == 0.1.2 12:07:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:29 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 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07:29 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 12:07:29 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 12:07:29 INFO - Using env: {'DISPLAY': ':0', 12:07:29 INFO - 'HOME': '/home/cltbld', 12:07:29 INFO - 'LANG': 'en_US.UTF-8', 12:07:29 INFO - 'LANGUAGE': 'en_US:en', 12:07:29 INFO - 'LOGNAME': 'cltbld', 12:07:29 INFO - 'MAIL': '/var/mail/cltbld', 12:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:29 INFO - 'MOZ_NO_REMOTE': '1', 12:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:29 INFO - 'NO_EM_RESTART': '1', 12:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:29 INFO - 'PWD': '/builds/slave/test', 12:07:29 INFO - 'SHELL': '/bin/bash', 12:07:29 INFO - 'SHLVL': '1', 12:07:29 INFO - 'TERM': 'linux', 12:07:29 INFO - 'TMOUT': '86400', 12:07:29 INFO - 'USER': 'cltbld', 12:07:29 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:29 INFO - '_': '/tools/buildbot/bin/python'} 12:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 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)) 12:07:29 INFO - Cleaning up... 12:07:29 INFO - Return code: 0 12:07:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, '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': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', '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 12:07: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', 'jsonschema'] in /builds/slave/test/build 12:07: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 jsonschema 12:07:29 INFO - Using env: {'DISPLAY': ':0', 12:07:29 INFO - 'HOME': '/home/cltbld', 12:07:29 INFO - 'LANG': 'en_US.UTF-8', 12:07:29 INFO - 'LANGUAGE': 'en_US:en', 12:07:29 INFO - 'LOGNAME': 'cltbld', 12:07:29 INFO - 'MAIL': '/var/mail/cltbld', 12:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:29 INFO - 'MOZ_NO_REMOTE': '1', 12:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:29 INFO - 'NO_EM_RESTART': '1', 12:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:29 INFO - 'PWD': '/builds/slave/test', 12:07:29 INFO - 'SHELL': '/bin/bash', 12:07:29 INFO - 'SHLVL': '1', 12:07:29 INFO - 'TERM': 'linux', 12:07:29 INFO - 'TMOUT': '86400', 12:07:29 INFO - 'USER': 'cltbld', 12:07:29 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:29 INFO - '_': '/tools/buildbot/bin/python'} 12:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:07:29 INFO - Cleaning up... 12:07:29 INFO - Return code: 0 12:07:29 INFO - Running post-action listener: _resource_record_post_action 12:07:29 INFO - Running post-action listener: _start_resource_monitoring 12:07:29 INFO - Starting resource monitoring. 12:07:29 INFO - [mozharness: 2016-08-04 19:07:29.889565Z] Finished create-virtualenv step (success) 12:07:29 INFO - [mozharness: 2016-08-04 19:07:29.890006Z] Running install step. 12:07:29 INFO - Running pre-action listener: _resource_record_pre_action 12:07:29 INFO - Running main action method: install 12:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:30 INFO - Reading from file tmpfile_stdout 12:07:30 INFO - Detecting whether we're running mozinstall >=1.0... 12:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:07:30 INFO - Reading from file tmpfile_stdout 12:07:30 INFO - Output received: 12:07:30 INFO - Usage: mozinstall [options] installer 12:07:30 INFO - Options: 12:07:30 INFO - -h, --help show this help message and exit 12:07:30 INFO - -d DEST, --destination=DEST 12:07:30 INFO - Directory to install application into. [default: 12:07:30 INFO - "/builds/slave/test"] 12:07:30 INFO - --app=APP Application being installed. [default: firefox] 12:07:30 INFO - mkdir: /builds/slave/test/build/application 12:07:30 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'] 12:07:30 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 12:07:47 INFO - Reading from file tmpfile_stdout 12:07:47 INFO - Output received: 12:07:47 INFO - /builds/slave/test/build/application/firefox/firefox 12:07:47 INFO - Running post-action listener: _resource_record_post_action 12:07:47 INFO - [mozharness: 2016-08-04 19:07:47.204205Z] Finished install step (success) 12:07:47 INFO - [mozharness: 2016-08-04 19:07:47.204451Z] Running run-tests step. 12:07:47 INFO - Running pre-action listener: _resource_record_pre_action 12:07:47 INFO - Running main action method: run_tests 12:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:07:47 INFO - Python 2.7.3 12:07:47 INFO - Return code: 0 12:07:47 INFO - grabbing minidump binary from tooltool 12:07: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')]} 12:07:47 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 0x29a03b0>, '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 0x29a12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a1740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:07:47 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 12:07:47 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 12:07:47 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 12:07:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:07:47 INFO - Return code: 0 12:07:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:07:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:07:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:07:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:07:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 12:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 12:07:47 INFO - Using env: {'DISPLAY': ':0', 12:07:47 INFO - 'HOME': '/home/cltbld', 12:07:47 INFO - 'LANG': 'en_US.UTF-8', 12:07:47 INFO - 'LANGUAGE': 'en_US:en', 12:07:47 INFO - 'LOGNAME': 'cltbld', 12:07:47 INFO - 'MAIL': '/var/mail/cltbld', 12:07:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:07:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:47 INFO - 'MOZ_NO_REMOTE': '1', 12:07:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:47 INFO - 'NO_EM_RESTART': '1', 12:07:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:47 INFO - 'PWD': '/builds/slave/test', 12:07:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:07:47 INFO - 'SHELL': '/bin/bash', 12:07:47 INFO - 'SHLVL': '1', 12:07:47 INFO - 'TERM': 'linux', 12:07:47 INFO - 'TMOUT': '86400', 12:07:47 INFO - 'USER': 'cltbld', 12:07:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470335627.972098-1609016002', 12:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:47 INFO - '_': '/tools/buildbot/bin/python'} 12:07:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 12:07:47 INFO - mozversion application_buildid: 20160804105222 12:07:47 INFO - mozversion application_changeset: d912055f8e8ad75d189bd480017fdb5a33ee4d7e 12:07:47 INFO - mozversion application_display_name: Nightly 12:07:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:07:47 INFO - mozversion application_name: Firefox 12:07:47 INFO - mozversion application_remotingname: firefox 12:07:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:07:47 INFO - mozversion application_vendor: Mozilla 12:07:47 INFO - mozversion application_version: 51.0a1 12:07:47 INFO - mozversion platform_buildid: 20160804105222 12:07:47 INFO - mozversion platform_changeset: d912055f8e8ad75d189bd480017fdb5a33ee4d7e 12:07:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:07:47 INFO - mozversion platform_version: 51.0a1 12:07:47 INFO - using testdate: 1470337667 12:07:47 INFO - actual date: 1470337667 12:07:47 INFO - starting webserver on 'localhost:47614' 12:07:47 INFO - SUITE-START | Running 2 tests 12:07:47 INFO - TEST-START | dromaeo_css 12:07:47 INFO - operating with platform_type : linux_ 12:07:47 INFO - Initialising browser for dromaeo_css test... 12:07:47 INFO - TEST-INFO | started process 24214 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkUbqwb/profile http://localhost:47614/getInfo.html) 12:07:52 INFO - PROCESS | 24214 | __metrics Screen width/height:1600/1200 12:07:52 INFO - PROCESS | 24214 | colorDepth:24 12:07:52 INFO - PROCESS | 24214 | Browser inner width/height: 1024/697 12:07:52 INFO - PROCESS | 24214 | __metrics 12:07:53 INFO - PROCESS | 24214 | 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" 12:07:53 INFO - PROCESS | 24214 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:07:54 INFO - PROCESS | 24214 | console.error: PushService: 12:07:54 INFO - PROCESS | 24214 | stateChangeProcessEnqueue: Error transitioning state 12:07:54 INFO - PROCESS | 24214 | UnknownError 12:07:54 INFO - TEST-INFO | 24214: exit 0 12:07:54 INFO - Browser initialized. 12:07:54 INFO - Running cycle 1/1 for dromaeo_css test... 12:07:54 INFO - TEST-INFO | started process 24296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkUbqwb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:06 INFO - PROCESS | 24296 | RSS: Main: 156778496 12:08:06 INFO - PROCESS | 24296 | 12:08:57 INFO - PROCESS | 24296 | 1470337737525 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] 12:11:11 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47614/tests/dromaeo/cssquery-ext.html) 12:11:12 INFO - PROCESS | 24296 | RSS: Main: 238403584 12:11:12 INFO - PROCESS | 24296 | 12:14:15 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-ext.html (next: http://localhost:47614/tests/dromaeo/cssquery-jquery.html) 12:14:15 INFO - PROCESS | 24296 | RSS: Main: 246382592 12:14:15 INFO - PROCESS | 24296 | 12:17:17 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47614/tests/dromaeo/cssquery-mootools.html) 12:17:18 INFO - PROCESS | 24296 | RSS: Main: 187138048 12:17:18 INFO - PROCESS | 24296 | 12:20:20 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47614/tests/dromaeo/cssquery-prototype.html) 12:20:21 INFO - PROCESS | 24296 | RSS: Main: 188096512 12:20:21 INFO - PROCESS | 24296 | 12:23:24 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47614/tests/dromaeo/cssquery-yui.html) 12:23:24 INFO - PROCESS | 24296 | RSS: Main: 188071936 12:23:24 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-yui.html (next: http://localhost:47614/tests/dromaeo/cssquery-dojo.html) 12:26:28 INFO - PROCESS | 24296 | RSS: Main: 280481792 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | __start_tp_report 12:26:28 INFO - PROCESS | 24296 | _x_x_mozilla_page_load 12:26:28 INFO - PROCESS | 24296 | _x_x_mozilla_page_load_details 12:26:28 INFO - PROCESS | 24296 | |i|pagename|runs| 12:26:28 INFO - PROCESS | 24296 | |0;dojo.html;2602.5445547029267;2614.908478203263;2641.7490338417842;2649.390640152764;2656.495265899479;4681.414823147106;4695.107929493398;4747.121782470245;4750.809967601292;4758.833440829571;4753.382060332161;4813.109574728677;4822.5900798432085;4828.0585285869265;4852.175130227861;4431.889202769924;4445.466544014723;4459.598636122756;4487.237169681162;4511.526289739563;4438.977805110986;4452.220861349267;4501.212287849629;4540.137373898965;4585.174668559658;4516.277395616702;4524.434445694288;4552.9089418211615;4557.908841823161;4577.198990176706;4346.717463364871;4472.955270447319;4474.172278128557;4508.61676757476;4519.389882365871;4364.6290065344165;4368.08270263242;4423.336499525065;4429.291313389842;4492.797824097924;4830.951690483085;4862.7568621569135;4876.439209490918;4885.828995985124;4888.633352498541;5172.586193104543;5218.321618189611;5232.581393488512;5235.293235413209;5238.188080847474;5035.647504674675;5116.590672746172;5175.249588809602;5183;5258.421573626898;4889.022195560938;4896.877578060577;4900.607951363882;4900.975495122501;4908.558229759339;5360.410354860962;5366.329208848894;5368.355797304349;5371.167469042305;5376.435474275223;4926.704397736147;4975.751212439363;5031.6729412588065;5094.006668699642;5162.664426812245;5152.845414637492;5199.350081239845;5204.609654275944;5254.500822421864;5258.079836028756;5153.2785410042625;5155.046316431472;5158.587313014949;5164.509371609691;5216.686998780086;4933.55597996182;4984.601231901439;5001.849944501598;5025.522575355335;5074;4389.363542286351;4404.2512772828695;4412.757298348557;4413.117376524708;4414.404055452473;3819.1406933439753;3866.9226615467533;3879.592642772525;3883.126296583246;3886.2616102940915;4397;4399.5380485049245;4400.9119817604105;4423.800928958204;4440.045390241049;5174.120399532088;5179.689218646893;5186.5850731941355;5192.584593232532;5236.6857988520815;4299.806508707115;4356.6732495063;4360.760158191267;4378.146261479044;4384.934225986613;4655.557722016403;4682.953170468251;4692.131955588227;4713.505081966344;4730.077634861238;4854.83008094722;4857.441394239567;4859.757012149307;4863.1003264395495;4903.8528844134735;5002.599792016557;5124.615653826053;5148.7425628717665;5155.097857874932;5210.921836172536;4859.173940430064;4917.6311776616185;4929.285253638274;4951.950480495149;4977.178765503651;5117.513836185556;5159.432462429055;5176.094183517945;5177.0940085485645;5224.791008359623;5012.724300163526;5169.457206993212;5221.660592061504;5228.215767634885;5230.476952304739;5072.34059572268;5105.8723531912;5157.819476318311;5159.845204643867;5170.457102004236;5087.87280317995;5095.77069031887;5104.336436263262;5105.795768169231;5155.793768249228;4821.927671084866;4905.2151655734915;5025.045241404122;5048.646594738333;5085.567726743268;5051.217061355495;5095.210242412432;5111.105556527518;5154.123798954111;5157.793688252428;4881.365422495053;4950.975245123751;4964.8758781030765;4981.800727970841;4985.52637499437 12:26:28 INFO - PROCESS | 24296 | |1;ext.html;567.4042255631587;575.8013485347557;578.4620303118102;588.0855270055065;596.0701305962701;37652;39266;39346.60653393462;43178.35232471515;43211.78394108025;39819.80090099553;40294;40360.59639403605;40410.59589404105;40436.59563404365;7677.347425468828;7822.335101516378;7822.37421006321;7824.256695613907;7827.804304892395;7555.471117021783;7629.275218854228;7632.694692212305;7636.31273185416;7636.809079772995;7636.350910172642;7669.424793140509;7670.578118203496;7675.884861727079;7683.078030636331;7606.733764318277;7618.580978046205;7639.274268944441;7646.273604007611;7652.540847549137;7695.23047695226;7753.728619498291;7760.650770715331;7763.844723105506;7764.805879852993;38443.23113537741;38865;38883.416748748794;38921;39001.21997560033;2810.211164166323;2991.6559595646563;3034.908952731422;3119.5788568543185;3377.9493307600405;29196.270093247625;29319.560206596918;29654.110376688735;29742.553861692093;31074.84462577673;3347.832608369572;3439.5012723155;3470.7570470067108;3520.8767693130617;3590.838412271454;261953;264980;265638;265842.9999999981;266262;30773.38453230923;31863.84068079691;48711.269330960065;49007.509924900296;49127.75436122796;33220.83389583085;33517.16207094842;33624.32751345008;33710.831445843105;33748.15629609279;13438.260895650637;13603.727925441435;13615.455381784815;13621.182729036289;13622.182669039888;11415.20093593457;11489.942550287195;11491.42542872853;11557.075433965263;11579.073674106052;7877.803054923558;7882.605869706492;7883.251091146332;7960.009997500625;7978.401619878532;3020.0939718084487;3049.8780048798244;3065.402246561899;3088.135322109813;3109.875604975776;770.2143813310392;843.843888880559;845.0324378586484;882.4705176893941;919.3977944446399;26681.3329666756;27980.440391192064;28119;28381.290467738057;28723.569146462818;767.9884801727978;955.9091886270793;962.5620342744035;992.5979978108877;1011.716719318592;14586.416543338148;15409.84590154084;15468.690626187414;15595.846152314458;15714.29285682147;410026;413544;414855;416130;416170;48094.5190548097;48608.27087593689;48624.27063594049;48660.513394866306;48737.268940965914;1067.8985496377832;1171.5782318365211;1182.184292837939;1201.4053043743404;1209.4255228766265;25431.745682542936;29396.706032939397;29425;29614.407711846074;29809.5528567076;40961.590384095776;46716.89773960248;48019.51980480151;48154.75922620364;52653.21020184777;383172;384679;385172;386629;386837;24429.14497992562;25117.372065698502;38943;39018;39537.209255814145;41153.1769364617;41233.58766412298;41437.378439322834;41527;41565;33159.668403316624;34413.48379774355;34480.31039379248;34606.480902786985;34834;35928.101797455274;36045.81977090098;36113;36444.81777591095;36465.635343647285;30914.690853091182;35134.82432587821;35148.29703405969;35867.461988070725;35934.460983085795;30769.61516743033;32820.179495511846;33623;33839;33877;25321.62017569701;25454.490910182063;25839;26235.60646590341;26320.8683956579 12:26:28 INFO - PROCESS | 24296 | |2;jquery.html;361.49571347969584;363.82900036982625;368.4675643694861;377.10625816814155;389.06624102154825;16173.838261617395;17286.135693215336;17555.297788088475;17597.912010439963;17625.55936101598;15547.68904621907;16621;16977;17090.487285381427;17139;4952.380952380961;5187.818426355078;5189.870253243661;5204.843854684365;5206.401263854648;3688.133288677159;3898.3957486589625;5044.19292913134;5807.448292412214;6035.758569657209;6099.603525770833;6101.969490152543;6103.755849766004;6114.327423983358;6144.7542098316235;5814.796482123126;5968.4031596840405;5978.372270911578;5986.880262394728;5994.100884867261;4628.09752098341;5274.736263186864;5283.1546952487415;5283.815066472655;5289.021531016774;12009.93995030028;12352.938235308855;12395.504179832886;12402.56591019319;12409.813852792215;1872.915718792644;1918.3957053528095;1939.9806001940083;2276.146445083094;2309.988450057756;8757.343199260082;8914.420562663407;9081.772955676095;9358.485283309416;9368.90631093687;1694.3561446650194;1694.6271820199536;1739.391213075426;1754.929802807886;1795.3985414886029;69756.6512167441;73474;73681.63159184223;73772;76448.88408927273;9625.51872406377;9822.557984890696;9823;9835.459049752333;10046.29675922693;13126.737465250833;13130.570844393818;13139.34303284851;13146.21122732639;13150.342482876009;7300.452466065067;7531.472796904164;7535.058117735283;7620.695172193161;7654.770356889302;7297.525660832029;7304.5982470963545;7304.817379565553;7464.85070298591;7484.925150748423;4098.446709694213;4129.69027322952;4169.749815011109;4173.812178451977;4206.158768246363;1625.886187966831;1652.867770578351;1728.9394871179447;1733.878628495993;1776.6535525572647;789.8459800338994;789.944703870735;813.7762115418188;839.6095815445725;849.3205435651454;16100.275487603087;16979.405720799714;17059.402920897708;17090.658186836194;17299.56751081233;829.9751007469786;858.6136238692488;861.2550144125287;895.3463971300987;906.6373450619805;9029.367944243972;9593.65688803568;9683.467409292416;9686.128248457673;9851.556679949419;392242;396493;400173;433781;440558;56009.71995139998;60650.39349606536;84001;89159;92200.53899730458;714.3677845107118;891.8038031633031;893.3924931046821;922.4188761080476;930.6370515498961;15589.766153507933;15621.453249136226;15767.211639417754;16088.919555402148;16412;29038.7096129036;38956.80521597374;39029.2194156121;39120.21759564849;40191.598084018784;55803.44196557983;56348;56492;57243.71378143083;58196.12705809331;6593.208814942238;6844.965775171092;6934.618595977269;7211.819704507429;7303.8539229216185;9281.39670921388;11361;11411.714707132389;11799.587014454453;12240.08199385032;9936.801263974825;10334.431606261727;10341.37951722899;10420.06219440254;11189.776204476027;9425.293103017164;9626.9037309626;9869.605215791287;10020.24848136378;10037.49812509357;9771.657991970247;9949.502524873873;10102.797944041225;10213.74465638365;10698.358098514114;6645.3022432643875;6739.528233023641;6917.412019978358;6939.618320992394;7028.367446929801;7413.851722965618;10090.39457632515;10196.745081373025;10226;10704.09015233714 12:26:28 INFO - PROCESS | 24296 | |3;mootools.html;1303.70666600015;1374.4433504430706;1389;1599.6800639872029;1640.3110693508725;6962.303769623041;7324.743633972812;7331.78004659861;7335.669894854731;7339.70641174353;6211.751529938809;6900.689468973896;7026.332498412643;7069.399101076415;7213;2136.2843447445084;2164.77269886662;2165.263810304496;2166.7616562178146;2172.1311475409802;2089.6447603907295;2140.7003019577273;2169.1323470611724;2169.7070895429074;2172.3808714516367;1779.8131196224404;2053.1376821734907;2104.042660589428;2111.831053515723;2131.968020479694;2120.512282175093;2121.5332626822074;2139.882306473143;2140.9464763380884;2141.742990841109;2122.204173434962;2125.978740212609;2126.6278401279715;2127.5213077057538;2129.6060228857555;7159.14090309161;7211.495195336329;7224.566526008456;7235.276472352775;7249.528780629243;4039.394090886361;4072.572379900127;4077;4085.0604360997136;4223.070924396628;2775.1258353618546;2865.627468429091;2868.641419822522;2869.856507174654;2884.2356775454523;901.5627420700955;942.7972985808016;961.9182369498601;963.6868017894212;975.6829030565095;9538.189253913357;9542.093501117382;9580.473073980998;9582.90417095827;9603;3637.8181090945345;3812.84748610058;3915.980420097881;3924.038610540436;3963.7225394222705;8307.750767476986;8308.875366869503;8312.709055183039;8321.66713331472;8323.583820809057;4329.978350108229;4366.192254432876;4367.10474352757;4372.737635741865;4374;3954.130091379892;3997.8001099944886;3997.9000524986527;3999.8800035998966;4009.599040095967;3441.380551500754;3540.13266749648;3551;3556.41319182332;3558.323918455485;1135.6479491357707;1136.1024790415645;1141.6777178410784;1149.3563604381573;1153.2388623508443;603.4026313949158;678.3555622158968;757.2011527838074;764.3388468974297;777.3392616276233;4813.03739252144;4878.878028049327;4979.178435558165;4995.150824359867;5047.545720885138;843.4770442325797;871.8299931513421;876.333986170515;896.3814967672286;912.0605776050678;3278.655741147193;3310.6854848789326;3359.798412095282;3380.594328680574;3393.8642454301553;8444.282236009885;8742.21320081183;8759.080296568769;8763.079876612992;8781.692640757543;8684.56577171139;8701.390902636755;8717.869231961526;8728.825423491495;8733.694320698745;6815.2162501312;6939.6877140528795;6941.34057264559;6941.409980151744;6945.340192681687;4765.9046819064115;4870.439899411472;4886.682365646364;4946.925796113133;5112.97443512795;8229.588520574067;8277.04813946404;8278.089410164997;8291.95854020726;8384.329253659815;7980.561069141253;8365.790855228668;8409.95795021021;8417.158284171534;8488.702895398632;6063.787767428119;6151.907721384272;6155.722992465439;6159.630422174684;6166.969165154145;6498.74005039812;6622.403983641303;6716.1604799400075;6742.797716068526;6838.14523184602;6249.031400132987;6363.5227357947415;6441.806745797634;6451.3226111257645;6454.19322584677;6256.280527739368;6262.937370626236;6329.398707122816;6457.096006558992;6486.54594178403;5700.543956483389;5740.425957404394;5788.160716696139;5794.82615521535;5818.301803783573;4221.746695198298;4235.576442355739;4317.8272869084885;4354.760488173181;4355.477342718894;4375.234333991475;4392.780360981874;4420.270655341833;4459.977700111608;4513.909721805479 12:26:28 INFO - PROCESS | 24296 | |4;prototype.html;286.61021011424464;289.6885847713708;290.94908391031566;293.7914081002488;294.97345238928517;4759.4764575896625;4852.5147485251455;4863.465018847924;4867.537583929526;4889.557580513748;4766.189747742884;4784.282357646346;4793.9280910786365;4817.421909370881;4847.951520484803;3576.4635304704243;3662.322470342982;3703.2223233120944;3727.9002694205137;3801.486799282096;3769.321522126016;3775.867239828054;3776.1126135358168;3786.9999999999864;3791;3754.9061273468246;3762.924741505168;3765.755225910321;3778.7732736035787;3789.071677439031;3592.389293820057;3686.2811751708427;3771.6605505504363;3784.9621503784883;3785.432185172219;3324.9501257481156;3330.4679847270286;3331.1172539277113;3331.4336562784383;3342.1477523231665;4708.246680531099;4723.795432164776;4725.267583524559;4740.360051393052;4748.7863046163;4215.515215750197;4301.548337424588;4328.826846926151;4333.393324934512;4335.544767799367;4971.925421118686;4976.751162441899;4983.401991761012;5009.674371165863;5052.368453943257;3625.836837342326;3632.418812989909;3632.946445530793;3647.4528820676846;3669.5963444021136;5103.821366252163;5289.2859463972645;5303.867403314909;5307.177387504943;5326.2543243945875;3724.646158614927;3744.2324323513612;3749.2876353492748;3754.6620804127215;3760.2855457463543;3803.733738638324;3809.695224382043;3815.8361699681864;3816.809159542012;3822.6177382261553;4312.568743125725;4314.978425107917;4340.804663790137;4341.9131617367475;4349.652027837766;4783.832565860178;5029.5724863386295;5032.245163225545;5039.521245481674;5047.470015648378;1314.953976610814;1326.4031185966353;1329.7473480038761;1330.3015916643647;1332.8333958255218;817.7669364231166;856.7601071699934;865.1521508921167;873.3493547307297;875.2516598308482;4283;4396.868093957122;4423.933640995388;4439.334099884978;4453.532379100213;5179.663321884141;5198.844034678966;5205.27126202332;5215.340176510007;5223.164293713064;4429.184034545885;4442.889627426946;4463.352813841975;4466.687331886802;4474.753888536096;2657.2426858345666;2757.2831063923522;2772.6118343431936;2782.789486573347;2787.1499192746405;4927.236278376858;4941.010688533457;4944.579710724629;4952.678075925053;4956.925646115311;5103.336566246438;5342.610921160526;5345.3852806926925;5383.730813459311;5411.83764487066;4900.240462728283;4920.680155789933;4927.43334516528;4930.753462326869;4980.800767969241;5204.245384419162;5236.711980841091;5246.213068039672;5268.420473747961;5299.364076310867;4922.261660750916;5057.519535644108;5063.885945092074;5117.385913690381;5128.384558863946;4901.166801643658;4903.509649035068;4903.5586797188425;4917.041176970456;4920.286558449076;3707.9814600927903;3726.6087060859304;3737.6262373762406;3757.380032294641;3761.5486141663177;5070.771815268248;5160.354955630546;5238.214267859851;5239.764210610456;5241.6068794839775;4949.925751113664;4978.651494395358;5007.924881126859;5013.949860501349;5016.648834581544;4998.950010499994;5013.2981382607195;5021;5063.8480845574695;5082.491750824888;4846.418429788448;4965.82619608312;4994.80020799164;4998.1003419383405;5009.123403404463;4805.207140821725;4892.437369702529;5029.7736601852275;5041.596672266137;5044.6631642614;4939.308496810378;4946.629002824874;4960.50394960501;4966.503349665005;4977.9751101244265 12:26:28 INFO - PROCESS | 24296 | |5;yui.html;488.9535494128058;539.1831375466169;555.8249151517272;570.5236127833259;666.3369946902833;13093.345332733374;14661.780073298909;14710.63223419415;14756.48352307668;14777.704445911075;12639.431225594848;12686.3656817159;12728.554500592481;12763.872361276384;12824.935875320634;642.5181114164377;766.0539234045963;782.4796510320658;785.2971590426565;785.5483097219093;766.1495739728897;794.4835856693138;798.6126728536655;802.5585927739749;803.3452736020154;834.2908527751423;835.4277320035765;836.9790755231107;837.3719710217337;837.4054421360842;671.3789744486379;828.1180542721982;829.3365307753803;829.5644786487112;829.6971605364029;573.4294377094817;595.743830153034;598.8712426828253;599.0714392691311;599.4904331318388;13070.019748518804;13147.671308217276;13701.657458563515;13706.93146534271;13774.65563360914;12672.68318292041;12888.677783055498;13049.739005219937;13346.799798002941;13506.054576179671;11903.523859045628;12024.33866137362;12176.634700958986;12378.44297006637;12585.874141258562;3971.821268042916;4053.716239863211;4083.9387409188885;4084.8774536763945;4085.3667681509414;69567.30432695709;70957.29042709559;71234;71612.6419367905;73135.99999999946;10761.78476430467;10771.246012779187;10772.838407423895;10806.243562950674;11105.83341249882;19673.409797706092;19830.107645156004;19930.30243941455;19951;19956.30152944669;6890.414314783299;7043.7534686285735;7054.188768291712;7217.133943926658;7218.530795498275;3760.52993375828;3766.6233376662112;3813.084859633723;3828.349180639298;3845.6346647068485;2787.1220565522203;2852.5863749756168;2864.5273529867754;2870.2250392393935;2871.1099559136733;500.53450291229507;509.3404041765922;519.7790938850973;521.7912834866084;522.8771238758889;514.9124648809636;530.4297879779252;543.8395673276393;546.6173678425118;553.6622660177288;6826.6272425780035;7071.611061391673;8044.798880027929;8174.550399728072;8366.707165249187;573.8938296415176;574.9080147176431;589.2251689028999;605.5458406195354;610.4353473037422;3704.7036237100965;3797.316483033025;3881.9611803882162;3903.843846246175;3926.411038344214;91495;94385;94893;95493;96489;66533.667331664;79974;81323.18676813274;82319.11883084184;82365.17634823575;59846;75459.69059238967;76698.61650691711;76754.84867726988;77045.61477192579;7578.355839753683;7640.961795190989;7650.961745191238;7674.8081297968;7726.961365193138;43203;43520.78239608908;45418.31872521849;45588.404405845635;45864.31203531883;54386.45613543814;56462.717686411306;57173;62773.372266276754;62968.37031629625;11367.090632749525;11381.772364552828;11547.191696581158;11566.65300041;11973.880261197624;12091;12222.20555663879;12375.443105060114;13367.398467069199;13639.795403068763;10921.672349829518;11812.645620631394;12173.087018473472;12361.443735031766;12375.938120309342;10532.89467105319;10812.35125892449;10971.835422468828;11317.434128293717;11342.376169310766;11380.829287560859;11700.648980530597;11815.645530634094;12029.278243305429;12052.457639406422;10734.570617175226;10939.28894621847;11043.779124417628;11099.833502497631;11364.602238921598;10091.89908100939;10325.63860264887;10394.22043346737;10418.114460270659;10452.477376131012 12:26:28 INFO - PROCESS | 24296 | __end_tp_report 12:26:28 INFO - PROCESS | 24296 | __start_cc_report 12:26:28 INFO - PROCESS | 24296 | _x_x_mozilla_cycle_collect,1039 12:26:28 INFO - PROCESS | 24296 | __end_cc_report 12:26:28 INFO - PROCESS | 24296 | __startTimestamp1470338788429__endTimestamp 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | ------- Summary: start ------- 12:26:28 INFO - PROCESS | 24296 | Number of tests: 6 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#0] dojo.html Cycles:165 Average:4787.58 Median:4905.22 stddev:513.01 (10.5%) stddev-sans-first:485.11 12:26:28 INFO - PROCESS | 24296 | Values: 2602.5 2614.9 2641.7 2649.4 2656.5 4681.4 4695.1 4747.1 4750.8 4758.8 4753.4 4813.1 4822.6 4828.1 4852.2 4431.9 4445.5 4459.6 4487.2 4511.5 4439.0 4452.2 4501.2 4540.1 4585.2 4516.3 4524.4 4552.9 4557.9 4577.2 4346.7 4473.0 4474.2 4508.6 4519.4 4364.6 4368.1 4423.3 4429.3 4492.8 4831.0 4862.8 4876.4 4885.8 4888.6 5172.6 5218.3 5232.6 5235.3 5238.2 5035.6 5116.6 5175.2 5183.0 5258.4 4889.0 4896.9 4900.6 4901.0 4908.6 5360.4 5366.3 5368.4 5371.2 5376.4 4926.7 4975.8 5031.7 5094.0 5162.7 5152.8 5199.4 5204.6 5254.5 5258.1 5153.3 5155.0 5158.6 5164.5 5216.7 4933.6 4984.6 5001.8 5025.5 5074.0 4389.4 4404.3 4412.8 4413.1 4414.4 3819.1 3866.9 3879.6 3883.1 3886.3 4397.0 4399.5 4400.9 4423.8 4440.0 5174.1 5179.7 5186.6 5192.6 5236.7 4299.8 4356.7 4360.8 4378.1 4384.9 4655.6 4683.0 4692.1 4713.5 4730.1 4854.8 4857.4 4859.8 4863.1 4903.9 5002.6 5124.6 5148.7 5155.1 5210.9 4859.2 4917.6 4929.3 4952.0 4977.2 5117.5 5159.4 5176.1 5177.1 5224.8 5012.7 5169.5 5221.7 5228.2 5230.5 5072.3 5105.9 5157.8 5159.8 5170.5 5087.9 5095.8 5104.3 5105.8 5155.8 4821.9 4905.2 5025.0 5048.6 5085.6 5051.2 5095.2 5111.1 5154.1 5157.8 4881.4 4951.0 4964.9 4981.8 4985.5 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#1] ext.html Cycles:180 Average:49457.99 Median:210412.00 stddev:95458.52 (45.4%) stddev-sans-first:95655.72 12:26:28 INFO - PROCESS | 24296 | Values: 567.4 575.8 578.5 588.1 596.1 37652.0 39266.0 39346.6 43178.4 43211.8 39819.8 40294.0 40360.6 40410.6 40436.6 7677.3 7822.3 7822.4 7824.3 7827.8 7555.5 7629.3 7632.7 7636.3 7636.8 7636.4 7669.4 7670.6 7675.9 7683.1 7606.7 7618.6 7639.3 7646.3 7652.5 7695.2 7753.7 7760.7 7763.8 7764.8 38443.2 38865.0 38883.4 38921.0 39001.2 2810.2 2991.7 3034.9 3119.6 3377.9 29196.3 29319.6 29654.1 29742.6 31074.8 3347.8 3439.5 3470.8 3520.9 3590.8 261953.0 264980.0 265638.0 265843.0 266262.0 30773.4 31863.8 48711.3 49007.5 49127.8 33220.8 33517.2 33624.3 33710.8 33748.2 13438.3 13603.7 13615.5 13621.2 13622.2 11415.2 11489.9 11491.4 11557.1 11579.1 7877.8 7882.6 7883.3 7960.0 7978.4 3020.1 3049.9 3065.4 3088.1 3109.9 770.2 843.8 845.0 882.5 919.4 26681.3 27980.4 28119.0 28381.3 28723.6 768.0 955.9 962.6 992.6 1011.7 14586.4 15409.8 15468.7 15595.8 15714.3 410026.0 413544.0 414855.0 416130.0 416170.0 48094.5 48608.3 48624.3 48660.5 48737.3 1067.9 1171.6 1182.2 1201.4 1209.4 25431.7 29396.7 29425.0 29614.4 29809.6 40961.6 46716.9 48019.5 48154.8 52653.2 383172.0 384679.0 385172.0 386629.0 386837.0 24429.1 25117.4 38943.0 39018.0 39537.2 41153.2 41233.6 41437.4 41527.0 41565.0 33159.7 34413.5 34480.3 34606.5 34834.0 35928.1 36045.8 36113.0 36444.8 36465.6 30914.7 35134.8 35148.3 35867.5 35934.5 30769.6 32820.2 33623.0 33839.0 33877.0 25321.6 25454.5 25839.0 26235.6 26320.9 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#2] jquery.html Cycles:180 Average:24920.70 Median:5205.62 stddev:68311.21 (1312.3%) stddev-sans-first:68477.95 12:26:28 INFO - PROCESS | 24296 | Values: 361.5 363.8 368.5 377.1 389.1 16173.8 17286.1 17555.3 17597.9 17625.6 15547.7 16621.0 16977.0 17090.5 17139.0 4952.4 5187.8 5189.9 5204.8 5206.4 3688.1 3898.4 5044.2 5807.4 6035.8 6099.6 6102.0 6103.8 6114.3 6144.8 5814.8 5968.4 5978.4 5986.9 5994.1 4628.1 5274.7 5283.2 5283.8 5289.0 12009.9 12352.9 12395.5 12402.6 12409.8 1872.9 1918.4 1940.0 2276.1 2310.0 8757.3 8914.4 9081.8 9358.5 9368.9 1694.4 1694.6 1739.4 1754.9 1795.4 69756.7 73474.0 73681.6 73772.0 76448.9 9625.5 9822.6 9823.0 9835.5 10046.3 13126.7 13130.6 13139.3 13146.2 13150.3 7300.5 7531.5 7535.1 7620.7 7654.8 7297.5 7304.6 7304.8 7464.9 7484.9 4098.4 4129.7 4169.7 4173.8 4206.2 1625.9 1652.9 1728.9 1733.9 1776.7 789.8 789.9 813.8 839.6 849.3 16100.3 16979.4 17059.4 17090.7 17299.6 830.0 858.6 861.3 895.3 906.6 9029.4 9593.7 9683.5 9686.1 9851.6 392242.0 396493.0 400173.0 433781.0 440558.0 56009.7 60650.4 84001.0 89159.0 92200.5 714.4 891.8 893.4 922.4 930.6 15589.8 15621.5 15767.2 16088.9 16412.0 29038.7 38956.8 39029.2 39120.2 40191.6 55803.4 56348.0 56492.0 57243.7 58196.1 6593.2 6845.0 6934.6 7211.8 7303.9 9281.4 11361.0 11411.7 11799.6 12240.1 9936.8 10334.4 10341.4 10420.1 11189.8 9425.3 9626.9 9869.6 10020.2 10037.5 9771.7 9949.5 10102.8 10213.7 10698.4 6645.3 6739.5 6917.4 6939.6 7028.4 7413.9 10090.4 10196.7 10226.0 10704.1 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#3] mootools.html Cycles:180 Average:4775.35 Median:5720.48 stddev:2589.23 (45.3%) stddev-sans-first:2583.35 12:26:28 INFO - PROCESS | 24296 | Values: 1303.7 1374.4 1389.0 1599.7 1640.3 6962.3 7324.7 7331.8 7335.7 7339.7 6211.8 6900.7 7026.3 7069.4 7213.0 2136.3 2164.8 2165.3 2166.8 2172.1 2089.6 2140.7 2169.1 2169.7 2172.4 1779.8 2053.1 2104.0 2111.8 2132.0 2120.5 2121.5 2139.9 2140.9 2141.7 2122.2 2126.0 2126.6 2127.5 2129.6 7159.1 7211.5 7224.6 7235.3 7249.5 4039.4 4072.6 4077.0 4085.1 4223.1 2775.1 2865.6 2868.6 2869.9 2884.2 901.6 942.8 961.9 963.7 975.7 9538.2 9542.1 9580.5 9582.9 9603.0 3637.8 3812.8 3916.0 3924.0 3963.7 8307.8 8308.9 8312.7 8321.7 8323.6 4330.0 4366.2 4367.1 4372.7 4374.0 3954.1 3997.8 3997.9 3999.9 4009.6 3441.4 3540.1 3551.0 3556.4 3558.3 1135.6 1136.1 1141.7 1149.4 1153.2 603.4 678.4 757.2 764.3 777.3 4813.0 4878.9 4979.2 4995.2 5047.5 843.5 871.8 876.3 896.4 912.1 3278.7 3310.7 3359.8 3380.6 3393.9 8444.3 8742.2 8759.1 8763.1 8781.7 8684.6 8701.4 8717.9 8728.8 8733.7 6815.2 6939.7 6941.3 6941.4 6945.3 4765.9 4870.4 4886.7 4946.9 5113.0 8229.6 8277.0 8278.1 8292.0 8384.3 7980.6 8365.8 8410.0 8417.2 8488.7 6063.8 6151.9 6155.7 6159.6 6167.0 6498.7 6622.4 6716.2 6742.8 6838.1 6249.0 6363.5 6441.8 6451.3 6454.2 6256.3 6262.9 6329.4 6457.1 6486.5 5700.5 5740.4 5788.2 5794.8 5818.3 4221.7 4235.6 4317.8 4354.8 4355.5 4375.2 4392.8 4420.3 4460.0 4513.9 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#4] prototype.html Cycles:180 Average:4202.95 Median:4811.31 stddev:1213.12 (25.2%) stddev-sans-first:1180.38 12:26:28 INFO - PROCESS | 24296 | Values: 286.6 289.7 290.9 293.8 295.0 4759.5 4852.5 4863.5 4867.5 4889.6 4766.2 4784.3 4793.9 4817.4 4848.0 3576.5 3662.3 3703.2 3727.9 3801.5 3769.3 3775.9 3776.1 3787.0 3791.0 3754.9 3762.9 3765.8 3778.8 3789.1 3592.4 3686.3 3771.7 3785.0 3785.4 3325.0 3330.5 3331.1 3331.4 3342.1 4708.2 4723.8 4725.3 4740.4 4748.8 4215.5 4301.5 4328.8 4333.4 4335.5 4971.9 4976.8 4983.4 5009.7 5052.4 3625.8 3632.4 3632.9 3647.5 3669.6 5103.8 5289.3 5303.9 5307.2 5326.3 3724.6 3744.2 3749.3 3754.7 3760.3 3803.7 3809.7 3815.8 3816.8 3822.6 4312.6 4315.0 4340.8 4341.9 4349.7 4783.8 5029.6 5032.2 5039.5 5047.5 1315.0 1326.4 1329.7 1330.3 1332.8 817.8 856.8 865.2 873.3 875.3 4283.0 4396.9 4423.9 4439.3 4453.5 5179.7 5198.8 5205.3 5215.3 5223.2 4429.2 4442.9 4463.4 4466.7 4474.8 2657.2 2757.3 2772.6 2782.8 2787.1 4927.2 4941.0 4944.6 4952.7 4956.9 5103.3 5342.6 5345.4 5383.7 5411.8 4900.2 4920.7 4927.4 4930.8 4980.8 5204.2 5236.7 5246.2 5268.4 5299.4 4922.3 5057.5 5063.9 5117.4 5128.4 4901.2 4903.5 4903.6 4917.0 4920.3 3708.0 3726.6 3737.6 3757.4 3761.5 5070.8 5160.4 5238.2 5239.8 5241.6 4949.9 4978.7 5007.9 5013.9 5016.6 4999.0 5013.3 5021.0 5063.8 5082.5 4846.4 4965.8 4994.8 4998.1 5009.1 4805.2 4892.4 5029.8 5041.6 5044.7 4939.3 4946.6 4960.5 4966.5 4978.0 12:26:28 INFO - PROCESS | 24296 | 12:26:28 INFO - PROCESS | 24296 | [#5] yui.html Cycles:180 Average:17805.06 Median:43361.89 stddev:24910.93 (57.4%) stddev-sans-first:24946.88 12:26:28 INFO - PROCESS | 24296 | Values: 489.0 539.2 555.8 570.5 666.3 13093.3 14661.8 14710.6 14756.5 14777.7 12639.4 12686.4 12728.6 12763.9 12824.9 642.5 766.1 782.5 785.3 785.5 766.1 794.5 798.6 802.6 803.3 834.3 835.4 837.0 837.4 837.4 671.4 828.1 829.3 829.6 829.7 573.4 595.7 598.9 599.1 599.5 13070.0 13147.7 13701.7 13706.9 13774.7 12672.7 12888.7 13049.7 13346.8 13506.1 11903.5 12024.3 12176.6 12378.4 12585.9 3971.8 4053.7 4083.9 4084.9 4085.4 69567.3 70957.3 71234.0 71612.6 73136.0 10761.8 10771.2 10772.8 10806.2 11105.8 19673.4 19830.1 19930.3 19951.0 19956.3 6890.4 7043.8 7054.2 7217.1 7218.5 3760.5 3766.6 3813.1 3828.3 3845.6 2787.1 2852.6 2864.5 2870.2 2871.1 500.5 509.3 519.8 521.8 522.9 514.9 530.4 543.8 546.6 553.7 6826.6 7071.6 8044.8 8174.6 8366.7 573.9 574.9 589.2 605.5 610.4 3704.7 3797.3 3882.0 3903.8 3926.4 91495.0 94385.0 94893.0 95493.0 96489.0 66533.7 79974.0 81323.2 82319.1 82365.2 59846.0 75459.7 76698.6 76754.8 77045.6 7578.4 7641.0 7651.0 7674.8 7727.0 43203.0 43520.8 45418.3 45588.4 45864.3 54386.5 56462.7 57173.0 62773.4 62968.4 11367.1 11381.8 11547.2 11566.7 11973.9 12091.0 12222.2 12375.4 13367.4 13639.8 10921.7 11812.6 12173.1 12361.4 12375.9 10532.9 10812.4 10971.8 11317.4 11342.4 11380.8 11700.6 11815.6 12029.3 12052.5 10734.6 10939.3 11043.8 11099.8 11364.6 10091.9 10325.6 10394.2 10418.1 10452.5 12:26:28 INFO - PROCESS | 24296 | -------- Summary: end -------- 12:26:28 INFO - PROCESS | 24296 | 12:26:29 INFO - TEST-INFO | 24296: exit 0 12:26:29 INFO - TEST-OK | dromaeo_css | took 1121845ms 12:26:29 INFO - TEST-START | kraken 12:26:29 INFO - operating with platform_type : linux_ 12:26:29 INFO - Initialising browser for kraken test... 12:26:29 INFO - TEST-INFO | started process 24485 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4h10Mv/profile http://localhost:47614/getInfo.html) 12:26:34 INFO - PROCESS | 24485 | __metrics Screen width/height:1600/1200 12:26:34 INFO - PROCESS | 24485 | colorDepth:24 12:26:34 INFO - PROCESS | 24485 | Browser inner width/height: 1024/697 12:26:34 INFO - PROCESS | 24485 | __metrics 12:26:35 INFO - PROCESS | 24485 | 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" 12:26:35 INFO - PROCESS | 24485 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:26:35 INFO - PROCESS | 24485 | console.error: PushService: 12:26:35 INFO - PROCESS | 24485 | stateChangeProcessEnqueue: Error transitioning state 12:26:35 INFO - PROCESS | 24485 | UnknownError 12:26:36 INFO - TEST-INFO | 24485: exit 0 12:26:36 INFO - Browser initialized. 12:26:36 INFO - Running cycle 1/1 for kraken test... 12:26:36 INFO - TEST-INFO | started process 24565 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4h10Mv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:26:48 INFO - PROCESS | 24565 | RSS: Main: 156229632 12:26:48 INFO - PROCESS | 24565 | 12:26:50 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-beat-detection) 12:26:51 INFO - PROCESS | 24565 | RSS: Main: 212697088 12:26:51 INFO - PROCESS | 24565 | 12:26:54 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-dft) 12:26:55 INFO - PROCESS | 24565 | RSS: Main: 190308352 12:26:55 INFO - PROCESS | 24565 | 12:26:58 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-fft) 12:26:58 INFO - PROCESS | 24565 | RSS: Main: 270348288 12:26:58 INFO - PROCESS | 24565 | 12:27:01 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-oscillator) 12:27:01 INFO - PROCESS | 24565 | RSS: Main: 179904512 12:27:01 INFO - PROCESS | 24565 | 12:27:03 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:04 INFO - PROCESS | 24565 | RSS: Main: 183881728 12:27:04 INFO - PROCESS | 24565 | 12:27:07 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:08 INFO - PROCESS | 24565 | RSS: Main: 220274688 12:27:08 INFO - PROCESS | 24565 | 12:27:11 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:12 INFO - PROCESS | 24565 | RSS: Main: 214335488 12:27:12 INFO - PROCESS | 24565 | 12:27:15 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47614/tests/kraken/driver.html?testName=json-parse-financial) 12:27:16 INFO - PROCESS | 24565 | RSS: Main: 217526272 12:27:16 INFO - PROCESS | 24565 | 12:27:17 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47614/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:27:18 INFO - PROCESS | 24565 | RSS: Main: 183214080 12:27:18 INFO - PROCESS | 24565 | 12:27:20 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:27:20 INFO - PROCESS | 24565 | RSS: Main: 189665280 12:27:20 INFO - PROCESS | 24565 | 12:27:22 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:23 INFO - PROCESS | 24565 | RSS: Main: 193040384 12:27:23 INFO - PROCESS | 24565 | 12:27:26 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:27 INFO - PROCESS | 24565 | RSS: Main: 193171456 12:27:27 INFO - PROCESS | 24565 | 12:27:29 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:27:30 INFO - PROCESS | 24565 | RSS: Main: 194994176 12:27:30 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47614/tests/kraken/driver.html?testName=ai-astar) 12:27:32 INFO - PROCESS | 24565 | RSS: Main: 242647040 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | __start_tp_report 12:27:32 INFO - PROCESS | 24565 | _x_x_mozilla_page_load 12:27:32 INFO - PROCESS | 24565 | _x_x_mozilla_page_load_details 12:27:32 INFO - PROCESS | 24565 | |i|pagename|runs| 12:27:32 INFO - PROCESS | 24565 | |0;ai-astar;104;116;115;113;115;117;114;115;113;113 12:27:32 INFO - PROCESS | 24565 | |1;audio-beat-detection;146;122;123;197;121;126;144;122;154;122 12:27:32 INFO - PROCESS | 24565 | |2;audio-dft;162;163;160;162;162;161;162;162;163;162 12:27:32 INFO - PROCESS | 24565 | |3;audio-fft;76;75;75;123;77;76;75;75;101;76 12:27:32 INFO - PROCESS | 24565 | |4;audio-oscillator;86;87;118;86;86;86;121;84;85;131 12:27:32 INFO - PROCESS | 24565 | |5;imaging-gaussian-blur;117;116;118;117;117;118;117;117;117;117 12:27:32 INFO - PROCESS | 24565 | |6;imaging-darkroom;147;148;148;146;147;146;147;147;147;146 12:27:32 INFO - PROCESS | 24565 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;94 12:27:32 INFO - PROCESS | 24565 | |8;json-parse-financial;64;63;64;64;62;64;63;63;62;62 12:27:32 INFO - PROCESS | 24565 | |9;json-stringify-tinderbox;65;65;66;64;65;65;65;65;65;66 12:27:32 INFO - PROCESS | 24565 | |10;stanford-crypto-aes;89;82;80;79;82;81;79;78;79;88 12:27:32 INFO - PROCESS | 24565 | |11;stanford-crypto-ccm;129;150;128;129;127;128;126;127;141;145 12:27:32 INFO - PROCESS | 24565 | |12;stanford-crypto-pbkdf2;165;167;169;171;167;165;167;167;166;167 12:27:32 INFO - PROCESS | 24565 | |13;stanford-crypto-sha256-iterative;68;66;67;68;68;68;67;68;68;68 12:27:32 INFO - PROCESS | 24565 | __end_tp_report 12:27:32 INFO - PROCESS | 24565 | __start_cc_report 12:27:32 INFO - PROCESS | 24565 | _x_x_mozilla_cycle_collect,1912 12:27:32 INFO - PROCESS | 24565 | __end_cc_report 12:27:32 INFO - PROCESS | 24565 | __startTimestamp1470338852573__endTimestamp 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | ------- Summary: start ------- 12:27:32 INFO - PROCESS | 24565 | Number of tests: 14 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#0] ai-astar Cycles:10 Average:113.50 Median:115.00 stddev:3.60 (3.1%) stddev-sans-first:1.42 12:27:32 INFO - PROCESS | 24565 | Values: 104.0 116.0 115.0 113.0 115.0 117.0 114.0 115.0 113.0 113.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:135.00 stddev:24.18 (17.9%) stddev-sans-first:25.46 12:27:32 INFO - PROCESS | 24565 | Values: 146.0 122.0 123.0 197.0 121.0 126.0 144.0 122.0 154.0 122.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 12:27:32 INFO - PROCESS | 24565 | Values: 162.0 163.0 160.0 162.0 162.0 161.0 162.0 162.0 163.0 162.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:16.20 (21.5%) stddev-sans-first:16.99 12:27:32 INFO - PROCESS | 24565 | Values: 76.0 75.0 75.0 123.0 77.0 76.0 75.0 75.0 101.0 76.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:86.00 stddev:18.47 (21.5%) stddev-sans-first:19.16 12:27:32 INFO - PROCESS | 24565 | Values: 86.0 87.0 118.0 86.0 86.0 86.0 121.0 84.0 85.0 131.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 12:27:32 INFO - PROCESS | 24565 | Values: 117.0 116.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 12:27:32 INFO - PROCESS | 24565 | Values: 147.0 148.0 148.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:27:32 INFO - PROCESS | 24565 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.50 stddev:0.88 (1.4%) stddev-sans-first:0.87 12:27:32 INFO - PROCESS | 24565 | Values: 64.0 63.0 64.0 64.0 62.0 64.0 63.0 63.0 62.0 62.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 12:27:32 INFO - PROCESS | 24565 | Values: 65.0 65.0 66.0 64.0 65.0 65.0 65.0 65.0 65.0 66.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:81.50 stddev:3.83 (4.7%) stddev-sans-first:3.02 12:27:32 INFO - PROCESS | 24565 | Values: 89.0 82.0 80.0 79.0 82.0 81.0 79.0 78.0 79.0 88.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:129.00 stddev:8.82 (6.8%) stddev-sans-first:9.23 12:27:32 INFO - PROCESS | 24565 | Values: 129.0 150.0 128.0 129.0 127.0 128.0 126.0 127.0 141.0 145.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:1.79 (1.1%) stddev-sans-first:1.73 12:27:32 INFO - PROCESS | 24565 | Values: 165.0 167.0 169.0 171.0 167.0 165.0 167.0 167.0 166.0 167.0 12:27:32 INFO - PROCESS | 24565 | 12:27:32 INFO - PROCESS | 24565 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 12:27:32 INFO - PROCESS | 24565 | Values: 68.0 66.0 67.0 68.0 68.0 68.0 67.0 68.0 68.0 68.0 12:27:32 INFO - PROCESS | 24565 | -------- Summary: end -------- 12:27:32 INFO - PROCESS | 24565 | 12:27:33 INFO - TEST-INFO | 24565: exit 0 12:27:33 INFO - TEST-OK | kraken | took 64416ms 12:27:33 INFO - SUITE-END | took 1186s 12:27:34 INFO - Completed test suite (00:19:46) 12:27:34 INFO - javascript benchmark 12:27:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.5445547029267, 2614.908478203263, 2641.7490338417842, 2649.390640152764, 2656.495265899479, 4681.414823147106, 4695.107929493398, 4747.121782470245, 4750.809967601292, 4758.833440829571, 4753.382060332161, 4813.109574728677, 4822.5900798432085, 4828.0585285869265, 4852.175130227861, 4431.889202769924, 4445.466544014723, 4459.598636122756, 4487.237169681162, 4511.526289739563, 4438.977805110986, 4452.220861349267, 4501.212287849629, 4540.137373898965, 4585.174668559658, 4516.277395616702, 4524.434445694288, 4552.9089418211615, 4557.908841823161, 4577.198990176706, 4346.717463364871, 4472.955270447319, 4474.172278128557, 4508.61676757476, 4519.389882365871, 4364.6290065344165, 4368.08270263242, 4423.336499525065, 4429.291313389842, 4492.797824097924, 4830.951690483085, 4862.7568621569135, 4876.439209490918, 4885.828995985124, 4888.633352498541, 5172.586193104543, 5218.321618189611, 5232.581393488512, 5235.293235413209, 5238.188080847474, 5035.647504674675, 5116.590672746172, 5175.249588809602, 5183.0, 5258.421573626898, 4889.022195560938, 4896.877578060577, 4900.607951363882, 4900.975495122501, 4908.558229759339, 5360.410354860962, 5366.329208848894, 5368.355797304349, 5371.167469042305, 5376.435474275223, 4926.704397736147, 4975.751212439363, 5031.6729412588065, 5094.006668699642, 5162.664426812245, 5152.845414637492, 5199.350081239845, 5204.609654275944, 5254.500822421864, 5258.079836028756, 5153.2785410042625, 5155.046316431472, 5158.587313014949, 5164.509371609691, 5216.686998780086, 4933.55597996182, 4984.601231901439, 5001.849944501598, 5025.522575355335, 5074.0, 4389.363542286351, 4404.2512772828695, 4412.757298348557, 4413.117376524708, 4414.404055452473, 3819.1406933439753, 3866.9226615467533, 3879.592642772525, 3883.126296583246, 3886.2616102940915, 4397.0, 4399.5380485049245, 4400.9119817604105, 4423.800928958204, 4440.045390241049, 5174.120399532088, 5179.689218646893, 5186.5850731941355, 5192.584593232532, 5236.6857988520815, 4299.806508707115, 4356.6732495063, 4360.760158191267, 4378.146261479044, 4384.934225986613, 4655.557722016403, 4682.953170468251, 4692.131955588227, 4713.505081966344, 4730.077634861238, 4854.83008094722, 4857.441394239567, 4859.757012149307, 4863.1003264395495, 4903.8528844134735, 5002.599792016557, 5124.615653826053, 5148.7425628717665, 5155.097857874932, 5210.921836172536, 4859.173940430064, 4917.6311776616185, 4929.285253638274, 4951.950480495149, 4977.178765503651, 5117.513836185556, 5159.432462429055, 5176.094183517945, 5177.0940085485645, 5224.791008359623, 5012.724300163526, 5169.457206993212, 5221.660592061504, 5228.215767634885, 5230.476952304739, 5072.34059572268, 5105.8723531912, 5157.819476318311, 5159.845204643867, 5170.457102004236, 5087.87280317995, 5095.77069031887, 5104.336436263262, 5105.795768169231, 5155.793768249228, 4821.927671084866, 4905.2151655734915, 5025.045241404122, 5048.646594738333, 5085.567726743268, 5051.217061355495, 5095.210242412432, 5111.105556527518, 5154.123798954111, 5157.793688252428, 4881.365422495053, 4950.975245123751, 4964.8758781030765, 4981.800727970841, 4985.52637499437], "value": 4753.613867361278, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.4042255631587, 575.8013485347557, 578.4620303118102, 588.0855270055065, 596.0701305962701, 37652.0, 39266.0, 39346.60653393462, 43178.35232471515, 43211.78394108025, 39819.80090099553, 40294.0, 40360.59639403605, 40410.59589404105, 40436.59563404365, 7677.347425468828, 7822.335101516378, 7822.37421006321, 7824.256695613907, 7827.804304892395, 7555.471117021783, 7629.275218854228, 7632.694692212305, 7636.31273185416, 7636.809079772995, 7636.350910172642, 7669.424793140509, 7670.578118203496, 7675.884861727079, 7683.078030636331, 7606.733764318277, 7618.580978046205, 7639.274268944441, 7646.273604007611, 7652.540847549137, 7695.23047695226, 7753.728619498291, 7760.650770715331, 7763.844723105506, 7764.805879852993, 38443.23113537741, 38865.0, 38883.416748748794, 38921.0, 39001.21997560033, 2810.211164166323, 2991.6559595646563, 3034.908952731422, 3119.5788568543185, 3377.9493307600405, 29196.270093247625, 29319.560206596918, 29654.110376688735, 29742.553861692093, 31074.84462577673, 3347.832608369572, 3439.5012723155, 3470.7570470067108, 3520.8767693130617, 3590.838412271454, 261953.0, 264980.0, 265638.0, 265842.9999999981, 266262.0, 30773.38453230923, 31863.84068079691, 48711.269330960065, 49007.509924900296, 49127.75436122796, 33220.83389583085, 33517.16207094842, 33624.32751345008, 33710.831445843105, 33748.15629609279, 13438.260895650637, 13603.727925441435, 13615.455381784815, 13621.182729036289, 13622.182669039888, 11415.20093593457, 11489.942550287195, 11491.42542872853, 11557.075433965263, 11579.073674106052, 7877.803054923558, 7882.605869706492, 7883.251091146332, 7960.009997500625, 7978.401619878532, 3020.0939718084487, 3049.8780048798244, 3065.402246561899, 3088.135322109813, 3109.875604975776, 770.2143813310392, 843.843888880559, 845.0324378586484, 882.4705176893941, 919.3977944446399, 26681.3329666756, 27980.440391192064, 28119.0, 28381.290467738057, 28723.569146462818, 767.9884801727978, 955.9091886270793, 962.5620342744035, 992.5979978108877, 1011.716719318592, 14586.416543338148, 15409.84590154084, 15468.690626187414, 15595.846152314458, 15714.29285682147, 410026.0, 413544.0, 414855.0, 416130.0, 416170.0, 48094.5190548097, 48608.27087593689, 48624.27063594049, 48660.513394866306, 48737.268940965914, 1067.8985496377832, 1171.5782318365211, 1182.184292837939, 1201.4053043743404, 1209.4255228766265, 25431.745682542936, 29396.706032939397, 29425.0, 29614.407711846074, 29809.5528567076, 40961.590384095776, 46716.89773960248, 48019.51980480151, 48154.75922620364, 52653.21020184777, 383172.0, 384679.0, 385172.0, 386629.0, 386837.0, 24429.14497992562, 25117.372065698502, 38943.0, 39018.0, 39537.209255814145, 41153.1769364617, 41233.58766412298, 41437.378439322834, 41527.0, 41565.0, 33159.668403316624, 34413.48379774355, 34480.31039379248, 34606.480902786985, 34834.0, 35928.101797455274, 36045.81977090098, 36113.0, 36444.81777591095, 36465.635343647285, 30914.690853091182, 35134.82432587821, 35148.29703405969, 35867.461988070725, 35934.460983085795, 30769.61516743033, 32820.179495511846, 33623.0, 33839.0, 33877.0, 25321.62017569701, 25454.490910182063, 25839.0, 26235.60646590341, 26320.8683956579], "value": 16659.44023008311, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.49571347969584, 363.82900036982625, 368.4675643694861, 377.10625816814155, 389.06624102154825, 16173.838261617395, 17286.135693215336, 17555.297788088475, 17597.912010439963, 17625.55936101598, 15547.68904621907, 16621.0, 16977.0, 17090.487285381427, 17139.0, 4952.380952380961, 5187.818426355078, 5189.870253243661, 5204.843854684365, 5206.401263854648, 3688.133288677159, 3898.3957486589625, 5044.19292913134, 5807.448292412214, 6035.758569657209, 6099.603525770833, 6101.969490152543, 6103.755849766004, 6114.327423983358, 6144.7542098316235, 5814.796482123126, 5968.4031596840405, 5978.372270911578, 5986.880262394728, 5994.100884867261, 4628.09752098341, 5274.736263186864, 5283.1546952487415, 5283.815066472655, 5289.021531016774, 12009.93995030028, 12352.938235308855, 12395.504179832886, 12402.56591019319, 12409.813852792215, 1872.915718792644, 1918.3957053528095, 1939.9806001940083, 2276.146445083094, 2309.988450057756, 8757.343199260082, 8914.420562663407, 9081.772955676095, 9358.485283309416, 9368.90631093687, 1694.3561446650194, 1694.6271820199536, 1739.391213075426, 1754.929802807886, 1795.3985414886029, 69756.6512167441, 73474.0, 73681.63159184223, 73772.0, 76448.88408927273, 9625.51872406377, 9822.557984890696, 9823.0, 9835.459049752333, 10046.29675922693, 13126.737465250833, 13130.570844393818, 13139.34303284851, 13146.21122732639, 13150.342482876009, 7300.452466065067, 7531.472796904164, 7535.058117735283, 7620.695172193161, 7654.770356889302, 7297.525660832029, 7304.5982470963545, 7304.817379565553, 7464.85070298591, 7484.925150748423, 4098.446709694213, 4129.69027322952, 4169.749815011109, 4173.812178451977, 4206.158768246363, 1625.886187966831, 1652.867770578351, 1728.9394871179447, 1733.878628495993, 1776.6535525572647, 789.8459800338994, 789.944703870735, 813.7762115418188, 839.6095815445725, 849.3205435651454, 16100.275487603087, 16979.405720799714, 17059.402920897708, 17090.658186836194, 17299.56751081233, 829.9751007469786, 858.6136238692488, 861.2550144125287, 895.3463971300987, 906.6373450619805, 9029.367944243972, 9593.65688803568, 9683.467409292416, 9686.128248457673, 9851.556679949419, 392242.0, 396493.0, 400173.0, 433781.0, 440558.0, 56009.71995139998, 60650.39349606536, 84001.0, 89159.0, 92200.53899730458, 714.3677845107118, 891.8038031633031, 893.3924931046821, 922.4188761080476, 930.6370515498961, 15589.766153507933, 15621.453249136226, 15767.211639417754, 16088.919555402148, 16412.0, 29038.7096129036, 38956.80521597374, 39029.2194156121, 39120.21759564849, 40191.598084018784, 55803.44196557983, 56348.0, 56492.0, 57243.71378143083, 58196.12705809331, 6593.208814942238, 6844.965775171092, 6934.618595977269, 7211.819704507429, 7303.8539229216185, 9281.39670921388, 11361.0, 11411.714707132389, 11799.587014454453, 12240.08199385032, 9936.801263974825, 10334.431606261727, 10341.37951722899, 10420.06219440254, 11189.776204476027, 9425.293103017164, 9626.9037309626, 9869.605215791287, 10020.24848136378, 10037.49812509357, 9771.657991970247, 9949.502524873873, 10102.797944041225, 10213.74465638365, 10698.358098514114, 6645.3022432643875, 6739.528233023641, 6917.412019978358, 6939.618320992394, 7028.367446929801, 7413.851722965618, 10090.39457632515, 10196.745081373025, 10226.0, 10704.09015233714], "value": 8066.495761348976, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.70666600015, 1374.4433504430706, 1389.0, 1599.6800639872029, 1640.3110693508725, 6962.303769623041, 7324.743633972812, 7331.78004659861, 7335.669894854731, 7339.70641174353, 6211.751529938809, 6900.689468973896, 7026.332498412643, 7069.399101076415, 7213.0, 2136.2843447445084, 2164.77269886662, 2165.263810304496, 2166.7616562178146, 2172.1311475409802, 2089.6447603907295, 2140.7003019577273, 2169.1323470611724, 2169.7070895429074, 2172.3808714516367, 1779.8131196224404, 2053.1376821734907, 2104.042660589428, 2111.831053515723, 2131.968020479694, 2120.512282175093, 2121.5332626822074, 2139.882306473143, 2140.9464763380884, 2141.742990841109, 2122.204173434962, 2125.978740212609, 2126.6278401279715, 2127.5213077057538, 2129.6060228857555, 7159.14090309161, 7211.495195336329, 7224.566526008456, 7235.276472352775, 7249.528780629243, 4039.394090886361, 4072.572379900127, 4077.0, 4085.0604360997136, 4223.070924396628, 2775.1258353618546, 2865.627468429091, 2868.641419822522, 2869.856507174654, 2884.2356775454523, 901.5627420700955, 942.7972985808016, 961.9182369498601, 963.6868017894212, 975.6829030565095, 9538.189253913357, 9542.093501117382, 9580.473073980998, 9582.90417095827, 9603.0, 3637.8181090945345, 3812.84748610058, 3915.980420097881, 3924.038610540436, 3963.7225394222705, 8307.750767476986, 8308.875366869503, 8312.709055183039, 8321.66713331472, 8323.583820809057, 4329.978350108229, 4366.192254432876, 4367.10474352757, 4372.737635741865, 4374.0, 3954.130091379892, 3997.8001099944886, 3997.9000524986527, 3999.8800035998966, 4009.599040095967, 3441.380551500754, 3540.13266749648, 3551.0, 3556.41319182332, 3558.323918455485, 1135.6479491357707, 1136.1024790415645, 1141.6777178410784, 1149.3563604381573, 1153.2388623508443, 603.4026313949158, 678.3555622158968, 757.2011527838074, 764.3388468974297, 777.3392616276233, 4813.03739252144, 4878.878028049327, 4979.178435558165, 4995.150824359867, 5047.545720885138, 843.4770442325797, 871.8299931513421, 876.333986170515, 896.3814967672286, 912.0605776050678, 3278.655741147193, 3310.6854848789326, 3359.798412095282, 3380.594328680574, 3393.8642454301553, 8444.282236009885, 8742.21320081183, 8759.080296568769, 8763.079876612992, 8781.692640757543, 8684.56577171139, 8701.390902636755, 8717.869231961526, 8728.825423491495, 8733.694320698745, 6815.2162501312, 6939.6877140528795, 6941.34057264559, 6941.409980151744, 6945.340192681687, 4765.9046819064115, 4870.439899411472, 4886.682365646364, 4946.925796113133, 5112.97443512795, 8229.588520574067, 8277.04813946404, 8278.089410164997, 8291.95854020726, 8384.329253659815, 7980.561069141253, 8365.790855228668, 8409.95795021021, 8417.158284171534, 8488.702895398632, 6063.787767428119, 6151.907721384272, 6155.722992465439, 6159.630422174684, 6166.969165154145, 6498.74005039812, 6622.403983641303, 6716.1604799400075, 6742.797716068526, 6838.14523184602, 6249.031400132987, 6363.5227357947415, 6441.806745797634, 6451.3226111257645, 6454.19322584677, 6256.280527739368, 6262.937370626236, 6329.398707122816, 6457.096006558992, 6486.54594178403, 5700.543956483389, 5740.425957404394, 5788.160716696139, 5794.82615521535, 5818.301803783573, 4221.746695198298, 4235.576442355739, 4317.8272869084885, 4354.760488173181, 4355.477342718894, 4375.234333991475, 4392.780360981874, 4420.270655341833, 4459.977700111608, 4513.909721805479], "value": 3896.691895968068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.61021011424464, 289.6885847713708, 290.94908391031566, 293.7914081002488, 294.97345238928517, 4759.4764575896625, 4852.5147485251455, 4863.465018847924, 4867.537583929526, 4889.557580513748, 4766.189747742884, 4784.282357646346, 4793.9280910786365, 4817.421909370881, 4847.951520484803, 3576.4635304704243, 3662.322470342982, 3703.2223233120944, 3727.9002694205137, 3801.486799282096, 3769.321522126016, 3775.867239828054, 3776.1126135358168, 3786.9999999999864, 3791.0, 3754.9061273468246, 3762.924741505168, 3765.755225910321, 3778.7732736035787, 3789.071677439031, 3592.389293820057, 3686.2811751708427, 3771.6605505504363, 3784.9621503784883, 3785.432185172219, 3324.9501257481156, 3330.4679847270286, 3331.1172539277113, 3331.4336562784383, 3342.1477523231665, 4708.246680531099, 4723.795432164776, 4725.267583524559, 4740.360051393052, 4748.7863046163, 4215.515215750197, 4301.548337424588, 4328.826846926151, 4333.393324934512, 4335.544767799367, 4971.925421118686, 4976.751162441899, 4983.401991761012, 5009.674371165863, 5052.368453943257, 3625.836837342326, 3632.418812989909, 3632.946445530793, 3647.4528820676846, 3669.5963444021136, 5103.821366252163, 5289.2859463972645, 5303.867403314909, 5307.177387504943, 5326.2543243945875, 3724.646158614927, 3744.2324323513612, 3749.2876353492748, 3754.6620804127215, 3760.2855457463543, 3803.733738638324, 3809.695224382043, 3815.8361699681864, 3816.809159542012, 3822.6177382261553, 4312.568743125725, 4314.978425107917, 4340.804663790137, 4341.9131617367475, 4349.652027837766, 4783.832565860178, 5029.5724863386295, 5032.245163225545, 5039.521245481674, 5047.470015648378, 1314.953976610814, 1326.4031185966353, 1329.7473480038761, 1330.3015916643647, 1332.8333958255218, 817.7669364231166, 856.7601071699934, 865.1521508921167, 873.3493547307297, 875.2516598308482, 4283.0, 4396.868093957122, 4423.933640995388, 4439.334099884978, 4453.532379100213, 5179.663321884141, 5198.844034678966, 5205.27126202332, 5215.340176510007, 5223.164293713064, 4429.184034545885, 4442.889627426946, 4463.352813841975, 4466.687331886802, 4474.753888536096, 2657.2426858345666, 2757.2831063923522, 2772.6118343431936, 2782.789486573347, 2787.1499192746405, 4927.236278376858, 4941.010688533457, 4944.579710724629, 4952.678075925053, 4956.925646115311, 5103.336566246438, 5342.610921160526, 5345.3852806926925, 5383.730813459311, 5411.83764487066, 4900.240462728283, 4920.680155789933, 4927.43334516528, 4930.753462326869, 4980.800767969241, 5204.245384419162, 5236.711980841091, 5246.213068039672, 5268.420473747961, 5299.364076310867, 4922.261660750916, 5057.519535644108, 5063.885945092074, 5117.385913690381, 5128.384558863946, 4901.166801643658, 4903.509649035068, 4903.5586797188425, 4917.041176970456, 4920.286558449076, 3707.9814600927903, 3726.6087060859304, 3737.6262373762406, 3757.380032294641, 3761.5486141663177, 5070.771815268248, 5160.354955630546, 5238.214267859851, 5239.764210610456, 5241.6068794839775, 4949.925751113664, 4978.651494395358, 5007.924881126859, 5013.949860501349, 5016.648834581544, 4998.950010499994, 5013.2981382607195, 5021.0, 5063.8480845574695, 5082.491750824888, 4846.418429788448, 4965.82619608312, 4994.80020799164, 4998.1003419383405, 5009.123403404463, 4805.207140821725, 4892.437369702529, 5029.7736601852275, 5041.596672266137, 5044.6631642614, 4939.308496810378, 4946.629002824874, 4960.50394960501, 4966.503349665005, 4977.9751101244265], "value": 3815.248854039049, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.9535494128058, 539.1831375466169, 555.8249151517272, 570.5236127833259, 666.3369946902833, 13093.345332733374, 14661.780073298909, 14710.63223419415, 14756.48352307668, 14777.704445911075, 12639.431225594848, 12686.3656817159, 12728.554500592481, 12763.872361276384, 12824.935875320634, 642.5181114164377, 766.0539234045963, 782.4796510320658, 785.2971590426565, 785.5483097219093, 766.1495739728897, 794.4835856693138, 798.6126728536655, 802.5585927739749, 803.3452736020154, 834.2908527751423, 835.4277320035765, 836.9790755231107, 837.3719710217337, 837.4054421360842, 671.3789744486379, 828.1180542721982, 829.3365307753803, 829.5644786487112, 829.6971605364029, 573.4294377094817, 595.743830153034, 598.8712426828253, 599.0714392691311, 599.4904331318388, 13070.019748518804, 13147.671308217276, 13701.657458563515, 13706.93146534271, 13774.65563360914, 12672.68318292041, 12888.677783055498, 13049.739005219937, 13346.799798002941, 13506.054576179671, 11903.523859045628, 12024.33866137362, 12176.634700958986, 12378.44297006637, 12585.874141258562, 3971.821268042916, 4053.716239863211, 4083.9387409188885, 4084.8774536763945, 4085.3667681509414, 69567.30432695709, 70957.29042709559, 71234.0, 71612.6419367905, 73135.99999999946, 10761.78476430467, 10771.246012779187, 10772.838407423895, 10806.243562950674, 11105.83341249882, 19673.409797706092, 19830.107645156004, 19930.30243941455, 19951.0, 19956.30152944669, 6890.414314783299, 7043.7534686285735, 7054.188768291712, 7217.133943926658, 7218.530795498275, 3760.52993375828, 3766.6233376662112, 3813.084859633723, 3828.349180639298, 3845.6346647068485, 2787.1220565522203, 2852.5863749756168, 2864.5273529867754, 2870.2250392393935, 2871.1099559136733, 500.53450291229507, 509.3404041765922, 519.7790938850973, 521.7912834866084, 522.8771238758889, 514.9124648809636, 530.4297879779252, 543.8395673276393, 546.6173678425118, 553.6622660177288, 6826.6272425780035, 7071.611061391673, 8044.798880027929, 8174.550399728072, 8366.707165249187, 573.8938296415176, 574.9080147176431, 589.2251689028999, 605.5458406195354, 610.4353473037422, 3704.7036237100965, 3797.316483033025, 3881.9611803882162, 3903.843846246175, 3926.411038344214, 91495.0, 94385.0, 94893.0, 95493.0, 96489.0, 66533.667331664, 79974.0, 81323.18676813274, 82319.11883084184, 82365.17634823575, 59846.0, 75459.69059238967, 76698.61650691711, 76754.84867726988, 77045.61477192579, 7578.355839753683, 7640.961795190989, 7650.961745191238, 7674.8081297968, 7726.961365193138, 43203.0, 43520.78239608908, 45418.31872521849, 45588.404405845635, 45864.31203531883, 54386.45613543814, 56462.717686411306, 57173.0, 62773.372266276754, 62968.37031629625, 11367.090632749525, 11381.772364552828, 11547.191696581158, 11566.65300041, 11973.880261197624, 12091.0, 12222.20555663879, 12375.443105060114, 13367.398467069199, 13639.795403068763, 10921.672349829518, 11812.645620631394, 12173.087018473472, 12361.443735031766, 12375.938120309342, 10532.89467105319, 10812.35125892449, 10971.835422468828, 11317.434128293717, 11342.376169310766, 11380.829287560859, 11700.648980530597, 11815.645530634094, 12029.278243305429, 12052.457639406422, 10734.570617175226, 10939.28894621847, 11043.779124417628, 11099.833502497631, 11364.602238921598, 10091.89908100939, 10325.63860264887, 10394.22043346737, 10418.114460270659, 10452.477376131012], "value": 6617.1024766747105, "unit": "score"}], "name": "dromaeo_css", "value": 6305.470356608782, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 115.0, 113.0, 115.0, 117.0, 114.0, 115.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 123.0, 197.0, 121.0, 126.0, 144.0, 122.0, 154.0, 122.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 160.0, 162.0, 162.0, 161.0, 162.0, 162.0, 163.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 123.0, 77.0, 76.0, 75.0, 75.0, 101.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 118.0, 86.0, 86.0, 86.0, 121.0, 84.0, 85.0, 131.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 62.0, 64.0, 63.0, 63.0, 62.0, 62.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 80.0, 79.0, 82.0, 81.0, 79.0, 78.0, 79.0, 88.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 150.0, 128.0, 129.0, 127.0, 128.0, 126.0, 127.0, 141.0, 145.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 169.0, 171.0, 167.0, 165.0, 167.0, 167.0, 166.0, 167.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1529.3999999999999, "alertThreshold": 2.0}]} 12:27:34 INFO - Return code: 0 12:27:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:27:34 INFO - # TBPL SUCCESS # 12:27:34 INFO - Running post-action listener: _resource_record_post_action 12:27:34 INFO - [mozharness: 2016-08-04 19:27:34.398681Z] Finished run-tests step (success) 12:27:34 INFO - Running post-run listener: _resource_record_post_run 12:27:34 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548806656; Read time: 12; Write time: 1078668 12:27:34 INFO - TinderboxPrint: CPU usage
13.1% 12:27:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:27:34 INFO - TinderboxPrint: I/O write bytes / time
548,806,656 / 1,078,668 12:27:34 INFO - TinderboxPrint: CPU idle
8,353.3 (86.9%) 12:27:34 INFO - TinderboxPrint: CPU user
1,201.8 (12.5%) 12:27:34 INFO - TinderboxPrint: Swap in / out
0 / 0 12:27:34 INFO - install - Wall time: 17s; CPU: 18.0%; Read bytes: 0; Write bytes: 254447616; Read time: 0; Write time: 783368 12:27:34 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 294359040; Read time: 0; Write time: 295300 12:27:35 INFO - Running post-run listener: _upload_blobber_files 12:27:35 INFO - Blob upload gear active. 12:27:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:27:35 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 12:27:35 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:27:35 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:27:35 INFO - (blobuploader) - INFO - Open directory for files ... 12:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:27:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:40 INFO - (blobuploader) - INFO - Done attempting. 12:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:27:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:41 INFO - (blobuploader) - INFO - Done attempting. 12:27:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:27:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:27:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:42 INFO - (blobuploader) - INFO - Done attempting. 12:27:42 INFO - (blobuploader) - INFO - Iteration through files over. 12:27:42 INFO - Return code: 0 12:27:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:27:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:27:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0faf3e9cc56d2fed10210e33dab83d9765492c9813190692d7ffb3a968ac264412d7a14eddfddba112724bd6dfdcc895f80a1283e3efddd30b5e63f41e0cf5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d7d71bb5ff2b37254d51a7c2da48e165da0c814975bf1e4072541f38a2b79baa8066e73414e2c3ad1b93fa4ef4425b76bf9e7760dc44769a1910cbe8762d5ec6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c555714906e2bb5d177baada05dcdc88d1c7f8925c48b57b7b9319b0db1adf2a7d4e6d55c12f1a4ffe3eaf07b0b65573ee42236893edd10f5ea1590589ddee2"} 12:27:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:27:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:27:42 INFO - Contents: 12:27:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0faf3e9cc56d2fed10210e33dab83d9765492c9813190692d7ffb3a968ac264412d7a14eddfddba112724bd6dfdcc895f80a1283e3efddd30b5e63f41e0cf5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d7d71bb5ff2b37254d51a7c2da48e165da0c814975bf1e4072541f38a2b79baa8066e73414e2c3ad1b93fa4ef4425b76bf9e7760dc44769a1910cbe8762d5ec6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c555714906e2bb5d177baada05dcdc88d1c7f8925c48b57b7b9319b0db1adf2a7d4e6d55c12f1a4ffe3eaf07b0b65573ee42236893edd10f5ea1590589ddee2"} 12:27:42 INFO - Running post-run listener: copy_logs_to_upload_dir 12:27:42 INFO - Copying logs to upload dir... 12:27:42 INFO - mkdir: /builds/slave/test/build/upload/logs 12:27:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.109609 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-04 12:27:42.213884) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 12:27:42.218237) ========= 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=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 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-Non-PGO/sha512/0faf3e9cc56d2fed10210e33dab83d9765492c9813190692d7ffb3a968ac264412d7a14eddfddba112724bd6dfdcc895f80a1283e3efddd30b5e63f41e0cf5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d7d71bb5ff2b37254d51a7c2da48e165da0c814975bf1e4072541f38a2b79baa8066e73414e2c3ad1b93fa4ef4425b76bf9e7760dc44769a1910cbe8762d5ec6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c555714906e2bb5d177baada05dcdc88d1c7f8925c48b57b7b9319b0db1adf2a7d4e6d55c12f1a4ffe3eaf07b0b65573ee42236893edd10f5ea1590589ddee2"} build_url:https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/N88kVI0aTkWcsRpBziZDww/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-Non-PGO/sha512/0faf3e9cc56d2fed10210e33dab83d9765492c9813190692d7ffb3a968ac264412d7a14eddfddba112724bd6dfdcc895f80a1283e3efddd30b5e63f41e0cf5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d7d71bb5ff2b37254d51a7c2da48e165da0c814975bf1e4072541f38a2b79baa8066e73414e2c3ad1b93fa4ef4425b76bf9e7760dc44769a1910cbe8762d5ec6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c555714906e2bb5d177baada05dcdc88d1c7f8925c48b57b7b9319b0db1adf2a7d4e6d55c12f1a4ffe3eaf07b0b65573ee42236893edd10f5ea1590589ddee2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 12:27:42.250170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:27:42.250771) ========= 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=745a4373a2a16df560f216e000000264-1470335627.972098-1609016002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 12:27:42.332614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 12:27:42.332941) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 12:27:42.333329) ========= ========= Total master_lag: 0.18 =========