builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1472699871.19 results: success (0) buildid: 20160831173003 builduid: b1f8eefb2db64b218456bd9b5d1cea41 revision: d3ef59a2f50a3109be079aa5c233daebdc00cb57 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.188386) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.188782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.189204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.247634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.247912) ========= 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=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 20:17:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-08-31 20:17:51 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129231 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.431783) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.432071) ========= 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=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020849 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.481850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:51.482166) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d3ef59a2f50a3109be079aa5c233daebdc00cb57 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d3ef59a2f50a3109be079aa5c233daebdc00cb57 --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=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 20:17:51,559 truncating revision to first 12 chars 2016-08-31 20:17:51,559 Setting DEBUG logging. 2016-08-31 20:17:51,559 attempt 1/10 2016-08-31 20:17:51,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d3ef59a2f50a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 20:17:51,893 unpacking tar archive at: fx-team-d3ef59a2f50a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649567 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:52.144469) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:52.144732) ========= script_repo_revision: d3ef59a2f50a3109be079aa5c233daebdc00cb57 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:52.145117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:52.145663) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 20:17:52.155077) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 20:17:52.155347) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:17:52 INFO - MultiFileLogger online at 20160831 20:17:52 in /builds/slave/test 20:17:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:17:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:17:52 INFO - {'append_to_log': False, 20:17:52 INFO - 'base_work_dir': '/builds/slave/test', 20:17:52 INFO - 'blob_upload_branch': 'Fx-Team', 20:17:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:17:52 INFO - 'branch': 'Fx-Team', 20:17:52 INFO - 'buildbot_json_path': 'buildprops.json', 20:17:52 INFO - 'config_files': ('talos/linux_config.py',), 20:17:52 INFO - 'default_actions': ('clobber', 20:17:52 INFO - 'read-buildbot-config', 20:17:52 INFO - 'download-and-extract', 20:17:52 INFO - 'populate-webroot', 20:17:52 INFO - 'create-virtualenv', 20:17:52 INFO - 'install', 20:17:52 INFO - 'run-tests'), 20:17:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:17:52 INFO - 'download_minidump_stackwalk': True, 20:17:52 INFO - 'download_symbols': 'ondemand', 20:17:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:17:52 INFO - 'tooltool.py': '/tools/tooltool.py', 20:17:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:17:52 INFO - '/tools/misc-python/virtualenv.py')}, 20:17:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:17:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:17:52 INFO - 'installer_path': 'installer.exe', 20:17:52 INFO - 'log_level': 'info', 20:17:52 INFO - 'log_name': 'talos', 20:17:52 INFO - 'log_to_console': True, 20:17:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:17:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:17:52 INFO - 'opt_config_files': (), 20:17:52 INFO - 'pip_index': False, 20:17:52 INFO - 'sps_profile': False, 20:17:52 INFO - 'sps_profile_interval': 0, 20:17:52 INFO - 'suite': 'dromaeojs', 20:17:52 INFO - 'system_bits': '32', 20:17:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:17:52 INFO - 'title': 'talos-linux64-ix-010', 20:17:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:17:52 INFO - 'use_talos_json': True, 20:17:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:17:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:17:52 INFO - 'work_dir': 'build'} 20:17:52 INFO - [mozharness: 2016-09-01 03:17:52.425294Z] Running clobber step. 20:17:52 INFO - Running pre-action listener: _resource_record_pre_action 20:17:52 INFO - Running main action method: clobber 20:17:52 INFO - rmtree: /builds/slave/test/build 20:17:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:17:52 INFO - Running post-action listener: _resource_record_post_action 20:17:52 INFO - [mozharness: 2016-09-01 03:17:52.836787Z] Finished clobber step (success) 20:17:52 INFO - [mozharness: 2016-09-01 03:17:52.836888Z] Running read-buildbot-config step. 20:17:52 INFO - Running pre-action listener: _resource_record_pre_action 20:17:52 INFO - Running main action method: read_buildbot_config 20:17:52 INFO - Using buildbot properties: 20:17:52 INFO - { 20:17:52 INFO - "project": "", 20:17:52 INFO - "product": "firefox", 20:17:52 INFO - "script_repo_revision": "production", 20:17:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:17:52 INFO - "repository": "", 20:17:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:17:52 INFO - "buildid": "20160831173003", 20:17:52 INFO - "pgo_build": "True", 20:17:52 INFO - "basedir": "/builds/slave/test", 20:17:52 INFO - "buildnumber": 1022, 20:17:52 INFO - "slavename": "talos-linux64-ix-010", 20:17:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:17:52 INFO - "platform": "ubuntu64_hw", 20:17:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:17:52 INFO - "branch": "fx-team", 20:17:52 INFO - "repo_path": "integration/fx-team", 20:17:52 INFO - "revision": "d3ef59a2f50a3109be079aa5c233daebdc00cb57", 20:17:52 INFO - "stage_platform": "linux64-pgo", 20:17:52 INFO - "builduid": "b1f8eefb2db64b218456bd9b5d1cea41", 20:17:52 INFO - "slavebuilddir": "test" 20:17:52 INFO - } 20:17:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:17:52 INFO - Running post-action listener: _resource_record_post_action 20:17:52 INFO - [mozharness: 2016-09-01 03:17:52.839312Z] Finished read-buildbot-config step (success) 20:17:52 INFO - [mozharness: 2016-09-01 03:17:52.839402Z] Running download-and-extract step. 20:17:52 INFO - Running pre-action listener: _resource_record_pre_action 20:17:52 INFO - Running main action method: download_and_extract 20:17:52 INFO - mkdir: /builds/slave/test/build/tests 20:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:52 INFO - trying https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:17:52 INFO - Downloading https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:17:53 INFO - Downloaded 1361 bytes. 20:17:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:17:53 INFO - Using the following test package requirements: 20:17:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:17:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:17:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:17:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'jsshell-linux-x86_64.zip'], 20:17:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:17:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:17:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:17:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:17:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:17:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:17:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:17:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:17:53 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')]} 20:17:53 INFO - trying https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:17:53 INFO - Downloading https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:17:54 INFO - Downloaded 21903642 bytes. 20:17:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 20:17:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:58 INFO - trying https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:17:59 INFO - Downloaded 21903642 bytes. 20:17:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 20:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:04 INFO - trying https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:18:04 INFO - Downloading https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:18:05 INFO - Downloaded 14413998 bytes. 20:18:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 20:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:05 INFO - trying https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:18:05 INFO - Downloading https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:18:06 INFO - Downloaded 59736338 bytes. 20:18:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:18:06 INFO - mkdir: /builds/slave/test/properties 20:18:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:18:06 INFO - Writing to file /builds/slave/test/properties/build_url 20:18:06 INFO - Contents: 20:18:06 INFO - build_url:https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:18:07 INFO - Running post-action listener: _resource_record_post_action 20:18:07 INFO - Running post-action listener: set_extra_try_arguments 20:18:07 INFO - [mozharness: 2016-09-01 03:18:07.367378Z] Finished download-and-extract step (success) 20:18:07 INFO - [mozharness: 2016-09-01 03:18:07.367612Z] Running populate-webroot step. 20:18:07 INFO - Running pre-action listener: _resource_record_pre_action 20:18:07 INFO - Running main action method: populate_webroot 20:18:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:18:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:18:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:18:07 INFO - '--tppagecycles', 20:18:07 INFO - '7'], 20:18:07 INFO - 'tests': ['tsvgm']}}, 20:18:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:18:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:18:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:18:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:18:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:18:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:18:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['damp', 'tps']}, 20:18:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'tests': ['damp', 'tps']}, 20:18:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['dromaeo_dom']}, 20:18:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:18:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['basic_compositor_video']}, 20:18:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:18:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'other-e10s': {'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['a11yr', 20:18:07 INFO - 'ts_paint', 20:18:07 INFO - 'tpaint', 20:18:07 INFO - 'sessionrestore', 20:18:07 INFO - 'sessionrestore_no_auto_restore', 20:18:07 INFO - 'tabpaint']}, 20:18:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['tsvgx', 20:18:07 INFO - 'tsvgr_opacity', 20:18:07 INFO - 'tart', 20:18:07 INFO - 'tscrollx', 20:18:07 INFO - 'cart']}, 20:18:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:18:07 INFO - 'tsvgr_opacity', 20:18:07 INFO - 'tart', 20:18:07 INFO - 'tscrollx', 20:18:07 INFO - 'cart']}, 20:18:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'talos_options': ['--disable-e10s'], 20:18:07 INFO - 'tests': ['tp5o']}, 20:18:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'tests': ['tp5o']}, 20:18:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'talos_options': ['--disable-e10s', 20:18:07 INFO - '--xperf_path', 20:18:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:07 INFO - 'tests': ['tp5n']}, 20:18:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:07 INFO - 'talos_options': ['--xperf_path', 20:18:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:07 INFO - 'tests': ['tp5n']}}, 20:18:07 INFO - 'talos.zip': {'path': '', 20:18:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:18:07 INFO - Running post-action listener: _resource_record_post_action 20:18:07 INFO - [mozharness: 2016-09-01 03:18:07.386740Z] Finished populate-webroot step (success) 20:18:07 INFO - [mozharness: 2016-09-01 03:18:07.386830Z] Running create-virtualenv step. 20:18:07 INFO - Running pre-action listener: _resource_record_pre_action 20:18:07 INFO - Running main action method: create_virtualenv 20:18:07 INFO - Creating virtualenv /builds/slave/test/build/venv 20:18:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:18:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:18:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:18:07 INFO - Using real prefix '/usr' 20:18:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:18:09 INFO - Installing distribute.............................................................................................................................................................................................done. 20:18:11 INFO - Installing pip.................done. 20:18:11 INFO - Return code: 0 20:18:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:11 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:11 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 20:18:11 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 20:18:11 INFO - Using env: {'DISPLAY': ':0', 20:18:11 INFO - 'HOME': '/home/cltbld', 20:18:11 INFO - 'LANG': 'en_US.UTF-8', 20:18:11 INFO - 'LANGUAGE': 'en_US:en', 20:18:11 INFO - 'LOGNAME': 'cltbld', 20:18:11 INFO - 'MAIL': '/var/mail/cltbld', 20:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:11 INFO - 'MOZ_NO_REMOTE': '1', 20:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:11 INFO - 'NO_EM_RESTART': '1', 20:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:11 INFO - 'PWD': '/builds/slave/test', 20:18:11 INFO - 'SHELL': '/bin/bash', 20:18:11 INFO - 'SHLVL': '1', 20:18:11 INFO - 'TERM': 'linux', 20:18:11 INFO - 'TMOUT': '86400', 20:18:11 INFO - 'USER': 'cltbld', 20:18:11 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:11 INFO - '_': '/tools/buildbot/bin/python'} 20:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:11 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 20:18:11 INFO - Cleaning up... 20:18:11 INFO - Return code: 0 20:18:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:11 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:11 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 20:18:11 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 20:18:11 INFO - Using env: {'DISPLAY': ':0', 20:18:11 INFO - 'HOME': '/home/cltbld', 20:18:11 INFO - 'LANG': 'en_US.UTF-8', 20:18:11 INFO - 'LANGUAGE': 'en_US:en', 20:18:11 INFO - 'LOGNAME': 'cltbld', 20:18:11 INFO - 'MAIL': '/var/mail/cltbld', 20:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:11 INFO - 'MOZ_NO_REMOTE': '1', 20:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:11 INFO - 'NO_EM_RESTART': '1', 20:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:11 INFO - 'PWD': '/builds/slave/test', 20:18:11 INFO - 'SHELL': '/bin/bash', 20:18:11 INFO - 'SHLVL': '1', 20:18:11 INFO - 'TERM': 'linux', 20:18:11 INFO - 'TMOUT': '86400', 20:18:11 INFO - 'USER': 'cltbld', 20:18:11 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:11 INFO - '_': '/tools/buildbot/bin/python'} 20:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:11 INFO - Downloading/unpacking psutil>=3.1.1 20:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:18:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:18:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:18:13 INFO - Installing collected packages: psutil 20:18:13 INFO - Running setup.py install for psutil 20:18:13 INFO - building 'psutil._psutil_linux' extension 20:18:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:18:14 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 20:18:15 INFO - building 'psutil._psutil_posix' extension 20:18:15 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 20:18:15 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 20:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:18:15 INFO - Successfully installed psutil 20:18:15 INFO - Cleaning up... 20:18:15 INFO - Return code: 0 20:18:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:18:15 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')]} 20:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:15 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')]} 20:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:15 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:15 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 20:18:15 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 20:18:15 INFO - Using env: {'DISPLAY': ':0', 20:18:15 INFO - 'HOME': '/home/cltbld', 20:18:15 INFO - 'LANG': 'en_US.UTF-8', 20:18:15 INFO - 'LANGUAGE': 'en_US:en', 20:18:15 INFO - 'LOGNAME': 'cltbld', 20:18:15 INFO - 'MAIL': '/var/mail/cltbld', 20:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:15 INFO - 'MOZ_NO_REMOTE': '1', 20:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:15 INFO - 'NO_EM_RESTART': '1', 20:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:15 INFO - 'PWD': '/builds/slave/test', 20:18:15 INFO - 'SHELL': '/bin/bash', 20:18:15 INFO - 'SHLVL': '1', 20:18:15 INFO - 'TERM': 'linux', 20:18:15 INFO - 'TMOUT': '86400', 20:18:15 INFO - 'USER': 'cltbld', 20:18:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:15 INFO - '_': '/tools/buildbot/bin/python'} 20:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:18:15 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 20:18:15 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 20:18:15 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 20:18:15 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 20:18:15 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 20:18:15 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 20:18:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:18:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:18:17 INFO - Installing collected packages: mozsystemmonitor 20:18:17 INFO - Running setup.py install for mozsystemmonitor 20:18:17 INFO - Successfully installed mozsystemmonitor 20:18:17 INFO - Cleaning up... 20:18:17 INFO - Return code: 0 20:18:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:18:17 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')]} 20:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:17 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')]} 20:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:17 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:17 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 20:18:17 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 20:18:17 INFO - Using env: {'DISPLAY': ':0', 20:18:17 INFO - 'HOME': '/home/cltbld', 20:18:17 INFO - 'LANG': 'en_US.UTF-8', 20:18:17 INFO - 'LANGUAGE': 'en_US:en', 20:18:17 INFO - 'LOGNAME': 'cltbld', 20:18:17 INFO - 'MAIL': '/var/mail/cltbld', 20:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:17 INFO - 'MOZ_NO_REMOTE': '1', 20:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:17 INFO - 'NO_EM_RESTART': '1', 20:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:17 INFO - 'PWD': '/builds/slave/test', 20:18:17 INFO - 'SHELL': '/bin/bash', 20:18:17 INFO - 'SHLVL': '1', 20:18:17 INFO - 'TERM': 'linux', 20:18:17 INFO - 'TMOUT': '86400', 20:18:17 INFO - 'USER': 'cltbld', 20:18:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:17 INFO - '_': '/tools/buildbot/bin/python'} 20:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:17 INFO - Downloading/unpacking jsonschema==2.5.1 20:18:17 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 20:18:17 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 20:18:17 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 20:18:17 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 20:18:17 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 20:18:17 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 20:18:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:18:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:18:19 INFO - Downloading functools32-3.2.3-2.tar.gz 20:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:18:19 INFO - warning: no files found matching '*.txt' 20:18:19 INFO - no previously-included directories found matching 'build' 20:18:19 INFO - no previously-included directories found matching 'dist' 20:18:19 INFO - no previously-included directories found matching '.git*' 20:18:20 INFO - Installing collected packages: jsonschema, functools32 20:18:20 INFO - Running setup.py install for functools32 20:18:20 INFO - warning: no files found matching '*.txt' 20:18:20 INFO - no previously-included directories found matching 'build' 20:18:20 INFO - no previously-included directories found matching 'dist' 20:18:20 INFO - no previously-included directories found matching '.git*' 20:18:20 INFO - Successfully installed jsonschema functools32 20:18:20 INFO - Cleaning up... 20:18:20 INFO - Return code: 0 20:18:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:20 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:20 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 20:18:20 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 20:18:20 INFO - Using env: {'DISPLAY': ':0', 20:18:20 INFO - 'HOME': '/home/cltbld', 20:18:20 INFO - 'LANG': 'en_US.UTF-8', 20:18:20 INFO - 'LANGUAGE': 'en_US:en', 20:18:20 INFO - 'LOGNAME': 'cltbld', 20:18:20 INFO - 'MAIL': '/var/mail/cltbld', 20:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:20 INFO - 'MOZ_NO_REMOTE': '1', 20:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:20 INFO - 'NO_EM_RESTART': '1', 20:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:20 INFO - 'PWD': '/builds/slave/test', 20:18:20 INFO - 'SHELL': '/bin/bash', 20:18:20 INFO - 'SHLVL': '1', 20:18:20 INFO - 'TERM': 'linux', 20:18:20 INFO - 'TMOUT': '86400', 20:18:20 INFO - 'USER': 'cltbld', 20:18:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:20 INFO - '_': '/tools/buildbot/bin/python'} 20:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:18:20 INFO - Cleaning up... 20:18:20 INFO - Return code: 0 20:18:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:20 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:20 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 20:18:20 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 20:18:20 INFO - Using env: {'DISPLAY': ':0', 20:18:20 INFO - 'HOME': '/home/cltbld', 20:18:20 INFO - 'LANG': 'en_US.UTF-8', 20:18:20 INFO - 'LANGUAGE': 'en_US:en', 20:18:20 INFO - 'LOGNAME': 'cltbld', 20:18:20 INFO - 'MAIL': '/var/mail/cltbld', 20:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:20 INFO - 'MOZ_NO_REMOTE': '1', 20:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:20 INFO - 'NO_EM_RESTART': '1', 20:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:20 INFO - 'PWD': '/builds/slave/test', 20:18:20 INFO - 'SHELL': '/bin/bash', 20:18:20 INFO - 'SHLVL': '1', 20:18:20 INFO - 'TERM': 'linux', 20:18:20 INFO - 'TMOUT': '86400', 20:18:20 INFO - 'USER': 'cltbld', 20:18:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:20 INFO - '_': '/tools/buildbot/bin/python'} 20:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:20 INFO - Downloading/unpacking blobuploader==1.2.4 20:18:20 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 20:18:20 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 20:18:20 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 20:18:20 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 20:18:20 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 20:18:20 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 20:18:22 INFO - Downloading blobuploader-1.2.4.tar.gz 20:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:18:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:18:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:22 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 20:18:23 INFO - Downloading docopt-0.6.1.tar.gz 20:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:18:23 INFO - Installing collected packages: blobuploader, requests, docopt 20:18:23 INFO - Running setup.py install for blobuploader 20:18:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:18:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:18:23 INFO - Running setup.py install for requests 20:18:23 INFO - Running setup.py install for docopt 20:18:23 INFO - Successfully installed blobuploader requests docopt 20:18:23 INFO - Cleaning up... 20:18:23 INFO - Return code: 0 20:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:23 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:23 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 20:18:23 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 20:18:23 INFO - Using env: {'DISPLAY': ':0', 20:18:23 INFO - 'HOME': '/home/cltbld', 20:18:23 INFO - 'LANG': 'en_US.UTF-8', 20:18:23 INFO - 'LANGUAGE': 'en_US:en', 20:18:23 INFO - 'LOGNAME': 'cltbld', 20:18:23 INFO - 'MAIL': '/var/mail/cltbld', 20:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:23 INFO - 'MOZ_NO_REMOTE': '1', 20:18:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:23 INFO - 'NO_EM_RESTART': '1', 20:18:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:23 INFO - 'PWD': '/builds/slave/test', 20:18:23 INFO - 'SHELL': '/bin/bash', 20:18:23 INFO - 'SHLVL': '1', 20:18:23 INFO - 'TERM': 'linux', 20:18:23 INFO - 'TMOUT': '86400', 20:18:23 INFO - 'USER': 'cltbld', 20:18:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:23 INFO - '_': '/tools/buildbot/bin/python'} 20:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:18:24 INFO - Running setup.py (path:/tmp/pip-YO8kTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:18:24 INFO - Running setup.py (path:/tmp/pip-4TBOiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:18:24 INFO - Running setup.py (path:/tmp/pip-o8aXBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:18:24 INFO - Running setup.py (path:/tmp/pip-IQ3dEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:18:24 INFO - Running setup.py (path:/tmp/pip-TVKINJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:18:24 INFO - Running setup.py (path:/tmp/pip-FnBeUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:18:24 INFO - Running setup.py (path:/tmp/pip-wSc7HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:18:24 INFO - Running setup.py (path:/tmp/pip-I8ZJ8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:18:24 INFO - Running setup.py (path:/tmp/pip-m9zg0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:18:24 INFO - Running setup.py (path:/tmp/pip-BqwOxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:18:24 INFO - Running setup.py (path:/tmp/pip-j94uAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:18:24 INFO - Running setup.py (path:/tmp/pip-f5d50O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:18:24 INFO - Running setup.py (path:/tmp/pip-zowfEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:18:25 INFO - Running setup.py (path:/tmp/pip-jLZthZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:18:25 INFO - Running setup.py (path:/tmp/pip-SECVrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:18:25 INFO - Running setup.py (path:/tmp/pip-xiCVar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:18:25 INFO - Running setup.py (path:/tmp/pip-3vhepG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:18:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:18:25 INFO - Running setup.py install for manifestparser 20:18:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:18:25 INFO - Running setup.py install for mozcrash 20:18:25 INFO - Running setup.py install for mozdebug 20:18:25 INFO - Running setup.py install for mozdevice 20:18:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:18:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:18:25 INFO - Running setup.py install for mozfile 20:18:25 INFO - Running setup.py install for mozhttpd 20:18:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:18:25 INFO - Running setup.py install for mozinfo 20:18:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:18:25 INFO - Running setup.py install for mozInstall 20:18:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Running setup.py install for mozleak 20:18:26 INFO - Running setup.py install for mozlog 20:18:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Running setup.py install for moznetwork 20:18:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Running setup.py install for mozprocess 20:18:26 INFO - Running setup.py install for mozprofile 20:18:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Running setup.py install for mozrunner 20:18:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:18:26 INFO - Running setup.py install for mozscreenshot 20:18:26 INFO - Running setup.py install for moztest 20:18:26 INFO - Running setup.py install for mozversion 20:18:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:18:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:18:27 INFO - Cleaning up... 20:18:27 INFO - Return code: 0 20:18:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:18:27 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')]} 20:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:27 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')]} 20:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:27 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:27 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 20:18:27 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 20:18:27 INFO - Using env: {'DISPLAY': ':0', 20:18:27 INFO - 'HOME': '/home/cltbld', 20:18:27 INFO - 'LANG': 'en_US.UTF-8', 20:18:27 INFO - 'LANGUAGE': 'en_US:en', 20:18:27 INFO - 'LOGNAME': 'cltbld', 20:18:27 INFO - 'MAIL': '/var/mail/cltbld', 20:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:27 INFO - 'MOZ_NO_REMOTE': '1', 20:18:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:27 INFO - 'NO_EM_RESTART': '1', 20:18:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:27 INFO - 'PWD': '/builds/slave/test', 20:18:27 INFO - 'SHELL': '/bin/bash', 20:18:27 INFO - 'SHLVL': '1', 20:18:27 INFO - 'TERM': 'linux', 20:18:27 INFO - 'TMOUT': '86400', 20:18:27 INFO - 'USER': 'cltbld', 20:18:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:27 INFO - '_': '/tools/buildbot/bin/python'} 20:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:18:27 INFO - Running setup.py (path:/tmp/pip-xar4Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:18:27 INFO - Running setup.py (path:/tmp/pip-lLAtfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:18:27 INFO - Running setup.py (path:/tmp/pip-GC_7B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:18:27 INFO - Running setup.py (path:/tmp/pip-lHHAqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:18:27 INFO - Running setup.py (path:/tmp/pip-Fsewp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:18:27 INFO - Running setup.py (path:/tmp/pip-02G2ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:18:27 INFO - Running setup.py (path:/tmp/pip-O5CMrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:18:27 INFO - Running setup.py (path:/tmp/pip-W_Zf8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:18:27 INFO - Running setup.py (path:/tmp/pip-STQdiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:18:27 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)) 20:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:18:27 INFO - Running setup.py (path:/tmp/pip-UZB0qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:18:28 INFO - Running setup.py (path:/tmp/pip-U6Jt6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:18:28 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)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:18:28 INFO - Running setup.py (path:/tmp/pip-GDLtVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:18:28 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)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:18:28 INFO - Running setup.py (path:/tmp/pip-uWtw1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:18:28 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)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:18:28 INFO - Running setup.py (path:/tmp/pip-5W07Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:18:28 INFO - Running setup.py (path:/tmp/pip-qetKK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:18:28 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)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:18:28 INFO - Running setup.py (path:/tmp/pip-sB_QJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:18:28 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)) 20:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:18:28 INFO - Running setup.py (path:/tmp/pip-0_7TfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:18:28 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)) 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:18:28 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)) 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:18:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:18:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:18:28 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 20:18:28 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 20:18:28 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 20:18:28 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 20:18:28 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 20:18:28 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 20:18:30 INFO - Downloading blessings-1.6.tar.gz 20:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:18:30 INFO - Installing collected packages: blessings 20:18:30 INFO - Running setup.py install for blessings 20:18:30 INFO - Successfully installed blessings 20:18:30 INFO - Cleaning up... 20:18:30 INFO - Return code: 0 20:18:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:18:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:18:30 INFO - Reading from file tmpfile_stdout 20:18:30 INFO - Current package versions: 20:18:30 INFO - argparse == 1.2.1 20:18:30 INFO - blessings == 1.6 20:18:30 INFO - blobuploader == 1.2.4 20:18:30 INFO - docopt == 0.6.1 20:18:30 INFO - functools32 == 3.2.3-2 20:18:30 INFO - jsonschema == 2.5.1 20:18:30 INFO - manifestparser == 1.1 20:18:30 INFO - mozInstall == 1.12 20:18:30 INFO - mozcrash == 0.17 20:18:30 INFO - mozdebug == 0.1 20:18:30 INFO - mozdevice == 0.48 20:18:30 INFO - mozfile == 1.2 20:18:30 INFO - mozhttpd == 0.7 20:18:30 INFO - mozinfo == 0.9 20:18:30 INFO - mozleak == 0.1 20:18:30 INFO - mozlog == 3.3 20:18:30 INFO - moznetwork == 0.27 20:18:30 INFO - mozprocess == 0.23 20:18:30 INFO - mozprofile == 0.28 20:18:30 INFO - mozrunner == 6.12 20:18:30 INFO - mozscreenshot == 0.1 20:18:30 INFO - mozsystemmonitor == 0.3 20:18:30 INFO - moztest == 0.7 20:18:30 INFO - mozversion == 1.4 20:18:30 INFO - psutil == 3.1.1 20:18:30 INFO - requests == 1.2.3 20:18:30 INFO - wsgiref == 0.1.2 20:18:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18:30 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 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18:30 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 20:18:30 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 20:18:30 INFO - Using env: {'DISPLAY': ':0', 20:18:30 INFO - 'HOME': '/home/cltbld', 20:18:30 INFO - 'LANG': 'en_US.UTF-8', 20:18:30 INFO - 'LANGUAGE': 'en_US:en', 20:18:30 INFO - 'LOGNAME': 'cltbld', 20:18:30 INFO - 'MAIL': '/var/mail/cltbld', 20:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:30 INFO - 'MOZ_NO_REMOTE': '1', 20:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:30 INFO - 'NO_EM_RESTART': '1', 20:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:30 INFO - 'PWD': '/builds/slave/test', 20:18:30 INFO - 'SHELL': '/bin/bash', 20:18:30 INFO - 'SHLVL': '1', 20:18:30 INFO - 'TERM': 'linux', 20:18:30 INFO - 'TMOUT': '86400', 20:18:30 INFO - 'USER': 'cltbld', 20:18:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:30 INFO - '_': '/tools/buildbot/bin/python'} 20:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 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)) 20:18:31 INFO - Cleaning up... 20:18:31 INFO - Return code: 0 20:18:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:18: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')]} 20:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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')]} 20:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:18: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff427e0bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160c850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, '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': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', '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 20:18: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', 'jsonschema'] in /builds/slave/test/build 20:18: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 jsonschema 20:18:31 INFO - Using env: {'DISPLAY': ':0', 20:18:31 INFO - 'HOME': '/home/cltbld', 20:18:31 INFO - 'LANG': 'en_US.UTF-8', 20:18:31 INFO - 'LANGUAGE': 'en_US:en', 20:18:31 INFO - 'LOGNAME': 'cltbld', 20:18:31 INFO - 'MAIL': '/var/mail/cltbld', 20:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:31 INFO - 'MOZ_NO_REMOTE': '1', 20:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:31 INFO - 'NO_EM_RESTART': '1', 20:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:31 INFO - 'PWD': '/builds/slave/test', 20:18:31 INFO - 'SHELL': '/bin/bash', 20:18:31 INFO - 'SHLVL': '1', 20:18:31 INFO - 'TERM': 'linux', 20:18:31 INFO - 'TMOUT': '86400', 20:18:31 INFO - 'USER': 'cltbld', 20:18:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:31 INFO - '_': '/tools/buildbot/bin/python'} 20:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:18:31 INFO - Cleaning up... 20:18:31 INFO - Return code: 0 20:18:31 INFO - Running post-action listener: _resource_record_post_action 20:18:31 INFO - Running post-action listener: _start_resource_monitoring 20:18:31 INFO - Starting resource monitoring. 20:18:31 INFO - [mozharness: 2016-09-01 03:18:31.357015Z] Finished create-virtualenv step (success) 20:18:31 INFO - [mozharness: 2016-09-01 03:18:31.358003Z] Running install step. 20:18:31 INFO - Running pre-action listener: _resource_record_pre_action 20:18:31 INFO - Running main action method: install 20:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:18:31 INFO - Reading from file tmpfile_stdout 20:18:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:18:31 INFO - Reading from file tmpfile_stdout 20:18:31 INFO - Output received: 20:18:31 INFO - Usage: mozinstall [options] installer 20:18:31 INFO - Options: 20:18:31 INFO - -h, --help show this help message and exit 20:18:31 INFO - -d DEST, --destination=DEST 20:18:31 INFO - Directory to install application into. [default: 20:18:31 INFO - "/builds/slave/test"] 20:18:31 INFO - --app=APP Application being installed. [default: firefox] 20:18:31 INFO - mkdir: /builds/slave/test/build/application 20:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:18:48 INFO - Reading from file tmpfile_stdout 20:18:48 INFO - Output received: 20:18:48 INFO - /builds/slave/test/build/application/firefox/firefox 20:18:48 INFO - Running post-action listener: _resource_record_post_action 20:18:48 INFO - [mozharness: 2016-09-01 03:18:48.661788Z] Finished install step (success) 20:18:48 INFO - [mozharness: 2016-09-01 03:18:48.662069Z] Running run-tests step. 20:18:48 INFO - Running pre-action listener: _resource_record_pre_action 20:18:48 INFO - Running main action method: run_tests 20:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:18:48 INFO - Python 2.7.3 20:18:48 INFO - Return code: 0 20:18:48 INFO - grabbing minidump binary from tooltool 20:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:48 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 0x1607370>, '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 0x160a8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160ad80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:18:48 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 20:18:48 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 20:18:48 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 20:18:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:18:48 INFO - Return code: 0 20:18:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:18:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:18:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:18:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:18:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 20:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 20:18:48 INFO - Using env: {'DISPLAY': ':0', 20:18:48 INFO - 'HOME': '/home/cltbld', 20:18:48 INFO - 'LANG': 'en_US.UTF-8', 20:18:48 INFO - 'LANGUAGE': 'en_US:en', 20:18:48 INFO - 'LOGNAME': 'cltbld', 20:18:48 INFO - 'MAIL': '/var/mail/cltbld', 20:18:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:18:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:48 INFO - 'MOZ_NO_REMOTE': '1', 20:18:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:18:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:18:48 INFO - 'NO_EM_RESTART': '1', 20:18:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:48 INFO - 'PWD': '/builds/slave/test', 20:18:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:18:48 INFO - 'SHELL': '/bin/bash', 20:18:48 INFO - 'SHLVL': '1', 20:18:48 INFO - 'TERM': 'linux', 20:18:48 INFO - 'TMOUT': '86400', 20:18:48 INFO - 'USER': 'cltbld', 20:18:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365', 20:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:48 INFO - '_': '/tools/buildbot/bin/python'} 20:18:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 20:18:49 INFO - mozversion application_buildid: 20160831173003 20:18:49 INFO - mozversion application_changeset: d3ef59a2f50a3109be079aa5c233daebdc00cb57 20:18:49 INFO - mozversion application_display_name: Nightly 20:18:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:18:49 INFO - mozversion application_name: Firefox 20:18:49 INFO - mozversion application_remotingname: firefox 20:18:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:18:49 INFO - mozversion application_vendor: Mozilla 20:18:49 INFO - mozversion application_version: 51.0a1 20:18:49 INFO - mozversion platform_buildid: 20160831173003 20:18:49 INFO - mozversion platform_changeset: d3ef59a2f50a3109be079aa5c233daebdc00cb57 20:18:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:18:49 INFO - mozversion platform_version: 51.0a1 20:18:49 INFO - using testdate: 1472699929 20:18:49 INFO - actual date: 1472699929 20:18:49 INFO - starting webserver on 'localhost:52595' 20:18:49 INFO - SUITE-START | Running 2 tests 20:18:49 INFO - TEST-START | dromaeo_css 20:18:49 INFO - operating with platform_type : linux_ 20:18:49 INFO - Initialising browser for dromaeo_css test... 20:18:49 INFO - TEST-INFO | started process 3288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoKWk33/profile http://localhost:52595/getInfo.html) 20:18:56 INFO - PROCESS | 3288 | __metrics Screen width/height:1600/1200 20:18:56 INFO - PROCESS | 3288 | colorDepth:24 20:18:56 INFO - PROCESS | 3288 | Browser inner width/height: 1024/697 20:18:56 INFO - PROCESS | 3288 | __metrics 20:18:57 INFO - PROCESS | 3288 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:18:57 INFO - PROCESS | 3288 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:18:57 INFO - PROCESS | 3288 | console.error: PushService: 20:18:57 INFO - PROCESS | 3288 | stateChangeProcessEnqueue: Error transitioning state 20:18:57 INFO - PROCESS | 3288 | UnknownError 20:18:58 INFO - TEST-INFO | 3288: exit 0 20:18:58 INFO - Browser initialized. 20:18:58 INFO - Running cycle 1/1 for dromaeo_css test... 20:18:58 INFO - TEST-INFO | started process 3370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoKWk33/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:19:10 INFO - PROCESS | 3370 | RSS: Main: 179781632 20:19:10 INFO - PROCESS | 3370 | 20:20:02 INFO - PROCESS | 3370 | 1472700002176 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] 20:22:11 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52595/tests/dromaeo/cssquery-ext.html) 20:22:13 INFO - PROCESS | 3370 | RSS: Main: 296148992 20:22:13 INFO - PROCESS | 3370 | 20:25:15 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-ext.html (next: http://localhost:52595/tests/dromaeo/cssquery-jquery.html) 20:25:16 INFO - PROCESS | 3370 | RSS: Main: 267350016 20:25:16 INFO - PROCESS | 3370 | 20:28:18 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52595/tests/dromaeo/cssquery-mootools.html) 20:28:18 INFO - PROCESS | 3370 | RSS: Main: 213872640 20:28:18 INFO - PROCESS | 3370 | 20:31:20 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52595/tests/dromaeo/cssquery-prototype.html) 20:31:21 INFO - PROCESS | 3370 | RSS: Main: 236064768 20:31:21 INFO - PROCESS | 3370 | 20:34:24 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52595/tests/dromaeo/cssquery-yui.html) 20:34:24 INFO - PROCESS | 3370 | RSS: Main: 215748608 20:34:24 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | Cycle 1(1): loaded http://localhost:52595/tests/dromaeo/cssquery-yui.html (next: http://localhost:52595/tests/dromaeo/cssquery-dojo.html) 20:37:28 INFO - PROCESS | 3370 | RSS: Main: 307949568 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | __start_tp_report 20:37:28 INFO - PROCESS | 3370 | _x_x_mozilla_page_load 20:37:28 INFO - PROCESS | 3370 | _x_x_mozilla_page_load_details 20:37:28 INFO - PROCESS | 3370 | |i|pagename|runs| 20:37:28 INFO - PROCESS | 3370 | |0;dojo.html;3066.7086626770456;3070.094322174959;3078.8152710837353;3118.5478105674665;3139.8430078496085;5356.517913387795;5381.515663590281;5442.673439593627;5482.616216864821;5503.669779813209;5368.516833484986;5408.513233808956;5425.3489581250205;5443.047466693332;5470.890582188354;4835.927461088087;4918.090153321629;4922.114019476493;4929.162042452792;5146.794128234867;5056.367954005749;5068.847934561951;5077.111505486525;5093.159628661267;5094.235864620299;4851.054044461332;4870.512948705136;4873.658843880929;4918.213085906274;5071.746412679369;4893.804247830082;4910.926336104962;4918.754062296906;4926.926096108561;4986.476419975923;4905.901881962376;4931.556159945586;4944.134776414149;4948.282499037619;4984.551390374847;5250.579953603741;5370.409254981949;5408.161734931092;5411.107167317388;5509.586780991442;5722.113072473773;5744.827655170351;5770.336411312711;5775.826725198251;5783.5084017858535;5881.970590147064;5882.794102206445;5898.587098903078;5901.144334071579;5913.319968203668;5275.841724748264;5389.51494365509;5437.619366644376;5443.074677304868;5522.2544956430365;5944.970275148683;5960.791372301948;5969.104634304889;5979.9402005980255;5983.012802887475;5746.138079288056;5746.741396637161;5749.108888122348;5751.568632352507;5752.654840709571;5645.322561292671;5654.208410822488;5703.944770217523;5713.143028545668;5793.8841223175305;5688.089905615082;5696.772129114872;5698.11679189718;5698.6580805151825;5699.088145896637;5650.7739690412745;5666.320041594952;5730.77076916927;5736.799212027559;5737.110747834093;4360.672949528798;4382.912341753147;4411.073674528321;4433.556644335606;4481.1933851906315;3981.7212795104642;4022.416749571296;4024.5975402460103;4048;4068.349064149722;5472.699001554953;5474.479924407175;5474.671519708831;5480.177973303957;5485.149801780731;5624.606277560531;5812.041013232769;5836.387179346192;5852.590318677651;5857.062869940789;5496.862578435491;5500.614956952975;5501.202325662756;5554.1946417769195;5575.191597218381;5049.671771334852;5078.873028174326;5093.465186155475;5097.592192624581;5154.5103215194495;5703.344115426779;5844.15259787337;6064.06007068905;6067.514598832083;6073.119397687311;5737.799177028784;5740.339860916049;5744.885102298014;5792.768289268382;5810.5061069809535;6010.939890601038;6047.0929360596265;6051.304100028553;6071.210742603434;6084.452399284086;5506.559475241891;5700.885982280414;5795.275590551181;5802.042662960733;5805.071188609802;5848.64908105515;5888.587798854039;5893.852653683693;5944.256967879015;5965.135055417027;5709.51469125129;5755.165501002415;5762.394948530512;5764.538836893123;5772.942270577409;5487.862803429786;5557.5831812613405;5724.313082430135;5725.456081672234;5732.856678583069;5546.223528706065;5588.357338905916;5595.692236926845;5655.632383895034;5665.461781130786;5678.659280443187;5694.743736531783;5701;5704.80033198836;5708.229389032427;5444.8094316698725;5553.416891226363;5570.080936645408;5574.804881829116;5577.3028371453565 20:37:28 INFO - PROCESS | 3370 | |1;ext.html;560.2688491518569;603.2158194347348;609.9752415941219;615.6798464798302;632.1907957814;43605.56394436055;43668.78165609171;43685.34471982919;43703.78148109255;47570.286445703256;46061.53938460614;46611.06777864441;46634.06731865361;46668.53331466684;46674.29988550174;8878.511681857495;9014.68448604299;9015.27877769777;9020.503872287021;9025.413348132382;8860.8670869937;8895.110488951086;8981.056989016157;8990.910090899106;8991.100889911022;9004.729858104267;9032.819343613124;9034.86447703285;9035.548222588877;9048.909510904905;8898.555072246361;8933.821323573524;8939.955300223522;8942.061083586259;9001.279897608176;8827.249683776823;8869.46783193004;8899.688510902088;8903.910960890373;8904.73285801427;42174.367384489255;42520.574794251974;42528.787356063025;42589.57410425887;42599;3179.77741558091;3271.96728032719;3360.462326027848;3580.445031020196;3594.6764791168657;31306.686933130604;32140;32601.6739832601;34461.82769086164;34461.97306383897;4239.724417912826;4335.761533115678;4379.474463064421;4393.53867843875;4482.977585112086;360114;362449;362883;363168;363457;60807;61019.38980610137;61093.38906610966;61130.694346527984;61200.693996529735;36732.44901326482;36957.630423695955;36977.44533831994;38299.617003830164;64062.71090691823;14089.859101408854;14099.36552855124;14113.858861411254;14123.293835308194;14129.929350353388;11479.598214062467;11569.247998880046;11579.421028948518;11580.710482237842;11588.536458541732;18499.260029598936;18547.258109675462;18579.72130418045;18580.907095464707;18692;6924.203716572659;6987.790366289019;7104.21853596104;7132.893006604905;7216.60308683017;880.4981160738317;983.6360546597662;1027.41950797799;1057.9317434415698;1109.389835590422;31813.681863181533;32067.51898721521;32239.194020149684;32403.35193296121;32845.835770820995;1056.730533713898;1070.8340207267886;1088.0588291128274;1128.2779021426293;1128.3568366031448;16344.918275408547;17047.48857534276;17058.929392709182;17126.486205413858;17395.91302043482;568324;573443;573955;574386;578485;59585;59839;59984;60067;60091.09863351966;1209.0690168570152;1308.2608326295613;1325.006245316013;1326.409747662281;1332.2672530108596;30834.537481938238;32076;33017.66982330242;33271.25699603595;33693;50390.748046259534;50850;50984.74507627438;50993.74503127461;51150.23274650809;403413;405414;405859;408221;409082.63760436175;25376.365590860376;28018.159455216377;28709;28727.712722872504;29706.257343566584;47975.76012119917;48691.269630956274;49047.754761225966;49927;50220.74889625528;39665.40501892416;40051;40675.52964736431;41407;42986;39752.40371394489;40282.79858600688;40661.1867762649;41070.79464602658;42343.7882810584;39247.80376098101;39337.606623933396;39968.60031399649;40408.79795601003;40669.389959150045;34312.65687343095;36332.455013174294;36446.27107457783;36935.63064369429;37282.25435491329;26723.599146012435;27356.863215683796;27527.58708619332;27898.581521276792;30004.699953001065 20:37:28 INFO - PROCESS | 3370 | |2;jquery.html;385.14305669884527;388.3495145631067;398.21154114852584;404.3812966161772;415.4619767401215;14265.572032839005;18157.909210453945;18769.155388007537;19801.50496237593;19802;18457.169427375768;18640.627187456277;19958.800411995875;20061.799382006175;20087.497812554695;5533.501984821365;5565.6104072714825;5717.514011309044;5749.425057494259;5846.795362162326;5597.188407680884;5740.339860915986;5769.1923130761725;5775.768969241225;5779.07534794433;5696.401877802833;5784.1034639630925;5787.594868359217;5792.942070579304;5811.476967072963;5792.218050563162;5792.304923409197;5795.855103622401;5797.5941684082345;6349.428551430347;5675.177099320616;5680.4319568042865;5680.4319568043275;5681.431856814327;5684.403137670568;13092.083554151213;17409.651806963917;19568.70646940297;23566.996805271574;23736.64395034076;2261.2537862505333;2261.502469456717;2306.1351993002622;2314.2825724025606;2773.72262773723;9640.373375730554;9793.559289831976;9928.106470417697;10307.072363487323;10378.688639340757;1859.2748827957107;1959.8726082804715;1971.310041485483;1999.7000449932627;2009.728686627316;79488;85020.57489712512;85605;86010.56994715049;86577.56711216403;10203.285769996177;10283.9485802572;10588.417637030037;10627.734306642242;10691.679249622524;13188;13200.339983000811;14530.273486325641;14539.200343981183;14563.126212427076;6964.79105626832;7157.4274058075225;7183.784486465414;7194.100737407824;7489.513181643285;6956.2000369958105;7016.649167541603;7039.788806335818;7215.242399548077;7233.734096533086;4348.608625223745;4358.6730995175485;4387.802548885308;4410.25025745624;4426.44669416323;1807.4966171851818;1826.433805520293;1846.6675998320163;1885.4437940807495;1893.2332405375569;878.2403221213732;944.7071407863447;996.8006398720285;1002.9398236105857;1031.3966329697062;18627.81372186288;19557.413277601696;19815.5141706869;19861.404157875288;19885.304014359426;934.9439033658002;945.1682519382899;982.7248370456284;997.6009596161593;1000.9149222316039;10536.157107431387;10884.455777211266;11038.944805276084;11076;11090.279131856403;489417;499008;505896;507464;510547;74203;92403.53798230966;92590;94309.52845235729;97721.51139244258;960.078324808192;994.5375400438975;997.3317877022413;1007.3200589601989;1016.7508960304625;16469.670606588043;16623.03991224819;16749.74875376895;17249.310027598756;17627.911860440614;34537.827310863286;42290;43112.56887431211;43466.56533434751;44454.77772611116;59591.40408595859;62617.68691156515;63326.36673633389;64491.67754161199;65152;8142;8630.223279904838;8796.428232164888;8881.067487913677;9091.636334546543;11934.582289619822;13531;13697.383617737027;13725;14006;11720.589779357682;11803.409829508319;11827.82258266144;11861.5848445304;12142;11747.590289165013;11781.410929453321;12031.518739250332;12045.277283363026;12093.697657558632;11857.881421186023;11920.642380728592;11932.28406295625;11953.701157471132;12492.437840297027;8629.784255393666;8735.868961965449;8767.430117042371;8823.867641985504;8975.820483590422;10287.89098234845;11756;11860.762784744084;11909.285442873455;11935.403229838646 20:37:28 INFO - PROCESS | 3370 | |3;mootools.html;1432.6060333408311;1473.2780937340704;1618.7167245732;1774.751534785131;1784.0633667324653;8030.076541197764;8850.114988501147;9144.4970526621;9552.856707149389;9561.28290378221;9157.175854173123;9247.445153290808;9290;9290.349675522717;9292.767680807983;2458.385403649092;2486.254123762873;2488.129154795825;2488.203774792067;2493.613489909058;2117.4388786971463;2203.2068455356057;2270.6026445372077;2352.5177338645535;2440.170342083691;2377.8454400464;2395.520895820834;2408.939776505593;2411.4935863468604;2412.8552286862755;2325.1513197682893;2373.738888722239;2400.759924007603;2404.699412573428;2416.154345978911;2318.594246006942;2329.697139371871;2335.1243283768586;2371.193794110011;2380.1074597026113;9290.953545232298;9353;9379.624815007392;9380.577873995619;9382.10869967352;4976.402831660224;5162;5163.535281824654;5164.612654050961;5164.948350516484;3222.5327327537375;3276.6068071831296;3365.1082463147286;3400.931981360384;3439.5872495300473;992.4591097851616;1037.667946257198;1069.12331887852;1070.839374093892;1073.7745073534568;13007.154534955198;13059;13099.47602095915;13112.213267203902;13124.278164700841;4265.55211702773;4595.701279416827;4653.813847446132;4756.453007904135;4766.118268120409;10974.561017559368;11030.393328366856;11040.33757974524;11046.061084807721;11047.226694131494;5095.388553373619;5100.158473851847;5102.3877134743325;5105.162141629011;5106.719130447861;4462.821487140478;4547.454305483363;4616.838410655611;4618.722876627413;4620.2607582786595;4056.127932494528;4124.979375103106;4161.146964872194;4195.91608167835;4234.174336004511;1160.8026635471822;1218.7806194885006;1242.3353505874313;1257.9182353147016;1275.853276873153;937.1191080384417;951.9762005949768;955.0878910640322;956.8373376526007;995.4525011243932;6193.752249910043;6204;6366.872662546723;6482.416582507502;6488.708008139645;944.4275281656103;964.5707660091192;1000.6141494030675;1001.6594357918343;1002.8245057114975;4247.723897946624;4302.9569704303185;4439;4505.301678239878;4519.282672584397;11139.554417823358;11566.363849988331;11570.479328430241;11583.362915039585;11625.069994400428;11402.429878506042;11431.028362589106;11434.428278586203;11440.599579014695;11461.255018423775;8160.102388737234;8507.064222935473;8508.27679647237;8719.956400218085;8720.345974051972;6188.783392581238;6254;6318.9052164216655;6319.8104056878365;6524.869502610016;9972.451515166445;10353;10667.253292269466;10804.351738895692;10838.729031774268;10330.121939635215;10683.732906677395;10727.302725323141;10844.620438284623;10881.238313317992;7419.109706835215;7661.540307581563;7687.500312479671;7745.961270193613;7756.767296981099;8884.067172946747;8925.955370223368;9001;9076.636934522545;9088.272938164799;8020.278174964281;8143.226393492609;8159.83680326402;8192.877106843283;8211.671533138608;7898.2891539761695;7917;7932.84134317322;7967.083785364525;8115.878261825959;7167.462440316893;7308.123025237006;7348.632568371667;7368;7372.7050917964825;5295.9470405297;5315.468453154654;5342.492463216144;5345;5398.919016214839;5526.889462210814;5587.1339942309005;5644.774209031757;5649.293838270216;5698.886022279614 20:37:28 INFO - PROCESS | 3370 | |4;prototype.html;322.88860343181597;336.7322978232305;337.7777777777777;339.72142842868857;340.7155025553663;5099.235114732792;5190.987757387311;5293.708846013467;5299;5300.496452836979;5157.948420515794;5174.327337446127;5192.3644052123645;5227.823739658575;5270.552003079733;4174.415581818548;4178.895527611804;4179.644730197937;4181.937270940924;4207.915841683164;3962.5839286874893;4095.508538975327;4175;4200.411942328061;4208.705390622657;4058.431819545266;4058.5129784425762;4086.4074709167153;4087.8364865405347;4105.609967053125;4061.1674606705556;4064.8983775405704;4090.2228576570355;4102.2410853992105;4107.63031327179;3624.5469316335457;3636.672699457035;3639.743359117364;3644.908877278063;3649.6350364963555;5071.112555302844;5090.618203634705;5098.960415833659;5117.769700363493;5146.9485305146845;4628.259478483426;4650.767546600154;4652.860414187579;4660.254359302529;4680.776631100234;5462.481064298885;5463.180522921593;5472.398036216013;5485.780568777244;5490.3411590608985;3811.5045044144085;3929.4105884117325;3939.684825213976;3940.8029598520247;3956.525216973953;5672.177534257551;5701.486866182064;5705.144228365737;5709.400512946122;5710.428957104256;4064.4716186896108;4073.042834933788;4079.5716449772344;4092.0383709828166;4111.8972025699595;4041.4948131483566;4130.876073717794;4136.875893723193;4146.875593732193;4152.958470415258;4652.371929789503;4662.160811054043;4670.136024835417;4671.27595222741;4678.742669153229;5584.274044374205;5584.766093562526;5606.383297837235;5609.663420194802;5614.9438505615235;1312.7046414556648;1356.9864301357059;1370.6123991684826;1392.8955328350414;1403.4105675616265;884.2748945864458;889.7419748273057;914.6204325205099;916.7341470973477;930.8138372325562;4612.607928326062;4614.238650622609;4647.395838540968;4654.464026871125;4675.971286317005;4877.12211801879;4881.267809828554;4899.2896030075435;4900.191468407744;4917.385326834145;4582.24392975162;4590.219662657356;4602.838900638461;4620.168369693421;4626.306054091913;2750.917472475829;2940.324015311252;2942.764578833688;2950.660674022472;2963.703629637019;5709.65742055478;5742.707890724637;5765.798197063083;5775.595708300379;5777.162311464814;5621.690807005654;5716.485516303469;5743.741531631086;5762.222100016528;5770.567207459458;5821.245296783551;5822.184894114827;5829.504492118217;5831.300243970751;5834.854128646818;5577.498025177753;5583.832485025456;5595.188697638901;5613.382527922007;5628.324601047901;5344.278522399426;5469.179623056414;5545.223668686306;5546.976897590742;5578.219049333015;5752.61266574367;5765.4522820332;5777.180188240691;5792.160136780059;5797.898399304118;3848.3650197718184;4004.8197831097086;4006.7395619284653;4050.311447053949;4054.9594504055763;5340.439253878442;5546.171452559304;5582.218489411567;5596.356419011866;5600.803971860965;5363.436839131991;5478.863028424162;5508.7521061553125;5513.917291240555;5517.862053448696;5504.137848318932;5522.3649280333275;5528.308961379827;5531.3362396512675;5551.805686800943;5201.609879258995;5412.431694672003;5459.972700136474;5461.972690136524;5463.9180412294645;5499.3675727291875;5521.806736764194;5522.392536821027;5532.031894418541;5549.556035517068;5387.49150237934;5389.326334208224;5406.2971813665545;5418.729063546885;5452.527817489176 20:37:28 INFO - PROCESS | 3370 | |5;yui.html;719.9568025918444;750.4559194583926;765.6478020110752;809.1503920883072;836.8159005018894;16018.59953501163;16699.666006679858;16713.832861671395;16750;16796.580085497855;15021.248937553106;15296.923515382408;15371.385144594204;15436.073835569845;15486.225688715576;657.8552718401944;794.364508393286;806.2783808491397;809.3929552835374;818.5375262976429;813.1177672225613;815.9143289954559;817.2726273616456;818.1327792539897;823.0904850140598;850.7192626433293;855.4995327733284;858.1847245117164;858.7037472072157;863.2575984653193;690.892911598703;835.2983493865122;854.3122786157141;854.811941372897;857.5412154497345;616.737886398279;621.4127649371346;622.6730966242759;622.741562251665;622.9844253893643;14587.41650333996;14685.55943321702;14691.706165876729;14709.043912145677;14720.411183552646;14301.856981430157;14567.635809104751;14742.336594853257;14783.630409239748;14851.628709282246;12172.756544869142;12336.62990110298;12478.75042499154;12655.114142010063;12960.416781244867;4318.589733975267;4349.891252718676;4482.462104547475;4618.030213655136;4618.1918164321105;83180;84108.57945710233;84281.57859210727;84446;84487.15512844855;10931.836022459669;11153.219274650697;11233.213675042669;11403.377223238702;11835.230710003994;20436.79563204349;20742.58514829695;20749.58500830005;20810.79189208119;20894.164233430798;8157.347412207009;8162.428629995843;8301.626426810808;8672.78318042054;8681.131886811394;4647.767611619473;4711.434627844681;4796.8081276749235;4856.854294371175;4865.464798872121;3446.758726889094;3544.167120726627;3547.5742910850863;3550.91122721934;3574.4817001534634;600.7026521871614;608.3034925010837;616.8057062025448;619.6437048697018;622.4149299658307;640.8013515810114;674.3694645506467;675.9966200168967;721.6217025481305;737.8339873528413;9275.62897484093;10087.848682269772;10111;10150.289479736495;10206.183505319557;631.8799428108645;712.383788023356;747.2938073520471;764.8087978005499;766.31797700047;4940.802367905244;5053.570446512014;5071.822486212965;5134.589232861362;5156.535911767959;121649.39175304066;121832;121910.3904480472;122537.38731306465;122862.385688071;105056.47471762593;106128;107478;108651.45674271736;108952.45523772489;89218;89563.55218223867;91891.6758668583;91897.10771692016;94630;7000.474964377794;7380.372668323038;7548.207438219128;7639.961800190964;7702.422318326036;51717.48282517127;51976.740116299174;52030;53873.191902120714;54237.18644220261;75959;82012;84074.57962710147;84338.57830710807;86318;11734.413279335828;12038.976686981705;12144.174585005694;12227.633171004883;12261.203021803554;13879.514217002761;14052;14191.787123192953;14949.701005979601;15001.324940377928;12622.305773182563;13038.217706937614;13229.801552976905;13234.205947643171;13689.624039147146;12630.684232894251;12702.428390722254;12916.354182291036;13195.934020329836;13467.595972120853;12175.45210465549;12191.085668574715;12986.090973631755;13012.28432436225;13021.47914083426;10609.628662996758;10953.616623418142;10990.890091099307;11203.383813889988;11484.9327121119;10438.498422399503;10927.83608245861;11028.669139925814;11069.059129974043;11134.109271258118 20:37:28 INFO - PROCESS | 3370 | __end_tp_report 20:37:28 INFO - PROCESS | 3370 | __start_cc_report 20:37:28 INFO - PROCESS | 3370 | _x_x_mozilla_cycle_collect,1021 20:37:28 INFO - PROCESS | 3370 | __end_cc_report 20:37:28 INFO - PROCESS | 3370 | __startTimestamp1472701048437__endTimestamp 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | ------- Summary: start ------- 20:37:28 INFO - PROCESS | 3370 | Number of tests: 6 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#0] dojo.html Cycles:165 Average:5394.89 Median:5575.19 stddev:609.24 (10.9%) stddev-sans-first:583.09 20:37:28 INFO - PROCESS | 3370 | Values: 3066.7 3070.1 3078.8 3118.5 3139.8 5356.5 5381.5 5442.7 5482.6 5503.7 5368.5 5408.5 5425.3 5443.0 5470.9 4835.9 4918.1 4922.1 4929.2 5146.8 5056.4 5068.8 5077.1 5093.2 5094.2 4851.1 4870.5 4873.7 4918.2 5071.7 4893.8 4910.9 4918.8 4926.9 4986.5 4905.9 4931.6 4944.1 4948.3 4984.6 5250.6 5370.4 5408.2 5411.1 5509.6 5722.1 5744.8 5770.3 5775.8 5783.5 5882.0 5882.8 5898.6 5901.1 5913.3 5275.8 5389.5 5437.6 5443.1 5522.3 5945.0 5960.8 5969.1 5979.9 5983.0 5746.1 5746.7 5749.1 5751.6 5752.7 5645.3 5654.2 5703.9 5713.1 5793.9 5688.1 5696.8 5698.1 5698.7 5699.1 5650.8 5666.3 5730.8 5736.8 5737.1 4360.7 4382.9 4411.1 4433.6 4481.2 3981.7 4022.4 4024.6 4048.0 4068.3 5472.7 5474.5 5474.7 5480.2 5485.1 5624.6 5812.0 5836.4 5852.6 5857.1 5496.9 5500.6 5501.2 5554.2 5575.2 5049.7 5078.9 5093.5 5097.6 5154.5 5703.3 5844.2 6064.1 6067.5 6073.1 5737.8 5740.3 5744.9 5792.8 5810.5 6010.9 6047.1 6051.3 6071.2 6084.5 5506.6 5700.9 5795.3 5802.0 5805.1 5848.6 5888.6 5893.9 5944.3 5965.1 5709.5 5755.2 5762.4 5764.5 5772.9 5487.9 5557.6 5724.3 5725.5 5732.9 5546.2 5588.4 5595.7 5655.6 5665.5 5678.7 5694.7 5701.0 5704.8 5708.2 5444.8 5553.4 5570.1 5574.8 5577.3 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#1] ext.html Cycles:180 Average:60453.14 Median:224448.26 stddev:121304.08 (54.0%) stddev-sans-first:121561.02 20:37:28 INFO - PROCESS | 3370 | Values: 560.3 603.2 610.0 615.7 632.2 43605.6 43668.8 43685.3 43703.8 47570.3 46061.5 46611.1 46634.1 46668.5 46674.3 8878.5 9014.7 9015.3 9020.5 9025.4 8860.9 8895.1 8981.1 8990.9 8991.1 9004.7 9032.8 9034.9 9035.5 9048.9 8898.6 8933.8 8940.0 8942.1 9001.3 8827.2 8869.5 8899.7 8903.9 8904.7 42174.4 42520.6 42528.8 42589.6 42599.0 3179.8 3272.0 3360.5 3580.4 3594.7 31306.7 32140.0 32601.7 34461.8 34462.0 4239.7 4335.8 4379.5 4393.5 4483.0 360114.0 362449.0 362883.0 363168.0 363457.0 60807.0 61019.4 61093.4 61130.7 61200.7 36732.4 36957.6 36977.4 38299.6 64062.7 14089.9 14099.4 14113.9 14123.3 14129.9 11479.6 11569.2 11579.4 11580.7 11588.5 18499.3 18547.3 18579.7 18580.9 18692.0 6924.2 6987.8 7104.2 7132.9 7216.6 880.5 983.6 1027.4 1057.9 1109.4 31813.7 32067.5 32239.2 32403.4 32845.8 1056.7 1070.8 1088.1 1128.3 1128.4 16344.9 17047.5 17058.9 17126.5 17395.9 568324.0 573443.0 573955.0 574386.0 578485.0 59585.0 59839.0 59984.0 60067.0 60091.1 1209.1 1308.3 1325.0 1326.4 1332.3 30834.5 32076.0 33017.7 33271.3 33693.0 50390.7 50850.0 50984.7 50993.7 51150.2 403413.0 405414.0 405859.0 408221.0 409082.6 25376.4 28018.2 28709.0 28727.7 29706.3 47975.8 48691.3 49047.8 49927.0 50220.7 39665.4 40051.0 40675.5 41407.0 42986.0 39752.4 40282.8 40661.2 41070.8 42343.8 39247.8 39337.6 39968.6 40408.8 40669.4 34312.7 36332.5 36446.3 36935.6 37282.3 26723.6 27356.9 27527.6 27898.6 30004.7 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#2] jquery.html Cycles:180 Average:29336.01 Median:401.30 stddev:82917.04 (20662.3%) stddev-sans-first:83121.15 20:37:28 INFO - PROCESS | 3370 | Values: 385.1 388.3 398.2 404.4 415.5 14265.6 18157.9 18769.2 19801.5 19802.0 18457.2 18640.6 19958.8 20061.8 20087.5 5533.5 5565.6 5717.5 5749.4 5846.8 5597.2 5740.3 5769.2 5775.8 5779.1 5696.4 5784.1 5787.6 5792.9 5811.5 5792.2 5792.3 5795.9 5797.6 6349.4 5675.2 5680.4 5680.4 5681.4 5684.4 13092.1 17409.7 19568.7 23567.0 23736.6 2261.3 2261.5 2306.1 2314.3 2773.7 9640.4 9793.6 9928.1 10307.1 10378.7 1859.3 1959.9 1971.3 1999.7 2009.7 79488.0 85020.6 85605.0 86010.6 86577.6 10203.3 10283.9 10588.4 10627.7 10691.7 13188.0 13200.3 14530.3 14539.2 14563.1 6964.8 7157.4 7183.8 7194.1 7489.5 6956.2 7016.6 7039.8 7215.2 7233.7 4348.6 4358.7 4387.8 4410.3 4426.4 1807.5 1826.4 1846.7 1885.4 1893.2 878.2 944.7 996.8 1002.9 1031.4 18627.8 19557.4 19815.5 19861.4 19885.3 934.9 945.2 982.7 997.6 1000.9 10536.2 10884.5 11038.9 11076.0 11090.3 489417.0 499008.0 505896.0 507464.0 510547.0 74203.0 92403.5 92590.0 94309.5 97721.5 960.1 994.5 997.3 1007.3 1016.8 16469.7 16623.0 16749.7 17249.3 17627.9 34537.8 42290.0 43112.6 43466.6 44454.8 59591.4 62617.7 63326.4 64491.7 65152.0 8142.0 8630.2 8796.4 8881.1 9091.6 11934.6 13531.0 13697.4 13725.0 14006.0 11720.6 11803.4 11827.8 11861.6 12142.0 11747.6 11781.4 12031.5 12045.3 12093.7 11857.9 11920.6 11932.3 11953.7 12492.4 8629.8 8735.9 8767.4 8823.9 8975.8 10287.9 11756.0 11860.8 11909.3 11935.4 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#3] mootools.html Cycles:180 Average:6032.36 Median:4617.78 stddev:3504.57 (75.9%) stddev-sans-first:3497.35 20:37:28 INFO - PROCESS | 3370 | Values: 1432.6 1473.3 1618.7 1774.8 1784.1 8030.1 8850.1 9144.5 9552.9 9561.3 9157.2 9247.4 9290.0 9290.3 9292.8 2458.4 2486.3 2488.1 2488.2 2493.6 2117.4 2203.2 2270.6 2352.5 2440.2 2377.8 2395.5 2408.9 2411.5 2412.9 2325.2 2373.7 2400.8 2404.7 2416.2 2318.6 2329.7 2335.1 2371.2 2380.1 9291.0 9353.0 9379.6 9380.6 9382.1 4976.4 5162.0 5163.5 5164.6 5164.9 3222.5 3276.6 3365.1 3400.9 3439.6 992.5 1037.7 1069.1 1070.8 1073.8 13007.2 13059.0 13099.5 13112.2 13124.3 4265.6 4595.7 4653.8 4756.5 4766.1 10974.6 11030.4 11040.3 11046.1 11047.2 5095.4 5100.2 5102.4 5105.2 5106.7 4462.8 4547.5 4616.8 4618.7 4620.3 4056.1 4125.0 4161.1 4195.9 4234.2 1160.8 1218.8 1242.3 1257.9 1275.9 937.1 952.0 955.1 956.8 995.5 6193.8 6204.0 6366.9 6482.4 6488.7 944.4 964.6 1000.6 1001.7 1002.8 4247.7 4303.0 4439.0 4505.3 4519.3 11139.6 11566.4 11570.5 11583.4 11625.1 11402.4 11431.0 11434.4 11440.6 11461.3 8160.1 8507.1 8508.3 8720.0 8720.3 6188.8 6254.0 6318.9 6319.8 6524.9 9972.5 10353.0 10667.3 10804.4 10838.7 10330.1 10683.7 10727.3 10844.6 10881.2 7419.1 7661.5 7687.5 7746.0 7756.8 8884.1 8926.0 9001.0 9076.6 9088.3 8020.3 8143.2 8159.8 8192.9 8211.7 7898.3 7917.0 7932.8 7967.1 8115.9 7167.5 7308.1 7348.6 7368.0 7372.7 5295.9 5315.5 5342.5 5345.0 5398.9 5526.9 5587.1 5644.8 5649.3 5698.9 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#4] prototype.html Cycles:180 Average:4571.82 Median:5152.45 stddev:1341.22 (26.0%) stddev-sans-first:1306.52 20:37:28 INFO - PROCESS | 3370 | Values: 322.9 336.7 337.8 339.7 340.7 5099.2 5191.0 5293.7 5299.0 5300.5 5157.9 5174.3 5192.4 5227.8 5270.6 4174.4 4178.9 4179.6 4181.9 4207.9 3962.6 4095.5 4175.0 4200.4 4208.7 4058.4 4058.5 4086.4 4087.8 4105.6 4061.2 4064.9 4090.2 4102.2 4107.6 3624.5 3636.7 3639.7 3644.9 3649.6 5071.1 5090.6 5099.0 5117.8 5146.9 4628.3 4650.8 4652.9 4660.3 4680.8 5462.5 5463.2 5472.4 5485.8 5490.3 3811.5 3929.4 3939.7 3940.8 3956.5 5672.2 5701.5 5705.1 5709.4 5710.4 4064.5 4073.0 4079.6 4092.0 4111.9 4041.5 4130.9 4136.9 4146.9 4153.0 4652.4 4662.2 4670.1 4671.3 4678.7 5584.3 5584.8 5606.4 5609.7 5614.9 1312.7 1357.0 1370.6 1392.9 1403.4 884.3 889.7 914.6 916.7 930.8 4612.6 4614.2 4647.4 4654.5 4676.0 4877.1 4881.3 4899.3 4900.2 4917.4 4582.2 4590.2 4602.8 4620.2 4626.3 2750.9 2940.3 2942.8 2950.7 2963.7 5709.7 5742.7 5765.8 5775.6 5777.2 5621.7 5716.5 5743.7 5762.2 5770.6 5821.2 5822.2 5829.5 5831.3 5834.9 5577.5 5583.8 5595.2 5613.4 5628.3 5344.3 5469.2 5545.2 5547.0 5578.2 5752.6 5765.5 5777.2 5792.2 5797.9 3848.4 4004.8 4006.7 4050.3 4055.0 5340.4 5546.2 5582.2 5596.4 5600.8 5363.4 5478.9 5508.8 5513.9 5517.9 5504.1 5522.4 5528.3 5531.3 5551.8 5201.6 5412.4 5460.0 5462.0 5463.9 5499.4 5521.8 5522.4 5532.0 5549.6 5387.5 5389.3 5406.3 5418.7 5452.5 20:37:28 INFO - PROCESS | 3370 | 20:37:28 INFO - PROCESS | 3370 | [#5] yui.html Cycles:180 Average:21770.04 Median:4416.18 stddev:32356.71 (732.7%) stddev-sans-first:32408.88 20:37:28 INFO - PROCESS | 3370 | Values: 720.0 750.5 765.6 809.2 836.8 16018.6 16699.7 16713.8 16750.0 16796.6 15021.2 15296.9 15371.4 15436.1 15486.2 657.9 794.4 806.3 809.4 818.5 813.1 815.9 817.3 818.1 823.1 850.7 855.5 858.2 858.7 863.3 690.9 835.3 854.3 854.8 857.5 616.7 621.4 622.7 622.7 623.0 14587.4 14685.6 14691.7 14709.0 14720.4 14301.9 14567.6 14742.3 14783.6 14851.6 12172.8 12336.6 12478.8 12655.1 12960.4 4318.6 4349.9 4482.5 4618.0 4618.2 83180.0 84108.6 84281.6 84446.0 84487.2 10931.8 11153.2 11233.2 11403.4 11835.2 20436.8 20742.6 20749.6 20810.8 20894.2 8157.3 8162.4 8301.6 8672.8 8681.1 4647.8 4711.4 4796.8 4856.9 4865.5 3446.8 3544.2 3547.6 3550.9 3574.5 600.7 608.3 616.8 619.6 622.4 640.8 674.4 676.0 721.6 737.8 9275.6 10087.8 10111.0 10150.3 10206.2 631.9 712.4 747.3 764.8 766.3 4940.8 5053.6 5071.8 5134.6 5156.5 121649.4 121832.0 121910.4 122537.4 122862.4 105056.5 106128.0 107478.0 108651.5 108952.5 89218.0 89563.6 91891.7 91897.1 94630.0 7000.5 7380.4 7548.2 7640.0 7702.4 51717.5 51976.7 52030.0 53873.2 54237.2 75959.0 82012.0 84074.6 84338.6 86318.0 11734.4 12039.0 12144.2 12227.6 12261.2 13879.5 14052.0 14191.8 14949.7 15001.3 12622.3 13038.2 13229.8 13234.2 13689.6 12630.7 12702.4 12916.4 13195.9 13467.6 12175.5 12191.1 12986.1 13012.3 13021.5 10609.6 10953.6 10990.9 11203.4 11484.9 10438.5 10927.8 11028.7 11069.1 11134.1 20:37:28 INFO - PROCESS | 3370 | -------- Summary: end -------- 20:37:28 INFO - PROCESS | 3370 | 20:37:29 INFO - TEST-INFO | 3370: exit 0 20:37:29 INFO - TEST-OK | dromaeo_css | took 1119951ms 20:37:29 INFO - TEST-START | kraken 20:37:29 INFO - operating with platform_type : linux_ 20:37:29 INFO - Initialising browser for kraken test... 20:37:29 INFO - TEST-INFO | started process 3676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr4DomW/profile http://localhost:52595/getInfo.html) 20:37:34 INFO - PROCESS | 3676 | __metrics Screen width/height:1600/1200 20:37:34 INFO - PROCESS | 3676 | colorDepth:24 20:37:34 INFO - PROCESS | 3676 | Browser inner width/height: 1024/697 20:37:34 INFO - PROCESS | 3676 | __metrics 20:37:34 INFO - PROCESS | 3676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:37:34 INFO - PROCESS | 3676 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:37:35 INFO - PROCESS | 3676 | console.error: PushService: 20:37:35 INFO - PROCESS | 3676 | stateChangeProcessEnqueue: Error transitioning state 20:37:35 INFO - PROCESS | 3676 | UnknownError 20:37:35 INFO - TEST-INFO | 3676: exit 0 20:37:35 INFO - Browser initialized. 20:37:35 INFO - Running cycle 1/1 for kraken test... 20:37:35 INFO - TEST-INFO | started process 3757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr4DomW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:37:48 INFO - PROCESS | 3757 | RSS: Main: 184737792 20:37:48 INFO - PROCESS | 3757 | 20:37:50 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52595/tests/kraken/driver.html?testName=audio-beat-detection) 20:37:51 INFO - PROCESS | 3757 | RSS: Main: 246644736 20:37:51 INFO - PROCESS | 3757 | 20:37:54 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52595/tests/kraken/driver.html?testName=audio-dft) 20:37:54 INFO - PROCESS | 3757 | RSS: Main: 229093376 20:37:54 INFO - PROCESS | 3757 | 20:37:57 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52595/tests/kraken/driver.html?testName=audio-fft) 20:37:58 INFO - PROCESS | 3757 | RSS: Main: 306946048 20:37:58 INFO - PROCESS | 3757 | 20:38:00 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52595/tests/kraken/driver.html?testName=audio-oscillator) 20:38:01 INFO - PROCESS | 3757 | RSS: Main: 216457216 20:38:01 INFO - PROCESS | 3757 | 20:38:03 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52595/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:38:03 INFO - PROCESS | 3757 | RSS: Main: 218566656 20:38:03 INFO - PROCESS | 3757 | 20:38:06 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52595/tests/kraken/driver.html?testName=imaging-darkroom) 20:38:07 INFO - PROCESS | 3757 | RSS: Main: 256659456 20:38:07 INFO - PROCESS | 3757 | 20:38:10 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52595/tests/kraken/driver.html?testName=imaging-desaturate) 20:38:11 INFO - PROCESS | 3757 | RSS: Main: 255553536 20:38:11 INFO - PROCESS | 3757 | 20:38:14 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52595/tests/kraken/driver.html?testName=json-parse-financial) 20:38:14 INFO - PROCESS | 3757 | RSS: Main: 255586304 20:38:14 INFO - PROCESS | 3757 | 20:38:16 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52595/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:38:16 INFO - PROCESS | 3757 | RSS: Main: 217948160 20:38:16 INFO - PROCESS | 3757 | 20:38:18 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:38:19 INFO - PROCESS | 3757 | RSS: Main: 225292288 20:38:19 INFO - PROCESS | 3757 | 20:38:21 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:38:22 INFO - PROCESS | 3757 | RSS: Main: 222896128 20:38:22 INFO - PROCESS | 3757 | 20:38:24 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:38:25 INFO - PROCESS | 3757 | RSS: Main: 223698944 20:38:25 INFO - PROCESS | 3757 | 20:38:27 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:38:28 INFO - PROCESS | 3757 | RSS: Main: 222273536 20:38:28 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | Cycle 1(1): loaded http://localhost:52595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52595/tests/kraken/driver.html?testName=ai-astar) 20:38:30 INFO - PROCESS | 3757 | RSS: Main: 273395712 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | __start_tp_report 20:38:30 INFO - PROCESS | 3757 | _x_x_mozilla_page_load 20:38:30 INFO - PROCESS | 3757 | _x_x_mozilla_page_load_details 20:38:30 INFO - PROCESS | 3757 | |i|pagename|runs| 20:38:30 INFO - PROCESS | 3757 | |0;ai-astar;114;112;135;113;112;113;112;112;112;113 20:38:30 INFO - PROCESS | 3757 | |1;audio-beat-detection;149;117;151;186;119;142;148;122;190;119 20:38:30 INFO - PROCESS | 3757 | |2;audio-dft;162;161;163;161;161;162;160;161;161;162 20:38:30 INFO - PROCESS | 3757 | |3;audio-fft;75;75;75;171;76;77;75;76;162;74 20:38:30 INFO - PROCESS | 3757 | |4;audio-oscillator;83;81;135;81;82;83;141;82;82;148 20:38:30 INFO - PROCESS | 3757 | |5;imaging-gaussian-blur;116;117;116;117;117;117;116;116;116;117 20:38:30 INFO - PROCESS | 3757 | |6;imaging-darkroom;145;145;146;145;146;146;146;146;146;145 20:38:30 INFO - PROCESS | 3757 | |7;imaging-desaturate;96;95;95;95;95;95;95;94;95;95 20:38:30 INFO - PROCESS | 3757 | |8;json-parse-financial;51;50;50;50;50;54;51;50;52;51 20:38:30 INFO - PROCESS | 3757 | |9;json-stringify-tinderbox;61;62;63;62;62;62;63;62;64;68 20:38:30 INFO - PROCESS | 3757 | |10;stanford-crypto-aes;79;79;86;81;79;79;79;78;78;85 20:38:30 INFO - PROCESS | 3757 | |11;stanford-crypto-ccm;135;125;123;135;125;123;123;133;163;126 20:38:30 INFO - PROCESS | 3757 | |12;stanford-crypto-pbkdf2;162;164;164;167;170;162;161;164;164;166 20:38:30 INFO - PROCESS | 3757 | |13;stanford-crypto-sha256-iterative;64;64;65;64;64;64;64;66;64;64 20:38:30 INFO - PROCESS | 3757 | __end_tp_report 20:38:30 INFO - PROCESS | 3757 | __start_cc_report 20:38:30 INFO - PROCESS | 3757 | _x_x_mozilla_cycle_collect,1767 20:38:30 INFO - PROCESS | 3757 | __end_cc_report 20:38:30 INFO - PROCESS | 3757 | __startTimestamp1472701110515__endTimestamp 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | ------- Summary: start ------- 20:38:30 INFO - PROCESS | 3757 | Number of tests: 14 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#0] ai-astar Cycles:10 Average:114.80 Median:113.00 stddev:7.13 (6.3%) stddev-sans-first:7.56 20:38:30 INFO - PROCESS | 3757 | Values: 114.0 112.0 135.0 113.0 112.0 113.0 112.0 112.0 112.0 113.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:148.50 stddev:26.74 (18.0%) stddev-sans-first:28.31 20:38:30 INFO - PROCESS | 3757 | Values: 149.0 117.0 151.0 186.0 119.0 142.0 148.0 122.0 190.0 119.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 20:38:30 INFO - PROCESS | 3757 | Values: 162.0 161.0 163.0 161.0 161.0 162.0 160.0 161.0 161.0 162.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#3] audio-fft Cycles:10 Average:93.60 Median:75.00 stddev:38.49 (51.3%) stddev-sans-first:40.23 20:38:30 INFO - PROCESS | 3757 | Values: 75.0 75.0 75.0 171.0 76.0 77.0 75.0 76.0 162.0 74.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:82.00 stddev:28.83 (35.2%) stddev-sans-first:29.93 20:38:30 INFO - PROCESS | 3757 | Values: 83.0 81.0 135.0 81.0 82.0 83.0 141.0 82.0 82.0 148.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 20:38:30 INFO - PROCESS | 3757 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 20:38:30 INFO - PROCESS | 3757 | Values: 145.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 20:38:30 INFO - PROCESS | 3757 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.29 (2.5%) stddev-sans-first:1.36 20:38:30 INFO - PROCESS | 3757 | Values: 51.0 50.0 50.0 50.0 50.0 54.0 51.0 50.0 52.0 51.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:62.50 stddev:1.97 (3.2%) stddev-sans-first:1.96 20:38:30 INFO - PROCESS | 3757 | Values: 61.0 62.0 63.0 62.0 62.0 62.0 63.0 62.0 64.0 68.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:79.00 stddev:2.87 (3.6%) stddev-sans-first:3.00 20:38:30 INFO - PROCESS | 3757 | Values: 79.0 79.0 86.0 81.0 79.0 79.0 79.0 78.0 78.0 85.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:129.50 stddev:12.24 (9.5%) stddev-sans-first:12.90 20:38:30 INFO - PROCESS | 3757 | Values: 135.0 125.0 123.0 135.0 125.0 123.0 123.0 133.0 163.0 126.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:164.00 stddev:2.67 (1.6%) stddev-sans-first:2.69 20:38:30 INFO - PROCESS | 3757 | Values: 162.0 164.0 164.0 167.0 170.0 162.0 161.0 164.0 164.0 166.0 20:38:30 INFO - PROCESS | 3757 | 20:38:30 INFO - PROCESS | 3757 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 20:38:30 INFO - PROCESS | 3757 | Values: 64.0 64.0 65.0 64.0 64.0 64.0 64.0 66.0 64.0 64.0 20:38:30 INFO - PROCESS | 3757 | -------- Summary: end -------- 20:38:30 INFO - PROCESS | 3757 | 20:38:31 INFO - TEST-INFO | 3757: exit 0 20:38:31 INFO - TEST-OK | kraken | took 62470ms 20:38:31 INFO - SUITE-END | took 1182s 20:38:32 INFO - Completed test suite (00:19:42) 20:38:32 INFO - javascript benchmark 20:38:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3066.7086626770456, 3070.094322174959, 3078.8152710837353, 3118.5478105674665, 3139.8430078496085, 5356.517913387795, 5381.515663590281, 5442.673439593627, 5482.616216864821, 5503.669779813209, 5368.516833484986, 5408.513233808956, 5425.3489581250205, 5443.047466693332, 5470.890582188354, 4835.927461088087, 4918.090153321629, 4922.114019476493, 4929.162042452792, 5146.794128234867, 5056.367954005749, 5068.847934561951, 5077.111505486525, 5093.159628661267, 5094.235864620299, 4851.054044461332, 4870.512948705136, 4873.658843880929, 4918.213085906274, 5071.746412679369, 4893.804247830082, 4910.926336104962, 4918.754062296906, 4926.926096108561, 4986.476419975923, 4905.901881962376, 4931.556159945586, 4944.134776414149, 4948.282499037619, 4984.551390374847, 5250.579953603741, 5370.409254981949, 5408.161734931092, 5411.107167317388, 5509.586780991442, 5722.113072473773, 5744.827655170351, 5770.336411312711, 5775.826725198251, 5783.5084017858535, 5881.970590147064, 5882.794102206445, 5898.587098903078, 5901.144334071579, 5913.319968203668, 5275.841724748264, 5389.51494365509, 5437.619366644376, 5443.074677304868, 5522.2544956430365, 5944.970275148683, 5960.791372301948, 5969.104634304889, 5979.9402005980255, 5983.012802887475, 5746.138079288056, 5746.741396637161, 5749.108888122348, 5751.568632352507, 5752.654840709571, 5645.322561292671, 5654.208410822488, 5703.944770217523, 5713.143028545668, 5793.8841223175305, 5688.089905615082, 5696.772129114872, 5698.11679189718, 5698.6580805151825, 5699.088145896637, 5650.7739690412745, 5666.320041594952, 5730.77076916927, 5736.799212027559, 5737.110747834093, 4360.672949528798, 4382.912341753147, 4411.073674528321, 4433.556644335606, 4481.1933851906315, 3981.7212795104642, 4022.416749571296, 4024.5975402460103, 4048.0, 4068.349064149722, 5472.699001554953, 5474.479924407175, 5474.671519708831, 5480.177973303957, 5485.149801780731, 5624.606277560531, 5812.041013232769, 5836.387179346192, 5852.590318677651, 5857.062869940789, 5496.862578435491, 5500.614956952975, 5501.202325662756, 5554.1946417769195, 5575.191597218381, 5049.671771334852, 5078.873028174326, 5093.465186155475, 5097.592192624581, 5154.5103215194495, 5703.344115426779, 5844.15259787337, 6064.06007068905, 6067.514598832083, 6073.119397687311, 5737.799177028784, 5740.339860916049, 5744.885102298014, 5792.768289268382, 5810.5061069809535, 6010.939890601038, 6047.0929360596265, 6051.304100028553, 6071.210742603434, 6084.452399284086, 5506.559475241891, 5700.885982280414, 5795.275590551181, 5802.042662960733, 5805.071188609802, 5848.64908105515, 5888.587798854039, 5893.852653683693, 5944.256967879015, 5965.135055417027, 5709.51469125129, 5755.165501002415, 5762.394948530512, 5764.538836893123, 5772.942270577409, 5487.862803429786, 5557.5831812613405, 5724.313082430135, 5725.456081672234, 5732.856678583069, 5546.223528706065, 5588.357338905916, 5595.692236926845, 5655.632383895034, 5665.461781130786, 5678.659280443187, 5694.743736531783, 5701.0, 5704.80033198836, 5708.229389032427, 5444.8094316698725, 5553.416891226363, 5570.080936645408, 5574.804881829116, 5577.3028371453565], "value": 5353.679171372804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.2688491518569, 603.2158194347348, 609.9752415941219, 615.6798464798302, 632.1907957814, 43605.56394436055, 43668.78165609171, 43685.34471982919, 43703.78148109255, 47570.286445703256, 46061.53938460614, 46611.06777864441, 46634.06731865361, 46668.53331466684, 46674.29988550174, 8878.511681857495, 9014.68448604299, 9015.27877769777, 9020.503872287021, 9025.413348132382, 8860.8670869937, 8895.110488951086, 8981.056989016157, 8990.910090899106, 8991.100889911022, 9004.729858104267, 9032.819343613124, 9034.86447703285, 9035.548222588877, 9048.909510904905, 8898.555072246361, 8933.821323573524, 8939.955300223522, 8942.061083586259, 9001.279897608176, 8827.249683776823, 8869.46783193004, 8899.688510902088, 8903.910960890373, 8904.73285801427, 42174.367384489255, 42520.574794251974, 42528.787356063025, 42589.57410425887, 42599.0, 3179.77741558091, 3271.96728032719, 3360.462326027848, 3580.445031020196, 3594.6764791168657, 31306.686933130604, 32140.0, 32601.6739832601, 34461.82769086164, 34461.97306383897, 4239.724417912826, 4335.761533115678, 4379.474463064421, 4393.53867843875, 4482.977585112086, 360114.0, 362449.0, 362883.0, 363168.0, 363457.0, 60807.0, 61019.38980610137, 61093.38906610966, 61130.694346527984, 61200.693996529735, 36732.44901326482, 36957.630423695955, 36977.44533831994, 38299.617003830164, 64062.71090691823, 14089.859101408854, 14099.36552855124, 14113.858861411254, 14123.293835308194, 14129.929350353388, 11479.598214062467, 11569.247998880046, 11579.421028948518, 11580.710482237842, 11588.536458541732, 18499.260029598936, 18547.258109675462, 18579.72130418045, 18580.907095464707, 18692.0, 6924.203716572659, 6987.790366289019, 7104.21853596104, 7132.893006604905, 7216.60308683017, 880.4981160738317, 983.6360546597662, 1027.41950797799, 1057.9317434415698, 1109.389835590422, 31813.681863181533, 32067.51898721521, 32239.194020149684, 32403.35193296121, 32845.835770820995, 1056.730533713898, 1070.8340207267886, 1088.0588291128274, 1128.2779021426293, 1128.3568366031448, 16344.918275408547, 17047.48857534276, 17058.929392709182, 17126.486205413858, 17395.91302043482, 568324.0, 573443.0, 573955.0, 574386.0, 578485.0, 59585.0, 59839.0, 59984.0, 60067.0, 60091.09863351966, 1209.0690168570152, 1308.2608326295613, 1325.006245316013, 1326.409747662281, 1332.2672530108596, 30834.537481938238, 32076.0, 33017.66982330242, 33271.25699603595, 33693.0, 50390.748046259534, 50850.0, 50984.74507627438, 50993.74503127461, 51150.23274650809, 403413.0, 405414.0, 405859.0, 408221.0, 409082.63760436175, 25376.365590860376, 28018.159455216377, 28709.0, 28727.712722872504, 29706.257343566584, 47975.76012119917, 48691.269630956274, 49047.754761225966, 49927.0, 50220.74889625528, 39665.40501892416, 40051.0, 40675.52964736431, 41407.0, 42986.0, 39752.40371394489, 40282.79858600688, 40661.1867762649, 41070.79464602658, 42343.7882810584, 39247.80376098101, 39337.606623933396, 39968.60031399649, 40408.79795601003, 40669.389959150045, 34312.65687343095, 36332.455013174294, 36446.27107457783, 36935.63064369429, 37282.25435491329, 26723.599146012435, 27356.863215683796, 27527.58708619332, 27898.581521276792, 30004.699953001065], "value": 19844.917944374163, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.14305669884527, 388.3495145631067, 398.21154114852584, 404.3812966161772, 415.4619767401215, 14265.572032839005, 18157.909210453945, 18769.155388007537, 19801.50496237593, 19802.0, 18457.169427375768, 18640.627187456277, 19958.800411995875, 20061.799382006175, 20087.497812554695, 5533.501984821365, 5565.6104072714825, 5717.514011309044, 5749.425057494259, 5846.795362162326, 5597.188407680884, 5740.339860915986, 5769.1923130761725, 5775.768969241225, 5779.07534794433, 5696.401877802833, 5784.1034639630925, 5787.594868359217, 5792.942070579304, 5811.476967072963, 5792.218050563162, 5792.304923409197, 5795.855103622401, 5797.5941684082345, 6349.428551430347, 5675.177099320616, 5680.4319568042865, 5680.4319568043275, 5681.431856814327, 5684.403137670568, 13092.083554151213, 17409.651806963917, 19568.70646940297, 23566.996805271574, 23736.64395034076, 2261.2537862505333, 2261.502469456717, 2306.1351993002622, 2314.2825724025606, 2773.72262773723, 9640.373375730554, 9793.559289831976, 9928.106470417697, 10307.072363487323, 10378.688639340757, 1859.2748827957107, 1959.8726082804715, 1971.310041485483, 1999.7000449932627, 2009.728686627316, 79488.0, 85020.57489712512, 85605.0, 86010.56994715049, 86577.56711216403, 10203.285769996177, 10283.9485802572, 10588.417637030037, 10627.734306642242, 10691.679249622524, 13188.0, 13200.339983000811, 14530.273486325641, 14539.200343981183, 14563.126212427076, 6964.79105626832, 7157.4274058075225, 7183.784486465414, 7194.100737407824, 7489.513181643285, 6956.2000369958105, 7016.649167541603, 7039.788806335818, 7215.242399548077, 7233.734096533086, 4348.608625223745, 4358.6730995175485, 4387.802548885308, 4410.25025745624, 4426.44669416323, 1807.4966171851818, 1826.433805520293, 1846.6675998320163, 1885.4437940807495, 1893.2332405375569, 878.2403221213732, 944.7071407863447, 996.8006398720285, 1002.9398236105857, 1031.3966329697062, 18627.81372186288, 19557.413277601696, 19815.5141706869, 19861.404157875288, 19885.304014359426, 934.9439033658002, 945.1682519382899, 982.7248370456284, 997.6009596161593, 1000.9149222316039, 10536.157107431387, 10884.455777211266, 11038.944805276084, 11076.0, 11090.279131856403, 489417.0, 499008.0, 505896.0, 507464.0, 510547.0, 74203.0, 92403.53798230966, 92590.0, 94309.52845235729, 97721.51139244258, 960.078324808192, 994.5375400438975, 997.3317877022413, 1007.3200589601989, 1016.7508960304625, 16469.670606588043, 16623.03991224819, 16749.74875376895, 17249.310027598756, 17627.911860440614, 34537.827310863286, 42290.0, 43112.56887431211, 43466.56533434751, 44454.77772611116, 59591.40408595859, 62617.68691156515, 63326.36673633389, 64491.67754161199, 65152.0, 8142.0, 8630.223279904838, 8796.428232164888, 8881.067487913677, 9091.636334546543, 11934.582289619822, 13531.0, 13697.383617737027, 13725.0, 14006.0, 11720.589779357682, 11803.409829508319, 11827.82258266144, 11861.5848445304, 12142.0, 11747.590289165013, 11781.410929453321, 12031.518739250332, 12045.277283363026, 12093.697657558632, 11857.881421186023, 11920.642380728592, 11932.28406295625, 11953.701157471132, 12492.437840297027, 8629.784255393666, 8735.868961965449, 8767.430117042371, 8823.867641985504, 8975.820483590422, 10287.89098234845, 11756.0, 11860.762784744084, 11909.285442873455, 11935.403229838646], "value": 9103.877118609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.6060333408311, 1473.2780937340704, 1618.7167245732, 1774.751534785131, 1784.0633667324653, 8030.076541197764, 8850.114988501147, 9144.4970526621, 9552.856707149389, 9561.28290378221, 9157.175854173123, 9247.445153290808, 9290.0, 9290.349675522717, 9292.767680807983, 2458.385403649092, 2486.254123762873, 2488.129154795825, 2488.203774792067, 2493.613489909058, 2117.4388786971463, 2203.2068455356057, 2270.6026445372077, 2352.5177338645535, 2440.170342083691, 2377.8454400464, 2395.520895820834, 2408.939776505593, 2411.4935863468604, 2412.8552286862755, 2325.1513197682893, 2373.738888722239, 2400.759924007603, 2404.699412573428, 2416.154345978911, 2318.594246006942, 2329.697139371871, 2335.1243283768586, 2371.193794110011, 2380.1074597026113, 9290.953545232298, 9353.0, 9379.624815007392, 9380.577873995619, 9382.10869967352, 4976.402831660224, 5162.0, 5163.535281824654, 5164.612654050961, 5164.948350516484, 3222.5327327537375, 3276.6068071831296, 3365.1082463147286, 3400.931981360384, 3439.5872495300473, 992.4591097851616, 1037.667946257198, 1069.12331887852, 1070.839374093892, 1073.7745073534568, 13007.154534955198, 13059.0, 13099.47602095915, 13112.213267203902, 13124.278164700841, 4265.55211702773, 4595.701279416827, 4653.813847446132, 4756.453007904135, 4766.118268120409, 10974.561017559368, 11030.393328366856, 11040.33757974524, 11046.061084807721, 11047.226694131494, 5095.388553373619, 5100.158473851847, 5102.3877134743325, 5105.162141629011, 5106.719130447861, 4462.821487140478, 4547.454305483363, 4616.838410655611, 4618.722876627413, 4620.2607582786595, 4056.127932494528, 4124.979375103106, 4161.146964872194, 4195.91608167835, 4234.174336004511, 1160.8026635471822, 1218.7806194885006, 1242.3353505874313, 1257.9182353147016, 1275.853276873153, 937.1191080384417, 951.9762005949768, 955.0878910640322, 956.8373376526007, 995.4525011243932, 6193.752249910043, 6204.0, 6366.872662546723, 6482.416582507502, 6488.708008139645, 944.4275281656103, 964.5707660091192, 1000.6141494030675, 1001.6594357918343, 1002.8245057114975, 4247.723897946624, 4302.9569704303185, 4439.0, 4505.301678239878, 4519.282672584397, 11139.554417823358, 11566.363849988331, 11570.479328430241, 11583.362915039585, 11625.069994400428, 11402.429878506042, 11431.028362589106, 11434.428278586203, 11440.599579014695, 11461.255018423775, 8160.102388737234, 8507.064222935473, 8508.27679647237, 8719.956400218085, 8720.345974051972, 6188.783392581238, 6254.0, 6318.9052164216655, 6319.8104056878365, 6524.869502610016, 9972.451515166445, 10353.0, 10667.253292269466, 10804.351738895692, 10838.729031774268, 10330.121939635215, 10683.732906677395, 10727.302725323141, 10844.620438284623, 10881.238313317992, 7419.109706835215, 7661.540307581563, 7687.500312479671, 7745.961270193613, 7756.767296981099, 8884.067172946747, 8925.955370223368, 9001.0, 9076.636934522545, 9088.272938164799, 8020.278174964281, 8143.226393492609, 8159.83680326402, 8192.877106843283, 8211.671533138608, 7898.2891539761695, 7917.0, 7932.84134317322, 7967.083785364525, 8115.878261825959, 7167.462440316893, 7308.123025237006, 7348.632568371667, 7368.0, 7372.7050917964825, 5295.9470405297, 5315.468453154654, 5342.492463216144, 5345.0, 5398.919016214839, 5526.889462210814, 5587.1339942309005, 5644.774209031757, 5649.293838270216, 5698.886022279614], "value": 4777.481016858405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.88860343181597, 336.7322978232305, 337.7777777777777, 339.72142842868857, 340.7155025553663, 5099.235114732792, 5190.987757387311, 5293.708846013467, 5299.0, 5300.496452836979, 5157.948420515794, 5174.327337446127, 5192.3644052123645, 5227.823739658575, 5270.552003079733, 4174.415581818548, 4178.895527611804, 4179.644730197937, 4181.937270940924, 4207.915841683164, 3962.5839286874893, 4095.508538975327, 4175.0, 4200.411942328061, 4208.705390622657, 4058.431819545266, 4058.5129784425762, 4086.4074709167153, 4087.8364865405347, 4105.609967053125, 4061.1674606705556, 4064.8983775405704, 4090.2228576570355, 4102.2410853992105, 4107.63031327179, 3624.5469316335457, 3636.672699457035, 3639.743359117364, 3644.908877278063, 3649.6350364963555, 5071.112555302844, 5090.618203634705, 5098.960415833659, 5117.769700363493, 5146.9485305146845, 4628.259478483426, 4650.767546600154, 4652.860414187579, 4660.254359302529, 4680.776631100234, 5462.481064298885, 5463.180522921593, 5472.398036216013, 5485.780568777244, 5490.3411590608985, 3811.5045044144085, 3929.4105884117325, 3939.684825213976, 3940.8029598520247, 3956.525216973953, 5672.177534257551, 5701.486866182064, 5705.144228365737, 5709.400512946122, 5710.428957104256, 4064.4716186896108, 4073.042834933788, 4079.5716449772344, 4092.0383709828166, 4111.8972025699595, 4041.4948131483566, 4130.876073717794, 4136.875893723193, 4146.875593732193, 4152.958470415258, 4652.371929789503, 4662.160811054043, 4670.136024835417, 4671.27595222741, 4678.742669153229, 5584.274044374205, 5584.766093562526, 5606.383297837235, 5609.663420194802, 5614.9438505615235, 1312.7046414556648, 1356.9864301357059, 1370.6123991684826, 1392.8955328350414, 1403.4105675616265, 884.2748945864458, 889.7419748273057, 914.6204325205099, 916.7341470973477, 930.8138372325562, 4612.607928326062, 4614.238650622609, 4647.395838540968, 4654.464026871125, 4675.971286317005, 4877.12211801879, 4881.267809828554, 4899.2896030075435, 4900.191468407744, 4917.385326834145, 4582.24392975162, 4590.219662657356, 4602.838900638461, 4620.168369693421, 4626.306054091913, 2750.917472475829, 2940.324015311252, 2942.764578833688, 2950.660674022472, 2963.703629637019, 5709.65742055478, 5742.707890724637, 5765.798197063083, 5775.595708300379, 5777.162311464814, 5621.690807005654, 5716.485516303469, 5743.741531631086, 5762.222100016528, 5770.567207459458, 5821.245296783551, 5822.184894114827, 5829.504492118217, 5831.300243970751, 5834.854128646818, 5577.498025177753, 5583.832485025456, 5595.188697638901, 5613.382527922007, 5628.324601047901, 5344.278522399426, 5469.179623056414, 5545.223668686306, 5546.976897590742, 5578.219049333015, 5752.61266574367, 5765.4522820332, 5777.180188240691, 5792.160136780059, 5797.898399304118, 3848.3650197718184, 4004.8197831097086, 4006.7395619284653, 4050.311447053949, 4054.9594504055763, 5340.439253878442, 5546.171452559304, 5582.218489411567, 5596.356419011866, 5600.803971860965, 5363.436839131991, 5478.863028424162, 5508.7521061553125, 5513.917291240555, 5517.862053448696, 5504.137848318932, 5522.3649280333275, 5528.308961379827, 5531.3362396512675, 5551.805686800943, 5201.609879258995, 5412.431694672003, 5459.972700136474, 5461.972690136524, 5463.9180412294645, 5499.3675727291875, 5521.806736764194, 5522.392536821027, 5532.031894418541, 5549.556035517068, 5387.49150237934, 5389.326334208224, 5406.2971813665545, 5418.729063546885, 5452.527817489176], "value": 4145.42186134189, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.9568025918444, 750.4559194583926, 765.6478020110752, 809.1503920883072, 836.8159005018894, 16018.59953501163, 16699.666006679858, 16713.832861671395, 16750.0, 16796.580085497855, 15021.248937553106, 15296.923515382408, 15371.385144594204, 15436.073835569845, 15486.225688715576, 657.8552718401944, 794.364508393286, 806.2783808491397, 809.3929552835374, 818.5375262976429, 813.1177672225613, 815.9143289954559, 817.2726273616456, 818.1327792539897, 823.0904850140598, 850.7192626433293, 855.4995327733284, 858.1847245117164, 858.7037472072157, 863.2575984653193, 690.892911598703, 835.2983493865122, 854.3122786157141, 854.811941372897, 857.5412154497345, 616.737886398279, 621.4127649371346, 622.6730966242759, 622.741562251665, 622.9844253893643, 14587.41650333996, 14685.55943321702, 14691.706165876729, 14709.043912145677, 14720.411183552646, 14301.856981430157, 14567.635809104751, 14742.336594853257, 14783.630409239748, 14851.628709282246, 12172.756544869142, 12336.62990110298, 12478.75042499154, 12655.114142010063, 12960.416781244867, 4318.589733975267, 4349.891252718676, 4482.462104547475, 4618.030213655136, 4618.1918164321105, 83180.0, 84108.57945710233, 84281.57859210727, 84446.0, 84487.15512844855, 10931.836022459669, 11153.219274650697, 11233.213675042669, 11403.377223238702, 11835.230710003994, 20436.79563204349, 20742.58514829695, 20749.58500830005, 20810.79189208119, 20894.164233430798, 8157.347412207009, 8162.428629995843, 8301.626426810808, 8672.78318042054, 8681.131886811394, 4647.767611619473, 4711.434627844681, 4796.8081276749235, 4856.854294371175, 4865.464798872121, 3446.758726889094, 3544.167120726627, 3547.5742910850863, 3550.91122721934, 3574.4817001534634, 600.7026521871614, 608.3034925010837, 616.8057062025448, 619.6437048697018, 622.4149299658307, 640.8013515810114, 674.3694645506467, 675.9966200168967, 721.6217025481305, 737.8339873528413, 9275.62897484093, 10087.848682269772, 10111.0, 10150.289479736495, 10206.183505319557, 631.8799428108645, 712.383788023356, 747.2938073520471, 764.8087978005499, 766.31797700047, 4940.802367905244, 5053.570446512014, 5071.822486212965, 5134.589232861362, 5156.535911767959, 121649.39175304066, 121832.0, 121910.3904480472, 122537.38731306465, 122862.385688071, 105056.47471762593, 106128.0, 107478.0, 108651.45674271736, 108952.45523772489, 89218.0, 89563.55218223867, 91891.6758668583, 91897.10771692016, 94630.0, 7000.474964377794, 7380.372668323038, 7548.207438219128, 7639.961800190964, 7702.422318326036, 51717.48282517127, 51976.740116299174, 52030.0, 53873.191902120714, 54237.18644220261, 75959.0, 82012.0, 84074.57962710147, 84338.57830710807, 86318.0, 11734.413279335828, 12038.976686981705, 12144.174585005694, 12227.633171004883, 12261.203021803554, 13879.514217002761, 14052.0, 14191.787123192953, 14949.701005979601, 15001.324940377928, 12622.305773182563, 13038.217706937614, 13229.801552976905, 13234.205947643171, 13689.624039147146, 12630.684232894251, 12702.428390722254, 12916.354182291036, 13195.934020329836, 13467.595972120853, 12175.45210465549, 12191.085668574715, 12986.090973631755, 13012.28432436225, 13021.47914083426, 10609.628662996758, 10953.616623418142, 10990.890091099307, 11203.383813889988, 11484.9327121119, 10438.498422399503, 10927.83608245861, 11028.669139925814, 11069.059129974043, 11134.109271258118], "value": 7599.39652084933, "unit": "score"}], "name": "dromaeo_css", "value": 7253.0362700423475, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 135.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0, 113.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 151.0, 186.0, 119.0, 142.0, 148.0, 122.0, 190.0, 119.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 171.0, 76.0, 77.0, 75.0, 76.0, 162.0, 74.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 135.0, 81.0, 82.0, 83.0, 141.0, 82.0, 82.0, 148.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 50.0, 54.0, 51.0, 50.0, 52.0, 51.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 64.0, 68.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 86.0, 81.0, 79.0, 79.0, 79.0, 78.0, 78.0, 85.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 125.0, 123.0, 135.0, 125.0, 123.0, 123.0, 133.0, 163.0, 126.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 164.0, 167.0, 170.0, 162.0, 161.0, 164.0, 164.0, 166.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 64.0, 64.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1524.8999999999999, "alertThreshold": 2.0}]} 20:38:32 INFO - Return code: 0 20:38:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:38:32 INFO - # TBPL SUCCESS # 20:38:32 INFO - Running post-action listener: _resource_record_post_action 20:38:32 INFO - [mozharness: 2016-09-01 03:38:32.349607Z] Finished run-tests step (success) 20:38:32 INFO - Running post-run listener: _resource_record_post_run 20:38:32 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 7843840; Write bytes: 440705024; Read time: 1924; Write time: 643500 20:38:32 INFO - TinderboxPrint: CPU usage
13.1% 20:38:32 INFO - TinderboxPrint: I/O read bytes / time
7,843,840 / 1,924 20:38:32 INFO - TinderboxPrint: I/O write bytes / time
440,705,024 / 643,500 20:38:32 INFO - TinderboxPrint: CPU idle
8,319.8 (86.9%) 20:38:32 INFO - TinderboxPrint: CPU user
1,197.9 (12.5%) 20:38:32 INFO - TinderboxPrint: Swap in / out
0 / 0 20:38:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 8192; Write bytes: 63709184; Read time: 144; Write time: 68428 20:38:32 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 5644288; Write bytes: 344072192; Read time: 1380; Write time: 521592 20:38:32 INFO - Running post-run listener: _upload_blobber_files 20:38:32 INFO - Blob upload gear active. 20:38:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:38:32 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 20:38:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:38:33 INFO - (blobuploader) - INFO - Open directory for files ... 20:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:34 INFO - (blobuploader) - INFO - Done attempting. 20:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:38:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:38 INFO - (blobuploader) - INFO - Done attempting. 20:38:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:38:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:38:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:39 INFO - (blobuploader) - INFO - Done attempting. 20:38:39 INFO - (blobuploader) - INFO - Iteration through files over. 20:38:39 INFO - Return code: 0 20:38:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:38:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:38:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfe9d74dd900d196e48300ee7a75584bb7137745ca7cf2617facdb6ce8df020485353307825d61ac615e429283a8c3251e58b7ca756c65b31a05fba737ec763", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b807bd49d83ec5f2e764815ebf6628491888ce3ac66b2982109296b2065ac3bd8006720e4c373e026152421bea4c17c2025ad769d1b7215c8ae0020e51e7b8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/664e1529937593b92063667a8485e4dff4beb2d8f3983e20fc7f4aab1bcdb36086303786ad071c5f98944d6070c81e6dc5d3823b7360b24168e67854ea0c7a4b"} 20:38:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:38:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:38:39 INFO - Contents: 20:38:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfe9d74dd900d196e48300ee7a75584bb7137745ca7cf2617facdb6ce8df020485353307825d61ac615e429283a8c3251e58b7ca756c65b31a05fba737ec763", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b807bd49d83ec5f2e764815ebf6628491888ce3ac66b2982109296b2065ac3bd8006720e4c373e026152421bea4c17c2025ad769d1b7215c8ae0020e51e7b8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/664e1529937593b92063667a8485e4dff4beb2d8f3983e20fc7f4aab1bcdb36086303786ad071c5f98944d6070c81e6dc5d3823b7360b24168e67854ea0c7a4b"} 20:38:39 INFO - Running post-run listener: copy_logs_to_upload_dir 20:38:39 INFO - Copying logs to upload dir... 20:38:39 INFO - mkdir: /builds/slave/test/build/upload/logs 20:38:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.117410 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 20:38:39.286047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 20:38:39.289411) ========= 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=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfe9d74dd900d196e48300ee7a75584bb7137745ca7cf2617facdb6ce8df020485353307825d61ac615e429283a8c3251e58b7ca756c65b31a05fba737ec763", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b807bd49d83ec5f2e764815ebf6628491888ce3ac66b2982109296b2065ac3bd8006720e4c373e026152421bea4c17c2025ad769d1b7215c8ae0020e51e7b8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/664e1529937593b92063667a8485e4dff4beb2d8f3983e20fc7f4aab1bcdb36086303786ad071c5f98944d6070c81e6dc5d3823b7360b24168e67854ea0c7a4b"} build_url:https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/YDjFiyw_TiKfcMYT6_L1ow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfe9d74dd900d196e48300ee7a75584bb7137745ca7cf2617facdb6ce8df020485353307825d61ac615e429283a8c3251e58b7ca756c65b31a05fba737ec763", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b807bd49d83ec5f2e764815ebf6628491888ce3ac66b2982109296b2065ac3bd8006720e4c373e026152421bea4c17c2025ad769d1b7215c8ae0020e51e7b8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/664e1529937593b92063667a8485e4dff4beb2d8f3983e20fc7f4aab1bcdb36086303786ad071c5f98944d6070c81e6dc5d3823b7360b24168e67854ea0c7a4b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 20:38:39.314181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:38:39.314481) ========= 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=eca2ab57546de7b41f256b19000001ef-1472697949.25742-1468641365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 20:38:39.396730) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 20:38:39.397051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 20:38:39.397329) ========= ========= Total master_lag: 0.20 =========