builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1469216281.1 results: success (0) buildid: 20160722121225 builduid: 575355bfa92547849578b0a9d424e33c revision: 34e32a1c5cef83dc0271fc973f0caad7910b440d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.096056) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.096570) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.096893) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.112285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.112679) ========= 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=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 12:38:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.6M=0s 2016-07-22 12:38:01 (34.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091664 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.220069) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.220378) ========= 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=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015152 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.269782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.270136) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 34e32a1c5cef83dc0271fc973f0caad7910b440d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 34e32a1c5cef83dc0271fc973f0caad7910b440d --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=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 12:38:01,310 truncating revision to first 12 chars 2016-07-22 12:38:01,310 Setting DEBUG logging. 2016-07-22 12:38:01,310 attempt 1/10 2016-07-22 12:38:01,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/34e32a1c5cef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 12:38:01,622 unpacking tar archive at: fx-team-34e32a1c5cef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683757 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.970558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.970836) ========= script_repo_revision: 34e32a1c5cef83dc0271fc973f0caad7910b440d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.971212) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.971485) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:38:01.982528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-22 12:38:01.982811) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:38:02 INFO - MultiFileLogger online at 20160722 12:38:02 in /builds/slave/test 12:38:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:38:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:38:02 INFO - {'append_to_log': False, 12:38:02 INFO - 'base_work_dir': '/builds/slave/test', 12:38:02 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:38:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:38:02 INFO - 'branch': 'Fx-Team-Non-PGO', 12:38:02 INFO - 'buildbot_json_path': 'buildprops.json', 12:38:02 INFO - 'config_files': ('talos/linux_config.py',), 12:38:02 INFO - 'default_actions': ('clobber', 12:38:02 INFO - 'read-buildbot-config', 12:38:02 INFO - 'download-and-extract', 12:38:02 INFO - 'populate-webroot', 12:38:02 INFO - 'create-virtualenv', 12:38:02 INFO - 'install', 12:38:02 INFO - 'run-tests'), 12:38:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:38:02 INFO - 'download_minidump_stackwalk': True, 12:38:02 INFO - 'download_symbols': 'ondemand', 12:38:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:38:02 INFO - 'tooltool.py': '/tools/tooltool.py', 12:38:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:38:02 INFO - '/tools/misc-python/virtualenv.py')}, 12:38:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:38:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:38:02 INFO - 'installer_path': 'installer.exe', 12:38:02 INFO - 'log_level': 'info', 12:38:02 INFO - 'log_name': 'talos', 12:38:02 INFO - 'log_to_console': True, 12:38:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:38:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:38:02 INFO - 'opt_config_files': (), 12:38:02 INFO - 'pip_index': False, 12:38:02 INFO - 'sps_profile': False, 12:38:02 INFO - 'sps_profile_interval': 0, 12:38:02 INFO - 'suite': 'dromaeojs', 12:38:02 INFO - 'system_bits': '32', 12:38:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:38:02 INFO - 'title': 'talos-linux64-ix-007', 12:38:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:38:02 INFO - 'use_talos_json': True, 12:38:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:38:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:38:02 INFO - 'work_dir': 'build'} 12:38:02 INFO - [mozharness: 2016-07-22 19:38:02.117301Z] Running clobber step. 12:38:02 INFO - Running pre-action listener: _resource_record_pre_action 12:38:02 INFO - Running main action method: clobber 12:38:02 INFO - rmtree: /builds/slave/test/build 12:38:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:38:02 INFO - Running post-action listener: _resource_record_post_action 12:38:02 INFO - [mozharness: 2016-07-22 19:38:02.586861Z] Finished clobber step (success) 12:38:02 INFO - [mozharness: 2016-07-22 19:38:02.586963Z] Running read-buildbot-config step. 12:38:02 INFO - Running pre-action listener: _resource_record_pre_action 12:38:02 INFO - Running main action method: read_buildbot_config 12:38:02 INFO - Using buildbot properties: 12:38:02 INFO - { 12:38:02 INFO - "project": "", 12:38:02 INFO - "product": "firefox", 12:38:02 INFO - "script_repo_revision": "production", 12:38:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:38:02 INFO - "repository": "", 12:38:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:38:02 INFO - "buildid": "20160722121225", 12:38:02 INFO - "pgo_build": "False", 12:38:02 INFO - "basedir": "/builds/slave/test", 12:38:02 INFO - "buildnumber": 2012, 12:38:02 INFO - "slavename": "talos-linux64-ix-007", 12:38:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:38:02 INFO - "platform": "ubuntu64_hw", 12:38:02 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:38:02 INFO - "branch": "fx-team", 12:38:02 INFO - "repo_path": "integration/fx-team", 12:38:02 INFO - "revision": "34e32a1c5cef83dc0271fc973f0caad7910b440d", 12:38:02 INFO - "stage_platform": "linux64", 12:38:02 INFO - "builduid": "575355bfa92547849578b0a9d424e33c", 12:38:02 INFO - "slavebuilddir": "test" 12:38:02 INFO - } 12:38:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 12:38:02 INFO - Running post-action listener: _resource_record_post_action 12:38:02 INFO - [mozharness: 2016-07-22 19:38:02.589451Z] Finished read-buildbot-config step (success) 12:38:02 INFO - [mozharness: 2016-07-22 19:38:02.589540Z] Running download-and-extract step. 12:38:02 INFO - Running pre-action listener: _resource_record_pre_action 12:38:02 INFO - Running main action method: download_and_extract 12:38:02 INFO - mkdir: /builds/slave/test/build/tests 12:38: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:02 INFO - https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:38:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:38:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:38:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:38:05 INFO - Downloaded 1361 bytes. 12:38:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:38:05 INFO - Using the following test package requirements: 12:38:05 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:38:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:38:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:38:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'jsshell-linux-x86_64.zip'], 12:38:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:38:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:38:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:38:05 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:38:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:38:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:38:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:38:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:38: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:05 INFO - https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:38:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:38:06 INFO - Downloaded 22737593 bytes. 12:38:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:38:08 INFO - Return code: 0 12:38:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:38:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:08 INFO - https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:38:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:38:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:38:18 INFO - Downloaded 22737593 bytes. 12:38:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:38:19 INFO - Return code: 0 12:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:19 INFO - https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:38:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:38:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:38:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:38:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:38:20 INFO - Downloaded 14403289 bytes. 12:38:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:38:21 INFO - Return code: 0 12:38:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:21 INFO - https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:38:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:38:24 INFO - Downloaded 57160034 bytes. 12:38:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:38:24 INFO - mkdir: /builds/slave/test/properties 12:38:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:38:24 INFO - Writing to file /builds/slave/test/properties/build_url 12:38:24 INFO - Contents: 12:38:24 INFO - build_url:https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:38:24 INFO - Running post-action listener: _resource_record_post_action 12:38:24 INFO - Running post-action listener: set_extra_try_arguments 12:38:24 INFO - [mozharness: 2016-07-22 19:38:24.931936Z] Finished download-and-extract step (success) 12:38:24 INFO - [mozharness: 2016-07-22 19:38:24.932170Z] Running populate-webroot step. 12:38:24 INFO - Running pre-action listener: _resource_record_pre_action 12:38:24 INFO - Running main action method: populate_webroot 12:38:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:38:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:38:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:38:24 INFO - '--tppagecycles', 12:38:24 INFO - '7'], 12:38:24 INFO - 'tests': ['tsvgm']}}, 12:38:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:38:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:38:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:38:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:38:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['damp', 'tps']}, 12:38:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'tests': ['damp', 'tps']}, 12:38:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['dromaeo_dom']}, 12:38:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:38:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['basic_compositor_video']}, 12:38:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:38:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'other-e10s': {'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['a11yr', 12:38:24 INFO - 'ts_paint', 12:38:24 INFO - 'tpaint', 12:38:24 INFO - 'sessionrestore', 12:38:24 INFO - 'sessionrestore_no_auto_restore', 12:38:24 INFO - 'tabpaint']}, 12:38:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['tsvgx', 12:38:24 INFO - 'tsvgr_opacity', 12:38:24 INFO - 'tart', 12:38:24 INFO - 'tscrollx', 12:38:24 INFO - 'cart']}, 12:38:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:38:24 INFO - 'tsvgr_opacity', 12:38:24 INFO - 'tart', 12:38:24 INFO - 'tscrollx', 12:38:24 INFO - 'cart']}, 12:38:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'talos_options': ['--disable-e10s'], 12:38:24 INFO - 'tests': ['tp5o']}, 12:38:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'tests': ['tp5o']}, 12:38:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'talos_options': ['--disable-e10s', 12:38:24 INFO - '--xperf_path', 12:38:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:24 INFO - 'tests': ['tp5n']}, 12:38:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:24 INFO - 'talos_options': ['--xperf_path', 12:38:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:24 INFO - 'tests': ['tp5n']}}, 12:38:24 INFO - 'talos.zip': {'path': '', 12:38:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:38:24 INFO - Running post-action listener: _resource_record_post_action 12:38:24 INFO - [mozharness: 2016-07-22 19:38:24.951239Z] Finished populate-webroot step (success) 12:38:24 INFO - [mozharness: 2016-07-22 19:38:24.951330Z] Running create-virtualenv step. 12:38:24 INFO - Running pre-action listener: _resource_record_pre_action 12:38:24 INFO - Running main action method: create_virtualenv 12:38:24 INFO - Creating virtualenv /builds/slave/test/build/venv 12:38:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:38:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:38:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:38:25 INFO - Using real prefix '/usr' 12:38:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:38:25 INFO - Installing distribute.............................................................................................................................................................................................done. 12:38:27 INFO - Installing pip.................done. 12:38:27 INFO - Return code: 0 12:38:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:27 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:38:27 INFO - Using env: {'DISPLAY': ':0', 12:38:27 INFO - 'HOME': '/home/cltbld', 12:38:27 INFO - 'LANG': 'en_US.UTF-8', 12:38:27 INFO - 'LANGUAGE': 'en_US:en', 12:38:27 INFO - 'LOGNAME': 'cltbld', 12:38:27 INFO - 'MAIL': '/var/mail/cltbld', 12:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:27 INFO - 'MOZ_NO_REMOTE': '1', 12:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:27 INFO - 'NO_EM_RESTART': '1', 12:38:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:27 INFO - 'PWD': '/builds/slave/test', 12:38:27 INFO - 'SHELL': '/bin/bash', 12:38:27 INFO - 'SHLVL': '1', 12:38:27 INFO - 'TERM': 'linux', 12:38:27 INFO - 'TMOUT': '86400', 12:38:27 INFO - 'USER': 'cltbld', 12:38:27 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:27 INFO - '_': '/tools/buildbot/bin/python'} 12:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:38:27 INFO - Cleaning up... 12:38:27 INFO - Return code: 0 12:38:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:27 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:38:27 INFO - Using env: {'DISPLAY': ':0', 12:38:27 INFO - 'HOME': '/home/cltbld', 12:38:27 INFO - 'LANG': 'en_US.UTF-8', 12:38:27 INFO - 'LANGUAGE': 'en_US:en', 12:38:27 INFO - 'LOGNAME': 'cltbld', 12:38:27 INFO - 'MAIL': '/var/mail/cltbld', 12:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:27 INFO - 'MOZ_NO_REMOTE': '1', 12:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:27 INFO - 'NO_EM_RESTART': '1', 12:38:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:27 INFO - 'PWD': '/builds/slave/test', 12:38:27 INFO - 'SHELL': '/bin/bash', 12:38:27 INFO - 'SHLVL': '1', 12:38:27 INFO - 'TERM': 'linux', 12:38:27 INFO - 'TMOUT': '86400', 12:38:27 INFO - 'USER': 'cltbld', 12:38:27 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:27 INFO - '_': '/tools/buildbot/bin/python'} 12:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:27 INFO - Downloading/unpacking psutil>=3.1.1 12:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:38:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:29 INFO - Installing collected packages: psutil 12:38:29 INFO - Running setup.py install for psutil 12:38:30 INFO - building 'psutil._psutil_linux' extension 12:38:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:38:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:38:30 INFO - building 'psutil._psutil_posix' extension 12:38:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:38:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:38:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:30 INFO - Successfully installed psutil 12:38:30 INFO - Cleaning up... 12:38:30 INFO - Return code: 0 12:38:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:30 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:38:30 INFO - Using env: {'DISPLAY': ':0', 12:38:30 INFO - 'HOME': '/home/cltbld', 12:38:30 INFO - 'LANG': 'en_US.UTF-8', 12:38:30 INFO - 'LANGUAGE': 'en_US:en', 12:38:30 INFO - 'LOGNAME': 'cltbld', 12:38:30 INFO - 'MAIL': '/var/mail/cltbld', 12:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:30 INFO - 'MOZ_NO_REMOTE': '1', 12:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:30 INFO - 'NO_EM_RESTART': '1', 12:38:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:30 INFO - 'PWD': '/builds/slave/test', 12:38:30 INFO - 'SHELL': '/bin/bash', 12:38:30 INFO - 'SHLVL': '1', 12:38:30 INFO - 'TERM': 'linux', 12:38:30 INFO - 'TMOUT': '86400', 12:38:30 INFO - 'USER': 'cltbld', 12:38:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:30 INFO - '_': '/tools/buildbot/bin/python'} 12:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:38:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:38:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:38:32 INFO - Installing collected packages: mozsystemmonitor 12:38:32 INFO - Running setup.py install for mozsystemmonitor 12:38:32 INFO - Successfully installed mozsystemmonitor 12:38:32 INFO - Cleaning up... 12:38:32 INFO - Return code: 0 12:38:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:32 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:38:32 INFO - Using env: {'DISPLAY': ':0', 12:38:32 INFO - 'HOME': '/home/cltbld', 12:38:32 INFO - 'LANG': 'en_US.UTF-8', 12:38:32 INFO - 'LANGUAGE': 'en_US:en', 12:38:32 INFO - 'LOGNAME': 'cltbld', 12:38:32 INFO - 'MAIL': '/var/mail/cltbld', 12:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:32 INFO - 'MOZ_NO_REMOTE': '1', 12:38:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:32 INFO - 'NO_EM_RESTART': '1', 12:38:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:32 INFO - 'PWD': '/builds/slave/test', 12:38:32 INFO - 'SHELL': '/bin/bash', 12:38:32 INFO - 'SHLVL': '1', 12:38:32 INFO - 'TERM': 'linux', 12:38:32 INFO - 'TMOUT': '86400', 12:38:32 INFO - 'USER': 'cltbld', 12:38:32 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:32 INFO - '_': '/tools/buildbot/bin/python'} 12:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:33 INFO - Downloading/unpacking jsonschema==2.5.1 12:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:38:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:35 INFO - Downloading functools32-3.2.3-2.tar.gz 12:38:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:38:35 INFO - warning: no files found matching '*.txt' 12:38:35 INFO - no previously-included directories found matching 'build' 12:38:35 INFO - no previously-included directories found matching 'dist' 12:38:35 INFO - no previously-included directories found matching '.git*' 12:38:35 INFO - Installing collected packages: jsonschema, functools32 12:38:35 INFO - Running setup.py install for functools32 12:38:35 INFO - warning: no files found matching '*.txt' 12:38:35 INFO - no previously-included directories found matching 'build' 12:38:35 INFO - no previously-included directories found matching 'dist' 12:38:35 INFO - no previously-included directories found matching '.git*' 12:38:35 INFO - Successfully installed jsonschema functools32 12:38:35 INFO - Cleaning up... 12:38:35 INFO - Return code: 0 12:38:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:38:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:35 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:38:35 INFO - Using env: {'DISPLAY': ':0', 12:38:35 INFO - 'HOME': '/home/cltbld', 12:38:35 INFO - 'LANG': 'en_US.UTF-8', 12:38:35 INFO - 'LANGUAGE': 'en_US:en', 12:38:35 INFO - 'LOGNAME': 'cltbld', 12:38:35 INFO - 'MAIL': '/var/mail/cltbld', 12:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:35 INFO - 'MOZ_NO_REMOTE': '1', 12:38:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:35 INFO - 'NO_EM_RESTART': '1', 12:38:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:35 INFO - 'PWD': '/builds/slave/test', 12:38:35 INFO - 'SHELL': '/bin/bash', 12:38:35 INFO - 'SHLVL': '1', 12:38:35 INFO - 'TERM': 'linux', 12:38:35 INFO - 'TMOUT': '86400', 12:38:35 INFO - 'USER': 'cltbld', 12:38:35 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:35 INFO - '_': '/tools/buildbot/bin/python'} 12:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:35 INFO - Downloading/unpacking blobuploader==1.2.4 12:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:37 INFO - Downloading blobuploader-1.2.4.tar.gz 12:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:38:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:38:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:38 INFO - Downloading docopt-0.6.1.tar.gz 12:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:38:38 INFO - Installing collected packages: blobuploader, requests, docopt 12:38:38 INFO - Running setup.py install for blobuploader 12:38:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:38:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:38:38 INFO - Running setup.py install for requests 12:38:38 INFO - Running setup.py install for docopt 12:38:39 INFO - Successfully installed blobuploader requests docopt 12:38:39 INFO - Cleaning up... 12:38:39 INFO - Return code: 0 12:38:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:39 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:38:39 INFO - Using env: {'DISPLAY': ':0', 12:38:39 INFO - 'HOME': '/home/cltbld', 12:38:39 INFO - 'LANG': 'en_US.UTF-8', 12:38:39 INFO - 'LANGUAGE': 'en_US:en', 12:38:39 INFO - 'LOGNAME': 'cltbld', 12:38:39 INFO - 'MAIL': '/var/mail/cltbld', 12:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:39 INFO - 'MOZ_NO_REMOTE': '1', 12:38:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:39 INFO - 'NO_EM_RESTART': '1', 12:38:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:39 INFO - 'PWD': '/builds/slave/test', 12:38:39 INFO - 'SHELL': '/bin/bash', 12:38:39 INFO - 'SHLVL': '1', 12:38:39 INFO - 'TERM': 'linux', 12:38:39 INFO - 'TMOUT': '86400', 12:38:39 INFO - 'USER': 'cltbld', 12:38:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:39 INFO - '_': '/tools/buildbot/bin/python'} 12:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:39 INFO - Running setup.py (path:/tmp/pip-xmPI1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:39 INFO - Running setup.py (path:/tmp/pip-P0EKrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:39 INFO - Running setup.py (path:/tmp/pip-Yq5dNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:39 INFO - Running setup.py (path:/tmp/pip-tXRzOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:39 INFO - Running setup.py (path:/tmp/pip-aSyTYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:39 INFO - Running setup.py (path:/tmp/pip-h7THV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:39 INFO - Running setup.py (path:/tmp/pip-M_KBEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:39 INFO - Running setup.py (path:/tmp/pip-QyeASs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:39 INFO - Running setup.py (path:/tmp/pip-NaPokL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:39 INFO - Running setup.py (path:/tmp/pip-DbprUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:40 INFO - Running setup.py (path:/tmp/pip-HK7L5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:40 INFO - Running setup.py (path:/tmp/pip-ZBVw4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:40 INFO - Running setup.py (path:/tmp/pip-bM2qK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:40 INFO - Running setup.py (path:/tmp/pip-oiiYrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:40 INFO - Running setup.py (path:/tmp/pip-zVnpTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:40 INFO - Running setup.py (path:/tmp/pip-UTeq6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:40 INFO - Running setup.py (path:/tmp/pip-Ur6utp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:38:40 INFO - Running setup.py install for manifestparser 12:38:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:38:40 INFO - Running setup.py install for mozcrash 12:38:40 INFO - Running setup.py install for mozdebug 12:38:40 INFO - Running setup.py install for mozdevice 12:38:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:38:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozfile 12:38:41 INFO - Running setup.py install for mozhttpd 12:38:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozinfo 12:38:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozInstall 12:38:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozleak 12:38:41 INFO - Running setup.py install for mozlog 12:38:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for moznetwork 12:38:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozprocess 12:38:41 INFO - Running setup.py install for mozprofile 12:38:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozrunner 12:38:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Running setup.py install for mozscreenshot 12:38:42 INFO - Running setup.py install for moztest 12:38:42 INFO - Running setup.py install for mozversion 12:38:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:38:42 INFO - Cleaning up... 12:38:42 INFO - Return code: 0 12:38:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:42 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:38:42 INFO - Using env: {'DISPLAY': ':0', 12:38:42 INFO - 'HOME': '/home/cltbld', 12:38:42 INFO - 'LANG': 'en_US.UTF-8', 12:38:42 INFO - 'LANGUAGE': 'en_US:en', 12:38:42 INFO - 'LOGNAME': 'cltbld', 12:38:42 INFO - 'MAIL': '/var/mail/cltbld', 12:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:42 INFO - 'MOZ_NO_REMOTE': '1', 12:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:42 INFO - 'NO_EM_RESTART': '1', 12:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:42 INFO - 'PWD': '/builds/slave/test', 12:38:42 INFO - 'SHELL': '/bin/bash', 12:38:42 INFO - 'SHLVL': '1', 12:38:42 INFO - 'TERM': 'linux', 12:38:42 INFO - 'TMOUT': '86400', 12:38:42 INFO - 'USER': 'cltbld', 12:38:42 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:42 INFO - '_': '/tools/buildbot/bin/python'} 12:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:42 INFO - Running setup.py (path:/tmp/pip-BqifDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:42 INFO - Running setup.py (path:/tmp/pip-9UyaGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:42 INFO - Running setup.py (path:/tmp/pip-jMj69d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:42 INFO - Running setup.py (path:/tmp/pip-chLaC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:42 INFO - Running setup.py (path:/tmp/pip-Ng6WCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:38:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:42 INFO - Running setup.py (path:/tmp/pip-LRT3wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:43 INFO - Running setup.py (path:/tmp/pip-4XsR7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:43 INFO - Running setup.py (path:/tmp/pip-Qp2Jiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:43 INFO - Running setup.py (path:/tmp/pip-vgMxFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:43 INFO - Running setup.py (path:/tmp/pip-57mpbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:43 INFO - Running setup.py (path:/tmp/pip-M_3n_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:43 INFO - Running setup.py (path:/tmp/pip-0P9f59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:43 INFO - Running setup.py (path:/tmp/pip-_56On7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:43 INFO - Running setup.py (path:/tmp/pip-urn7db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:43 INFO - Running setup.py (path:/tmp/pip-LYhpRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:43 INFO - Running setup.py (path:/tmp/pip-Qau9ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:43 INFO - Running setup.py (path:/tmp/pip-E93rHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:38:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:45 INFO - Downloading blessings-1.6.tar.gz 12:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:38:45 INFO - Installing collected packages: blessings 12:38:45 INFO - Running setup.py install for blessings 12:38:45 INFO - Successfully installed blessings 12:38:45 INFO - Cleaning up... 12:38:45 INFO - Return code: 0 12:38:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:38:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:46 INFO - Reading from file tmpfile_stdout 12:38:46 INFO - Current package versions: 12:38:46 INFO - argparse == 1.2.1 12:38:46 INFO - blessings == 1.6 12:38:46 INFO - blobuploader == 1.2.4 12:38:46 INFO - docopt == 0.6.1 12:38:46 INFO - functools32 == 3.2.3-2 12:38:46 INFO - jsonschema == 2.5.1 12:38:46 INFO - manifestparser == 1.1 12:38:46 INFO - mozInstall == 1.12 12:38:46 INFO - mozcrash == 0.17 12:38:46 INFO - mozdebug == 0.1 12:38:46 INFO - mozdevice == 0.48 12:38:46 INFO - mozfile == 1.2 12:38:46 INFO - mozhttpd == 0.7 12:38:46 INFO - mozinfo == 0.9 12:38:46 INFO - mozleak == 0.1 12:38:46 INFO - mozlog == 3.3 12:38:46 INFO - moznetwork == 0.27 12:38:46 INFO - mozprocess == 0.23 12:38:46 INFO - mozprofile == 0.28 12:38:46 INFO - mozrunner == 6.12 12:38:46 INFO - mozscreenshot == 0.1 12:38:46 INFO - mozsystemmonitor == 0.3 12:38:46 INFO - moztest == 0.7 12:38:46 INFO - mozversion == 1.4 12:38:46 INFO - psutil == 3.1.1 12:38:46 INFO - requests == 1.2.3 12:38:46 INFO - wsgiref == 0.1.2 12:38:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:46 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:38:46 INFO - Using env: {'DISPLAY': ':0', 12:38:46 INFO - 'HOME': '/home/cltbld', 12:38:46 INFO - 'LANG': 'en_US.UTF-8', 12:38:46 INFO - 'LANGUAGE': 'en_US:en', 12:38:46 INFO - 'LOGNAME': 'cltbld', 12:38:46 INFO - 'MAIL': '/var/mail/cltbld', 12:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:46 INFO - 'MOZ_NO_REMOTE': '1', 12:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:46 INFO - 'NO_EM_RESTART': '1', 12:38:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:46 INFO - 'PWD': '/builds/slave/test', 12:38:46 INFO - 'SHELL': '/bin/bash', 12:38:46 INFO - 'SHLVL': '1', 12:38:46 INFO - 'TERM': 'linux', 12:38:46 INFO - 'TMOUT': '86400', 12:38:46 INFO - 'USER': 'cltbld', 12:38:46 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:46 INFO - '_': '/tools/buildbot/bin/python'} 12:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:38:46 INFO - Cleaning up... 12:38:46 INFO - Return code: 0 12:38:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:46 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 0x175e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, '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': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:38:46 INFO - Using env: {'DISPLAY': ':0', 12:38:46 INFO - 'HOME': '/home/cltbld', 12:38:46 INFO - 'LANG': 'en_US.UTF-8', 12:38:46 INFO - 'LANGUAGE': 'en_US:en', 12:38:46 INFO - 'LOGNAME': 'cltbld', 12:38:46 INFO - 'MAIL': '/var/mail/cltbld', 12:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:46 INFO - 'MOZ_NO_REMOTE': '1', 12:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:46 INFO - 'NO_EM_RESTART': '1', 12:38:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:46 INFO - 'PWD': '/builds/slave/test', 12:38:46 INFO - 'SHELL': '/bin/bash', 12:38:46 INFO - 'SHLVL': '1', 12:38:46 INFO - 'TERM': 'linux', 12:38:46 INFO - 'TMOUT': '86400', 12:38:46 INFO - 'USER': 'cltbld', 12:38:46 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:46 INFO - '_': '/tools/buildbot/bin/python'} 12:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:38:46 INFO - Cleaning up... 12:38:46 INFO - Return code: 0 12:38:46 INFO - Running post-action listener: _resource_record_post_action 12:38:46 INFO - Running post-action listener: _start_resource_monitoring 12:38:46 INFO - Starting resource monitoring. 12:38:46 INFO - [mozharness: 2016-07-22 19:38:46.674773Z] Finished create-virtualenv step (success) 12:38:46 INFO - [mozharness: 2016-07-22 19:38:46.675216Z] Running install step. 12:38:46 INFO - Running pre-action listener: _resource_record_pre_action 12:38:46 INFO - Running main action method: install 12:38:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:46 INFO - Reading from file tmpfile_stdout 12:38:46 INFO - Detecting whether we're running mozinstall >=1.0... 12:38:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:38:46 INFO - Reading from file tmpfile_stdout 12:38:46 INFO - Output received: 12:38:46 INFO - Usage: mozinstall [options] installer 12:38:46 INFO - Options: 12:38:46 INFO - -h, --help show this help message and exit 12:38:46 INFO - -d DEST, --destination=DEST 12:38:46 INFO - Directory to install application into. [default: 12:38:46 INFO - "/builds/slave/test"] 12:38:46 INFO - --app=APP Application being installed. [default: firefox] 12:38:46 INFO - mkdir: /builds/slave/test/build/application 12:38:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:39:03 INFO - Reading from file tmpfile_stdout 12:39:03 INFO - Output received: 12:39:03 INFO - /builds/slave/test/build/application/firefox/firefox 12:39:03 INFO - Running post-action listener: _resource_record_post_action 12:39:03 INFO - [mozharness: 2016-07-22 19:39:03.344858Z] Finished install step (success) 12:39:03 INFO - [mozharness: 2016-07-22 19:39:03.345184Z] Running run-tests step. 12:39:03 INFO - Running pre-action listener: _resource_record_pre_action 12:39:03 INFO - Running main action method: run_tests 12:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:39:03 INFO - Python 2.7.3 12:39:03 INFO - Return code: 0 12:39:03 INFO - grabbing minidump binary from tooltool 12:39:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:03 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 0x1a1d3b0>, '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 0x1a1e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:39:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:39:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:39:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:39:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:39:03 INFO - Return code: 0 12:39:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:39:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:39:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:39:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:39:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:39:03 INFO - Using env: {'DISPLAY': ':0', 12:39:03 INFO - 'HOME': '/home/cltbld', 12:39:03 INFO - 'LANG': 'en_US.UTF-8', 12:39:03 INFO - 'LANGUAGE': 'en_US:en', 12:39:03 INFO - 'LOGNAME': 'cltbld', 12:39:03 INFO - 'MAIL': '/var/mail/cltbld', 12:39:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:39:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:03 INFO - 'MOZ_NO_REMOTE': '1', 12:39:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:39:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:39:03 INFO - 'NO_EM_RESTART': '1', 12:39:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:03 INFO - 'PWD': '/builds/slave/test', 12:39:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:39:03 INFO - 'SHELL': '/bin/bash', 12:39:03 INFO - 'SHLVL': '1', 12:39:03 INFO - 'TERM': 'linux', 12:39:03 INFO - 'TMOUT': '86400', 12:39:03 INFO - 'USER': 'cltbld', 12:39:03 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141', 12:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:03 INFO - '_': '/tools/buildbot/bin/python'} 12:39:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:39:03 INFO - mozversion application_buildid: 20160722121225 12:39:03 INFO - mozversion application_changeset: 34e32a1c5cef83dc0271fc973f0caad7910b440d 12:39:03 INFO - mozversion application_display_name: Nightly 12:39:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:39:03 INFO - mozversion application_name: Firefox 12:39:03 INFO - mozversion application_remotingname: firefox 12:39:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:39:03 INFO - mozversion application_vendor: Mozilla 12:39:03 INFO - mozversion application_version: 50.0a1 12:39:03 INFO - mozversion platform_buildid: 20160722121225 12:39:03 INFO - mozversion platform_changeset: 34e32a1c5cef83dc0271fc973f0caad7910b440d 12:39:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:39:03 INFO - mozversion platform_version: 50.0a1 12:39:03 INFO - using testdate: 1469216343 12:39:03 INFO - actual date: 1469216343 12:39:03 INFO - starting webserver on 'localhost:45956' 12:39:03 INFO - SUITE-START | Running 2 tests 12:39:03 INFO - TEST-START | dromaeo_css 12:39:03 INFO - operating with platform_type : linux_ 12:39:03 INFO - Initialising browser for dromaeo_css test... 12:39:03 INFO - TEST-INFO | started process 12600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTfHgKA/profile http://localhost:45956/getInfo.html) 12:39:07 INFO - PROCESS | 12600 | JavaScript error: , line 0: uncaught exception: undefined 12:39:07 INFO - PROCESS | 12600 | __metrics Screen width/height:1600/1200 12:39:07 INFO - PROCESS | 12600 | colorDepth:24 12:39:07 INFO - PROCESS | 12600 | Browser inner width/height: 1024/697 12:39:07 INFO - PROCESS | 12600 | __metrics 12:39:08 INFO - PROCESS | 12600 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:39:08 INFO - PROCESS | 12600 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:39:09 INFO - TEST-INFO | 12600: exit 0 12:39:09 INFO - Browser initialized. 12:39:09 INFO - Running cycle 1/1 for dromaeo_css test... 12:39:09 INFO - TEST-INFO | started process 12678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTfHgKA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:21 INFO - PROCESS | 12678 | RSS: Main: 159752192 12:39:21 INFO - PROCESS | 12678 | 12:40:13 INFO - PROCESS | 12678 | 1469216413296 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:42:24 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45956/tests/dromaeo/cssquery-ext.html) 12:42:25 INFO - PROCESS | 12678 | RSS: Main: 245125120 12:42:25 INFO - PROCESS | 12678 | 12:45:28 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-ext.html (next: http://localhost:45956/tests/dromaeo/cssquery-jquery.html) 12:45:29 INFO - PROCESS | 12678 | RSS: Main: 246702080 12:45:29 INFO - PROCESS | 12678 | 12:48:31 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45956/tests/dromaeo/cssquery-mootools.html) 12:48:32 INFO - PROCESS | 12678 | RSS: Main: 223109120 12:48:32 INFO - PROCESS | 12678 | 12:51:34 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45956/tests/dromaeo/cssquery-prototype.html) 12:51:34 INFO - PROCESS | 12678 | RSS: Main: 189886464 12:51:34 INFO - PROCESS | 12678 | 12:54:38 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45956/tests/dromaeo/cssquery-yui.html) 12:54:38 INFO - PROCESS | 12678 | RSS: Main: 191021056 12:54:38 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | Cycle 1(1): loaded http://localhost:45956/tests/dromaeo/cssquery-yui.html (next: http://localhost:45956/tests/dromaeo/cssquery-dojo.html) 12:57:41 INFO - PROCESS | 12678 | RSS: Main: 282726400 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | __start_tp_report 12:57:41 INFO - PROCESS | 12678 | _x_x_mozilla_page_load 12:57:41 INFO - PROCESS | 12678 | _x_x_mozilla_page_load_details 12:57:41 INFO - PROCESS | 12678 | |i|pagename|runs| 12:57:41 INFO - PROCESS | 12678 | |0;dojo.html;2547.3122256990614;2558.398776287571;2590.676165479315;2597.363645906752;2599.0903183885644;4559.521250268724;4602.240630295997;4653.441587009555;4669.813207471706;4697.5302469753005;4571.040081582863;4628.953710462894;4638.675292729502;4657.813687452506;4713.10451014307;4222.113356195203;4231.513375961773;4296.591823776736;4351.73889566627;4449.3548435477;4357.2592659248;4393.187260356828;4402.185595664796;4410.867673969786;4424.823007079713;4175.582441755831;4179.707420480568;4182.059036716714;4188.979055104705;4317.913641727164;4221.598948099926;4240.915181696349;4270.274053410928;4337.219300525904;4352.7605981670995;4278.850240241608;4287;4292.849750258757;4324.610785029331;4325.091730736549;4561.794719237642;4638.170715863321;4704.1062198182235;4733.431988161443;4734.313524538922;4943.258511223309;4989.65072444929;4995.975020124913;5000.0999820032375;5051.393832740058;5014.598832093424;5034.697918124888;5062.139436295838;5062.468440813698;5068.467810879842;4497.415336006364;4604.263317869192;4712.128256272566;4717.646176536773;4722.913729842156;5068.16375298079;5082.440931497532;5133.79464821403;5148.33071700684;5160.92258616121;4903.338049363361;4943.209086546135;4952.059108769322;4953.18272485036;4957.950420495821;4856.417229932431;4864.73243971585;5026.673266237683;5036.169032109734;5045.8738531537;4853.733044682576;4881.267809828483;4887.902241955212;4920.261960705851;4925.261210818334;4852.369192005017;4857.319975203474;4858.368412106403;4866.8783280417565;4912.189488734319;4141.399497072907;4195.958040419557;4232.8518501852905;4238.703290769617;4284.785760711952;3671.2657468506404;3683.594804571495;3695.4641576971726;3711.6102809205186;3791;4236.597523235288;4248;4252.48970123581;4267.487901451784;4292.12011537634;4830.9275360869615;4945.4312754033745;4950.826721064817;4953.430355509163;4987.775550100182;4198.9370159447635;4238.491381034296;4275.679324050647;4289.313709806416;4322.243607368698;4535.659825513099;4544.681872268976;4544.954550454519;4552.863414097516;4596.057808149321;4713.198756211452;4725.787339569659;4742.739149346819;4753.239481682914;4769.42766867978;4873.756312184448;4890.2909078184175;4921.31101645777;4928.802847886044;4967.925481117858;4735.621150308036;4740.0756852414825;4744.169770290255;4757.476677565534;4783.923617186106;4888.193448081027;4909.1654418748185;4912.82805101834;4921.434035086011;4948.579370753526;4799.736014519235;4852.102361063215;4897.094037603055;4915.508449155056;4945.67853089548;4746.833860814855;4755.286706993978;4756.381670382828;4787.42550893895;4795.664303498722;4736;4744.596709279612;4750.12122757291;4752.453467851241;4797.280407938837;4609.377734005866;4651.604613607746;4681.063787242633;4696.365990591226;4711.882202944954;4806.711597304173;4835.226363781778;4840.273958906051;4857.562819346276;4874.610031197425;4699.812007519661;4710.64670149747;4724.598409135262;4732.100900828969;4776.379070720923 12:57:41 INFO - PROCESS | 12678 | |1;ext.html;573.2232824522772;582.2256398989344;585.0668184246126;587.1222522329118;590.3594599859152;27000.86499567502;39820.60179398205;41331.17337653249;41371.79314103433;45175.548244517544;40767.59232407675;41221.17557648845;41224.17551648965;41273.38089928654;41274.38088428676;7314.670839812221;7540.585267810282;7708.922910770877;7711.460197786129;7719.72980945667;9645.228381729445;9945.751356216117;9973.601055957753;10275.383476991366;10294.536745846417;9949.403035817873;10045.598176072948;10052.648157314496;10053.748656283578;10084.14284785794;10035.949820250926;10055.446950417723;10073;10104.292699511037;10118.139958103497;9932.851007234898;10015.899841001643;10023.047810457994;10029;10029.498525073717;39081;39391.40912886309;39413.80293098516;39439.80280098581;39527.60472395268;2918.255844759594;3118.4075025745037;3307.96692033079;3356.228067544454;3774.905627359338;27441.588376174175;29931.40137197244;30280.8485957571;30302;30348.696513034807;3516.982415087934;3535.8232088395735;3604.891853244381;3605.603383627799;3725.4784330193793;271849;273084;273719;274062.6296868523;274121;29031.27421814429;54982.45017549853;55149.72425137849;55163.44836551663;55253.72373138189;31286.374272514422;33125.83437082799;33155.83422082874;33191.17022074467;33200.16999574981;13830.930845345709;13971.441142354395;13975.161490310615;13991.790123148161;14019.50931717385;10935.507902144263;11076.224664273584;11079.612213572485;11385.715357115972;11639.06887449002;7819.452638315263;7847.097583777824;7953.443258971932;7974.681012759424;7979.521228726295;2930.8388038657654;3042.4827779277575;3174.4286028514625;3175.428422883857;3199.664035276309;775.4649291988623;892.9821403571892;959.3332633819429;965.4400447740294;994.3139233928566;28025.159245222676;28530.28674283118;28662;29014.41971160565;29045.564316534845;912.1653686876566;936.2509992006368;947.8104379124203;982.543117450389;1016.598443614768;13806.447742090404;14528.564143075724;14550.781738273934;14686.339114739862;14768.852311476963;404682;408223;408562;408762;409417;43529.78235108804;48448.51551484511;49028.75485622549;49072;49139.52581422563;1166.8133098704166;1218.4273391506156;1227.1042139238584;1234.512367614805;1234.8703386144505;27483.587746183424;28630.14109576716;28979.130626081253;29111;29117.416663756427;42615.5738442624;43248.351274730274;43296.78351608222;43313.35029974616;43342.783286083366;358917;364149;429322;439792;440938;39110.804445977585;39257.607423925394;39529.80235098806;39563.20873582455;40047.599524004385;40379;40558.39162412625;42800.57199427966;43067;43450.13099738051;36918.44622330721;37043;37177.81411092927;37734;38955.805220973714;37275.62724372721;37350.25299493941;37669;37806.81096594499;37825.81087094657;35841.64158358383;36015.27969440544;36494.45258321074;37623;37670.81164594269;29289.707102928696;30668.539971899994;31419.685803141678;31531.527027094155;31637.525437118922;24215.75784242135;24500.75499244985;24616.507669846862;24965.625515617645;25028.874855625607 12:57:41 INFO - PROCESS | 12678 | |2;jquery.html;338.90171850163455;357.45309676195416;361.94208926571747;369.2596344329619;376.0954903457185;12757.234565926039;13643;14011.649708757273;17038.48884533463;17056.488305350827;16341.18294085294;16616.83383166171;17266.395676151336;17281.82718172818;18026.81973180268;4455.955440445603;4793.9280910786365;4800.399950006249;4800.447948485934;4801.1117943180625;3535.4166562517153;5597.552195824344;5826;5893.263342082239;5971.37300583439;5676.460736230051;5929.644221346712;5932.525397968174;5934.910976335358;5942;5784.884302313952;5843.590948633619;5857.179994800731;5897.3218079920925;5903.763849446017;5138.614603904722;5178.948210517922;5247.370315562157;5253.08071087562;5298.867528311823;12701.364931753467;13625.182489050689;17473.912630436767;17484.562885927826;17568.38510652121;1780.9643807123784;1879.36101725414;1907.5898681783483;2078.6985887046294;2283.7944584987263;8974.50640214788;9071.727848164566;9348.719538413858;9545.665901693408;9749.220062394992;1701.7192163293041;1709.273558737544;1717.3645751072183;1735.5921358480748;1768.142450911307;68414;71954;73187.26812731857;75634.24365756327;77509;10180.847287290697;10213.897861021444;10456.268061235793;10474;10548.261621686408;14161.645958851112;14165.71668566623;14169.574912752634;14180.14919104857;14186.290685465685;7603.847923041619;7708.956398551786;7752.7674169775;7756.844863102707;7896.881546776803;7501.512401693873;7535.321821036133;7674.079110506775;7725.884111738328;7736.497127686683;4319.524852266248;4394.384786129945;4394.516603173648;4471.418715566955;4516.9096618068115;1796.1917137288272;1804.3594523944073;1817.790954040276;1843.2074208090382;1847.9538011549819;770.2028400605346;808.5148910653561;868.7306934850217;877.4033657113224;877.7630039889322;16705.498835034967;17121.657566848593;17821.7326740099;17836.19737111833;18099.366522171924;854.1543871567104;855.3157474020759;864.7059999600041;922.5341202692597;957.6265256549951;9442.527873606292;9707.9029209707;10158.898411015944;10766.676999690022;10853.674389768319;403073;404160;404932;405314;407569;82645.58677206576;83265.16734832696;83768;84323.57838210769;87860;898.955052247398;913.2785099771239;934.9532523373804;937.7374335186158;937.8452555328431;15652.608684782972;15808.841911580737;16244.269007894436;16329.755053673967;16644.583885402477;39735;39825.601743983345;39865.80067099646;39874.2025159501;40375.39436908507;50291.24563131629;51024;54891.45108549023;55380.16929745976;56028.71985640046;7791.493552919269;7812.257835505618;7821.96089019574;7855.56794376292;7859.685612575433;9487.952560237154;11878.346690932081;12002.939985300367;12029.398530073286;12104.636860894187;9909.950450247703;9974.60101595928;10273.589056437957;10354.068133867988;10893.400862952614;10031.54858031405;10145.137663298407;10286.794264114826;10304.072633463024;10426.843597345895;9559.808803823746;10206.438645874548;10214.336068155546;10285.228607854291;10436.112930401001;7506.587137707478;7618.123915749538;7661.808454788452;7722.53664780115;7795.883061754192;8956.149165829236;9554.378965367228;9791.0128599854;10189.796204075728;10257.384556926609 12:57:41 INFO - PROCESS | 12678 | |3;mootools.html;1264.835571375721;1363.0731102850064;1409.21084192852;1431.7780743984683;1567.6551158745078;6450.096986421899;7361.668724907378;7578.204288549706;7594.392448604105;7811.648475818588;7629.580373079479;7670.539767613947;7674.884876726854;7675.6545955432;7681.3086822186;2134.4770531219797;2165.783421657837;2168.793964573363;2169.6745488176743;2169.8481106322565;2099.3177217404327;2140.625390556654;2164.3290579920276;2165.17723265159;2166.3609235275553;2118.5339225370394;2128.88291143987;2136.957260854782;2139.850210485267;2162.859414138084;2078;2083.5832833433374;2086.3010891351414;2088.759792623845;2089.7492300923986;2026.311054241565;2085.676720108386;2086.050846864673;2086.0821238654944;2089.352300786761;7655.272749088827;7698.384129269645;7712;7716.228377162295;7726.575038372887;4430.645548356124;4445.355423463579;4562.1560011398;4567.33773602829;4587.243104887723;2851.70057144001;2897.652281726206;2947.2484516448385;2952.1881482592244;2957.645082590103;933.682547933699;936.2463217110223;945.2579724915907;952.7094236258008;955.2214944819949;10454.32046916948;10549.9472502637;10564.683059508227;10576.682699519026;10584.788304233873;3972.6027397260623;4050.7569545826755;4051.331530297468;4060.8578699745367;4221;8187.631556579968;8373.288270496954;8376.832463350698;8393.24460798531;8397.244248017707;4516.254817955;4545.454545454567;4548.4541854977615;4548.6361091113295;4549.09018196362;3690.4279836625365;4121.093359460914;4250.808713607895;4251.936220956688;4253.064325848309;3336.132605522533;3388.508666243381;3481.7736847104857;3491.389006923778;3500.982495087559;997.6857289953641;997.8004399120205;998.2912132385942;1008.5864795433837;1016.3089099412471;655.0730716036754;703.9366457018892;738.0700317599863;811.1604489353491;846.4455781463151;5112.769925353368;5141.280220769096;5153.123968925292;5292.5501332387175;5465.508104270635;837.2172019161981;869.3783944479727;873.2839071960931;909.3543584055262;944.9244060475284;3529.5940966788635;3653.908652283714;3742.8877133686033;3750.1374683822874;3756.7745935243975;9023.548822558845;9244.537773111451;9338.953305233566;9445.716628501155;9545.522723863918;9360.391574547633;9428.01005894385;9430.3398762086;9477.573509192103;9487.193588545051;6717.261101278856;6871;6908.585484870924;7070.787876363717;7127.643617819088;4722.811087556459;4863.294822250825;4894;5074.68058304836;5112.054269960069;8600.09698981598;8623.267022303175;8695.21743043104;8777.078406767198;8820.603072861608;8685.913140868763;8795.604197810986;8867.113288671082;8958.641654333753;8974.775630609287;5333.173358129496;5809.535237180931;6261.217347831521;6354.459870911024;6355.523335749745;7032.1912980007955;7156.749513766993;7215.8556828864175;7229.783106506813;7241.782746517613;6317.4630156437215;6393.904091438725;6437.742490300336;6495.9025614614875;6624.436922861611;6345.841353966188;6363.5545511813725;6424.550281480251;6426.550141490051;6432.871342573028;5765.048766953406;5850.414958504116;5852.882942341214;5856.150858125633;5856.677882716492;4462.308342206963;4492.191405547033;4498.3252512122135;4538.818447262072;4599.0342028174455;4460.085682435158;4475.7985890635655;4481.081378317503;4481.529439408945;4497.572730590588 12:57:41 INFO - PROCESS | 12678 | |4;prototype.html;278.34033340981875;280.2349585631225;287.44379625424807;289.7985899799639;295.39001960950634;4816.446108697501;4837.177679794435;4848.878778030551;4855.9802441487245;4859.781309841057;4719.7256740679995;4796.040791841629;4803.150786947024;4806.591439727627;4812.350332705084;3575.8569657213743;3666.2850744104912;3714.4799728038097;3777.4333849922596;3806.4861243732157;3772.0569857535615;3784.735068545203;3808.1050953026015;3825.158465137665;3831.7700937943814;3739.7943113128767;3744.9625503745024;3754.4180651998986;3769.736118471708;3774.0753515388633;3584.3010612930357;3644.562652481719;3721.274351501458;3772.7359084863797;3791.981040094796;3303.966960330414;3318.551995480603;3320.8007519548664;3324.3683700096904;3334.4664853623303;4670.673052886299;4671.743054132022;4681.344611754357;4686.5782079613;4697.67116301859;4172.4784401949755;4288.957110428887;4289.1421715657;4290.678199135046;4296.849610263625;4946.431160416526;4960.156773348539;4987.354173122861;4995.775190116417;5017.974910125463;3652.7077833773233;3658.432942893856;3661.1945372018113;3663;3664.560252769658;5042.6470147090085;5251.107311756972;5251.186066159751;5254.947450525484;5267.604929630293;3670;3681.2942487271835;3705.8517659293866;3721.5906250312446;3729.701623870084;3714.832832522543;3724.5902950675404;3730.6756101584087;3745.4756334113245;3769.4345848122452;4240.469941257343;4267.338562522814;4273.952881544042;4274.273373526508;4294.506131794821;4831.589314908202;4938.728369939688;4976.527229913152;4982.3522942018035;4995.450500444948;1254.8894228607749;1264.2225031605496;1267.0370518406078;1267.575362253637;1273.490603758504;816.506013861617;828.1263267252986;842.9789255268668;853.5774791478134;861.642418396371;4297.140571885635;4390.7047421010875;4398.362237475548;4407.294832826732;4424.610710216447;5131.384233891957;5131.692098474104;5136.640435169577;5145.485451454824;5178.766955487012;4367.978160109179;4384.736915784999;4389.824407023747;4409.294512877924;4411.536788637211;2580.548404029287;2717.768989635903;2723.891044358243;2734.8222365546176;2734.8495832728995;4882.218844984785;4888.071266459361;4891.706497610155;4892;4894.3147959285725;4996.275540046673;5207.323048003811;5263.236830659533;5273.815416460406;5278.604104692164;4903.289023091632;4921.9507804921495;4927.87680307988;4948;4949.65352425334;5187.533122019036;5201.6098792591465;5207.505286997729;5258.6581872178185;5265.919961615064;4866.197077482176;5064.7467626619255;5124.615653825904;5166.741662916915;5168;4911.459739428588;4919.311296418433;4930.235813448921;4931.284963680175;4937.8271760488215;3634.2368102697674;3671.963280367162;3676.3382591133854;3684.3368193725387;3687.1150923778637;5093.261477085876;5171.81898633546;5203.167493201221;5251.973740131275;5263.710495922761;4931.802727890844;4959.97520012412;4961.578265847444;4963.436517496977;5011.148104822262;4949.579285760607;4953.23224900141;4978.4274808397495;5033.949660503348;5058.140116180185;4763.809447622057;4873.1959226727195;4945.876353091202;4952.281919121635;4960.131976904099;4782.33047373361;4872.6832755870755;4999.400431689178;5000.724960127082;5006.674566153189;4880.145974454527;4897.485763994871;4907.362042934538;4909.9018019640125;4971.1797553403285 12:57:41 INFO - PROCESS | 12678 | |5;yui.html;610.7251736718478;623.9656818874963;641.5797652537584;689.9793006209813;712.1169749510608;12142.575009874645;13410;13412.93293533531;13451.125676831012;13464.057515973886;12533.68665783356;12703.809442858364;12704.491820327175;12712.23726576406;12764.361781910891;625.1997443272618;644.4683136412455;749.2357795049047;751.5265382808823;767.2289349204042;756.8940348351235;773.5590713293425;776.1539921485579;776.7048521561799;777.4169372970272;822.8477731619639;823.5470491229829;823.6046697585133;823.979400514986;824.295227580416;643.8519140597689;786.7836345005112;806.1213277527464;815.4332738746543;817.3992115794886;597.4264705882315;597.9910301345483;598.0819442156269;598.0998597111363;598.7994022002591;12437.191582547106;12468.189567678071;12478.75042499145;12482.875171248354;12496.062795290298;11950.282983021047;12016.399180041051;12096.092793040558;12109;12659.556915507914;11682.532698692043;11723.120765942589;11770.882291177064;11841.348725820077;11898.762024759542;3264.412405766961;3387.3225354929114;4050.392441133824;4081.346984482498;4186.790660466995;48448;49046.50953490455;49087.509124908654;53544.46455535434;56688.433115668726;10336.17310615149;10501.474926253657;10505.82304884399;10622.468876556295;10742.946285268523;18167.636647267245;18289.542761431072;18343.81656183421;18419.81580184181;18446.538836528925;6753.763618273435;6880.518363714592;7226.566406015656;7239.239879812649;7252.854942901113;3730.1793605406765;3769.076576238839;3795.221979494252;3984.143409167043;3987.521497420328;2831;2895.811772234798;2912.7815413844046;2923.9122826315242;2926.941461170765;491.09148076059006;501.1680610187072;507.2467385931888;509.1471784760511;512.8487096306599;541.9458054194628;635.9141515895387;637.8118455055769;643.8036398898288;650.2879347114902;7399.4450416219;7744;7835.177306382861;7875.212478752193;7879.369650427952;507.71567921963816;530.7081105392019;562.9971850140723;633.1136409027306;642.1651852591613;3610.4223324268514;3720.4605332226674;3808.1622043150464;3833.961660383416;3979.840806367713;56923.71538142283;91265.80569680162;91616;93112.53443732738;93122;80966;81390.59304703439;81848.13233883855;82144.58927705324;82511;68432.31567684359;69898.3010169902;73946.52106957938;74921.62539187269;77566.61216693993;6524.380183882524;6851.862962740617;7404.333609975128;7627.54234745917;7795;44391.334129988725;44738.139954267324;45478.77260613676;45710.77144614256;47006.529934700215;36378;53048.73475632597;61156.38843611507;61612.69193654003;62406.37593624187;10109.949450252701;10806.18953578469;10864.45677716127;10904.127669786556;10985.670429887115;10865.967733065347;11098.334099953705;11135.443227838738;11624.709382265512;11657;9090.272778177598;9216.447013179231;9253.953730231306;9494.85757713621;10072.244581656258;9746.707598772047;9843.753906152404;9936.801263974825;10020.398776073458;10147.746306342106;9334.673286434941;9428.481433520948;9430.245580353692;9474.57364418589;9560.23518118535;8344.290735287568;8811.383203175716;9161.725148245563;9192.126747958933;9280;8307.875381869157;8452.281556067803;8529.488230706178;9043.819123617623;9201.723948281295 12:57:41 INFO - PROCESS | 12678 | __end_tp_report 12:57:41 INFO - PROCESS | 12678 | __start_cc_report 12:57:41 INFO - PROCESS | 12678 | _x_x_mozilla_cycle_collect,937 12:57:41 INFO - PROCESS | 12678 | __end_cc_report 12:57:41 INFO - PROCESS | 12678 | __startTimestamp1469217461931__endTimestamp 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | ------- Summary: start ------- 12:57:41 INFO - PROCESS | 12678 | Number of tests: 6 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#0] dojo.html Cycles:165 Average:4588.95 Median:4725.79 stddev:474.69 (10.0%) stddev-sans-first:448.32 12:57:41 INFO - PROCESS | 12678 | Values: 2547.3 2558.4 2590.7 2597.4 2599.1 4559.5 4602.2 4653.4 4669.8 4697.5 4571.0 4629.0 4638.7 4657.8 4713.1 4222.1 4231.5 4296.6 4351.7 4449.4 4357.3 4393.2 4402.2 4410.9 4424.8 4175.6 4179.7 4182.1 4189.0 4317.9 4221.6 4240.9 4270.3 4337.2 4352.8 4278.9 4287.0 4292.8 4324.6 4325.1 4561.8 4638.2 4704.1 4733.4 4734.3 4943.3 4989.7 4996.0 5000.1 5051.4 5014.6 5034.7 5062.1 5062.5 5068.5 4497.4 4604.3 4712.1 4717.6 4722.9 5068.2 5082.4 5133.8 5148.3 5160.9 4903.3 4943.2 4952.1 4953.2 4958.0 4856.4 4864.7 5026.7 5036.2 5045.9 4853.7 4881.3 4887.9 4920.3 4925.3 4852.4 4857.3 4858.4 4866.9 4912.2 4141.4 4196.0 4232.9 4238.7 4284.8 3671.3 3683.6 3695.5 3711.6 3791.0 4236.6 4248.0 4252.5 4267.5 4292.1 4830.9 4945.4 4950.8 4953.4 4987.8 4198.9 4238.5 4275.7 4289.3 4322.2 4535.7 4544.7 4545.0 4552.9 4596.1 4713.2 4725.8 4742.7 4753.2 4769.4 4873.8 4890.3 4921.3 4928.8 4967.9 4735.6 4740.1 4744.2 4757.5 4783.9 4888.2 4909.2 4912.8 4921.4 4948.6 4799.7 4852.1 4897.1 4915.5 4945.7 4746.8 4755.3 4756.4 4787.4 4795.7 4736.0 4744.6 4750.1 4752.5 4797.3 4609.4 4651.6 4681.1 4696.4 4711.9 4806.7 4835.2 4840.3 4857.6 4874.6 4699.8 4710.6 4724.6 4732.1 4776.4 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#1] ext.html Cycles:180 Average:50724.20 Median:36706.45 stddev:97532.00 (265.7%) stddev-sans-first:97732.92 12:57:41 INFO - PROCESS | 12678 | Values: 573.2 582.2 585.1 587.1 590.4 27000.9 39820.6 41331.2 41371.8 45175.5 40767.6 41221.2 41224.2 41273.4 41274.4 7314.7 7540.6 7708.9 7711.5 7719.7 9645.2 9945.8 9973.6 10275.4 10294.5 9949.4 10045.6 10052.6 10053.7 10084.1 10035.9 10055.4 10073.0 10104.3 10118.1 9932.9 10015.9 10023.0 10029.0 10029.5 39081.0 39391.4 39413.8 39439.8 39527.6 2918.3 3118.4 3308.0 3356.2 3774.9 27441.6 29931.4 30280.8 30302.0 30348.7 3517.0 3535.8 3604.9 3605.6 3725.5 271849.0 273084.0 273719.0 274062.6 274121.0 29031.3 54982.5 55149.7 55163.4 55253.7 31286.4 33125.8 33155.8 33191.2 33200.2 13830.9 13971.4 13975.2 13991.8 14019.5 10935.5 11076.2 11079.6 11385.7 11639.1 7819.5 7847.1 7953.4 7974.7 7979.5 2930.8 3042.5 3174.4 3175.4 3199.7 775.5 893.0 959.3 965.4 994.3 28025.2 28530.3 28662.0 29014.4 29045.6 912.2 936.3 947.8 982.5 1016.6 13806.4 14528.6 14550.8 14686.3 14768.9 404682.0 408223.0 408562.0 408762.0 409417.0 43529.8 48448.5 49028.8 49072.0 49139.5 1166.8 1218.4 1227.1 1234.5 1234.9 27483.6 28630.1 28979.1 29111.0 29117.4 42615.6 43248.4 43296.8 43313.4 43342.8 358917.0 364149.0 429322.0 439792.0 440938.0 39110.8 39257.6 39529.8 39563.2 40047.6 40379.0 40558.4 42800.6 43067.0 43450.1 36918.4 37043.0 37177.8 37734.0 38955.8 37275.6 37350.3 37669.0 37806.8 37825.8 35841.6 36015.3 36494.5 37623.0 37670.8 29289.7 30668.5 31419.7 31531.5 31637.5 24215.8 24500.8 24616.5 24965.6 25028.9 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#2] jquery.html Cycles:180 Average:25124.74 Median:4463.69 stddev:67079.07 (1502.8%) stddev-sans-first:67241.43 12:57:41 INFO - PROCESS | 12678 | Values: 338.9 357.5 361.9 369.3 376.1 12757.2 13643.0 14011.6 17038.5 17056.5 16341.2 16616.8 17266.4 17281.8 18026.8 4456.0 4793.9 4800.4 4800.4 4801.1 3535.4 5597.6 5826.0 5893.3 5971.4 5676.5 5929.6 5932.5 5934.9 5942.0 5784.9 5843.6 5857.2 5897.3 5903.8 5138.6 5178.9 5247.4 5253.1 5298.9 12701.4 13625.2 17473.9 17484.6 17568.4 1781.0 1879.4 1907.6 2078.7 2283.8 8974.5 9071.7 9348.7 9545.7 9749.2 1701.7 1709.3 1717.4 1735.6 1768.1 68414.0 71954.0 73187.3 75634.2 77509.0 10180.8 10213.9 10456.3 10474.0 10548.3 14161.6 14165.7 14169.6 14180.1 14186.3 7603.8 7709.0 7752.8 7756.8 7896.9 7501.5 7535.3 7674.1 7725.9 7736.5 4319.5 4394.4 4394.5 4471.4 4516.9 1796.2 1804.4 1817.8 1843.2 1848.0 770.2 808.5 868.7 877.4 877.8 16705.5 17121.7 17821.7 17836.2 18099.4 854.2 855.3 864.7 922.5 957.6 9442.5 9707.9 10158.9 10766.7 10853.7 403073.0 404160.0 404932.0 405314.0 407569.0 82645.6 83265.2 83768.0 84323.6 87860.0 899.0 913.3 935.0 937.7 937.8 15652.6 15808.8 16244.3 16329.8 16644.6 39735.0 39825.6 39865.8 39874.2 40375.4 50291.2 51024.0 54891.5 55380.2 56028.7 7791.5 7812.3 7822.0 7855.6 7859.7 9488.0 11878.3 12002.9 12029.4 12104.6 9910.0 9974.6 10273.6 10354.1 10893.4 10031.5 10145.1 10286.8 10304.1 10426.8 9559.8 10206.4 10214.3 10285.2 10436.1 7506.6 7618.1 7661.8 7722.5 7795.9 8956.1 9554.4 9791.0 10189.8 10257.4 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#3] mootools.html Cycles:180 Average:4967.14 Median:5312.86 stddev:2779.70 (52.3%) stddev-sans-first:2773.57 12:57:41 INFO - PROCESS | 12678 | Values: 1264.8 1363.1 1409.2 1431.8 1567.7 6450.1 7361.7 7578.2 7594.4 7811.6 7629.6 7670.5 7674.9 7675.7 7681.3 2134.5 2165.8 2168.8 2169.7 2169.8 2099.3 2140.6 2164.3 2165.2 2166.4 2118.5 2128.9 2137.0 2139.9 2162.9 2078.0 2083.6 2086.3 2088.8 2089.7 2026.3 2085.7 2086.1 2086.1 2089.4 7655.3 7698.4 7712.0 7716.2 7726.6 4430.6 4445.4 4562.2 4567.3 4587.2 2851.7 2897.7 2947.2 2952.2 2957.6 933.7 936.2 945.3 952.7 955.2 10454.3 10549.9 10564.7 10576.7 10584.8 3972.6 4050.8 4051.3 4060.9 4221.0 8187.6 8373.3 8376.8 8393.2 8397.2 4516.3 4545.5 4548.5 4548.6 4549.1 3690.4 4121.1 4250.8 4251.9 4253.1 3336.1 3388.5 3481.8 3491.4 3501.0 997.7 997.8 998.3 1008.6 1016.3 655.1 703.9 738.1 811.2 846.4 5112.8 5141.3 5153.1 5292.6 5465.5 837.2 869.4 873.3 909.4 944.9 3529.6 3653.9 3742.9 3750.1 3756.8 9023.5 9244.5 9339.0 9445.7 9545.5 9360.4 9428.0 9430.3 9477.6 9487.2 6717.3 6871.0 6908.6 7070.8 7127.6 4722.8 4863.3 4894.0 5074.7 5112.1 8600.1 8623.3 8695.2 8777.1 8820.6 8685.9 8795.6 8867.1 8958.6 8974.8 5333.2 5809.5 6261.2 6354.5 6355.5 7032.2 7156.7 7215.9 7229.8 7241.8 6317.5 6393.9 6437.7 6495.9 6624.4 6345.8 6363.6 6424.6 6426.6 6432.9 5765.0 5850.4 5852.9 5856.2 5856.7 4462.3 4492.2 4498.3 4538.8 4599.0 4460.1 4475.8 4481.1 4481.5 4497.6 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#4] prototype.html Cycles:180 Average:4172.72 Median:4809.47 stddev:1210.32 (25.2%) stddev-sans-first:1177.89 12:57:41 INFO - PROCESS | 12678 | Values: 278.3 280.2 287.4 289.8 295.4 4816.4 4837.2 4848.9 4856.0 4859.8 4719.7 4796.0 4803.2 4806.6 4812.4 3575.9 3666.3 3714.5 3777.4 3806.5 3772.1 3784.7 3808.1 3825.2 3831.8 3739.8 3745.0 3754.4 3769.7 3774.1 3584.3 3644.6 3721.3 3772.7 3792.0 3304.0 3318.6 3320.8 3324.4 3334.5 4670.7 4671.7 4681.3 4686.6 4697.7 4172.5 4289.0 4289.1 4290.7 4296.8 4946.4 4960.2 4987.4 4995.8 5018.0 3652.7 3658.4 3661.2 3663.0 3664.6 5042.6 5251.1 5251.2 5254.9 5267.6 3670.0 3681.3 3705.9 3721.6 3729.7 3714.8 3724.6 3730.7 3745.5 3769.4 4240.5 4267.3 4274.0 4274.3 4294.5 4831.6 4938.7 4976.5 4982.4 4995.5 1254.9 1264.2 1267.0 1267.6 1273.5 816.5 828.1 843.0 853.6 861.6 4297.1 4390.7 4398.4 4407.3 4424.6 5131.4 5131.7 5136.6 5145.5 5178.8 4368.0 4384.7 4389.8 4409.3 4411.5 2580.5 2717.8 2723.9 2734.8 2734.8 4882.2 4888.1 4891.7 4892.0 4894.3 4996.3 5207.3 5263.2 5273.8 5278.6 4903.3 4922.0 4927.9 4948.0 4949.7 5187.5 5201.6 5207.5 5258.7 5265.9 4866.2 5064.7 5124.6 5166.7 5168.0 4911.5 4919.3 4930.2 4931.3 4937.8 3634.2 3672.0 3676.3 3684.3 3687.1 5093.3 5171.8 5203.2 5252.0 5263.7 4931.8 4960.0 4961.6 4963.4 5011.1 4949.6 4953.2 4978.4 5033.9 5058.1 4763.8 4873.2 4945.9 4952.3 4960.1 4782.3 4872.7 4999.4 5000.7 5006.7 4880.1 4897.5 4907.4 4909.9 4971.2 12:57:41 INFO - PROCESS | 12678 | 12:57:41 INFO - PROCESS | 12678 | [#5] yui.html Cycles:180 Average:16493.08 Median:597.71 stddev:23421.16 (3918.5%) stddev-sans-first:23456.50 12:57:41 INFO - PROCESS | 12678 | Values: 610.7 624.0 641.6 690.0 712.1 12142.6 13410.0 13412.9 13451.1 13464.1 12533.7 12703.8 12704.5 12712.2 12764.4 625.2 644.5 749.2 751.5 767.2 756.9 773.6 776.2 776.7 777.4 822.8 823.5 823.6 824.0 824.3 643.9 786.8 806.1 815.4 817.4 597.4 598.0 598.1 598.1 598.8 12437.2 12468.2 12478.8 12482.9 12496.1 11950.3 12016.4 12096.1 12109.0 12659.6 11682.5 11723.1 11770.9 11841.3 11898.8 3264.4 3387.3 4050.4 4081.3 4186.8 48448.0 49046.5 49087.5 53544.5 56688.4 10336.2 10501.5 10505.8 10622.5 10742.9 18167.6 18289.5 18343.8 18419.8 18446.5 6753.8 6880.5 7226.6 7239.2 7252.9 3730.2 3769.1 3795.2 3984.1 3987.5 2831.0 2895.8 2912.8 2923.9 2926.9 491.1 501.2 507.2 509.1 512.8 541.9 635.9 637.8 643.8 650.3 7399.4 7744.0 7835.2 7875.2 7879.4 507.7 530.7 563.0 633.1 642.2 3610.4 3720.5 3808.2 3834.0 3979.8 56923.7 91265.8 91616.0 93112.5 93122.0 80966.0 81390.6 81848.1 82144.6 82511.0 68432.3 69898.3 73946.5 74921.6 77566.6 6524.4 6851.9 7404.3 7627.5 7795.0 44391.3 44738.1 45478.8 45710.8 47006.5 36378.0 53048.7 61156.4 61612.7 62406.4 10109.9 10806.2 10864.5 10904.1 10985.7 10866.0 11098.3 11135.4 11624.7 11657.0 9090.3 9216.4 9254.0 9494.9 10072.2 9746.7 9843.8 9936.8 10020.4 10147.7 9334.7 9428.5 9430.2 9474.6 9560.2 8344.3 8811.4 9161.7 9192.1 9280.0 8307.9 8452.3 8529.5 9043.8 9201.7 12:57:41 INFO - PROCESS | 12678 | -------- Summary: end -------- 12:57:41 INFO - PROCESS | 12678 | 12:57:42 INFO - TEST-INFO | 12678: exit 0 12:57:42 INFO - TEST-OK | dromaeo_css | took 1119166ms 12:57:42 INFO - TEST-START | kraken 12:57:42 INFO - operating with platform_type : linux_ 12:57:42 INFO - Initialising browser for kraken test... 12:57:42 INFO - TEST-INFO | started process 12875 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplBT1lW/profile http://localhost:45956/getInfo.html) 12:57:46 INFO - PROCESS | 12875 | JavaScript error: , line 0: uncaught exception: undefined 12:57:46 INFO - PROCESS | 12875 | __metrics Screen width/height:1600/1200 12:57:46 INFO - PROCESS | 12875 | colorDepth:24 12:57:46 INFO - PROCESS | 12875 | Browser inner width/height: 1024/697 12:57:46 INFO - PROCESS | 12875 | __metrics 12:57:47 INFO - PROCESS | 12875 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:57:47 INFO - PROCESS | 12875 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:57:48 INFO - TEST-INFO | 12875: exit 0 12:57:48 INFO - Browser initialized. 12:57:48 INFO - Running cycle 1/1 for kraken test... 12:57:48 INFO - TEST-INFO | started process 12959 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplBT1lW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:58:00 INFO - PROCESS | 12959 | RSS: Main: 158937088 12:58:00 INFO - PROCESS | 12959 | 12:58:03 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45956/tests/kraken/driver.html?testName=audio-beat-detection) 12:58:03 INFO - PROCESS | 12959 | RSS: Main: 214773760 12:58:03 INFO - PROCESS | 12959 | 12:58:06 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45956/tests/kraken/driver.html?testName=audio-dft) 12:58:07 INFO - PROCESS | 12959 | RSS: Main: 193798144 12:58:07 INFO - PROCESS | 12959 | 12:58:10 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45956/tests/kraken/driver.html?testName=audio-fft) 12:58:11 INFO - PROCESS | 12959 | RSS: Main: 273006592 12:58:11 INFO - PROCESS | 12959 | 12:58:13 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45956/tests/kraken/driver.html?testName=audio-oscillator) 12:58:14 INFO - PROCESS | 12959 | RSS: Main: 181964800 12:58:14 INFO - PROCESS | 12959 | 12:58:16 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45956/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:58:16 INFO - PROCESS | 12959 | RSS: Main: 186855424 12:58:16 INFO - PROCESS | 12959 | 12:58:19 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45956/tests/kraken/driver.html?testName=imaging-darkroom) 12:58:20 INFO - PROCESS | 12959 | RSS: Main: 222224384 12:58:20 INFO - PROCESS | 12959 | 12:58:24 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45956/tests/kraken/driver.html?testName=imaging-desaturate) 12:58:24 INFO - PROCESS | 12959 | RSS: Main: 222384128 12:58:24 INFO - PROCESS | 12959 | 12:58:27 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45956/tests/kraken/driver.html?testName=json-parse-financial) 12:58:28 INFO - PROCESS | 12959 | RSS: Main: 222441472 12:58:28 INFO - PROCESS | 12959 | 12:58:29 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45956/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:58:30 INFO - PROCESS | 12959 | RSS: Main: 186671104 12:58:30 INFO - PROCESS | 12959 | 12:58:32 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:58:32 INFO - PROCESS | 12959 | RSS: Main: 192622592 12:58:32 INFO - PROCESS | 12959 | 12:58:35 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:58:35 INFO - PROCESS | 12959 | RSS: Main: 197414912 12:58:35 INFO - PROCESS | 12959 | 12:58:38 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:58:39 INFO - PROCESS | 12959 | RSS: Main: 192483328 12:58:39 INFO - PROCESS | 12959 | 12:58:42 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:58:42 INFO - PROCESS | 12959 | RSS: Main: 194838528 12:58:42 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | Cycle 1(1): loaded http://localhost:45956/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45956/tests/kraken/driver.html?testName=ai-astar) 12:58:44 INFO - PROCESS | 12959 | RSS: Main: 244899840 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | __start_tp_report 12:58:44 INFO - PROCESS | 12959 | _x_x_mozilla_page_load 12:58:44 INFO - PROCESS | 12959 | _x_x_mozilla_page_load_details 12:58:44 INFO - PROCESS | 12959 | |i|pagename|runs| 12:58:44 INFO - PROCESS | 12959 | |0;ai-astar;105;113;114;115;115;114;113;115;115;112 12:58:44 INFO - PROCESS | 12959 | |1;audio-beat-detection;143;122;126;179;122;125;146;132;163;122 12:58:44 INFO - PROCESS | 12959 | |2;audio-dft;159;160;161;162;162;162;161;161;160;162 12:58:44 INFO - PROCESS | 12959 | |3;audio-fft;75;75;75;119;87;77;75;76;100;77 12:58:44 INFO - PROCESS | 12959 | |4;audio-oscillator;86;86;121;85;86;85;130;84;86;132 12:58:44 INFO - PROCESS | 12959 | |5;imaging-gaussian-blur;117;117;116;117;117;119;117;117;117;117 12:58:44 INFO - PROCESS | 12959 | |6;imaging-darkroom;147;148;149;148;148;146;146;146;146;146 12:58:44 INFO - PROCESS | 12959 | |7;imaging-desaturate;94;94;96;95;94;95;95;95;95;95 12:58:44 INFO - PROCESS | 12959 | |8;json-parse-financial;64;62;62;61;62;62;71;61;63;61 12:58:44 INFO - PROCESS | 12959 | |9;json-stringify-tinderbox;66;70;65;65;67;65;64;65;65;66 12:58:44 INFO - PROCESS | 12959 | |10;stanford-crypto-aes;88;79;79;79;79;80;79;98;80;84 12:58:44 INFO - PROCESS | 12959 | |11;stanford-crypto-ccm;137;130;132;140;137;125;128;126;174;139 12:58:44 INFO - PROCESS | 12959 | |12;stanford-crypto-pbkdf2;162;168;164;167;166;163;164;165;166;165 12:58:44 INFO - PROCESS | 12959 | |13;stanford-crypto-sha256-iterative;67;73;66;67;66;67;66;66;66;68 12:58:44 INFO - PROCESS | 12959 | __end_tp_report 12:58:44 INFO - PROCESS | 12959 | __start_cc_report 12:58:44 INFO - PROCESS | 12959 | _x_x_mozilla_cycle_collect,1959 12:58:44 INFO - PROCESS | 12959 | __end_cc_report 12:58:44 INFO - PROCESS | 12959 | __startTimestamp1469217524690__endTimestamp 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | ------- Summary: start ------- 12:58:44 INFO - PROCESS | 12959 | Number of tests: 14 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:3.03 (2.7%) stddev-sans-first:1.12 12:58:44 INFO - PROCESS | 12959 | Values: 105.0 113.0 114.0 115.0 115.0 114.0 113.0 115.0 115.0 112.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:137.50 stddev:19.75 (14.4%) stddev-sans-first:20.87 12:58:44 INFO - PROCESS | 12959 | Values: 143.0 122.0 126.0 179.0 122.0 125.0 146.0 132.0 163.0 122.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.05 (0.7%) stddev-sans-first:0.83 12:58:44 INFO - PROCESS | 12959 | Values: 159.0 160.0 161.0 162.0 162.0 162.0 161.0 161.0 160.0 162.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#3] audio-fft Cycles:10 Average:83.60 Median:75.50 stddev:14.81 (19.6%) stddev-sans-first:15.38 12:58:44 INFO - PROCESS | 12959 | Values: 75.0 75.0 75.0 119.0 87.0 77.0 75.0 76.0 100.0 77.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:85.50 stddev:20.60 (24.1%) stddev-sans-first:21.38 12:58:44 INFO - PROCESS | 12959 | Values: 86.0 86.0 121.0 85.0 86.0 85.0 130.0 84.0 86.0 132.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 12:58:44 INFO - PROCESS | 12959 | Values: 117.0 117.0 116.0 117.0 117.0 119.0 117.0 117.0 117.0 117.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.50 stddev:1.15 (0.8%) stddev-sans-first:1.22 12:58:44 INFO - PROCESS | 12959 | Values: 147.0 148.0 149.0 148.0 148.0 146.0 146.0 146.0 146.0 146.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 12:58:44 INFO - PROCESS | 12959 | Values: 94.0 94.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:62.00 stddev:3.00 (4.8%) stddev-sans-first:3.15 12:58:44 INFO - PROCESS | 12959 | Values: 64.0 62.0 62.0 61.0 62.0 62.0 71.0 61.0 63.0 61.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:65.50 stddev:1.69 (2.6%) stddev-sans-first:1.79 12:58:44 INFO - PROCESS | 12959 | Values: 66.0 70.0 65.0 65.0 67.0 65.0 64.0 65.0 65.0 66.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:80.00 stddev:6.20 (7.8%) stddev-sans-first:6.25 12:58:44 INFO - PROCESS | 12959 | Values: 88.0 79.0 79.0 79.0 79.0 80.0 79.0 98.0 80.0 84.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:137.00 stddev:14.15 (10.3%) stddev-sans-first:15.01 12:58:44 INFO - PROCESS | 12959 | Values: 137.0 130.0 132.0 140.0 137.0 125.0 128.0 126.0 174.0 139.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.50 stddev:1.83 (1.1%) stddev-sans-first:1.58 12:58:44 INFO - PROCESS | 12959 | Values: 162.0 168.0 164.0 167.0 166.0 163.0 164.0 165.0 166.0 165.0 12:58:44 INFO - PROCESS | 12959 | 12:58:44 INFO - PROCESS | 12959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:2.15 (3.2%) stddev-sans-first:2.28 12:58:44 INFO - PROCESS | 12959 | Values: 67.0 73.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 68.0 12:58:44 INFO - PROCESS | 12959 | -------- Summary: end -------- 12:58:44 INFO - PROCESS | 12959 | 12:58:45 INFO - TEST-INFO | 12959: exit 0 12:58:45 INFO - TEST-OK | kraken | took 63048ms 12:58:45 INFO - SUITE-END | took 1182s 12:58:45 INFO - Completed test suite (00:19:42) 12:58:45 INFO - javascript benchmark 12:58:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2547.3122256990614, 2558.398776287571, 2590.676165479315, 2597.363645906752, 2599.0903183885644, 4559.521250268724, 4602.240630295997, 4653.441587009555, 4669.813207471706, 4697.5302469753005, 4571.040081582863, 4628.953710462894, 4638.675292729502, 4657.813687452506, 4713.10451014307, 4222.113356195203, 4231.513375961773, 4296.591823776736, 4351.73889566627, 4449.3548435477, 4357.2592659248, 4393.187260356828, 4402.185595664796, 4410.867673969786, 4424.823007079713, 4175.582441755831, 4179.707420480568, 4182.059036716714, 4188.979055104705, 4317.913641727164, 4221.598948099926, 4240.915181696349, 4270.274053410928, 4337.219300525904, 4352.7605981670995, 4278.850240241608, 4287.0, 4292.849750258757, 4324.610785029331, 4325.091730736549, 4561.794719237642, 4638.170715863321, 4704.1062198182235, 4733.431988161443, 4734.313524538922, 4943.258511223309, 4989.65072444929, 4995.975020124913, 5000.0999820032375, 5051.393832740058, 5014.598832093424, 5034.697918124888, 5062.139436295838, 5062.468440813698, 5068.467810879842, 4497.415336006364, 4604.263317869192, 4712.128256272566, 4717.646176536773, 4722.913729842156, 5068.16375298079, 5082.440931497532, 5133.79464821403, 5148.33071700684, 5160.92258616121, 4903.338049363361, 4943.209086546135, 4952.059108769322, 4953.18272485036, 4957.950420495821, 4856.417229932431, 4864.73243971585, 5026.673266237683, 5036.169032109734, 5045.8738531537, 4853.733044682576, 4881.267809828483, 4887.902241955212, 4920.261960705851, 4925.261210818334, 4852.369192005017, 4857.319975203474, 4858.368412106403, 4866.8783280417565, 4912.189488734319, 4141.399497072907, 4195.958040419557, 4232.8518501852905, 4238.703290769617, 4284.785760711952, 3671.2657468506404, 3683.594804571495, 3695.4641576971726, 3711.6102809205186, 3791.0, 4236.597523235288, 4248.0, 4252.48970123581, 4267.487901451784, 4292.12011537634, 4830.9275360869615, 4945.4312754033745, 4950.826721064817, 4953.430355509163, 4987.775550100182, 4198.9370159447635, 4238.491381034296, 4275.679324050647, 4289.313709806416, 4322.243607368698, 4535.659825513099, 4544.681872268976, 4544.954550454519, 4552.863414097516, 4596.057808149321, 4713.198756211452, 4725.787339569659, 4742.739149346819, 4753.239481682914, 4769.42766867978, 4873.756312184448, 4890.2909078184175, 4921.31101645777, 4928.802847886044, 4967.925481117858, 4735.621150308036, 4740.0756852414825, 4744.169770290255, 4757.476677565534, 4783.923617186106, 4888.193448081027, 4909.1654418748185, 4912.82805101834, 4921.434035086011, 4948.579370753526, 4799.736014519235, 4852.102361063215, 4897.094037603055, 4915.508449155056, 4945.67853089548, 4746.833860814855, 4755.286706993978, 4756.381670382828, 4787.42550893895, 4795.664303498722, 4736.0, 4744.596709279612, 4750.12122757291, 4752.453467851241, 4797.280407938837, 4609.377734005866, 4651.604613607746, 4681.063787242633, 4696.365990591226, 4711.882202944954, 4806.711597304173, 4835.226363781778, 4840.273958906051, 4857.562819346276, 4874.610031197425, 4699.812007519661, 4710.64670149747, 4724.598409135262, 4732.100900828969, 4776.379070720923], "value": 4558.74080061838, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.2232824522772, 582.2256398989344, 585.0668184246126, 587.1222522329118, 590.3594599859152, 27000.86499567502, 39820.60179398205, 41331.17337653249, 41371.79314103433, 45175.548244517544, 40767.59232407675, 41221.17557648845, 41224.17551648965, 41273.38089928654, 41274.38088428676, 7314.670839812221, 7540.585267810282, 7708.922910770877, 7711.460197786129, 7719.72980945667, 9645.228381729445, 9945.751356216117, 9973.601055957753, 10275.383476991366, 10294.536745846417, 9949.403035817873, 10045.598176072948, 10052.648157314496, 10053.748656283578, 10084.14284785794, 10035.949820250926, 10055.446950417723, 10073.0, 10104.292699511037, 10118.139958103497, 9932.851007234898, 10015.899841001643, 10023.047810457994, 10029.0, 10029.498525073717, 39081.0, 39391.40912886309, 39413.80293098516, 39439.80280098581, 39527.60472395268, 2918.255844759594, 3118.4075025745037, 3307.96692033079, 3356.228067544454, 3774.905627359338, 27441.588376174175, 29931.40137197244, 30280.8485957571, 30302.0, 30348.696513034807, 3516.982415087934, 3535.8232088395735, 3604.891853244381, 3605.603383627799, 3725.4784330193793, 271849.0, 273084.0, 273719.0, 274062.6296868523, 274121.0, 29031.27421814429, 54982.45017549853, 55149.72425137849, 55163.44836551663, 55253.72373138189, 31286.374272514422, 33125.83437082799, 33155.83422082874, 33191.17022074467, 33200.16999574981, 13830.930845345709, 13971.441142354395, 13975.161490310615, 13991.790123148161, 14019.50931717385, 10935.507902144263, 11076.224664273584, 11079.612213572485, 11385.715357115972, 11639.06887449002, 7819.452638315263, 7847.097583777824, 7953.443258971932, 7974.681012759424, 7979.521228726295, 2930.8388038657654, 3042.4827779277575, 3174.4286028514625, 3175.428422883857, 3199.664035276309, 775.4649291988623, 892.9821403571892, 959.3332633819429, 965.4400447740294, 994.3139233928566, 28025.159245222676, 28530.28674283118, 28662.0, 29014.41971160565, 29045.564316534845, 912.1653686876566, 936.2509992006368, 947.8104379124203, 982.543117450389, 1016.598443614768, 13806.447742090404, 14528.564143075724, 14550.781738273934, 14686.339114739862, 14768.852311476963, 404682.0, 408223.0, 408562.0, 408762.0, 409417.0, 43529.78235108804, 48448.51551484511, 49028.75485622549, 49072.0, 49139.52581422563, 1166.8133098704166, 1218.4273391506156, 1227.1042139238584, 1234.512367614805, 1234.8703386144505, 27483.587746183424, 28630.14109576716, 28979.130626081253, 29111.0, 29117.416663756427, 42615.5738442624, 43248.351274730274, 43296.78351608222, 43313.35029974616, 43342.783286083366, 358917.0, 364149.0, 429322.0, 439792.0, 440938.0, 39110.804445977585, 39257.607423925394, 39529.80235098806, 39563.20873582455, 40047.599524004385, 40379.0, 40558.39162412625, 42800.57199427966, 43067.0, 43450.13099738051, 36918.44622330721, 37043.0, 37177.81411092927, 37734.0, 38955.805220973714, 37275.62724372721, 37350.25299493941, 37669.0, 37806.81096594499, 37825.81087094657, 35841.64158358383, 36015.27969440544, 36494.45258321074, 37623.0, 37670.81164594269, 29289.707102928696, 30668.539971899994, 31419.685803141678, 31531.527027094155, 31637.525437118922, 24215.75784242135, 24500.75499244985, 24616.507669846862, 24965.625515617645, 25028.874855625607], "value": 17438.309097091493, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.90171850163455, 357.45309676195416, 361.94208926571747, 369.2596344329619, 376.0954903457185, 12757.234565926039, 13643.0, 14011.649708757273, 17038.48884533463, 17056.488305350827, 16341.18294085294, 16616.83383166171, 17266.395676151336, 17281.82718172818, 18026.81973180268, 4455.955440445603, 4793.9280910786365, 4800.399950006249, 4800.447948485934, 4801.1117943180625, 3535.4166562517153, 5597.552195824344, 5826.0, 5893.263342082239, 5971.37300583439, 5676.460736230051, 5929.644221346712, 5932.525397968174, 5934.910976335358, 5942.0, 5784.884302313952, 5843.590948633619, 5857.179994800731, 5897.3218079920925, 5903.763849446017, 5138.614603904722, 5178.948210517922, 5247.370315562157, 5253.08071087562, 5298.867528311823, 12701.364931753467, 13625.182489050689, 17473.912630436767, 17484.562885927826, 17568.38510652121, 1780.9643807123784, 1879.36101725414, 1907.5898681783483, 2078.6985887046294, 2283.7944584987263, 8974.50640214788, 9071.727848164566, 9348.719538413858, 9545.665901693408, 9749.220062394992, 1701.7192163293041, 1709.273558737544, 1717.3645751072183, 1735.5921358480748, 1768.142450911307, 68414.0, 71954.0, 73187.26812731857, 75634.24365756327, 77509.0, 10180.847287290697, 10213.897861021444, 10456.268061235793, 10474.0, 10548.261621686408, 14161.645958851112, 14165.71668566623, 14169.574912752634, 14180.14919104857, 14186.290685465685, 7603.847923041619, 7708.956398551786, 7752.7674169775, 7756.844863102707, 7896.881546776803, 7501.512401693873, 7535.321821036133, 7674.079110506775, 7725.884111738328, 7736.497127686683, 4319.524852266248, 4394.384786129945, 4394.516603173648, 4471.418715566955, 4516.9096618068115, 1796.1917137288272, 1804.3594523944073, 1817.790954040276, 1843.2074208090382, 1847.9538011549819, 770.2028400605346, 808.5148910653561, 868.7306934850217, 877.4033657113224, 877.7630039889322, 16705.498835034967, 17121.657566848593, 17821.7326740099, 17836.19737111833, 18099.366522171924, 854.1543871567104, 855.3157474020759, 864.7059999600041, 922.5341202692597, 957.6265256549951, 9442.527873606292, 9707.9029209707, 10158.898411015944, 10766.676999690022, 10853.674389768319, 403073.0, 404160.0, 404932.0, 405314.0, 407569.0, 82645.58677206576, 83265.16734832696, 83768.0, 84323.57838210769, 87860.0, 898.955052247398, 913.2785099771239, 934.9532523373804, 937.7374335186158, 937.8452555328431, 15652.608684782972, 15808.841911580737, 16244.269007894436, 16329.755053673967, 16644.583885402477, 39735.0, 39825.601743983345, 39865.80067099646, 39874.2025159501, 40375.39436908507, 50291.24563131629, 51024.0, 54891.45108549023, 55380.16929745976, 56028.71985640046, 7791.493552919269, 7812.257835505618, 7821.96089019574, 7855.56794376292, 7859.685612575433, 9487.952560237154, 11878.346690932081, 12002.939985300367, 12029.398530073286, 12104.636860894187, 9909.950450247703, 9974.60101595928, 10273.589056437957, 10354.068133867988, 10893.400862952614, 10031.54858031405, 10145.137663298407, 10286.794264114826, 10304.072633463024, 10426.843597345895, 9559.808803823746, 10206.438645874548, 10214.336068155546, 10285.228607854291, 10436.112930401001, 7506.587137707478, 7618.123915749538, 7661.808454788452, 7722.53664780115, 7795.883061754192, 8956.149165829236, 9554.378965367228, 9791.0128599854, 10189.796204075728, 10257.384556926609], "value": 8285.141498570329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.835571375721, 1363.0731102850064, 1409.21084192852, 1431.7780743984683, 1567.6551158745078, 6450.096986421899, 7361.668724907378, 7578.204288549706, 7594.392448604105, 7811.648475818588, 7629.580373079479, 7670.539767613947, 7674.884876726854, 7675.6545955432, 7681.3086822186, 2134.4770531219797, 2165.783421657837, 2168.793964573363, 2169.6745488176743, 2169.8481106322565, 2099.3177217404327, 2140.625390556654, 2164.3290579920276, 2165.17723265159, 2166.3609235275553, 2118.5339225370394, 2128.88291143987, 2136.957260854782, 2139.850210485267, 2162.859414138084, 2078.0, 2083.5832833433374, 2086.3010891351414, 2088.759792623845, 2089.7492300923986, 2026.311054241565, 2085.676720108386, 2086.050846864673, 2086.0821238654944, 2089.352300786761, 7655.272749088827, 7698.384129269645, 7712.0, 7716.228377162295, 7726.575038372887, 4430.645548356124, 4445.355423463579, 4562.1560011398, 4567.33773602829, 4587.243104887723, 2851.70057144001, 2897.652281726206, 2947.2484516448385, 2952.1881482592244, 2957.645082590103, 933.682547933699, 936.2463217110223, 945.2579724915907, 952.7094236258008, 955.2214944819949, 10454.32046916948, 10549.9472502637, 10564.683059508227, 10576.682699519026, 10584.788304233873, 3972.6027397260623, 4050.7569545826755, 4051.331530297468, 4060.8578699745367, 4221.0, 8187.631556579968, 8373.288270496954, 8376.832463350698, 8393.24460798531, 8397.244248017707, 4516.254817955, 4545.454545454567, 4548.4541854977615, 4548.6361091113295, 4549.09018196362, 3690.4279836625365, 4121.093359460914, 4250.808713607895, 4251.936220956688, 4253.064325848309, 3336.132605522533, 3388.508666243381, 3481.7736847104857, 3491.389006923778, 3500.982495087559, 997.6857289953641, 997.8004399120205, 998.2912132385942, 1008.5864795433837, 1016.3089099412471, 655.0730716036754, 703.9366457018892, 738.0700317599863, 811.1604489353491, 846.4455781463151, 5112.769925353368, 5141.280220769096, 5153.123968925292, 5292.5501332387175, 5465.508104270635, 837.2172019161981, 869.3783944479727, 873.2839071960931, 909.3543584055262, 944.9244060475284, 3529.5940966788635, 3653.908652283714, 3742.8877133686033, 3750.1374683822874, 3756.7745935243975, 9023.548822558845, 9244.537773111451, 9338.953305233566, 9445.716628501155, 9545.522723863918, 9360.391574547633, 9428.01005894385, 9430.3398762086, 9477.573509192103, 9487.193588545051, 6717.261101278856, 6871.0, 6908.585484870924, 7070.787876363717, 7127.643617819088, 4722.811087556459, 4863.294822250825, 4894.0, 5074.68058304836, 5112.054269960069, 8600.09698981598, 8623.267022303175, 8695.21743043104, 8777.078406767198, 8820.603072861608, 8685.913140868763, 8795.604197810986, 8867.113288671082, 8958.641654333753, 8974.775630609287, 5333.173358129496, 5809.535237180931, 6261.217347831521, 6354.459870911024, 6355.523335749745, 7032.1912980007955, 7156.749513766993, 7215.8556828864175, 7229.783106506813, 7241.782746517613, 6317.4630156437215, 6393.904091438725, 6437.742490300336, 6495.9025614614875, 6624.436922861611, 6345.841353966188, 6363.5545511813725, 6424.550281480251, 6426.550141490051, 6432.871342573028, 5765.048766953406, 5850.414958504116, 5852.882942341214, 5856.150858125633, 5856.677882716492, 4462.308342206963, 4492.191405547033, 4498.3252512122135, 4538.818447262072, 4599.0342028174455, 4460.085682435158, 4475.7985890635655, 4481.081378317503, 4481.529439408945, 4497.572730590588], "value": 4001.0928817226713, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.34033340981875, 280.2349585631225, 287.44379625424807, 289.7985899799639, 295.39001960950634, 4816.446108697501, 4837.177679794435, 4848.878778030551, 4855.9802441487245, 4859.781309841057, 4719.7256740679995, 4796.040791841629, 4803.150786947024, 4806.591439727627, 4812.350332705084, 3575.8569657213743, 3666.2850744104912, 3714.4799728038097, 3777.4333849922596, 3806.4861243732157, 3772.0569857535615, 3784.735068545203, 3808.1050953026015, 3825.158465137665, 3831.7700937943814, 3739.7943113128767, 3744.9625503745024, 3754.4180651998986, 3769.736118471708, 3774.0753515388633, 3584.3010612930357, 3644.562652481719, 3721.274351501458, 3772.7359084863797, 3791.981040094796, 3303.966960330414, 3318.551995480603, 3320.8007519548664, 3324.3683700096904, 3334.4664853623303, 4670.673052886299, 4671.743054132022, 4681.344611754357, 4686.5782079613, 4697.67116301859, 4172.4784401949755, 4288.957110428887, 4289.1421715657, 4290.678199135046, 4296.849610263625, 4946.431160416526, 4960.156773348539, 4987.354173122861, 4995.775190116417, 5017.974910125463, 3652.7077833773233, 3658.432942893856, 3661.1945372018113, 3663.0, 3664.560252769658, 5042.6470147090085, 5251.107311756972, 5251.186066159751, 5254.947450525484, 5267.604929630293, 3670.0, 3681.2942487271835, 3705.8517659293866, 3721.5906250312446, 3729.701623870084, 3714.832832522543, 3724.5902950675404, 3730.6756101584087, 3745.4756334113245, 3769.4345848122452, 4240.469941257343, 4267.338562522814, 4273.952881544042, 4274.273373526508, 4294.506131794821, 4831.589314908202, 4938.728369939688, 4976.527229913152, 4982.3522942018035, 4995.450500444948, 1254.8894228607749, 1264.2225031605496, 1267.0370518406078, 1267.575362253637, 1273.490603758504, 816.506013861617, 828.1263267252986, 842.9789255268668, 853.5774791478134, 861.642418396371, 4297.140571885635, 4390.7047421010875, 4398.362237475548, 4407.294832826732, 4424.610710216447, 5131.384233891957, 5131.692098474104, 5136.640435169577, 5145.485451454824, 5178.766955487012, 4367.978160109179, 4384.736915784999, 4389.824407023747, 4409.294512877924, 4411.536788637211, 2580.548404029287, 2717.768989635903, 2723.891044358243, 2734.8222365546176, 2734.8495832728995, 4882.218844984785, 4888.071266459361, 4891.706497610155, 4892.0, 4894.3147959285725, 4996.275540046673, 5207.323048003811, 5263.236830659533, 5273.815416460406, 5278.604104692164, 4903.289023091632, 4921.9507804921495, 4927.87680307988, 4948.0, 4949.65352425334, 5187.533122019036, 5201.6098792591465, 5207.505286997729, 5258.6581872178185, 5265.919961615064, 4866.197077482176, 5064.7467626619255, 5124.615653825904, 5166.741662916915, 5168.0, 4911.459739428588, 4919.311296418433, 4930.235813448921, 4931.284963680175, 4937.8271760488215, 3634.2368102697674, 3671.963280367162, 3676.3382591133854, 3684.3368193725387, 3687.1150923778637, 5093.261477085876, 5171.81898633546, 5203.167493201221, 5251.973740131275, 5263.710495922761, 4931.802727890844, 4959.97520012412, 4961.578265847444, 4963.436517496977, 5011.148104822262, 4949.579285760607, 4953.23224900141, 4978.4274808397495, 5033.949660503348, 5058.140116180185, 4763.809447622057, 4873.1959226727195, 4945.876353091202, 4952.281919121635, 4960.131976904099, 4782.33047373361, 4872.6832755870755, 4999.400431689178, 5000.724960127082, 5006.674566153189, 4880.145974454527, 4897.485763994871, 4907.362042934538, 4909.9018019640125, 4971.1797553403285], "value": 3782.400036063636, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.7251736718478, 623.9656818874963, 641.5797652537584, 689.9793006209813, 712.1169749510608, 12142.575009874645, 13410.0, 13412.93293533531, 13451.125676831012, 13464.057515973886, 12533.68665783356, 12703.809442858364, 12704.491820327175, 12712.23726576406, 12764.361781910891, 625.1997443272618, 644.4683136412455, 749.2357795049047, 751.5265382808823, 767.2289349204042, 756.8940348351235, 773.5590713293425, 776.1539921485579, 776.7048521561799, 777.4169372970272, 822.8477731619639, 823.5470491229829, 823.6046697585133, 823.979400514986, 824.295227580416, 643.8519140597689, 786.7836345005112, 806.1213277527464, 815.4332738746543, 817.3992115794886, 597.4264705882315, 597.9910301345483, 598.0819442156269, 598.0998597111363, 598.7994022002591, 12437.191582547106, 12468.189567678071, 12478.75042499145, 12482.875171248354, 12496.062795290298, 11950.282983021047, 12016.399180041051, 12096.092793040558, 12109.0, 12659.556915507914, 11682.532698692043, 11723.120765942589, 11770.882291177064, 11841.348725820077, 11898.762024759542, 3264.412405766961, 3387.3225354929114, 4050.392441133824, 4081.346984482498, 4186.790660466995, 48448.0, 49046.50953490455, 49087.509124908654, 53544.46455535434, 56688.433115668726, 10336.17310615149, 10501.474926253657, 10505.82304884399, 10622.468876556295, 10742.946285268523, 18167.636647267245, 18289.542761431072, 18343.81656183421, 18419.81580184181, 18446.538836528925, 6753.763618273435, 6880.518363714592, 7226.566406015656, 7239.239879812649, 7252.854942901113, 3730.1793605406765, 3769.076576238839, 3795.221979494252, 3984.143409167043, 3987.521497420328, 2831.0, 2895.811772234798, 2912.7815413844046, 2923.9122826315242, 2926.941461170765, 491.09148076059006, 501.1680610187072, 507.2467385931888, 509.1471784760511, 512.8487096306599, 541.9458054194628, 635.9141515895387, 637.8118455055769, 643.8036398898288, 650.2879347114902, 7399.4450416219, 7744.0, 7835.177306382861, 7875.212478752193, 7879.369650427952, 507.71567921963816, 530.7081105392019, 562.9971850140723, 633.1136409027306, 642.1651852591613, 3610.4223324268514, 3720.4605332226674, 3808.1622043150464, 3833.961660383416, 3979.840806367713, 56923.71538142283, 91265.80569680162, 91616.0, 93112.53443732738, 93122.0, 80966.0, 81390.59304703439, 81848.13233883855, 82144.58927705324, 82511.0, 68432.31567684359, 69898.3010169902, 73946.52106957938, 74921.62539187269, 77566.61216693993, 6524.380183882524, 6851.862962740617, 7404.333609975128, 7627.54234745917, 7795.0, 44391.334129988725, 44738.139954267324, 45478.77260613676, 45710.77144614256, 47006.529934700215, 36378.0, 53048.73475632597, 61156.38843611507, 61612.69193654003, 62406.37593624187, 10109.949450252701, 10806.18953578469, 10864.45677716127, 10904.127669786556, 10985.670429887115, 10865.967733065347, 11098.334099953705, 11135.443227838738, 11624.709382265512, 11657.0, 9090.272778177598, 9216.447013179231, 9253.953730231306, 9494.85757713621, 10072.244581656258, 9746.707598772047, 9843.753906152404, 9936.801263974825, 10020.398776073458, 10147.746306342106, 9334.673286434941, 9428.481433520948, 9430.245580353692, 9474.57364418589, 9560.23518118535, 8344.290735287568, 8811.383203175716, 9161.725148245563, 9192.126747958933, 9280.0, 8307.875381869157, 8452.281556067803, 8529.488230706178, 9043.819123617623, 9201.723948281295], "value": 6268.618204597373, "unit": "score"}], "name": "dromaeo_css", "value": 6299.456968672115, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 114.0, 115.0, 115.0, 114.0, 113.0, 115.0, 115.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 122.0, 126.0, 179.0, 122.0, 125.0, 146.0, 132.0, 163.0, 122.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 162.0, 162.0, 162.0, 161.0, 161.0, 160.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 119.0, 87.0, 77.0, 75.0, 76.0, 100.0, 77.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 121.0, 85.0, 86.0, 85.0, 130.0, 84.0, 86.0, 132.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 149.0, 148.0, 148.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 61.0, 62.0, 62.0, 71.0, 61.0, 63.0, 61.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 65.0, 65.0, 67.0, 65.0, 64.0, 65.0, 65.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 79.0, 79.0, 79.0, 79.0, 80.0, 79.0, 98.0, 80.0, 84.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 130.0, 132.0, 140.0, 137.0, 125.0, 128.0, 126.0, 174.0, 139.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 168.0, 164.0, 167.0, 166.0, 163.0, 164.0, 165.0, 166.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 68.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1532.9, "alertThreshold": 2.0}]} 12:58:45 INFO - Return code: 0 12:58:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:58:45 INFO - # TBPL SUCCESS # 12:58:45 INFO - Running post-action listener: _resource_record_post_action 12:58:45 INFO - [mozharness: 2016-07-22 19:58:45.979813Z] Finished run-tests step (success) 12:58:45 INFO - Running post-run listener: _resource_record_post_run 12:58:46 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 49152; Write bytes: 536530944; Read time: 240; Write time: 393488 12:58:46 INFO - TinderboxPrint: CPU usage
13.0% 12:58:46 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 240 12:58:46 INFO - TinderboxPrint: I/O write bytes / time
536,530,944 / 393,488 12:58:46 INFO - TinderboxPrint: CPU idle
8,329.4 (87.0%) 12:58:46 INFO - TinderboxPrint: CPU user
1,199.1 (12.5%) 12:58:46 INFO - TinderboxPrint: Swap in / out
0 / 0 12:58:46 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 32768; Write bytes: 247128064; Read time: 188; Write time: 235280 12:58:46 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 12288; Write bytes: 289402880; Read time: 44; Write time: 158208 12:58:46 INFO - Running post-run listener: _upload_blobber_files 12:58:46 INFO - Blob upload gear active. 12:58:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:58:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:58:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:58:47 INFO - (blobuploader) - INFO - Open directory for files ... 12:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:58:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:47 INFO - (blobuploader) - INFO - Done attempting. 12:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:58:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:56 INFO - (blobuploader) - INFO - Done attempting. 12:58:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:58:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:58:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:57 INFO - (blobuploader) - INFO - Done attempting. 12:58:57 INFO - (blobuploader) - INFO - Iteration through files over. 12:58:57 INFO - Return code: 0 12:58:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:58:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:58:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4da8d87f863ed8ee665cf8d0e9d15d0733cedae7c2f7040ff076fc3f7d186e0e241192a5c5309df46569602e50651c07710377e53bcf11dbf366826a0d124880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e9914850d210f569d5abdad3d7308d774066e567a995b116dd5f1fb3101a6529d9150fef1c9b5ec59743c0dae231386f0295055c94470af54abc61396af4e3a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f89d58aefe2d634260098c451bdc69a6c464e591cf88d6bcb070204da3b367ea67cf70bb863f0f65eaa24718f2d63b218e7057e10493792a1bd67d39b81447ba"} 12:58:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:58:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:58:57 INFO - Contents: 12:58:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4da8d87f863ed8ee665cf8d0e9d15d0733cedae7c2f7040ff076fc3f7d186e0e241192a5c5309df46569602e50651c07710377e53bcf11dbf366826a0d124880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e9914850d210f569d5abdad3d7308d774066e567a995b116dd5f1fb3101a6529d9150fef1c9b5ec59743c0dae231386f0295055c94470af54abc61396af4e3a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f89d58aefe2d634260098c451bdc69a6c464e591cf88d6bcb070204da3b367ea67cf70bb863f0f65eaa24718f2d63b218e7057e10493792a1bd67d39b81447ba"} 12:58:57 INFO - Running post-run listener: copy_logs_to_upload_dir 12:58:57 INFO - Copying logs to upload dir... 12:58:57 INFO - mkdir: /builds/slave/test/build/upload/logs 12:58:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.434261 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-22 12:58:57.426796) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:58:57.431018) ========= 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=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4da8d87f863ed8ee665cf8d0e9d15d0733cedae7c2f7040ff076fc3f7d186e0e241192a5c5309df46569602e50651c07710377e53bcf11dbf366826a0d124880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e9914850d210f569d5abdad3d7308d774066e567a995b116dd5f1fb3101a6529d9150fef1c9b5ec59743c0dae231386f0295055c94470af54abc61396af4e3a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f89d58aefe2d634260098c451bdc69a6c464e591cf88d6bcb070204da3b367ea67cf70bb863f0f65eaa24718f2d63b218e7057e10493792a1bd67d39b81447ba"} build_url:https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/JFTClu6ESr-gYcb2tXlBYw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4da8d87f863ed8ee665cf8d0e9d15d0733cedae7c2f7040ff076fc3f7d186e0e241192a5c5309df46569602e50651c07710377e53bcf11dbf366826a0d124880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e9914850d210f569d5abdad3d7308d774066e567a995b116dd5f1fb3101a6529d9150fef1c9b5ec59743c0dae231386f0295055c94470af54abc61396af4e3a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f89d58aefe2d634260098c451bdc69a6c464e591cf88d6bcb070204da3b367ea67cf70bb863f0f65eaa24718f2d63b218e7057e10493792a1bd67d39b81447ba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:58:57.463759) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:58:57.464314) ========= 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=923d835db2ba3686ebe126b700000192-1469215889.165330-1752752141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:58:57.548948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:58:57.549293) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:58:57.549647) ========= ========= Total master_lag: 0.18 =========