builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1470366533.0 results: success (0) buildid: 20160804173002 builduid: b98337d5f41e4cd7ae1712513204fe83 revision: fff9d624dca283235c916d8769786e04f5622282 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.005698) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.006155) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.006457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.022651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.022999) ========= 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=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-04 20:08:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.6M=0s 2016-08-04 20:08:53 (89.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089302 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.126448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.126769) ========= 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=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015989 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:53.180046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 20:08:53.180451) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fff9d624dca283235c916d8769786e04f5622282 --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 fff9d624dca283235c916d8769786e04f5622282 --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=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-04 20:08:53,291 truncating revision to first 12 chars 2016-08-04 20:08:53,291 Setting DEBUG logging. 2016-08-04 20:08:53,291 attempt 1/10 2016-08-04 20:08:53,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fff9d624dca2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 20:08:54,312 unpacking tar archive at: fx-team-fff9d624dca2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 20:08:54.532792) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:54.533128) ========= script_repo_revision: fff9d624dca283235c916d8769786e04f5622282 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:54.533563) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:54.533840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 20:08:54.545158) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-04 20:08:54.545589) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:08:54 INFO - MultiFileLogger online at 20160804 20:08:54 in /builds/slave/test 20:08:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:08:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:54 INFO - {'append_to_log': False, 20:08:54 INFO - 'base_work_dir': '/builds/slave/test', 20:08:54 INFO - 'blob_upload_branch': 'Fx-Team', 20:08:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:54 INFO - 'branch': 'Fx-Team', 20:08:54 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:54 INFO - 'config_files': ('talos/linux_config.py',), 20:08:54 INFO - 'default_actions': ('clobber', 20:08:54 INFO - 'read-buildbot-config', 20:08:54 INFO - 'download-and-extract', 20:08:54 INFO - 'populate-webroot', 20:08:54 INFO - 'create-virtualenv', 20:08:54 INFO - 'install', 20:08:54 INFO - 'run-tests'), 20:08:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:54 INFO - 'download_minidump_stackwalk': True, 20:08:54 INFO - 'download_symbols': 'ondemand', 20:08:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:54 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:54 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:54 INFO - 'installer_path': 'installer.exe', 20:08:54 INFO - 'log_level': 'info', 20:08:54 INFO - 'log_name': 'talos', 20:08:54 INFO - 'log_to_console': True, 20:08:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:08:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:08:54 INFO - 'opt_config_files': (), 20:08:54 INFO - 'pip_index': False, 20:08:54 INFO - 'sps_profile': False, 20:08:54 INFO - 'sps_profile_interval': 0, 20:08:54 INFO - 'suite': 'dromaeojs', 20:08:54 INFO - 'system_bits': '32', 20:08:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:54 INFO - 'title': 'talos-linux64-ix-053', 20:08:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:54 INFO - 'use_talos_json': True, 20:08:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:54 INFO - 'work_dir': 'build'} 20:08:54 INFO - [mozharness: 2016-08-05 03:08:54.746530Z] Running clobber step. 20:08:54 INFO - Running pre-action listener: _resource_record_pre_action 20:08:54 INFO - Running main action method: clobber 20:08:54 INFO - rmtree: /builds/slave/test/build 20:08:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:55 INFO - Running post-action listener: _resource_record_post_action 20:08:55 INFO - [mozharness: 2016-08-05 03:08:55.188424Z] Finished clobber step (success) 20:08:55 INFO - [mozharness: 2016-08-05 03:08:55.188524Z] Running read-buildbot-config step. 20:08:55 INFO - Running pre-action listener: _resource_record_pre_action 20:08:55 INFO - Running main action method: read_buildbot_config 20:08:55 INFO - Using buildbot properties: 20:08:55 INFO - { 20:08:55 INFO - "project": "", 20:08:55 INFO - "product": "firefox", 20:08:55 INFO - "script_repo_revision": "production", 20:08:55 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:08:55 INFO - "repository": "", 20:08:55 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:08:55 INFO - "buildid": "20160804173002", 20:08:55 INFO - "pgo_build": "True", 20:08:55 INFO - "basedir": "/builds/slave/test", 20:08:55 INFO - "buildnumber": 1421, 20:08:55 INFO - "slavename": "talos-linux64-ix-053", 20:08:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:08:55 INFO - "platform": "ubuntu64_hw", 20:08:55 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:08:55 INFO - "branch": "fx-team", 20:08:55 INFO - "repo_path": "integration/fx-team", 20:08:55 INFO - "revision": "fff9d624dca283235c916d8769786e04f5622282", 20:08:55 INFO - "stage_platform": "linux64-pgo", 20:08:55 INFO - "builduid": "b98337d5f41e4cd7ae1712513204fe83", 20:08:55 INFO - "slavebuilddir": "test" 20:08:55 INFO - } 20:08:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:08:55 INFO - Running post-action listener: _resource_record_post_action 20:08:55 INFO - [mozharness: 2016-08-05 03:08:55.190995Z] Finished read-buildbot-config step (success) 20:08:55 INFO - [mozharness: 2016-08-05 03:08:55.191084Z] Running download-and-extract step. 20:08:55 INFO - Running pre-action listener: _resource_record_pre_action 20:08:55 INFO - Running main action method: download_and_extract 20:08:55 INFO - mkdir: /builds/slave/test/build/tests 20:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:55 INFO - trying https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:08:55 INFO - Downloaded 1361 bytes. 20:08:55 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:55 INFO - Using the following test package requirements: 20:08:55 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:08:55 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:08:55 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:08:55 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'jsshell-linux-x86_64.zip'], 20:08:55 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:08:55 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:08:55 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:08:55 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:08:55 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:08:55 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:55 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:08:55 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:55 INFO - trying https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:08:57 INFO - Downloaded 21820898 bytes. 20:08:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:08:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:08:58 INFO - Return code: 0 20:08:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:58 INFO - trying https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:08:59 INFO - Downloaded 21820898 bytes. 20:08:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:08:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:09:01 INFO - Return code: 0 20:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:01 INFO - trying https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:09:01 INFO - Downloading https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:09:02 INFO - Downloaded 14413723 bytes. 20:09:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:09:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:09:02 INFO - Return code: 0 20:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:02 INFO - trying https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:02 INFO - Downloading https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:09:04 INFO - Downloaded 59363080 bytes. 20:09:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:04 INFO - mkdir: /builds/slave/test/properties 20:09:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:09:04 INFO - Writing to file /builds/slave/test/properties/build_url 20:09:04 INFO - Contents: 20:09:04 INFO - build_url:https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:04 INFO - Running post-action listener: _resource_record_post_action 20:09:04 INFO - Running post-action listener: set_extra_try_arguments 20:09:04 INFO - [mozharness: 2016-08-05 03:09:04.984797Z] Finished download-and-extract step (success) 20:09:04 INFO - [mozharness: 2016-08-05 03:09:04.985032Z] Running populate-webroot step. 20:09:04 INFO - Running pre-action listener: _resource_record_pre_action 20:09:04 INFO - Running main action method: populate_webroot 20:09:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:09:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:09:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:09:04 INFO - '--tppagecycles', 20:09:04 INFO - '7'], 20:09:04 INFO - 'tests': ['tsvgm']}}, 20:09:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:09:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:09:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:09:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:09:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:04 INFO - 'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:04 INFO - 'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['damp', 'tps']}, 20:09:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:04 INFO - 'tests': ['damp', 'tps']}, 20:09:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['dromaeo_dom']}, 20:09:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:09:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['basic_compositor_video']}, 20:09:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:09:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:09:04 INFO - 'tests': ['a11yr', 20:09:04 INFO - 'ts_paint', 20:09:04 INFO - 'tpaint', 20:09:04 INFO - 'sessionrestore', 20:09:04 INFO - 'sessionrestore_no_auto_restore', 20:09:04 INFO - 'tabpaint']}, 20:09:04 INFO - 'other-e10s': {'tests': ['a11yr', 20:09:04 INFO - 'ts_paint', 20:09:04 INFO - 'tpaint', 20:09:04 INFO - 'sessionrestore', 20:09:04 INFO - 'sessionrestore_no_auto_restore', 20:09:05 INFO - 'tabpaint']}, 20:09:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:09:05 INFO - 'ts_paint', 20:09:05 INFO - 'tpaint', 20:09:05 INFO - 'sessionrestore', 20:09:05 INFO - 'sessionrestore_no_auto_restore', 20:09:05 INFO - 'tabpaint']}, 20:09:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:09:05 INFO - 'ts_paint', 20:09:05 INFO - 'tpaint', 20:09:05 INFO - 'sessionrestore', 20:09:05 INFO - 'sessionrestore_no_auto_restore', 20:09:05 INFO - 'tabpaint']}, 20:09:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:09:05 INFO - 'tests': ['a11yr', 20:09:05 INFO - 'ts_paint', 20:09:05 INFO - 'tpaint', 20:09:05 INFO - 'sessionrestore', 20:09:05 INFO - 'sessionrestore_no_auto_restore', 20:09:05 INFO - 'tabpaint']}, 20:09:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:09:05 INFO - 'tests': ['a11yr', 20:09:05 INFO - 'ts_paint', 20:09:05 INFO - 'tpaint', 20:09:05 INFO - 'sessionrestore', 20:09:05 INFO - 'sessionrestore_no_auto_restore', 20:09:05 INFO - 'tabpaint']}, 20:09:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:09:05 INFO - 'tests': ['tsvgx', 20:09:05 INFO - 'tsvgr_opacity', 20:09:05 INFO - 'tart', 20:09:05 INFO - 'tscrollx', 20:09:05 INFO - 'cart']}, 20:09:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:09:05 INFO - 'tsvgr_opacity', 20:09:05 INFO - 'tart', 20:09:05 INFO - 'tscrollx', 20:09:05 INFO - 'cart']}, 20:09:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:05 INFO - 'talos_options': ['--disable-e10s'], 20:09:05 INFO - 'tests': ['tp5o']}, 20:09:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:05 INFO - 'tests': ['tp5o']}, 20:09:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:05 INFO - 'talos_options': ['--disable-e10s', 20:09:05 INFO - '--xperf_path', 20:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:05 INFO - 'tests': ['tp5n']}, 20:09:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:05 INFO - 'talos_options': ['--xperf_path', 20:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:05 INFO - 'tests': ['tp5n']}}, 20:09:05 INFO - 'talos.zip': {'path': '', 20:09:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:09:05 INFO - Running post-action listener: _resource_record_post_action 20:09:05 INFO - [mozharness: 2016-08-05 03:09:05.004295Z] Finished populate-webroot step (success) 20:09:05 INFO - [mozharness: 2016-08-05 03:09:05.004384Z] Running create-virtualenv step. 20:09:05 INFO - Running pre-action listener: _resource_record_pre_action 20:09:05 INFO - Running main action method: create_virtualenv 20:09:05 INFO - Creating virtualenv /builds/slave/test/build/venv 20:09:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:09:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:09:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:09:05 INFO - Using real prefix '/usr' 20:09:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:09:05 INFO - Installing distribute.............................................................................................................................................................................................done. 20:09:07 INFO - Installing pip.................done. 20:09:07 INFO - Return code: 0 20:09:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:09:07 INFO - Using env: {'DISPLAY': ':0', 20:09:07 INFO - 'HOME': '/home/cltbld', 20:09:07 INFO - 'LANG': 'en_US.UTF-8', 20:09:07 INFO - 'LANGUAGE': 'en_US:en', 20:09:07 INFO - 'LOGNAME': 'cltbld', 20:09:07 INFO - 'MAIL': '/var/mail/cltbld', 20:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:07 INFO - 'MOZ_NO_REMOTE': '1', 20:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:07 INFO - 'NO_EM_RESTART': '1', 20:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:07 INFO - 'PWD': '/builds/slave/test', 20:09:07 INFO - 'SHELL': '/bin/bash', 20:09:07 INFO - 'SHLVL': '1', 20:09:07 INFO - 'TERM': 'linux', 20:09:07 INFO - 'TMOUT': '86400', 20:09:07 INFO - 'USER': 'cltbld', 20:09:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:07 INFO - '_': '/tools/buildbot/bin/python'} 20:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:09:07 INFO - Cleaning up... 20:09:07 INFO - Return code: 0 20:09:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:09:07 INFO - Using env: {'DISPLAY': ':0', 20:09:07 INFO - 'HOME': '/home/cltbld', 20:09:07 INFO - 'LANG': 'en_US.UTF-8', 20:09:07 INFO - 'LANGUAGE': 'en_US:en', 20:09:07 INFO - 'LOGNAME': 'cltbld', 20:09:07 INFO - 'MAIL': '/var/mail/cltbld', 20:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:07 INFO - 'MOZ_NO_REMOTE': '1', 20:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:07 INFO - 'NO_EM_RESTART': '1', 20:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:07 INFO - 'PWD': '/builds/slave/test', 20:09:07 INFO - 'SHELL': '/bin/bash', 20:09:07 INFO - 'SHLVL': '1', 20:09:07 INFO - 'TERM': 'linux', 20:09:07 INFO - 'TMOUT': '86400', 20:09:07 INFO - 'USER': 'cltbld', 20:09:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:07 INFO - '_': '/tools/buildbot/bin/python'} 20:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:07 INFO - Downloading/unpacking psutil>=3.1.1 20:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:10 INFO - Installing collected packages: psutil 20:09:10 INFO - Running setup.py install for psutil 20:09:10 INFO - building 'psutil._psutil_linux' extension 20:09:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:09:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:09:10 INFO - building 'psutil._psutil_posix' extension 20:09:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:09:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:10 INFO - Successfully installed psutil 20:09:10 INFO - Cleaning up... 20:09:10 INFO - Return code: 0 20:09:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:09:10 INFO - Using env: {'DISPLAY': ':0', 20:09:10 INFO - 'HOME': '/home/cltbld', 20:09:10 INFO - 'LANG': 'en_US.UTF-8', 20:09:10 INFO - 'LANGUAGE': 'en_US:en', 20:09:10 INFO - 'LOGNAME': 'cltbld', 20:09:10 INFO - 'MAIL': '/var/mail/cltbld', 20:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:10 INFO - 'MOZ_NO_REMOTE': '1', 20:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:10 INFO - 'NO_EM_RESTART': '1', 20:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:10 INFO - 'PWD': '/builds/slave/test', 20:09:10 INFO - 'SHELL': '/bin/bash', 20:09:10 INFO - 'SHLVL': '1', 20:09:10 INFO - 'TERM': 'linux', 20:09:10 INFO - 'TMOUT': '86400', 20:09:10 INFO - 'USER': 'cltbld', 20:09:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:10 INFO - '_': '/tools/buildbot/bin/python'} 20:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:09:12 INFO - Installing collected packages: mozsystemmonitor 20:09:12 INFO - Running setup.py install for mozsystemmonitor 20:09:12 INFO - Successfully installed mozsystemmonitor 20:09:12 INFO - Cleaning up... 20:09:12 INFO - Return code: 0 20:09:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:09:12 INFO - Using env: {'DISPLAY': ':0', 20:09:12 INFO - 'HOME': '/home/cltbld', 20:09:12 INFO - 'LANG': 'en_US.UTF-8', 20:09:12 INFO - 'LANGUAGE': 'en_US:en', 20:09:12 INFO - 'LOGNAME': 'cltbld', 20:09:12 INFO - 'MAIL': '/var/mail/cltbld', 20:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:12 INFO - 'MOZ_NO_REMOTE': '1', 20:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:12 INFO - 'NO_EM_RESTART': '1', 20:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:12 INFO - 'PWD': '/builds/slave/test', 20:09:12 INFO - 'SHELL': '/bin/bash', 20:09:12 INFO - 'SHLVL': '1', 20:09:12 INFO - 'TERM': 'linux', 20:09:12 INFO - 'TMOUT': '86400', 20:09:12 INFO - 'USER': 'cltbld', 20:09:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:12 INFO - '_': '/tools/buildbot/bin/python'} 20:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:13 INFO - Downloading/unpacking jsonschema==2.5.1 20:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:09:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:15 INFO - Downloading functools32-3.2.3-2.tar.gz 20:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:09:15 INFO - warning: no files found matching '*.txt' 20:09:15 INFO - no previously-included directories found matching 'build' 20:09:15 INFO - no previously-included directories found matching 'dist' 20:09:15 INFO - no previously-included directories found matching '.git*' 20:09:15 INFO - Installing collected packages: jsonschema, functools32 20:09:15 INFO - Running setup.py install for functools32 20:09:15 INFO - warning: no files found matching '*.txt' 20:09:15 INFO - no previously-included directories found matching 'build' 20:09:15 INFO - no previously-included directories found matching 'dist' 20:09:15 INFO - no previously-included directories found matching '.git*' 20:09:15 INFO - Successfully installed jsonschema functools32 20:09:15 INFO - Cleaning up... 20:09:15 INFO - Return code: 0 20:09:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:09:15 INFO - Using env: {'DISPLAY': ':0', 20:09:15 INFO - 'HOME': '/home/cltbld', 20:09:15 INFO - 'LANG': 'en_US.UTF-8', 20:09:15 INFO - 'LANGUAGE': 'en_US:en', 20:09:15 INFO - 'LOGNAME': 'cltbld', 20:09:15 INFO - 'MAIL': '/var/mail/cltbld', 20:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:15 INFO - 'MOZ_NO_REMOTE': '1', 20:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:15 INFO - 'NO_EM_RESTART': '1', 20:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:15 INFO - 'PWD': '/builds/slave/test', 20:09:15 INFO - 'SHELL': '/bin/bash', 20:09:15 INFO - 'SHLVL': '1', 20:09:15 INFO - 'TERM': 'linux', 20:09:15 INFO - 'TMOUT': '86400', 20:09:15 INFO - 'USER': 'cltbld', 20:09:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:15 INFO - '_': '/tools/buildbot/bin/python'} 20:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:09:15 INFO - Cleaning up... 20:09:15 INFO - Return code: 0 20:09:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:09:15 INFO - Using env: {'DISPLAY': ':0', 20:09:15 INFO - 'HOME': '/home/cltbld', 20:09:15 INFO - 'LANG': 'en_US.UTF-8', 20:09:15 INFO - 'LANGUAGE': 'en_US:en', 20:09:15 INFO - 'LOGNAME': 'cltbld', 20:09:15 INFO - 'MAIL': '/var/mail/cltbld', 20:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:15 INFO - 'MOZ_NO_REMOTE': '1', 20:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:15 INFO - 'NO_EM_RESTART': '1', 20:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:15 INFO - 'PWD': '/builds/slave/test', 20:09:15 INFO - 'SHELL': '/bin/bash', 20:09:15 INFO - 'SHLVL': '1', 20:09:15 INFO - 'TERM': 'linux', 20:09:15 INFO - 'TMOUT': '86400', 20:09:15 INFO - 'USER': 'cltbld', 20:09:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:15 INFO - '_': '/tools/buildbot/bin/python'} 20:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:15 INFO - Downloading/unpacking blobuploader==1.2.4 20:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:17 INFO - Downloading blobuploader-1.2.4.tar.gz 20:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:09:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:09:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:18 INFO - Downloading docopt-0.6.1.tar.gz 20:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:09:18 INFO - Installing collected packages: blobuploader, requests, docopt 20:09:18 INFO - Running setup.py install for blobuploader 20:09:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:09:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:09:18 INFO - Running setup.py install for requests 20:09:18 INFO - Running setup.py install for docopt 20:09:19 INFO - Successfully installed blobuploader requests docopt 20:09:19 INFO - Cleaning up... 20:09:19 INFO - Return code: 0 20:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:19 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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:09:19 INFO - Using env: {'DISPLAY': ':0', 20:09:19 INFO - 'HOME': '/home/cltbld', 20:09:19 INFO - 'LANG': 'en_US.UTF-8', 20:09:19 INFO - 'LANGUAGE': 'en_US:en', 20:09:19 INFO - 'LOGNAME': 'cltbld', 20:09:19 INFO - 'MAIL': '/var/mail/cltbld', 20:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:19 INFO - 'MOZ_NO_REMOTE': '1', 20:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:19 INFO - 'NO_EM_RESTART': '1', 20:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:19 INFO - 'PWD': '/builds/slave/test', 20:09:19 INFO - 'SHELL': '/bin/bash', 20:09:19 INFO - 'SHLVL': '1', 20:09:19 INFO - 'TERM': 'linux', 20:09:19 INFO - 'TMOUT': '86400', 20:09:19 INFO - 'USER': 'cltbld', 20:09:19 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:19 INFO - '_': '/tools/buildbot/bin/python'} 20:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:19 INFO - Running setup.py (path:/tmp/pip-WSk1N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:19 INFO - Running setup.py (path:/tmp/pip-A21OdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:19 INFO - Running setup.py (path:/tmp/pip-p46U8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:19 INFO - Running setup.py (path:/tmp/pip-udRVtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:19 INFO - Running setup.py (path:/tmp/pip-QnY3uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:19 INFO - Running setup.py (path:/tmp/pip-UUm7eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:19 INFO - Running setup.py (path:/tmp/pip-Js958H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:19 INFO - Running setup.py (path:/tmp/pip-NqKmYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:19 INFO - Running setup.py (path:/tmp/pip-EpzWIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:19 INFO - Running setup.py (path:/tmp/pip-zuIFWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:20 INFO - Running setup.py (path:/tmp/pip-R5QuKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:20 INFO - Running setup.py (path:/tmp/pip-A9vafg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:20 INFO - Running setup.py (path:/tmp/pip-YyCLWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:20 INFO - Running setup.py (path:/tmp/pip-RbSDUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:20 INFO - Running setup.py (path:/tmp/pip-7rgWzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:20 INFO - Running setup.py (path:/tmp/pip-yvZ9tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:20 INFO - Running setup.py (path:/tmp/pip-8UVip5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:09:20 INFO - Running setup.py install for manifestparser 20:09:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:09:20 INFO - Running setup.py install for mozcrash 20:09:20 INFO - Running setup.py install for mozdebug 20:09:20 INFO - Running setup.py install for mozdevice 20:09:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:09:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:09:20 INFO - Running setup.py install for mozfile 20:09:21 INFO - Running setup.py install for mozhttpd 20:09:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozinfo 20:09:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozInstall 20:09:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozleak 20:09:21 INFO - Running setup.py install for mozlog 20:09:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for moznetwork 20:09:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozprocess 20:09:21 INFO - Running setup.py install for mozprofile 20:09:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozrunner 20:09:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:09:21 INFO - Running setup.py install for mozscreenshot 20:09:22 INFO - Running setup.py install for moztest 20:09:22 INFO - Running setup.py install for mozversion 20:09:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:09:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:09:22 INFO - Cleaning up... 20:09:22 INFO - Return code: 0 20:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:22 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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:09:22 INFO - Using env: {'DISPLAY': ':0', 20:09:22 INFO - 'HOME': '/home/cltbld', 20:09:22 INFO - 'LANG': 'en_US.UTF-8', 20:09:22 INFO - 'LANGUAGE': 'en_US:en', 20:09:22 INFO - 'LOGNAME': 'cltbld', 20:09:22 INFO - 'MAIL': '/var/mail/cltbld', 20:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:22 INFO - 'MOZ_NO_REMOTE': '1', 20:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:22 INFO - 'NO_EM_RESTART': '1', 20:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:22 INFO - 'PWD': '/builds/slave/test', 20:09:22 INFO - 'SHELL': '/bin/bash', 20:09:22 INFO - 'SHLVL': '1', 20:09:22 INFO - 'TERM': 'linux', 20:09:22 INFO - 'TMOUT': '86400', 20:09:22 INFO - 'USER': 'cltbld', 20:09:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:22 INFO - '_': '/tools/buildbot/bin/python'} 20:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:22 INFO - Running setup.py (path:/tmp/pip-dxUZTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:22 INFO - Running setup.py (path:/tmp/pip-839uw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:22 INFO - Running setup.py (path:/tmp/pip-l1CxlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:22 INFO - Running setup.py (path:/tmp/pip-q0X2L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:22 INFO - Running setup.py (path:/tmp/pip-OskSNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:22 INFO - Running setup.py (path:/tmp/pip-BnqCbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:22 INFO - Running setup.py (path:/tmp/pip-VaT5CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:23 INFO - Running setup.py (path:/tmp/pip-N21XZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:23 INFO - Running setup.py (path:/tmp/pip-XVAZ5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:23 INFO - Running setup.py (path:/tmp/pip-EXUx7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:23 INFO - Running setup.py (path:/tmp/pip-31ZcpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:23 INFO - Running setup.py (path:/tmp/pip-UxIyDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:23 INFO - Running setup.py (path:/tmp/pip-oT5hQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:23 INFO - Running setup.py (path:/tmp/pip-5J1WBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:23 INFO - Running setup.py (path:/tmp/pip-p9dypj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:23 INFO - Running setup.py (path:/tmp/pip-N7BTSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:23 INFO - Running setup.py (path:/tmp/pip-w1Q1Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:29 INFO - Downloading blessings-1.6.tar.gz 20:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:09:29 INFO - Installing collected packages: blessings 20:09:29 INFO - Running setup.py install for blessings 20:09:29 INFO - Successfully installed blessings 20:09:29 INFO - Cleaning up... 20:09:29 INFO - Return code: 0 20:09:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:29 INFO - Reading from file tmpfile_stdout 20:09:29 INFO - Current package versions: 20:09:29 INFO - argparse == 1.2.1 20:09:29 INFO - blessings == 1.6 20:09:29 INFO - blobuploader == 1.2.4 20:09:29 INFO - docopt == 0.6.1 20:09:29 INFO - functools32 == 3.2.3-2 20:09:29 INFO - jsonschema == 2.5.1 20:09:29 INFO - manifestparser == 1.1 20:09:29 INFO - mozInstall == 1.12 20:09:29 INFO - mozcrash == 0.17 20:09:29 INFO - mozdebug == 0.1 20:09:29 INFO - mozdevice == 0.48 20:09:29 INFO - mozfile == 1.2 20:09:29 INFO - mozhttpd == 0.7 20:09:29 INFO - mozinfo == 0.9 20:09:29 INFO - mozleak == 0.1 20:09:29 INFO - mozlog == 3.3 20:09:29 INFO - moznetwork == 0.27 20:09:29 INFO - mozprocess == 0.23 20:09:29 INFO - mozprofile == 0.28 20:09:29 INFO - mozrunner == 6.12 20:09:29 INFO - mozscreenshot == 0.1 20:09:29 INFO - mozsystemmonitor == 0.3 20:09:29 INFO - moztest == 0.7 20:09:29 INFO - mozversion == 1.4 20:09:29 INFO - psutil == 3.1.1 20:09:29 INFO - requests == 1.2.3 20:09:29 INFO - wsgiref == 0.1.2 20:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:29 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 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:09:29 INFO - Using env: {'DISPLAY': ':0', 20:09:29 INFO - 'HOME': '/home/cltbld', 20:09:29 INFO - 'LANG': 'en_US.UTF-8', 20:09:29 INFO - 'LANGUAGE': 'en_US:en', 20:09:29 INFO - 'LOGNAME': 'cltbld', 20:09:29 INFO - 'MAIL': '/var/mail/cltbld', 20:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:29 INFO - 'MOZ_NO_REMOTE': '1', 20:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:29 INFO - 'NO_EM_RESTART': '1', 20:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:29 INFO - 'PWD': '/builds/slave/test', 20:09:29 INFO - 'SHELL': '/bin/bash', 20:09:29 INFO - 'SHLVL': '1', 20:09:29 INFO - 'TERM': 'linux', 20:09:29 INFO - 'TMOUT': '86400', 20:09:29 INFO - 'USER': 'cltbld', 20:09:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:29 INFO - '_': '/tools/buildbot/bin/python'} 20:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:09:30 INFO - Cleaning up... 20:09:30 INFO - Return code: 0 20:09:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, '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': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:09: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', 'jsonschema'] in /builds/slave/test/build 20:09: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 jsonschema 20:09:30 INFO - Using env: {'DISPLAY': ':0', 20:09:30 INFO - 'HOME': '/home/cltbld', 20:09:30 INFO - 'LANG': 'en_US.UTF-8', 20:09:30 INFO - 'LANGUAGE': 'en_US:en', 20:09:30 INFO - 'LOGNAME': 'cltbld', 20:09:30 INFO - 'MAIL': '/var/mail/cltbld', 20:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:30 INFO - 'MOZ_NO_REMOTE': '1', 20:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:30 INFO - 'NO_EM_RESTART': '1', 20:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:30 INFO - 'PWD': '/builds/slave/test', 20:09:30 INFO - 'SHELL': '/bin/bash', 20:09:30 INFO - 'SHLVL': '1', 20:09:30 INFO - 'TERM': 'linux', 20:09:30 INFO - 'TMOUT': '86400', 20:09:30 INFO - 'USER': 'cltbld', 20:09:30 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:30 INFO - '_': '/tools/buildbot/bin/python'} 20:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:09:30 INFO - Cleaning up... 20:09:30 INFO - Return code: 0 20:09:30 INFO - Running post-action listener: _resource_record_post_action 20:09:30 INFO - Running post-action listener: _start_resource_monitoring 20:09:30 INFO - Starting resource monitoring. 20:09:30 INFO - [mozharness: 2016-08-05 03:09:30.306963Z] Finished create-virtualenv step (success) 20:09:30 INFO - [mozharness: 2016-08-05 03:09:30.307398Z] Running install step. 20:09:30 INFO - Running pre-action listener: _resource_record_pre_action 20:09:30 INFO - Running main action method: install 20:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:30 INFO - Reading from file tmpfile_stdout 20:09:30 INFO - Detecting whether we're running mozinstall >=1.0... 20:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:09:30 INFO - Reading from file tmpfile_stdout 20:09:30 INFO - Output received: 20:09:30 INFO - Usage: mozinstall [options] installer 20:09:30 INFO - Options: 20:09:30 INFO - -h, --help show this help message and exit 20:09:30 INFO - -d DEST, --destination=DEST 20:09:30 INFO - Directory to install application into. [default: 20:09:30 INFO - "/builds/slave/test"] 20:09:30 INFO - --app=APP Application being installed. [default: firefox] 20:09:30 INFO - mkdir: /builds/slave/test/build/application 20:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:09:47 INFO - Reading from file tmpfile_stdout 20:09:47 INFO - Output received: 20:09:47 INFO - /builds/slave/test/build/application/firefox/firefox 20:09:47 INFO - Running post-action listener: _resource_record_post_action 20:09:47 INFO - [mozharness: 2016-08-05 03:09:47.447821Z] Finished install step (success) 20:09:47 INFO - [mozharness: 2016-08-05 03:09:47.448066Z] Running run-tests step. 20:09:47 INFO - Running pre-action listener: _resource_record_pre_action 20:09:47 INFO - Running main action method: run_tests 20:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:47 INFO - Python 2.7.3 20:09:47 INFO - Return code: 0 20:09:47 INFO - grabbing minidump binary from tooltool 20:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:47 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 0x29983b0>, '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 0x29992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2999740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:09:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:09:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:09:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:47 INFO - Return code: 0 20:09:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:09:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:09:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:09:47 INFO - Using env: {'DISPLAY': ':0', 20:09:47 INFO - 'HOME': '/home/cltbld', 20:09:47 INFO - 'LANG': 'en_US.UTF-8', 20:09:47 INFO - 'LANGUAGE': 'en_US:en', 20:09:47 INFO - 'LOGNAME': 'cltbld', 20:09:47 INFO - 'MAIL': '/var/mail/cltbld', 20:09:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:47 INFO - 'MOZ_NO_REMOTE': '1', 20:09:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:47 INFO - 'NO_EM_RESTART': '1', 20:09:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:47 INFO - 'PWD': '/builds/slave/test', 20:09:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:47 INFO - 'SHELL': '/bin/bash', 20:09:47 INFO - 'SHLVL': '1', 20:09:47 INFO - 'TERM': 'linux', 20:09:47 INFO - 'TMOUT': '86400', 20:09:47 INFO - 'USER': 'cltbld', 20:09:47 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167', 20:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:47 INFO - '_': '/tools/buildbot/bin/python'} 20:09:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:09:47 INFO - mozversion application_buildid: 20160804173002 20:09:47 INFO - mozversion application_changeset: fff9d624dca283235c916d8769786e04f5622282 20:09:47 INFO - mozversion application_display_name: Nightly 20:09:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:47 INFO - mozversion application_name: Firefox 20:09:47 INFO - mozversion application_remotingname: firefox 20:09:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:09:47 INFO - mozversion application_vendor: Mozilla 20:09:47 INFO - mozversion application_version: 51.0a1 20:09:47 INFO - mozversion platform_buildid: 20160804173002 20:09:47 INFO - mozversion platform_changeset: fff9d624dca283235c916d8769786e04f5622282 20:09:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:09:47 INFO - mozversion platform_version: 51.0a1 20:09:47 INFO - using testdate: 1470366587 20:09:47 INFO - actual date: 1470366587 20:09:47 INFO - starting webserver on 'localhost:56231' 20:09:47 INFO - SUITE-START | Running 2 tests 20:09:47 INFO - TEST-START | dromaeo_css 20:09:47 INFO - operating with platform_type : linux_ 20:09:47 INFO - Initialising browser for dromaeo_css test... 20:09:47 INFO - TEST-INFO | started process 4989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprxpYta/profile http://localhost:56231/getInfo.html) 20:09:51 INFO - PROCESS | 4989 | __metrics Screen width/height:1600/1200 20:09:51 INFO - PROCESS | 4989 | colorDepth:24 20:09:51 INFO - PROCESS | 4989 | Browser inner width/height: 1024/697 20:09:51 INFO - PROCESS | 4989 | __metrics 20:09:52 INFO - PROCESS | 4989 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:09:52 INFO - PROCESS | 4989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:53 INFO - PROCESS | 4989 | console.error: PushService: 20:09:53 INFO - PROCESS | 4989 | stateChangeProcessEnqueue: Error transitioning state 20:09:53 INFO - PROCESS | 4989 | UnknownError 20:09:53 INFO - TEST-INFO | 4989: exit 0 20:09:53 INFO - Browser initialized. 20:09:53 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:53 INFO - TEST-INFO | started process 5072 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprxpYta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:05 INFO - PROCESS | 5072 | RSS: Main: 156577792 20:10:05 INFO - PROCESS | 5072 | 20:10:56 INFO - PROCESS | 5072 | 1470366656696 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:13:06 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56231/tests/dromaeo/cssquery-ext.html) 20:13:07 INFO - PROCESS | 5072 | RSS: Main: 288333824 20:13:07 INFO - PROCESS | 5072 | 20:16:10 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-ext.html (next: http://localhost:56231/tests/dromaeo/cssquery-jquery.html) 20:16:10 INFO - PROCESS | 5072 | RSS: Main: 244592640 20:16:10 INFO - PROCESS | 5072 | 20:19:12 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56231/tests/dromaeo/cssquery-mootools.html) 20:19:13 INFO - PROCESS | 5072 | RSS: Main: 184664064 20:19:13 INFO - PROCESS | 5072 | 20:22:15 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56231/tests/dromaeo/cssquery-prototype.html) 20:22:15 INFO - PROCESS | 5072 | RSS: Main: 244539392 20:22:15 INFO - PROCESS | 5072 | 20:25:18 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56231/tests/dromaeo/cssquery-yui.html) 20:25:18 INFO - PROCESS | 5072 | RSS: Main: 184078336 20:25:18 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | Cycle 1(1): loaded http://localhost:56231/tests/dromaeo/cssquery-yui.html (next: http://localhost:56231/tests/dromaeo/cssquery-dojo.html) 20:28:22 INFO - PROCESS | 5072 | RSS: Main: 278720512 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | __start_tp_report 20:28:22 INFO - PROCESS | 5072 | _x_x_mozilla_page_load 20:28:22 INFO - PROCESS | 5072 | _x_x_mozilla_page_load_details 20:28:22 INFO - PROCESS | 5072 | |i|pagename|runs| 20:28:22 INFO - PROCESS | 5072 | |0;dojo.html;3008.18778929689;3040.3919216156773;3073.3699591583727;3087.012156110044;3097.581826453428;5184.429712731606;5268.736563171845;5295.682259064454;5396.433374495676;5414.269073675057;5254.395744489385;5284.128118860393;5292.682439053649;5319.255304257407;5329.840104796862;4760.428748550179;4847.345608342882;4853.781579828917;4864.0515099555605;5009.423916249623;4965.3296804931415;4968.900621987558;4973.552380285773;4976.079425306312;4983.053219888227;4762.499937506581;4806.687565308243;4832.202686556714;4885.413750349945;4937.456879743226;4865.732384718839;4877.268409738532;4878.658493905428;4883.780229889663;4884.218525036013;4785.66500344976;4834.975825120852;4839.637027222937;4842.612590992712;4938.827141050082;5179.507946745053;5195.922061169085;5243.79024839006;5283.841484755463;5335.33308336458;5642.4075472075665;5701.14482827575;5706.087026075808;5717.399673034313;5718.485336319751;5520.1443776214755;5567.749451274703;5573.832785016456;5726.942730572724;5732.799352022659;5270.973645131827;5290.100682883919;5292.603054770831;5293.153095504777;5293.470652934714;5776.942230577724;5805.36141024487;5814.011618024946;5816.360200377955;5818.44724751148;5423.647462914898;5552.7778888843995;5582.24639673647;5601.719914004365;5603.551715862721;5730.914036289459;5760.193572899798;5786;5787.71061446926;5789.768409263666;5715.914261286084;5718.085106382959;5720.399358067343;5727.341355744143;5730.684812335278;5469.890602188014;5541.833744987657;5598.524125449382;5619.550435965113;5719.685417302005;4350.347447882843;4376.824927002948;4382.23310920594;4415.2935530315;4417.315316126005;3873.070463088895;3888.7083468739957;3905.5899130591447;3936.1537269486626;3940.2119576084897;5276.208568714724;5341.679499230058;5344.8663783404945;5373.919391209135;5376.301080859464;5635.774569017193;5652.63257888236;5653.660780353192;5684.317881854204;5691.800786972436;5262.447443018505;5295.12630415977;5306.681599104066;5312.867178320573;5324.315466436257;4821.831235906727;4885.389326334208;4908.312836202935;4924.556789888928;4945.109880221523;5960.76156953717;5964.5526585505395;5966.76132954677;5983.401659833982;5999.970000149971;5717.514011309086;5719.113537401709;5726.484616384545;5730.398308177581;5733.885322293614;5604.271444712324;5849.853753656193;5886.117082437669;5901.203337549376;5932.822015339547;5615.859603509945;5659.292588426447;5661.490465858093;5696.943030569641;5713.714314284352;5744.310682718101;5749.051406517878;5756.1941328214925;5756.884862302814;5759.94240057594;5325.973370133124;5527.088030474926;5575.972120139373;5624.887502250014;5630.324361076697;5617.382087970402;5617.578681598815;5622.718864056863;5622.718864056863;5651.773929042956;5543.473370030002;5548.750306236148;5553.555715542666;5555.30558680165;5557.972210138923;5405.405405405481;5424.511793938406;5471.425500322409;5670.121131224667;5670.489655930986;5443.455654434525;5469.316335458067;5473.890522189614;5487.6158668892795;5498.505134537911 20:28:22 INFO - PROCESS | 5072 | |1;ext.html;594.7145370222294;615.4799194680494;622.1321256846702;643.1092936283246;643.5623775832432;33013.504797428024;44428.77785611071;45292.773536132314;45431.54568454323;45510.31734523977;46665.766671166595;47063.529364706344;47069.52930470694;47081;47130.528694713044;9074.773130671736;9221.769455763593;9232.769180770467;9233;9237.584308706124;9040.412373195753;9117.68088116916;9130.452172869649;9135.725928222164;9142.451452912814;9143.908560914406;9187.954060229722;9204.263658907304;9211.861822072675;9214.170724634781;9129.497877616728;9156.908430915671;9168.083191680846;9181.265498760116;9185.127412895765;9011.909880901172;9079.591418386188;9082.364234503588;9084.227840633554;9086;44597.33104003443;44908.101837963055;44922;44926.32610510845;44941.55058449406;3365.209175843675;3962.841486340543;4219.156168766228;4235.407043013981;4387.232234358974;32742;33742.6625733742;33847.153821154425;33963.32073358519;34339;4287.571242875719;4372.584604462571;4420.359047938031;4435.3125265583885;4511.503734589192;368298;370662.32019559183;371366.14316928515;372631;373212;60579.394206058256;60806.69596652077;60848.69575652093;60872.69563652154;60940;63972.36027639757;64225;64289.35710642834;64415.67792161009;64440;15852.128132952568;16070.11614361198;16077;16083.678326433639;16089.115098669685;12326.260424374566;12359.443825027894;12404.317762523147;12415;12436.19164754288;13880.722385552232;17429.564260893327;17435.65128697419;17492;17526.737098943526;6236.812895613139;6348.492120630339;6433.485321174295;6583.9012414813815;6587.209534855752;1028.4675833008912;1044.3785947361307;1063.7340664833791;1116.6984914073155;1191.1007189571817;32471;32549.674503255137;32765.50851737226;32835.83582082074;33172;1125.0268517732102;1153.8595904597018;1182.5742732616252;1222.8043513037871;1231.950721971111;17048;17203;17303.56741081483;17364.65270694579;17534.210960506807;526092;530624;531296;531580;532972;61701.691491543155;61705.691471542355;61817.38182618116;61837.38162618406;61879.07181392283;1221.9205751626293;1287.594407761552;1317.736452709462;1345.6770375109902;1348.494314632013;30432.84783576068;35202.29595408129;35249.823750882104;35290;35624.821875890455;52531.47468525419;53160;53194.73402632962;53229.73385133049;53342.73328633487;401793;403633;403746;404284;404527;42491.57508424876;43089;43723;43929;43995.56004440042;49382;49834.559189937776;49844;50464.24303635375;51573;41990.370144447246;42824.78587607042;43602.12795744004;44070.559294406645;44815;42757;43379.78310108429;43452.78273608612;43592.78203609089;43703;43209.78395108004;43436.78281608572;43929;43983.560164397946;44169;35447.11382215465;37387.43918841165;37401.81299093487;38475.42286865755;38722.80638596789;28957.56563651589;29017.854910725313;29250.41499169962;29351.853240734516;29507.262318442212 20:28:22 INFO - PROCESS | 5072 | |2;jquery.html;365.1145971020275;381.4907099022806;389.287603685256;400.88173988673344;418.43093392985537;16829.15854207289;19126.71309930352;19417.3203937862;20206.29277975271;20209.494762630904;18856.245750169976;19049.333273335436;19506.609867802672;19809.30667426637;19940.40178794635;5793.739281732311;5992.43071908168;6000.279966404038;6148.169997050386;6637.701303441332;6702.229243636971;6712.83217919551;6719.092922455479;6720.495962802785;6732.360425759545;6442.871142577145;6590.8022759317055;6603.273639899608;6618.966905165492;6623.6025838449605;6422.197225346832;6588.143541339643;6641.269460359356;6683.966580167116;6687.966560167192;5829.183914252008;5830.3586605473365;5832.270966129234;5832.795852145155;5840.415958404125;24113.638295425582;24233.273001809797;24334;24506.142285019938;24641.137560185485;2157.2018353209255;2165.523584811339;2176.5320456101854;2200.4278887489368;2211.148707747529;14563.417463301455;15044.774328384974;15382.923085384613;15392.769108463383;15985.52043438687;1889.6031833315021;1927.942161735136;1929.170456703617;1964.4695932098252;1997.3907958072782;72974;74480.25519744841;76328.61835690786;77871.61064194699;78504.21495785026;11083.944580277048;11090.667279981451;11210.607628732956;11254.774904501865;11416.657500275005;15301.770473442908;15341.539753807403;15379.000364976464;15396.923015385075;15400.691986160215;7946.404019698546;8026.518408895485;8119.309858661962;8258.628361723737;8293.21214484623;7619.1999840016;7656.885146722803;7758.262965018314;7834.7257845976255;7890.526568405914;4718.787654555554;4762.9761851191215;4790.856274311776;4857.765427903133;4859.902801943941;1954.130411966661;2017.243533674872;2025.9797402026086;2027.6248893954667;2056.8663036902553;927.5594092805864;1024.6669832304383;1080.5736427915233;1087.1846115413439;1110.2672236323988;19916.402507924784;20105.49736256576;20493;20530.486737831674;20643.58712825735;973.9172170365457;995.9900400996042;1008.8335424654997;1013.6198925402974;1041.4688508860536;10435.843462348072;10644.787104257872;10962.780744385067;11432.428378581204;11444.198906076661;501390;508937;528990;532106;532196;84471;92620.5074522703;94200;94889.42631329571;95659.9123083306;999.1307562420595;1042.6559235452435;1050.3382868792612;1054.8101341758404;1057.984130238047;17175.96944183303;17716;18614.2554297832;18634.16146273448;18684.906575467037;32282;32679.346413072082;43039.56960430356;43217.567824321355;44463.55536444724;58787.70606146942;60443.39556604553;61422.31466112048;61679;62841;7803.102643196105;8427.915720842713;8485.236328730198;8759.255463285437;8774.34192435557;12825.525064617688;13290.800637990631;13532.052756306965;13832.308384580932;13845.653858653613;11759.412029398667;11944.343061131374;12138.575149869712;12170.991786355506;12447.564335248222;11673.766524669629;11783.823242651539;11832.763344732884;12135.939320303341;12375.133740638068;10895.891041089488;11772.587959421378;12170.087243456599;12270.141090123605;12272.263664180178;8735.257503112052;8829.426087304304;8921.910780892367;9236.168744813;9257.583408746757;11283.379414131974;12176.391180440765;12261.080418968435;12326.75346493083;12357 20:28:22 INFO - PROCESS | 5072 | |3;mootools.html;1350.716349566591;1455.2505459688257;1506.8493150684933;1652.6281586643006;1795.3895675470346;8442.91557084429;8852.513111778857;8852.911470885281;9145.908540914588;9147.725568232947;8978.865317020234;9077.95461022694;9100.54497275137;9109.362344635878;9112.8633070504;2392.9162479313272;2421.733609302984;2421.9394515137174;2423.273018094573;2424.054618698709;2317.918872839451;2337.4273303040695;2367.9408014799596;2372.5847976604027;2372.6559648850907;2367.3016460144213;2377.5720370333333;2385.60637494814;2400.7959323457526;2401.819863510235;2278.362058623588;2293.7935585797277;2297.689811875392;2300.229423143249;2322.2336628912417;2300.7124109486313;2322.11759531377;2326.4649130700036;2331.603627383332;2333.300009996994;8769.210771030574;8985.146411090936;9059.275257979345;9099.363044586882;9111.362204645677;5038.244263360489;5252.422233554306;5259.000789849916;5262.710550919736;5266.657667251617;3447.5692587576377;3474.722022238215;3518.6481351864863;3553.626869178751;3556.5376501054698;1125.217973508412;1127.6053381316556;1139.3221033485063;1143.2340331977512;1153.129387312582;12652.873471265262;12656.050796190322;12697.873021269761;12731.299778512179;12731.681707957283;4265.3601959705675;4352.499462561783;4487.798049087799;4501.636640763625;4513.255312873404;9909.752256193653;9921.454320012468;9947.950260248797;9948.054934781185;9954.352967057119;5070.569001634903;5081.364829396325;5089.9745501273;5091.847244582668;5093.694378337312;4151.771652559078;4589.311603259471;4722.787474563653;4724.763761811896;4735.455422626374;3686.668199862025;3746.0822098586023;3811.599782022903;3848.365019771706;3850.3261929162286;1078.3907092492873;1084.8318510630866;1088;1090.7164137324194;1097.401915955806;845.3744229270295;923.5936188077225;937.8593211018265;939.5959737313021;986.0829428631298;6091.939080609226;6139.631622102688;6244.843878903063;6390.392912673288;6481.254655714653;931.8928323242778;933.2021121940778;937.6858752317913;1003.2726273451689;1023.4984857419811;4098.672106231495;4172.687048471377;4295.978520107379;4332.783360832009;4361.127774445124;11241.437928103562;11668.88331116695;11677.941610291895;11678.824817627572;11697.532098715956;11548.076153907667;11595.884041159648;11604.709882252842;11614.419279036014;11647.6505704829;7969.920300797034;8148.266656000988;8269.710560130367;8473.279771219391;8484.066752657203;6119.632822030514;6268.937310627018;6329.082283068838;6359.713812878525;6403.879321118692;10082.647107351208;10277.897221027693;10358.896411035794;10395.58417663285;10495.475226238505;10476.68569942903;10491.475426228506;10562.260641755003;10565.524551395052;10596.788064238826;7589.696412143452;7660.502067365604;7743.728969486041;7762.340201082971;7813.14055453911;8231.794205144679;8317.708880189164;8323.458975166595;8330.666773329;8354.16458354184;7318.34134927859;7491.513051651626;7580.393568514616;7608.581528016012;7683.346915512244;7131.322524360177;7431.925680743123;7448.292412220831;7467.141278753013;7665.885011724717;6732.225794033749;6880.621565813928;6887.104676392037;6888.448924086161;6913.827154320981;5100.464451232716;5104.285400043923;5121.8719532012;5257.369115705987;5452.945470545244;5330.0938840398;5414.187871819101;5425.348958125051;5480.150576660624;5484.561235101102 20:28:22 INFO - PROCESS | 5072 | |4;prototype.html;292.67074541141216;307.9568860359549;308.39246684032446;331.47626749735423;331.9186799234188;4923;5280.630355875091;5302.522772950439;5322.148456247001;5354.303940487731;5138.743062846861;5153.1239689252825;5169.30214421053;5231.24146998685;5244.842654720355;4094.488188976378;4112.6298633123015;4115.629593336599;4129.979350103245;4132.028973191313;4080.8979775505554;4139.834406623747;4155.168966206756;4167.9583204167875;4176.331786914095;4097.549269580344;4106.630403263705;4122.299209134454;4128.091819799639;4151.12826306476;4114.444549985744;4115.73247738896;4127.977291127326;4132.97933510332;4136.607022332873;3644.671979521856;3648.41625339947;3661.2494438640015;3664.3221004114057;3675.5773086095173;5009.499050094998;5159.5614372778355;5169.56058735008;5180.922286165711;5195.298634684307;4667.183242932473;4699.060187962421;4713.717176969393;4717.315989181584;4723.173444647207;5390.838274851761;5412.675239485643;5475.479829416239;5476.397596264408;5480.80817171401;3829.740453132665;3960.386140148282;3965.8017099144927;3967.4048892666333;3971.205758848242;5330.973345133289;5478.91781623276;5631.549476041907;5641;5648;4076.30702780528;4079.73481723687;4080.5307389649975;4092.7953602319767;4100.261952848516;3902.902427439337;4076.857309994136;4152.896177595584;4158.147579746144;4159.292920203573;4661.603763680125;4678.789454474489;4683.367745354333;4689;4708.529147085264;5460.781568737286;5473.2063850742215;5511.669299842022;5545.362283337387;5545.9722701387045;1349.8785109340206;1380.5858242527202;1382;1395.8115654386731;1398.3497673581833;928.2388441477923;950.3890904916238;962.5764663548016;971.3637567393368;975.2734213011211;4712.50518695532;4717.386739723814;4724.527547245248;4734.597559207437;4763.976180119146;4959.421101027446;4975.228839529878;4986.526280003394;4990.679334109656;5007.599392048627;4499.380223119674;4662.906741865144;4674.298855171752;4680.555347242007;4682.789274482589;2632.7893768498475;2635.407033417504;2638.656974593329;2650.674662668666;2657.1364306600044;5143.277002204229;5147.588192944556;5153.561947234452;5158.277841102249;5173.870653233624;4827.444843842933;4950.702957822542;4974.701517908937;4993.400791904995;5120.411152717411;5064.569511591482;5095.694258344512;5107.259447380184;5125.205593133071;5137.640365174402;4937.580305674058;4938.654294199371;4947.416826615448;5001.974990125026;5018.247262910592;4815.95184048155;5254.816081437131;5256.421793602777;5536.4186760389575;5541.362743284574;5728.79949201776;5750.597458177887;5752.856178595569;5757.510611597893;5770;4104.261232978092;4128.60778226068;4132.235536425771;4140.192662430797;4142.233686767958;5388;5587.972060139673;5626.971865140648;5677.489025987681;5688.488036076773;5538.9169162461785;5543.861403464945;5550.750216240198;5551.336118161994;5605;5217.452167522507;5457.154141108134;5489.698066606375;5502.257195278587;5582.3859375467855;5416.2688037114485;5420.457954204706;5431.891362172813;5446.645968012067;5463.426340234377;5597.356304025089;5598.424724626718;5605.383407825218;5623.072193088258;5631.521320687787;5142.51146141116;5404.513593776579;5421.376541697755;5446.400895901527;5494.972525137348 20:28:22 INFO - PROCESS | 5072 | |5;yui.html;753.902539696748;786.5988345943567;800.3557136505111;829.8589239829229;847.6439895243997;15748;16845.41041063563;16846.410375636857;16890.74663880043;16935.915320423395;15799;15931.920340398281;15943.601409964727;15956.441524546643;15962.840371596281;659.3439527669975;796.8964034675513;806.358944639012;810.7081450677751;824.7154731617583;801.6592947997107;802.081616549051;808.8705807070841;812.9634166462494;813.2111851504061;860.0754189246554;860.3246451535546;860.5310105992218;861.1560670542872;861.2378045429778;690.1063123255346;831.1107115386538;843.4306842881061;850.4557083466617;852.8720691896203;623.0249659283207;626.139058794158;626.5833220908136;626.9968650156766;627.1032423634201;15930.044197348196;15936.442224522087;15974.28115734783;15981.52055438327;15984.280707368196;14616.561503154922;14773.26133693311;14953.252337383195;15009.624759380993;15131.243437828065;13474.326283685776;13543.322833858268;13648.385822638005;13780.724385512334;14057.64855878601;4530.728156310632;4535.342375355588;4630.166570017395;4699.812007519695;4748.050389922029;81988.18011819865;82019.9999999994;84079.15920840774;84896.57551712202;86231.13768862294;11784.58753943608;11942.462589183508;11975.161738678209;12016.158868879269;12569.874301257054;22641.660375094387;22754.772452275596;22854.31437056891;22855.542889142456;22859.657105143437;8109.432339736162;8188.020477477749;8400.831983360298;8509.829803404022;8516.70191543293;4693.624510039189;4846.491118432514;4956.157453232959;4985.202367621163;4993.450720420751;3551.538300020981;3680.4111342185656;3682.410814269704;3683.0976410778994;3692.981535092361;588.3733823477983;596.785157343356;608.8173547935602;627.9340669229756;663.2969052804043;629.5066390185225;663.2670898657011;674.2490638196148;716.835217132655;734.7575300150937;9682.515874206261;10476.73808154787;10725;10747.946260268649;10892.292001019909;593.3532449629924;689.7792706333925;719.3597698048741;728.2571776787753;762.7521055656823;5021.397432308147;5327.893442131136;5334.466553344634;5364.275822763955;5383.461653834664;119008;122942.38528807298;126823;131916.34041829922;132449;98533;116574;118042;118259.40870295593;121696;97520;101060;101873;103146;105532;9389.859152112587;10085.394876307446;10130.594776208869;10190.745231369276;10193.796124077626;55614.82888783438;56400.71799640975;56630.43369566252;57119.14487268629;59244.70377648084;50191.24713129233;50265;67829;79810;83108.16891831245;11898.464569094585;12169.33068681231;12805.167664101804;13353.933230333787;13443.395047223094;13242.337883105998;13541.932290338485;13829.446822127406;14155.363008664428;14465.855341446872;13470.865291346961;13598.388072536562;13727.245001524612;14067.788983165055;14238.698244237987;12455.377231138587;12464.937675311565;12565.937170314455;12635;13120.934395327962;12599.87400125987;12995.545155919497;13033.47866085346;13582.660433489242;13592.184468931895;11167.683651636537;11326.546938122383;11362.204645675054;11446;11565.710857228636;11236.66290011301;11444;11509.309441433541;11967.581134660593;11977.580784672495 20:28:22 INFO - PROCESS | 5072 | __end_tp_report 20:28:22 INFO - PROCESS | 5072 | __start_cc_report 20:28:22 INFO - PROCESS | 5072 | _x_x_mozilla_cycle_collect,1143 20:28:22 INFO - PROCESS | 5072 | __end_cc_report 20:28:22 INFO - PROCESS | 5072 | __startTimestamp1470367702322__endTimestamp 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | ------- Summary: start ------- 20:28:22 INFO - PROCESS | 5072 | Number of tests: 6 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#0] dojo.html Cycles:165 Average:5289.61 Median:5471.43 stddev:597.05 (10.9%) stddev-sans-first:571.42 20:28:22 INFO - PROCESS | 5072 | Values: 3008.2 3040.4 3073.4 3087.0 3097.6 5184.4 5268.7 5295.7 5396.4 5414.3 5254.4 5284.1 5292.7 5319.3 5329.8 4760.4 4847.3 4853.8 4864.1 5009.4 4965.3 4968.9 4973.6 4976.1 4983.1 4762.5 4806.7 4832.2 4885.4 4937.5 4865.7 4877.3 4878.7 4883.8 4884.2 4785.7 4835.0 4839.6 4842.6 4938.8 5179.5 5195.9 5243.8 5283.8 5335.3 5642.4 5701.1 5706.1 5717.4 5718.5 5520.1 5567.7 5573.8 5726.9 5732.8 5271.0 5290.1 5292.6 5293.2 5293.5 5776.9 5805.4 5814.0 5816.4 5818.4 5423.6 5552.8 5582.2 5601.7 5603.6 5730.9 5760.2 5786.0 5787.7 5789.8 5715.9 5718.1 5720.4 5727.3 5730.7 5469.9 5541.8 5598.5 5619.6 5719.7 4350.3 4376.8 4382.2 4415.3 4417.3 3873.1 3888.7 3905.6 3936.2 3940.2 5276.2 5341.7 5344.9 5373.9 5376.3 5635.8 5652.6 5653.7 5684.3 5691.8 5262.4 5295.1 5306.7 5312.9 5324.3 4821.8 4885.4 4908.3 4924.6 4945.1 5960.8 5964.6 5966.8 5983.4 6000.0 5717.5 5719.1 5726.5 5730.4 5733.9 5604.3 5849.9 5886.1 5901.2 5932.8 5615.9 5659.3 5661.5 5696.9 5713.7 5744.3 5749.1 5756.2 5756.9 5759.9 5326.0 5527.1 5576.0 5624.9 5630.3 5617.4 5617.6 5622.7 5622.7 5651.8 5543.5 5548.8 5553.6 5555.3 5558.0 5405.4 5424.5 5471.4 5670.1 5670.5 5443.5 5469.3 5473.9 5487.6 5498.5 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#1] ext.html Cycles:180 Average:61082.73 Median:24158.12 stddev:116467.93 (482.1%) stddev-sans-first:116706.11 20:28:22 INFO - PROCESS | 5072 | Values: 594.7 615.5 622.1 643.1 643.6 33013.5 44428.8 45292.8 45431.5 45510.3 46665.8 47063.5 47069.5 47081.0 47130.5 9074.8 9221.8 9232.8 9233.0 9237.6 9040.4 9117.7 9130.5 9135.7 9142.5 9143.9 9188.0 9204.3 9211.9 9214.2 9129.5 9156.9 9168.1 9181.3 9185.1 9011.9 9079.6 9082.4 9084.2 9086.0 44597.3 44908.1 44922.0 44926.3 44941.6 3365.2 3962.8 4219.2 4235.4 4387.2 32742.0 33742.7 33847.2 33963.3 34339.0 4287.6 4372.6 4420.4 4435.3 4511.5 368298.0 370662.3 371366.1 372631.0 373212.0 60579.4 60806.7 60848.7 60872.7 60940.0 63972.4 64225.0 64289.4 64415.7 64440.0 15852.1 16070.1 16077.0 16083.7 16089.1 12326.3 12359.4 12404.3 12415.0 12436.2 13880.7 17429.6 17435.7 17492.0 17526.7 6236.8 6348.5 6433.5 6583.9 6587.2 1028.5 1044.4 1063.7 1116.7 1191.1 32471.0 32549.7 32765.5 32835.8 33172.0 1125.0 1153.9 1182.6 1222.8 1232.0 17048.0 17203.0 17303.6 17364.7 17534.2 526092.0 530624.0 531296.0 531580.0 532972.0 61701.7 61705.7 61817.4 61837.4 61879.1 1221.9 1287.6 1317.7 1345.7 1348.5 30432.8 35202.3 35249.8 35290.0 35624.8 52531.5 53160.0 53194.7 53229.7 53342.7 401793.0 403633.0 403746.0 404284.0 404527.0 42491.6 43089.0 43723.0 43929.0 43995.6 49382.0 49834.6 49844.0 50464.2 51573.0 41990.4 42824.8 43602.1 44070.6 44815.0 42757.0 43379.8 43452.8 43592.8 43703.0 43209.8 43436.8 43929.0 43983.6 44169.0 35447.1 37387.4 37401.8 38475.4 38722.8 28957.6 29017.9 29250.4 29351.9 29507.3 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#2] jquery.html Cycles:180 Average:30129.36 Median:13162.39 stddev:85603.91 (650.4%) stddev-sans-first:85814.88 20:28:22 INFO - PROCESS | 5072 | Values: 365.1 381.5 389.3 400.9 418.4 16829.2 19126.7 19417.3 20206.3 20209.5 18856.2 19049.3 19506.6 19809.3 19940.4 5793.7 5992.4 6000.3 6148.2 6637.7 6702.2 6712.8 6719.1 6720.5 6732.4 6442.9 6590.8 6603.3 6619.0 6623.6 6422.2 6588.1 6641.3 6684.0 6688.0 5829.2 5830.4 5832.3 5832.8 5840.4 24113.6 24233.3 24334.0 24506.1 24641.1 2157.2 2165.5 2176.5 2200.4 2211.1 14563.4 15044.8 15382.9 15392.8 15985.5 1889.6 1927.9 1929.2 1964.5 1997.4 72974.0 74480.3 76328.6 77871.6 78504.2 11083.9 11090.7 11210.6 11254.8 11416.7 15301.8 15341.5 15379.0 15396.9 15400.7 7946.4 8026.5 8119.3 8258.6 8293.2 7619.2 7656.9 7758.3 7834.7 7890.5 4718.8 4763.0 4790.9 4857.8 4859.9 1954.1 2017.2 2026.0 2027.6 2056.9 927.6 1024.7 1080.6 1087.2 1110.3 19916.4 20105.5 20493.0 20530.5 20643.6 973.9 996.0 1008.8 1013.6 1041.5 10435.8 10644.8 10962.8 11432.4 11444.2 501390.0 508937.0 528990.0 532106.0 532196.0 84471.0 92620.5 94200.0 94889.4 95659.9 999.1 1042.7 1050.3 1054.8 1058.0 17176.0 17716.0 18614.3 18634.2 18684.9 32282.0 32679.3 43039.6 43217.6 44463.6 58787.7 60443.4 61422.3 61679.0 62841.0 7803.1 8427.9 8485.2 8759.3 8774.3 12825.5 13290.8 13532.1 13832.3 13845.7 11759.4 11944.3 12138.6 12171.0 12447.6 11673.8 11783.8 11832.8 12135.9 12375.1 10895.9 11772.6 12170.1 12270.1 12272.3 8735.3 8829.4 8921.9 9236.2 9257.6 11283.4 12176.4 12261.1 12326.8 12357.0 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#3] mootools.html Cycles:180 Average:5869.04 Median:4730.11 stddev:3399.34 (71.9%) stddev-sans-first:3391.91 20:28:22 INFO - PROCESS | 5072 | Values: 1350.7 1455.3 1506.8 1652.6 1795.4 8442.9 8852.5 8852.9 9145.9 9147.7 8978.9 9078.0 9100.5 9109.4 9112.9 2392.9 2421.7 2421.9 2423.3 2424.1 2317.9 2337.4 2367.9 2372.6 2372.7 2367.3 2377.6 2385.6 2400.8 2401.8 2278.4 2293.8 2297.7 2300.2 2322.2 2300.7 2322.1 2326.5 2331.6 2333.3 8769.2 8985.1 9059.3 9099.4 9111.4 5038.2 5252.4 5259.0 5262.7 5266.7 3447.6 3474.7 3518.6 3553.6 3556.5 1125.2 1127.6 1139.3 1143.2 1153.1 12652.9 12656.1 12697.9 12731.3 12731.7 4265.4 4352.5 4487.8 4501.6 4513.3 9909.8 9921.5 9948.0 9948.1 9954.4 5070.6 5081.4 5090.0 5091.8 5093.7 4151.8 4589.3 4722.8 4724.8 4735.5 3686.7 3746.1 3811.6 3848.4 3850.3 1078.4 1084.8 1088.0 1090.7 1097.4 845.4 923.6 937.9 939.6 986.1 6091.9 6139.6 6244.8 6390.4 6481.3 931.9 933.2 937.7 1003.3 1023.5 4098.7 4172.7 4296.0 4332.8 4361.1 11241.4 11668.9 11677.9 11678.8 11697.5 11548.1 11595.9 11604.7 11614.4 11647.7 7969.9 8148.3 8269.7 8473.3 8484.1 6119.6 6268.9 6329.1 6359.7 6403.9 10082.6 10277.9 10358.9 10395.6 10495.5 10476.7 10491.5 10562.3 10565.5 10596.8 7589.7 7660.5 7743.7 7762.3 7813.1 8231.8 8317.7 8323.5 8330.7 8354.2 7318.3 7491.5 7580.4 7608.6 7683.3 7131.3 7431.9 7448.3 7467.1 7665.9 6732.2 6880.6 6887.1 6888.4 6913.8 5100.5 5104.3 5121.9 5257.4 5452.9 5330.1 5414.2 5425.3 5480.2 5484.6 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#4] prototype.html Cycles:180 Average:4494.01 Median:4988.60 stddev:1292.81 (25.9%) stddev-sans-first:1257.39 20:28:22 INFO - PROCESS | 5072 | Values: 292.7 308.0 308.4 331.5 331.9 4923.0 5280.6 5302.5 5322.1 5354.3 5138.7 5153.1 5169.3 5231.2 5244.8 4094.5 4112.6 4115.6 4130.0 4132.0 4080.9 4139.8 4155.2 4168.0 4176.3 4097.5 4106.6 4122.3 4128.1 4151.1 4114.4 4115.7 4128.0 4133.0 4136.6 3644.7 3648.4 3661.2 3664.3 3675.6 5009.5 5159.6 5169.6 5180.9 5195.3 4667.2 4699.1 4713.7 4717.3 4723.2 5390.8 5412.7 5475.5 5476.4 5480.8 3829.7 3960.4 3965.8 3967.4 3971.2 5331.0 5478.9 5631.5 5641.0 5648.0 4076.3 4079.7 4080.5 4092.8 4100.3 3902.9 4076.9 4152.9 4158.1 4159.3 4661.6 4678.8 4683.4 4689.0 4708.5 5460.8 5473.2 5511.7 5545.4 5546.0 1349.9 1380.6 1382.0 1395.8 1398.3 928.2 950.4 962.6 971.4 975.3 4712.5 4717.4 4724.5 4734.6 4764.0 4959.4 4975.2 4986.5 4990.7 5007.6 4499.4 4662.9 4674.3 4680.6 4682.8 2632.8 2635.4 2638.7 2650.7 2657.1 5143.3 5147.6 5153.6 5158.3 5173.9 4827.4 4950.7 4974.7 4993.4 5120.4 5064.6 5095.7 5107.3 5125.2 5137.6 4937.6 4938.7 4947.4 5002.0 5018.2 4816.0 5254.8 5256.4 5536.4 5541.4 5728.8 5750.6 5752.9 5757.5 5770.0 4104.3 4128.6 4132.2 4140.2 4142.2 5388.0 5588.0 5627.0 5677.5 5688.5 5538.9 5543.9 5550.8 5551.3 5605.0 5217.5 5457.2 5489.7 5502.3 5582.4 5416.3 5420.5 5431.9 5446.6 5463.4 5597.4 5598.4 5605.4 5623.1 5631.5 5142.5 5404.5 5421.4 5446.4 5495.0 20:28:22 INFO - PROCESS | 5072 | 20:28:22 INFO - PROCESS | 5072 | [#5] yui.html Cycles:180 Average:22371.13 Median:13205.60 stddev:33371.63 (252.7%) stddev-sans-first:33425.77 20:28:22 INFO - PROCESS | 5072 | Values: 753.9 786.6 800.4 829.9 847.6 15748.0 16845.4 16846.4 16890.7 16935.9 15799.0 15931.9 15943.6 15956.4 15962.8 659.3 796.9 806.4 810.7 824.7 801.7 802.1 808.9 813.0 813.2 860.1 860.3 860.5 861.2 861.2 690.1 831.1 843.4 850.5 852.9 623.0 626.1 626.6 627.0 627.1 15930.0 15936.4 15974.3 15981.5 15984.3 14616.6 14773.3 14953.3 15009.6 15131.2 13474.3 13543.3 13648.4 13780.7 14057.6 4530.7 4535.3 4630.2 4699.8 4748.1 81988.2 82020.0 84079.2 84896.6 86231.1 11784.6 11942.5 11975.2 12016.2 12569.9 22641.7 22754.8 22854.3 22855.5 22859.7 8109.4 8188.0 8400.8 8509.8 8516.7 4693.6 4846.5 4956.2 4985.2 4993.5 3551.5 3680.4 3682.4 3683.1 3693.0 588.4 596.8 608.8 627.9 663.3 629.5 663.3 674.2 716.8 734.8 9682.5 10476.7 10725.0 10747.9 10892.3 593.4 689.8 719.4 728.3 762.8 5021.4 5327.9 5334.5 5364.3 5383.5 119008.0 122942.4 126823.0 131916.3 132449.0 98533.0 116574.0 118042.0 118259.4 121696.0 97520.0 101060.0 101873.0 103146.0 105532.0 9389.9 10085.4 10130.6 10190.7 10193.8 55614.8 56400.7 56630.4 57119.1 59244.7 50191.2 50265.0 67829.0 79810.0 83108.2 11898.5 12169.3 12805.2 13353.9 13443.4 13242.3 13541.9 13829.4 14155.4 14465.9 13470.9 13598.4 13727.2 14067.8 14238.7 12455.4 12464.9 12565.9 12635.0 13120.9 12599.9 12995.5 13033.5 13582.7 13592.2 11167.7 11326.5 11362.2 11446.0 11565.7 11236.7 11444.0 11509.3 11967.6 11977.6 20:28:22 INFO - PROCESS | 5072 | -------- Summary: end -------- 20:28:22 INFO - PROCESS | 5072 | 20:28:23 INFO - TEST-INFO | 5072: exit 0 20:28:23 INFO - TEST-OK | dromaeo_css | took 1115385ms 20:28:23 INFO - TEST-START | kraken 20:28:23 INFO - operating with platform_type : linux_ 20:28:23 INFO - Initialising browser for kraken test... 20:28:23 INFO - TEST-INFO | started process 5261 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsAgxGg/profile http://localhost:56231/getInfo.html) 20:28:27 INFO - PROCESS | 5261 | __metrics Screen width/height:1600/1200 20:28:27 INFO - PROCESS | 5261 | colorDepth:24 20:28:27 INFO - PROCESS | 5261 | Browser inner width/height: 1024/697 20:28:27 INFO - PROCESS | 5261 | __metrics 20:28:28 INFO - PROCESS | 5261 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:28:28 INFO - PROCESS | 5261 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:28:28 INFO - PROCESS | 5261 | console.error: PushService: 20:28:28 INFO - PROCESS | 5261 | stateChangeProcessEnqueue: Error transitioning state 20:28:28 INFO - PROCESS | 5261 | UnknownError 20:28:29 INFO - TEST-INFO | 5261: exit 0 20:28:29 INFO - Browser initialized. 20:28:29 INFO - Running cycle 1/1 for kraken test... 20:28:29 INFO - TEST-INFO | started process 5348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsAgxGg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:41 INFO - PROCESS | 5348 | RSS: Main: 156446720 20:28:41 INFO - PROCESS | 5348 | 20:28:43 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56231/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:44 INFO - PROCESS | 5348 | RSS: Main: 212471808 20:28:44 INFO - PROCESS | 5348 | 20:28:47 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56231/tests/kraken/driver.html?testName=audio-dft) 20:28:47 INFO - PROCESS | 5348 | RSS: Main: 191500288 20:28:47 INFO - PROCESS | 5348 | 20:28:50 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56231/tests/kraken/driver.html?testName=audio-fft) 20:28:51 INFO - PROCESS | 5348 | RSS: Main: 269393920 20:28:51 INFO - PROCESS | 5348 | 20:28:53 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56231/tests/kraken/driver.html?testName=audio-oscillator) 20:28:54 INFO - PROCESS | 5348 | RSS: Main: 179982336 20:28:54 INFO - PROCESS | 5348 | 20:28:56 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:56 INFO - PROCESS | 5348 | RSS: Main: 184446976 20:28:56 INFO - PROCESS | 5348 | 20:28:59 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56231/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:00 INFO - PROCESS | 5348 | RSS: Main: 219680768 20:29:00 INFO - PROCESS | 5348 | 20:29:03 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56231/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:04 INFO - PROCESS | 5348 | RSS: Main: 220258304 20:29:04 INFO - PROCESS | 5348 | 20:29:07 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56231/tests/kraken/driver.html?testName=json-parse-financial) 20:29:07 INFO - PROCESS | 5348 | RSS: Main: 219586560 20:29:07 INFO - PROCESS | 5348 | 20:29:09 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:29:09 INFO - PROCESS | 5348 | RSS: Main: 184029184 20:29:09 INFO - PROCESS | 5348 | 20:29:11 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:29:12 INFO - PROCESS | 5348 | RSS: Main: 189100032 20:29:12 INFO - PROCESS | 5348 | 20:29:14 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:29:14 INFO - PROCESS | 5348 | RSS: Main: 194211840 20:29:14 INFO - PROCESS | 5348 | 20:29:17 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:29:17 INFO - PROCESS | 5348 | RSS: Main: 195162112 20:29:17 INFO - PROCESS | 5348 | 20:29:20 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:21 INFO - PROCESS | 5348 | RSS: Main: 195436544 20:29:21 INFO - PROCESS | 5348 | 20:29:22 INFO - PROCESS | 5348 | Cycle 1(1): loaded http://localhost:56231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56231/tests/kraken/driver.html?testName=ai-astar) 20:29:23 INFO - PROCESS | 5348 | RSS: Main: 243240960 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | __start_tp_report 20:29:23 INFO - PROCESS | 5348 | _x_x_mozilla_page_load 20:29:23 INFO - PROCESS | 5348 | _x_x_mozilla_page_load_details 20:29:23 INFO - PROCESS | 5348 | |i|pagename|runs| 20:29:23 INFO - PROCESS | 5348 | |0;ai-astar;103;111;111;110;111;111;110;113;110;112 20:29:23 INFO - PROCESS | 5348 | |1;audio-beat-detection;144;118;118;179;121;121;141;118;152;117 20:29:23 INFO - PROCESS | 5348 | |2;audio-dft;160;161;162;160;159;159;160;163;159;160 20:29:23 INFO - PROCESS | 5348 | |3;audio-fft;74;75;74;112;75;75;74;74;98;74 20:29:23 INFO - PROCESS | 5348 | |4;audio-oscillator;84;84;112;84;85;85;129;85;84;126 20:29:23 INFO - PROCESS | 5348 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;117;117;117 20:29:23 INFO - PROCESS | 5348 | |6;imaging-darkroom;146;145;146;146;145;146;148;145;146;146 20:29:23 INFO - PROCESS | 5348 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;97 20:29:23 INFO - PROCESS | 5348 | |8;json-parse-financial;50;49;49;50;49;50;50;49;50;49 20:29:23 INFO - PROCESS | 5348 | |9;json-stringify-tinderbox;60;61;59;59;59;60;59;59;60;59 20:29:23 INFO - PROCESS | 5348 | |10;stanford-crypto-aes;80;74;84;77;85;78;75;76;75;75 20:29:23 INFO - PROCESS | 5348 | |11;stanford-crypto-ccm;126;132;118;134;120;121;121;120;125;127 20:29:23 INFO - PROCESS | 5348 | |12;stanford-crypto-pbkdf2;161;159;157;154;154;154;154;156;156;155 20:29:23 INFO - PROCESS | 5348 | |13;stanford-crypto-sha256-iterative;65;63;65;64;65;62;62;63;63;62 20:29:23 INFO - PROCESS | 5348 | __end_tp_report 20:29:23 INFO - PROCESS | 5348 | __start_cc_report 20:29:23 INFO - PROCESS | 5348 | _x_x_mozilla_cycle_collect,1633 20:29:23 INFO - PROCESS | 5348 | __end_cc_report 20:29:23 INFO - PROCESS | 5348 | __startTimestamp1470367763063__endTimestamp 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | ------- Summary: start ------- 20:29:23 INFO - PROCESS | 5348 | Number of tests: 14 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:2.70 (2.4%) stddev-sans-first:1.00 20:29:23 INFO - PROCESS | 5348 | Values: 103.0 111.0 111.0 110.0 111.0 111.0 110.0 113.0 110.0 112.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:131.00 stddev:20.77 (15.9%) stddev-sans-first:21.63 20:29:23 INFO - PROCESS | 5348 | Values: 144.0 118.0 118.0 179.0 121.0 121.0 141.0 118.0 152.0 117.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.34 (0.8%) stddev-sans-first:1.41 20:29:23 INFO - PROCESS | 5348 | Values: 160.0 161.0 162.0 160.0 159.0 159.0 160.0 163.0 159.0 160.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#3] audio-fft Cycles:10 Average:80.50 Median:74.50 stddev:13.34 (17.9%) stddev-sans-first:13.94 20:29:23 INFO - PROCESS | 5348 | Values: 74.0 75.0 74.0 112.0 75.0 75.0 74.0 74.0 98.0 74.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:84.00 stddev:18.81 (22.4%) stddev-sans-first:19.46 20:29:23 INFO - PROCESS | 5348 | Values: 84.0 84.0 112.0 84.0 85.0 85.0 129.0 85.0 84.0 126.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:29:23 INFO - PROCESS | 5348 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 20:29:23 INFO - PROCESS | 5348 | Values: 146.0 145.0 146.0 146.0 145.0 146.0 148.0 145.0 146.0 146.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 20:29:23 INFO - PROCESS | 5348 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 97.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:0.53 (1.1%) stddev-sans-first:0.53 20:29:23 INFO - PROCESS | 5348 | Values: 50.0 49.0 49.0 50.0 49.0 50.0 50.0 49.0 50.0 49.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 20:29:23 INFO - PROCESS | 5348 | Values: 60.0 61.0 59.0 59.0 59.0 60.0 59.0 59.0 60.0 59.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:77.50 stddev:3.90 (5.0%) stddev-sans-first:4.06 20:29:23 INFO - PROCESS | 5348 | Values: 80.0 74.0 84.0 77.0 85.0 78.0 75.0 76.0 75.0 75.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:125.50 stddev:5.40 (4.3%) stddev-sans-first:5.70 20:29:23 INFO - PROCESS | 5348 | Values: 126.0 132.0 118.0 134.0 120.0 121.0 121.0 120.0 125.0 127.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:156.00 stddev:2.40 (1.5%) stddev-sans-first:1.74 20:29:23 INFO - PROCESS | 5348 | Values: 161.0 159.0 157.0 154.0 154.0 154.0 154.0 156.0 156.0 155.0 20:29:23 INFO - PROCESS | 5348 | 20:29:23 INFO - PROCESS | 5348 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.26 (2.0%) stddev-sans-first:1.20 20:29:23 INFO - PROCESS | 5348 | Values: 65.0 63.0 65.0 64.0 65.0 62.0 62.0 63.0 63.0 62.0 20:29:23 INFO - PROCESS | 5348 | -------- Summary: end -------- 20:29:23 INFO - PROCESS | 5348 | 20:29:24 INFO - TEST-INFO | 5348: exit 0 20:29:24 INFO - TEST-OK | kraken | took 61030ms 20:29:24 INFO - SUITE-END | took 1176s 20:29:24 INFO - Completed test suite (00:19:36) 20:29:24 INFO - javascript benchmark 20:29:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3008.18778929689, 3040.3919216156773, 3073.3699591583727, 3087.012156110044, 3097.581826453428, 5184.429712731606, 5268.736563171845, 5295.682259064454, 5396.433374495676, 5414.269073675057, 5254.395744489385, 5284.128118860393, 5292.682439053649, 5319.255304257407, 5329.840104796862, 4760.428748550179, 4847.345608342882, 4853.781579828917, 4864.0515099555605, 5009.423916249623, 4965.3296804931415, 4968.900621987558, 4973.552380285773, 4976.079425306312, 4983.053219888227, 4762.499937506581, 4806.687565308243, 4832.202686556714, 4885.413750349945, 4937.456879743226, 4865.732384718839, 4877.268409738532, 4878.658493905428, 4883.780229889663, 4884.218525036013, 4785.66500344976, 4834.975825120852, 4839.637027222937, 4842.612590992712, 4938.827141050082, 5179.507946745053, 5195.922061169085, 5243.79024839006, 5283.841484755463, 5335.33308336458, 5642.4075472075665, 5701.14482827575, 5706.087026075808, 5717.399673034313, 5718.485336319751, 5520.1443776214755, 5567.749451274703, 5573.832785016456, 5726.942730572724, 5732.799352022659, 5270.973645131827, 5290.100682883919, 5292.603054770831, 5293.153095504777, 5293.470652934714, 5776.942230577724, 5805.36141024487, 5814.011618024946, 5816.360200377955, 5818.44724751148, 5423.647462914898, 5552.7778888843995, 5582.24639673647, 5601.719914004365, 5603.551715862721, 5730.914036289459, 5760.193572899798, 5786.0, 5787.71061446926, 5789.768409263666, 5715.914261286084, 5718.085106382959, 5720.399358067343, 5727.341355744143, 5730.684812335278, 5469.890602188014, 5541.833744987657, 5598.524125449382, 5619.550435965113, 5719.685417302005, 4350.347447882843, 4376.824927002948, 4382.23310920594, 4415.2935530315, 4417.315316126005, 3873.070463088895, 3888.7083468739957, 3905.5899130591447, 3936.1537269486626, 3940.2119576084897, 5276.208568714724, 5341.679499230058, 5344.8663783404945, 5373.919391209135, 5376.301080859464, 5635.774569017193, 5652.63257888236, 5653.660780353192, 5684.317881854204, 5691.800786972436, 5262.447443018505, 5295.12630415977, 5306.681599104066, 5312.867178320573, 5324.315466436257, 4821.831235906727, 4885.389326334208, 4908.312836202935, 4924.556789888928, 4945.109880221523, 5960.76156953717, 5964.5526585505395, 5966.76132954677, 5983.401659833982, 5999.970000149971, 5717.514011309086, 5719.113537401709, 5726.484616384545, 5730.398308177581, 5733.885322293614, 5604.271444712324, 5849.853753656193, 5886.117082437669, 5901.203337549376, 5932.822015339547, 5615.859603509945, 5659.292588426447, 5661.490465858093, 5696.943030569641, 5713.714314284352, 5744.310682718101, 5749.051406517878, 5756.1941328214925, 5756.884862302814, 5759.94240057594, 5325.973370133124, 5527.088030474926, 5575.972120139373, 5624.887502250014, 5630.324361076697, 5617.382087970402, 5617.578681598815, 5622.718864056863, 5622.718864056863, 5651.773929042956, 5543.473370030002, 5548.750306236148, 5553.555715542666, 5555.30558680165, 5557.972210138923, 5405.405405405481, 5424.511793938406, 5471.425500322409, 5670.121131224667, 5670.489655930986, 5443.455654434525, 5469.316335458067, 5473.890522189614, 5487.6158668892795, 5498.505134537911], "value": 5249.440876419713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.7145370222294, 615.4799194680494, 622.1321256846702, 643.1092936283246, 643.5623775832432, 33013.504797428024, 44428.77785611071, 45292.773536132314, 45431.54568454323, 45510.31734523977, 46665.766671166595, 47063.529364706344, 47069.52930470694, 47081.0, 47130.528694713044, 9074.773130671736, 9221.769455763593, 9232.769180770467, 9233.0, 9237.584308706124, 9040.412373195753, 9117.68088116916, 9130.452172869649, 9135.725928222164, 9142.451452912814, 9143.908560914406, 9187.954060229722, 9204.263658907304, 9211.861822072675, 9214.170724634781, 9129.497877616728, 9156.908430915671, 9168.083191680846, 9181.265498760116, 9185.127412895765, 9011.909880901172, 9079.591418386188, 9082.364234503588, 9084.227840633554, 9086.0, 44597.33104003443, 44908.101837963055, 44922.0, 44926.32610510845, 44941.55058449406, 3365.209175843675, 3962.841486340543, 4219.156168766228, 4235.407043013981, 4387.232234358974, 32742.0, 33742.6625733742, 33847.153821154425, 33963.32073358519, 34339.0, 4287.571242875719, 4372.584604462571, 4420.359047938031, 4435.3125265583885, 4511.503734589192, 368298.0, 370662.32019559183, 371366.14316928515, 372631.0, 373212.0, 60579.394206058256, 60806.69596652077, 60848.69575652093, 60872.69563652154, 60940.0, 63972.36027639757, 64225.0, 64289.35710642834, 64415.67792161009, 64440.0, 15852.128132952568, 16070.11614361198, 16077.0, 16083.678326433639, 16089.115098669685, 12326.260424374566, 12359.443825027894, 12404.317762523147, 12415.0, 12436.19164754288, 13880.722385552232, 17429.564260893327, 17435.65128697419, 17492.0, 17526.737098943526, 6236.812895613139, 6348.492120630339, 6433.485321174295, 6583.9012414813815, 6587.209534855752, 1028.4675833008912, 1044.3785947361307, 1063.7340664833791, 1116.6984914073155, 1191.1007189571817, 32471.0, 32549.674503255137, 32765.50851737226, 32835.83582082074, 33172.0, 1125.0268517732102, 1153.8595904597018, 1182.5742732616252, 1222.8043513037871, 1231.950721971111, 17048.0, 17203.0, 17303.56741081483, 17364.65270694579, 17534.210960506807, 526092.0, 530624.0, 531296.0, 531580.0, 532972.0, 61701.691491543155, 61705.691471542355, 61817.38182618116, 61837.38162618406, 61879.07181392283, 1221.9205751626293, 1287.594407761552, 1317.736452709462, 1345.6770375109902, 1348.494314632013, 30432.84783576068, 35202.29595408129, 35249.823750882104, 35290.0, 35624.821875890455, 52531.47468525419, 53160.0, 53194.73402632962, 53229.73385133049, 53342.73328633487, 401793.0, 403633.0, 403746.0, 404284.0, 404527.0, 42491.57508424876, 43089.0, 43723.0, 43929.0, 43995.56004440042, 49382.0, 49834.559189937776, 49844.0, 50464.24303635375, 51573.0, 41990.370144447246, 42824.78587607042, 43602.12795744004, 44070.559294406645, 44815.0, 42757.0, 43379.78310108429, 43452.78273608612, 43592.78203609089, 43703.0, 43209.78395108004, 43436.78281608572, 43929.0, 43983.560164397946, 44169.0, 35447.11382215465, 37387.43918841165, 37401.81299093487, 38475.42286865755, 38722.80638596789, 28957.56563651589, 29017.854910725313, 29250.41499169962, 29351.853240734516, 29507.262318442212], "value": 20845.20321097309, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.1145971020275, 381.4907099022806, 389.287603685256, 400.88173988673344, 418.43093392985537, 16829.15854207289, 19126.71309930352, 19417.3203937862, 20206.29277975271, 20209.494762630904, 18856.245750169976, 19049.333273335436, 19506.609867802672, 19809.30667426637, 19940.40178794635, 5793.739281732311, 5992.43071908168, 6000.279966404038, 6148.169997050386, 6637.701303441332, 6702.229243636971, 6712.83217919551, 6719.092922455479, 6720.495962802785, 6732.360425759545, 6442.871142577145, 6590.8022759317055, 6603.273639899608, 6618.966905165492, 6623.6025838449605, 6422.197225346832, 6588.143541339643, 6641.269460359356, 6683.966580167116, 6687.966560167192, 5829.183914252008, 5830.3586605473365, 5832.270966129234, 5832.795852145155, 5840.415958404125, 24113.638295425582, 24233.273001809797, 24334.0, 24506.142285019938, 24641.137560185485, 2157.2018353209255, 2165.523584811339, 2176.5320456101854, 2200.4278887489368, 2211.148707747529, 14563.417463301455, 15044.774328384974, 15382.923085384613, 15392.769108463383, 15985.52043438687, 1889.6031833315021, 1927.942161735136, 1929.170456703617, 1964.4695932098252, 1997.3907958072782, 72974.0, 74480.25519744841, 76328.61835690786, 77871.61064194699, 78504.21495785026, 11083.944580277048, 11090.667279981451, 11210.607628732956, 11254.774904501865, 11416.657500275005, 15301.770473442908, 15341.539753807403, 15379.000364976464, 15396.923015385075, 15400.691986160215, 7946.404019698546, 8026.518408895485, 8119.309858661962, 8258.628361723737, 8293.21214484623, 7619.1999840016, 7656.885146722803, 7758.262965018314, 7834.7257845976255, 7890.526568405914, 4718.787654555554, 4762.9761851191215, 4790.856274311776, 4857.765427903133, 4859.902801943941, 1954.130411966661, 2017.243533674872, 2025.9797402026086, 2027.6248893954667, 2056.8663036902553, 927.5594092805864, 1024.6669832304383, 1080.5736427915233, 1087.1846115413439, 1110.2672236323988, 19916.402507924784, 20105.49736256576, 20493.0, 20530.486737831674, 20643.58712825735, 973.9172170365457, 995.9900400996042, 1008.8335424654997, 1013.6198925402974, 1041.4688508860536, 10435.843462348072, 10644.787104257872, 10962.780744385067, 11432.428378581204, 11444.198906076661, 501390.0, 508937.0, 528990.0, 532106.0, 532196.0, 84471.0, 92620.5074522703, 94200.0, 94889.42631329571, 95659.9123083306, 999.1307562420595, 1042.6559235452435, 1050.3382868792612, 1054.8101341758404, 1057.984130238047, 17175.96944183303, 17716.0, 18614.2554297832, 18634.16146273448, 18684.906575467037, 32282.0, 32679.346413072082, 43039.56960430356, 43217.567824321355, 44463.55536444724, 58787.70606146942, 60443.39556604553, 61422.31466112048, 61679.0, 62841.0, 7803.102643196105, 8427.915720842713, 8485.236328730198, 8759.255463285437, 8774.34192435557, 12825.525064617688, 13290.800637990631, 13532.052756306965, 13832.308384580932, 13845.653858653613, 11759.412029398667, 11944.343061131374, 12138.575149869712, 12170.991786355506, 12447.564335248222, 11673.766524669629, 11783.823242651539, 11832.763344732884, 12135.939320303341, 12375.133740638068, 10895.891041089488, 11772.587959421378, 12170.087243456599, 12270.141090123605, 12272.263664180178, 8735.257503112052, 8829.426087304304, 8921.910780892367, 9236.168744813, 9257.583408746757, 11283.379414131974, 12176.391180440765, 12261.080418968435, 12326.75346493083, 12357.0], "value": 9573.478981386066, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.716349566591, 1455.2505459688257, 1506.8493150684933, 1652.6281586643006, 1795.3895675470346, 8442.91557084429, 8852.513111778857, 8852.911470885281, 9145.908540914588, 9147.725568232947, 8978.865317020234, 9077.95461022694, 9100.54497275137, 9109.362344635878, 9112.8633070504, 2392.9162479313272, 2421.733609302984, 2421.9394515137174, 2423.273018094573, 2424.054618698709, 2317.918872839451, 2337.4273303040695, 2367.9408014799596, 2372.5847976604027, 2372.6559648850907, 2367.3016460144213, 2377.5720370333333, 2385.60637494814, 2400.7959323457526, 2401.819863510235, 2278.362058623588, 2293.7935585797277, 2297.689811875392, 2300.229423143249, 2322.2336628912417, 2300.7124109486313, 2322.11759531377, 2326.4649130700036, 2331.603627383332, 2333.300009996994, 8769.210771030574, 8985.146411090936, 9059.275257979345, 9099.363044586882, 9111.362204645677, 5038.244263360489, 5252.422233554306, 5259.000789849916, 5262.710550919736, 5266.657667251617, 3447.5692587576377, 3474.722022238215, 3518.6481351864863, 3553.626869178751, 3556.5376501054698, 1125.217973508412, 1127.6053381316556, 1139.3221033485063, 1143.2340331977512, 1153.129387312582, 12652.873471265262, 12656.050796190322, 12697.873021269761, 12731.299778512179, 12731.681707957283, 4265.3601959705675, 4352.499462561783, 4487.798049087799, 4501.636640763625, 4513.255312873404, 9909.752256193653, 9921.454320012468, 9947.950260248797, 9948.054934781185, 9954.352967057119, 5070.569001634903, 5081.364829396325, 5089.9745501273, 5091.847244582668, 5093.694378337312, 4151.771652559078, 4589.311603259471, 4722.787474563653, 4724.763761811896, 4735.455422626374, 3686.668199862025, 3746.0822098586023, 3811.599782022903, 3848.365019771706, 3850.3261929162286, 1078.3907092492873, 1084.8318510630866, 1088.0, 1090.7164137324194, 1097.401915955806, 845.3744229270295, 923.5936188077225, 937.8593211018265, 939.5959737313021, 986.0829428631298, 6091.939080609226, 6139.631622102688, 6244.843878903063, 6390.392912673288, 6481.254655714653, 931.8928323242778, 933.2021121940778, 937.6858752317913, 1003.2726273451689, 1023.4984857419811, 4098.672106231495, 4172.687048471377, 4295.978520107379, 4332.783360832009, 4361.127774445124, 11241.437928103562, 11668.88331116695, 11677.941610291895, 11678.824817627572, 11697.532098715956, 11548.076153907667, 11595.884041159648, 11604.709882252842, 11614.419279036014, 11647.6505704829, 7969.920300797034, 8148.266656000988, 8269.710560130367, 8473.279771219391, 8484.066752657203, 6119.632822030514, 6268.937310627018, 6329.082283068838, 6359.713812878525, 6403.879321118692, 10082.647107351208, 10277.897221027693, 10358.896411035794, 10395.58417663285, 10495.475226238505, 10476.68569942903, 10491.475426228506, 10562.260641755003, 10565.524551395052, 10596.788064238826, 7589.696412143452, 7660.502067365604, 7743.728969486041, 7762.340201082971, 7813.14055453911, 8231.794205144679, 8317.708880189164, 8323.458975166595, 8330.666773329, 8354.16458354184, 7318.34134927859, 7491.513051651626, 7580.393568514616, 7608.581528016012, 7683.346915512244, 7131.322524360177, 7431.925680743123, 7448.292412220831, 7467.141278753013, 7665.885011724717, 6732.225794033749, 6880.621565813928, 6887.104676392037, 6888.448924086161, 6913.827154320981, 5100.464451232716, 5104.285400043923, 5121.8719532012, 5257.369115705987, 5452.945470545244, 5330.0938840398, 5414.187871819101, 5425.348958125051, 5480.150576660624, 5484.561235101102], "value": 4660.539139678736, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.67074541141216, 307.9568860359549, 308.39246684032446, 331.47626749735423, 331.9186799234188, 4923.0, 5280.630355875091, 5302.522772950439, 5322.148456247001, 5354.303940487731, 5138.743062846861, 5153.1239689252825, 5169.30214421053, 5231.24146998685, 5244.842654720355, 4094.488188976378, 4112.6298633123015, 4115.629593336599, 4129.979350103245, 4132.028973191313, 4080.8979775505554, 4139.834406623747, 4155.168966206756, 4167.9583204167875, 4176.331786914095, 4097.549269580344, 4106.630403263705, 4122.299209134454, 4128.091819799639, 4151.12826306476, 4114.444549985744, 4115.73247738896, 4127.977291127326, 4132.97933510332, 4136.607022332873, 3644.671979521856, 3648.41625339947, 3661.2494438640015, 3664.3221004114057, 3675.5773086095173, 5009.499050094998, 5159.5614372778355, 5169.56058735008, 5180.922286165711, 5195.298634684307, 4667.183242932473, 4699.060187962421, 4713.717176969393, 4717.315989181584, 4723.173444647207, 5390.838274851761, 5412.675239485643, 5475.479829416239, 5476.397596264408, 5480.80817171401, 3829.740453132665, 3960.386140148282, 3965.8017099144927, 3967.4048892666333, 3971.205758848242, 5330.973345133289, 5478.91781623276, 5631.549476041907, 5641.0, 5648.0, 4076.30702780528, 4079.73481723687, 4080.5307389649975, 4092.7953602319767, 4100.261952848516, 3902.902427439337, 4076.857309994136, 4152.896177595584, 4158.147579746144, 4159.292920203573, 4661.603763680125, 4678.789454474489, 4683.367745354333, 4689.0, 4708.529147085264, 5460.781568737286, 5473.2063850742215, 5511.669299842022, 5545.362283337387, 5545.9722701387045, 1349.8785109340206, 1380.5858242527202, 1382.0, 1395.8115654386731, 1398.3497673581833, 928.2388441477923, 950.3890904916238, 962.5764663548016, 971.3637567393368, 975.2734213011211, 4712.50518695532, 4717.386739723814, 4724.527547245248, 4734.597559207437, 4763.976180119146, 4959.421101027446, 4975.228839529878, 4986.526280003394, 4990.679334109656, 5007.599392048627, 4499.380223119674, 4662.906741865144, 4674.298855171752, 4680.555347242007, 4682.789274482589, 2632.7893768498475, 2635.407033417504, 2638.656974593329, 2650.674662668666, 2657.1364306600044, 5143.277002204229, 5147.588192944556, 5153.561947234452, 5158.277841102249, 5173.870653233624, 4827.444843842933, 4950.702957822542, 4974.701517908937, 4993.400791904995, 5120.411152717411, 5064.569511591482, 5095.694258344512, 5107.259447380184, 5125.205593133071, 5137.640365174402, 4937.580305674058, 4938.654294199371, 4947.416826615448, 5001.974990125026, 5018.247262910592, 4815.95184048155, 5254.816081437131, 5256.421793602777, 5536.4186760389575, 5541.362743284574, 5728.79949201776, 5750.597458177887, 5752.856178595569, 5757.510611597893, 5770.0, 4104.261232978092, 4128.60778226068, 4132.235536425771, 4140.192662430797, 4142.233686767958, 5388.0, 5587.972060139673, 5626.971865140648, 5677.489025987681, 5688.488036076773, 5538.9169162461785, 5543.861403464945, 5550.750216240198, 5551.336118161994, 5605.0, 5217.452167522507, 5457.154141108134, 5489.698066606375, 5502.257195278587, 5582.3859375467855, 5416.2688037114485, 5420.457954204706, 5431.891362172813, 5446.645968012067, 5463.426340234377, 5597.356304025089, 5598.424724626718, 5605.383407825218, 5623.072193088258, 5631.521320687787, 5142.51146141116, 5404.513593776579, 5421.376541697755, 5446.400895901527, 5494.972525137348], "value": 4081.9173022649525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.902539696748, 786.5988345943567, 800.3557136505111, 829.8589239829229, 847.6439895243997, 15748.0, 16845.41041063563, 16846.410375636857, 16890.74663880043, 16935.915320423395, 15799.0, 15931.920340398281, 15943.601409964727, 15956.441524546643, 15962.840371596281, 659.3439527669975, 796.8964034675513, 806.358944639012, 810.7081450677751, 824.7154731617583, 801.6592947997107, 802.081616549051, 808.8705807070841, 812.9634166462494, 813.2111851504061, 860.0754189246554, 860.3246451535546, 860.5310105992218, 861.1560670542872, 861.2378045429778, 690.1063123255346, 831.1107115386538, 843.4306842881061, 850.4557083466617, 852.8720691896203, 623.0249659283207, 626.139058794158, 626.5833220908136, 626.9968650156766, 627.1032423634201, 15930.044197348196, 15936.442224522087, 15974.28115734783, 15981.52055438327, 15984.280707368196, 14616.561503154922, 14773.26133693311, 14953.252337383195, 15009.624759380993, 15131.243437828065, 13474.326283685776, 13543.322833858268, 13648.385822638005, 13780.724385512334, 14057.64855878601, 4530.728156310632, 4535.342375355588, 4630.166570017395, 4699.812007519695, 4748.050389922029, 81988.18011819865, 82019.9999999994, 84079.15920840774, 84896.57551712202, 86231.13768862294, 11784.58753943608, 11942.462589183508, 11975.161738678209, 12016.158868879269, 12569.874301257054, 22641.660375094387, 22754.772452275596, 22854.31437056891, 22855.542889142456, 22859.657105143437, 8109.432339736162, 8188.020477477749, 8400.831983360298, 8509.829803404022, 8516.70191543293, 4693.624510039189, 4846.491118432514, 4956.157453232959, 4985.202367621163, 4993.450720420751, 3551.538300020981, 3680.4111342185656, 3682.410814269704, 3683.0976410778994, 3692.981535092361, 588.3733823477983, 596.785157343356, 608.8173547935602, 627.9340669229756, 663.2969052804043, 629.5066390185225, 663.2670898657011, 674.2490638196148, 716.835217132655, 734.7575300150937, 9682.515874206261, 10476.73808154787, 10725.0, 10747.946260268649, 10892.292001019909, 593.3532449629924, 689.7792706333925, 719.3597698048741, 728.2571776787753, 762.7521055656823, 5021.397432308147, 5327.893442131136, 5334.466553344634, 5364.275822763955, 5383.461653834664, 119008.0, 122942.38528807298, 126823.0, 131916.34041829922, 132449.0, 98533.0, 116574.0, 118042.0, 118259.40870295593, 121696.0, 97520.0, 101060.0, 101873.0, 103146.0, 105532.0, 9389.859152112587, 10085.394876307446, 10130.594776208869, 10190.745231369276, 10193.796124077626, 55614.82888783438, 56400.71799640975, 56630.43369566252, 57119.14487268629, 59244.70377648084, 50191.24713129233, 50265.0, 67829.0, 79810.0, 83108.16891831245, 11898.464569094585, 12169.33068681231, 12805.167664101804, 13353.933230333787, 13443.395047223094, 13242.337883105998, 13541.932290338485, 13829.446822127406, 14155.363008664428, 14465.855341446872, 13470.865291346961, 13598.388072536562, 13727.245001524612, 14067.788983165055, 14238.698244237987, 12455.377231138587, 12464.937675311565, 12565.937170314455, 12635.0, 13120.934395327962, 12599.87400125987, 12995.545155919497, 13033.47866085346, 13582.660433489242, 13592.184468931895, 11167.683651636537, 11326.546938122383, 11362.204645675054, 11446.0, 11565.710857228636, 11236.66290011301, 11444.0, 11509.309441433541, 11967.581134660593, 11977.580784672495], "value": 7831.914460648409, "unit": "score"}], "name": "dromaeo_css", "value": 7337.830637093358, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 110.0, 111.0, 111.0, 110.0, 113.0, 110.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 118.0, 179.0, 121.0, 121.0, 141.0, 118.0, 152.0, 117.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 159.0, 159.0, 160.0, 163.0, 159.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 112.0, 75.0, 75.0, 74.0, 74.0, 98.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 112.0, 84.0, 85.0, 85.0, 129.0, 85.0, 84.0, 126.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 148.0, 145.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 97.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 50.0, 49.0, 50.0, 50.0, 49.0, 50.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 84.0, 77.0, 85.0, 78.0, 75.0, 76.0, 75.0, 75.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 118.0, 134.0, 120.0, 121.0, 121.0, 120.0, 125.0, 127.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 157.0, 154.0, 154.0, 154.0, 154.0, 156.0, 156.0, 155.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 64.0, 65.0, 62.0, 62.0, 63.0, 63.0, 62.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1468.5000000000002, "alertThreshold": 2.0}]} 20:29:24 INFO - Return code: 0 20:29:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:29:24 INFO - # TBPL SUCCESS # 20:29:24 INFO - Running post-action listener: _resource_record_post_action 20:29:24 INFO - [mozharness: 2016-08-05 03:29:24.369810Z] Finished run-tests step (success) 20:29:24 INFO - Running post-run listener: _resource_record_post_run 20:29:24 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 618496; Write bytes: 363458560; Read time: 124; Write time: 254964 20:29:24 INFO - TinderboxPrint: CPU usage
13.0% 20:29:24 INFO - TinderboxPrint: I/O read bytes / time
618,496 / 124 20:29:24 INFO - TinderboxPrint: I/O write bytes / time
363,458,560 / 254,964 20:29:24 INFO - TinderboxPrint: CPU idle
8,291.3 (87.0%) 20:29:24 INFO - TinderboxPrint: CPU user
1,193.7 (12.5%) 20:29:24 INFO - TinderboxPrint: Swap in / out
0 / 0 20:29:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78020608; Read time: 0; Write time: 33684 20:29:24 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 614400; Write bytes: 285003776; Read time: 112; Write time: 221136 20:29:24 INFO - Running post-run listener: _upload_blobber_files 20:29:24 INFO - Blob upload gear active. 20:29:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:29:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:29:25 INFO - (blobuploader) - INFO - Open directory for files ... 20:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:26 INFO - (blobuploader) - INFO - Done attempting. 20:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:30 INFO - (blobuploader) - INFO - Done attempting. 20:29:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:29:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:31 INFO - (blobuploader) - INFO - Done attempting. 20:29:31 INFO - (blobuploader) - INFO - Iteration through files over. 20:29:31 INFO - Return code: 0 20:29:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:29:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:29:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b9772165b8c593a9ceacd0bfdb956cb0095cfe1f9a305ccfa78a637e1c935eb13c6d77f66f2cf177fee94ec7e21c80f3d1a617da70f2500358ed51efa183595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7759cce8146431f47dc3e7b807475ac07a39978adbda403e6d741c391af3be47b50dbc33e22a86776ea69ba8e7d50fd8683fe01c4b5b9401d8fc0fb6daddc4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dee888848c00bfdf9454424674bb694759dc2ec74922f1b86b10eb82ffafd5fc7540249c31da96f354e1220e6c3b8f1678e0c299f2bb304ebfda6f0fa30bec8b"} 20:29:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:29:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:29:31 INFO - Contents: 20:29:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b9772165b8c593a9ceacd0bfdb956cb0095cfe1f9a305ccfa78a637e1c935eb13c6d77f66f2cf177fee94ec7e21c80f3d1a617da70f2500358ed51efa183595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7759cce8146431f47dc3e7b807475ac07a39978adbda403e6d741c391af3be47b50dbc33e22a86776ea69ba8e7d50fd8683fe01c4b5b9401d8fc0fb6daddc4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dee888848c00bfdf9454424674bb694759dc2ec74922f1b86b10eb82ffafd5fc7540249c31da96f354e1220e6c3b8f1678e0c299f2bb304ebfda6f0fa30bec8b"} 20:29:31 INFO - Running post-run listener: copy_logs_to_upload_dir 20:29:31 INFO - Copying logs to upload dir... 20:29:31 INFO - mkdir: /builds/slave/test/build/upload/logs 20:29:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.720385 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-04 20:29:31.274006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 20:29:31.277746) ========= 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=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b9772165b8c593a9ceacd0bfdb956cb0095cfe1f9a305ccfa78a637e1c935eb13c6d77f66f2cf177fee94ec7e21c80f3d1a617da70f2500358ed51efa183595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7759cce8146431f47dc3e7b807475ac07a39978adbda403e6d741c391af3be47b50dbc33e22a86776ea69ba8e7d50fd8683fe01c4b5b9401d8fc0fb6daddc4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dee888848c00bfdf9454424674bb694759dc2ec74922f1b86b10eb82ffafd5fc7540249c31da96f354e1220e6c3b8f1678e0c299f2bb304ebfda6f0fa30bec8b"} build_url:https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/TDOhmD_LRpyVUNxZO9pyiA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b9772165b8c593a9ceacd0bfdb956cb0095cfe1f9a305ccfa78a637e1c935eb13c6d77f66f2cf177fee94ec7e21c80f3d1a617da70f2500358ed51efa183595", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7759cce8146431f47dc3e7b807475ac07a39978adbda403e6d741c391af3be47b50dbc33e22a86776ea69ba8e7d50fd8683fe01c4b5b9401d8fc0fb6daddc4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dee888848c00bfdf9454424674bb694759dc2ec74922f1b86b10eb82ffafd5fc7540249c31da96f354e1220e6c3b8f1678e0c299f2bb304ebfda6f0fa30bec8b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 20:29:31.302628) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:29:31.302956) ========= 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=920470bf432669ad7e3a5387000001ca-1470365541.97391-2046490167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004362 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 20:29:31.389249) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 20:29:31.389585) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 20:29:31.389870) ========= ========= Total master_lag: 0.17 =========