builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1472050600.47 results: success (0) buildid: 20160824061127 builduid: 1c7e6c411479456bb03f9ea89b4c778d revision: a215678635e0f7ac78f8da5e723a1e09d37c3f97 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.472245) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.472681) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.472964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.490048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.490380) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-24 07:56:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.7M=0s 2016-08-24 07:56:40 (34.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085450 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.882846) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.886253) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019656 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:40.939213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 07:56:40.939563) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a215678635e0f7ac78f8da5e723a1e09d37c3f97 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a215678635e0f7ac78f8da5e723a1e09d37c3f97 --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=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-24 07:56:41,055 truncating revision to first 12 chars 2016-08-24 07:56:41,055 Setting DEBUG logging. 2016-08-24 07:56:41,055 attempt 1/10 2016-08-24 07:56:41,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a215678635e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 07:56:42,001 unpacking tar archive at: autoland-a215678635e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.285122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 07:56:42.246242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:42.246592) ========= script_repo_revision: a215678635e0f7ac78f8da5e723a1e09d37c3f97 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:42.247044) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:42.247317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 07:56:42.258013) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-24 07:56:42.258342) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:56:42 INFO - MultiFileLogger online at 20160824 07:56:42 in /builds/slave/test 07:56:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 07:56:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:42 INFO - {'append_to_log': False, 07:56:42 INFO - 'base_work_dir': '/builds/slave/test', 07:56:42 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 07:56:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:42 INFO - 'branch': 'Autoland-Non-PGO', 07:56:42 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:42 INFO - 'config_files': ('talos/linux_config.py',), 07:56:42 INFO - 'default_actions': ('clobber', 07:56:42 INFO - 'read-buildbot-config', 07:56:42 INFO - 'download-and-extract', 07:56:42 INFO - 'populate-webroot', 07:56:42 INFO - 'create-virtualenv', 07:56:42 INFO - 'install', 07:56:42 INFO - 'run-tests'), 07:56:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:42 INFO - 'download_minidump_stackwalk': True, 07:56:42 INFO - 'download_symbols': 'ondemand', 07:56:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:42 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:42 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:42 INFO - 'installer_path': 'installer.exe', 07:56:42 INFO - 'log_level': 'info', 07:56:42 INFO - 'log_name': 'talos', 07:56:42 INFO - 'log_to_console': True, 07:56:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:56:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:56:42 INFO - 'opt_config_files': (), 07:56:42 INFO - 'pip_index': False, 07:56:42 INFO - 'sps_profile': False, 07:56:42 INFO - 'sps_profile_interval': 0, 07:56:42 INFO - 'suite': 'dromaeojs', 07:56:42 INFO - 'system_bits': '32', 07:56:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:42 INFO - 'title': 'talos-linux64-ix-012', 07:56:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:42 INFO - 'use_talos_json': True, 07:56:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:42 INFO - 'work_dir': 'build'} 07:56:42 INFO - [mozharness: 2016-08-24 14:56:42.463314Z] Running clobber step. 07:56:42 INFO - Running pre-action listener: _resource_record_pre_action 07:56:42 INFO - Running main action method: clobber 07:56:42 INFO - rmtree: /builds/slave/test/build 07:56:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:43 INFO - Running post-action listener: _resource_record_post_action 07:56:43 INFO - [mozharness: 2016-08-24 14:56:43.429632Z] Finished clobber step (success) 07:56:43 INFO - [mozharness: 2016-08-24 14:56:43.429731Z] Running read-buildbot-config step. 07:56:43 INFO - Running pre-action listener: _resource_record_pre_action 07:56:43 INFO - Running main action method: read_buildbot_config 07:56:43 INFO - Using buildbot properties: 07:56:43 INFO - { 07:56:43 INFO - "project": "", 07:56:43 INFO - "product": "firefox", 07:56:43 INFO - "script_repo_revision": "production", 07:56:43 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 07:56:43 INFO - "repository": "", 07:56:43 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 07:56:43 INFO - "buildid": "20160824061127", 07:56:43 INFO - "pgo_build": "False", 07:56:43 INFO - "basedir": "/builds/slave/test", 07:56:43 INFO - "buildnumber": 479, 07:56:43 INFO - "slavename": "talos-linux64-ix-012", 07:56:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:56:43 INFO - "platform": "ubuntu64_hw", 07:56:43 INFO - "scheduler": "tests-autoland-linux64-talos", 07:56:43 INFO - "branch": "autoland", 07:56:43 INFO - "repo_path": "integration/autoland", 07:56:43 INFO - "revision": "a215678635e0f7ac78f8da5e723a1e09d37c3f97", 07:56:43 INFO - "stage_platform": "linux64", 07:56:43 INFO - "builduid": "1c7e6c411479456bb03f9ea89b4c778d", 07:56:43 INFO - "slavebuilddir": "test" 07:56:43 INFO - } 07:56:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:56:43 INFO - Running post-action listener: _resource_record_post_action 07:56:43 INFO - [mozharness: 2016-08-24 14:56:43.432111Z] Finished read-buildbot-config step (success) 07:56:43 INFO - [mozharness: 2016-08-24 14:56:43.432199Z] Running download-and-extract step. 07:56:43 INFO - Running pre-action listener: _resource_record_pre_action 07:56:43 INFO - Running main action method: download_and_extract 07:56:43 INFO - mkdir: /builds/slave/test/build/tests 07:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:43 INFO - trying https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:56:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:44 INFO - Downloaded 1361 bytes. 07:56:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:56:44 INFO - Using the following test package requirements: 07:56:44 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:56:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:56:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:56:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'jsshell-linux-x86_64.zip'], 07:56:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:56:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:56:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:56:44 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:56:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:56:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:56:44 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:56:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:56:44 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')]} 07:56:44 INFO - trying https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:56:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:44 INFO - Downloaded 20324257 bytes. 07:56:44 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 07:56:49 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 07:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:49 INFO - trying https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:56:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:49 INFO - Downloaded 20324257 bytes. 07:56:49 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 07:56:54 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')]} 07:56:54 INFO - trying https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:56:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:56 INFO - Downloaded 14413981 bytes. 07:56:56 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 07:56:56 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')]} 07:56:56 INFO - trying https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:56:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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 07:56:58 INFO - Downloaded 55758308 bytes. 07:56:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:56:58 INFO - mkdir: /builds/slave/test/properties 07:56:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:56:58 INFO - Writing to file /builds/slave/test/properties/build_url 07:56:58 INFO - Contents: 07:56:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:56:59 INFO - Running post-action listener: _resource_record_post_action 07:56:59 INFO - Running post-action listener: set_extra_try_arguments 07:56:59 INFO - [mozharness: 2016-08-24 14:56:59.551214Z] Finished download-and-extract step (success) 07:56:59 INFO - [mozharness: 2016-08-24 14:56:59.551437Z] Running populate-webroot step. 07:56:59 INFO - Running pre-action listener: _resource_record_pre_action 07:56:59 INFO - Running main action method: populate_webroot 07:56:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:56:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:56:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:56:59 INFO - '--tppagecycles', 07:56:59 INFO - '7'], 07:56:59 INFO - 'tests': ['tsvgm']}}, 07:56:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:56:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:56:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:56:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:56:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['damp', 'tps']}, 07:56:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'tests': ['damp', 'tps']}, 07:56:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['dromaeo_dom']}, 07:56:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:56:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['basic_compositor_video']}, 07:56:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:56:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'other-e10s': {'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['a11yr', 07:56:59 INFO - 'ts_paint', 07:56:59 INFO - 'tpaint', 07:56:59 INFO - 'sessionrestore', 07:56:59 INFO - 'sessionrestore_no_auto_restore', 07:56:59 INFO - 'tabpaint']}, 07:56:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['tsvgx', 07:56:59 INFO - 'tsvgr_opacity', 07:56:59 INFO - 'tart', 07:56:59 INFO - 'tscrollx', 07:56:59 INFO - 'cart']}, 07:56:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:56:59 INFO - 'tsvgr_opacity', 07:56:59 INFO - 'tart', 07:56:59 INFO - 'tscrollx', 07:56:59 INFO - 'cart']}, 07:56:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'talos_options': ['--disable-e10s'], 07:56:59 INFO - 'tests': ['tp5o']}, 07:56:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'tests': ['tp5o']}, 07:56:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'talos_options': ['--disable-e10s', 07:56:59 INFO - '--xperf_path', 07:56:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:59 INFO - 'tests': ['tp5n']}, 07:56:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:59 INFO - 'talos_options': ['--xperf_path', 07:56:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:59 INFO - 'tests': ['tp5n']}}, 07:56:59 INFO - 'talos.zip': {'path': '', 07:56:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:56:59 INFO - Running post-action listener: _resource_record_post_action 07:56:59 INFO - [mozharness: 2016-08-24 14:56:59.570975Z] Finished populate-webroot step (success) 07:56:59 INFO - [mozharness: 2016-08-24 14:56:59.571062Z] Running create-virtualenv step. 07:56:59 INFO - Running pre-action listener: _resource_record_pre_action 07:56:59 INFO - Running main action method: create_virtualenv 07:56:59 INFO - Creating virtualenv /builds/slave/test/build/venv 07:56:59 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 07:56:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:56:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:56:59 INFO - Using real prefix '/usr' 07:56:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:57:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:57:02 INFO - Installing pip.................done. 07:57:02 INFO - Return code: 0 07:57:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:57:02 INFO - Using env: {'DISPLAY': ':0', 07:57:02 INFO - 'HOME': '/home/cltbld', 07:57:02 INFO - 'LANG': 'en_US.UTF-8', 07:57:02 INFO - 'LANGUAGE': 'en_US:en', 07:57:02 INFO - 'LOGNAME': 'cltbld', 07:57:02 INFO - 'MAIL': '/var/mail/cltbld', 07:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:02 INFO - 'MOZ_NO_REMOTE': '1', 07:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:02 INFO - 'NO_EM_RESTART': '1', 07:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:02 INFO - 'PWD': '/builds/slave/test', 07:57:02 INFO - 'SHELL': '/bin/bash', 07:57:02 INFO - 'SHLVL': '1', 07:57:02 INFO - 'TERM': 'linux', 07:57:02 INFO - 'TMOUT': '86400', 07:57:02 INFO - 'USER': 'cltbld', 07:57:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:02 INFO - '_': '/tools/buildbot/bin/python'} 07:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:02 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 07:57:02 INFO - Cleaning up... 07:57:02 INFO - Return code: 0 07:57:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:57:02 INFO - Using env: {'DISPLAY': ':0', 07:57:02 INFO - 'HOME': '/home/cltbld', 07:57:02 INFO - 'LANG': 'en_US.UTF-8', 07:57:02 INFO - 'LANGUAGE': 'en_US:en', 07:57:02 INFO - 'LOGNAME': 'cltbld', 07:57:02 INFO - 'MAIL': '/var/mail/cltbld', 07:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:02 INFO - 'MOZ_NO_REMOTE': '1', 07:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:02 INFO - 'NO_EM_RESTART': '1', 07:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:02 INFO - 'PWD': '/builds/slave/test', 07:57:02 INFO - 'SHELL': '/bin/bash', 07:57:02 INFO - 'SHLVL': '1', 07:57:02 INFO - 'TERM': 'linux', 07:57:02 INFO - 'TMOUT': '86400', 07:57:02 INFO - 'USER': 'cltbld', 07:57:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:02 INFO - '_': '/tools/buildbot/bin/python'} 07:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:02 INFO - Downloading/unpacking psutil>=3.1.1 07:57:02 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 07:57:02 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 07:57:02 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 07:57:02 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 07:57:02 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 07:57:02 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 07:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:04 INFO - Installing collected packages: psutil 07:57:04 INFO - Running setup.py install for psutil 07:57:04 INFO - building 'psutil._psutil_linux' extension 07:57:04 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 07:57:04 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 07:57:04 INFO - building 'psutil._psutil_posix' extension 07:57:04 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 07:57:04 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 07:57:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:05 INFO - Successfully installed psutil 07:57:05 INFO - Cleaning up... 07:57:05 INFO - Return code: 0 07:57:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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')]} 07:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:05 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:05 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 07:57:05 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 07:57:05 INFO - Using env: {'DISPLAY': ':0', 07:57:05 INFO - 'HOME': '/home/cltbld', 07:57:05 INFO - 'LANG': 'en_US.UTF-8', 07:57:05 INFO - 'LANGUAGE': 'en_US:en', 07:57:05 INFO - 'LOGNAME': 'cltbld', 07:57:05 INFO - 'MAIL': '/var/mail/cltbld', 07:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:05 INFO - 'MOZ_NO_REMOTE': '1', 07:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:05 INFO - 'NO_EM_RESTART': '1', 07:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:05 INFO - 'PWD': '/builds/slave/test', 07:57:05 INFO - 'SHELL': '/bin/bash', 07:57:05 INFO - 'SHLVL': '1', 07:57:05 INFO - 'TERM': 'linux', 07:57:05 INFO - 'TMOUT': '86400', 07:57:05 INFO - 'USER': 'cltbld', 07:57:05 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:05 INFO - '_': '/tools/buildbot/bin/python'} 07:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:05 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 07:57:05 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 07:57:05 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 07:57:05 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 07:57:05 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 07:57:05 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 07:57:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:07 INFO - Installing collected packages: mozsystemmonitor 07:57:07 INFO - Running setup.py install for mozsystemmonitor 07:57:07 INFO - Successfully installed mozsystemmonitor 07:57:07 INFO - Cleaning up... 07:57:07 INFO - Return code: 0 07:57:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:07 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 07:57:07 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 07:57:07 INFO - Using env: {'DISPLAY': ':0', 07:57:07 INFO - 'HOME': '/home/cltbld', 07:57:07 INFO - 'LANG': 'en_US.UTF-8', 07:57:07 INFO - 'LANGUAGE': 'en_US:en', 07:57:07 INFO - 'LOGNAME': 'cltbld', 07:57:07 INFO - 'MAIL': '/var/mail/cltbld', 07:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:07 INFO - 'MOZ_NO_REMOTE': '1', 07:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:07 INFO - 'NO_EM_RESTART': '1', 07:57:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:07 INFO - 'PWD': '/builds/slave/test', 07:57:07 INFO - 'SHELL': '/bin/bash', 07:57:07 INFO - 'SHLVL': '1', 07:57:07 INFO - 'TERM': 'linux', 07:57:07 INFO - 'TMOUT': '86400', 07:57:07 INFO - 'USER': 'cltbld', 07:57:07 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:07 INFO - '_': '/tools/buildbot/bin/python'} 07:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:07 INFO - Downloading/unpacking jsonschema==2.5.1 07:57:07 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 07:57:07 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 07:57:07 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 07:57:07 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 07:57:07 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 07:57:07 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 07:57:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:57:09 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 07:57:09 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 07:57:09 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 07:57:09 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 07:57:09 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 07:57:09 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 07:57:09 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:09 INFO - warning: no files found matching '*.txt' 07:57:09 INFO - no previously-included directories found matching 'build' 07:57:09 INFO - no previously-included directories found matching 'dist' 07:57:09 INFO - no previously-included directories found matching '.git*' 07:57:09 INFO - Installing collected packages: jsonschema, functools32 07:57:09 INFO - Running setup.py install for functools32 07:57:09 INFO - warning: no files found matching '*.txt' 07:57:09 INFO - no previously-included directories found matching 'build' 07:57:09 INFO - no previously-included directories found matching 'dist' 07:57:09 INFO - no previously-included directories found matching '.git*' 07:57:09 INFO - Successfully installed jsonschema functools32 07:57:09 INFO - Cleaning up... 07:57:09 INFO - Return code: 0 07:57:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:57:09 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')]} 07:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:09 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')]} 07:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:09 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:09 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 07:57:09 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 07:57:09 INFO - Using env: {'DISPLAY': ':0', 07:57:09 INFO - 'HOME': '/home/cltbld', 07:57:09 INFO - 'LANG': 'en_US.UTF-8', 07:57:09 INFO - 'LANGUAGE': 'en_US:en', 07:57:09 INFO - 'LOGNAME': 'cltbld', 07:57:09 INFO - 'MAIL': '/var/mail/cltbld', 07:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:09 INFO - 'MOZ_NO_REMOTE': '1', 07:57:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:09 INFO - 'NO_EM_RESTART': '1', 07:57:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:09 INFO - 'PWD': '/builds/slave/test', 07:57:09 INFO - 'SHELL': '/bin/bash', 07:57:09 INFO - 'SHLVL': '1', 07:57:09 INFO - 'TERM': 'linux', 07:57:09 INFO - 'TMOUT': '86400', 07:57:09 INFO - 'USER': 'cltbld', 07:57:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:09 INFO - '_': '/tools/buildbot/bin/python'} 07:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:57:10 INFO - Cleaning up... 07:57:10 INFO - Return code: 0 07:57:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:57:10 INFO - Using env: {'DISPLAY': ':0', 07:57:10 INFO - 'HOME': '/home/cltbld', 07:57:10 INFO - 'LANG': 'en_US.UTF-8', 07:57:10 INFO - 'LANGUAGE': 'en_US:en', 07:57:10 INFO - 'LOGNAME': 'cltbld', 07:57:10 INFO - 'MAIL': '/var/mail/cltbld', 07:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:10 INFO - 'MOZ_NO_REMOTE': '1', 07:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:10 INFO - 'NO_EM_RESTART': '1', 07:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:10 INFO - 'PWD': '/builds/slave/test', 07:57:10 INFO - 'SHELL': '/bin/bash', 07:57:10 INFO - 'SHLVL': '1', 07:57:10 INFO - 'TERM': 'linux', 07:57:10 INFO - 'TMOUT': '86400', 07:57:10 INFO - 'USER': 'cltbld', 07:57:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:10 INFO - '_': '/tools/buildbot/bin/python'} 07:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:10 INFO - Downloading/unpacking blobuploader==1.2.4 07:57:10 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 07:57:10 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 07:57:10 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 07:57:10 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 07:57:10 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 07:57:10 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 07:57:12 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 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 07:57:12 INFO - Downloading docopt-0.6.1.tar.gz 07:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:13 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:13 INFO - Running setup.py install for blobuploader 07:57:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:13 INFO - Running setup.py install for requests 07:57:13 INFO - Running setup.py install for docopt 07:57:13 INFO - Successfully installed blobuploader requests docopt 07:57:13 INFO - Cleaning up... 07:57:13 INFO - Return code: 0 07:57:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:13 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:13 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 07:57:13 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 07:57:13 INFO - Using env: {'DISPLAY': ':0', 07:57:13 INFO - 'HOME': '/home/cltbld', 07:57:13 INFO - 'LANG': 'en_US.UTF-8', 07:57:13 INFO - 'LANGUAGE': 'en_US:en', 07:57:13 INFO - 'LOGNAME': 'cltbld', 07:57:13 INFO - 'MAIL': '/var/mail/cltbld', 07:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:13 INFO - 'MOZ_NO_REMOTE': '1', 07:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:13 INFO - 'NO_EM_RESTART': '1', 07:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:13 INFO - 'PWD': '/builds/slave/test', 07:57:13 INFO - 'SHELL': '/bin/bash', 07:57:13 INFO - 'SHLVL': '1', 07:57:13 INFO - 'TERM': 'linux', 07:57:13 INFO - 'TMOUT': '86400', 07:57:13 INFO - 'USER': 'cltbld', 07:57:13 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:13 INFO - '_': '/tools/buildbot/bin/python'} 07:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:13 INFO - Running setup.py (path:/tmp/pip-r0vPNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:13 INFO - Running setup.py (path:/tmp/pip-yHXLPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:14 INFO - Running setup.py (path:/tmp/pip-SPQbnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:14 INFO - Running setup.py (path:/tmp/pip-0cd2DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:14 INFO - Running setup.py (path:/tmp/pip-NFNnYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:14 INFO - Running setup.py (path:/tmp/pip-cEY259-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:14 INFO - Running setup.py (path:/tmp/pip-v1OofJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:14 INFO - Running setup.py (path:/tmp/pip-Kb4A8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:14 INFO - Running setup.py (path:/tmp/pip-uatkNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:14 INFO - Running setup.py (path:/tmp/pip-WzRumA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:14 INFO - Running setup.py (path:/tmp/pip-Z76aVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:14 INFO - Running setup.py (path:/tmp/pip-RV505j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:14 INFO - Running setup.py (path:/tmp/pip-qjhemo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:14 INFO - Running setup.py (path:/tmp/pip-jhTFnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:14 INFO - Running setup.py (path:/tmp/pip-zK62P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:14 INFO - Running setup.py (path:/tmp/pip-GlcC2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:15 INFO - Running setup.py (path:/tmp/pip-FZo7e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:15 INFO - Running setup.py install for manifestparser 07:57:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozcrash 07:57:15 INFO - Running setup.py install for mozdebug 07:57:15 INFO - Running setup.py install for mozdevice 07:57:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozfile 07:57:15 INFO - Running setup.py install for mozhttpd 07:57:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozinfo 07:57:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozInstall 07:57:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozleak 07:57:16 INFO - Running setup.py install for mozlog 07:57:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for moznetwork 07:57:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozprocess 07:57:16 INFO - Running setup.py install for mozprofile 07:57:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozrunner 07:57:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozscreenshot 07:57:16 INFO - Running setup.py install for moztest 07:57:16 INFO - Running setup.py install for mozversion 07:57:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:16 INFO - Cleaning up... 07:57:16 INFO - Return code: 0 07:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:16 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:16 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 07:57:16 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 07:57:16 INFO - Using env: {'DISPLAY': ':0', 07:57:16 INFO - 'HOME': '/home/cltbld', 07:57:16 INFO - 'LANG': 'en_US.UTF-8', 07:57:16 INFO - 'LANGUAGE': 'en_US:en', 07:57:16 INFO - 'LOGNAME': 'cltbld', 07:57:16 INFO - 'MAIL': '/var/mail/cltbld', 07:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:16 INFO - 'MOZ_NO_REMOTE': '1', 07:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:16 INFO - 'NO_EM_RESTART': '1', 07:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:16 INFO - 'PWD': '/builds/slave/test', 07:57:16 INFO - 'SHELL': '/bin/bash', 07:57:16 INFO - 'SHLVL': '1', 07:57:16 INFO - 'TERM': 'linux', 07:57:16 INFO - 'TMOUT': '86400', 07:57:16 INFO - 'USER': 'cltbld', 07:57:16 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:16 INFO - '_': '/tools/buildbot/bin/python'} 07:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:17 INFO - Running setup.py (path:/tmp/pip-fylG3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:17 INFO - Running setup.py (path:/tmp/pip-Zntd4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:17 INFO - Running setup.py (path:/tmp/pip-4YULtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:17 INFO - Running setup.py (path:/tmp/pip-lnDdI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:17 INFO - Running setup.py (path:/tmp/pip-NaBWkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:17 INFO - Running setup.py (path:/tmp/pip-Gc5jQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:17 INFO - Running setup.py (path:/tmp/pip-OeGpHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:17 INFO - Running setup.py (path:/tmp/pip-SlXQXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:17 INFO - Running setup.py (path:/tmp/pip-kdAS16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:17 INFO - Running setup.py (path:/tmp/pip-Gs7vng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:17 INFO - Running setup.py (path:/tmp/pip-nJ2kJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:17 INFO - Running setup.py (path:/tmp/pip-EzqmCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:18 INFO - Running setup.py (path:/tmp/pip-_pbkTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:18 INFO - Running setup.py (path:/tmp/pip-O8t8p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:18 INFO - Running setup.py (path:/tmp/pip-9Q6OIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:18 INFO - Running setup.py (path:/tmp/pip-wDDtKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:18 INFO - Running setup.py (path:/tmp/pip-u0qzk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:18 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)) 07:57:18 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)) 07:57:18 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)) 07:57:18 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)) 07:57:18 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)) 07:57:18 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)) 07:57:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:20 INFO - Downloading blessings-1.6.tar.gz 07:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:20 INFO - Installing collected packages: blessings 07:57:20 INFO - Running setup.py install for blessings 07:57:20 INFO - Successfully installed blessings 07:57:20 INFO - Cleaning up... 07:57:20 INFO - Return code: 0 07:57:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:20 INFO - Reading from file tmpfile_stdout 07:57:20 INFO - Current package versions: 07:57:20 INFO - argparse == 1.2.1 07:57:20 INFO - blessings == 1.6 07:57:20 INFO - blobuploader == 1.2.4 07:57:20 INFO - docopt == 0.6.1 07:57:20 INFO - functools32 == 3.2.3-2 07:57:20 INFO - jsonschema == 2.5.1 07:57:20 INFO - manifestparser == 1.1 07:57:20 INFO - mozInstall == 1.12 07:57:20 INFO - mozcrash == 0.17 07:57:20 INFO - mozdebug == 0.1 07:57:20 INFO - mozdevice == 0.48 07:57:20 INFO - mozfile == 1.2 07:57:20 INFO - mozhttpd == 0.7 07:57:20 INFO - mozinfo == 0.9 07:57:20 INFO - mozleak == 0.1 07:57:20 INFO - mozlog == 3.3 07:57:20 INFO - moznetwork == 0.27 07:57:20 INFO - mozprocess == 0.23 07:57:20 INFO - mozprofile == 0.28 07:57:20 INFO - mozrunner == 6.12 07:57:20 INFO - mozscreenshot == 0.1 07:57:20 INFO - mozsystemmonitor == 0.3 07:57:20 INFO - moztest == 0.7 07:57:20 INFO - mozversion == 1.4 07:57:20 INFO - psutil == 3.1.1 07:57:20 INFO - requests == 1.2.3 07:57:20 INFO - wsgiref == 0.1.2 07:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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')]} 07:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:20 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 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57:20 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 07:57:20 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 07:57:20 INFO - Using env: {'DISPLAY': ':0', 07:57:20 INFO - 'HOME': '/home/cltbld', 07:57:20 INFO - 'LANG': 'en_US.UTF-8', 07:57:20 INFO - 'LANGUAGE': 'en_US:en', 07:57:20 INFO - 'LOGNAME': 'cltbld', 07:57:20 INFO - 'MAIL': '/var/mail/cltbld', 07:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:20 INFO - 'MOZ_NO_REMOTE': '1', 07:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:20 INFO - 'NO_EM_RESTART': '1', 07:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:20 INFO - 'PWD': '/builds/slave/test', 07:57:20 INFO - 'SHELL': '/bin/bash', 07:57:20 INFO - 'SHLVL': '1', 07:57:20 INFO - 'TERM': 'linux', 07:57:20 INFO - 'TMOUT': '86400', 07:57:20 INFO - 'USER': 'cltbld', 07:57:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:20 INFO - '_': '/tools/buildbot/bin/python'} 07:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 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)) 07:57:20 INFO - Cleaning up... 07:57:20 INFO - Return code: 0 07:57:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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')]} 07:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad455cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2816230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', '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 07:57: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', 'jsonschema'] in /builds/slave/test/build 07:57: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 jsonschema 07:57:20 INFO - Using env: {'DISPLAY': ':0', 07:57:20 INFO - 'HOME': '/home/cltbld', 07:57:20 INFO - 'LANG': 'en_US.UTF-8', 07:57:20 INFO - 'LANGUAGE': 'en_US:en', 07:57:20 INFO - 'LOGNAME': 'cltbld', 07:57:20 INFO - 'MAIL': '/var/mail/cltbld', 07:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:20 INFO - 'MOZ_NO_REMOTE': '1', 07:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:20 INFO - 'NO_EM_RESTART': '1', 07:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:20 INFO - 'PWD': '/builds/slave/test', 07:57:20 INFO - 'SHELL': '/bin/bash', 07:57:20 INFO - 'SHLVL': '1', 07:57:20 INFO - 'TERM': 'linux', 07:57:20 INFO - 'TMOUT': '86400', 07:57:20 INFO - 'USER': 'cltbld', 07:57:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:20 INFO - '_': '/tools/buildbot/bin/python'} 07:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:57:21 INFO - Cleaning up... 07:57:21 INFO - Return code: 0 07:57:21 INFO - Running post-action listener: _resource_record_post_action 07:57:21 INFO - Running post-action listener: _start_resource_monitoring 07:57:21 INFO - Starting resource monitoring. 07:57:21 INFO - [mozharness: 2016-08-24 14:57:21.191684Z] Finished create-virtualenv step (success) 07:57:21 INFO - [mozharness: 2016-08-24 14:57:21.192190Z] Running install step. 07:57:21 INFO - Running pre-action listener: _resource_record_pre_action 07:57:21 INFO - Running main action method: install 07:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:21 INFO - Reading from file tmpfile_stdout 07:57:21 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:21 INFO - Reading from file tmpfile_stdout 07:57:21 INFO - Output received: 07:57:21 INFO - Usage: mozinstall [options] installer 07:57:21 INFO - Options: 07:57:21 INFO - -h, --help show this help message and exit 07:57:21 INFO - -d DEST, --destination=DEST 07:57:21 INFO - Directory to install application into. [default: 07:57:21 INFO - "/builds/slave/test"] 07:57:21 INFO - --app=APP Application being installed. [default: firefox] 07:57:21 INFO - mkdir: /builds/slave/test/build/application 07:57:21 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'] 07:57:21 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 07:57:37 INFO - Reading from file tmpfile_stdout 07:57:37 INFO - Output received: 07:57:37 INFO - /builds/slave/test/build/application/firefox/firefox 07:57:37 INFO - Running post-action listener: _resource_record_post_action 07:57:37 INFO - [mozharness: 2016-08-24 14:57:37.587964Z] Finished install step (success) 07:57:37 INFO - [mozharness: 2016-08-24 14:57:37.588209Z] Running run-tests step. 07:57:37 INFO - Running pre-action listener: _resource_record_pre_action 07:57:37 INFO - Running main action method: run_tests 07:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:57:37 INFO - Python 2.7.3 07:57:37 INFO - Return code: 0 07:57:37 INFO - grabbing minidump binary from tooltool 07:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:37 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 0x2810090>, '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 0x28139b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2813e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:57:37 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 07:57:37 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 07:57:37 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 07:57:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:37 INFO - Return code: 0 07:57:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:57:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:57:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:57:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:57:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 07:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 07:57:37 INFO - Using env: {'DISPLAY': ':0', 07:57:37 INFO - 'HOME': '/home/cltbld', 07:57:37 INFO - 'LANG': 'en_US.UTF-8', 07:57:37 INFO - 'LANGUAGE': 'en_US:en', 07:57:37 INFO - 'LOGNAME': 'cltbld', 07:57:37 INFO - 'MAIL': '/var/mail/cltbld', 07:57:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:57:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:37 INFO - 'MOZ_NO_REMOTE': '1', 07:57:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:57:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:37 INFO - 'NO_EM_RESTART': '1', 07:57:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:37 INFO - 'PWD': '/builds/slave/test', 07:57:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:57:37 INFO - 'SHELL': '/bin/bash', 07:57:37 INFO - 'SHLVL': '1', 07:57:37 INFO - 'TERM': 'linux', 07:57:37 INFO - 'TMOUT': '86400', 07:57:37 INFO - 'USER': 'cltbld', 07:57:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280', 07:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:37 INFO - '_': '/tools/buildbot/bin/python'} 07:57:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 07:57:37 INFO - mozversion application_buildid: 20160824061127 07:57:37 INFO - mozversion application_changeset: a215678635e0f7ac78f8da5e723a1e09d37c3f97 07:57:37 INFO - mozversion application_display_name: Nightly 07:57:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:37 INFO - mozversion application_name: Firefox 07:57:37 INFO - mozversion application_remotingname: firefox 07:57:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:57:37 INFO - mozversion application_vendor: Mozilla 07:57:37 INFO - mozversion application_version: 51.0a1 07:57:37 INFO - mozversion platform_buildid: 20160824061127 07:57:37 INFO - mozversion platform_changeset: a215678635e0f7ac78f8da5e723a1e09d37c3f97 07:57:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:57:37 INFO - mozversion platform_version: 51.0a1 07:57:37 INFO - using testdate: 1472050657 07:57:37 INFO - actual date: 1472050657 07:57:37 INFO - starting webserver on 'localhost:33477' 07:57:37 INFO - SUITE-START | Running 2 tests 07:57:37 INFO - TEST-START | dromaeo_css 07:57:37 INFO - operating with platform_type : linux_ 07:57:37 INFO - Initialising browser for dromaeo_css test... 07:57:37 INFO - TEST-INFO | started process 8582 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAdVT8A/profile http://localhost:33477/getInfo.html) 07:57:41 INFO - PROCESS | 8582 | __metrics Screen width/height:1600/1200 07:57:41 INFO - PROCESS | 8582 | colorDepth:24 07:57:41 INFO - PROCESS | 8582 | Browser inner width/height: 1024/697 07:57:41 INFO - PROCESS | 8582 | __metrics 07:57:42 INFO - PROCESS | 8582 | 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" 07:57:42 INFO - PROCESS | 8582 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:57:43 INFO - TEST-INFO | 8582: exit 0 07:57:43 INFO - Browser initialized. 07:57:43 INFO - Running cycle 1/1 for dromaeo_css test... 07:57:43 INFO - TEST-INFO | started process 8662 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAdVT8A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:57:55 INFO - PROCESS | 8662 | RSS: Main: 181309440 07:57:55 INFO - PROCESS | 8662 | 07:58:47 INFO - PROCESS | 8662 | 1472050727758 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] 08:00:59 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33477/tests/dromaeo/cssquery-ext.html) 08:01:00 INFO - PROCESS | 8662 | RSS: Main: 270295040 08:01:00 INFO - PROCESS | 8662 | 08:04:02 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-ext.html (next: http://localhost:33477/tests/dromaeo/cssquery-jquery.html) 08:04:03 INFO - PROCESS | 8662 | RSS: Main: 280694784 08:04:03 INFO - PROCESS | 8662 | 08:07:05 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33477/tests/dromaeo/cssquery-mootools.html) 08:07:06 INFO - PROCESS | 8662 | RSS: Main: 255201280 08:07:06 INFO - PROCESS | 8662 | 08:10:08 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33477/tests/dromaeo/cssquery-prototype.html) 08:10:09 INFO - PROCESS | 8662 | RSS: Main: 224296960 08:10:09 INFO - PROCESS | 8662 | 08:13:12 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33477/tests/dromaeo/cssquery-yui.html) 08:13:12 INFO - PROCESS | 8662 | RSS: Main: 218742784 08:13:12 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | Cycle 1(1): loaded http://localhost:33477/tests/dromaeo/cssquery-yui.html (next: http://localhost:33477/tests/dromaeo/cssquery-dojo.html) 08:16:16 INFO - PROCESS | 8662 | RSS: Main: 306475008 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | __start_tp_report 08:16:16 INFO - PROCESS | 8662 | _x_x_mozilla_page_load 08:16:16 INFO - PROCESS | 8662 | _x_x_mozilla_page_load_details 08:16:16 INFO - PROCESS | 8662 | |i|pagename|runs| 08:16:16 INFO - PROCESS | 8662 | |0;dojo.html;2516.094206085809;2528.4184637533376;2540.377607486166;2551.0561092395815;2570.087618895292;4516.751578663172;4568.908621827562;4579.175748365293;4634.698744581599;4645.5586719261655;4547.613452856503;4566.337881007252;4569.817207311712;4588.655850811194;4592.402987611614;4160.5007399112;4191.308434108369;4232.724872883253;4303.419038429819;4355.499117601485;4319.352097185431;4367.803448844794;4401.647868170555;4416.470023597173;4418.513963464033;4191.664666826647;4219.409282700424;4228.090960443488;4242.639375653073;4332.718373305741;4188.078622703;4226.3026600610865;4239.6396306314;4251.893702657428;4255.106427650167;4216;4269.509006464265;4271.295236286009;4271.807768650418;4333.414988976479;4502.684812063157;4625.606823420013;4635.0729854029;4657.673962822604;4726.1020406122725;4880.194767863299;4900.779464924087;4933.210686290176;4936.531029552187;4941.308216849644;4935.851924442272;4938.950610493921;4940.407151141886;4943.6539442239055;5007.12375334315;4485.394471746273;4523.231050721385;4618.745968971739;4621.722696638212;4624.768761561976;5035.1188542005;5041.79832806691;5057.797688092435;5114.028334616411;5134.614903882224;4844.8304309349705;4850.611951043908;4852.80588776445;4861.052094841542;4911.582515486152;4756.928646070242;4834.347363106006;4923.286123512071;4942.629302802305;4995.500449954975;4867.440244371943;4918.852434426973;4933.703977761345;4945.901081978341;4950.257461380822;4883.194272944996;4890.682105663121;4899;4903.809883062972;4968.552830245223;4197.727147735387;4198.769067701244;4200.348945913388;4203.306454434984;4246.384274280212;3633.291508155937;3644.325799727059;3648.7263455240964;3677.4116141417244;3710.1466662667735;4191.706580539394;4194.601512856274;4198.6011328923705;4240.406343111967;4241.4698162729655;4929.162042452792;4930.975345123252;4934.925976110361;4940.975295123501;4992.775325110379;4082.4488694026522;4127.938080928789;4138.193052354762;4177.791110444466;4178.979105104516;4397.73613583186;4432.3351497275025;4476.239039363316;4486.147631949919;4488.4165058542185;4619;4625.468071171858;4626.884327891829;4631.536846315342;4688.156131896291;4825.4933232011535;4876.21980483121;4926.8768280793265;4928.728919909296;4936.111499930047;4622.121796858586;4626.8149274028665;4665.2768820832825;4674.69614475058;4687.953120468888;4876.634252431011;4876.853694389174;4878.658493905535;4885.780139893643;4941.97529012367;4772.188727916332;4875.19559272716;4917.655764096479;4932.062908047459;4995.200767877122;4780.020095880405;4823.1800593899825;4827.637927155548;4881.341018962395;4888.997755460051;4805.7597120144555;4816.8073277069925;4820.204666230173;4821.421429428491;4875.878103047452;4633.582977531903;4693.741844198602;4735;4771.117343291502;4818.638602104786;4797.64017698681;4860.465348811698;4881.90236195267;4936.481669424802;4937.037277730951;4742.691725037862;4755.096531658974;4758.738269395217;4765.0946320199055;4801.927971080507 08:16:16 INFO - PROCESS | 8662 | |1;ext.html;543.326275418481;561.3460318728681;564.5116973817649;587.0138167877964;592.4371846745593;36659.75155589991;38746.22507549851;38819.611803881955;42329.576704232946;42361.364579531335;38029;38570.4214436783;38587;38776.80611596945;38956.61043389565;7611.847763044722;7719.228077192264;7776.30013298803;7827.843443131134;7829.17793631669;7641.923580764204;7757.185495522974;7760.456768026241;7830.647620857047;7844.137144914083;7829.725959591416;7883.763487095368;7885.172056934025;7905.446618736691;7908.130105688369;7816.570088645122;7855.214478552156;7872.3308518775975;7872.448928575002;7875.291223789829;7716.807079823049;7794.883076753853;7799.922000780033;7801.843963120706;7807.12628969049;36676;36940.44589331136;37065.62934370649;37102.44346334807;37158.44262336067;2840.3467202543325;3040.2095455181593;3057.9847100764355;3215;3426.640202778697;27850.442991140266;28195.71804281972;28215;28312.85843570769;28458.857705711547;3461.6019157796923;3575.249197668493;3615.3130905128205;3675.3384390809642;3702.648248416396;235395;235449;236457;237446;239502;30889.382212355627;49011.50988490141;49305.26042109371;49359.75320123376;49403.50596493989;31076.22309442282;32660.183495412806;32694.182645434055;32764.836175818968;32769.50845737316;11907.76184476323;12030.338331391857;12037.277763334228;12069;12091.27452352844;10139.442330671884;10306.227032972558;10316.845247321146;10326.638567650096;10328.896711032794;7645.923540764632;7659.961700191463;7722.150563438017;7726.995490586188;7730;2954.601128847621;2974.985125074404;3001.444732724453;3010.969890301069;3022.848857557113;867.5445391169686;881.167296904432;919.4299534288783;931.2410385535767;993.3692105512907;26817.865910670324;27030.32424189411;27365.86317068402;28066.43867122646;28098.958723874464;915.5742579700338;929.7443203119196;962.821877952581;976.272676855747;997.2122023601419;13871.306434678225;14769.556913292403;15081.396744130332;15165.545033649008;15186.620334491727;282414;392153;392247;392789;393321;48407.757961209965;48750.51249487531;48836.75581622069;48902;48970.510294896594;1087.923845330835;1160.2748282323548;1160.4371879638368;1178.187050934852;1185.958491452795;26852.865735671196;27964.30089247785;28125.578116327863;28461.573076403456;28530.42939141164;40402;41081.38377924393;41083;41152.58847411607;41209.79395103005;302568;303986;304009;304304;304533;21958.341249762532;24479.38801529976;38880.6111938877;39093.609063908996;39912;38620.227595448494;42620;42768.57231427646;42866.35700464558;43136.13727725365;35968.28063438664;37016.81491592525;37039;37413.25173496569;37842.81078594589;35733;36189.819050904574;36378.636213637525;37074.62925370712;37560.624393755716;36329;36457;36745.44881826828;36855.26289474249;37228.62771372252;31328.686713132578;32892.1776955578;33303.16742081467;33664.49503257404;34028;25616.359591010372;25936;25936.74063259343;26071.73928260693;26229.21312360632 08:16:16 INFO - PROCESS | 8662 | |2;jquery.html;345.55250950019723;357.55662977907394;360.0997506234414;360.46111063959387;373.63010619486715;12929.159604625704;15114.92442537787;16824.495265142064;16899.831001689978;17001.65996680067;16238;16506.42227522037;16695.83304166958;16739.66520669586;16800.32798688054;4399.362092496586;4645.6980296280635;4677.74272415017;4688.554587314216;4694.131585656647;3494.8776792812255;4622.58396744293;4658.836940707076;4665.0669866026765;4665.2768820832825;5064.949350506485;5288.8413347599635;5314.043472175007;5373.973130134364;5387.892242155154;5183.455737147583;5326.201069839516;5352.598555108363;5364.302640656729;5364.892702145974;5206.869828254286;5212.166053431432;5215.947840521584;5217.034848552992;5219.895602087937;11849.822252666218;12167.817482737766;19603.31388401399;20066.29767958129;20384.796152038587;1887.6036032433205;1969.5076230942263;2122.734658167729;2169.0889826273005;2269.3986093685185;9028.097190280985;9447.480388578626;9798;11419.858014198597;13719.657008574766;1679.781628388314;1744.8865823721417;1762.4272111563664;1799.803130917938;1802.6755184066863;65317.346826531604;71299;71422;74203.99999999946;74616.25383746147;9549.904500955041;9614.471204083702;10278.17774578032;10355.585776568852;10499.737506562396;13879.930600346934;13894.583162505141;13995.720085598232;14015.719685606231;14017.088889222168;6746.797596072126;6800;6865.107536020385;6972.616506092214;6974.546654467444;6197.659128747962;6263.843403914848;6411.198600174978;6437.485001199892;6474.611523308617;4215.683823713233;4270.893227669333;4312.7627980461375;4379.846705365297;4438.556144385536;1782.393986044751;1815.1105958080445;1820.1536285627246;1852.4720454670355;1856.201833211705;828.2545708862073;830.7341650671727;850.8043150075393;852.7484392104344;904.5160838951261;15419.92290038565;17188.054656993736;17270.22283997198;17398.739018914726;18322.908385457988;808.8301456694043;827.4952279109739;838.2413915406567;857.5797859049021;875.5622188905547;7998.600069996593;9476.668316608886;10349.379037257788;10404.739881502872;10754.838677419844;408274;411479;412769;413199;414701;79016.20983790203;80233;81001.18998810054;81774.59112704398;82612;925.7685578605349;926.4996901673156;927.8422668146432;940.6002448959165;972.8540718892223;15568.922155389151;15662.686746265239;15955.680886382439;16215.756763648318;16382.426615068416;26557.468850622492;28125.71874281231;38070;39898;40882.59117408788;53758.19362709484;53885.730571346896;54148;54299.45700542944;54572;7301.233370496021;7544.132424771002;7551.169371369255;7687.692492300245;7702.614869256627;11075.723106922069;11364.602238921598;11700.47347869365;11830.940845295718;12353.073519485895;9957.302988790716;10010.14913732341;10142.645007424704;10479.68560943173;10693.358398496115;10464.372137671764;10508.316959397614;10575.947120264656;10746.946265268623;10923.453827308444;10867.891321087005;10897.12822974176;10963.342199468058;11061.944690276498;11218.943905280421;7571.394288457021;7706.730264440717;7711.26742959418;7730.420218483749;7926.365890728844;8056.919430805617;9967.800643987224;10078.949605251926;10186.694399168036;10304.866464688728 08:16:16 INFO - PROCESS | 8662 | |3;mootools.html;1238.9938050309747;1320.4256148575369;1373.6565858535366;1420.3608376230693;1635.0353291557979;6581.506387020974;7176.569405835654;7448.292412220837;7451.105867295919;7672.88490672639;7451.552906825581;7520.511166774156;7525.924740752591;7528.661210245538;7530.246975302467;2099.4226587688354;2122.4269447249235;2124.7875212478784;2129.510212651091;2131.531063166101;2083.5832833433374;2103.221807931068;2128.88291143987;2129.691194776756;2132.76539580646;2028.6348457277684;2047.4267205182573;2060.9484762880898;2065.328768150357;2075.6678931370984;2064.143380497092;2070.782567830371;2082.1983536338466;2089.634313995045;2091.5712278982924;2016.5462770876582;2027.0675489274952;2066.2354928676336;2068.379486154148;2075.647139986206;7539.170691223961;7684;7768.883466748003;7773.922260777433;7791.10402303731;4146.1914926589325;4284.357346398066;4302.935455968163;4305.354196870463;4311.611954924072;2695.5956606508985;2837.7729781617422;2883.610712553799;2888.393437378153;2893.8842446302124;924.4407133684092;924.5100096948628;941.9905800941972;951.1012093571578;951.1487218939011;10430.478476076165;10509.894901051044;10530.894691053069;10535;10549.841752373797;3835.213781174908;3841.692664586826;3967.897014582276;4007.6192761687594;4009.077912080238;8275.131111233355;8288.70989515364;8345.123762004901;8359.8328033439;8365.163483651708;4374.190774706626;4381.627561657231;4392.143532011227;4393.450818647669;4394.648428125743;3482.6169121396624;3798.4682144499793;3936.7834769087403;4097.160082183145;4117.3618089196225;3286.457734473785;3323.4350160472777;3406.8977930662118;3422.3326451342245;3427.4002049641713;996.5721098912046;1018.9643362482277;1021.4535223655308;1032.2155162076726;1032.8708911386077;796.2714116583261;830.9210624990616;840.0507426608016;849.0321034021221;861.8965724113147;5212.035773381861;5451.536619387396;5467.234587157801;5653;5706.600537962386;782.1083964280724;842.0484852117191;854.7350321400386;858.5490521019457;910.717677519971;3560.626134255918;3609.8917032489067;3725.869594564177;3733.5706393765063;3755.9812200938823;9102.362834601514;9587.568559414844;9680.467574283482;9700.611975521042;9709.757256068513;9588.37675551087;9630.32587718853;9666.423423333412;9668.806623867484;9675.467849268218;7209.89185162223;7239.239879812649;7298.598577078312;7326.780196594111;7368.74209402679;5291;5292.153255479105;5367.570594352364;5433.266509021231;5518.558515318685;8382.203690649623;8578.914210858062;8647.308215342884;8775.824483510421;8787.25308348772;8527.360447966303;8905.688300909436;8906.465612063297;9006;9115.817683646423;6154.169187159855;6387.808365749035;6807.0810440589885;6943.375096241362;6946.895796562954;7173.892391614234;7394.630268486661;7433.8141546461775;7437.739679111205;7441;6616.90074648871;6638.601683898982;6707.608869038069;6761.256261811295;6862.828429289308;6598.934010659832;6600.900986485302;6602.207735071822;6603.636799976048;6660.3672651100005;6065.059915713072;6146;6148.354422785543;6150.384961503813;6161.938380616137;4529.977350113229;4637.65217608674;4686.203345431217;4696.2955556666775;4731.313959475788;4630.513796051366;4639.976800115978;4672.836450724209;4772.212584923449;4794.400699912511 08:16:16 INFO - PROCESS | 8662 | |4;prototype.html;280.3453935061631;287.8201124297314;292.57430438711674;293.5230250842381;293.6520223535111;4625.514320996297;4744.857654270373;4767.427908650967;4773.90452190956;4805.231163013918;4517.887052823681;4622.121796858594;4646.210144275473;4665.88335291617;4671.602913752336;3710.666040056394;3715.275521273339;3718.981405092971;3736.271427071722;3741.3265612189725;3569.7858128512244;3725.85763186169;3734.3278209922205;3738.8130593470355;3771.1326394929183;3466.2374277658873;3675.5405574303213;3699;3706.314331848617;3714.9442758358646;3697.5193224880727;3698.389765688658;3700.0379901225606;3703.592604813495;3708.35103856824;3282.983585082059;3283.162793487669;3284.441644920369;3314.1880239341517;3315.8176300303476;4502.189605870942;4518.638508919312;4546.840860569897;4631.675782695213;4650.604698600623;4206.179794939988;4207.91584168318;4210.59999300066;4216.936745948813;4224.746944852477;4886.315915771795;4896.608271338285;4900.068986892479;4912.778924948386;4916.410030796292;3484.63411341808;3584.551931008624;3584.8028358440274;3590.174259920207;3613.9277214455565;5047.621428392885;5048.844472204872;5073.34046573943;5080.364954380702;5129.204973229155;3606.2246616977477;3613.349597072552;3614.765040272374;3627.510286111394;3638.072291565634;3682.429223470366;3693.4459831025024;3696.4270538066644;3705.981470092632;3708.2398108388256;4080.693947953856;4196.412502249687;4197.580241975778;4202.495700515897;4215.852445164404;4886.438059623189;4890.58430033444;4892.486288939681;4897.804087836446;4950.183219768698;1204.0608325505943;1230.6185082624415;1263.5072321794507;1291.1349395904767;1293.9741205176033;883.6023789294843;891.4695756025284;895.2166854002747;900.8199258970769;902.4359775140537;4275.5724427557;4277.443932288845;4290.570942905684;4301.913961720748;4310.8706738797255;4999.42506611737;5000.199968005102;5020.171671674133;5026.723530205801;5035.77339019745;4095.52901416335;4286.89282767927;4338.934915976263;4362.738235705804;4381.868543943687;2673.1980405878157;2687.6774787025292;2691.4347986922967;2698.7571118599026;2699.5545734953907;4777.976110119427;4779.952200477951;4782.832600859023;4803.423589169322;4822.855314340505;5128.230765385148;5135.62885603012;5156.896862062813;5177.86087060846;5181.745940515888;4719.528047195322;4730.763461826964;4730.858074257778;4736.857894263108;4757.333973243749;5067.265246539305;5068.974655126848;5069.3156423883775;5074.264231686311;5076.340075790124;4836.854894353175;4922.532359425849;4938.950610493849;4943.8516844494725;4980.701157930535;4722.5513576210205;4734.4081989751285;4736.8105275788585;4736.952630473789;4743.83396581118;3594.586622538337;3603.0992251937014;3605.531280933462;3616.1682812953695;3621.384364658067;4840.636952228668;4974.850754477371;4994.925076123788;4996.6002719783055;5067.797288108434;4914.434839993446;4915.311856340187;4917.360743103373;4923.876903077452;4926.58124059459;4890.584300334512;4899.583535399531;4912.410510738734;4918.753622609061;4918.754062296943;4604.58558729716;4626.976865115653;4738.407699037621;4819.638527110411;4838.588719958702;4866.221404575251;4871.926921096116;4882.487338829372;4893.143699852582;4900.92648610264;4764.142454358249;4791.95208047929;4809.9038019240115;4810.254410566367;4812.133815913169 08:16:16 INFO - PROCESS | 8662 | |5;yui.html;602.2157180283378;631.6178711879313;680.3434685528467;701.8105111619866;731.6670914733796;12400.503979840807;12932.676683082904;12959.157654752435;12981.350932453386;12995.545155919544;12413.131080824345;12454.875451245485;12466.314352710597;12545.1218414711;12879.420426080826;612.2377639838408;722.6061796321018;729.6461216310112;753.5365750063726;753.5779963220587;761.6800943603672;763.5914785589711;763.7441457111875;764.1136281912982;764.1365257259289;799.4124318625805;800.1998001998002;800.3037357498984;800.8478389106051;801.9639116239783;649.7271146118666;779.2480256552398;799.5043073294595;800.4236949396425;801.8877357169943;588.047363271503;588.1148870949237;588.6644612570836;588.6938791828226;588.7733222709245;12500.312482813442;12529.937350313281;12547;12549.309787961658;12555.748885022249;12352.752944941141;12757.55348562796;12940.417681204368;13043.152195107383;13373.995834573101;11103.944480277627;11169.944150279196;11386.316820990767;11545.884541154648;11962;3978.170757164592;4008.3386241270155;4023.3361495353233;4244.830206791725;4252.043290259698;63211.68394158046;63621;63756.36243637597;63939.13034784052;64153.35846641521;9867.703968880945;10255.384676919408;10271.537780799732;10540.631077912236;10824.621138260281;19195.520111997033;19207.711884321747;19225.807741922683;19242.903785480983;19248.133833977503;6791.660416979132;6810.455163586901;6875.621840798804;7047.753328633473;7130.180029296594;3632.6912212462234;3722.1253005543676;3729.7202709796875;3736.3461394255896;3764.378877485239;2740.6985231624503;2755.6968733439307;2795.46886091646;2797.6922538521153;2816.070696670094;496.3894409875873;502.5728131088546;504.57394292751803;506.6200349737697;516.3906590223572;558.1655425139361;569.4590139367617;571.2003195526213;580.1182203051338;580.4166812353558;7616.238376162451;7864.9606751965875;7938.483998540077;8171.264586187272;8199.139090395542;435.25571273123313;524.9947500524946;556.9418105599312;585.5871610514577;588.150839897218;3606.062423769839;3715.6655900968503;3734.607866174067;3828.8085595720104;3855.8843234703004;101132.49433752931;101911;102782;103092;104367.4781626087;85575.14424855672;86414.56792716122;87882;90828.54585727162;91849;75098.87351689729;75321;76353;80488;80531;6908;6941.722947770505;7006.684699188447;7209.134903811576;7258.237885021997;31147.532787008666;37845;46915.530844691115;47291.52708472871;50287.47412732725;39136.80431597824;50760.23859642182;53561;60691.39308606857;60987.695061524406;11135;11444.226144947546;11462.541498339973;11525.197352683212;12042.337671428155;13208.141470804367;13541.187528747912;13760.243186624832;14091;14133.081349712236;11493.54025838957;11523.884761152616;11909.761804763682;11910.344931028876;11972.521099156285;11138.159769258787;11723.648290551297;11939.343336116597;12578.937105314415;12615.87384126147;10464.319819211727;10811.945940270249;10852.046631606387;11115.83326250123;11197.44012799373;8507.829843402973;10142.54358553852;10610.922832378525;10663.731512744655;10728.463576820972;8232.300254478436;8714.38999270045;9174.587143578421;10023.298369114093;10785.676429707122 08:16:16 INFO - PROCESS | 8662 | __end_tp_report 08:16:16 INFO - PROCESS | 8662 | __start_cc_report 08:16:16 INFO - PROCESS | 8662 | _x_x_mozilla_cycle_collect,976 08:16:16 INFO - PROCESS | 8662 | __end_cc_report 08:16:16 INFO - PROCESS | 8662 | __startTimestamp1472051776495__endTimestamp 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | ------- Summary: start ------- 08:16:16 INFO - PROCESS | 8662 | Number of tests: 6 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#0] dojo.html Cycles:165 Average:4560.03 Median:4674.70 stddev:482.22 (10.3%) stddev-sans-first:456.26 08:16:16 INFO - PROCESS | 8662 | Values: 2516.1 2528.4 2540.4 2551.1 2570.1 4516.8 4568.9 4579.2 4634.7 4645.6 4547.6 4566.3 4569.8 4588.7 4592.4 4160.5 4191.3 4232.7 4303.4 4355.5 4319.4 4367.8 4401.6 4416.5 4418.5 4191.7 4219.4 4228.1 4242.6 4332.7 4188.1 4226.3 4239.6 4251.9 4255.1 4216.0 4269.5 4271.3 4271.8 4333.4 4502.7 4625.6 4635.1 4657.7 4726.1 4880.2 4900.8 4933.2 4936.5 4941.3 4935.9 4939.0 4940.4 4943.7 5007.1 4485.4 4523.2 4618.7 4621.7 4624.8 5035.1 5041.8 5057.8 5114.0 5134.6 4844.8 4850.6 4852.8 4861.1 4911.6 4756.9 4834.3 4923.3 4942.6 4995.5 4867.4 4918.9 4933.7 4945.9 4950.3 4883.2 4890.7 4899.0 4903.8 4968.6 4197.7 4198.8 4200.3 4203.3 4246.4 3633.3 3644.3 3648.7 3677.4 3710.1 4191.7 4194.6 4198.6 4240.4 4241.5 4929.2 4931.0 4934.9 4941.0 4992.8 4082.4 4127.9 4138.2 4177.8 4179.0 4397.7 4432.3 4476.2 4486.1 4488.4 4619.0 4625.5 4626.9 4631.5 4688.2 4825.5 4876.2 4926.9 4928.7 4936.1 4622.1 4626.8 4665.3 4674.7 4688.0 4876.6 4876.9 4878.7 4885.8 4942.0 4772.2 4875.2 4917.7 4932.1 4995.2 4780.0 4823.2 4827.6 4881.3 4889.0 4805.8 4816.8 4820.2 4821.4 4875.9 4633.6 4693.7 4735.0 4771.1 4818.6 4797.6 4860.5 4881.9 4936.5 4937.0 4742.7 4755.1 4758.7 4765.1 4801.9 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#1] ext.html Cycles:180 Average:44986.18 Median:20370.82 stddev:81619.97 (400.7%) stddev-sans-first:81780.73 08:16:16 INFO - PROCESS | 8662 | Values: 543.3 561.3 564.5 587.0 592.4 36659.8 38746.2 38819.6 42329.6 42361.4 38029.0 38570.4 38587.0 38776.8 38956.6 7611.8 7719.2 7776.3 7827.8 7829.2 7641.9 7757.2 7760.5 7830.6 7844.1 7829.7 7883.8 7885.2 7905.4 7908.1 7816.6 7855.2 7872.3 7872.4 7875.3 7716.8 7794.9 7799.9 7801.8 7807.1 36676.0 36940.4 37065.6 37102.4 37158.4 2840.3 3040.2 3058.0 3215.0 3426.6 27850.4 28195.7 28215.0 28312.9 28458.9 3461.6 3575.2 3615.3 3675.3 3702.6 235395.0 235449.0 236457.0 237446.0 239502.0 30889.4 49011.5 49305.3 49359.8 49403.5 31076.2 32660.2 32694.2 32764.8 32769.5 11907.8 12030.3 12037.3 12069.0 12091.3 10139.4 10306.2 10316.8 10326.6 10328.9 7645.9 7660.0 7722.2 7727.0 7730.0 2954.6 2975.0 3001.4 3011.0 3022.8 867.5 881.2 919.4 931.2 993.4 26817.9 27030.3 27365.9 28066.4 28099.0 915.6 929.7 962.8 976.3 997.2 13871.3 14769.6 15081.4 15165.5 15186.6 282414.0 392153.0 392247.0 392789.0 393321.0 48407.8 48750.5 48836.8 48902.0 48970.5 1087.9 1160.3 1160.4 1178.2 1186.0 26852.9 27964.3 28125.6 28461.6 28530.4 40402.0 41081.4 41083.0 41152.6 41209.8 302568.0 303986.0 304009.0 304304.0 304533.0 21958.3 24479.4 38880.6 39093.6 39912.0 38620.2 42620.0 42768.6 42866.4 43136.1 35968.3 37016.8 37039.0 37413.3 37842.8 35733.0 36189.8 36378.6 37074.6 37560.6 36329.0 36457.0 36745.4 36855.3 37228.6 31328.7 32892.2 33303.2 33664.5 34028.0 25616.4 25936.0 25936.7 26071.7 26229.2 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#2] jquery.html Cycles:180 Average:24989.18 Median:209458.34 stddev:68090.71 (32.5%) stddev-sans-first:68256.58 08:16:16 INFO - PROCESS | 8662 | Values: 345.6 357.6 360.1 360.5 373.6 12929.2 15114.9 16824.5 16899.8 17001.7 16238.0 16506.4 16695.8 16739.7 16800.3 4399.4 4645.7 4677.7 4688.6 4694.1 3494.9 4622.6 4658.8 4665.1 4665.3 5064.9 5288.8 5314.0 5374.0 5387.9 5183.5 5326.2 5352.6 5364.3 5364.9 5206.9 5212.2 5215.9 5217.0 5219.9 11849.8 12167.8 19603.3 20066.3 20384.8 1887.6 1969.5 2122.7 2169.1 2269.4 9028.1 9447.5 9798.0 11419.9 13719.7 1679.8 1744.9 1762.4 1799.8 1802.7 65317.3 71299.0 71422.0 74204.0 74616.3 9549.9 9614.5 10278.2 10355.6 10499.7 13879.9 13894.6 13995.7 14015.7 14017.1 6746.8 6800.0 6865.1 6972.6 6974.5 6197.7 6263.8 6411.2 6437.5 6474.6 4215.7 4270.9 4312.8 4379.8 4438.6 1782.4 1815.1 1820.2 1852.5 1856.2 828.3 830.7 850.8 852.7 904.5 15419.9 17188.1 17270.2 17398.7 18322.9 808.8 827.5 838.2 857.6 875.6 7998.6 9476.7 10349.4 10404.7 10754.8 408274.0 411479.0 412769.0 413199.0 414701.0 79016.2 80233.0 81001.2 81774.6 82612.0 925.8 926.5 927.8 940.6 972.9 15568.9 15662.7 15955.7 16215.8 16382.4 26557.5 28125.7 38070.0 39898.0 40882.6 53758.2 53885.7 54148.0 54299.5 54572.0 7301.2 7544.1 7551.2 7687.7 7702.6 11075.7 11364.6 11700.5 11830.9 12353.1 9957.3 10010.1 10142.6 10479.7 10693.4 10464.4 10508.3 10575.9 10746.9 10923.5 10867.9 10897.1 10963.3 11061.9 11218.9 7571.4 7706.7 7711.3 7730.4 7926.4 8056.9 9967.8 10078.9 10186.7 10304.9 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#3] mootools.html Cycles:180 Average:5025.05 Median:5492.90 stddev:2832.49 (51.6%) stddev-sans-first:2826.15 08:16:16 INFO - PROCESS | 8662 | Values: 1239.0 1320.4 1373.7 1420.4 1635.0 6581.5 7176.6 7448.3 7451.1 7672.9 7451.6 7520.5 7525.9 7528.7 7530.2 2099.4 2122.4 2124.8 2129.5 2131.5 2083.6 2103.2 2128.9 2129.7 2132.8 2028.6 2047.4 2060.9 2065.3 2075.7 2064.1 2070.8 2082.2 2089.6 2091.6 2016.5 2027.1 2066.2 2068.4 2075.6 7539.2 7684.0 7768.9 7773.9 7791.1 4146.2 4284.4 4302.9 4305.4 4311.6 2695.6 2837.8 2883.6 2888.4 2893.9 924.4 924.5 942.0 951.1 951.1 10430.5 10509.9 10530.9 10535.0 10549.8 3835.2 3841.7 3967.9 4007.6 4009.1 8275.1 8288.7 8345.1 8359.8 8365.2 4374.2 4381.6 4392.1 4393.5 4394.6 3482.6 3798.5 3936.8 4097.2 4117.4 3286.5 3323.4 3406.9 3422.3 3427.4 996.6 1019.0 1021.5 1032.2 1032.9 796.3 830.9 840.1 849.0 861.9 5212.0 5451.5 5467.2 5653.0 5706.6 782.1 842.0 854.7 858.5 910.7 3560.6 3609.9 3725.9 3733.6 3756.0 9102.4 9587.6 9680.5 9700.6 9709.8 9588.4 9630.3 9666.4 9668.8 9675.5 7209.9 7239.2 7298.6 7326.8 7368.7 5291.0 5292.2 5367.6 5433.3 5518.6 8382.2 8578.9 8647.3 8775.8 8787.3 8527.4 8905.7 8906.5 9006.0 9115.8 6154.2 6387.8 6807.1 6943.4 6946.9 7173.9 7394.6 7433.8 7437.7 7441.0 6616.9 6638.6 6707.6 6761.3 6862.8 6598.9 6600.9 6602.2 6603.6 6660.4 6065.1 6146.0 6148.4 6150.4 6161.9 4530.0 4637.7 4686.2 4696.3 4731.3 4630.5 4640.0 4672.8 4772.2 4794.4 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#4] prototype.html Cycles:180 Average:4087.43 Median:4658.24 stddev:1167.86 (25.1%) stddev-sans-first:1135.64 08:16:16 INFO - PROCESS | 8662 | Values: 280.3 287.8 292.6 293.5 293.7 4625.5 4744.9 4767.4 4773.9 4805.2 4517.9 4622.1 4646.2 4665.9 4671.6 3710.7 3715.3 3719.0 3736.3 3741.3 3569.8 3725.9 3734.3 3738.8 3771.1 3466.2 3675.5 3699.0 3706.3 3714.9 3697.5 3698.4 3700.0 3703.6 3708.4 3283.0 3283.2 3284.4 3314.2 3315.8 4502.2 4518.6 4546.8 4631.7 4650.6 4206.2 4207.9 4210.6 4216.9 4224.7 4886.3 4896.6 4900.1 4912.8 4916.4 3484.6 3584.6 3584.8 3590.2 3613.9 5047.6 5048.8 5073.3 5080.4 5129.2 3606.2 3613.3 3614.8 3627.5 3638.1 3682.4 3693.4 3696.4 3706.0 3708.2 4080.7 4196.4 4197.6 4202.5 4215.9 4886.4 4890.6 4892.5 4897.8 4950.2 1204.1 1230.6 1263.5 1291.1 1294.0 883.6 891.5 895.2 900.8 902.4 4275.6 4277.4 4290.6 4301.9 4310.9 4999.4 5000.2 5020.2 5026.7 5035.8 4095.5 4286.9 4338.9 4362.7 4381.9 2673.2 2687.7 2691.4 2698.8 2699.6 4778.0 4780.0 4782.8 4803.4 4822.9 5128.2 5135.6 5156.9 5177.9 5181.7 4719.5 4730.8 4730.9 4736.9 4757.3 5067.3 5069.0 5069.3 5074.3 5076.3 4836.9 4922.5 4939.0 4943.9 4980.7 4722.6 4734.4 4736.8 4737.0 4743.8 3594.6 3603.1 3605.5 3616.2 3621.4 4840.6 4974.9 4994.9 4996.6 5067.8 4914.4 4915.3 4917.4 4923.9 4926.6 4890.6 4899.6 4912.4 4918.8 4918.8 4604.6 4627.0 4738.4 4819.6 4838.6 4866.2 4871.9 4882.5 4893.1 4900.9 4764.1 4792.0 4809.9 4810.3 4812.1 08:16:16 INFO - PROCESS | 8662 | 08:16:16 INFO - PROCESS | 8662 | [#5] yui.html Cycles:180 Average:17824.61 Median:4134.08 stddev:25979.49 (628.4%) stddev-sans-first:26020.19 08:16:16 INFO - PROCESS | 8662 | Values: 602.2 631.6 680.3 701.8 731.7 12400.5 12932.7 12959.2 12981.4 12995.5 12413.1 12454.9 12466.3 12545.1 12879.4 612.2 722.6 729.6 753.5 753.6 761.7 763.6 763.7 764.1 764.1 799.4 800.2 800.3 800.8 802.0 649.7 779.2 799.5 800.4 801.9 588.0 588.1 588.7 588.7 588.8 12500.3 12529.9 12547.0 12549.3 12555.7 12352.8 12757.6 12940.4 13043.2 13374.0 11103.9 11169.9 11386.3 11545.9 11962.0 3978.2 4008.3 4023.3 4244.8 4252.0 63211.7 63621.0 63756.4 63939.1 64153.4 9867.7 10255.4 10271.5 10540.6 10824.6 19195.5 19207.7 19225.8 19242.9 19248.1 6791.7 6810.5 6875.6 7047.8 7130.2 3632.7 3722.1 3729.7 3736.3 3764.4 2740.7 2755.7 2795.5 2797.7 2816.1 496.4 502.6 504.6 506.6 516.4 558.2 569.5 571.2 580.1 580.4 7616.2 7865.0 7938.5 8171.3 8199.1 435.3 525.0 556.9 585.6 588.2 3606.1 3715.7 3734.6 3828.8 3855.9 101132.5 101911.0 102782.0 103092.0 104367.5 85575.1 86414.6 87882.0 90828.5 91849.0 75098.9 75321.0 76353.0 80488.0 80531.0 6908.0 6941.7 7006.7 7209.1 7258.2 31147.5 37845.0 46915.5 47291.5 50287.5 39136.8 50760.2 53561.0 60691.4 60987.7 11135.0 11444.2 11462.5 11525.2 12042.3 13208.1 13541.2 13760.2 14091.0 14133.1 11493.5 11523.9 11909.8 11910.3 11972.5 11138.2 11723.6 11939.3 12578.9 12615.9 10464.3 10811.9 10852.0 11115.8 11197.4 8507.8 10142.5 10610.9 10663.7 10728.5 8232.3 8714.4 9174.6 10023.3 10785.7 08:16:16 INFO - PROCESS | 8662 | -------- Summary: end -------- 08:16:16 INFO - PROCESS | 8662 | 08:16:17 INFO - TEST-INFO | 8662: exit 0 08:16:17 INFO - TEST-OK | dromaeo_css | took 1119556ms 08:16:17 INFO - TEST-START | kraken 08:16:17 INFO - operating with platform_type : linux_ 08:16:17 INFO - Initialising browser for kraken test... 08:16:17 INFO - TEST-INFO | started process 8965 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwjuS2L/profile http://localhost:33477/getInfo.html) 08:16:21 INFO - PROCESS | 8965 | __metrics Screen width/height:1600/1200 08:16:21 INFO - PROCESS | 8965 | colorDepth:24 08:16:21 INFO - PROCESS | 8965 | Browser inner width/height: 1024/697 08:16:21 INFO - PROCESS | 8965 | __metrics 08:16:22 INFO - PROCESS | 8965 | 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" 08:16:22 INFO - PROCESS | 8965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:16:22 INFO - PROCESS | 8965 | console.error: PushService: 08:16:22 INFO - PROCESS | 8965 | stateChangeProcessEnqueue: Error transitioning state 08:16:22 INFO - PROCESS | 8965 | UnknownError 08:16:23 INFO - TEST-INFO | 8965: exit 0 08:16:23 INFO - Browser initialized. 08:16:23 INFO - Running cycle 1/1 for kraken test... 08:16:23 INFO - TEST-INFO | started process 9046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwjuS2L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:35 INFO - PROCESS | 9046 | RSS: Main: 181579776 08:16:35 INFO - PROCESS | 9046 | 08:16:38 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33477/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:39 INFO - PROCESS | 9046 | RSS: Main: 235421696 08:16:39 INFO - PROCESS | 9046 | 08:16:41 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33477/tests/kraken/driver.html?testName=audio-dft) 08:16:42 INFO - PROCESS | 9046 | RSS: Main: 225714176 08:16:42 INFO - PROCESS | 9046 | 08:16:45 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33477/tests/kraken/driver.html?testName=audio-fft) 08:16:46 INFO - PROCESS | 9046 | RSS: Main: 304136192 08:16:46 INFO - PROCESS | 9046 | 08:16:48 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33477/tests/kraken/driver.html?testName=audio-oscillator) 08:16:48 INFO - PROCESS | 9046 | RSS: Main: 214077440 08:16:48 INFO - PROCESS | 9046 | 08:16:51 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33477/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:51 INFO - PROCESS | 9046 | RSS: Main: 217026560 08:16:51 INFO - PROCESS | 9046 | 08:16:55 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33477/tests/kraken/driver.html?testName=imaging-darkroom) 08:16:55 INFO - PROCESS | 9046 | RSS: Main: 256495616 08:16:55 INFO - PROCESS | 9046 | 08:16:59 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33477/tests/kraken/driver.html?testName=imaging-desaturate) 08:16:59 INFO - PROCESS | 9046 | RSS: Main: 250281984 08:16:59 INFO - PROCESS | 9046 | 08:17:02 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33477/tests/kraken/driver.html?testName=json-parse-financial) 08:17:03 INFO - PROCESS | 9046 | RSS: Main: 253333504 08:17:03 INFO - PROCESS | 9046 | 08:17:05 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33477/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:05 INFO - PROCESS | 9046 | RSS: Main: 216530944 08:17:05 INFO - PROCESS | 9046 | 08:17:07 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:08 INFO - PROCESS | 9046 | RSS: Main: 224124928 08:17:08 INFO - PROCESS | 9046 | 08:17:10 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:11 INFO - PROCESS | 9046 | RSS: Main: 224059392 08:17:11 INFO - PROCESS | 9046 | 08:17:13 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:14 INFO - PROCESS | 9046 | RSS: Main: 218710016 08:17:14 INFO - PROCESS | 9046 | 08:17:17 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:18 INFO - PROCESS | 9046 | RSS: Main: 222146560 08:17:18 INFO - PROCESS | 9046 | 08:17:19 INFO - PROCESS | 9046 | Cycle 1(1): loaded http://localhost:33477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33477/tests/kraken/driver.html?testName=ai-astar) 08:17:20 INFO - PROCESS | 9046 | RSS: Main: 271872000 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | __start_tp_report 08:17:20 INFO - PROCESS | 9046 | _x_x_mozilla_page_load 08:17:20 INFO - PROCESS | 9046 | _x_x_mozilla_page_load_details 08:17:20 INFO - PROCESS | 9046 | |i|pagename|runs| 08:17:20 INFO - PROCESS | 9046 | |0;ai-astar;114;115;128;114;114;113;112;113;113;113 08:17:20 INFO - PROCESS | 9046 | |1;audio-beat-detection;152;121;145;188;122;154;164;130;182;126 08:17:20 INFO - PROCESS | 9046 | |2;audio-dft;160;161;161;161;161;161;162;164;163;162 08:17:20 INFO - PROCESS | 9046 | |3;audio-fft;76;76;77;172;76;76;76;77;163;77 08:17:20 INFO - PROCESS | 9046 | |4;audio-oscillator;87;86;156;86;86;86;147;86;87;149 08:17:20 INFO - PROCESS | 9046 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;118;118;119 08:17:20 INFO - PROCESS | 9046 | |6;imaging-darkroom;148;148;147;148;147;147;148;148;147;148 08:17:20 INFO - PROCESS | 9046 | |7;imaging-desaturate;95;95;97;95;95;95;95;97;95;95 08:17:20 INFO - PROCESS | 9046 | |8;json-parse-financial;68;66;69;68;67;69;68;66;66;67 08:17:20 INFO - PROCESS | 9046 | |9;json-stringify-tinderbox;64;65;65;66;66;66;66;66;66;66 08:17:20 INFO - PROCESS | 9046 | |10;stanford-crypto-aes;82;101;89;93;89;82;85;82;92;82 08:17:20 INFO - PROCESS | 9046 | |11;stanford-crypto-ccm;134;130;137;139;134;130;143;135;156;144 08:17:20 INFO - PROCESS | 9046 | |12;stanford-crypto-pbkdf2;173;174;173;171;172;173;177;174;176;171 08:17:20 INFO - PROCESS | 9046 | |13;stanford-crypto-sha256-iterative;68;67;68;73;70;69;72;72;67;69 08:17:20 INFO - PROCESS | 9046 | __end_tp_report 08:17:20 INFO - PROCESS | 9046 | __start_cc_report 08:17:20 INFO - PROCESS | 9046 | _x_x_mozilla_cycle_collect,1973 08:17:20 INFO - PROCESS | 9046 | __end_cc_report 08:17:20 INFO - PROCESS | 9046 | __startTimestamp1472051840193__endTimestamp 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | ------- Summary: start ------- 08:17:20 INFO - PROCESS | 9046 | Number of tests: 14 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#0] ai-astar Cycles:10 Average:114.90 Median:114.00 stddev:4.68 (4.1%) stddev-sans-first:4.95 08:17:20 INFO - PROCESS | 9046 | Values: 114.0 115.0 128.0 114.0 114.0 113.0 112.0 113.0 113.0 113.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#1] audio-beat-detection Cycles:10 Average:148.40 Median:153.00 stddev:24.23 (15.8%) stddev-sans-first:25.67 08:17:20 INFO - PROCESS | 9046 | Values: 152.0 121.0 145.0 188.0 122.0 154.0 164.0 130.0 182.0 126.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.09 08:17:20 INFO - PROCESS | 9046 | Values: 160.0 161.0 161.0 161.0 161.0 161.0 162.0 164.0 163.0 162.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#3] audio-fft Cycles:10 Average:94.60 Median:76.00 stddev:38.48 (50.6%) stddev-sans-first:40.22 08:17:20 INFO - PROCESS | 9046 | Values: 76.0 76.0 77.0 172.0 76.0 76.0 76.0 77.0 163.0 77.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:86.00 stddev:31.18 (36.3%) stddev-sans-first:32.34 08:17:20 INFO - PROCESS | 9046 | Values: 87.0 86.0 156.0 86.0 86.0 86.0 147.0 86.0 87.0 149.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 08:17:20 INFO - PROCESS | 9046 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 119.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 08:17:20 INFO - PROCESS | 9046 | Values: 148.0 148.0 147.0 148.0 147.0 147.0 148.0 148.0 147.0 148.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.84 (0.9%) stddev-sans-first:0.88 08:17:20 INFO - PROCESS | 9046 | Values: 95.0 95.0 97.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#8] json-parse-financial Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 08:17:20 INFO - PROCESS | 9046 | Values: 68.0 66.0 69.0 68.0 67.0 69.0 68.0 66.0 66.0 67.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.44 08:17:20 INFO - PROCESS | 9046 | Values: 64.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.00 stddev:6.36 (7.3%) stddev-sans-first:6.40 08:17:20 INFO - PROCESS | 9046 | Values: 82.0 101.0 89.0 93.0 89.0 82.0 85.0 82.0 92.0 82.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:138.00 stddev:7.86 (5.7%) stddev-sans-first:8.19 08:17:20 INFO - PROCESS | 9046 | Values: 134.0 130.0 137.0 139.0 134.0 130.0 143.0 135.0 156.0 144.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:173.50 stddev:1.96 (1.1%) stddev-sans-first:2.07 08:17:20 INFO - PROCESS | 9046 | Values: 173.0 174.0 173.0 171.0 172.0 173.0 177.0 174.0 176.0 171.0 08:17:20 INFO - PROCESS | 9046 | 08:17:20 INFO - PROCESS | 9046 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:2.17 (3.1%) stddev-sans-first:2.24 08:17:20 INFO - PROCESS | 9046 | Values: 68.0 67.0 68.0 73.0 70.0 69.0 72.0 72.0 67.0 69.0 08:17:20 INFO - PROCESS | 9046 | -------- Summary: end -------- 08:17:20 INFO - PROCESS | 9046 | 08:17:21 INFO - TEST-INFO | 9046: exit 0 08:17:21 INFO - TEST-OK | kraken | took 64067ms 08:17:21 INFO - SUITE-END | took 1183s 08:17:21 INFO - Completed test suite (00:19:43) 08:17:21 INFO - javascript benchmark 08:17:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2516.094206085809, 2528.4184637533376, 2540.377607486166, 2551.0561092395815, 2570.087618895292, 4516.751578663172, 4568.908621827562, 4579.175748365293, 4634.698744581599, 4645.5586719261655, 4547.613452856503, 4566.337881007252, 4569.817207311712, 4588.655850811194, 4592.402987611614, 4160.5007399112, 4191.308434108369, 4232.724872883253, 4303.419038429819, 4355.499117601485, 4319.352097185431, 4367.803448844794, 4401.647868170555, 4416.470023597173, 4418.513963464033, 4191.664666826647, 4219.409282700424, 4228.090960443488, 4242.639375653073, 4332.718373305741, 4188.078622703, 4226.3026600610865, 4239.6396306314, 4251.893702657428, 4255.106427650167, 4216.0, 4269.509006464265, 4271.295236286009, 4271.807768650418, 4333.414988976479, 4502.684812063157, 4625.606823420013, 4635.0729854029, 4657.673962822604, 4726.1020406122725, 4880.194767863299, 4900.779464924087, 4933.210686290176, 4936.531029552187, 4941.308216849644, 4935.851924442272, 4938.950610493921, 4940.407151141886, 4943.6539442239055, 5007.12375334315, 4485.394471746273, 4523.231050721385, 4618.745968971739, 4621.722696638212, 4624.768761561976, 5035.1188542005, 5041.79832806691, 5057.797688092435, 5114.028334616411, 5134.614903882224, 4844.8304309349705, 4850.611951043908, 4852.80588776445, 4861.052094841542, 4911.582515486152, 4756.928646070242, 4834.347363106006, 4923.286123512071, 4942.629302802305, 4995.500449954975, 4867.440244371943, 4918.852434426973, 4933.703977761345, 4945.901081978341, 4950.257461380822, 4883.194272944996, 4890.682105663121, 4899.0, 4903.809883062972, 4968.552830245223, 4197.727147735387, 4198.769067701244, 4200.348945913388, 4203.306454434984, 4246.384274280212, 3633.291508155937, 3644.325799727059, 3648.7263455240964, 3677.4116141417244, 3710.1466662667735, 4191.706580539394, 4194.601512856274, 4198.6011328923705, 4240.406343111967, 4241.4698162729655, 4929.162042452792, 4930.975345123252, 4934.925976110361, 4940.975295123501, 4992.775325110379, 4082.4488694026522, 4127.938080928789, 4138.193052354762, 4177.791110444466, 4178.979105104516, 4397.73613583186, 4432.3351497275025, 4476.239039363316, 4486.147631949919, 4488.4165058542185, 4619.0, 4625.468071171858, 4626.884327891829, 4631.536846315342, 4688.156131896291, 4825.4933232011535, 4876.21980483121, 4926.8768280793265, 4928.728919909296, 4936.111499930047, 4622.121796858586, 4626.8149274028665, 4665.2768820832825, 4674.69614475058, 4687.953120468888, 4876.634252431011, 4876.853694389174, 4878.658493905535, 4885.780139893643, 4941.97529012367, 4772.188727916332, 4875.19559272716, 4917.655764096479, 4932.062908047459, 4995.200767877122, 4780.020095880405, 4823.1800593899825, 4827.637927155548, 4881.341018962395, 4888.997755460051, 4805.7597120144555, 4816.8073277069925, 4820.204666230173, 4821.421429428491, 4875.878103047452, 4633.582977531903, 4693.741844198602, 4735.0, 4771.117343291502, 4818.638602104786, 4797.64017698681, 4860.465348811698, 4881.90236195267, 4936.481669424802, 4937.037277730951, 4742.691725037862, 4755.096531658974, 4758.738269395217, 4765.0946320199055, 4801.927971080507], "value": 4528.661793943293, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.326275418481, 561.3460318728681, 564.5116973817649, 587.0138167877964, 592.4371846745593, 36659.75155589991, 38746.22507549851, 38819.611803881955, 42329.576704232946, 42361.364579531335, 38029.0, 38570.4214436783, 38587.0, 38776.80611596945, 38956.61043389565, 7611.847763044722, 7719.228077192264, 7776.30013298803, 7827.843443131134, 7829.17793631669, 7641.923580764204, 7757.185495522974, 7760.456768026241, 7830.647620857047, 7844.137144914083, 7829.725959591416, 7883.763487095368, 7885.172056934025, 7905.446618736691, 7908.130105688369, 7816.570088645122, 7855.214478552156, 7872.3308518775975, 7872.448928575002, 7875.291223789829, 7716.807079823049, 7794.883076753853, 7799.922000780033, 7801.843963120706, 7807.12628969049, 36676.0, 36940.44589331136, 37065.62934370649, 37102.44346334807, 37158.44262336067, 2840.3467202543325, 3040.2095455181593, 3057.9847100764355, 3215.0, 3426.640202778697, 27850.442991140266, 28195.71804281972, 28215.0, 28312.85843570769, 28458.857705711547, 3461.6019157796923, 3575.249197668493, 3615.3130905128205, 3675.3384390809642, 3702.648248416396, 235395.0, 235449.0, 236457.0, 237446.0, 239502.0, 30889.382212355627, 49011.50988490141, 49305.26042109371, 49359.75320123376, 49403.50596493989, 31076.22309442282, 32660.183495412806, 32694.182645434055, 32764.836175818968, 32769.50845737316, 11907.76184476323, 12030.338331391857, 12037.277763334228, 12069.0, 12091.27452352844, 10139.442330671884, 10306.227032972558, 10316.845247321146, 10326.638567650096, 10328.896711032794, 7645.923540764632, 7659.961700191463, 7722.150563438017, 7726.995490586188, 7730.0, 2954.601128847621, 2974.985125074404, 3001.444732724453, 3010.969890301069, 3022.848857557113, 867.5445391169686, 881.167296904432, 919.4299534288783, 931.2410385535767, 993.3692105512907, 26817.865910670324, 27030.32424189411, 27365.86317068402, 28066.43867122646, 28098.958723874464, 915.5742579700338, 929.7443203119196, 962.821877952581, 976.272676855747, 997.2122023601419, 13871.306434678225, 14769.556913292403, 15081.396744130332, 15165.545033649008, 15186.620334491727, 282414.0, 392153.0, 392247.0, 392789.0, 393321.0, 48407.757961209965, 48750.51249487531, 48836.75581622069, 48902.0, 48970.510294896594, 1087.923845330835, 1160.2748282323548, 1160.4371879638368, 1178.187050934852, 1185.958491452795, 26852.865735671196, 27964.30089247785, 28125.578116327863, 28461.573076403456, 28530.42939141164, 40402.0, 41081.38377924393, 41083.0, 41152.58847411607, 41209.79395103005, 302568.0, 303986.0, 304009.0, 304304.0, 304533.0, 21958.341249762532, 24479.38801529976, 38880.6111938877, 39093.609063908996, 39912.0, 38620.227595448494, 42620.0, 42768.57231427646, 42866.35700464558, 43136.13727725365, 35968.28063438664, 37016.81491592525, 37039.0, 37413.25173496569, 37842.81078594589, 35733.0, 36189.819050904574, 36378.636213637525, 37074.62925370712, 37560.624393755716, 36329.0, 36457.0, 36745.44881826828, 36855.26289474249, 37228.62771372252, 31328.686713132578, 32892.1776955578, 33303.16742081467, 33664.49503257404, 34028.0, 25616.359591010372, 25936.0, 25936.74063259343, 26071.73928260693, 26229.21312360632], "value": 16322.261119783872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.55250950019723, 357.55662977907394, 360.0997506234414, 360.46111063959387, 373.63010619486715, 12929.159604625704, 15114.92442537787, 16824.495265142064, 16899.831001689978, 17001.65996680067, 16238.0, 16506.42227522037, 16695.83304166958, 16739.66520669586, 16800.32798688054, 4399.362092496586, 4645.6980296280635, 4677.74272415017, 4688.554587314216, 4694.131585656647, 3494.8776792812255, 4622.58396744293, 4658.836940707076, 4665.0669866026765, 4665.2768820832825, 5064.949350506485, 5288.8413347599635, 5314.043472175007, 5373.973130134364, 5387.892242155154, 5183.455737147583, 5326.201069839516, 5352.598555108363, 5364.302640656729, 5364.892702145974, 5206.869828254286, 5212.166053431432, 5215.947840521584, 5217.034848552992, 5219.895602087937, 11849.822252666218, 12167.817482737766, 19603.31388401399, 20066.29767958129, 20384.796152038587, 1887.6036032433205, 1969.5076230942263, 2122.734658167729, 2169.0889826273005, 2269.3986093685185, 9028.097190280985, 9447.480388578626, 9798.0, 11419.858014198597, 13719.657008574766, 1679.781628388314, 1744.8865823721417, 1762.4272111563664, 1799.803130917938, 1802.6755184066863, 65317.346826531604, 71299.0, 71422.0, 74203.99999999946, 74616.25383746147, 9549.904500955041, 9614.471204083702, 10278.17774578032, 10355.585776568852, 10499.737506562396, 13879.930600346934, 13894.583162505141, 13995.720085598232, 14015.719685606231, 14017.088889222168, 6746.797596072126, 6800.0, 6865.107536020385, 6972.616506092214, 6974.546654467444, 6197.659128747962, 6263.843403914848, 6411.198600174978, 6437.485001199892, 6474.611523308617, 4215.683823713233, 4270.893227669333, 4312.7627980461375, 4379.846705365297, 4438.556144385536, 1782.393986044751, 1815.1105958080445, 1820.1536285627246, 1852.4720454670355, 1856.201833211705, 828.2545708862073, 830.7341650671727, 850.8043150075393, 852.7484392104344, 904.5160838951261, 15419.92290038565, 17188.054656993736, 17270.22283997198, 17398.739018914726, 18322.908385457988, 808.8301456694043, 827.4952279109739, 838.2413915406567, 857.5797859049021, 875.5622188905547, 7998.600069996593, 9476.668316608886, 10349.379037257788, 10404.739881502872, 10754.838677419844, 408274.0, 411479.0, 412769.0, 413199.0, 414701.0, 79016.20983790203, 80233.0, 81001.18998810054, 81774.59112704398, 82612.0, 925.7685578605349, 926.4996901673156, 927.8422668146432, 940.6002448959165, 972.8540718892223, 15568.922155389151, 15662.686746265239, 15955.680886382439, 16215.756763648318, 16382.426615068416, 26557.468850622492, 28125.71874281231, 38070.0, 39898.0, 40882.59117408788, 53758.19362709484, 53885.730571346896, 54148.0, 54299.45700542944, 54572.0, 7301.233370496021, 7544.132424771002, 7551.169371369255, 7687.692492300245, 7702.614869256627, 11075.723106922069, 11364.602238921598, 11700.47347869365, 11830.940845295718, 12353.073519485895, 9957.302988790716, 10010.14913732341, 10142.645007424704, 10479.68560943173, 10693.358398496115, 10464.372137671764, 10508.316959397614, 10575.947120264656, 10746.946265268623, 10923.453827308444, 10867.891321087005, 10897.12822974176, 10963.342199468058, 11061.944690276498, 11218.943905280421, 7571.394288457021, 7706.730264440717, 7711.26742959418, 7730.420218483749, 7926.365890728844, 8056.919430805617, 9967.800643987224, 10078.949605251926, 10186.694399168036, 10304.866464688728], "value": 8129.73434180522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1238.9938050309747, 1320.4256148575369, 1373.6565858535366, 1420.3608376230693, 1635.0353291557979, 6581.506387020974, 7176.569405835654, 7448.292412220837, 7451.105867295919, 7672.88490672639, 7451.552906825581, 7520.511166774156, 7525.924740752591, 7528.661210245538, 7530.246975302467, 2099.4226587688354, 2122.4269447249235, 2124.7875212478784, 2129.510212651091, 2131.531063166101, 2083.5832833433374, 2103.221807931068, 2128.88291143987, 2129.691194776756, 2132.76539580646, 2028.6348457277684, 2047.4267205182573, 2060.9484762880898, 2065.328768150357, 2075.6678931370984, 2064.143380497092, 2070.782567830371, 2082.1983536338466, 2089.634313995045, 2091.5712278982924, 2016.5462770876582, 2027.0675489274952, 2066.2354928676336, 2068.379486154148, 2075.647139986206, 7539.170691223961, 7684.0, 7768.883466748003, 7773.922260777433, 7791.10402303731, 4146.1914926589325, 4284.357346398066, 4302.935455968163, 4305.354196870463, 4311.611954924072, 2695.5956606508985, 2837.7729781617422, 2883.610712553799, 2888.393437378153, 2893.8842446302124, 924.4407133684092, 924.5100096948628, 941.9905800941972, 951.1012093571578, 951.1487218939011, 10430.478476076165, 10509.894901051044, 10530.894691053069, 10535.0, 10549.841752373797, 3835.213781174908, 3841.692664586826, 3967.897014582276, 4007.6192761687594, 4009.077912080238, 8275.131111233355, 8288.70989515364, 8345.123762004901, 8359.8328033439, 8365.163483651708, 4374.190774706626, 4381.627561657231, 4392.143532011227, 4393.450818647669, 4394.648428125743, 3482.6169121396624, 3798.4682144499793, 3936.7834769087403, 4097.160082183145, 4117.3618089196225, 3286.457734473785, 3323.4350160472777, 3406.8977930662118, 3422.3326451342245, 3427.4002049641713, 996.5721098912046, 1018.9643362482277, 1021.4535223655308, 1032.2155162076726, 1032.8708911386077, 796.2714116583261, 830.9210624990616, 840.0507426608016, 849.0321034021221, 861.8965724113147, 5212.035773381861, 5451.536619387396, 5467.234587157801, 5653.0, 5706.600537962386, 782.1083964280724, 842.0484852117191, 854.7350321400386, 858.5490521019457, 910.717677519971, 3560.626134255918, 3609.8917032489067, 3725.869594564177, 3733.5706393765063, 3755.9812200938823, 9102.362834601514, 9587.568559414844, 9680.467574283482, 9700.611975521042, 9709.757256068513, 9588.37675551087, 9630.32587718853, 9666.423423333412, 9668.806623867484, 9675.467849268218, 7209.89185162223, 7239.239879812649, 7298.598577078312, 7326.780196594111, 7368.74209402679, 5291.0, 5292.153255479105, 5367.570594352364, 5433.266509021231, 5518.558515318685, 8382.203690649623, 8578.914210858062, 8647.308215342884, 8775.824483510421, 8787.25308348772, 8527.360447966303, 8905.688300909436, 8906.465612063297, 9006.0, 9115.817683646423, 6154.169187159855, 6387.808365749035, 6807.0810440589885, 6943.375096241362, 6946.895796562954, 7173.892391614234, 7394.630268486661, 7433.8141546461775, 7437.739679111205, 7441.0, 6616.90074648871, 6638.601683898982, 6707.608869038069, 6761.256261811295, 6862.828429289308, 6598.934010659832, 6600.900986485302, 6602.207735071822, 6603.636799976048, 6660.3672651100005, 6065.059915713072, 6146.0, 6148.354422785543, 6150.384961503813, 6161.938380616137, 4529.977350113229, 4637.65217608674, 4686.203345431217, 4696.2955556666775, 4731.313959475788, 4630.513796051366, 4639.976800115978, 4672.836450724209, 4772.212584923449, 4794.400699912511], "value": 4030.784408616031, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.3453935061631, 287.8201124297314, 292.57430438711674, 293.5230250842381, 293.6520223535111, 4625.514320996297, 4744.857654270373, 4767.427908650967, 4773.90452190956, 4805.231163013918, 4517.887052823681, 4622.121796858594, 4646.210144275473, 4665.88335291617, 4671.602913752336, 3710.666040056394, 3715.275521273339, 3718.981405092971, 3736.271427071722, 3741.3265612189725, 3569.7858128512244, 3725.85763186169, 3734.3278209922205, 3738.8130593470355, 3771.1326394929183, 3466.2374277658873, 3675.5405574303213, 3699.0, 3706.314331848617, 3714.9442758358646, 3697.5193224880727, 3698.389765688658, 3700.0379901225606, 3703.592604813495, 3708.35103856824, 3282.983585082059, 3283.162793487669, 3284.441644920369, 3314.1880239341517, 3315.8176300303476, 4502.189605870942, 4518.638508919312, 4546.840860569897, 4631.675782695213, 4650.604698600623, 4206.179794939988, 4207.91584168318, 4210.59999300066, 4216.936745948813, 4224.746944852477, 4886.315915771795, 4896.608271338285, 4900.068986892479, 4912.778924948386, 4916.410030796292, 3484.63411341808, 3584.551931008624, 3584.8028358440274, 3590.174259920207, 3613.9277214455565, 5047.621428392885, 5048.844472204872, 5073.34046573943, 5080.364954380702, 5129.204973229155, 3606.2246616977477, 3613.349597072552, 3614.765040272374, 3627.510286111394, 3638.072291565634, 3682.429223470366, 3693.4459831025024, 3696.4270538066644, 3705.981470092632, 3708.2398108388256, 4080.693947953856, 4196.412502249687, 4197.580241975778, 4202.495700515897, 4215.852445164404, 4886.438059623189, 4890.58430033444, 4892.486288939681, 4897.804087836446, 4950.183219768698, 1204.0608325505943, 1230.6185082624415, 1263.5072321794507, 1291.1349395904767, 1293.9741205176033, 883.6023789294843, 891.4695756025284, 895.2166854002747, 900.8199258970769, 902.4359775140537, 4275.5724427557, 4277.443932288845, 4290.570942905684, 4301.913961720748, 4310.8706738797255, 4999.42506611737, 5000.199968005102, 5020.171671674133, 5026.723530205801, 5035.77339019745, 4095.52901416335, 4286.89282767927, 4338.934915976263, 4362.738235705804, 4381.868543943687, 2673.1980405878157, 2687.6774787025292, 2691.4347986922967, 2698.7571118599026, 2699.5545734953907, 4777.976110119427, 4779.952200477951, 4782.832600859023, 4803.423589169322, 4822.855314340505, 5128.230765385148, 5135.62885603012, 5156.896862062813, 5177.86087060846, 5181.745940515888, 4719.528047195322, 4730.763461826964, 4730.858074257778, 4736.857894263108, 4757.333973243749, 5067.265246539305, 5068.974655126848, 5069.3156423883775, 5074.264231686311, 5076.340075790124, 4836.854894353175, 4922.532359425849, 4938.950610493849, 4943.8516844494725, 4980.701157930535, 4722.5513576210205, 4734.4081989751285, 4736.8105275788585, 4736.952630473789, 4743.83396581118, 3594.586622538337, 3603.0992251937014, 3605.531280933462, 3616.1682812953695, 3621.384364658067, 4840.636952228668, 4974.850754477371, 4994.925076123788, 4996.6002719783055, 5067.797288108434, 4914.434839993446, 4915.311856340187, 4917.360743103373, 4923.876903077452, 4926.58124059459, 4890.584300334512, 4899.583535399531, 4912.410510738734, 4918.753622609061, 4918.754062296943, 4604.58558729716, 4626.976865115653, 4738.407699037621, 4819.638527110411, 4838.588719958702, 4866.221404575251, 4871.926921096116, 4882.487338829372, 4893.143699852582, 4900.92648610264, 4764.142454358249, 4791.95208047929, 4809.9038019240115, 4810.254410566367, 4812.133815913169], "value": 3718.5963223479143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.2157180283378, 631.6178711879313, 680.3434685528467, 701.8105111619866, 731.6670914733796, 12400.503979840807, 12932.676683082904, 12959.157654752435, 12981.350932453386, 12995.545155919544, 12413.131080824345, 12454.875451245485, 12466.314352710597, 12545.1218414711, 12879.420426080826, 612.2377639838408, 722.6061796321018, 729.6461216310112, 753.5365750063726, 753.5779963220587, 761.6800943603672, 763.5914785589711, 763.7441457111875, 764.1136281912982, 764.1365257259289, 799.4124318625805, 800.1998001998002, 800.3037357498984, 800.8478389106051, 801.9639116239783, 649.7271146118666, 779.2480256552398, 799.5043073294595, 800.4236949396425, 801.8877357169943, 588.047363271503, 588.1148870949237, 588.6644612570836, 588.6938791828226, 588.7733222709245, 12500.312482813442, 12529.937350313281, 12547.0, 12549.309787961658, 12555.748885022249, 12352.752944941141, 12757.55348562796, 12940.417681204368, 13043.152195107383, 13373.995834573101, 11103.944480277627, 11169.944150279196, 11386.316820990767, 11545.884541154648, 11962.0, 3978.170757164592, 4008.3386241270155, 4023.3361495353233, 4244.830206791725, 4252.043290259698, 63211.68394158046, 63621.0, 63756.36243637597, 63939.13034784052, 64153.35846641521, 9867.703968880945, 10255.384676919408, 10271.537780799732, 10540.631077912236, 10824.621138260281, 19195.520111997033, 19207.711884321747, 19225.807741922683, 19242.903785480983, 19248.133833977503, 6791.660416979132, 6810.455163586901, 6875.621840798804, 7047.753328633473, 7130.180029296594, 3632.6912212462234, 3722.1253005543676, 3729.7202709796875, 3736.3461394255896, 3764.378877485239, 2740.6985231624503, 2755.6968733439307, 2795.46886091646, 2797.6922538521153, 2816.070696670094, 496.3894409875873, 502.5728131088546, 504.57394292751803, 506.6200349737697, 516.3906590223572, 558.1655425139361, 569.4590139367617, 571.2003195526213, 580.1182203051338, 580.4166812353558, 7616.238376162451, 7864.9606751965875, 7938.483998540077, 8171.264586187272, 8199.139090395542, 435.25571273123313, 524.9947500524946, 556.9418105599312, 585.5871610514577, 588.150839897218, 3606.062423769839, 3715.6655900968503, 3734.607866174067, 3828.8085595720104, 3855.8843234703004, 101132.49433752931, 101911.0, 102782.0, 103092.0, 104367.4781626087, 85575.14424855672, 86414.56792716122, 87882.0, 90828.54585727162, 91849.0, 75098.87351689729, 75321.0, 76353.0, 80488.0, 80531.0, 6908.0, 6941.722947770505, 7006.684699188447, 7209.134903811576, 7258.237885021997, 31147.532787008666, 37845.0, 46915.530844691115, 47291.52708472871, 50287.47412732725, 39136.80431597824, 50760.23859642182, 53561.0, 60691.39308606857, 60987.695061524406, 11135.0, 11444.226144947546, 11462.541498339973, 11525.197352683212, 12042.337671428155, 13208.141470804367, 13541.187528747912, 13760.243186624832, 14091.0, 14133.081349712236, 11493.54025838957, 11523.884761152616, 11909.761804763682, 11910.344931028876, 11972.521099156285, 11138.159769258787, 11723.648290551297, 11939.343336116597, 12578.937105314415, 12615.87384126147, 10464.319819211727, 10811.945940270249, 10852.046631606387, 11115.83326250123, 11197.44012799373, 8507.829843402973, 10142.54358553852, 10610.922832378525, 10663.731512744655, 10728.463576820972, 8232.300254478436, 8714.38999270045, 9174.587143578421, 10023.298369114093, 10785.676429707122], "value": 6495.276718114345, "unit": "score"}], "name": "dromaeo_css", "value": 6230.749925219949, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 128.0, 114.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 145.0, 188.0, 122.0, 154.0, 164.0, 130.0, 182.0, 126.0], "value": 148.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 164.0, 163.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 172.0, 76.0, 76.0, 76.0, 77.0, 163.0, 77.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 156.0, 86.0, 86.0, 86.0, 147.0, 86.0, 87.0, 149.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 119.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 147.0, 148.0, 148.0, 147.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 68.0, 67.0, 69.0, 68.0, 66.0, 66.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 101.0, 89.0, 93.0, 89.0, 82.0, 85.0, 82.0, 92.0, 82.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 130.0, 137.0, 139.0, 134.0, 130.0, 143.0, 135.0, 156.0, 144.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 173.0, 171.0, 172.0, 173.0, 177.0, 174.0, 176.0, 171.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 73.0, 70.0, 69.0, 72.0, 72.0, 67.0, 69.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1587.4, "alertThreshold": 2.0}]} 08:17:21 INFO - Return code: 0 08:17:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:17:21 INFO - # TBPL SUCCESS # 08:17:21 INFO - Running post-action listener: _resource_record_post_action 08:17:21 INFO - [mozharness: 2016-08-24 15:17:21.982412Z] Finished run-tests step (success) 08:17:21 INFO - Running post-run listener: _resource_record_post_run 08:17:22 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396173312; Read time: 8; Write time: 259968 08:17:22 INFO - TinderboxPrint: CPU usage
13.0% 08:17:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:17:22 INFO - TinderboxPrint: I/O write bytes / time
396,173,312 / 259,968 08:17:22 INFO - TinderboxPrint: CPU idle
8,328.5 (87.0%) 08:17:22 INFO - TinderboxPrint: CPU user
1,199.9 (12.5%) 08:17:22 INFO - TinderboxPrint: Swap in / out
0 / 0 08:17:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114196480; Read time: 0; Write time: 99444 08:17:22 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 281976832; Read time: 0; Write time: 160524 08:17:22 INFO - Running post-run listener: _upload_blobber_files 08:17:22 INFO - Blob upload gear active. 08:17:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:22 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 08:17:22 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:17:22 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:17:23 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:23 INFO - (blobuploader) - INFO - Done attempting. 08:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:17:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:27 INFO - (blobuploader) - INFO - Done attempting. 08:17:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:17:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:17:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:29 INFO - (blobuploader) - INFO - Done attempting. 08:17:29 INFO - (blobuploader) - INFO - Iteration through files over. 08:17:29 INFO - Return code: 0 08:17:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:17:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:17:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/570e4e6a197bc719eded91719b3a975110ef2146f46c9f5d44ab54c96d454f7ad11eaac4268fa0c9c717a6b0b8defafa7096f22e6233d5396bef0590f80dc23c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bf9c35027759474c1996c5c079cac7f6546af2c8e6019e1e572e64b2243dbfcde2cf8354a3a0552c9dde935c1386c1135fbcfb5f8bcbfe0656524f24028c1be9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71e2afd8ab4c573fb679dc8da973f0db17f74e1c9331538a589a2ef6ae6d79ef6e8737d1e0060a4dc1a222cc61ae5b06c3c5f8fba09d023c9362fbcb0d4589b2"} 08:17:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:17:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:17:29 INFO - Contents: 08:17:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/570e4e6a197bc719eded91719b3a975110ef2146f46c9f5d44ab54c96d454f7ad11eaac4268fa0c9c717a6b0b8defafa7096f22e6233d5396bef0590f80dc23c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bf9c35027759474c1996c5c079cac7f6546af2c8e6019e1e572e64b2243dbfcde2cf8354a3a0552c9dde935c1386c1135fbcfb5f8bcbfe0656524f24028c1be9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71e2afd8ab4c573fb679dc8da973f0db17f74e1c9331538a589a2ef6ae6d79ef6e8737d1e0060a4dc1a222cc61ae5b06c3c5f8fba09d023c9362fbcb0d4589b2"} 08:17:29 INFO - Running post-run listener: copy_logs_to_upload_dir 08:17:29 INFO - Copying logs to upload dir... 08:17:29 INFO - mkdir: /builds/slave/test/build/upload/logs 08:17:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.019762 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-24 08:17:29.299125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 08:17:29.302628) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/570e4e6a197bc719eded91719b3a975110ef2146f46c9f5d44ab54c96d454f7ad11eaac4268fa0c9c717a6b0b8defafa7096f22e6233d5396bef0590f80dc23c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bf9c35027759474c1996c5c079cac7f6546af2c8e6019e1e572e64b2243dbfcde2cf8354a3a0552c9dde935c1386c1135fbcfb5f8bcbfe0656524f24028c1be9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71e2afd8ab4c573fb679dc8da973f0db17f74e1c9331538a589a2ef6ae6d79ef6e8737d1e0060a4dc1a222cc61ae5b06c3c5f8fba09d023c9362fbcb0d4589b2"} build_url:https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/Im9eLYp6Q2afhOzn5j-Vwg/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/Autoland-Non-PGO/sha512/570e4e6a197bc719eded91719b3a975110ef2146f46c9f5d44ab54c96d454f7ad11eaac4268fa0c9c717a6b0b8defafa7096f22e6233d5396bef0590f80dc23c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bf9c35027759474c1996c5c079cac7f6546af2c8e6019e1e572e64b2243dbfcde2cf8354a3a0552c9dde935c1386c1135fbcfb5f8bcbfe0656524f24028c1be9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71e2afd8ab4c573fb679dc8da973f0db17f74e1c9331538a589a2ef6ae6d79ef6e8737d1e0060a4dc1a222cc61ae5b06c3c5f8fba09d023c9362fbcb0d4589b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 08:17:29.328783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:17:29.329176) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1472050444.45418-1913389280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:17:29.414350) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 08:17:29.414754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 08:17:29.415172) ========= ========= Total master_lag: 0.48 =========