builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1482254169.49 results: success (0) buildid: 20161220081453 builduid: 8319cb49ab724ec283a2e8ac2d07e7ba revision: 138d3e518727488f2c500a31f2f1431a2e41d023 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.491131) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.491621) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.491934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.506508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.506861) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-20 09:16:09-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-12-20 09:16:10 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113830 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.635595) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.636144) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014978 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.686438) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:09.686783) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 138d3e518727488f2c500a31f2f1431a2e41d023 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 138d3e518727488f2c500a31f2f1431a2e41d023 --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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-20 09:16:10,130 truncating revision to first 12 chars 2016-12-20 09:16:10,130 Setting DEBUG logging. 2016-12-20 09:16:10,130 attempt 1/10 2016-12-20 09:16:10,130 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/138d3e518727?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 09:16:10,443 unpacking tar archive at: graphics-138d3e518727/testing/mozharness/ program finished with exit code 0 elapsedTime=0.618032 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:10.318325) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:10.318644) ========= script_repo_revision: 138d3e518727488f2c500a31f2f1431a2e41d023 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:10.319040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:10.319307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 09:16:10.330015) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-20 09:16:10.330554) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:16:10 INFO - MultiFileLogger online at 20161220 09:16:10 in /builds/slave/test 09:16:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:16:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:10 INFO - {'append_to_log': False, 09:16:10 INFO - 'base_work_dir': '/builds/slave/test', 09:16:10 INFO - 'blob_upload_branch': 'Graphics', 09:16:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:10 INFO - 'branch': 'Graphics', 09:16:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:10 INFO - 'config_files': ('talos/linux_config.py',), 09:16:10 INFO - 'default_actions': ('clobber', 09:16:10 INFO - 'read-buildbot-config', 09:16:10 INFO - 'download-and-extract', 09:16:10 INFO - 'populate-webroot', 09:16:10 INFO - 'create-virtualenv', 09:16:10 INFO - 'install', 09:16:10 INFO - 'run-tests'), 09:16:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:10 INFO - 'download_minidump_stackwalk': True, 09:16:10 INFO - 'download_symbols': 'ondemand', 09:16:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:10 INFO - 'installer_path': 'installer.exe', 09:16:10 INFO - 'log_level': 'info', 09:16:10 INFO - 'log_name': 'talos', 09:16:10 INFO - 'log_to_console': True, 09:16:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:16:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:16:10 INFO - 'opt_config_files': (), 09:16:10 INFO - 'pip_index': False, 09:16:10 INFO - 'sps_profile': False, 09:16:10 INFO - 'sps_profile_interval': 0, 09:16:10 INFO - 'suite': 'dromaeojs', 09:16:10 INFO - 'system_bits': '32', 09:16:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:10 INFO - 'title': 'talos-linux64-ix-042', 09:16:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:10 INFO - 'use_talos_json': True, 09:16:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:10 INFO - 'work_dir': 'build'} 09:16:10 INFO - [mozharness: 2016-12-20 17:16:10.868875Z] Running clobber step. 09:16:10 INFO - Running pre-action listener: _resource_record_pre_action 09:16:10 INFO - Running main action method: clobber 09:16:10 INFO - rmtree: /builds/slave/test/build 09:16:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:11 INFO - Running post-action listener: _resource_record_post_action 09:16:11 INFO - [mozharness: 2016-12-20 17:16:11.843971Z] Finished clobber step (success) 09:16:11 INFO - [mozharness: 2016-12-20 17:16:11.844073Z] Running read-buildbot-config step. 09:16:11 INFO - Running pre-action listener: _resource_record_pre_action 09:16:11 INFO - Running main action method: read_buildbot_config 09:16:11 INFO - Using buildbot properties: 09:16:11 INFO - { 09:16:11 INFO - "project": "", 09:16:11 INFO - "product": "firefox", 09:16:11 INFO - "script_repo_revision": "default", 09:16:11 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 09:16:11 INFO - "repository": "", 09:16:11 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 09:16:11 INFO - "buildid": "20161220081453", 09:16:11 INFO - "pgo_build": "False", 09:16:11 INFO - "basedir": "/builds/slave/test", 09:16:11 INFO - "buildnumber": 26, 09:16:11 INFO - "slavename": "talos-linux64-ix-042", 09:16:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:16:11 INFO - "platform": "ubuntu64_hw", 09:16:11 INFO - "scheduler": "tests-graphics-linux64-talos", 09:16:11 INFO - "branch": "graphics", 09:16:11 INFO - "repo_path": "projects/graphics", 09:16:11 INFO - "revision": "138d3e518727488f2c500a31f2f1431a2e41d023", 09:16:11 INFO - "stage_platform": "linux64", 09:16:11 INFO - "builduid": "8319cb49ab724ec283a2e8ac2d07e7ba", 09:16:11 INFO - "slavebuilddir": "test" 09:16:11 INFO - } 09:16:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:16:11 INFO - Running post-action listener: _resource_record_post_action 09:16:11 INFO - [mozharness: 2016-12-20 17:16:11.846536Z] Finished read-buildbot-config step (success) 09:16:11 INFO - [mozharness: 2016-12-20 17:16:11.846625Z] Running download-and-extract step. 09:16:11 INFO - Running pre-action listener: _resource_record_pre_action 09:16:11 INFO - Running main action method: download_and_extract 09:16:11 INFO - mkdir: /builds/slave/test/build/tests 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:11 INFO - trying https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:16:11 INFO - Downloading https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:16:12 INFO - Downloaded 1361 bytes. 09:16:12 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:16:12 INFO - Using the following test package requirements: 09:16:12 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:16:12 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:16:12 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:16:12 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'jsshell-linux-x86_64.zip'], 09:16:12 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:16:12 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:16:12 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:16:12 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:16:12 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:16:12 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:16:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:16:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:16:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:12 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:16:13 INFO - Expected file size: 20258275 09:16:13 INFO - Obtained file size: 20258275 09:16:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:16:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:16:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:17 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:16:17 INFO - Expected file size: 20258275 09:16:18 INFO - Obtained file size: 20258275 09:16:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:16:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:16:22 INFO - Fetch https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:16:26 INFO - Expected file size: 14409734 09:16:26 INFO - Obtained file size: 14409734 09:16: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')]} 09:16:26 INFO - trying https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:26 INFO - Downloading https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:16:27 INFO - Downloaded 55645830 bytes. 09:16:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:27 INFO - mkdir: /builds/slave/test/properties 09:16:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:27 INFO - Contents: 09:16:27 INFO - build_url:https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:28 INFO - Running post-action listener: _resource_record_post_action 09:16:28 INFO - Running post-action listener: set_extra_try_arguments 09:16:28 INFO - [mozharness: 2016-12-20 17:16:28.205600Z] Finished download-and-extract step (success) 09:16:28 INFO - [mozharness: 2016-12-20 17:16:28.205827Z] Running populate-webroot step. 09:16:28 INFO - Running pre-action listener: _resource_record_pre_action 09:16:28 INFO - Running main action method: populate_webroot 09:16:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:28 INFO - '--tppagecycles', 09:16:28 INFO - '7'], 09:16:28 INFO - 'tests': ['tsvgm']}}, 09:16:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['damp', 'tps']}, 09:16:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'tests': ['damp', 'tps']}, 09:16:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['dromaeo_dom']}, 09:16:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['basic_compositor_video']}, 09:16:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['a11yr', 09:16:28 INFO - 'ts_paint', 09:16:28 INFO - 'tpaint', 09:16:28 INFO - 'sessionrestore', 09:16:28 INFO - 'sessionrestore_no_auto_restore', 09:16:28 INFO - 'tabpaint']}, 09:16:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['tsvgx', 09:16:28 INFO - 'tsvgr_opacity', 09:16:28 INFO - 'tart', 09:16:28 INFO - 'tscrollx', 09:16:28 INFO - 'cart', 09:16:28 INFO - 'tsvg_static']}, 09:16:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:28 INFO - 'tsvgr_opacity', 09:16:28 INFO - 'tart', 09:16:28 INFO - 'tscrollx', 09:16:28 INFO - 'cart', 09:16:28 INFO - 'tsvg_static']}, 09:16:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'talos_options': ['--disable-e10s'], 09:16:28 INFO - 'tests': ['tp5o']}, 09:16:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'talos_options': ['--disable-e10s', 09:16:28 INFO - '--xperf_path', 09:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:28 INFO - 'tests': ['tp5n']}, 09:16:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:28 INFO - 'talos_options': ['--xperf_path', 09:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:28 INFO - 'tests': ['tp5n']}}, 09:16:28 INFO - 'talos.zip': {'path': '', 09:16:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:28 INFO - Running post-action listener: _resource_record_post_action 09:16:28 INFO - [mozharness: 2016-12-20 17:16:28.225668Z] Finished populate-webroot step (success) 09:16:28 INFO - [mozharness: 2016-12-20 17:16:28.225757Z] Running create-virtualenv step. 09:16:28 INFO - Running pre-action listener: _resource_record_pre_action 09:16:28 INFO - Running main action method: create_virtualenv 09:16:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:16:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:28 INFO - Using real prefix '/usr' 09:16:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:30 INFO - Installing pip.................done. 09:16:30 INFO - Return code: 0 09:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:31 INFO - Reading from file tmpfile_stdout 09:16:31 INFO - Output received: 09:16:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:16:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:31 INFO - Using env: {'DISPLAY': ':0', 09:16:31 INFO - 'HOME': '/home/cltbld', 09:16:31 INFO - 'LANG': 'en_US.UTF-8', 09:16:31 INFO - 'LANGUAGE': 'en_US:en', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MAIL': '/var/mail/cltbld', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SHLVL': '1', 09:16:31 INFO - 'TERM': 'linux', 09:16:31 INFO - 'TMOUT': '86400', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - '_': '/tools/buildbot/bin/python'} 09:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:16:31 INFO - Cleaning up... 09:16:31 INFO - Return code: 0 09:16:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:16:31 INFO - Using env: {'DISPLAY': ':0', 09:16:31 INFO - 'HOME': '/home/cltbld', 09:16:31 INFO - 'LANG': 'en_US.UTF-8', 09:16:31 INFO - 'LANGUAGE': 'en_US:en', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MAIL': '/var/mail/cltbld', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SHLVL': '1', 09:16:31 INFO - 'TERM': 'linux', 09:16:31 INFO - 'TMOUT': '86400', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - '_': '/tools/buildbot/bin/python'} 09:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:31 INFO - Downloading/unpacking psutil>=3.1.1 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:33 INFO - Installing collected packages: psutil 09:16:33 INFO - Running setup.py install for psutil 09:16:33 INFO - building 'psutil._psutil_linux' extension 09:16:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:16:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:16:33 INFO - building 'psutil._psutil_posix' extension 09:16:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:16:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:16:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:34 INFO - Successfully installed psutil 09:16:34 INFO - Cleaning up... 09:16:34 INFO - Return code: 0 09:16:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:34 INFO - Using env: {'DISPLAY': ':0', 09:16:34 INFO - 'HOME': '/home/cltbld', 09:16:34 INFO - 'LANG': 'en_US.UTF-8', 09:16:34 INFO - 'LANGUAGE': 'en_US:en', 09:16:34 INFO - 'LOGNAME': 'cltbld', 09:16:34 INFO - 'MAIL': '/var/mail/cltbld', 09:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:34 INFO - 'MOZ_NO_REMOTE': '1', 09:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:34 INFO - 'NO_EM_RESTART': '1', 09:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:34 INFO - 'PWD': '/builds/slave/test', 09:16:34 INFO - 'SHELL': '/bin/bash', 09:16:34 INFO - 'SHLVL': '1', 09:16:34 INFO - 'TERM': 'linux', 09:16:34 INFO - 'TMOUT': '86400', 09:16:34 INFO - 'USER': 'cltbld', 09:16:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:34 INFO - '_': '/tools/buildbot/bin/python'} 09:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:36 INFO - Installing collected packages: mozsystemmonitor 09:16:36 INFO - Running setup.py install for mozsystemmonitor 09:16:36 INFO - Successfully installed mozsystemmonitor 09:16:36 INFO - Cleaning up... 09:16:36 INFO - Return code: 0 09:16:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:36 INFO - Using env: {'DISPLAY': ':0', 09:16:36 INFO - 'HOME': '/home/cltbld', 09:16:36 INFO - 'LANG': 'en_US.UTF-8', 09:16:36 INFO - 'LANGUAGE': 'en_US:en', 09:16:36 INFO - 'LOGNAME': 'cltbld', 09:16:36 INFO - 'MAIL': '/var/mail/cltbld', 09:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:36 INFO - 'MOZ_NO_REMOTE': '1', 09:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:36 INFO - 'NO_EM_RESTART': '1', 09:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:36 INFO - 'PWD': '/builds/slave/test', 09:16:36 INFO - 'SHELL': '/bin/bash', 09:16:36 INFO - 'SHLVL': '1', 09:16:36 INFO - 'TERM': 'linux', 09:16:36 INFO - 'TMOUT': '86400', 09:16:36 INFO - 'USER': 'cltbld', 09:16:36 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:36 INFO - '_': '/tools/buildbot/bin/python'} 09:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:39 INFO - warning: no files found matching '*.txt' 09:16:39 INFO - no previously-included directories found matching 'build' 09:16:39 INFO - no previously-included directories found matching 'dist' 09:16:39 INFO - no previously-included directories found matching '.git*' 09:16:39 INFO - Installing collected packages: jsonschema, functools32 09:16:39 INFO - Running setup.py install for functools32 09:16:39 INFO - warning: no files found matching '*.txt' 09:16:39 INFO - no previously-included directories found matching 'build' 09:16:39 INFO - no previously-included directories found matching 'dist' 09:16:39 INFO - no previously-included directories found matching '.git*' 09:16:39 INFO - Successfully installed jsonschema functools32 09:16:39 INFO - Cleaning up... 09:16:39 INFO - Return code: 0 09:16:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:39 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:39 INFO - Using env: {'DISPLAY': ':0', 09:16:39 INFO - 'HOME': '/home/cltbld', 09:16:39 INFO - 'LANG': 'en_US.UTF-8', 09:16:39 INFO - 'LANGUAGE': 'en_US:en', 09:16:39 INFO - 'LOGNAME': 'cltbld', 09:16:39 INFO - 'MAIL': '/var/mail/cltbld', 09:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:39 INFO - 'MOZ_NO_REMOTE': '1', 09:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:39 INFO - 'NO_EM_RESTART': '1', 09:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:39 INFO - 'PWD': '/builds/slave/test', 09:16:39 INFO - 'SHELL': '/bin/bash', 09:16:39 INFO - 'SHLVL': '1', 09:16:39 INFO - 'TERM': 'linux', 09:16:39 INFO - 'TMOUT': '86400', 09:16:39 INFO - 'USER': 'cltbld', 09:16:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:39 INFO - '_': '/tools/buildbot/bin/python'} 09:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:39 INFO - Cleaning up... 09:16:39 INFO - Return code: 0 09:16:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:39 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:39 INFO - Using env: {'DISPLAY': ':0', 09:16:39 INFO - 'HOME': '/home/cltbld', 09:16:39 INFO - 'LANG': 'en_US.UTF-8', 09:16:39 INFO - 'LANGUAGE': 'en_US:en', 09:16:39 INFO - 'LOGNAME': 'cltbld', 09:16:39 INFO - 'MAIL': '/var/mail/cltbld', 09:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:39 INFO - 'MOZ_NO_REMOTE': '1', 09:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:39 INFO - 'NO_EM_RESTART': '1', 09:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:39 INFO - 'PWD': '/builds/slave/test', 09:16:39 INFO - 'SHELL': '/bin/bash', 09:16:39 INFO - 'SHLVL': '1', 09:16:39 INFO - 'TERM': 'linux', 09:16:39 INFO - 'TMOUT': '86400', 09:16:39 INFO - 'USER': 'cltbld', 09:16:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:39 INFO - '_': '/tools/buildbot/bin/python'} 09:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:42 INFO - Downloading docopt-0.6.1.tar.gz 09:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:42 INFO - Running setup.py install for blobuploader 09:16:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:42 INFO - Running setup.py install for requests 09:16:43 INFO - Running setup.py install for docopt 09:16:43 INFO - Successfully installed blobuploader requests docopt 09:16:43 INFO - Cleaning up... 09:16:43 INFO - Return code: 0 09:16:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:43 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:43 INFO - Using env: {'DISPLAY': ':0', 09:16:43 INFO - 'HOME': '/home/cltbld', 09:16:43 INFO - 'LANG': 'en_US.UTF-8', 09:16:43 INFO - 'LANGUAGE': 'en_US:en', 09:16:43 INFO - 'LOGNAME': 'cltbld', 09:16:43 INFO - 'MAIL': '/var/mail/cltbld', 09:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:43 INFO - 'MOZ_NO_REMOTE': '1', 09:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:43 INFO - 'NO_EM_RESTART': '1', 09:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:43 INFO - 'PWD': '/builds/slave/test', 09:16:43 INFO - 'SHELL': '/bin/bash', 09:16:43 INFO - 'SHLVL': '1', 09:16:43 INFO - 'TERM': 'linux', 09:16:43 INFO - 'TMOUT': '86400', 09:16:43 INFO - 'USER': 'cltbld', 09:16:43 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:43 INFO - '_': '/tools/buildbot/bin/python'} 09:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:43 INFO - Running setup.py (path:/tmp/pip-F1aeaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:43 INFO - Running setup.py (path:/tmp/pip-sIIyzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:43 INFO - Running setup.py (path:/tmp/pip-cmwaL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:43 INFO - Running setup.py (path:/tmp/pip-0WNluf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:43 INFO - Running setup.py (path:/tmp/pip-YNruWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:43 INFO - Running setup.py (path:/tmp/pip-WBc0GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:43 INFO - Running setup.py (path:/tmp/pip-Pe_AJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:43 INFO - Running setup.py (path:/tmp/pip-0gyDQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:43 INFO - Running setup.py (path:/tmp/pip-QJlEpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:44 INFO - Running setup.py (path:/tmp/pip-9Bkfo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:44 INFO - Running setup.py (path:/tmp/pip-WNZH2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:44 INFO - Running setup.py (path:/tmp/pip-VERwkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:44 INFO - Running setup.py (path:/tmp/pip-zgNxn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:44 INFO - Running setup.py (path:/tmp/pip-kzuEAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:44 INFO - Running setup.py (path:/tmp/pip-7rgQYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:44 INFO - Running setup.py (path:/tmp/pip-pi2P7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:44 INFO - Running setup.py (path:/tmp/pip-f6fyjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:44 INFO - Running setup.py install for manifestparser 09:16:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for mozcrash 09:16:44 INFO - Running setup.py install for mozdebug 09:16:44 INFO - Running setup.py install for mozdevice 09:16:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozfile 09:16:45 INFO - Running setup.py install for mozhttpd 09:16:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozinfo 09:16:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozInstall 09:16:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozleak 09:16:45 INFO - Running setup.py install for mozlog 09:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for moznetwork 09:16:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozprocess 09:16:45 INFO - Running setup.py install for mozprofile 09:16:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozrunner 09:16:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:46 INFO - Running setup.py install for mozscreenshot 09:16:46 INFO - Running setup.py install for moztest 09:16:46 INFO - Running setup.py install for mozversion 09:16:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:46 INFO - Cleaning up... 09:16:46 INFO - Return code: 0 09:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:46 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:46 INFO - Using env: {'DISPLAY': ':0', 09:16:46 INFO - 'HOME': '/home/cltbld', 09:16:46 INFO - 'LANG': 'en_US.UTF-8', 09:16:46 INFO - 'LANGUAGE': 'en_US:en', 09:16:46 INFO - 'LOGNAME': 'cltbld', 09:16:46 INFO - 'MAIL': '/var/mail/cltbld', 09:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:46 INFO - 'MOZ_NO_REMOTE': '1', 09:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:46 INFO - 'NO_EM_RESTART': '1', 09:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:46 INFO - 'PWD': '/builds/slave/test', 09:16:46 INFO - 'SHELL': '/bin/bash', 09:16:46 INFO - 'SHLVL': '1', 09:16:46 INFO - 'TERM': 'linux', 09:16:46 INFO - 'TMOUT': '86400', 09:16:46 INFO - 'USER': 'cltbld', 09:16:46 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:46 INFO - '_': '/tools/buildbot/bin/python'} 09:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:46 INFO - Running setup.py (path:/tmp/pip-t6htIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:46 INFO - Running setup.py (path:/tmp/pip-1UPVrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:46 INFO - Running setup.py (path:/tmp/pip-MFi6Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:46 INFO - Running setup.py (path:/tmp/pip-lE6rtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:46 INFO - Running setup.py (path:/tmp/pip-BybEpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:46 INFO - Running setup.py (path:/tmp/pip-C_lyy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:47 INFO - Running setup.py (path:/tmp/pip-LZb8PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:47 INFO - Running setup.py (path:/tmp/pip-jE1lEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:47 INFO - Running setup.py (path:/tmp/pip-W2EZRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:47 INFO - Running setup.py (path:/tmp/pip-sAw5XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:47 INFO - Running setup.py (path:/tmp/pip-cJjo90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:47 INFO - Running setup.py (path:/tmp/pip-GjcwGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:47 INFO - Running setup.py (path:/tmp/pip-kMsh8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:47 INFO - Running setup.py (path:/tmp/pip-8bwJHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:47 INFO - Running setup.py (path:/tmp/pip-zUb3km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:47 INFO - Running setup.py (path:/tmp/pip-390voS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:47 INFO - Running setup.py (path:/tmp/pip-o6ueMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:47 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:47 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:49 INFO - Downloading blessings-1.6.tar.gz 09:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:49 INFO - Installing collected packages: blessings 09:16:49 INFO - Running setup.py install for blessings 09:16:50 INFO - Successfully installed blessings 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:50 INFO - Reading from file tmpfile_stdout 09:16:50 INFO - Current package versions: 09:16:50 INFO - argparse == 1.2.1 09:16:50 INFO - blessings == 1.6 09:16:50 INFO - blobuploader == 1.2.4 09:16:50 INFO - docopt == 0.6.1 09:16:50 INFO - functools32 == 3.2.3-2 09:16:50 INFO - jsonschema == 2.5.1 09:16:50 INFO - manifestparser == 1.1 09:16:50 INFO - mozInstall == 1.12 09:16:50 INFO - mozcrash == 1.0 09:16:50 INFO - mozdebug == 0.1 09:16:50 INFO - mozdevice == 0.49 09:16:50 INFO - mozfile == 1.2 09:16:50 INFO - mozhttpd == 0.7 09:16:50 INFO - mozinfo == 0.9 09:16:50 INFO - mozleak == 0.1 09:16:50 INFO - mozlog == 3.4 09:16:50 INFO - moznetwork == 0.27 09:16:50 INFO - mozprocess == 0.23 09:16:50 INFO - mozprofile == 0.28 09:16:50 INFO - mozrunner == 6.13 09:16:50 INFO - mozscreenshot == 0.1 09:16:50 INFO - mozsystemmonitor == 0.3 09:16:50 INFO - moztest == 0.7 09:16:50 INFO - mozversion == 1.4 09:16:50 INFO - psutil == 3.1.1 09:16:50 INFO - requests == 1.2.3 09:16:50 INFO - wsgiref == 0.1.2 09:16:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:50 INFO - Using env: {'DISPLAY': ':0', 09:16:50 INFO - 'HOME': '/home/cltbld', 09:16:50 INFO - 'LANG': 'en_US.UTF-8', 09:16:50 INFO - 'LANGUAGE': 'en_US:en', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MAIL': '/var/mail/cltbld', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SHLVL': '1', 09:16:50 INFO - 'TERM': 'linux', 09:16:50 INFO - 'TMOUT': '86400', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - '_': '/tools/buildbot/bin/python'} 09:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:50 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 0xd1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:50 INFO - Using env: {'DISPLAY': ':0', 09:16:50 INFO - 'HOME': '/home/cltbld', 09:16:50 INFO - 'LANG': 'en_US.UTF-8', 09:16:50 INFO - 'LANGUAGE': 'en_US:en', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MAIL': '/var/mail/cltbld', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SHLVL': '1', 09:16:50 INFO - 'TERM': 'linux', 09:16:50 INFO - 'TMOUT': '86400', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - '_': '/tools/buildbot/bin/python'} 09:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Running post-action listener: _resource_record_post_action 09:16:50 INFO - Running post-action listener: _start_resource_monitoring 09:16:50 INFO - Starting resource monitoring. 09:16:50 INFO - [mozharness: 2016-12-20 17:16:50.803500Z] Finished create-virtualenv step (success) 09:16:50 INFO - [mozharness: 2016-12-20 17:16:50.804022Z] Running install step. 09:16:50 INFO - Running pre-action listener: _resource_record_pre_action 09:16:50 INFO - Running main action method: install 09:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:51 INFO - Reading from file tmpfile_stdout 09:16:51 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:51 INFO - Reading from file tmpfile_stdout 09:16:51 INFO - Output received: 09:16:51 INFO - Usage: mozinstall [options] installer 09:16:51 INFO - Options: 09:16:51 INFO - -h, --help show this help message and exit 09:16:51 INFO - -d DEST, --destination=DEST 09:16:51 INFO - Directory to install application into. [default: 09:16:51 INFO - "/builds/slave/test"] 09:16:51 INFO - --app=APP Application being installed. [default: firefox] 09:16:51 INFO - mkdir: /builds/slave/test/build/application 09:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:17:07 INFO - Reading from file tmpfile_stdout 09:17:07 INFO - Output received: 09:17:07 INFO - /builds/slave/test/build/application/firefox/firefox 09:17:07 INFO - Running post-action listener: _resource_record_post_action 09:17:07 INFO - [mozharness: 2016-12-20 17:17:07.147675Z] Finished install step (success) 09:17:07 INFO - [mozharness: 2016-12-20 17:17:07.147922Z] Running run-tests step. 09:17:07 INFO - Running pre-action listener: _resource_record_pre_action 09:17:07 INFO - Running main action method: run_tests 09:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:07 INFO - Python 2.7.3 09:17:07 INFO - Return code: 0 09:17:07 INFO - grabbing minidump binary from tooltool 09:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:07 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 0x108b3f0>, '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 0x108bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:17:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:17:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:17:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:07 INFO - Return code: 0 09:17:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:17:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:17:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:17:07 INFO - Using env: {'DISPLAY': ':0', 09:17:07 INFO - 'HOME': '/home/cltbld', 09:17:07 INFO - 'LANG': 'en_US.UTF-8', 09:17:07 INFO - 'LANGUAGE': 'en_US:en', 09:17:07 INFO - 'LOGNAME': 'cltbld', 09:17:07 INFO - 'MAIL': '/var/mail/cltbld', 09:17:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:07 INFO - 'MOZ_NO_REMOTE': '1', 09:17:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:07 INFO - 'NO_EM_RESTART': '1', 09:17:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:07 INFO - 'PWD': '/builds/slave/test', 09:17:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:07 INFO - 'SHELL': '/bin/bash', 09:17:07 INFO - 'SHLVL': '1', 09:17:07 INFO - 'TERM': 'linux', 09:17:07 INFO - 'TMOUT': '86400', 09:17:07 INFO - 'USER': 'cltbld', 09:17:07 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405', 09:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:07 INFO - '_': '/tools/buildbot/bin/python'} 09:17:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:17:07 INFO - mozversion application_buildid: 20161220081453 09:17:07 INFO - mozversion application_changeset: 138d3e518727488f2c500a31f2f1431a2e41d023 09:17:07 INFO - mozversion application_display_name: Nightly 09:17:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:07 INFO - mozversion application_name: Firefox 09:17:07 INFO - mozversion application_remotingname: firefox 09:17:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:17:07 INFO - mozversion application_vendor: Mozilla 09:17:07 INFO - mozversion application_version: 53.0a1 09:17:07 INFO - mozversion platform_buildid: 20161220081453 09:17:07 INFO - mozversion platform_changeset: 138d3e518727488f2c500a31f2f1431a2e41d023 09:17:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:17:07 INFO - mozversion platform_version: 53.0a1 09:17:07 INFO - using testdate: 1482254227 09:17:07 INFO - actual date: 1482254227 09:17:07 INFO - starting webserver on 'localhost:34027' 09:17:07 INFO - SUITE-START | Running 2 tests 09:17:07 INFO - TEST-START | dromaeo_css 09:17:07 INFO - operating with platform_type : linux_ 09:17:07 INFO - Initialising browser for dromaeo_css test... 09:17:07 INFO - TEST-INFO | started process 25645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJxK2fu/profile http://localhost:34027/getInfo.html) 09:17:11 INFO - PROCESS | 25645 | __metrics Screen width/height:1600/1200 09:17:11 INFO - PROCESS | 25645 | colorDepth:24 09:17:11 INFO - PROCESS | 25645 | Browser inner width/height: 1024/697 09:17:11 INFO - PROCESS | 25645 | __metrics 09:17:12 INFO - PROCESS | 25645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:12 INFO - PROCESS | 25645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:12 INFO - PROCESS | 25645 | console.error: PushService: 09:17:12 INFO - PROCESS | 25645 | stateChangeProcessEnqueue: Error transitioning state 09:17:12 INFO - PROCESS | 25645 | UnknownError 09:17:13 INFO - TEST-INFO | 25645: exit 0 09:17:13 INFO - Browser initialized. 09:17:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:13 INFO - TEST-INFO | started process 25729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJxK2fu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:25 INFO - PROCESS | 25729 | RSS: Main: 155635712 09:17:25 INFO - PROCESS | 25729 | 09:18:16 INFO - PROCESS | 25729 | 1482254296355 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:20:30 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34027/tests/dromaeo/cssquery-ext.html) 09:20:32 INFO - PROCESS | 25729 | RSS: Main: 272871424 09:20:32 INFO - PROCESS | 25729 | 09:23:34 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-ext.html (next: http://localhost:34027/tests/dromaeo/cssquery-jquery.html) 09:23:35 INFO - PROCESS | 25729 | RSS: Main: 250216448 09:23:35 INFO - PROCESS | 25729 | 09:26:37 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34027/tests/dromaeo/cssquery-mootools.html) 09:26:38 INFO - PROCESS | 25729 | RSS: Main: 221163520 09:26:38 INFO - PROCESS | 25729 | 09:29:40 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34027/tests/dromaeo/cssquery-prototype.html) 09:29:41 INFO - PROCESS | 25729 | RSS: Main: 189493248 09:29:41 INFO - PROCESS | 25729 | 09:32:44 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34027/tests/dromaeo/cssquery-yui.html) 09:32:45 INFO - PROCESS | 25729 | RSS: Main: 189087744 09:32:45 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | Cycle 1(1): loaded http://localhost:34027/tests/dromaeo/cssquery-yui.html (next: http://localhost:34027/tests/dromaeo/cssquery-dojo.html) 09:35:48 INFO - PROCESS | 25729 | RSS: Main: 288133120 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | __start_tp_report 09:35:48 INFO - PROCESS | 25729 | _x_x_mozilla_page_load 09:35:48 INFO - PROCESS | 25729 | _x_x_mozilla_page_load_details 09:35:48 INFO - PROCESS | 25729 | |i|pagename|runs| 09:35:48 INFO - PROCESS | 25729 | |0;dojo.html;2580.793536517079;2620.646212761279;2634.7628713415793;2658.5347564176263;2658.7208343123984;4614.077184563084;4643.7910294036765;4767.928481072782;4794.328793968843;4810.999999999995;4733.71597704138;4742.90514189716;4768.380110585619;4789.090072886149;4858.902821943568;4241.936370954438;4264.0405908670455;4302.784860756965;4360.433143691332;4436.378906953029;4017.645762719817;4343.239933011726;4448.243798554254;4480.775961201944;4527.524609915944;4266.338717498793;4286.678499112579;4407.427034485497;4463.509014008457;4493.9550604494025;4323.697341186087;4353;4359.716618419824;4359.84740534083;4364.912701745979;4336.024394511242;4356.956430435686;4358.324459708719;4362.17118747437;4375.124975005012;4717.787699553528;4721.079389519045;4724.433068031858;4733.290006499018;4736.502667219961;5011.974940125312;5057.115004874132;5057.342545469102;5080.822171223989;5133.6149788765615;5091.363579552556;5093.949060509384;5096.031753966771;5112.233165025276;5139.229115632647;4597.586217240464;4731.455882573479;4776.020915712271;4778.354922085508;4826.155422801066;5036.597072234213;5155.561777248976;5166.43169251382;5166.819161329336;5189.948100519022;4967.850964471072;5019.824306149268;5020.246962955513;5032.220005899091;5062.7215503146945;5118.155504341742;5121.0014047261175;5124.103281925648;5128.820491282787;5163.3545806774155;4854.757262136879;4886.462489126193;4887.706737595756;4992.650514464025;5064.848054558369;4894.657373983787;5045.949540504622;5101.540861322499;5114.6931184128325;5115.0792857285305;4259.105587826592;4274.081072569413;4275.572442755762;4275.850345237902;4322.8703138905885;3643.107438677541;3758.5489741231227;3761.360568703327;3777.320082385197;3798.2023775007556;4260.701750877409;4325.870223893288;4390.319500477431;4400.119976004812;4405.647548196137;5166.4058633257655;5171.793128274827;5176.404713457925;5187.403448603459;5223.112070947948;4402.229609818333;4434.068845542472;4438.312061630452;4471.28459446487;4484.618807401343;4365.148795984753;4415.315626077963;4419;4512.187806194916;4594.402727645384;4718.174319494006;4747.738874361943;4764.380630518011;4796.016816552528;4796.208625576741;5060.772265247999;5074.746262686777;5092.159793634009;5098.439171691037;5141.28022076917;4567.566081222279;4589.862304130881;4734.73958932262;4756.143894099096;4764.49972752856;5001.799928002839;5008.799648014185;5029.7988080477835;5074.3657042869645;5114.053900028507;5086.0845047891735;5115.130427827204;5116.334876466036;5130.025295193901;5173.301604283524;4792.7603619819565;4932.161532539405;4969.577585905238;4993.4756850530175;4993.675411098412;4822.95177048225;4865.318855360323;4896.363472748662;4913.729744864067;4983.103041452573;4889.3888263967;4889.486603906681;4891.26921374689;4891.413030436371;4921.729304888122;4984.850454486371;4985.102681517216;4987.725675087905;4989.600831933364;5040.672356296829;4763.833265835818;4785.712857228577;4832.226843704991;4860.0279944012045;4862.05189987949 09:35:48 INFO - PROCESS | 25729 | |1;ext.html;516.2643233392415;529.11637565266;537.3417563484729;543.8368489453164;549.5164255455202;27750.86124569377;37033.25933481332;38485.807570962104;38492.80753596232;42154.57845421545;38579.61420385796;38983.61016389835;39006;39060;39073.21853562927;7312.232215617363;7548.094228692565;7680.078390593108;7711.652975616083;7718.498297610666;7345.559266444004;9743.317967742225;10008.349457285289;10022.14590300793;10330.535125919314;10003.199744020461;10064.194864410829;10066.899331006705;10075.596976120984;10077.546510407012;9971;10037.749056273578;10038;10051.296409251356;10054.64808731691;9954.90045099547;9990.45052522111;10019.348742331726;10019.949900250453;10023.849642255373;36542.81728591367;36894.262114757825;36898;36939.4459083114;36995.26009479795;2806.0880213930554;2845.558938364557;2869.4404591104744;2934.6918573549683;3323.1858194742445;27400.177109141052;27497.725022749917;27636.30909227265;27724.584131238047;28277.15168544947;3303.3723592517345;3352.2122301259183;3396.4056290149374;3420.0423881313022;3429.2969941162;242448;242729;243935;244556;244988;41418;41469.585304146574;41575;41575.792121039805;41581.79209103935;52012;52109.7394513025;52125.7393713029;52139.47860521422;52141.47858521366;13298.73402531944;14083.155010699185;14087.85912140886;14109.647258818612;14120.85879141216;9377.95311023454;9520.333576649567;10451;10538.947305263579;10589.364638121737;14684;15306.311215995307;15315.61710957235;15391.307391167424;15500.767488487456;5699.971500142473;5795.362510123883;5825.592208545446;5882.588218824727;5883.558733094949;704.5631708340861;739.3678404963679;825.5046971816981;825.8637324841334;840.7435732101768;26516.469670606864;27346.726532734418;27963.860180698965;27994;28108.71891281102;887.7425546591545;904.8914130304274;922.2898368256405;927.2072378116748;969.3021024862088;13071.607851764462;13889;14384.928075359556;14424.927875360765;14630.00084982576;337039;338211;339414;339462;339465;41217.587824121976;41395.172096558505;41455.7927210362;41524.169516610105;41602.375964359955;1025.5487585462372;1067.6636859389423;1121.1031175060082;1123.5561953028507;1147.0708725931854;22462.438439039153;24494.877525612257;25384.04756204586;25473.872630636728;25545.61681574815;34507.827460862536;35037.8248108768;35104.47343289802;35148;35152;263117;263167;263894;263924;264878;22087;22871.542569148856;22908.770912290664;23978.52042959166;24093;32173;32260;32781;32948.34103317872;33271.66728332783;27108;28457.715422846333;28663.856680717297;28728.856355718086;29199;27804.16587502378;28002.859985700758;28541.714582853907;29018.854905725337;29076;27824.860875695493;28270.85864570664;28380;28610.85694571514;28778.280542985754;25473.872630636728;26578.335541611614;26617.600735988588;26769.33076673099;26804.86597567078;21359.46601334979;21526.892365538697;21690.457738556663;21696.783032169475;21716.34850954474 09:35:48 INFO - PROCESS | 25729 | |2;jquery.html;339.8453703564878;347.9704225140863;357.90157706630674;360.19855820798716;376.89446954852633;12236.755264894708;14419.639509012266;14433.711325773478;15195.6960860783;15698.215089245548;14629.487967921124;14882.776758348633;14925.477608283712;15011;15254;3858.054776579742;4818.662693611449;4937.23472861707;4979.875503112433;4981.327520784702;3625.7099432045507;3714.7585406948597;4881;4929.926051109236;5524.502794748471;5723.141528770676;5776.740046697888;5803.303603567578;5812.883742325151;5840.036393994986;5642.830715078534;5701.885962280772;5761.683107429089;5768.596198266123;5818.6217895836635;5103.1324674805;5104.489551044903;5112.284280200774;5113.258577506241;5116.948830511722;19302.71045934312;19599.90200049005;19871.90064049685;19988.500287492785;20016.59966800656;1764.1179410294853;1938.6995015772436;1999.6500612392917;2153.5262242306667;2408.5544174327806;8860.424072435271;12085.15403921726;13034.543790967366;13256.270905100215;13422.261775602337;1704.616461296211;1710.931562737489;1719.7248440249625;1783.7235228539466;1784.9910750446295;65377.67311163414;65841.67079164574;66061.66969165171;66505;67483;9616.134547890724;9769.120779129913;9801.313908026512;9810.80378392428;9983.301168918106;13373.732525349438;13397.263150526815;13398.799018014737;13406.731865362834;13434.395452204673;6774.898376524356;6857.5199736018;7004.439644828503;7057.541259818096;7103.183133939559;6394.904076438857;6465.579737317059;6596.86806263872;6620.106285651472;6720.596764194164;3835.137094153849;3921.647051765298;3927.0771368728324;3990.9623497890757;4014.8594799181888;1659.402615058578;1716.5708572856786;1721.7761690980092;1831.7985021647607;1842.5854182808762;683.6820878291519;768.8577613141475;812.6789917982364;855.4482358878488;891.9375643705008;15825.920870395805;16707.164641767864;16847.74728379075;17079.743803842954;17125.14374281306;780.3484090784259;825.8885050518103;832.1844592299581;833.3208435125354;879.349281531662;9074.504511259325;9087.954560227157;9420.057994200663;9421.623135074657;9569.856452153223;265940;269545;352628;356281;358120;77293;79700.60149699214;79763.88451915428;79958;80830.59584702039;886.3839631456076;909.736176508805;913.0686699566405;942.1379437814323;944.6929747831982;8901.821963560822;9494.335396522454;9588.808223835345;9610.903890961281;9773.85339219898;34158.487622685185;36123;36253.27493450064;36914.07714807151;37145.257094857414;42882.78558607312;43185.3522197161;44023;44564.55435445604;44697.776511117234;10823.242373033812;10887.642525733509;10943.726406839893;11024.283421577571;11091.72270693239;11858.229215100991;11895.405229738652;12040.939795300967;12350.38248087574;12869.35653217354;10480;10560.894391055992;10651.57393704243;10818.1886358525;10859.619913302997;10324.174066074846;10883.564657413615;10886.23796334249;10981.725456863642;11090.556377745123;10276.434796086287;10793.460326983777;11203.887961120285;11280.492377842853;11371.374574398487;10266.948665256627;10532.631357902437;10881.836772448578;10959.397233152253;11249.437528123724;10437.32157409766;10963.451827408757;10980.505877235384;11035;11146.721331966766 09:35:48 INFO - PROCESS | 25729 | |3;mootools.html;1230.4032544216054;1239.9690007749805;1280.2446556531645;1627.6663284026777;1657.9834201657986;6652.301508341627;7176.282371762821;7195.53229040112;7196.640167991599;7198.604076775789;6320.367963203677;6445.8710825783455;6755.1556055493065;6834.760783372583;6838.14523184602;2048.9692654610158;2083.9374818755464;2085.4786303424144;2086.6452703040445;2088.237793205484;2071.730675012254;2113.651247544161;2115.3548167808813;2121.596896589651;2124.54322320701;1816.9000704961222;1864.766904136983;2012.667909794882;2071.834253259743;2072.8549001569895;2096.1929657081982;2114.714513540683;2122.214780531198;2127.755308139568;2134.082344591825;2118.1633254864396;2135.2206444647745;2136.8504204705678;2139.978600214009;2140.8929553522416;7068.823279418004;7117.964410177968;7219.422446204291;7225.024621676059;7238.312360325761;4325.567443255681;4419.18245124653;4451.399061126739;4455.1535208310315;4459.019015816515;2709.146618815067;2858.5997960285367;2866.054202113298;2875.6692980307116;2887.9422411551864;818.9344852411792;877.1316396767155;905.855063189893;912.1790388650202;913.5934509143434;9588.472634005127;9659.034096590354;9693.22454203662;9699.563519641633;9701.66044188457;3756.5492140942715;3978.5225772906856;4049.352103663459;4060.167665628539;4064.5935406459116;8241.381896357678;8284.585770711441;8320.417570769989;8325.750227493185;8327.66689332432;4403.383526306319;4455.1980643484485;4492.932606010912;4495.078508905666;4500.347449619787;3944.467496887941;4086.346184610448;4087.7956102195367;4094.447249621263;4100.2414553307735;3225.951610725841;3287.3260981498734;3347.062822409729;3356.0938546592283;3374.426347520878;977.3158788847693;982.5627595719836;984.9162821160138;987.5407935310116;989.4063561862824;575.6143481927481;715.2561336210283;817.3747083481142;839.341117222977;862.1077185113378;4872.561469467765;5042.773075211624;5098.643094983316;5120.692758434431;5146.742662866842;715.7566427414704;758.8102974256436;795.4074214710078;810.8580998325269;843.2663582683105;3398.354312680632;3435.209901722618;3482.2861313430685;3491.720662347006;3521.4189658706537;8417.915820841714;8655.307575393954;8714.564271786385;8726.86909696355;8736;8626.611802468904;8642.654293828302;8644.178803013829;8651.264642505332;8652.221300082896;6418.582792118497;6434.839129021719;6525;6788.626625535643;6800.489963252776;4902.607791376753;4973.776180071833;5058.519440653132;5063.113955057924;5254.054270231395;6979;7765.7670269892;7927.762167134995;7944.086430060386;7958.960205198937;8021.197880212166;8074.83850323002;8120.796980075309;8192.221738934792;8210.055843578066;6220.7511699531515;6318.684065796784;6333.65164915934;6349.0476428536085;6351.2696039956;6314.147590075465;6365.745370185326;6376.075469056868;6497.155369801895;6606.240282367589;6228.22147231596;6242.3133455320785;6272.905906411499;6321.241451025722;6382.68086595659;6109.20580324555;6141.7543298267565;6184.072389141485;6205.379462053758;6291.874162516816;5357.089294819812;5367.624266301322;5432.320959880015;5682.488576028178;5784.045632470596;4329.826806927687;4418.4918734345965;4476.597106260452;4525.728456292633;4530.139273538017;4377.496587892306;4454.109178164315;4496;4511.05267893746;4582.587567118975 09:35:48 INFO - PROCESS | 25729 | |4;prototype.html;349.3519521288011;357.89442114576207;359.1828589957847;365.5850609558151;365.8518300088465;4794.904101917959;4797.641650844405;4807.879803004922;4831.275308703696;4851.781669824857;4553.521880202581;4786.329913812069;4792.353032340633;4799.664023518355;4806.591439727618;3737.2712321097397;3759.0226541099237;3806.6954643628583;3843.7693738375647;3880.3403421418425;3852.8809154964656;3862.9613703863024;3865.323568375537;3878.4182372644045;3884.164904545522;3678.926421471569;3841.3277676406656;3841.654251117413;3844.942325865114;3869.6517313441786;3574;3723.1809002019513;3754.5869954305003;3769.246150769857;3881.7670939743707;3460.2387474755515;3471.0041726007394;3472;3474.8436320365645;3513.1217195701074;4655.906881862344;4679.566625354831;4681.274402467623;4684.594621613536;4686.718796872198;4080.204360149772;4246.214450326699;4270.231358355495;4415.757133357664;4424.225760491901;4969.378827646544;4981.178105612606;5006.824761133343;5007.699538027694;5019.673721208146;3553.751237413382;3556.715462762973;3558.839852206631;3570.4822800694014;3579.3199292134673;5041.621878359101;5237.16205407133;5252.868678282997;5284.233786101032;5308.840734777963;3644.2529281497227;3656.926861462756;3660.3228402745576;3663.1867225723436;3671.430928206132;3709.7943168469815;3713.087759803703;3720.162963333228;3722.40441529354;3728.12389088564;4240.448741663564;4251.893702657397;4252.29837076885;4258.233517966796;4265.402843601898;4942.505749425029;5024.773885175176;5039.269305950616;5039.748012599355;5084.77118529667;1249.5189352099508;1251.330538162079;1253.2731016010873;1257.5472829781272;1267.3536496386726;825.2737590919952;860.5697151424288;867.7657032601162;871.8692196170498;873.7810753998137;4279.786010699515;4342.34864770287;4391.8682439526865;4396.164728701537;4424.911501770011;5193.948060519347;5198.584113270929;5211.530962213419;5211.661242019257;5214.296070030573;4426.8047627140495;4435.423394958635;4458;4468.352088947149;4470.675248870569;2599.6750406199226;2682.5171469135744;2688.7042425333198;2735.097417852104;2735.261479400551;4744.217204161344;4750.477447480775;4756.548127927841;4789.185838407479;4815.0146908916695;5148.768305426265;5178.352705911761;5187.239251542602;5197.454267301954;5198.350206224222;4509;4759.952400476021;4770.403699537558;4789.281607758864;4790.257510085942;5125.308083408692;5128.7179205144075;5130.230465430215;5130.769115389891;5137.8201762938115;4900.805146544674;4937.8271760488215;4963.255511673278;4971.254311853251;4976.5023497649945;4446.911061778811;4664.883377915444;4684.812607495662;4686.437627484724;4702.670813043054;3540.937089417687;3556.6087730349122;3557.9288414232087;3572.998681107882;3575.5530558680166;5078.441371449064;5095.337606111182;5110.48895110486;5112.795488180431;5114.769835357343;4685.5782979532005;4894.535019173315;4901.632377571767;4908.7545622719435;4912.484189160086;4900.534449227461;4919.3604831371695;4919.926201107058;4924.532169443897;4933.531314525115;4867.951320486892;4877.244192090773;4878.878028049186;4891.853244402673;4898.363212782316;4714.925432809553;4814.1815891297865;4907.312976183409;4973.577245933991;4990.525900039491;4812.157872372391;4950.529699678525;4957.157283261782;4977.8257760978195;4984.576311013604 09:35:48 INFO - PROCESS | 25729 | |5;yui.html;596.4244504053587;635.2567496029646;661.4146480364876;727.1347096954623;728.9526180798249;12833;14223;14238;14238.288085595717;14246.857531424683;13104.148230365043;13250.602481925534;13264.93367533161;13276.734465310688;13382.799258011137;612.7365232949829;630.6342321453546;750.2722359311487;757.8597959377505;768.7655265144128;748.4012789768191;754.2570567990525;755.2862544895077;771.8880762289465;772.0696560644438;832.7127141136298;834.5451728807784;834.7495751274623;834.8539005673982;835.1356346181703;649.9252585952629;671.1476424940347;787.4448513797759;823.9299659528947;825.2284114353099;588.1442501160801;601.5488383712216;601.711178634258;601.9729112189962;601.997673873001;13470.393832277474;13494.527691530848;13524.86475135246;13526.864731352658;13529.797053044213;13188.538401155913;13209.207447553177;13244.53644122451;13281.86718132816;13412.329383530881;11689;11762.235454695418;11858.407079645984;12330;12413.503459861595;4315.417418648473;4434.977825110886;4449.7330160190495;4451.132029254297;4456.420665313521;63580;64438;64497;64580;67192;10435.530401131968;10744.51649675751;10785.730356741144;10938.781224375627;11014.724631884266;20162.193512259637;20165.471042567784;20177.092030858646;20257.898710506648;20314.593708125754;6689.5986240825705;6733.427658648971;6931.133608298963;6966.268541803139;6974;3590.389633762267;3703.8703645372284;3716.405375140019;3717.1450566369886;3726.8136593170775;2756.641636587231;2806.593044008607;2813.099808061401;2819.873105710248;2837.517622004264;487.63914703120145;499.2486308106277;501.7340809371039;510.5277618203147;511.93600799900014;441.0671429925684;514.7040451740264;575.3987083497755;597.3518732175638;602.3133627664466;7705.653245603961;7794.883076753853;8224.588770561448;8321.95839020801;8348.123447038095;465.2625588442357;525.4010428111956;526.6798418972332;535.5528134008147;549.5219159331407;3741.9438708419398;3911.2373087248284;3922.7646341219047;4078.4610633486514;4146.460960075171;59909.700451498335;62004.68997654983;62805.68597156985;63001;63338.36661633417;54874.451255486936;58068.70965645145;59310;59557.404425956054;60585;43054;53300;53412.46587534153;53815.46184538105;54351;8365.581720914051;9257.120573545502;9287.303452241244;9289.303302252494;9407.576659050495;29446.558301625064;30610.693893060783;33427.83286083554;33915.830420847735;35737.498375114534;39450.40824387579;40417.59582404138;43455.782721086194;44519.55480445154;44850.775746121064;11675.971789732528;12458;12608.432620531914;12687.429065691882;12723.363831808558;12144.574939877415;12491.125621206429;12910.419031143434;13024.674383140497;13619.86380136186;11939.701507462036;12125.939370303093;12378.938105309777;12531.93734031324;12831.807522886977;12255.75488490243;12276.386180691108;12541.247525148521;12629.684257893627;13148.342582870628;12346.506139754309;12507.249565026128;12613.432395542039;12648.114631975674;12929.482820687443;10200.4389758564;10223.079922806983;10302.690919272434;10433.426161561187;10575.947120264349;10357.326773759682;10569.788604228026;10575.78848423012;10633.787324253317;10639.361638301418 09:35:48 INFO - PROCESS | 25729 | __end_tp_report 09:35:48 INFO - PROCESS | 25729 | __start_cc_report 09:35:48 INFO - PROCESS | 25729 | _x_x_mozilla_cycle_collect,1230 09:35:48 INFO - PROCESS | 25729 | __end_cc_report 09:35:48 INFO - PROCESS | 25729 | __startTimestamp1482255348633__endTimestamp 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | ------- Summary: start ------- 09:35:48 INFO - PROCESS | 25729 | Number of tests: 6 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#0] dojo.html Cycles:165 Average:4684.23 Median:4794.33 stddev:500.50 (10.4%) stddev-sans-first:474.06 09:35:48 INFO - PROCESS | 25729 | Values: 2580.8 2620.6 2634.8 2658.5 2658.7 4614.1 4643.8 4767.9 4794.3 4811.0 4733.7 4742.9 4768.4 4789.1 4858.9 4241.9 4264.0 4302.8 4360.4 4436.4 4017.6 4343.2 4448.2 4480.8 4527.5 4266.3 4286.7 4407.4 4463.5 4494.0 4323.7 4353.0 4359.7 4359.8 4364.9 4336.0 4357.0 4358.3 4362.2 4375.1 4717.8 4721.1 4724.4 4733.3 4736.5 5012.0 5057.1 5057.3 5080.8 5133.6 5091.4 5093.9 5096.0 5112.2 5139.2 4597.6 4731.5 4776.0 4778.4 4826.2 5036.6 5155.6 5166.4 5166.8 5189.9 4967.9 5019.8 5020.2 5032.2 5062.7 5118.2 5121.0 5124.1 5128.8 5163.4 4854.8 4886.5 4887.7 4992.7 5064.8 4894.7 5045.9 5101.5 5114.7 5115.1 4259.1 4274.1 4275.6 4275.9 4322.9 3643.1 3758.5 3761.4 3777.3 3798.2 4260.7 4325.9 4390.3 4400.1 4405.6 5166.4 5171.8 5176.4 5187.4 5223.1 4402.2 4434.1 4438.3 4471.3 4484.6 4365.1 4415.3 4419.0 4512.2 4594.4 4718.2 4747.7 4764.4 4796.0 4796.2 5060.8 5074.7 5092.2 5098.4 5141.3 4567.6 4589.9 4734.7 4756.1 4764.5 5001.8 5008.8 5029.8 5074.4 5114.1 5086.1 5115.1 5116.3 5130.0 5173.3 4792.8 4932.2 4969.6 4993.5 4993.7 4823.0 4865.3 4896.4 4913.7 4983.1 4889.4 4889.5 4891.3 4891.4 4921.7 4984.9 4985.1 4987.7 4989.6 5040.7 4763.8 4785.7 4832.2 4860.0 4862.1 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#1] ext.html Cycles:180 Average:42057.95 Median:15766.65 stddev:74790.47 (474.4%) stddev-sans-first:74935.24 09:35:48 INFO - PROCESS | 25729 | Values: 516.3 529.1 537.3 543.8 549.5 27750.9 37033.3 38485.8 38492.8 42154.6 38579.6 38983.6 39006.0 39060.0 39073.2 7312.2 7548.1 7680.1 7711.7 7718.5 7345.6 9743.3 10008.3 10022.1 10330.5 10003.2 10064.2 10066.9 10075.6 10077.5 9971.0 10037.7 10038.0 10051.3 10054.6 9954.9 9990.5 10019.3 10019.9 10023.8 36542.8 36894.3 36898.0 36939.4 36995.3 2806.1 2845.6 2869.4 2934.7 3323.2 27400.2 27497.7 27636.3 27724.6 28277.2 3303.4 3352.2 3396.4 3420.0 3429.3 242448.0 242729.0 243935.0 244556.0 244988.0 41418.0 41469.6 41575.0 41575.8 41581.8 52012.0 52109.7 52125.7 52139.5 52141.5 13298.7 14083.2 14087.9 14109.6 14120.9 9378.0 9520.3 10451.0 10538.9 10589.4 14684.0 15306.3 15315.6 15391.3 15500.8 5700.0 5795.4 5825.6 5882.6 5883.6 704.6 739.4 825.5 825.9 840.7 26516.5 27346.7 27963.9 27994.0 28108.7 887.7 904.9 922.3 927.2 969.3 13071.6 13889.0 14384.9 14424.9 14630.0 337039.0 338211.0 339414.0 339462.0 339465.0 41217.6 41395.2 41455.8 41524.2 41602.4 1025.5 1067.7 1121.1 1123.6 1147.1 22462.4 24494.9 25384.0 25473.9 25545.6 34507.8 35037.8 35104.5 35148.0 35152.0 263117.0 263167.0 263894.0 263924.0 264878.0 22087.0 22871.5 22908.8 23978.5 24093.0 32173.0 32260.0 32781.0 32948.3 33271.7 27108.0 28457.7 28663.9 28728.9 29199.0 27804.2 28002.9 28541.7 29018.9 29076.0 27824.9 28270.9 28380.0 28610.9 28778.3 25473.9 26578.3 26617.6 26769.3 26804.9 21359.5 21526.9 21690.5 21696.8 21716.3 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#2] jquery.html Cycles:180 Average:22092.46 Median:19939.49 stddev:53839.28 (270.0%) stddev-sans-first:53965.54 09:35:48 INFO - PROCESS | 25729 | Values: 339.8 348.0 357.9 360.2 376.9 12236.8 14419.6 14433.7 15195.7 15698.2 14629.5 14882.8 14925.5 15011.0 15254.0 3858.1 4818.7 4937.2 4979.9 4981.3 3625.7 3714.8 4881.0 4929.9 5524.5 5723.1 5776.7 5803.3 5812.9 5840.0 5642.8 5701.9 5761.7 5768.6 5818.6 5103.1 5104.5 5112.3 5113.3 5116.9 19302.7 19599.9 19871.9 19988.5 20016.6 1764.1 1938.7 1999.7 2153.5 2408.6 8860.4 12085.2 13034.5 13256.3 13422.3 1704.6 1710.9 1719.7 1783.7 1785.0 65377.7 65841.7 66061.7 66505.0 67483.0 9616.1 9769.1 9801.3 9810.8 9983.3 13373.7 13397.3 13398.8 13406.7 13434.4 6774.9 6857.5 7004.4 7057.5 7103.2 6394.9 6465.6 6596.9 6620.1 6720.6 3835.1 3921.6 3927.1 3991.0 4014.9 1659.4 1716.6 1721.8 1831.8 1842.6 683.7 768.9 812.7 855.4 891.9 15825.9 16707.2 16847.7 17079.7 17125.1 780.3 825.9 832.2 833.3 879.3 9074.5 9088.0 9420.1 9421.6 9569.9 265940.0 269545.0 352628.0 356281.0 358120.0 77293.0 79700.6 79763.9 79958.0 80830.6 886.4 909.7 913.1 942.1 944.7 8901.8 9494.3 9588.8 9610.9 9773.9 34158.5 36123.0 36253.3 36914.1 37145.3 42882.8 43185.4 44023.0 44564.6 44697.8 10823.2 10887.6 10943.7 11024.3 11091.7 11858.2 11895.4 12040.9 12350.4 12869.4 10480.0 10560.9 10651.6 10818.2 10859.6 10324.2 10883.6 10886.2 10981.7 11090.6 10276.4 10793.5 11203.9 11280.5 11371.4 10266.9 10532.6 10881.8 10959.4 11249.4 10437.3 10963.5 10980.5 11035.0 11146.7 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#3] mootools.html Cycles:180 Average:4727.16 Median:5946.63 stddev:2550.35 (42.9%) stddev-sans-first:2543.96 09:35:48 INFO - PROCESS | 25729 | Values: 1230.4 1240.0 1280.2 1627.7 1658.0 6652.3 7176.3 7195.5 7196.6 7198.6 6320.4 6445.9 6755.2 6834.8 6838.1 2049.0 2083.9 2085.5 2086.6 2088.2 2071.7 2113.7 2115.4 2121.6 2124.5 1816.9 1864.8 2012.7 2071.8 2072.9 2096.2 2114.7 2122.2 2127.8 2134.1 2118.2 2135.2 2136.9 2140.0 2140.9 7068.8 7118.0 7219.4 7225.0 7238.3 4325.6 4419.2 4451.4 4455.2 4459.0 2709.1 2858.6 2866.1 2875.7 2887.9 818.9 877.1 905.9 912.2 913.6 9588.5 9659.0 9693.2 9699.6 9701.7 3756.5 3978.5 4049.4 4060.2 4064.6 8241.4 8284.6 8320.4 8325.8 8327.7 4403.4 4455.2 4492.9 4495.1 4500.3 3944.5 4086.3 4087.8 4094.4 4100.2 3226.0 3287.3 3347.1 3356.1 3374.4 977.3 982.6 984.9 987.5 989.4 575.6 715.3 817.4 839.3 862.1 4872.6 5042.8 5098.6 5120.7 5146.7 715.8 758.8 795.4 810.9 843.3 3398.4 3435.2 3482.3 3491.7 3521.4 8417.9 8655.3 8714.6 8726.9 8736.0 8626.6 8642.7 8644.2 8651.3 8652.2 6418.6 6434.8 6525.0 6788.6 6800.5 4902.6 4973.8 5058.5 5063.1 5254.1 6979.0 7765.8 7927.8 7944.1 7959.0 8021.2 8074.8 8120.8 8192.2 8210.1 6220.8 6318.7 6333.7 6349.0 6351.3 6314.1 6365.7 6376.1 6497.2 6606.2 6228.2 6242.3 6272.9 6321.2 6382.7 6109.2 6141.8 6184.1 6205.4 6291.9 5357.1 5367.6 5432.3 5682.5 5784.0 4329.8 4418.5 4476.6 4525.7 4530.1 4377.5 4454.1 4496.0 4511.1 4582.6 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#4] prototype.html Cycles:180 Average:4150.19 Median:4686.58 stddev:1183.86 (25.3%) stddev-sans-first:1152.29 09:35:48 INFO - PROCESS | 25729 | Values: 349.4 357.9 359.2 365.6 365.9 4794.9 4797.6 4807.9 4831.3 4851.8 4553.5 4786.3 4792.4 4799.7 4806.6 3737.3 3759.0 3806.7 3843.8 3880.3 3852.9 3863.0 3865.3 3878.4 3884.2 3678.9 3841.3 3841.7 3844.9 3869.7 3574.0 3723.2 3754.6 3769.2 3881.8 3460.2 3471.0 3472.0 3474.8 3513.1 4655.9 4679.6 4681.3 4684.6 4686.7 4080.2 4246.2 4270.2 4415.8 4424.2 4969.4 4981.2 5006.8 5007.7 5019.7 3553.8 3556.7 3558.8 3570.5 3579.3 5041.6 5237.2 5252.9 5284.2 5308.8 3644.3 3656.9 3660.3 3663.2 3671.4 3709.8 3713.1 3720.2 3722.4 3728.1 4240.4 4251.9 4252.3 4258.2 4265.4 4942.5 5024.8 5039.3 5039.7 5084.8 1249.5 1251.3 1253.3 1257.5 1267.4 825.3 860.6 867.8 871.9 873.8 4279.8 4342.3 4391.9 4396.2 4424.9 5193.9 5198.6 5211.5 5211.7 5214.3 4426.8 4435.4 4458.0 4468.4 4470.7 2599.7 2682.5 2688.7 2735.1 2735.3 4744.2 4750.5 4756.5 4789.2 4815.0 5148.8 5178.4 5187.2 5197.5 5198.4 4509.0 4760.0 4770.4 4789.3 4790.3 5125.3 5128.7 5130.2 5130.8 5137.8 4900.8 4937.8 4963.3 4971.3 4976.5 4446.9 4664.9 4684.8 4686.4 4702.7 3540.9 3556.6 3557.9 3573.0 3575.6 5078.4 5095.3 5110.5 5112.8 5114.8 4685.6 4894.5 4901.6 4908.8 4912.5 4900.5 4919.4 4919.9 4924.5 4933.5 4868.0 4877.2 4878.9 4891.9 4898.4 4714.9 4814.2 4907.3 4973.6 4990.5 4812.2 4950.5 4957.2 4977.8 4984.6 09:35:48 INFO - PROCESS | 25729 | 09:35:48 INFO - PROCESS | 25729 | [#5] yui.html Cycles:180 Average:14939.96 Median:4112.46 stddev:18074.47 (439.5%) stddev-sans-first:18093.08 09:35:48 INFO - PROCESS | 25729 | Values: 596.4 635.3 661.4 727.1 729.0 12833.0 14223.0 14238.0 14238.3 14246.9 13104.1 13250.6 13264.9 13276.7 13382.8 612.7 630.6 750.3 757.9 768.8 748.4 754.3 755.3 771.9 772.1 832.7 834.5 834.7 834.9 835.1 649.9 671.1 787.4 823.9 825.2 588.1 601.5 601.7 602.0 602.0 13470.4 13494.5 13524.9 13526.9 13529.8 13188.5 13209.2 13244.5 13281.9 13412.3 11689.0 11762.2 11858.4 12330.0 12413.5 4315.4 4435.0 4449.7 4451.1 4456.4 63580.0 64438.0 64497.0 64580.0 67192.0 10435.5 10744.5 10785.7 10938.8 11014.7 20162.2 20165.5 20177.1 20257.9 20314.6 6689.6 6733.4 6931.1 6966.3 6974.0 3590.4 3703.9 3716.4 3717.1 3726.8 2756.6 2806.6 2813.1 2819.9 2837.5 487.6 499.2 501.7 510.5 511.9 441.1 514.7 575.4 597.4 602.3 7705.7 7794.9 8224.6 8322.0 8348.1 465.3 525.4 526.7 535.6 549.5 3741.9 3911.2 3922.8 4078.5 4146.5 59909.7 62004.7 62805.7 63001.0 63338.4 54874.5 58068.7 59310.0 59557.4 60585.0 43054.0 53300.0 53412.5 53815.5 54351.0 8365.6 9257.1 9287.3 9289.3 9407.6 29446.6 30610.7 33427.8 33915.8 35737.5 39450.4 40417.6 43455.8 44519.6 44850.8 11676.0 12458.0 12608.4 12687.4 12723.4 12144.6 12491.1 12910.4 13024.7 13619.9 11939.7 12125.9 12378.9 12531.9 12831.8 12255.8 12276.4 12541.2 12629.7 13148.3 12346.5 12507.2 12613.4 12648.1 12929.5 10200.4 10223.1 10302.7 10433.4 10575.9 10357.3 10569.8 10575.8 10633.8 10639.4 09:35:48 INFO - PROCESS | 25729 | -------- Summary: end -------- 09:35:48 INFO - PROCESS | 25729 | 09:35:49 INFO - TEST-INFO | 25729: exit 0 09:35:49 INFO - TEST-OK | dromaeo_css | took 1122094ms 09:35:49 INFO - TEST-START | kraken 09:35:49 INFO - operating with platform_type : linux_ 09:35:49 INFO - Initialising browser for kraken test... 09:35:49 INFO - TEST-INFO | started process 26034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphDQ8UJ/profile http://localhost:34027/getInfo.html) 09:35:53 INFO - PROCESS | 26034 | __metrics Screen width/height:1600/1200 09:35:53 INFO - PROCESS | 26034 | colorDepth:24 09:35:53 INFO - PROCESS | 26034 | Browser inner width/height: 1024/697 09:35:53 INFO - PROCESS | 26034 | __metrics 09:35:54 INFO - PROCESS | 26034 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:35:54 INFO - PROCESS | 26034 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:54 INFO - PROCESS | 26034 | console.error: PushService: 09:35:54 INFO - PROCESS | 26034 | stateChangeProcessEnqueue: Error transitioning state 09:35:54 INFO - PROCESS | 26034 | UnknownError 09:35:55 INFO - TEST-INFO | 26034: exit 0 09:35:55 INFO - Browser initialized. 09:35:55 INFO - Running cycle 1/1 for kraken test... 09:35:55 INFO - TEST-INFO | started process 26117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphDQ8UJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:07 INFO - PROCESS | 26117 | RSS: Main: 155766784 09:36:07 INFO - PROCESS | 26117 | 09:36:10 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34027/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:11 INFO - PROCESS | 26117 | RSS: Main: 209801216 09:36:11 INFO - PROCESS | 26117 | 09:36:13 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34027/tests/kraken/driver.html?testName=audio-dft) 09:36:14 INFO - PROCESS | 26117 | RSS: Main: 187600896 09:36:14 INFO - PROCESS | 26117 | 09:36:17 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34027/tests/kraken/driver.html?testName=audio-fft) 09:36:18 INFO - PROCESS | 26117 | RSS: Main: 269897728 09:36:18 INFO - PROCESS | 26117 | 09:36:20 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34027/tests/kraken/driver.html?testName=audio-oscillator) 09:36:21 INFO - PROCESS | 26117 | RSS: Main: 180957184 09:36:21 INFO - PROCESS | 26117 | 09:36:23 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34027/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:23 INFO - PROCESS | 26117 | RSS: Main: 184578048 09:36:23 INFO - PROCESS | 26117 | 09:36:27 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34027/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:27 INFO - PROCESS | 26117 | RSS: Main: 224337920 09:36:27 INFO - PROCESS | 26117 | 09:36:31 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34027/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:31 INFO - PROCESS | 26117 | RSS: Main: 218509312 09:36:31 INFO - PROCESS | 26117 | 09:36:34 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34027/tests/kraken/driver.html?testName=json-parse-financial) 09:36:35 INFO - PROCESS | 26117 | RSS: Main: 220016640 09:36:35 INFO - PROCESS | 26117 | 09:36:37 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34027/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:37 INFO - PROCESS | 26117 | RSS: Main: 183492608 09:36:37 INFO - PROCESS | 26117 | 09:36:39 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:40 INFO - PROCESS | 26117 | RSS: Main: 190816256 09:36:40 INFO - PROCESS | 26117 | 09:36:42 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:43 INFO - PROCESS | 26117 | RSS: Main: 199192576 09:36:43 INFO - PROCESS | 26117 | 09:36:45 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:46 INFO - PROCESS | 26117 | RSS: Main: 207450112 09:36:46 INFO - PROCESS | 26117 | 09:36:49 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:50 INFO - PROCESS | 26117 | RSS: Main: 204333056 09:36:50 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | Cycle 1(1): loaded http://localhost:34027/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34027/tests/kraken/driver.html?testName=ai-astar) 09:36:52 INFO - PROCESS | 26117 | RSS: Main: 256745472 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | __start_tp_report 09:36:52 INFO - PROCESS | 26117 | _x_x_mozilla_page_load 09:36:52 INFO - PROCESS | 26117 | _x_x_mozilla_page_load_details 09:36:52 INFO - PROCESS | 26117 | |i|pagename|runs| 09:36:52 INFO - PROCESS | 26117 | |0;ai-astar;106;117;118;116;117;115;115;117;114;114 09:36:52 INFO - PROCESS | 26117 | |1;audio-beat-detection;156;124;132;167;131;129;126;130;156;125 09:36:52 INFO - PROCESS | 26117 | |2;audio-dft;161;160;163;163;165;163;163;162;160;162 09:36:52 INFO - PROCESS | 26117 | |3;audio-fft;75;76;75;109;77;77;76;75;93;75 09:36:52 INFO - PROCESS | 26117 | |4;audio-oscillator;84;86;108;86;85;91;101;85;85;110 09:36:52 INFO - PROCESS | 26117 | |5;imaging-gaussian-blur;127;127;127;127;127;126;127;127;128;129 09:36:52 INFO - PROCESS | 26117 | |6;imaging-darkroom;147;147;147;147;146;146;147;147;146;146 09:36:52 INFO - PROCESS | 26117 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;96 09:36:52 INFO - PROCESS | 26117 | |8;json-parse-financial;66;64;65;63;65;66;64;64;65;64 09:36:52 INFO - PROCESS | 26117 | |9;json-stringify-tinderbox;66;67;66;67;66;68;67;67;66;67 09:36:52 INFO - PROCESS | 26117 | |10;stanford-crypto-aes;84;84;87;93;85;90;83;85;84;84 09:36:52 INFO - PROCESS | 26117 | |11;stanford-crypto-ccm;145;134;136;135;136;134;133;142;183;135 09:36:52 INFO - PROCESS | 26117 | |12;stanford-crypto-pbkdf2;180;177;177;178;179;179;177;175;184;179 09:36:52 INFO - PROCESS | 26117 | |13;stanford-crypto-sha256-iterative;68;76;68;67;69;68;68;68;67;66 09:36:52 INFO - PROCESS | 26117 | __end_tp_report 09:36:52 INFO - PROCESS | 26117 | __start_cc_report 09:36:52 INFO - PROCESS | 26117 | _x_x_mozilla_cycle_collect,1946 09:36:52 INFO - PROCESS | 26117 | __end_cc_report 09:36:52 INFO - PROCESS | 26117 | __startTimestamp1482255412434__endTimestamp 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | ------- Summary: start ------- 09:36:52 INFO - PROCESS | 26117 | Number of tests: 14 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#0] ai-astar Cycles:10 Average:114.90 Median:116.50 stddev:3.41 (2.9%) stddev-sans-first:1.45 09:36:52 INFO - PROCESS | 26117 | Values: 106.0 117.0 118.0 116.0 117.0 115.0 115.0 117.0 114.0 114.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:131.50 stddev:15.73 (12.0%) stddev-sans-first:15.21 09:36:52 INFO - PROCESS | 26117 | Values: 156.0 124.0 132.0 167.0 131.0 129.0 126.0 130.0 156.0 125.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#2] audio-dft Cycles:10 Average:162.20 Median:163.00 stddev:1.55 (1.0%) stddev-sans-first:1.58 09:36:52 INFO - PROCESS | 26117 | Values: 161.0 160.0 163.0 163.0 165.0 163.0 163.0 162.0 160.0 162.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:11.32 (14.9%) stddev-sans-first:11.81 09:36:52 INFO - PROCESS | 26117 | Values: 75.0 76.0 75.0 109.0 77.0 77.0 76.0 75.0 93.0 75.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:85.00 stddev:10.25 (12.1%) stddev-sans-first:10.44 09:36:52 INFO - PROCESS | 26117 | Values: 84.0 86.0 108.0 86.0 85.0 91.0 101.0 85.0 85.0 110.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#5] imaging-gaussian-blur Cycles:10 Average:127.20 Median:127.00 stddev:0.79 (0.6%) stddev-sans-first:0.83 09:36:52 INFO - PROCESS | 26117 | Values: 127.0 127.0 127.0 127.0 127.0 126.0 127.0 127.0 128.0 129.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:36:52 INFO - PROCESS | 26117 | Values: 147.0 147.0 147.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:36:52 INFO - PROCESS | 26117 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:0.97 (1.5%) stddev-sans-first:0.88 09:36:52 INFO - PROCESS | 26117 | Values: 66.0 64.0 65.0 63.0 65.0 66.0 64.0 64.0 65.0 64.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 09:36:52 INFO - PROCESS | 26117 | Values: 66.0 67.0 66.0 67.0 66.0 68.0 67.0 67.0 66.0 67.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:85.00 stddev:3.21 (3.8%) stddev-sans-first:3.33 09:36:52 INFO - PROCESS | 26117 | Values: 84.0 84.0 87.0 93.0 85.0 90.0 83.0 85.0 84.0 84.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:136.00 stddev:15.14 (11.1%) stddev-sans-first:16.00 09:36:52 INFO - PROCESS | 26117 | Values: 145.0 134.0 136.0 135.0 136.0 134.0 133.0 142.0 183.0 135.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:179.00 stddev:2.42 (1.3%) stddev-sans-first:2.50 09:36:52 INFO - PROCESS | 26117 | Values: 180.0 177.0 177.0 178.0 179.0 179.0 177.0 175.0 184.0 179.0 09:36:52 INFO - PROCESS | 26117 | 09:36:52 INFO - PROCESS | 26117 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.76 (4.1%) stddev-sans-first:2.92 09:36:52 INFO - PROCESS | 26117 | Values: 68.0 76.0 68.0 67.0 69.0 68.0 68.0 68.0 67.0 66.0 09:36:52 INFO - PROCESS | 26117 | -------- Summary: end -------- 09:36:52 INFO - PROCESS | 26117 | 09:36:53 INFO - TEST-INFO | 26117: exit 0 09:36:53 INFO - TEST-OK | kraken | took 64127ms 09:36:53 INFO - SUITE-END | took 1186s 09:36:54 INFO - Completed test suite (00:19:46) 09:36:54 INFO - javascript benchmark 09:36:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2580.793536517079, 2620.646212761279, 2634.7628713415793, 2658.5347564176263, 2658.7208343123984, 4614.077184563084, 4643.7910294036765, 4767.928481072782, 4794.328793968843, 4810.999999999995, 4733.71597704138, 4742.90514189716, 4768.380110585619, 4789.090072886149, 4858.902821943568, 4241.936370954438, 4264.0405908670455, 4302.784860756965, 4360.433143691332, 4436.378906953029, 4017.645762719817, 4343.239933011726, 4448.243798554254, 4480.775961201944, 4527.524609915944, 4266.338717498793, 4286.678499112579, 4407.427034485497, 4463.509014008457, 4493.9550604494025, 4323.697341186087, 4353.0, 4359.716618419824, 4359.84740534083, 4364.912701745979, 4336.024394511242, 4356.956430435686, 4358.324459708719, 4362.17118747437, 4375.124975005012, 4717.787699553528, 4721.079389519045, 4724.433068031858, 4733.290006499018, 4736.502667219961, 5011.974940125312, 5057.115004874132, 5057.342545469102, 5080.822171223989, 5133.6149788765615, 5091.363579552556, 5093.949060509384, 5096.031753966771, 5112.233165025276, 5139.229115632647, 4597.586217240464, 4731.455882573479, 4776.020915712271, 4778.354922085508, 4826.155422801066, 5036.597072234213, 5155.561777248976, 5166.43169251382, 5166.819161329336, 5189.948100519022, 4967.850964471072, 5019.824306149268, 5020.246962955513, 5032.220005899091, 5062.7215503146945, 5118.155504341742, 5121.0014047261175, 5124.103281925648, 5128.820491282787, 5163.3545806774155, 4854.757262136879, 4886.462489126193, 4887.706737595756, 4992.650514464025, 5064.848054558369, 4894.657373983787, 5045.949540504622, 5101.540861322499, 5114.6931184128325, 5115.0792857285305, 4259.105587826592, 4274.081072569413, 4275.572442755762, 4275.850345237902, 4322.8703138905885, 3643.107438677541, 3758.5489741231227, 3761.360568703327, 3777.320082385197, 3798.2023775007556, 4260.701750877409, 4325.870223893288, 4390.319500477431, 4400.119976004812, 4405.647548196137, 5166.4058633257655, 5171.793128274827, 5176.404713457925, 5187.403448603459, 5223.112070947948, 4402.229609818333, 4434.068845542472, 4438.312061630452, 4471.28459446487, 4484.618807401343, 4365.148795984753, 4415.315626077963, 4419.0, 4512.187806194916, 4594.402727645384, 4718.174319494006, 4747.738874361943, 4764.380630518011, 4796.016816552528, 4796.208625576741, 5060.772265247999, 5074.746262686777, 5092.159793634009, 5098.439171691037, 5141.28022076917, 4567.566081222279, 4589.862304130881, 4734.73958932262, 4756.143894099096, 4764.49972752856, 5001.799928002839, 5008.799648014185, 5029.7988080477835, 5074.3657042869645, 5114.053900028507, 5086.0845047891735, 5115.130427827204, 5116.334876466036, 5130.025295193901, 5173.301604283524, 4792.7603619819565, 4932.161532539405, 4969.577585905238, 4993.4756850530175, 4993.675411098412, 4822.95177048225, 4865.318855360323, 4896.363472748662, 4913.729744864067, 4983.103041452573, 4889.3888263967, 4889.486603906681, 4891.26921374689, 4891.413030436371, 4921.729304888122, 4984.850454486371, 4985.102681517216, 4987.725675087905, 4989.600831933364, 5040.672356296829, 4763.833265835818, 4785.712857228577, 4832.226843704991, 4860.0279944012045, 4862.05189987949], "value": 4651.889156279494, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.2643233392415, 529.11637565266, 537.3417563484729, 543.8368489453164, 549.5164255455202, 27750.86124569377, 37033.25933481332, 38485.807570962104, 38492.80753596232, 42154.57845421545, 38579.61420385796, 38983.61016389835, 39006.0, 39060.0, 39073.21853562927, 7312.232215617363, 7548.094228692565, 7680.078390593108, 7711.652975616083, 7718.498297610666, 7345.559266444004, 9743.317967742225, 10008.349457285289, 10022.14590300793, 10330.535125919314, 10003.199744020461, 10064.194864410829, 10066.899331006705, 10075.596976120984, 10077.546510407012, 9971.0, 10037.749056273578, 10038.0, 10051.296409251356, 10054.64808731691, 9954.90045099547, 9990.45052522111, 10019.348742331726, 10019.949900250453, 10023.849642255373, 36542.81728591367, 36894.262114757825, 36898.0, 36939.4459083114, 36995.26009479795, 2806.0880213930554, 2845.558938364557, 2869.4404591104744, 2934.6918573549683, 3323.1858194742445, 27400.177109141052, 27497.725022749917, 27636.30909227265, 27724.584131238047, 28277.15168544947, 3303.3723592517345, 3352.2122301259183, 3396.4056290149374, 3420.0423881313022, 3429.2969941162, 242448.0, 242729.0, 243935.0, 244556.0, 244988.0, 41418.0, 41469.585304146574, 41575.0, 41575.792121039805, 41581.79209103935, 52012.0, 52109.7394513025, 52125.7393713029, 52139.47860521422, 52141.47858521366, 13298.73402531944, 14083.155010699185, 14087.85912140886, 14109.647258818612, 14120.85879141216, 9377.95311023454, 9520.333576649567, 10451.0, 10538.947305263579, 10589.364638121737, 14684.0, 15306.311215995307, 15315.61710957235, 15391.307391167424, 15500.767488487456, 5699.971500142473, 5795.362510123883, 5825.592208545446, 5882.588218824727, 5883.558733094949, 704.5631708340861, 739.3678404963679, 825.5046971816981, 825.8637324841334, 840.7435732101768, 26516.469670606864, 27346.726532734418, 27963.860180698965, 27994.0, 28108.71891281102, 887.7425546591545, 904.8914130304274, 922.2898368256405, 927.2072378116748, 969.3021024862088, 13071.607851764462, 13889.0, 14384.928075359556, 14424.927875360765, 14630.00084982576, 337039.0, 338211.0, 339414.0, 339462.0, 339465.0, 41217.587824121976, 41395.172096558505, 41455.7927210362, 41524.169516610105, 41602.375964359955, 1025.5487585462372, 1067.6636859389423, 1121.1031175060082, 1123.5561953028507, 1147.0708725931854, 22462.438439039153, 24494.877525612257, 25384.04756204586, 25473.872630636728, 25545.61681574815, 34507.827460862536, 35037.8248108768, 35104.47343289802, 35148.0, 35152.0, 263117.0, 263167.0, 263894.0, 263924.0, 264878.0, 22087.0, 22871.542569148856, 22908.770912290664, 23978.52042959166, 24093.0, 32173.0, 32260.0, 32781.0, 32948.34103317872, 33271.66728332783, 27108.0, 28457.715422846333, 28663.856680717297, 28728.856355718086, 29199.0, 27804.16587502378, 28002.859985700758, 28541.714582853907, 29018.854905725337, 29076.0, 27824.860875695493, 28270.85864570664, 28380.0, 28610.85694571514, 28778.280542985754, 25473.872630636728, 26578.335541611614, 26617.600735988588, 26769.33076673099, 26804.86597567078, 21359.46601334979, 21526.892365538697, 21690.457738556663, 21696.783032169475, 21716.34850954474], "value": 16226.691402895403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.8453703564878, 347.9704225140863, 357.90157706630674, 360.19855820798716, 376.89446954852633, 12236.755264894708, 14419.639509012266, 14433.711325773478, 15195.6960860783, 15698.215089245548, 14629.487967921124, 14882.776758348633, 14925.477608283712, 15011.0, 15254.0, 3858.054776579742, 4818.662693611449, 4937.23472861707, 4979.875503112433, 4981.327520784702, 3625.7099432045507, 3714.7585406948597, 4881.0, 4929.926051109236, 5524.502794748471, 5723.141528770676, 5776.740046697888, 5803.303603567578, 5812.883742325151, 5840.036393994986, 5642.830715078534, 5701.885962280772, 5761.683107429089, 5768.596198266123, 5818.6217895836635, 5103.1324674805, 5104.489551044903, 5112.284280200774, 5113.258577506241, 5116.948830511722, 19302.71045934312, 19599.90200049005, 19871.90064049685, 19988.500287492785, 20016.59966800656, 1764.1179410294853, 1938.6995015772436, 1999.6500612392917, 2153.5262242306667, 2408.5544174327806, 8860.424072435271, 12085.15403921726, 13034.543790967366, 13256.270905100215, 13422.261775602337, 1704.616461296211, 1710.931562737489, 1719.7248440249625, 1783.7235228539466, 1784.9910750446295, 65377.67311163414, 65841.67079164574, 66061.66969165171, 66505.0, 67483.0, 9616.134547890724, 9769.120779129913, 9801.313908026512, 9810.80378392428, 9983.301168918106, 13373.732525349438, 13397.263150526815, 13398.799018014737, 13406.731865362834, 13434.395452204673, 6774.898376524356, 6857.5199736018, 7004.439644828503, 7057.541259818096, 7103.183133939559, 6394.904076438857, 6465.579737317059, 6596.86806263872, 6620.106285651472, 6720.596764194164, 3835.137094153849, 3921.647051765298, 3927.0771368728324, 3990.9623497890757, 4014.8594799181888, 1659.402615058578, 1716.5708572856786, 1721.7761690980092, 1831.7985021647607, 1842.5854182808762, 683.6820878291519, 768.8577613141475, 812.6789917982364, 855.4482358878488, 891.9375643705008, 15825.920870395805, 16707.164641767864, 16847.74728379075, 17079.743803842954, 17125.14374281306, 780.3484090784259, 825.8885050518103, 832.1844592299581, 833.3208435125354, 879.349281531662, 9074.504511259325, 9087.954560227157, 9420.057994200663, 9421.623135074657, 9569.856452153223, 265940.0, 269545.0, 352628.0, 356281.0, 358120.0, 77293.0, 79700.60149699214, 79763.88451915428, 79958.0, 80830.59584702039, 886.3839631456076, 909.736176508805, 913.0686699566405, 942.1379437814323, 944.6929747831982, 8901.821963560822, 9494.335396522454, 9588.808223835345, 9610.903890961281, 9773.85339219898, 34158.487622685185, 36123.0, 36253.27493450064, 36914.07714807151, 37145.257094857414, 42882.78558607312, 43185.3522197161, 44023.0, 44564.55435445604, 44697.776511117234, 10823.242373033812, 10887.642525733509, 10943.726406839893, 11024.283421577571, 11091.72270693239, 11858.229215100991, 11895.405229738652, 12040.939795300967, 12350.38248087574, 12869.35653217354, 10480.0, 10560.894391055992, 10651.57393704243, 10818.1886358525, 10859.619913302997, 10324.174066074846, 10883.564657413615, 10886.23796334249, 10981.725456863642, 11090.556377745123, 10276.434796086287, 10793.460326983777, 11203.887961120285, 11280.492377842853, 11371.374574398487, 10266.948665256627, 10532.631357902437, 10881.836772448578, 10959.397233152253, 11249.437528123724, 10437.32157409766, 10963.451827408757, 10980.505877235384, 11035.0, 11146.721331966766], "value": 8087.437644739436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1230.4032544216054, 1239.9690007749805, 1280.2446556531645, 1627.6663284026777, 1657.9834201657986, 6652.301508341627, 7176.282371762821, 7195.53229040112, 7196.640167991599, 7198.604076775789, 6320.367963203677, 6445.8710825783455, 6755.1556055493065, 6834.760783372583, 6838.14523184602, 2048.9692654610158, 2083.9374818755464, 2085.4786303424144, 2086.6452703040445, 2088.237793205484, 2071.730675012254, 2113.651247544161, 2115.3548167808813, 2121.596896589651, 2124.54322320701, 1816.9000704961222, 1864.766904136983, 2012.667909794882, 2071.834253259743, 2072.8549001569895, 2096.1929657081982, 2114.714513540683, 2122.214780531198, 2127.755308139568, 2134.082344591825, 2118.1633254864396, 2135.2206444647745, 2136.8504204705678, 2139.978600214009, 2140.8929553522416, 7068.823279418004, 7117.964410177968, 7219.422446204291, 7225.024621676059, 7238.312360325761, 4325.567443255681, 4419.18245124653, 4451.399061126739, 4455.1535208310315, 4459.019015816515, 2709.146618815067, 2858.5997960285367, 2866.054202113298, 2875.6692980307116, 2887.9422411551864, 818.9344852411792, 877.1316396767155, 905.855063189893, 912.1790388650202, 913.5934509143434, 9588.472634005127, 9659.034096590354, 9693.22454203662, 9699.563519641633, 9701.66044188457, 3756.5492140942715, 3978.5225772906856, 4049.352103663459, 4060.167665628539, 4064.5935406459116, 8241.381896357678, 8284.585770711441, 8320.417570769989, 8325.750227493185, 8327.66689332432, 4403.383526306319, 4455.1980643484485, 4492.932606010912, 4495.078508905666, 4500.347449619787, 3944.467496887941, 4086.346184610448, 4087.7956102195367, 4094.447249621263, 4100.2414553307735, 3225.951610725841, 3287.3260981498734, 3347.062822409729, 3356.0938546592283, 3374.426347520878, 977.3158788847693, 982.5627595719836, 984.9162821160138, 987.5407935310116, 989.4063561862824, 575.6143481927481, 715.2561336210283, 817.3747083481142, 839.341117222977, 862.1077185113378, 4872.561469467765, 5042.773075211624, 5098.643094983316, 5120.692758434431, 5146.742662866842, 715.7566427414704, 758.8102974256436, 795.4074214710078, 810.8580998325269, 843.2663582683105, 3398.354312680632, 3435.209901722618, 3482.2861313430685, 3491.720662347006, 3521.4189658706537, 8417.915820841714, 8655.307575393954, 8714.564271786385, 8726.86909696355, 8736.0, 8626.611802468904, 8642.654293828302, 8644.178803013829, 8651.264642505332, 8652.221300082896, 6418.582792118497, 6434.839129021719, 6525.0, 6788.626625535643, 6800.489963252776, 4902.607791376753, 4973.776180071833, 5058.519440653132, 5063.113955057924, 5254.054270231395, 6979.0, 7765.7670269892, 7927.762167134995, 7944.086430060386, 7958.960205198937, 8021.197880212166, 8074.83850323002, 8120.796980075309, 8192.221738934792, 8210.055843578066, 6220.7511699531515, 6318.684065796784, 6333.65164915934, 6349.0476428536085, 6351.2696039956, 6314.147590075465, 6365.745370185326, 6376.075469056868, 6497.155369801895, 6606.240282367589, 6228.22147231596, 6242.3133455320785, 6272.905906411499, 6321.241451025722, 6382.68086595659, 6109.20580324555, 6141.7543298267565, 6184.072389141485, 6205.379462053758, 6291.874162516816, 5357.089294819812, 5367.624266301322, 5432.320959880015, 5682.488576028178, 5784.045632470596, 4329.826806927687, 4418.4918734345965, 4476.597106260452, 4525.728456292633, 4530.139273538017, 4377.496587892306, 4454.109178164315, 4496.0, 4511.05267893746, 4582.587567118975], "value": 3843.0145356730577, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.3519521288011, 357.89442114576207, 359.1828589957847, 365.5850609558151, 365.8518300088465, 4794.904101917959, 4797.641650844405, 4807.879803004922, 4831.275308703696, 4851.781669824857, 4553.521880202581, 4786.329913812069, 4792.353032340633, 4799.664023518355, 4806.591439727618, 3737.2712321097397, 3759.0226541099237, 3806.6954643628583, 3843.7693738375647, 3880.3403421418425, 3852.8809154964656, 3862.9613703863024, 3865.323568375537, 3878.4182372644045, 3884.164904545522, 3678.926421471569, 3841.3277676406656, 3841.654251117413, 3844.942325865114, 3869.6517313441786, 3574.0, 3723.1809002019513, 3754.5869954305003, 3769.246150769857, 3881.7670939743707, 3460.2387474755515, 3471.0041726007394, 3472.0, 3474.8436320365645, 3513.1217195701074, 4655.906881862344, 4679.566625354831, 4681.274402467623, 4684.594621613536, 4686.718796872198, 4080.204360149772, 4246.214450326699, 4270.231358355495, 4415.757133357664, 4424.225760491901, 4969.378827646544, 4981.178105612606, 5006.824761133343, 5007.699538027694, 5019.673721208146, 3553.751237413382, 3556.715462762973, 3558.839852206631, 3570.4822800694014, 3579.3199292134673, 5041.621878359101, 5237.16205407133, 5252.868678282997, 5284.233786101032, 5308.840734777963, 3644.2529281497227, 3656.926861462756, 3660.3228402745576, 3663.1867225723436, 3671.430928206132, 3709.7943168469815, 3713.087759803703, 3720.162963333228, 3722.40441529354, 3728.12389088564, 4240.448741663564, 4251.893702657397, 4252.29837076885, 4258.233517966796, 4265.402843601898, 4942.505749425029, 5024.773885175176, 5039.269305950616, 5039.748012599355, 5084.77118529667, 1249.5189352099508, 1251.330538162079, 1253.2731016010873, 1257.5472829781272, 1267.3536496386726, 825.2737590919952, 860.5697151424288, 867.7657032601162, 871.8692196170498, 873.7810753998137, 4279.786010699515, 4342.34864770287, 4391.8682439526865, 4396.164728701537, 4424.911501770011, 5193.948060519347, 5198.584113270929, 5211.530962213419, 5211.661242019257, 5214.296070030573, 4426.8047627140495, 4435.423394958635, 4458.0, 4468.352088947149, 4470.675248870569, 2599.6750406199226, 2682.5171469135744, 2688.7042425333198, 2735.097417852104, 2735.261479400551, 4744.217204161344, 4750.477447480775, 4756.548127927841, 4789.185838407479, 4815.0146908916695, 5148.768305426265, 5178.352705911761, 5187.239251542602, 5197.454267301954, 5198.350206224222, 4509.0, 4759.952400476021, 4770.403699537558, 4789.281607758864, 4790.257510085942, 5125.308083408692, 5128.7179205144075, 5130.230465430215, 5130.769115389891, 5137.8201762938115, 4900.805146544674, 4937.8271760488215, 4963.255511673278, 4971.254311853251, 4976.5023497649945, 4446.911061778811, 4664.883377915444, 4684.812607495662, 4686.437627484724, 4702.670813043054, 3540.937089417687, 3556.6087730349122, 3557.9288414232087, 3572.998681107882, 3575.5530558680166, 5078.441371449064, 5095.337606111182, 5110.48895110486, 5112.795488180431, 5114.769835357343, 4685.5782979532005, 4894.535019173315, 4901.632377571767, 4908.7545622719435, 4912.484189160086, 4900.534449227461, 4919.3604831371695, 4919.926201107058, 4924.532169443897, 4933.531314525115, 4867.951320486892, 4877.244192090773, 4878.878028049186, 4891.853244402673, 4898.363212782316, 4714.925432809553, 4814.1815891297865, 4907.312976183409, 4973.577245933991, 4990.525900039491, 4812.157872372391, 4950.529699678525, 4957.157283261782, 4977.8257760978195, 4984.576311013604], "value": 3789.6743475153676, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.4244504053587, 635.2567496029646, 661.4146480364876, 727.1347096954623, 728.9526180798249, 12833.0, 14223.0, 14238.0, 14238.288085595717, 14246.857531424683, 13104.148230365043, 13250.602481925534, 13264.93367533161, 13276.734465310688, 13382.799258011137, 612.7365232949829, 630.6342321453546, 750.2722359311487, 757.8597959377505, 768.7655265144128, 748.4012789768191, 754.2570567990525, 755.2862544895077, 771.8880762289465, 772.0696560644438, 832.7127141136298, 834.5451728807784, 834.7495751274623, 834.8539005673982, 835.1356346181703, 649.9252585952629, 671.1476424940347, 787.4448513797759, 823.9299659528947, 825.2284114353099, 588.1442501160801, 601.5488383712216, 601.711178634258, 601.9729112189962, 601.997673873001, 13470.393832277474, 13494.527691530848, 13524.86475135246, 13526.864731352658, 13529.797053044213, 13188.538401155913, 13209.207447553177, 13244.53644122451, 13281.86718132816, 13412.329383530881, 11689.0, 11762.235454695418, 11858.407079645984, 12330.0, 12413.503459861595, 4315.417418648473, 4434.977825110886, 4449.7330160190495, 4451.132029254297, 4456.420665313521, 63580.0, 64438.0, 64497.0, 64580.0, 67192.0, 10435.530401131968, 10744.51649675751, 10785.730356741144, 10938.781224375627, 11014.724631884266, 20162.193512259637, 20165.471042567784, 20177.092030858646, 20257.898710506648, 20314.593708125754, 6689.5986240825705, 6733.427658648971, 6931.133608298963, 6966.268541803139, 6974.0, 3590.389633762267, 3703.8703645372284, 3716.405375140019, 3717.1450566369886, 3726.8136593170775, 2756.641636587231, 2806.593044008607, 2813.099808061401, 2819.873105710248, 2837.517622004264, 487.63914703120145, 499.2486308106277, 501.7340809371039, 510.5277618203147, 511.93600799900014, 441.0671429925684, 514.7040451740264, 575.3987083497755, 597.3518732175638, 602.3133627664466, 7705.653245603961, 7794.883076753853, 8224.588770561448, 8321.95839020801, 8348.123447038095, 465.2625588442357, 525.4010428111956, 526.6798418972332, 535.5528134008147, 549.5219159331407, 3741.9438708419398, 3911.2373087248284, 3922.7646341219047, 4078.4610633486514, 4146.460960075171, 59909.700451498335, 62004.68997654983, 62805.68597156985, 63001.0, 63338.36661633417, 54874.451255486936, 58068.70965645145, 59310.0, 59557.404425956054, 60585.0, 43054.0, 53300.0, 53412.46587534153, 53815.46184538105, 54351.0, 8365.581720914051, 9257.120573545502, 9287.303452241244, 9289.303302252494, 9407.576659050495, 29446.558301625064, 30610.693893060783, 33427.83286083554, 33915.830420847735, 35737.498375114534, 39450.40824387579, 40417.59582404138, 43455.782721086194, 44519.55480445154, 44850.775746121064, 11675.971789732528, 12458.0, 12608.432620531914, 12687.429065691882, 12723.363831808558, 12144.574939877415, 12491.125621206429, 12910.419031143434, 13024.674383140497, 13619.86380136186, 11939.701507462036, 12125.939370303093, 12378.938105309777, 12531.93734031324, 12831.807522886977, 12255.75488490243, 12276.386180691108, 12541.247525148521, 12629.684257893627, 13148.342582870628, 12346.506139754309, 12507.249565026128, 12613.432395542039, 12648.114631975674, 12929.482820687443, 10200.4389758564, 10223.079922806983, 10302.690919272434, 10433.426161561187, 10575.947120264349, 10357.326773759682, 10569.788604228026, 10575.78848423012, 10633.787324253317, 10639.361638301418], "value": 6370.780608532438, "unit": "score"}], "name": "dromaeo_css", "value": 6197.230651110449, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 116.0, 117.0, 115.0, 115.0, 117.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 132.0, 167.0, 131.0, 129.0, 126.0, 130.0, 156.0, 125.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 163.0, 163.0, 165.0, 163.0, 163.0, 162.0, 160.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 109.0, 77.0, 77.0, 76.0, 75.0, 93.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 108.0, 86.0, 85.0, 91.0, 101.0, 85.0, 85.0, 110.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 127.0, 126.0, 127.0, 127.0, 128.0, 129.0], "value": 127.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 63.0, 65.0, 66.0, 64.0, 64.0, 65.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 66.0, 68.0, 67.0, 67.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 93.0, 85.0, 90.0, 83.0, 85.0, 84.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 136.0, 135.0, 136.0, 134.0, 133.0, 142.0, 183.0, 135.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 177.0, 177.0, 178.0, 179.0, 179.0, 177.0, 175.0, 184.0, 179.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 68.0, 67.0, 69.0, 68.0, 68.0, 68.0, 67.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1562.1000000000001, "alertThreshold": 2.0}]} 09:36:54 INFO - Return code: 0 09:36:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:54 INFO - # TBPL SUCCESS # 09:36:54 INFO - Running post-action listener: _resource_record_post_action 09:36:54 INFO - [mozharness: 2016-12-20 17:36:54.249709Z] Finished run-tests step (success) 09:36:54 INFO - Running post-run listener: _resource_record_post_run 09:36:54 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395554816; Read time: 8; Write time: 262308 09:36:54 INFO - TinderboxPrint: CPU usage
13.1% 09:36:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:36:54 INFO - TinderboxPrint: I/O write bytes / time
395,554,816 / 262,308 09:36:54 INFO - TinderboxPrint: CPU idle
8,338.8 (86.8%) 09:36:54 INFO - TinderboxPrint: CPU user
1,217.9 (12.7%) 09:36:54 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99848192; Read time: 0; Write time: 102148 09:36:54 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 295706624; Read time: 0; Write time: 160160 09:36:54 INFO - Running post-run listener: _upload_blobber_files 09:36:54 INFO - Blob upload gear active. 09:36:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:36:54 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:36:54 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:36:55 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:56 INFO - (blobuploader) - INFO - Done attempting. 09:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:56 INFO - (blobuploader) - INFO - Done attempting. 09:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:57 INFO - (blobuploader) - INFO - Done attempting. 09:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:00 INFO - (blobuploader) - INFO - Done attempting. 09:37:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:00 INFO - Return code: 0 09:37:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc7b38f0f85de1577f7e993e19832c9fa975edbcfc228b30d7fae10dc29803d4157a2833320c3c300e934420bfaecfc53ba62e88779f498fd16a4a177e6be25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85616f4a0a3ad29e73858e8edb80015beb0ef06d7562911c177759f76970ed3aa7f9be9d913b1bfc1cd47595bd91783feb385325ba0675cfe594b0590a654ebf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3d3a77a919d1ce33522644284d2c85303a084fb93923955a9696fcbc47f8ee4b57402af36d59c361140c1e82058a03497aead33aea03d9d17d65fc00c147e4de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5c75e660af40c374774828c58d964f5b4dc47cf499a0e855b58ac9cc64093ed90435c593ca7c26e9087f1e755f01a7fcda8ac9ee00257763b40cd48e43558efe"} 09:37:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:00 INFO - Contents: 09:37:00 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc7b38f0f85de1577f7e993e19832c9fa975edbcfc228b30d7fae10dc29803d4157a2833320c3c300e934420bfaecfc53ba62e88779f498fd16a4a177e6be25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85616f4a0a3ad29e73858e8edb80015beb0ef06d7562911c177759f76970ed3aa7f9be9d913b1bfc1cd47595bd91783feb385325ba0675cfe594b0590a654ebf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3d3a77a919d1ce33522644284d2c85303a084fb93923955a9696fcbc47f8ee4b57402af36d59c361140c1e82058a03497aead33aea03d9d17d65fc00c147e4de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5c75e660af40c374774828c58d964f5b4dc47cf499a0e855b58ac9cc64093ed90435c593ca7c26e9087f1e755f01a7fcda8ac9ee00257763b40cd48e43558efe"} 09:37:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:00 INFO - Copying logs to upload dir... 09:37:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.163416 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-20 09:37:00.514976) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 09:37:00.518511) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc7b38f0f85de1577f7e993e19832c9fa975edbcfc228b30d7fae10dc29803d4157a2833320c3c300e934420bfaecfc53ba62e88779f498fd16a4a177e6be25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85616f4a0a3ad29e73858e8edb80015beb0ef06d7562911c177759f76970ed3aa7f9be9d913b1bfc1cd47595bd91783feb385325ba0675cfe594b0590a654ebf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3d3a77a919d1ce33522644284d2c85303a084fb93923955a9696fcbc47f8ee4b57402af36d59c361140c1e82058a03497aead33aea03d9d17d65fc00c147e4de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5c75e660af40c374774828c58d964f5b4dc47cf499a0e855b58ac9cc64093ed90435c593ca7c26e9087f1e755f01a7fcda8ac9ee00257763b40cd48e43558efe"} build_url:https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011555 build_url: 'https://queue.taskcluster.net/v1/task/BnJswCE-QcaSf43-XpqJSQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc7b38f0f85de1577f7e993e19832c9fa975edbcfc228b30d7fae10dc29803d4157a2833320c3c300e934420bfaecfc53ba62e88779f498fd16a4a177e6be25f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85616f4a0a3ad29e73858e8edb80015beb0ef06d7562911c177759f76970ed3aa7f9be9d913b1bfc1cd47595bd91783feb385325ba0675cfe594b0590a654ebf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3d3a77a919d1ce33522644284d2c85303a084fb93923955a9696fcbc47f8ee4b57402af36d59c361140c1e82058a03497aead33aea03d9d17d65fc00c147e4de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5c75e660af40c374774828c58d964f5b4dc47cf499a0e855b58ac9cc64093ed90435c593ca7c26e9087f1e755f01a7fcda8ac9ee00257763b40cd48e43558efe"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 09:37:00.544447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:37:00.544766) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482254169.267712-826177405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 09:37:00.629087) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 09:37:00.629397) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 09:37:00.629700) ========= ========= Total master_lag: 0.18 =========