builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1470745344.3 results: success (0) buildid: 20160809035220 builduid: 696c928ee5644fe0a978d33a344732ab revision: f76b9d417a3678f840f498598d215b41f5c6616a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.301862) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.302482) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.302794) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.317773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.318064) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 05:22:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 191K=0.06s 2016-08-09 05:22:24 (191 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147906 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.482171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.482455) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.533378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:24.533749) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f76b9d417a3678f840f498598d215b41f5c6616a --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 f76b9d417a3678f840f498598d215b41f5c6616a --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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 05:22:25,092 truncating revision to first 12 chars 2016-08-09 05:22:25,092 Setting DEBUG logging. 2016-08-09 05:22:25,092 attempt 1/10 2016-08-09 05:22:25,093 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f76b9d417a36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 05:22:25,396 unpacking tar archive at: fx-team-f76b9d417a36/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:25.201705) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:25.202014) ========= script_repo_revision: f76b9d417a3678f840f498598d215b41f5c6616a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:25.202449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:25.202750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 05:22:25.213953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-09 05:22:25.214265) ========= /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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:22:25 INFO - MultiFileLogger online at 20160809 05:22:25 in /builds/slave/test 05:22:25 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 05:22:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:22:25 INFO - {'append_to_log': False, 05:22:25 INFO - 'base_work_dir': '/builds/slave/test', 05:22:25 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:22:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:22:25 INFO - 'branch': 'Fx-Team-Non-PGO', 05:22:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:22:25 INFO - 'config_files': ('talos/linux_config.py',), 05:22:25 INFO - 'default_actions': ('clobber', 05:22:25 INFO - 'read-buildbot-config', 05:22:25 INFO - 'download-and-extract', 05:22:25 INFO - 'populate-webroot', 05:22:25 INFO - 'create-virtualenv', 05:22:25 INFO - 'install', 05:22:25 INFO - 'run-tests'), 05:22:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:22:25 INFO - 'download_minidump_stackwalk': True, 05:22:25 INFO - 'download_symbols': 'ondemand', 05:22:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:22:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:22:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:22:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:22:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:22:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:22:25 INFO - 'installer_path': 'installer.exe', 05:22:25 INFO - 'log_level': 'info', 05:22:25 INFO - 'log_name': 'talos', 05:22:25 INFO - 'log_to_console': True, 05:22:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:22:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:22:25 INFO - 'opt_config_files': (), 05:22:25 INFO - 'pip_index': False, 05:22:25 INFO - 'sps_profile': False, 05:22:25 INFO - 'sps_profile_interval': 0, 05:22:25 INFO - 'suite': 'dromaeojs', 05:22:25 INFO - 'system_bits': '32', 05:22:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:22:25 INFO - 'title': 'talos-linux64-ix-014', 05:22:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:22:25 INFO - 'use_talos_json': True, 05:22:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:22:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:22:25 INFO - 'work_dir': 'build'} 05:22:25 INFO - [mozharness: 2016-08-09 12:22:25.864625Z] Running clobber step. 05:22:25 INFO - Running pre-action listener: _resource_record_pre_action 05:22:25 INFO - Running main action method: clobber 05:22:25 INFO - rmtree: /builds/slave/test/build 05:22:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:22:26 INFO - Running post-action listener: _resource_record_post_action 05:22:26 INFO - [mozharness: 2016-08-09 12:22:26.327639Z] Finished clobber step (success) 05:22:26 INFO - [mozharness: 2016-08-09 12:22:26.327740Z] Running read-buildbot-config step. 05:22:26 INFO - Running pre-action listener: _resource_record_pre_action 05:22:26 INFO - Running main action method: read_buildbot_config 05:22:26 INFO - Using buildbot properties: 05:22:26 INFO - { 05:22:26 INFO - "project": "", 05:22:26 INFO - "product": "firefox", 05:22:26 INFO - "script_repo_revision": "production", 05:22:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:22:26 INFO - "repository": "", 05:22:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:22:26 INFO - "buildid": "20160809035220", 05:22:26 INFO - "pgo_build": "False", 05:22:26 INFO - "basedir": "/builds/slave/test", 05:22:26 INFO - "buildnumber": 2085, 05:22:26 INFO - "slavename": "talos-linux64-ix-014", 05:22:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:22:26 INFO - "platform": "ubuntu64_hw", 05:22:26 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 05:22:26 INFO - "branch": "fx-team", 05:22:26 INFO - "repo_path": "integration/fx-team", 05:22:26 INFO - "revision": "f76b9d417a3678f840f498598d215b41f5c6616a", 05:22:26 INFO - "stage_platform": "linux64", 05:22:26 INFO - "builduid": "696c928ee5644fe0a978d33a344732ab", 05:22:26 INFO - "slavebuilddir": "test" 05:22:26 INFO - } 05:22:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:22:26 INFO - Running post-action listener: _resource_record_post_action 05:22:26 INFO - [mozharness: 2016-08-09 12:22:26.330307Z] Finished read-buildbot-config step (success) 05:22:26 INFO - [mozharness: 2016-08-09 12:22:26.330396Z] Running download-and-extract step. 05:22:26 INFO - Running pre-action listener: _resource_record_pre_action 05:22:26 INFO - Running main action method: download_and_extract 05:22:26 INFO - mkdir: /builds/slave/test/build/tests 05:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:26 INFO - trying https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:22:26 INFO - Downloading https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:26 INFO - Downloaded 1361 bytes. 05:22:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:22:26 INFO - Using the following test package requirements: 05:22:26 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:22:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:22:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:22:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'jsshell-linux-x86_64.zip'], 05:22:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:22:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:22:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:22:26 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:22:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:22:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:22:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:22:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:26 INFO - trying https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:22:26 INFO - Downloading https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:27 INFO - Downloaded 20257787 bytes. 05:22:27 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'] 05:22:27 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 05:22:28 INFO - Return code: 0 05:22:28 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 05:22:28 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')]} 05:22:28 INFO - trying https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:22:28 INFO - Downloading https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:29 INFO - Downloaded 20257787 bytes. 05:22:29 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'] 05:22:29 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 05:22:30 INFO - Return code: 0 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:30 INFO - trying https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:22:30 INFO - Downloading https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:32 INFO - Downloaded 14413723 bytes. 05:22:32 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'] 05:22:32 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 05:22:32 INFO - Return code: 0 05:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:32 INFO - trying https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:22:32 INFO - Downloading https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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 05:22:36 INFO - Downloaded 56138538 bytes. 05:22:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:22:36 INFO - mkdir: /builds/slave/test/properties 05:22:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:22:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:22:36 INFO - Contents: 05:22:36 INFO - build_url:https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:22:38 INFO - Running post-action listener: _resource_record_post_action 05:22:38 INFO - Running post-action listener: set_extra_try_arguments 05:22:38 INFO - [mozharness: 2016-08-09 12:22:38.000600Z] Finished download-and-extract step (success) 05:22:38 INFO - [mozharness: 2016-08-09 12:22:38.000832Z] Running populate-webroot step. 05:22:38 INFO - Running pre-action listener: _resource_record_pre_action 05:22:38 INFO - Running main action method: populate_webroot 05:22:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:38 INFO - '--tppagecycles', 05:22:38 INFO - '7'], 05:22:38 INFO - 'tests': ['tsvgm']}}, 05:22:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:22:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:22:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:22:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['damp', 'tps']}, 05:22:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'tests': ['damp', 'tps']}, 05:22:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['dromaeo_dom']}, 05:22:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['basic_compositor_video']}, 05:22:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:22:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'other-e10s': {'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['a11yr', 05:22:38 INFO - 'ts_paint', 05:22:38 INFO - 'tpaint', 05:22:38 INFO - 'sessionrestore', 05:22:38 INFO - 'sessionrestore_no_auto_restore', 05:22:38 INFO - 'tabpaint']}, 05:22:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['tsvgx', 05:22:38 INFO - 'tsvgr_opacity', 05:22:38 INFO - 'tart', 05:22:38 INFO - 'tscrollx', 05:22:38 INFO - 'cart']}, 05:22:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:22:38 INFO - 'tsvgr_opacity', 05:22:38 INFO - 'tart', 05:22:38 INFO - 'tscrollx', 05:22:38 INFO - 'cart']}, 05:22:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'talos_options': ['--disable-e10s'], 05:22:38 INFO - 'tests': ['tp5o']}, 05:22:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'tests': ['tp5o']}, 05:22:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'talos_options': ['--disable-e10s', 05:22:38 INFO - '--xperf_path', 05:22:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:38 INFO - 'tests': ['tp5n']}, 05:22:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:38 INFO - 'talos_options': ['--xperf_path', 05:22:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:38 INFO - 'tests': ['tp5n']}}, 05:22:38 INFO - 'talos.zip': {'path': '', 05:22:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:38 INFO - Running post-action listener: _resource_record_post_action 05:22:38 INFO - [mozharness: 2016-08-09 12:22:38.016265Z] Finished populate-webroot step (success) 05:22:38 INFO - [mozharness: 2016-08-09 12:22:38.016353Z] Running create-virtualenv step. 05:22:38 INFO - Running pre-action listener: _resource_record_pre_action 05:22:38 INFO - Running main action method: create_virtualenv 05:22:38 INFO - Creating virtualenv /builds/slave/test/build/venv 05:22:38 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 05:22:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:22:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:38 INFO - Using real prefix '/usr' 05:22:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:40 INFO - Installing pip.................done. 05:22:40 INFO - Return code: 0 05:22:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:22:40 INFO - Using env: {'DISPLAY': ':0', 05:22:40 INFO - 'HOME': '/home/cltbld', 05:22:40 INFO - 'LANG': 'en_US.UTF-8', 05:22:40 INFO - 'LANGUAGE': 'en_US:en', 05:22:40 INFO - 'LOGNAME': 'cltbld', 05:22:40 INFO - 'MAIL': '/var/mail/cltbld', 05:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:40 INFO - 'MOZ_NO_REMOTE': '1', 05:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:40 INFO - 'NO_EM_RESTART': '1', 05:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:40 INFO - 'PWD': '/builds/slave/test', 05:22:40 INFO - 'SHELL': '/bin/bash', 05:22:40 INFO - 'SHLVL': '1', 05:22:40 INFO - 'TERM': 'linux', 05:22:40 INFO - 'TMOUT': '86400', 05:22:40 INFO - 'USER': 'cltbld', 05:22:40 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:40 INFO - '_': '/tools/buildbot/bin/python'} 05:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:40 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 05:22:40 INFO - Cleaning up... 05:22:40 INFO - Return code: 0 05:22:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:22:40 INFO - Using env: {'DISPLAY': ':0', 05:22:40 INFO - 'HOME': '/home/cltbld', 05:22:40 INFO - 'LANG': 'en_US.UTF-8', 05:22:40 INFO - 'LANGUAGE': 'en_US:en', 05:22:40 INFO - 'LOGNAME': 'cltbld', 05:22:40 INFO - 'MAIL': '/var/mail/cltbld', 05:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:40 INFO - 'MOZ_NO_REMOTE': '1', 05:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:40 INFO - 'NO_EM_RESTART': '1', 05:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:40 INFO - 'PWD': '/builds/slave/test', 05:22:40 INFO - 'SHELL': '/bin/bash', 05:22:40 INFO - 'SHLVL': '1', 05:22:40 INFO - 'TERM': 'linux', 05:22:40 INFO - 'TMOUT': '86400', 05:22:40 INFO - 'USER': 'cltbld', 05:22:40 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:40 INFO - '_': '/tools/buildbot/bin/python'} 05:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:41 INFO - Downloading/unpacking psutil>=3.1.1 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:45 INFO - Installing collected packages: psutil 05:22:45 INFO - Running setup.py install for psutil 05:22:45 INFO - building 'psutil._psutil_linux' extension 05:22:45 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 05:22:45 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 05:22:45 INFO - building 'psutil._psutil_posix' extension 05:22:45 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 05:22:45 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 05:22:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:45 INFO - Successfully installed psutil 05:22:45 INFO - Cleaning up... 05:22:45 INFO - Return code: 0 05:22:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:22:45 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')]} 05:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:45 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')]} 05:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:45 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:45 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 05:22:45 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 05:22:45 INFO - Using env: {'DISPLAY': ':0', 05:22:45 INFO - 'HOME': '/home/cltbld', 05:22:45 INFO - 'LANG': 'en_US.UTF-8', 05:22:45 INFO - 'LANGUAGE': 'en_US:en', 05:22:45 INFO - 'LOGNAME': 'cltbld', 05:22:45 INFO - 'MAIL': '/var/mail/cltbld', 05:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:45 INFO - 'MOZ_NO_REMOTE': '1', 05:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:45 INFO - 'NO_EM_RESTART': '1', 05:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:45 INFO - 'PWD': '/builds/slave/test', 05:22:45 INFO - 'SHELL': '/bin/bash', 05:22:45 INFO - 'SHLVL': '1', 05:22:45 INFO - 'TERM': 'linux', 05:22:45 INFO - 'TMOUT': '86400', 05:22:45 INFO - 'USER': 'cltbld', 05:22:45 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:45 INFO - '_': '/tools/buildbot/bin/python'} 05:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:22:48 INFO - Installing collected packages: mozsystemmonitor 05:22:48 INFO - Running setup.py install for mozsystemmonitor 05:22:48 INFO - Successfully installed mozsystemmonitor 05:22:48 INFO - Cleaning up... 05:22:49 INFO - Return code: 0 05:22:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:22:49 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')]} 05:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:49 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')]} 05:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:49 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:49 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 05:22:49 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 05:22:49 INFO - Using env: {'DISPLAY': ':0', 05:22:49 INFO - 'HOME': '/home/cltbld', 05:22:49 INFO - 'LANG': 'en_US.UTF-8', 05:22:49 INFO - 'LANGUAGE': 'en_US:en', 05:22:49 INFO - 'LOGNAME': 'cltbld', 05:22:49 INFO - 'MAIL': '/var/mail/cltbld', 05:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:49 INFO - 'MOZ_NO_REMOTE': '1', 05:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:49 INFO - 'NO_EM_RESTART': '1', 05:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:49 INFO - 'PWD': '/builds/slave/test', 05:22:49 INFO - 'SHELL': '/bin/bash', 05:22:49 INFO - 'SHLVL': '1', 05:22:49 INFO - 'TERM': 'linux', 05:22:49 INFO - 'TMOUT': '86400', 05:22:49 INFO - 'USER': 'cltbld', 05:22:49 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:49 INFO - '_': '/tools/buildbot/bin/python'} 05:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:49 INFO - Downloading/unpacking jsonschema==2.5.1 05:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:22:51 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 05:22:51 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 05:22:51 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 05:22:51 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 05:22:51 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 05:22:51 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 05:22:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:51 INFO - warning: no files found matching '*.txt' 05:22:51 INFO - no previously-included directories found matching 'build' 05:22:51 INFO - no previously-included directories found matching 'dist' 05:22:51 INFO - no previously-included directories found matching '.git*' 05:22:51 INFO - Installing collected packages: jsonschema, functools32 05:22:51 INFO - Running setup.py install for functools32 05:22:51 INFO - warning: no files found matching '*.txt' 05:22:51 INFO - no previously-included directories found matching 'build' 05:22:51 INFO - no previously-included directories found matching 'dist' 05:22:51 INFO - no previously-included directories found matching '.git*' 05:22:51 INFO - Successfully installed jsonschema functools32 05:22:51 INFO - Cleaning up... 05:22:51 INFO - Return code: 0 05:22:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:51 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:51 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 05:22:51 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 05:22:51 INFO - Using env: {'DISPLAY': ':0', 05:22:51 INFO - 'HOME': '/home/cltbld', 05:22:51 INFO - 'LANG': 'en_US.UTF-8', 05:22:51 INFO - 'LANGUAGE': 'en_US:en', 05:22:51 INFO - 'LOGNAME': 'cltbld', 05:22:51 INFO - 'MAIL': '/var/mail/cltbld', 05:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:51 INFO - 'MOZ_NO_REMOTE': '1', 05:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:51 INFO - 'NO_EM_RESTART': '1', 05:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:51 INFO - 'PWD': '/builds/slave/test', 05:22:51 INFO - 'SHELL': '/bin/bash', 05:22:51 INFO - 'SHLVL': '1', 05:22:51 INFO - 'TERM': 'linux', 05:22:51 INFO - 'TMOUT': '86400', 05:22:51 INFO - 'USER': 'cltbld', 05:22:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:51 INFO - '_': '/tools/buildbot/bin/python'} 05:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:22:52 INFO - Cleaning up... 05:22:52 INFO - Return code: 0 05:22:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:22:52 INFO - Using env: {'DISPLAY': ':0', 05:22:52 INFO - 'HOME': '/home/cltbld', 05:22:52 INFO - 'LANG': 'en_US.UTF-8', 05:22:52 INFO - 'LANGUAGE': 'en_US:en', 05:22:52 INFO - 'LOGNAME': 'cltbld', 05:22:52 INFO - 'MAIL': '/var/mail/cltbld', 05:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:52 INFO - 'MOZ_NO_REMOTE': '1', 05:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:52 INFO - 'NO_EM_RESTART': '1', 05:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:52 INFO - 'PWD': '/builds/slave/test', 05:22:52 INFO - 'SHELL': '/bin/bash', 05:22:52 INFO - 'SHLVL': '1', 05:22:52 INFO - 'TERM': 'linux', 05:22:52 INFO - 'TMOUT': '86400', 05:22:52 INFO - 'USER': 'cltbld', 05:22:52 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:52 INFO - '_': '/tools/buildbot/bin/python'} 05:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:22:52 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 05:22:52 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 05:22:52 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 05:22:52 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 05:22:52 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 05:22:52 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 05:22:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 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 05:22:54 INFO - Downloading docopt-0.6.1.tar.gz 05:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:55 INFO - Running setup.py install for blobuploader 05:22:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:55 INFO - Running setup.py install for requests 05:22:55 INFO - Running setup.py install for docopt 05:22:55 INFO - Successfully installed blobuploader requests docopt 05:22:55 INFO - Cleaning up... 05:22:55 INFO - Return code: 0 05:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:55 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')]} 05:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:55 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')]} 05:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:55 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:55 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 05:22:55 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 05:22:55 INFO - Using env: {'DISPLAY': ':0', 05:22:55 INFO - 'HOME': '/home/cltbld', 05:22:55 INFO - 'LANG': 'en_US.UTF-8', 05:22:55 INFO - 'LANGUAGE': 'en_US:en', 05:22:55 INFO - 'LOGNAME': 'cltbld', 05:22:55 INFO - 'MAIL': '/var/mail/cltbld', 05:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:55 INFO - 'MOZ_NO_REMOTE': '1', 05:22:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:55 INFO - 'NO_EM_RESTART': '1', 05:22:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:55 INFO - 'PWD': '/builds/slave/test', 05:22:55 INFO - 'SHELL': '/bin/bash', 05:22:55 INFO - 'SHLVL': '1', 05:22:55 INFO - 'TERM': 'linux', 05:22:55 INFO - 'TMOUT': '86400', 05:22:55 INFO - 'USER': 'cltbld', 05:22:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:55 INFO - '_': '/tools/buildbot/bin/python'} 05:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:55 INFO - Running setup.py (path:/tmp/pip-cMnbua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:55 INFO - Running setup.py (path:/tmp/pip-oal0f5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:55 INFO - Running setup.py (path:/tmp/pip-m8Zi8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:56 INFO - Running setup.py (path:/tmp/pip-5rjpyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:56 INFO - Running setup.py (path:/tmp/pip-cU2gXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:56 INFO - Running setup.py (path:/tmp/pip-vVYFur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:56 INFO - Running setup.py (path:/tmp/pip-aDkXEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:56 INFO - Running setup.py (path:/tmp/pip-ZJIo9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:56 INFO - Running setup.py (path:/tmp/pip-mOqh6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:56 INFO - Running setup.py (path:/tmp/pip-D1jFAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:56 INFO - Running setup.py (path:/tmp/pip-uiG5AI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:56 INFO - Running setup.py (path:/tmp/pip-1YLLom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:56 INFO - Running setup.py (path:/tmp/pip-GVqecq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:56 INFO - Running setup.py (path:/tmp/pip-2T4voA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:56 INFO - Running setup.py (path:/tmp/pip-5LXNFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:56 INFO - Running setup.py (path:/tmp/pip-ab608W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:56 INFO - Running setup.py (path:/tmp/pip-SC0Oml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:57 INFO - Running setup.py install for manifestparser 05:22:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozcrash 05:22:57 INFO - Running setup.py install for mozdebug 05:22:57 INFO - Running setup.py install for mozdevice 05:22:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozfile 05:22:57 INFO - Running setup.py install for mozhttpd 05:22:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozinfo 05:22:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozInstall 05:22:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozleak 05:22:57 INFO - Running setup.py install for mozlog 05:22:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for moznetwork 05:22:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozprocess 05:22:58 INFO - Running setup.py install for mozprofile 05:22:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozrunner 05:22:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozscreenshot 05:22:58 INFO - Running setup.py install for moztest 05:22:58 INFO - Running setup.py install for mozversion 05:22:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:58 INFO - Cleaning up... 05:22:58 INFO - Return code: 0 05:22:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:58 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:22:58 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 05:22:58 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 05:22:58 INFO - Using env: {'DISPLAY': ':0', 05:22:58 INFO - 'HOME': '/home/cltbld', 05:22:58 INFO - 'LANG': 'en_US.UTF-8', 05:22:58 INFO - 'LANGUAGE': 'en_US:en', 05:22:58 INFO - 'LOGNAME': 'cltbld', 05:22:58 INFO - 'MAIL': '/var/mail/cltbld', 05:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:58 INFO - 'MOZ_NO_REMOTE': '1', 05:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:58 INFO - 'NO_EM_RESTART': '1', 05:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:58 INFO - 'PWD': '/builds/slave/test', 05:22:58 INFO - 'SHELL': '/bin/bash', 05:22:58 INFO - 'SHLVL': '1', 05:22:58 INFO - 'TERM': 'linux', 05:22:58 INFO - 'TMOUT': '86400', 05:22:58 INFO - 'USER': 'cltbld', 05:22:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:58 INFO - '_': '/tools/buildbot/bin/python'} 05:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:59 INFO - Running setup.py (path:/tmp/pip-4JUa7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:59 INFO - Running setup.py (path:/tmp/pip-FA_w8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:59 INFO - Running setup.py (path:/tmp/pip-yRlbaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:59 INFO - Running setup.py (path:/tmp/pip-F0EQY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:59 INFO - Running setup.py (path:/tmp/pip-1iKYBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:59 INFO - Running setup.py (path:/tmp/pip-dzXkh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:59 INFO - Running setup.py (path:/tmp/pip-KozAZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:59 INFO - Running setup.py (path:/tmp/pip-ayj7Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:59 INFO - Running setup.py (path:/tmp/pip-H1Eaks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:59 INFO - Running setup.py (path:/tmp/pip-yAe6hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:59 INFO - Running setup.py (path:/tmp/pip-Fgq3dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:59 INFO - Running setup.py (path:/tmp/pip-oyDL8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:59 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)) 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:59 INFO - Running setup.py (path:/tmp/pip-XFUkqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:00 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)) 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:00 INFO - Running setup.py (path:/tmp/pip-nDXvIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:00 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)) 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:00 INFO - Running setup.py (path:/tmp/pip-0h6YJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:00 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)) 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:00 INFO - Running setup.py (path:/tmp/pip-LxawdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:00 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)) 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:00 INFO - Running setup.py (path:/tmp/pip-7sx51O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:00 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)) 05:23:00 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)) 05:23:00 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)) 05:23:00 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)) 05:23:00 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)) 05:23:00 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)) 05:23:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:00 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 05:23:00 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 05:23:00 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 05:23:00 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 05:23:00 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 05:23:00 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 05:23:02 INFO - Downloading blessings-1.6.tar.gz 05:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:02 INFO - Installing collected packages: blessings 05:23:02 INFO - Running setup.py install for blessings 05:23:02 INFO - Successfully installed blessings 05:23:02 INFO - Cleaning up... 05:23:02 INFO - Return code: 0 05:23:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:02 INFO - Reading from file tmpfile_stdout 05:23:02 INFO - Current package versions: 05:23:02 INFO - argparse == 1.2.1 05:23:02 INFO - blessings == 1.6 05:23:02 INFO - blobuploader == 1.2.4 05:23:02 INFO - docopt == 0.6.1 05:23:02 INFO - functools32 == 3.2.3-2 05:23:02 INFO - jsonschema == 2.5.1 05:23:02 INFO - manifestparser == 1.1 05:23:02 INFO - mozInstall == 1.12 05:23:02 INFO - mozcrash == 0.17 05:23:02 INFO - mozdebug == 0.1 05:23:02 INFO - mozdevice == 0.48 05:23:02 INFO - mozfile == 1.2 05:23:02 INFO - mozhttpd == 0.7 05:23:02 INFO - mozinfo == 0.9 05:23:02 INFO - mozleak == 0.1 05:23:02 INFO - mozlog == 3.3 05:23:02 INFO - moznetwork == 0.27 05:23:02 INFO - mozprocess == 0.23 05:23:02 INFO - mozprofile == 0.28 05:23:02 INFO - mozrunner == 6.12 05:23:02 INFO - mozscreenshot == 0.1 05:23:02 INFO - mozsystemmonitor == 0.3 05:23:02 INFO - moztest == 0.7 05:23:02 INFO - mozversion == 1.4 05:23:02 INFO - psutil == 3.1.1 05:23:02 INFO - requests == 1.2.3 05:23:02 INFO - wsgiref == 0.1.2 05:23:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:02 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:23:02 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 05:23:02 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 05:23:02 INFO - Using env: {'DISPLAY': ':0', 05:23:02 INFO - 'HOME': '/home/cltbld', 05:23:02 INFO - 'LANG': 'en_US.UTF-8', 05:23:02 INFO - 'LANGUAGE': 'en_US:en', 05:23:02 INFO - 'LOGNAME': 'cltbld', 05:23:02 INFO - 'MAIL': '/var/mail/cltbld', 05:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:02 INFO - 'MOZ_NO_REMOTE': '1', 05:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:02 INFO - 'NO_EM_RESTART': '1', 05:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:02 INFO - 'PWD': '/builds/slave/test', 05:23:02 INFO - 'SHELL': '/bin/bash', 05:23:02 INFO - 'SHLVL': '1', 05:23:02 INFO - 'TERM': 'linux', 05:23:02 INFO - 'TMOUT': '86400', 05:23:02 INFO - 'USER': 'cltbld', 05:23:02 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:02 INFO - '_': '/tools/buildbot/bin/python'} 05:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 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)) 05:23:02 INFO - Cleaning up... 05:23:02 INFO - Return code: 0 05:23:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:02 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 0xddf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', '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 05:23:02 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 05:23:02 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 05:23:02 INFO - Using env: {'DISPLAY': ':0', 05:23:02 INFO - 'HOME': '/home/cltbld', 05:23:02 INFO - 'LANG': 'en_US.UTF-8', 05:23:02 INFO - 'LANGUAGE': 'en_US:en', 05:23:02 INFO - 'LOGNAME': 'cltbld', 05:23:02 INFO - 'MAIL': '/var/mail/cltbld', 05:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:02 INFO - 'MOZ_NO_REMOTE': '1', 05:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:02 INFO - 'NO_EM_RESTART': '1', 05:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:02 INFO - 'PWD': '/builds/slave/test', 05:23:02 INFO - 'SHELL': '/bin/bash', 05:23:02 INFO - 'SHLVL': '1', 05:23:02 INFO - 'TERM': 'linux', 05:23:02 INFO - 'TMOUT': '86400', 05:23:02 INFO - 'USER': 'cltbld', 05:23:02 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:02 INFO - '_': '/tools/buildbot/bin/python'} 05:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:03 INFO - Cleaning up... 05:23:03 INFO - Return code: 0 05:23:03 INFO - Running post-action listener: _resource_record_post_action 05:23:03 INFO - Running post-action listener: _start_resource_monitoring 05:23:03 INFO - Starting resource monitoring. 05:23:03 INFO - [mozharness: 2016-08-09 12:23:03.135464Z] Finished create-virtualenv step (success) 05:23:03 INFO - [mozharness: 2016-08-09 12:23:03.135944Z] Running install step. 05:23:03 INFO - Running pre-action listener: _resource_record_pre_action 05:23:03 INFO - Running main action method: install 05:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:03 INFO - Reading from file tmpfile_stdout 05:23:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:03 INFO - Reading from file tmpfile_stdout 05:23:03 INFO - Output received: 05:23:03 INFO - Usage: mozinstall [options] installer 05:23:03 INFO - Options: 05:23:03 INFO - -h, --help show this help message and exit 05:23:03 INFO - -d DEST, --destination=DEST 05:23:03 INFO - Directory to install application into. [default: 05:23:03 INFO - "/builds/slave/test"] 05:23:03 INFO - --app=APP Application being installed. [default: firefox] 05:23:03 INFO - mkdir: /builds/slave/test/build/application 05:23:03 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'] 05:23:03 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 05:23:19 INFO - Reading from file tmpfile_stdout 05:23:19 INFO - Output received: 05:23:19 INFO - /builds/slave/test/build/application/firefox/firefox 05:23:19 INFO - Running post-action listener: _resource_record_post_action 05:23:19 INFO - [mozharness: 2016-08-09 12:23:19.643615Z] Finished install step (success) 05:23:19 INFO - [mozharness: 2016-08-09 12:23:19.643862Z] Running run-tests step. 05:23:19 INFO - Running pre-action listener: _resource_record_pre_action 05:23:19 INFO - Running main action method: run_tests 05:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:23:19 INFO - Python 2.7.3 05:23:19 INFO - Return code: 0 05:23:19 INFO - grabbing minidump binary from tooltool 05:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:19 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 0x109e3b0>, '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 0x109f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:23:19 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 05:23:19 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 05:23:19 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 05:23:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:23:19 INFO - Return code: 0 05:23:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:23:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:23:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:23:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:23:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:23:19 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/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:23:19 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/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 05:23:19 INFO - Using env: {'DISPLAY': ':0', 05:23:19 INFO - 'HOME': '/home/cltbld', 05:23:19 INFO - 'LANG': 'en_US.UTF-8', 05:23:19 INFO - 'LANGUAGE': 'en_US:en', 05:23:19 INFO - 'LOGNAME': 'cltbld', 05:23:19 INFO - 'MAIL': '/var/mail/cltbld', 05:23:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:23:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:19 INFO - 'MOZ_NO_REMOTE': '1', 05:23:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:19 INFO - 'NO_EM_RESTART': '1', 05:23:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:19 INFO - 'PWD': '/builds/slave/test', 05:23:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:23:19 INFO - 'SHELL': '/bin/bash', 05:23:19 INFO - 'SHLVL': '1', 05:23:19 INFO - 'TERM': 'linux', 05:23:19 INFO - 'TMOUT': '86400', 05:23:19 INFO - 'USER': 'cltbld', 05:23:19 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863', 05:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:19 INFO - '_': '/tools/buildbot/bin/python'} 05:23:19 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/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:23:19 INFO - mozversion application_buildid: 20160809035220 05:23:19 INFO - mozversion application_changeset: f76b9d417a3678f840f498598d215b41f5c6616a 05:23:19 INFO - mozversion application_display_name: Nightly 05:23:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:23:19 INFO - mozversion application_name: Firefox 05:23:19 INFO - mozversion application_remotingname: firefox 05:23:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:23:19 INFO - mozversion application_vendor: Mozilla 05:23:19 INFO - mozversion application_version: 51.0a1 05:23:19 INFO - mozversion platform_buildid: 20160809035220 05:23:19 INFO - mozversion platform_changeset: f76b9d417a3678f840f498598d215b41f5c6616a 05:23:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:23:19 INFO - mozversion platform_version: 51.0a1 05:23:19 INFO - using testdate: 1470745399 05:23:19 INFO - actual date: 1470745399 05:23:19 INFO - starting webserver on 'localhost:54740' 05:23:19 INFO - SUITE-START | Running 2 tests 05:23:19 INFO - TEST-START | dromaeo_css 05:23:19 INFO - operating with platform_type : linux_ 05:23:19 INFO - Initialising browser for dromaeo_css test... 05:23:19 INFO - TEST-INFO | started process 463 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgMdtCt/profile http://localhost:54740/getInfo.html) 05:23:24 INFO - PROCESS | 463 | __metrics Screen width/height:1600/1200 05:23:24 INFO - PROCESS | 463 | colorDepth:24 05:23:24 INFO - PROCESS | 463 | Browser inner width/height: 1024/697 05:23:24 INFO - PROCESS | 463 | __metrics 05:23:24 INFO - PROCESS | 463 | 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" 05:23:24 INFO - PROCESS | 463 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:23:25 INFO - TEST-INFO | 463: exit 0 05:23:25 INFO - Browser initialized. 05:23:25 INFO - Running cycle 1/1 for dromaeo_css test... 05:23:25 INFO - TEST-INFO | started process 545 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgMdtCt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:37 INFO - PROCESS | 545 | RSS: Main: 157532160 05:23:37 INFO - PROCESS | 545 | 05:24:29 INFO - PROCESS | 545 | 1470745469821 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] 05:26:41 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54740/tests/dromaeo/cssquery-ext.html) 05:26:42 INFO - PROCESS | 545 | RSS: Main: 242606080 05:26:42 INFO - PROCESS | 545 | 05:29:45 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-ext.html (next: http://localhost:54740/tests/dromaeo/cssquery-jquery.html) 05:29:45 INFO - PROCESS | 545 | RSS: Main: 241963008 05:29:45 INFO - PROCESS | 545 | 05:32:47 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54740/tests/dromaeo/cssquery-mootools.html) 05:32:48 INFO - PROCESS | 545 | RSS: Main: 230203392 05:32:48 INFO - PROCESS | 545 | 05:35:50 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54740/tests/dromaeo/cssquery-prototype.html) 05:35:51 INFO - PROCESS | 545 | RSS: Main: 190619648 05:35:51 INFO - PROCESS | 545 | 05:38:54 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54740/tests/dromaeo/cssquery-yui.html) 05:38:54 INFO - PROCESS | 545 | RSS: Main: 189378560 05:38:54 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | Cycle 1(1): loaded http://localhost:54740/tests/dromaeo/cssquery-yui.html (next: http://localhost:54740/tests/dromaeo/cssquery-dojo.html) 05:41:57 INFO - PROCESS | 545 | RSS: Main: 270929920 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | __start_tp_report 05:41:57 INFO - PROCESS | 545 | _x_x_mozilla_page_load 05:41:57 INFO - PROCESS | 545 | _x_x_mozilla_page_load_details 05:41:57 INFO - PROCESS | 545 | |i|pagename|runs| 05:41:57 INFO - PROCESS | 545 | |0;dojo.html;2602.6616539849824;2615.032437997941;2651.907183248586;2670.212287375224;2670.719574444682;4593.08138372326;4671.532846715327;4751.29844682445;4820.951790482094;4820.951790482099;4645.628349732022;4679.298105284209;4706.764661766915;4746.359241502396;4751.952480475203;4297.828086876521;4312.956870431302;4369.060651959828;4423.823047078113;4517.593416592506;4445;4479.193745125876;4505.211587972108;4510.097980403916;4521.525239849818;4218.430511880888;4253.234417804794;4355.6733245006435;4359.84740534083;4455.353973673816;4239.682023848224;4260.190563792869;4288.249556327662;4360.825566977317;4372.737635741865;4235.9364609530885;4296.033392486665;4298.763568003758;4306.784660766949;4334.544872788343;4549.09018196362;4641.187792136363;4699.859004229878;4704.576588107053;4711.481737008926;5019.171836646949;5080.771365288534;5090.872728181788;5099.15863882459;5169.276301317818;5117.488251174853;5134.486551344873;5172.172452407597;5178.818741344073;5187.870303242411;4649.349091127245;4651.86044418668;4776.331313616061;4821.590164835958;4837.6613637045775;5108.361454818148;5134.922976155361;5176.275321454999;5187.118189907725;5188.688678679291;4937.975310123426;4974.950250497521;4981.850544483671;4983.725895075806;4984.003199360142;4887.242477416006;4941.332920055746;4992.975035124801;5061.291419201315;5072.239164125337;4929.556339929351;4973.925391119136;4991.301217829434;5010.323606313174;5069.923951140736;4987.975060124749;4990.525900039491;4991.5258050485145;4996.025774973918;5042.218456139304;4100.405441211067;4321.58944900234;4329.935050974238;4334.956650433518;4383.956160438419;3710.239400922804;3756.586775454698;3788.8484460621817;3818.579956204815;3889.2610404023144;4350.978245108818;4357.215701173755;4364.258076127066;4364.956350436519;4413.691041627056;5094.694318340911;5112.233165025202;5112.565431938327;5117.565006974449;5137.2037334213255;4201.369794530845;4252.936205956913;4320.395144679747;4357.782110894443;4363.52001279859;4611.100835337077;4611.953880461219;4626.583607475343;4660.906781864412;4663;4792.7363994980615;4799.928001079917;4806.206975848946;4806.855794326036;4831.806727730992;4923.384576927884;4999.700017998786;5001.24981252815;5010.348654674869;5083.745812709276;4757.57181853621;4764.714117152982;4769.070031343855;4779.5220477951925;4832.613390928647;4977.701337919591;4979.726115063561;4981.452040275639;4982.626303027215;5022.924656130234;4854.1747902857305;5036.647434679684;5057.684076407831;5088.821891233789;5114.9232761507865;4975.353204083446;4976.402831660224;4981.4769449207315;5009.749512524287;5043.293938848491;4953.975230123826;4965.006998600222;4968.8757781055765;4989.052080104769;5014.924776128288;4733.692309999839;4759.524047595212;4774.188387974123;4908.460069392436;4952.7771250294545;4917.704937703749;4928.605711542997;4932.383452068491;4944.703317800943;4953.950460495349;4821.001674925396;4823.469418364048;4825.9758701207675;4829.05833362505;4873.269009648581 05:41:57 INFO - PROCESS | 545 | |1;ext.html;556.5686592890506;559.8768270980385;562.1343131577371;570.8658465260663;582.1529674323858;28583.28541786454;30862.84568577154;30895.845520772422;31020.99999999997;31143.532847007285;30942.381152376995;38370.23259534815;39640.60359396412;39650.20699586006;42904.356434653426;7737.883931741028;7851.725189618364;7854.410919181056;7863.921360786376;7864.213578642147;7370.74202402916;7445.367143792784;7454.888176677354;7455.440841936849;7457.291557302047;7746.264104910025;7828.295453409192;7882.842343153134;7886.802829929269;7898.802529936769;7805.492642978188;7811.33603643691;7833.138354780969;7853.764387068426;7854.450188486809;7387.704491820321;7440.144383395925;7447.515911465737;7449.515781474187;7453.217412171698;37494.62505374938;37800.81099594512;37839.62160378388;37846;37875;3035.3170536629304;3163.398954198695;3401.3707464119216;3706.796126213057;3800.8669696560764;28823.135305940647;29048;29145.12564623065;29402.70597294021;30654;2938.6914373990635;2974.702529747008;2974.9553756693667;3035.362573859492;3048.5122380419025;257625;257626;258777;258852.705736472;259480;30579;31586.526202106987;50934.74532627313;51264.74367628212;51304.48695513072;32727.18182045468;32910.670893290764;32910.83544582262;32919;32931.3413731724;10628.574857005633;10641;10655;10661.253712240068;10681.572737090584;9922.652707155215;9990.40057596532;9992.150667193224;9994.650187243411;9998.100170984646;7685.769426917202;7768.982849058761;7770.844583108419;7831.177726338652;7837.255460731221;3058.266016156151;3067.3160984138876;3131.530270459449;3137.3568418474156;3139.4662907305355;892.8169725206317;899.8065415935605;916.4180745226696;917.5045475443186;952.9809403812023;26299.474010520065;27419.17742467729;27743.167704968884;27755;27884.30289242785;768.4966347042696;926.3654396738256;931.1619542411748;951.4624237306039;951.8952915179325;13777.74029182807;14401.423943042371;14651.92674036623;14785.260736963324;14837.258137093319;390373;393663;393801;394008;394094;47476.28785568219;47813;48012.279815802794;48082.75958620184;48154.27768583474;1049.6063976008998;1159.4202898550725;1173.5423184957874;1196.6888608961558;1208.112037652325;26019.609705854047;28056.43887122287;28736;28927.276818079717;29146.56280155757;40859.79570102131;40945;41263.1747365057;41369.79315103405;41380.58619413888;244376;245105;245306;245328;246635;22277.331680049625;22827.31518054461;22895.88552057229;23033.193838215582;23064.4233894154;40858.79570602128;40882.9779255521;41056.58943410528;41518.79240603778;42357.57642423536;35053.824730876186;35429.29141417209;35513.46729799107;35978.64021359858;36256.63743362638;34691.826540867136;35105.473417899266;35326.646733533365;35339;35738.463923040654;33661;33752.83123584367;33935.15162120967;34490.13774655654;34889.47665784964;30778.38443231078;32861.50707739338;33075.66924330822;33095;33324.8336308228;23953.401164970317;24310.392240194138;24485.51028979446;24955;25119.372015699755 05:41:57 INFO - PROCESS | 545 | |2;jquery.html;328.8027183689788;348.2304107921493;349.5997083339576;358.7345364430321;378.8730775190311;12837.165584237018;16124.355025798968;16742.916285418585;17395.130243487805;17526.474205773848;14196.07725497842;14381.496647617336;14810.703785924303;14964.401423943056;15044.548663540112;4949.158643030684;5166.586673066165;5173.379194496666;5248.265242865983;5489.945100549004;3456.6888979991795;5491.670499770006;5536.529395001429;5536.833894983137;5538.252335934638;5189.273501709745;5487.5609951203805;5506.091494903336;5565.554755619561;5569.997400467914;5370.838874833761;5510.641808282448;5538.446155384469;5559.416261292547;5561.610687251914;5355.062863998784;5356.223347614574;5356.919646205271;5358.437364076754;5358.839234822962;12500.812487812691;12533;12600;12739.108262421634;12927.288999105045;1876.8873867567854;1923.2307077169105;1954.355062829263;2094.413564202026;2289.439087423575;8855.33584981129;9141.634334626608;9201.21789647874;9736.902630973671;9748.75628109296;1776.564741638307;1789.0518025446534;1805.9458216253533;1809.6109336492586;1822.6810308196145;70010.64994675045;70947.64526177336;71005;71440;72913.6354318225;8880.200781929658;8998.910010899937;9148.72553823373;9520.23838092951;9657.32398732096;12688.111832171659;12739.872601273868;12761.234325940473;12915.418806153746;12920.483180672856;6749.460043196631;6845.110135682329;6991.300869912968;7035;7063.25835787235;6582.670866456658;6641.269460359356;6649.401553860176;6801.693923773442;6811.25076241613;4100.528439229467;4168.582222311053;4188.8324467020975;4252.596003379674;4293.506246781598;1636.746304322832;1640.7949006374204;1771.6114199266462;1779.4038996936172;1809.0662626584822;860.7245681382022;881.1805021330097;901.7249738829589;923.7690577355661;955.3646824861528;16517.669646607243;16628.919120257146;17315.91342043282;17510.474685759447;17583.29666813313;822.3791037766567;829.2660995019452;836.8995720513577;855.6919508976763;861.3496809908604;8723.040465548784;9518.809623807623;10226.28416010887;10317.432541210306;10723.410212438392;429396;433050;446593;451929;452271;79272.20727792649;81627.52968142979;81667.18332816714;82250.58874705707;84721;881.1188811188811;889.1286539191628;903.9457632541937;905.4069584422214;905.5517518828223;15444.150571718663;15610.21948902573;16111.516654500383;16123.274452649894;16317.18414079315;38966;39573.60426395814;39986.80006599949;40262.79868600638;40796.59203407928;48315.75842120907;49297.26054109119;52476.73761631167;52897.735511323735;55041.44958550524;7245.565266083841;7415.593854854321;7548.509346892434;7649.235076492307;8037.517748934849;8579.442336248374;9431.151196392357;9506.667266645634;9743.220542356736;10844.566217351534;8741.868871967054;9120.179183873217;9270.814583708423;9412.623495060396;9472.47901365404;9096.727098187064;9120.49837258957;9347.859782103138;9392.76518087026;9439.575219115019;9036.638534458549;9361.765955851159;9439.292053096182;9571.234301255743;9887.703368898945;7478.738244161428;7684.039495063117;7836.84326313482;7893.644785984529;8018;10088.495575221357;10126.13927816144;10276.640317588848;10385.376877387382;10672.092872105957 05:41:57 INFO - PROCESS | 545 | |3;mootools.html;1017.1151098544268;1017.3234798858759;1125.3023125662085;1214.9210301330413;1238.9752204955907;6211.782587609429;7263.164736055355;7468.55188688679;7543.057117860268;7543.245675432454;7662.578558179298;7721.459497835153;7722.498037627551;7729.9227007729905;7734.458587898849;2122.2147805312015;2149.6453085240914;2154.2244791874914;2155.105631163073;2155.2887547109417;2095.31902131807;2131.158192513964;2156.428546435196;2159.168720042787;2161.6649419339947;2100.674395468697;2116.8306535477127;2126.5746850629857;2129.904154313052;2136.7863213678584;2076.273304343483;2078.605065037638;2081.313166655008;2084.0205103601284;2085.7914208579023;2030.3198428526462;2056.321413933398;2059.1145807302855;2059.9588008239753;2078.189506092625;7585.810354741176;7713.845723085563;7713.884291735629;7771.72798952034;7833.138354780969;4107.096438783463;4234.25900467417;4276.48682158143;4283.121959998193;4286.635635970946;2543.9364015899564;2681.0616284300536;2688.1801050679537;2726.05950946923;2738.315421144714;872.6596627315353;891.3465521457667;893.0266010049013;1631.5513233860665;1667.4914151183884;10406.531706073245;10440.582376704922;10458.477076146239;10464.319819211727;10498.947505262502;3733.2160246348026;3797.2215695782297;3941.1132495188353;3946.644801967837;3960.663343615767;8015.398845086642;8046.275835174863;8058.033036035596;8060.758177254692;8062.677492900336;4218.029853133797;4234.322508398642;4234.534201237861;4238.576142385737;4240.448741663564;3320.335932813447;3768.510093687803;3897.7466464680274;3898.181381909774;3920.5491368492417;3285.2772390074147;3305.289362787012;3407.3185362927516;3420.521127042216;3426.5031570422148;977.3256453047375;981.3964411886639;987.76293689514;993.6124911284605;995.7709726762885;628.089270557684;647.9384458476437;655.0894256982798;657.57914934442;665.1120753793643;4844.660873738804;4867.367242258554;5040;5044.646874718735;5192.143296356059;380.84956442205174;384.49246993967694;387.3859932007799;399.5584878708985;411.3664955967776;3206.887758928473;3373.4602463605706;3419.8461069251944;3424.74314426419;3504.299140171976;9144.405613635225;9468.526573671288;9585.281103917094;9652.372595781251;9654.903450965541;9571.712848614552;9583.760405989906;9586.089321514444;9587.472689002032;9593.28050396223;7075.5400898942435;7238.3485486305435;7266.45501587383;7270.382017528464;7282.089738782653;4810;4830.855074347634;4859.805607775791;4879.731614761222;4894.510548945077;8634.481931084156;8741.519216443157;8769.605367758339;8775.605097770487;8812.91187088121;8932.86600701003;8936.195742382957;9017.323700722603;9051.32115091384;9080.091990800867;5444.455554444524;6073.787417440369;6247.750089996349;6248.781292654735;6255.124282600348;6351.523635727246;6401.231852177769;6412.711427985659;6851.3491218334175;7155.570665760071;6420.06908998202;6425.22897252314;6435.099086127852;6515.869682606227;6578.177727784027;6292.622442653455;6328.588641738273;6345.206849143857;6365.427111559982;6377.362263773585;5727.570432217517;5825.155352473783;5825.300963884361;5828.358880523054;5830.4461076197695;4272.935905961351;4310.396544483712;4313.827446902089;4325.351197320427;4503.12189123131;4442.533963792005;4523.909521809611;4552.7495987720995;4609.3777340060005;4629.722216667011 05:41:57 INFO - PROCESS | 545 | |4;prototype.html;279.5485291254624;280.74592493793114;285.9170840456268;297.83172507533243;298.2811424467032;4845.830395936138;4853.199222128349;4858.587020103287;4871.9025619487675;4877.219644856824;4786.856394308167;4807.326974223612;4807.663463557552;4808.447028591713;4818.59041981432;3589.62308957559;3677.5841301099003;3728.142527218741;3783.451399547064;3797.411401232813;3795.2599243147597;3808.2002779416357;3814.5231846019246;3821.586013175129;3823.655870971612;3755.248950209955;3757.534561521009;3763.115667818074;3764.661180493755;3765.8305376258136;3560.8041557714187;3634.8364323605238;3752.587215406303;3760.8871733848027;3776.3957766757326;3313.3373325334787;3323.6344002159744;3325.551050608161;3332.683395077464;3340.16495876031;4676.976615116937;4690.123950419826;4692.131955588227;4693.78877950493;4713.434387873443;4181.372794080882;4299.204647140258;4300.569943005706;4303.978480107579;4304.268274393361;4990.30135780991;5016.2224855147515;5020.623453240985;5022.986208275006;5027.371578552681;3634.745567810254;3641.526601541809;3641.7632853864416;3646.5988741238443;3650.4341827016856;5138.61460390476;5255.894882102337;5269.156934890438;5293.311869456985;5296.013994751968;3712.1833196696684;3731.4029755239576;3736.5889752127246;3744.868929587506;3746.3256613809776;3782.319182547113;3785.337565925952;3788.734788564774;3791.2227993261317;3793.3740932745786;4259.488861336659;4298.3552467130185;4300.677449191323;4311.2239796836875;4311.8490852820005;4813.855584332476;5009.348784657971;5031.496850315012;5038;5058.342415486037;1295.2228662802431;1299.1750238598559;1303.2831942431624;1304.478208716521;1316.3813007886279;797.2426195114664;819.6393586821779;827.4662842466576;831.6340810043562;831.7338451695399;4266.552012038754;4313.870583882489;4459.9331010034875;4467.5532446755715;4484.843030493982;5222.686638801608;5225.973870130625;5228.229038609543;5230.267762513251;5233.331001189421;4423.5576442355505;4437.201303765353;4437.445319335083;4437.8224871004795;4445.199864024442;2632.631431599578;2728.358835673615;2741.2872653110335;2755.641766570373;2759.503289407886;4896.853094407174;4899.550224887556;4901.58336541398;4904.117258893433;4904.901901962012;5101.795928162832;5309.5752339812725;5332.760025798849;5335.33308336458;5338.786448542092;4854.514548545188;4889.242167464049;4903.77932993009;4905.754712264444;4923.753812309442;5144.328093369744;5163.845084647466;5184.637075404686;5194.013137503862;5203.453637368022;4940.7776650051555;5052.8484145475695;5085.931954289641;5101.642884998015;5114.667546609458;4823.252395878644;4834.661573689948;4842.893341396504;4878.63410244226;4892.68197567157;3735.58908520068;3742.1580144467275;3747.8500859965297;3752.6622603965775;3755.098776293615;4950.772152531304;5174.404943431403;5190.392677089387;5192.350956130484;5208.8437346879655;4942.392053800674;4981.252812078217;4993.675411098267;5032.773525191448;5039.496050395078;5000.574951129195;5000.7746552783665;5044.697318160922;5047.545720885138;5062.670926389773;4795.36957434474;4977.228529577921;4991.276264941491;5014.222795466709;5018.096742586369;4931.802727890844;4983.576396006235;5129.204973229155;5136.203888397305;5161.716105614228;4968.552830245295;4994.175960966545;4999.500049994971;5001.4998500149695;5018.598512119095 05:41:57 INFO - PROCESS | 545 | |5;yui.html;572.2846441947565;584.208397621223;605.8073950282014;614.649649699671;686.8008277599496;12355.258684478926;13279.535216267432;13686.863131368684;13708.383122759462;13938.58184254474;13075.215487070782;13344.799828002564;13352.53266135683;13361.064725469198;13391.866081339183;628.4658040665444;750.7860259825951;769.7190525458195;770.6686124966262;771.3212373111654;739.1204466684628;761.2045412543878;761.6839011810092;775.9030121234846;777.2460713108276;813.9593020348989;816.6161903905155;817.207308910356;817.284875733733;817.5748610722421;648.044134901022;801.0667572278347;810.3760104719393;811.2131232704268;811.7118422959883;562.504995603866;568.6389142894251;569.0269638917456;584.0946532874028;584.187978709594;13034.543790967366;13041.217526948414;13046.152000119962;13065.216087034809;13067.607971760863;12227.510899564008;12572.182808117443;12751.489940402373;12933.676658083528;13264.938804895683;11622.825657615143;11654.76690466186;11784.175107742461;11966.401679915969;12035.157538972275;4017.1563971566006;4053.27041132596;4134.545200028025;4171.624553790173;4225.72532785371;70058.64970675165;70099.2990070103;71625;72475;72912.27087729108;10077.59689612422;10112.191024718151;10119.443430611387;10166.339187952759;10281.845772313422;17005;17049.573760656083;17057.48827535176;17065.232064557124;17074.317027318768;6935.3411425914455;6942.930570694229;6975.511714179958;7130;7222.3860971817985;3761.285355782393;3789.4694742736037;3800.3919372900205;3808.8666896658483;3854.1328201155075;2699.2442116207494;2771.1686494052105;2788.63747712796;2793.497170509328;2796.5106106431294;444.957729015743;454.4410375238475;455.4011474910482;463.168612340849;466.5660935330176;451.42668810610996;451.6770509086019;456.14700510046424;472.24912389301176;494.2487419122913;7320.560766354036;7390.556566606021;7510.399168066541;7521.811954701177;7855;403.0287008309959;420.739141732128;443.0540795401776;466.68318057963836;468.33496435062295;3513.0865974846724;3533.5229743984746;3574.1422058705725;3610.945835812465;3749.1002159481527;59113.70443147757;59143;59235.703821480616;107623.38564921533;108572.4571377138;92234.53882730678;93150;95763;97372;97743.51128244313;80873.19126808774;80981.19018809854;81915;82636.17363826526;83319.58340208381;6801;7150.284971502808;7163.6060016699585;7343.963280183565;7366.558006519626;48196.7590162047;49099.50900491092;49602;50684.239736403244;51055;63500.68249658722;67690;71789.6410517944;73643.6317818429;73890.63054684692;9065.27477801761;9227.077292270718;9264.907350926405;9433.24534037289;9515.33392662507;9697.418154910727;9753.853692194481;9766;9888.901110989085;10024.799504009734;9148.359614826899;9327.300452466228;9393.906060939576;9406.670766523139;9762.511874406393;8976.147266009719;9292.95353523228;9325.020872808529;9568.712938611852;9714.611415543583;8540.572971351283;8572.699955501528;8886.911130888608;8896.866547001671;9155.450672959643;7934.563599001879;8023.1110225630855;8070.152633973348;8070.5964701763505;8122.83754324922;7676.846463070596;7686.730964416219;7898.763037108896;7922.405819563441;8027.558484283187 05:41:57 INFO - PROCESS | 545 | __end_tp_report 05:41:57 INFO - PROCESS | 545 | __start_cc_report 05:41:57 INFO - PROCESS | 545 | _x_x_mozilla_cycle_collect,912 05:41:57 INFO - PROCESS | 545 | __end_cc_report 05:41:57 INFO - PROCESS | 545 | __startTimestamp1470746517978__endTimestamp 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | ------- Summary: start ------- 05:41:57 INFO - PROCESS | 545 | Number of tests: 6 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#0] dojo.html Cycles:165 Average:4677.26 Median:4820.95 stddev:488.23 (10.1%) stddev-sans-first:461.81 05:41:57 INFO - PROCESS | 545 | Values: 2602.7 2615.0 2651.9 2670.2 2670.7 4593.1 4671.5 4751.3 4821.0 4821.0 4645.6 4679.3 4706.8 4746.4 4752.0 4297.8 4313.0 4369.1 4423.8 4517.6 4445.0 4479.2 4505.2 4510.1 4521.5 4218.4 4253.2 4355.7 4359.8 4455.4 4239.7 4260.2 4288.2 4360.8 4372.7 4235.9 4296.0 4298.8 4306.8 4334.5 4549.1 4641.2 4699.9 4704.6 4711.5 5019.2 5080.8 5090.9 5099.2 5169.3 5117.5 5134.5 5172.2 5178.8 5187.9 4649.3 4651.9 4776.3 4821.6 4837.7 5108.4 5134.9 5176.3 5187.1 5188.7 4938.0 4975.0 4981.9 4983.7 4984.0 4887.2 4941.3 4993.0 5061.3 5072.2 4929.6 4973.9 4991.3 5010.3 5069.9 4988.0 4990.5 4991.5 4996.0 5042.2 4100.4 4321.6 4329.9 4335.0 4384.0 3710.2 3756.6 3788.8 3818.6 3889.3 4351.0 4357.2 4364.3 4365.0 4413.7 5094.7 5112.2 5112.6 5117.6 5137.2 4201.4 4252.9 4320.4 4357.8 4363.5 4611.1 4612.0 4626.6 4660.9 4663.0 4792.7 4799.9 4806.2 4806.9 4831.8 4923.4 4999.7 5001.2 5010.3 5083.7 4757.6 4764.7 4769.1 4779.5 4832.6 4977.7 4979.7 4981.5 4982.6 5022.9 4854.2 5036.6 5057.7 5088.8 5114.9 4975.4 4976.4 4981.5 5009.7 5043.3 4954.0 4965.0 4968.9 4989.1 5014.9 4733.7 4759.5 4774.2 4908.5 4952.8 4917.7 4928.6 4932.4 4944.7 4954.0 4821.0 4823.5 4826.0 4829.1 4873.3 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#1] ext.html Cycles:180 Average:43733.83 Median:35384.15 stddev:80920.17 (228.7%) stddev-sans-first:81082.24 05:41:57 INFO - PROCESS | 545 | Values: 556.6 559.9 562.1 570.9 582.2 28583.3 30862.8 30895.8 31021.0 31143.5 30942.4 38370.2 39640.6 39650.2 42904.4 7737.9 7851.7 7854.4 7863.9 7864.2 7370.7 7445.4 7454.9 7455.4 7457.3 7746.3 7828.3 7882.8 7886.8 7898.8 7805.5 7811.3 7833.1 7853.8 7854.5 7387.7 7440.1 7447.5 7449.5 7453.2 37494.6 37800.8 37839.6 37846.0 37875.0 3035.3 3163.4 3401.4 3706.8 3800.9 28823.1 29048.0 29145.1 29402.7 30654.0 2938.7 2974.7 2975.0 3035.4 3048.5 257625.0 257626.0 258777.0 258852.7 259480.0 30579.0 31586.5 50934.7 51264.7 51304.5 32727.2 32910.7 32910.8 32919.0 32931.3 10628.6 10641.0 10655.0 10661.3 10681.6 9922.7 9990.4 9992.2 9994.7 9998.1 7685.8 7769.0 7770.8 7831.2 7837.3 3058.3 3067.3 3131.5 3137.4 3139.5 892.8 899.8 916.4 917.5 953.0 26299.5 27419.2 27743.2 27755.0 27884.3 768.5 926.4 931.2 951.5 951.9 13777.7 14401.4 14651.9 14785.3 14837.3 390373.0 393663.0 393801.0 394008.0 394094.0 47476.3 47813.0 48012.3 48082.8 48154.3 1049.6 1159.4 1173.5 1196.7 1208.1 26019.6 28056.4 28736.0 28927.3 29146.6 40859.8 40945.0 41263.2 41369.8 41380.6 244376.0 245105.0 245306.0 245328.0 246635.0 22277.3 22827.3 22895.9 23033.2 23064.4 40858.8 40883.0 41056.6 41518.8 42357.6 35053.8 35429.3 35513.5 35978.6 36256.6 34691.8 35105.5 35326.6 35339.0 35738.5 33661.0 33752.8 33935.2 34490.1 34889.5 30778.4 32861.5 33075.7 33095.0 33324.8 23953.4 24310.4 24485.5 24955.0 25119.4 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#2] jquery.html Cycles:180 Average:25594.20 Median:5488.75 stddev:73036.03 (1330.6%) stddev-sans-first:73216.28 05:41:57 INFO - PROCESS | 545 | Values: 328.8 348.2 349.6 358.7 378.9 12837.2 16124.4 16742.9 17395.1 17526.5 14196.1 14381.5 14810.7 14964.4 15044.5 4949.2 5166.6 5173.4 5248.3 5489.9 3456.7 5491.7 5536.5 5536.8 5538.3 5189.3 5487.6 5506.1 5565.6 5570.0 5370.8 5510.6 5538.4 5559.4 5561.6 5355.1 5356.2 5356.9 5358.4 5358.8 12500.8 12533.0 12600.0 12739.1 12927.3 1876.9 1923.2 1954.4 2094.4 2289.4 8855.3 9141.6 9201.2 9736.9 9748.8 1776.6 1789.1 1805.9 1809.6 1822.7 70010.6 70947.6 71005.0 71440.0 72913.6 8880.2 8998.9 9148.7 9520.2 9657.3 12688.1 12739.9 12761.2 12915.4 12920.5 6749.5 6845.1 6991.3 7035.0 7063.3 6582.7 6641.3 6649.4 6801.7 6811.3 4100.5 4168.6 4188.8 4252.6 4293.5 1636.7 1640.8 1771.6 1779.4 1809.1 860.7 881.2 901.7 923.8 955.4 16517.7 16628.9 17315.9 17510.5 17583.3 822.4 829.3 836.9 855.7 861.3 8723.0 9518.8 10226.3 10317.4 10723.4 429396.0 433050.0 446593.0 451929.0 452271.0 79272.2 81627.5 81667.2 82250.6 84721.0 881.1 889.1 903.9 905.4 905.6 15444.2 15610.2 16111.5 16123.3 16317.2 38966.0 39573.6 39986.8 40262.8 40796.6 48315.8 49297.3 52476.7 52897.7 55041.4 7245.6 7415.6 7548.5 7649.2 8037.5 8579.4 9431.2 9506.7 9743.2 10844.6 8741.9 9120.2 9270.8 9412.6 9472.5 9096.7 9120.5 9347.9 9392.8 9439.6 9036.6 9361.8 9439.3 9571.2 9887.7 7478.7 7684.0 7836.8 7893.6 8018.0 10088.5 10126.1 10276.6 10385.4 10672.1 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#3] mootools.html Cycles:180 Average:4880.60 Median:4863.59 stddev:2829.03 (58.2%) stddev-sans-first:2822.06 05:41:57 INFO - PROCESS | 545 | Values: 1017.1 1017.3 1125.3 1214.9 1239.0 6211.8 7263.2 7468.6 7543.1 7543.2 7662.6 7721.5 7722.5 7729.9 7734.5 2122.2 2149.6 2154.2 2155.1 2155.3 2095.3 2131.2 2156.4 2159.2 2161.7 2100.7 2116.8 2126.6 2129.9 2136.8 2076.3 2078.6 2081.3 2084.0 2085.8 2030.3 2056.3 2059.1 2060.0 2078.2 7585.8 7713.8 7713.9 7771.7 7833.1 4107.1 4234.3 4276.5 4283.1 4286.6 2543.9 2681.1 2688.2 2726.1 2738.3 872.7 891.3 893.0 1631.6 1667.5 10406.5 10440.6 10458.5 10464.3 10498.9 3733.2 3797.2 3941.1 3946.6 3960.7 8015.4 8046.3 8058.0 8060.8 8062.7 4218.0 4234.3 4234.5 4238.6 4240.4 3320.3 3768.5 3897.7 3898.2 3920.5 3285.3 3305.3 3407.3 3420.5 3426.5 977.3 981.4 987.8 993.6 995.8 628.1 647.9 655.1 657.6 665.1 4844.7 4867.4 5040.0 5044.6 5192.1 380.8 384.5 387.4 399.6 411.4 3206.9 3373.5 3419.8 3424.7 3504.3 9144.4 9468.5 9585.3 9652.4 9654.9 9571.7 9583.8 9586.1 9587.5 9593.3 7075.5 7238.3 7266.5 7270.4 7282.1 4810.0 4830.9 4859.8 4879.7 4894.5 8634.5 8741.5 8769.6 8775.6 8812.9 8932.9 8936.2 9017.3 9051.3 9080.1 5444.5 6073.8 6247.8 6248.8 6255.1 6351.5 6401.2 6412.7 6851.3 7155.6 6420.1 6425.2 6435.1 6515.9 6578.2 6292.6 6328.6 6345.2 6365.4 6377.4 5727.6 5825.2 5825.3 5828.4 5830.4 4272.9 4310.4 4313.8 4325.4 4503.1 4442.5 4523.9 4552.7 4609.4 4629.7 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#4] prototype.html Cycles:180 Average:4194.04 Median:4816.22 stddev:1215.82 (25.2%) stddev-sans-first:1183.19 05:41:57 INFO - PROCESS | 545 | Values: 279.5 280.7 285.9 297.8 298.3 4845.8 4853.2 4858.6 4871.9 4877.2 4786.9 4807.3 4807.7 4808.4 4818.6 3589.6 3677.6 3728.1 3783.5 3797.4 3795.3 3808.2 3814.5 3821.6 3823.7 3755.2 3757.5 3763.1 3764.7 3765.8 3560.8 3634.8 3752.6 3760.9 3776.4 3313.3 3323.6 3325.6 3332.7 3340.2 4677.0 4690.1 4692.1 4693.8 4713.4 4181.4 4299.2 4300.6 4304.0 4304.3 4990.3 5016.2 5020.6 5023.0 5027.4 3634.7 3641.5 3641.8 3646.6 3650.4 5138.6 5255.9 5269.2 5293.3 5296.0 3712.2 3731.4 3736.6 3744.9 3746.3 3782.3 3785.3 3788.7 3791.2 3793.4 4259.5 4298.4 4300.7 4311.2 4311.8 4813.9 5009.3 5031.5 5038.0 5058.3 1295.2 1299.2 1303.3 1304.5 1316.4 797.2 819.6 827.5 831.6 831.7 4266.6 4313.9 4459.9 4467.6 4484.8 5222.7 5226.0 5228.2 5230.3 5233.3 4423.6 4437.2 4437.4 4437.8 4445.2 2632.6 2728.4 2741.3 2755.6 2759.5 4896.9 4899.6 4901.6 4904.1 4904.9 5101.8 5309.6 5332.8 5335.3 5338.8 4854.5 4889.2 4903.8 4905.8 4923.8 5144.3 5163.8 5184.6 5194.0 5203.5 4940.8 5052.8 5085.9 5101.6 5114.7 4823.3 4834.7 4842.9 4878.6 4892.7 3735.6 3742.2 3747.9 3752.7 3755.1 4950.8 5174.4 5190.4 5192.4 5208.8 4942.4 4981.3 4993.7 5032.8 5039.5 5000.6 5000.8 5044.7 5047.5 5062.7 4795.4 4977.2 4991.3 5014.2 5018.1 4931.8 4983.6 5129.2 5136.2 5161.7 4968.6 4994.2 4999.5 5001.5 5018.6 05:41:57 INFO - PROCESS | 545 | 05:41:57 INFO - PROCESS | 545 | [#5] yui.html Cycles:180 Average:17860.56 Median:32064.57 stddev:26737.18 (83.4%) stddev-sans-first:26780.67 05:41:57 INFO - PROCESS | 545 | Values: 572.3 584.2 605.8 614.6 686.8 12355.3 13279.5 13686.9 13708.4 13938.6 13075.2 13344.8 13352.5 13361.1 13391.9 628.5 750.8 769.7 770.7 771.3 739.1 761.2 761.7 775.9 777.2 814.0 816.6 817.2 817.3 817.6 648.0 801.1 810.4 811.2 811.7 562.5 568.6 569.0 584.1 584.2 13034.5 13041.2 13046.2 13065.2 13067.6 12227.5 12572.2 12751.5 12933.7 13264.9 11622.8 11654.8 11784.2 11966.4 12035.2 4017.2 4053.3 4134.5 4171.6 4225.7 70058.6 70099.3 71625.0 72475.0 72912.3 10077.6 10112.2 10119.4 10166.3 10281.8 17005.0 17049.6 17057.5 17065.2 17074.3 6935.3 6942.9 6975.5 7130.0 7222.4 3761.3 3789.5 3800.4 3808.9 3854.1 2699.2 2771.2 2788.6 2793.5 2796.5 445.0 454.4 455.4 463.2 466.6 451.4 451.7 456.1 472.2 494.2 7320.6 7390.6 7510.4 7521.8 7855.0 403.0 420.7 443.1 466.7 468.3 3513.1 3533.5 3574.1 3610.9 3749.1 59113.7 59143.0 59235.7 107623.4 108572.5 92234.5 93150.0 95763.0 97372.0 97743.5 80873.2 80981.2 81915.0 82636.2 83319.6 6801.0 7150.3 7163.6 7344.0 7366.6 48196.8 49099.5 49602.0 50684.2 51055.0 63500.7 67690.0 71789.6 73643.6 73890.6 9065.3 9227.1 9264.9 9433.2 9515.3 9697.4 9753.9 9766.0 9888.9 10024.8 9148.4 9327.3 9393.9 9406.7 9762.5 8976.1 9293.0 9325.0 9568.7 9714.6 8540.6 8572.7 8886.9 8896.9 9155.5 7934.6 8023.1 8070.2 8070.6 8122.8 7676.8 7686.7 7898.8 7922.4 8027.6 05:41:57 INFO - PROCESS | 545 | -------- Summary: end -------- 05:41:57 INFO - PROCESS | 545 | 05:41:58 INFO - TEST-INFO | 545: exit 0 05:41:58 INFO - TEST-OK | dromaeo_css | took 1118862ms 05:41:58 INFO - TEST-START | kraken 05:41:58 INFO - operating with platform_type : linux_ 05:41:58 INFO - Initialising browser for kraken test... 05:41:58 INFO - TEST-INFO | started process 739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcghVOK/profile http://localhost:54740/getInfo.html) 05:42:02 INFO - PROCESS | 739 | __metrics Screen width/height:1600/1200 05:42:02 INFO - PROCESS | 739 | colorDepth:24 05:42:02 INFO - PROCESS | 739 | Browser inner width/height: 1024/697 05:42:02 INFO - PROCESS | 739 | __metrics 05:42:03 INFO - PROCESS | 739 | 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" 05:42:03 INFO - PROCESS | 739 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:04 INFO - TEST-INFO | 739: exit 0 05:42:04 INFO - Browser initialized. 05:42:04 INFO - Running cycle 1/1 for kraken test... 05:42:04 INFO - TEST-INFO | started process 828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcghVOK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:16 INFO - PROCESS | 828 | RSS: Main: 158273536 05:42:16 INFO - PROCESS | 828 | 05:42:19 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54740/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:20 INFO - PROCESS | 828 | RSS: Main: 214757376 05:42:20 INFO - PROCESS | 828 | 05:42:22 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54740/tests/kraken/driver.html?testName=audio-dft) 05:42:23 INFO - PROCESS | 828 | RSS: Main: 192569344 05:42:23 INFO - PROCESS | 828 | 05:42:26 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54740/tests/kraken/driver.html?testName=audio-fft) 05:42:27 INFO - PROCESS | 828 | RSS: Main: 270999552 05:42:27 INFO - PROCESS | 828 | 05:42:29 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54740/tests/kraken/driver.html?testName=audio-oscillator) 05:42:30 INFO - PROCESS | 828 | RSS: Main: 180985856 05:42:30 INFO - PROCESS | 828 | 05:42:32 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54740/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:33 INFO - PROCESS | 828 | RSS: Main: 186216448 05:42:33 INFO - PROCESS | 828 | 05:42:36 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54740/tests/kraken/driver.html?testName=imaging-darkroom) 05:42:36 INFO - PROCESS | 828 | RSS: Main: 221655040 05:42:36 INFO - PROCESS | 828 | 05:42:40 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54740/tests/kraken/driver.html?testName=imaging-desaturate) 05:42:40 INFO - PROCESS | 828 | RSS: Main: 215793664 05:42:40 INFO - PROCESS | 828 | 05:42:43 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54740/tests/kraken/driver.html?testName=json-parse-financial) 05:42:44 INFO - PROCESS | 828 | RSS: Main: 219037696 05:42:44 INFO - PROCESS | 828 | 05:42:46 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54740/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:42:46 INFO - PROCESS | 828 | RSS: Main: 184520704 05:42:46 INFO - PROCESS | 828 | 05:42:48 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:49 INFO - PROCESS | 828 | RSS: Main: 191664128 05:42:49 INFO - PROCESS | 828 | 05:42:51 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:52 INFO - PROCESS | 828 | RSS: Main: 194355200 05:42:52 INFO - PROCESS | 828 | 05:42:54 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:55 INFO - PROCESS | 828 | RSS: Main: 188825600 05:42:55 INFO - PROCESS | 828 | 05:42:58 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:58 INFO - PROCESS | 828 | RSS: Main: 192757760 05:42:58 INFO - PROCESS | 828 | 05:43:00 INFO - PROCESS | 828 | Cycle 1(1): loaded http://localhost:54740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54740/tests/kraken/driver.html?testName=ai-astar) 05:43:00 INFO - PROCESS | 828 | RSS: Main: 243212288 05:43:00 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | __start_tp_report 05:43:01 INFO - PROCESS | 828 | _x_x_mozilla_page_load 05:43:01 INFO - PROCESS | 828 | _x_x_mozilla_page_load_details 05:43:01 INFO - PROCESS | 828 | |i|pagename|runs| 05:43:01 INFO - PROCESS | 828 | |0;ai-astar;105;116;112;115;114;114;114;113;115;113 05:43:01 INFO - PROCESS | 828 | |1;audio-beat-detection;148;120;120;175;121;123;149;120;160;120 05:43:01 INFO - PROCESS | 828 | |2;audio-dft;161;162;163;161;160;162;160;162;160;161 05:43:01 INFO - PROCESS | 828 | |3;audio-fft;75;76;75;105;76;77;87;76;102;75 05:43:01 INFO - PROCESS | 828 | |4;audio-oscillator;85;85;123;86;85;87;122;84;85;125 05:43:01 INFO - PROCESS | 828 | |5;imaging-gaussian-blur;117;117;117;116;118;118;118;117;117;116 05:43:01 INFO - PROCESS | 828 | |6;imaging-darkroom;146;147;145;148;146;146;146;148;146;146 05:43:01 INFO - PROCESS | 828 | |7;imaging-desaturate;94;95;95;95;94;95;94;95;95;95 05:43:01 INFO - PROCESS | 828 | |8;json-parse-financial;65;61;61;62;61;62;61;61;62;62 05:43:01 INFO - PROCESS | 828 | |9;json-stringify-tinderbox;65;64;64;65;65;64;65;65;66;65 05:43:01 INFO - PROCESS | 828 | |10;stanford-crypto-aes;88;81;79;85;79;80;80;89;84;79 05:43:01 INFO - PROCESS | 828 | |11;stanford-crypto-ccm;130;138;138;139;128;137;128;128;150;139 05:43:01 INFO - PROCESS | 828 | |12;stanford-crypto-pbkdf2;166;171;165;166;163;165;165;164;164;165 05:43:01 INFO - PROCESS | 828 | |13;stanford-crypto-sha256-iterative;68;67;67;69;66;65;67;66;66;65 05:43:01 INFO - PROCESS | 828 | __end_tp_report 05:43:01 INFO - PROCESS | 828 | __start_cc_report 05:43:01 INFO - PROCESS | 828 | _x_x_mozilla_cycle_collect,1973 05:43:01 INFO - PROCESS | 828 | __end_cc_report 05:43:01 INFO - PROCESS | 828 | __startTimestamp1470746581000__endTimestamp 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | ------- Summary: start ------- 05:43:01 INFO - PROCESS | 828 | Number of tests: 14 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.07 (2.7%) stddev-sans-first:1.22 05:43:01 INFO - PROCESS | 828 | Values: 105.0 116.0 112.0 115.0 114.0 114.0 114.0 113.0 115.0 113.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:135.50 stddev:20.62 (15.2%) stddev-sans-first:21.38 05:43:01 INFO - PROCESS | 828 | Values: 148.0 120.0 120.0 175.0 121.0 123.0 149.0 120.0 160.0 120.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.09 05:43:01 INFO - PROCESS | 828 | Values: 161.0 162.0 163.0 161.0 160.0 162.0 160.0 162.0 160.0 161.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:11.70 (15.4%) stddev-sans-first:12.10 05:43:01 INFO - PROCESS | 828 | Values: 75.0 76.0 75.0 105.0 76.0 77.0 87.0 76.0 102.0 75.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:85.00 stddev:18.41 (21.7%) stddev-sans-first:19.03 05:43:01 INFO - PROCESS | 828 | Values: 85.0 85.0 123.0 86.0 85.0 87.0 122.0 84.0 85.0 125.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 05:43:01 INFO - PROCESS | 828 | Values: 117.0 117.0 117.0 116.0 118.0 118.0 118.0 117.0 117.0 116.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 05:43:01 INFO - PROCESS | 828 | Values: 146.0 147.0 145.0 148.0 146.0 146.0 146.0 148.0 146.0 146.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:43:01 INFO - PROCESS | 828 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:0.53 05:43:01 INFO - PROCESS | 828 | Values: 65.0 61.0 61.0 62.0 61.0 62.0 61.0 61.0 62.0 62.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 05:43:01 INFO - PROCESS | 828 | Values: 65.0 64.0 64.0 65.0 65.0 64.0 65.0 65.0 66.0 65.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:3.84 (4.6%) stddev-sans-first:3.49 05:43:01 INFO - PROCESS | 828 | Values: 88.0 81.0 79.0 85.0 79.0 80.0 80.0 89.0 84.0 79.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:138.00 stddev:7.06 (5.1%) stddev-sans-first:7.20 05:43:01 INFO - PROCESS | 828 | Values: 130.0 138.0 138.0 139.0 128.0 137.0 128.0 128.0 150.0 139.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.00 stddev:2.17 (1.3%) stddev-sans-first:2.29 05:43:01 INFO - PROCESS | 828 | Values: 166.0 171.0 165.0 166.0 163.0 165.0 165.0 164.0 164.0 165.0 05:43:01 INFO - PROCESS | 828 | 05:43:01 INFO - PROCESS | 828 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.24 05:43:01 INFO - PROCESS | 828 | Values: 68.0 67.0 67.0 69.0 66.0 65.0 67.0 66.0 66.0 65.0 05:43:01 INFO - PROCESS | 828 | -------- Summary: end -------- 05:43:01 INFO - PROCESS | 828 | 05:43:02 INFO - TEST-INFO | 828: exit 0 05:43:02 INFO - TEST-OK | kraken | took 63373ms 05:43:02 INFO - SUITE-END | took 1182s 05:43:02 INFO - Completed test suite (00:19:42) 05:43:02 INFO - javascript benchmark 05:43:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.6616539849824, 2615.032437997941, 2651.907183248586, 2670.212287375224, 2670.719574444682, 4593.08138372326, 4671.532846715327, 4751.29844682445, 4820.951790482094, 4820.951790482099, 4645.628349732022, 4679.298105284209, 4706.764661766915, 4746.359241502396, 4751.952480475203, 4297.828086876521, 4312.956870431302, 4369.060651959828, 4423.823047078113, 4517.593416592506, 4445.0, 4479.193745125876, 4505.211587972108, 4510.097980403916, 4521.525239849818, 4218.430511880888, 4253.234417804794, 4355.6733245006435, 4359.84740534083, 4455.353973673816, 4239.682023848224, 4260.190563792869, 4288.249556327662, 4360.825566977317, 4372.737635741865, 4235.9364609530885, 4296.033392486665, 4298.763568003758, 4306.784660766949, 4334.544872788343, 4549.09018196362, 4641.187792136363, 4699.859004229878, 4704.576588107053, 4711.481737008926, 5019.171836646949, 5080.771365288534, 5090.872728181788, 5099.15863882459, 5169.276301317818, 5117.488251174853, 5134.486551344873, 5172.172452407597, 5178.818741344073, 5187.870303242411, 4649.349091127245, 4651.86044418668, 4776.331313616061, 4821.590164835958, 4837.6613637045775, 5108.361454818148, 5134.922976155361, 5176.275321454999, 5187.118189907725, 5188.688678679291, 4937.975310123426, 4974.950250497521, 4981.850544483671, 4983.725895075806, 4984.003199360142, 4887.242477416006, 4941.332920055746, 4992.975035124801, 5061.291419201315, 5072.239164125337, 4929.556339929351, 4973.925391119136, 4991.301217829434, 5010.323606313174, 5069.923951140736, 4987.975060124749, 4990.525900039491, 4991.5258050485145, 4996.025774973918, 5042.218456139304, 4100.405441211067, 4321.58944900234, 4329.935050974238, 4334.956650433518, 4383.956160438419, 3710.239400922804, 3756.586775454698, 3788.8484460621817, 3818.579956204815, 3889.2610404023144, 4350.978245108818, 4357.215701173755, 4364.258076127066, 4364.956350436519, 4413.691041627056, 5094.694318340911, 5112.233165025202, 5112.565431938327, 5117.565006974449, 5137.2037334213255, 4201.369794530845, 4252.936205956913, 4320.395144679747, 4357.782110894443, 4363.52001279859, 4611.100835337077, 4611.953880461219, 4626.583607475343, 4660.906781864412, 4663.0, 4792.7363994980615, 4799.928001079917, 4806.206975848946, 4806.855794326036, 4831.806727730992, 4923.384576927884, 4999.700017998786, 5001.24981252815, 5010.348654674869, 5083.745812709276, 4757.57181853621, 4764.714117152982, 4769.070031343855, 4779.5220477951925, 4832.613390928647, 4977.701337919591, 4979.726115063561, 4981.452040275639, 4982.626303027215, 5022.924656130234, 4854.1747902857305, 5036.647434679684, 5057.684076407831, 5088.821891233789, 5114.9232761507865, 4975.353204083446, 4976.402831660224, 4981.4769449207315, 5009.749512524287, 5043.293938848491, 4953.975230123826, 4965.006998600222, 4968.8757781055765, 4989.052080104769, 5014.924776128288, 4733.692309999839, 4759.524047595212, 4774.188387974123, 4908.460069392436, 4952.7771250294545, 4917.704937703749, 4928.605711542997, 4932.383452068491, 4944.703317800943, 4953.950460495349, 4821.001674925396, 4823.469418364048, 4825.9758701207675, 4829.05833362505, 4873.269009648581], "value": 4646.2545567948755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.5686592890506, 559.8768270980385, 562.1343131577371, 570.8658465260663, 582.1529674323858, 28583.28541786454, 30862.84568577154, 30895.845520772422, 31020.99999999997, 31143.532847007285, 30942.381152376995, 38370.23259534815, 39640.60359396412, 39650.20699586006, 42904.356434653426, 7737.883931741028, 7851.725189618364, 7854.410919181056, 7863.921360786376, 7864.213578642147, 7370.74202402916, 7445.367143792784, 7454.888176677354, 7455.440841936849, 7457.291557302047, 7746.264104910025, 7828.295453409192, 7882.842343153134, 7886.802829929269, 7898.802529936769, 7805.492642978188, 7811.33603643691, 7833.138354780969, 7853.764387068426, 7854.450188486809, 7387.704491820321, 7440.144383395925, 7447.515911465737, 7449.515781474187, 7453.217412171698, 37494.62505374938, 37800.81099594512, 37839.62160378388, 37846.0, 37875.0, 3035.3170536629304, 3163.398954198695, 3401.3707464119216, 3706.796126213057, 3800.8669696560764, 28823.135305940647, 29048.0, 29145.12564623065, 29402.70597294021, 30654.0, 2938.6914373990635, 2974.702529747008, 2974.9553756693667, 3035.362573859492, 3048.5122380419025, 257625.0, 257626.0, 258777.0, 258852.705736472, 259480.0, 30579.0, 31586.526202106987, 50934.74532627313, 51264.74367628212, 51304.48695513072, 32727.18182045468, 32910.670893290764, 32910.83544582262, 32919.0, 32931.3413731724, 10628.574857005633, 10641.0, 10655.0, 10661.253712240068, 10681.572737090584, 9922.652707155215, 9990.40057596532, 9992.150667193224, 9994.650187243411, 9998.100170984646, 7685.769426917202, 7768.982849058761, 7770.844583108419, 7831.177726338652, 7837.255460731221, 3058.266016156151, 3067.3160984138876, 3131.530270459449, 3137.3568418474156, 3139.4662907305355, 892.8169725206317, 899.8065415935605, 916.4180745226696, 917.5045475443186, 952.9809403812023, 26299.474010520065, 27419.17742467729, 27743.167704968884, 27755.0, 27884.30289242785, 768.4966347042696, 926.3654396738256, 931.1619542411748, 951.4624237306039, 951.8952915179325, 13777.74029182807, 14401.423943042371, 14651.92674036623, 14785.260736963324, 14837.258137093319, 390373.0, 393663.0, 393801.0, 394008.0, 394094.0, 47476.28785568219, 47813.0, 48012.279815802794, 48082.75958620184, 48154.27768583474, 1049.6063976008998, 1159.4202898550725, 1173.5423184957874, 1196.6888608961558, 1208.112037652325, 26019.609705854047, 28056.43887122287, 28736.0, 28927.276818079717, 29146.56280155757, 40859.79570102131, 40945.0, 41263.1747365057, 41369.79315103405, 41380.58619413888, 244376.0, 245105.0, 245306.0, 245328.0, 246635.0, 22277.331680049625, 22827.31518054461, 22895.88552057229, 23033.193838215582, 23064.4233894154, 40858.79570602128, 40882.9779255521, 41056.58943410528, 41518.79240603778, 42357.57642423536, 35053.824730876186, 35429.29141417209, 35513.46729799107, 35978.64021359858, 36256.63743362638, 34691.826540867136, 35105.473417899266, 35326.646733533365, 35339.0, 35738.463923040654, 33661.0, 33752.83123584367, 33935.15162120967, 34490.13774655654, 34889.47665784964, 30778.38443231078, 32861.50707739338, 33075.66924330822, 33095.0, 33324.8336308228, 23953.401164970317, 24310.392240194138, 24485.51028979446, 24955.0, 25119.372015699755], "value": 15747.984304326224, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.8027183689788, 348.2304107921493, 349.5997083339576, 358.7345364430321, 378.8730775190311, 12837.165584237018, 16124.355025798968, 16742.916285418585, 17395.130243487805, 17526.474205773848, 14196.07725497842, 14381.496647617336, 14810.703785924303, 14964.401423943056, 15044.548663540112, 4949.158643030684, 5166.586673066165, 5173.379194496666, 5248.265242865983, 5489.945100549004, 3456.6888979991795, 5491.670499770006, 5536.529395001429, 5536.833894983137, 5538.252335934638, 5189.273501709745, 5487.5609951203805, 5506.091494903336, 5565.554755619561, 5569.997400467914, 5370.838874833761, 5510.641808282448, 5538.446155384469, 5559.416261292547, 5561.610687251914, 5355.062863998784, 5356.223347614574, 5356.919646205271, 5358.437364076754, 5358.839234822962, 12500.812487812691, 12533.0, 12600.0, 12739.108262421634, 12927.288999105045, 1876.8873867567854, 1923.2307077169105, 1954.355062829263, 2094.413564202026, 2289.439087423575, 8855.33584981129, 9141.634334626608, 9201.21789647874, 9736.902630973671, 9748.75628109296, 1776.564741638307, 1789.0518025446534, 1805.9458216253533, 1809.6109336492586, 1822.6810308196145, 70010.64994675045, 70947.64526177336, 71005.0, 71440.0, 72913.6354318225, 8880.200781929658, 8998.910010899937, 9148.72553823373, 9520.23838092951, 9657.32398732096, 12688.111832171659, 12739.872601273868, 12761.234325940473, 12915.418806153746, 12920.483180672856, 6749.460043196631, 6845.110135682329, 6991.300869912968, 7035.0, 7063.25835787235, 6582.670866456658, 6641.269460359356, 6649.401553860176, 6801.693923773442, 6811.25076241613, 4100.528439229467, 4168.582222311053, 4188.8324467020975, 4252.596003379674, 4293.506246781598, 1636.746304322832, 1640.7949006374204, 1771.6114199266462, 1779.4038996936172, 1809.0662626584822, 860.7245681382022, 881.1805021330097, 901.7249738829589, 923.7690577355661, 955.3646824861528, 16517.669646607243, 16628.919120257146, 17315.91342043282, 17510.474685759447, 17583.29666813313, 822.3791037766567, 829.2660995019452, 836.8995720513577, 855.6919508976763, 861.3496809908604, 8723.040465548784, 9518.809623807623, 10226.28416010887, 10317.432541210306, 10723.410212438392, 429396.0, 433050.0, 446593.0, 451929.0, 452271.0, 79272.20727792649, 81627.52968142979, 81667.18332816714, 82250.58874705707, 84721.0, 881.1188811188811, 889.1286539191628, 903.9457632541937, 905.4069584422214, 905.5517518828223, 15444.150571718663, 15610.21948902573, 16111.516654500383, 16123.274452649894, 16317.18414079315, 38966.0, 39573.60426395814, 39986.80006599949, 40262.79868600638, 40796.59203407928, 48315.75842120907, 49297.26054109119, 52476.73761631167, 52897.735511323735, 55041.44958550524, 7245.565266083841, 7415.593854854321, 7548.509346892434, 7649.235076492307, 8037.517748934849, 8579.442336248374, 9431.151196392357, 9506.667266645634, 9743.220542356736, 10844.566217351534, 8741.868871967054, 9120.179183873217, 9270.814583708423, 9412.623495060396, 9472.47901365404, 9096.727098187064, 9120.49837258957, 9347.859782103138, 9392.76518087026, 9439.575219115019, 9036.638534458549, 9361.765955851159, 9439.292053096182, 9571.234301255743, 9887.703368898945, 7478.738244161428, 7684.039495063117, 7836.84326313482, 7893.644785984529, 8018.0, 10088.495575221357, 10126.13927816144, 10276.640317588848, 10385.376877387382, 10672.092872105957], "value": 7973.204587128349, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1017.1151098544268, 1017.3234798858759, 1125.3023125662085, 1214.9210301330413, 1238.9752204955907, 6211.782587609429, 7263.164736055355, 7468.55188688679, 7543.057117860268, 7543.245675432454, 7662.578558179298, 7721.459497835153, 7722.498037627551, 7729.9227007729905, 7734.458587898849, 2122.2147805312015, 2149.6453085240914, 2154.2244791874914, 2155.105631163073, 2155.2887547109417, 2095.31902131807, 2131.158192513964, 2156.428546435196, 2159.168720042787, 2161.6649419339947, 2100.674395468697, 2116.8306535477127, 2126.5746850629857, 2129.904154313052, 2136.7863213678584, 2076.273304343483, 2078.605065037638, 2081.313166655008, 2084.0205103601284, 2085.7914208579023, 2030.3198428526462, 2056.321413933398, 2059.1145807302855, 2059.9588008239753, 2078.189506092625, 7585.810354741176, 7713.845723085563, 7713.884291735629, 7771.72798952034, 7833.138354780969, 4107.096438783463, 4234.25900467417, 4276.48682158143, 4283.121959998193, 4286.635635970946, 2543.9364015899564, 2681.0616284300536, 2688.1801050679537, 2726.05950946923, 2738.315421144714, 872.6596627315353, 891.3465521457667, 893.0266010049013, 1631.5513233860665, 1667.4914151183884, 10406.531706073245, 10440.582376704922, 10458.477076146239, 10464.319819211727, 10498.947505262502, 3733.2160246348026, 3797.2215695782297, 3941.1132495188353, 3946.644801967837, 3960.663343615767, 8015.398845086642, 8046.275835174863, 8058.033036035596, 8060.758177254692, 8062.677492900336, 4218.029853133797, 4234.322508398642, 4234.534201237861, 4238.576142385737, 4240.448741663564, 3320.335932813447, 3768.510093687803, 3897.7466464680274, 3898.181381909774, 3920.5491368492417, 3285.2772390074147, 3305.289362787012, 3407.3185362927516, 3420.521127042216, 3426.5031570422148, 977.3256453047375, 981.3964411886639, 987.76293689514, 993.6124911284605, 995.7709726762885, 628.089270557684, 647.9384458476437, 655.0894256982798, 657.57914934442, 665.1120753793643, 4844.660873738804, 4867.367242258554, 5040.0, 5044.646874718735, 5192.143296356059, 380.84956442205174, 384.49246993967694, 387.3859932007799, 399.5584878708985, 411.3664955967776, 3206.887758928473, 3373.4602463605706, 3419.8461069251944, 3424.74314426419, 3504.299140171976, 9144.405613635225, 9468.526573671288, 9585.281103917094, 9652.372595781251, 9654.903450965541, 9571.712848614552, 9583.760405989906, 9586.089321514444, 9587.472689002032, 9593.28050396223, 7075.5400898942435, 7238.3485486305435, 7266.45501587383, 7270.382017528464, 7282.089738782653, 4810.0, 4830.855074347634, 4859.805607775791, 4879.731614761222, 4894.510548945077, 8634.481931084156, 8741.519216443157, 8769.605367758339, 8775.605097770487, 8812.91187088121, 8932.86600701003, 8936.195742382957, 9017.323700722603, 9051.32115091384, 9080.091990800867, 5444.455554444524, 6073.787417440369, 6247.750089996349, 6248.781292654735, 6255.124282600348, 6351.523635727246, 6401.231852177769, 6412.711427985659, 6851.3491218334175, 7155.570665760071, 6420.06908998202, 6425.22897252314, 6435.099086127852, 6515.869682606227, 6578.177727784027, 6292.622442653455, 6328.588641738273, 6345.206849143857, 6365.427111559982, 6377.362263773585, 5727.570432217517, 5825.155352473783, 5825.300963884361, 5828.358880523054, 5830.4461076197695, 4272.935905961351, 4310.396544483712, 4313.827446902089, 4325.351197320427, 4503.12189123131, 4442.533963792005, 4523.909521809611, 4552.7495987720995, 4609.3777340060005, 4629.722216667011], "value": 3828.1683223842506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.5485291254624, 280.74592493793114, 285.9170840456268, 297.83172507533243, 298.2811424467032, 4845.830395936138, 4853.199222128349, 4858.587020103287, 4871.9025619487675, 4877.219644856824, 4786.856394308167, 4807.326974223612, 4807.663463557552, 4808.447028591713, 4818.59041981432, 3589.62308957559, 3677.5841301099003, 3728.142527218741, 3783.451399547064, 3797.411401232813, 3795.2599243147597, 3808.2002779416357, 3814.5231846019246, 3821.586013175129, 3823.655870971612, 3755.248950209955, 3757.534561521009, 3763.115667818074, 3764.661180493755, 3765.8305376258136, 3560.8041557714187, 3634.8364323605238, 3752.587215406303, 3760.8871733848027, 3776.3957766757326, 3313.3373325334787, 3323.6344002159744, 3325.551050608161, 3332.683395077464, 3340.16495876031, 4676.976615116937, 4690.123950419826, 4692.131955588227, 4693.78877950493, 4713.434387873443, 4181.372794080882, 4299.204647140258, 4300.569943005706, 4303.978480107579, 4304.268274393361, 4990.30135780991, 5016.2224855147515, 5020.623453240985, 5022.986208275006, 5027.371578552681, 3634.745567810254, 3641.526601541809, 3641.7632853864416, 3646.5988741238443, 3650.4341827016856, 5138.61460390476, 5255.894882102337, 5269.156934890438, 5293.311869456985, 5296.013994751968, 3712.1833196696684, 3731.4029755239576, 3736.5889752127246, 3744.868929587506, 3746.3256613809776, 3782.319182547113, 3785.337565925952, 3788.734788564774, 3791.2227993261317, 3793.3740932745786, 4259.488861336659, 4298.3552467130185, 4300.677449191323, 4311.2239796836875, 4311.8490852820005, 4813.855584332476, 5009.348784657971, 5031.496850315012, 5038.0, 5058.342415486037, 1295.2228662802431, 1299.1750238598559, 1303.2831942431624, 1304.478208716521, 1316.3813007886279, 797.2426195114664, 819.6393586821779, 827.4662842466576, 831.6340810043562, 831.7338451695399, 4266.552012038754, 4313.870583882489, 4459.9331010034875, 4467.5532446755715, 4484.843030493982, 5222.686638801608, 5225.973870130625, 5228.229038609543, 5230.267762513251, 5233.331001189421, 4423.5576442355505, 4437.201303765353, 4437.445319335083, 4437.8224871004795, 4445.199864024442, 2632.631431599578, 2728.358835673615, 2741.2872653110335, 2755.641766570373, 2759.503289407886, 4896.853094407174, 4899.550224887556, 4901.58336541398, 4904.117258893433, 4904.901901962012, 5101.795928162832, 5309.5752339812725, 5332.760025798849, 5335.33308336458, 5338.786448542092, 4854.514548545188, 4889.242167464049, 4903.77932993009, 4905.754712264444, 4923.753812309442, 5144.328093369744, 5163.845084647466, 5184.637075404686, 5194.013137503862, 5203.453637368022, 4940.7776650051555, 5052.8484145475695, 5085.931954289641, 5101.642884998015, 5114.667546609458, 4823.252395878644, 4834.661573689948, 4842.893341396504, 4878.63410244226, 4892.68197567157, 3735.58908520068, 3742.1580144467275, 3747.8500859965297, 3752.6622603965775, 3755.098776293615, 4950.772152531304, 5174.404943431403, 5190.392677089387, 5192.350956130484, 5208.8437346879655, 4942.392053800674, 4981.252812078217, 4993.675411098267, 5032.773525191448, 5039.496050395078, 5000.574951129195, 5000.7746552783665, 5044.697318160922, 5047.545720885138, 5062.670926389773, 4795.36957434474, 4977.228529577921, 4991.276264941491, 5014.222795466709, 5018.096742586369, 4931.802727890844, 4983.576396006235, 5129.204973229155, 5136.203888397305, 5161.716105614228, 4968.552830245295, 4994.175960966545, 4999.500049994971, 5001.4998500149695, 5018.598512119095], "value": 3801.5281311659364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.2846441947565, 584.208397621223, 605.8073950282014, 614.649649699671, 686.8008277599496, 12355.258684478926, 13279.535216267432, 13686.863131368684, 13708.383122759462, 13938.58184254474, 13075.215487070782, 13344.799828002564, 13352.53266135683, 13361.064725469198, 13391.866081339183, 628.4658040665444, 750.7860259825951, 769.7190525458195, 770.6686124966262, 771.3212373111654, 739.1204466684628, 761.2045412543878, 761.6839011810092, 775.9030121234846, 777.2460713108276, 813.9593020348989, 816.6161903905155, 817.207308910356, 817.284875733733, 817.5748610722421, 648.044134901022, 801.0667572278347, 810.3760104719393, 811.2131232704268, 811.7118422959883, 562.504995603866, 568.6389142894251, 569.0269638917456, 584.0946532874028, 584.187978709594, 13034.543790967366, 13041.217526948414, 13046.152000119962, 13065.216087034809, 13067.607971760863, 12227.510899564008, 12572.182808117443, 12751.489940402373, 12933.676658083528, 13264.938804895683, 11622.825657615143, 11654.76690466186, 11784.175107742461, 11966.401679915969, 12035.157538972275, 4017.1563971566006, 4053.27041132596, 4134.545200028025, 4171.624553790173, 4225.72532785371, 70058.64970675165, 70099.2990070103, 71625.0, 72475.0, 72912.27087729108, 10077.59689612422, 10112.191024718151, 10119.443430611387, 10166.339187952759, 10281.845772313422, 17005.0, 17049.573760656083, 17057.48827535176, 17065.232064557124, 17074.317027318768, 6935.3411425914455, 6942.930570694229, 6975.511714179958, 7130.0, 7222.3860971817985, 3761.285355782393, 3789.4694742736037, 3800.3919372900205, 3808.8666896658483, 3854.1328201155075, 2699.2442116207494, 2771.1686494052105, 2788.63747712796, 2793.497170509328, 2796.5106106431294, 444.957729015743, 454.4410375238475, 455.4011474910482, 463.168612340849, 466.5660935330176, 451.42668810610996, 451.6770509086019, 456.14700510046424, 472.24912389301176, 494.2487419122913, 7320.560766354036, 7390.556566606021, 7510.399168066541, 7521.811954701177, 7855.0, 403.0287008309959, 420.739141732128, 443.0540795401776, 466.68318057963836, 468.33496435062295, 3513.0865974846724, 3533.5229743984746, 3574.1422058705725, 3610.945835812465, 3749.1002159481527, 59113.70443147757, 59143.0, 59235.703821480616, 107623.38564921533, 108572.4571377138, 92234.53882730678, 93150.0, 95763.0, 97372.0, 97743.51128244313, 80873.19126808774, 80981.19018809854, 81915.0, 82636.17363826526, 83319.58340208381, 6801.0, 7150.284971502808, 7163.6060016699585, 7343.963280183565, 7366.558006519626, 48196.7590162047, 49099.50900491092, 49602.0, 50684.239736403244, 51055.0, 63500.68249658722, 67690.0, 71789.6410517944, 73643.6317818429, 73890.63054684692, 9065.27477801761, 9227.077292270718, 9264.907350926405, 9433.24534037289, 9515.33392662507, 9697.418154910727, 9753.853692194481, 9766.0, 9888.901110989085, 10024.799504009734, 9148.359614826899, 9327.300452466228, 9393.906060939576, 9406.670766523139, 9762.511874406393, 8976.147266009719, 9292.95353523228, 9325.020872808529, 9568.712938611852, 9714.611415543583, 8540.572971351283, 8572.699955501528, 8886.911130888608, 8896.866547001671, 9155.450672959643, 7934.563599001879, 8023.1110225630855, 8070.152633973348, 8070.5964701763505, 8122.83754324922, 7676.846463070596, 7686.730964416219, 7898.763037108896, 7922.405819563441, 8027.558484283187], "value": 6176.45146007009, "unit": "score"}], "name": "dromaeo_css", "value": 6118.093699292086, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 112.0, 115.0, 114.0, 114.0, 114.0, 113.0, 115.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 120.0, 175.0, 121.0, 123.0, 149.0, 120.0, 160.0, 120.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 160.0, 162.0, 160.0, 162.0, 160.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 105.0, 76.0, 77.0, 87.0, 76.0, 102.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 123.0, 86.0, 85.0, 87.0, 122.0, 84.0, 85.0, 125.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 118.0, 118.0, 118.0, 117.0, 117.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 148.0, 146.0, 146.0, 146.0, 148.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 79.0, 85.0, 79.0, 80.0, 80.0, 89.0, 84.0, 79.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 138.0, 138.0, 139.0, 128.0, 137.0, 128.0, 128.0, 150.0, 139.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 165.0, 166.0, 163.0, 165.0, 165.0, 164.0, 164.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 69.0, 66.0, 65.0, 67.0, 66.0, 66.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1523.7, "alertThreshold": 2.0}]} 05:43:02 INFO - Return code: 0 05:43:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:02 INFO - # TBPL SUCCESS # 05:43:02 INFO - Running post-action listener: _resource_record_post_action 05:43:02 INFO - [mozharness: 2016-08-09 12:43:02.787249Z] Finished run-tests step (success) 05:43:02 INFO - Running post-run listener: _resource_record_post_run 05:43:02 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 507346944; Read time: 0; Write time: 427424 05:43:02 INFO - TinderboxPrint: CPU usage
13.0% 05:43:02 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:43:02 INFO - TinderboxPrint: I/O write bytes / time
507,346,944 / 427,424 05:43:02 INFO - TinderboxPrint: CPU idle
8,328.0 (87.0%) 05:43:02 INFO - TinderboxPrint: CPU user
1,199.3 (12.5%) 05:43:02 INFO - TinderboxPrint: Swap in / out
0 / 0 05:43:02 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 221876224; Read time: 0; Write time: 212768 05:43:02 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 285470720; Read time: 0; Write time: 214656 05:43:03 INFO - Running post-run listener: _upload_blobber_files 05:43:03 INFO - Blob upload gear active. 05:43:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:03 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 05:43:03 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'] 05:43:03 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 05:43:03 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:43:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:43:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:04 INFO - (blobuploader) - INFO - Done attempting. 05:43:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:09 INFO - (blobuploader) - INFO - Done attempting. 05:43:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:43:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:43:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:09 INFO - (blobuploader) - INFO - Done attempting. 05:43:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:09 INFO - Return code: 0 05:43:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d745a646272e5866542404b0c70872fc6b7ecfcada6d67d4eb40e26a72b75c2f06fda6f5d49c344d2e5685a64bc90df9f0aa0fbe6f9df58c68c4887f7ae146e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff3ba4d9234fa0fb1f5e4670617b09a1f60ab702cfeb156247bbdb565c13cdf08ded9ff042404794b6e912d32cab932ceea39ed8173db5b6e976b6d92a1bb541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ebdf87f48f022b1a10275c5cb6b8d52b6e906287f7e3c3a4e8a78370d7b5f2dc6a812ea7c088e1ba354fe0540355c008a399181c0a31e7aa0c17d177b20b732"} 05:43:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:09 INFO - Contents: 05:43:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d745a646272e5866542404b0c70872fc6b7ecfcada6d67d4eb40e26a72b75c2f06fda6f5d49c344d2e5685a64bc90df9f0aa0fbe6f9df58c68c4887f7ae146e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff3ba4d9234fa0fb1f5e4670617b09a1f60ab702cfeb156247bbdb565c13cdf08ded9ff042404794b6e912d32cab932ceea39ed8173db5b6e976b6d92a1bb541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ebdf87f48f022b1a10275c5cb6b8d52b6e906287f7e3c3a4e8a78370d7b5f2dc6a812ea7c088e1ba354fe0540355c008a399181c0a31e7aa0c17d177b20b732"} 05:43:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:09 INFO - Copying logs to upload dir... 05:43:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.216121 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-09 05:43:09.445302) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 05:43:09.448695) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 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/d745a646272e5866542404b0c70872fc6b7ecfcada6d67d4eb40e26a72b75c2f06fda6f5d49c344d2e5685a64bc90df9f0aa0fbe6f9df58c68c4887f7ae146e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff3ba4d9234fa0fb1f5e4670617b09a1f60ab702cfeb156247bbdb565c13cdf08ded9ff042404794b6e912d32cab932ceea39ed8173db5b6e976b6d92a1bb541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ebdf87f48f022b1a10275c5cb6b8d52b6e906287f7e3c3a4e8a78370d7b5f2dc6a812ea7c088e1ba354fe0540355c008a399181c0a31e7aa0c17d177b20b732"} build_url:https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/d3pX5VabQu-PSdSiseaXtQ/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/d745a646272e5866542404b0c70872fc6b7ecfcada6d67d4eb40e26a72b75c2f06fda6f5d49c344d2e5685a64bc90df9f0aa0fbe6f9df58c68c4887f7ae146e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff3ba4d9234fa0fb1f5e4670617b09a1f60ab702cfeb156247bbdb565c13cdf08ded9ff042404794b6e912d32cab932ceea39ed8173db5b6e976b6d92a1bb541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ebdf87f48f022b1a10275c5cb6b8d52b6e906287f7e3c3a4e8a78370d7b5f2dc6a812ea7c088e1ba354fe0540355c008a399181c0a31e7aa0c17d177b20b732"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 05:43:09.477314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:43:09.477883) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470742197.778927-661108863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 05:43:09.559378) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 05:43:09.559730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 05:43:09.560069) ========= ========= Total master_lag: 0.18 =========