builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1472259878.08 results: success (0) buildid: 20160826163414 builduid: 64d1a37c61f644b88b7ca024b6f355d1 revision: 3c4c4accb1392bbc70fed3ddebbaa42453963900 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.077822) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.078324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.078621) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.093802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.094199) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 18:04:38-- 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% 46.0M=0s 2016-08-26 18:04:38 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079713 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.188370) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.188692) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023276 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.238456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:38.238896) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3c4c4accb1392bbc70fed3ddebbaa42453963900 --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 3c4c4accb1392bbc70fed3ddebbaa42453963900 --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=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 18:04:38,579 truncating revision to first 12 chars 2016-08-26 18:04:38,580 Setting DEBUG logging. 2016-08-26 18:04:38,580 attempt 1/10 2016-08-26 18:04:38,580 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3c4c4accb139?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 18:04:39,276 unpacking tar archive at: fx-team-3c4c4accb139/testing/mozharness/ program finished with exit code 0 elapsedTime=0.983018 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:39.236400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:39.236728) ========= script_repo_revision: 3c4c4accb1392bbc70fed3ddebbaa42453963900 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:39.237173) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:39.237523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:04:39.247321) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-26 18:04:39.247687) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:04:39 INFO - MultiFileLogger online at 20160826 18:04:39 in /builds/slave/test 18:04:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:04:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:39 INFO - {'append_to_log': False, 18:04:39 INFO - 'base_work_dir': '/builds/slave/test', 18:04:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:04:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:39 INFO - 'branch': 'Fx-Team-Non-PGO', 18:04:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:39 INFO - 'config_files': ('talos/linux_config.py',), 18:04:39 INFO - 'default_actions': ('clobber', 18:04:39 INFO - 'read-buildbot-config', 18:04:39 INFO - 'download-and-extract', 18:04:39 INFO - 'populate-webroot', 18:04:39 INFO - 'create-virtualenv', 18:04:39 INFO - 'install', 18:04:39 INFO - 'run-tests'), 18:04:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:39 INFO - 'download_minidump_stackwalk': True, 18:04:39 INFO - 'download_symbols': 'ondemand', 18:04:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:39 INFO - 'installer_path': 'installer.exe', 18:04:39 INFO - 'log_level': 'info', 18:04:39 INFO - 'log_name': 'talos', 18:04:39 INFO - 'log_to_console': True, 18:04:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:04:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:04:39 INFO - 'opt_config_files': (), 18:04:39 INFO - 'pip_index': False, 18:04:39 INFO - 'sps_profile': False, 18:04:39 INFO - 'sps_profile_interval': 0, 18:04:39 INFO - 'suite': 'dromaeojs', 18:04:39 INFO - 'system_bits': '32', 18:04:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:39 INFO - 'title': 'talos-linux64-ix-006', 18:04:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:39 INFO - 'use_talos_json': True, 18:04:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:39 INFO - 'work_dir': 'build'} 18:04:39 INFO - [mozharness: 2016-08-27 01:04:39.681041Z] Running clobber step. 18:04:39 INFO - Running pre-action listener: _resource_record_pre_action 18:04:39 INFO - Running main action method: clobber 18:04:39 INFO - rmtree: /builds/slave/test/build 18:04:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:40 INFO - Running post-action listener: _resource_record_post_action 18:04:40 INFO - [mozharness: 2016-08-27 01:04:40.662054Z] Finished clobber step (success) 18:04:40 INFO - [mozharness: 2016-08-27 01:04:40.662156Z] Running read-buildbot-config step. 18:04:40 INFO - Running pre-action listener: _resource_record_pre_action 18:04:40 INFO - Running main action method: read_buildbot_config 18:04:40 INFO - Using buildbot properties: 18:04:40 INFO - { 18:04:40 INFO - "project": "", 18:04:40 INFO - "product": "firefox", 18:04:40 INFO - "script_repo_revision": "production", 18:04:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:04:40 INFO - "repository": "", 18:04:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:04:40 INFO - "buildid": "20160826163414", 18:04:40 INFO - "pgo_build": "False", 18:04:40 INFO - "basedir": "/builds/slave/test", 18:04:40 INFO - "buildnumber": 1891, 18:04:40 INFO - "slavename": "talos-linux64-ix-006", 18:04:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:04:40 INFO - "platform": "ubuntu64_hw", 18:04:40 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:04:40 INFO - "branch": "fx-team", 18:04:40 INFO - "repo_path": "integration/fx-team", 18:04:40 INFO - "revision": "3c4c4accb1392bbc70fed3ddebbaa42453963900", 18:04:40 INFO - "stage_platform": "linux64", 18:04:40 INFO - "builduid": "64d1a37c61f644b88b7ca024b6f355d1", 18:04:40 INFO - "slavebuilddir": "test" 18:04:40 INFO - } 18:04:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:04:40 INFO - Running post-action listener: _resource_record_post_action 18:04:40 INFO - [mozharness: 2016-08-27 01:04:40.664624Z] Finished read-buildbot-config step (success) 18:04:40 INFO - [mozharness: 2016-08-27 01:04:40.664717Z] Running download-and-extract step. 18:04:40 INFO - Running pre-action listener: _resource_record_pre_action 18:04:40 INFO - Running main action method: download_and_extract 18:04:40 INFO - mkdir: /builds/slave/test/build/tests 18:04:40 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')]} 18:04:40 INFO - trying https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:04:40 INFO - Downloading https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:41 INFO - Downloaded 1361 bytes. 18:04:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:04:41 INFO - Using the following test package requirements: 18:04:41 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:04:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:04:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:04:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'jsshell-linux-x86_64.zip'], 18:04:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:04:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:04:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:04:41 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:04:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:04:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:04:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:04:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:04:41 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')]} 18:04:41 INFO - trying https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:04:41 INFO - Downloading https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:42 INFO - Downloaded 20304670 bytes. 18:04:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:04:46 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 18:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:46 INFO - trying https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:04:46 INFO - Downloading https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:47 INFO - Downloaded 20304670 bytes. 18:04:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:52 INFO - trying https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:04:52 INFO - Downloading https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:53 INFO - Downloaded 14413998 bytes. 18:04:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 18:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:54 INFO - trying https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:04:54 INFO - Downloading https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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 18:04:56 INFO - Downloaded 55857940 bytes. 18:04:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:04:56 INFO - mkdir: /builds/slave/test/properties 18:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:56 INFO - Contents: 18:04:56 INFO - build_url:https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:04:56 INFO - Running post-action listener: _resource_record_post_action 18:04:56 INFO - Running post-action listener: set_extra_try_arguments 18:04:56 INFO - [mozharness: 2016-08-27 01:04:56.848246Z] Finished download-and-extract step (success) 18:04:56 INFO - [mozharness: 2016-08-27 01:04:56.848474Z] Running populate-webroot step. 18:04:56 INFO - Running pre-action listener: _resource_record_pre_action 18:04:56 INFO - Running main action method: populate_webroot 18:04:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:56 INFO - '--tppagecycles', 18:04:56 INFO - '7'], 18:04:56 INFO - 'tests': ['tsvgm']}}, 18:04:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['damp', 'tps']}, 18:04:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['damp', 'tps']}, 18:04:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['dromaeo_dom']}, 18:04:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['basic_compositor_video']}, 18:04:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:04:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['a11yr', 18:04:56 INFO - 'ts_paint', 18:04:56 INFO - 'tpaint', 18:04:56 INFO - 'sessionrestore', 18:04:56 INFO - 'sessionrestore_no_auto_restore', 18:04:56 INFO - 'tabpaint']}, 18:04:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['tsvgx', 18:04:56 INFO - 'tsvgr_opacity', 18:04:56 INFO - 'tart', 18:04:56 INFO - 'tscrollx', 18:04:56 INFO - 'cart']}, 18:04:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:56 INFO - 'tsvgr_opacity', 18:04:56 INFO - 'tart', 18:04:56 INFO - 'tscrollx', 18:04:56 INFO - 'cart']}, 18:04:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--disable-e10s'], 18:04:56 INFO - 'tests': ['tp5o']}, 18:04:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'tests': ['tp5o']}, 18:04:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--disable-e10s', 18:04:56 INFO - '--xperf_path', 18:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:56 INFO - 'tests': ['tp5n']}, 18:04:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:56 INFO - 'talos_options': ['--xperf_path', 18:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:56 INFO - 'tests': ['tp5n']}}, 18:04:56 INFO - 'talos.zip': {'path': '', 18:04:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:56 INFO - Running post-action listener: _resource_record_post_action 18:04:56 INFO - [mozharness: 2016-08-27 01:04:56.870067Z] Finished populate-webroot step (success) 18:04:56 INFO - [mozharness: 2016-08-27 01:04:56.870158Z] Running create-virtualenv step. 18:04:56 INFO - Running pre-action listener: _resource_record_pre_action 18:04:56 INFO - Running main action method: create_virtualenv 18:04:56 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:56 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 18:04:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:56 INFO - Using real prefix '/usr' 18:04:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:59 INFO - Installing pip.................done. 18:04:59 INFO - Return code: 0 18:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:59 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')]} 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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')]} 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:04:59 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 18:04:59 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 18:04:59 INFO - Using env: {'DISPLAY': ':0', 18:04:59 INFO - 'HOME': '/home/cltbld', 18:04:59 INFO - 'LANG': 'en_US.UTF-8', 18:04:59 INFO - 'LANGUAGE': 'en_US:en', 18:04:59 INFO - 'LOGNAME': 'cltbld', 18:04:59 INFO - 'MAIL': '/var/mail/cltbld', 18:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:59 INFO - 'MOZ_NO_REMOTE': '1', 18:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:59 INFO - 'NO_EM_RESTART': '1', 18:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:59 INFO - 'PWD': '/builds/slave/test', 18:04:59 INFO - 'SHELL': '/bin/bash', 18:04:59 INFO - 'SHLVL': '1', 18:04:59 INFO - 'TERM': 'linux', 18:04:59 INFO - 'TMOUT': '86400', 18:04:59 INFO - 'USER': 'cltbld', 18:04:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:59 INFO - '_': '/tools/buildbot/bin/python'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 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 18:04:59 INFO - Cleaning up... 18:04:59 INFO - Return code: 0 18:04:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:59 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')]} 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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')]} 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:04:59 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 18:04:59 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 18:04:59 INFO - Using env: {'DISPLAY': ':0', 18:04:59 INFO - 'HOME': '/home/cltbld', 18:04:59 INFO - 'LANG': 'en_US.UTF-8', 18:04:59 INFO - 'LANGUAGE': 'en_US:en', 18:04:59 INFO - 'LOGNAME': 'cltbld', 18:04:59 INFO - 'MAIL': '/var/mail/cltbld', 18:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:59 INFO - 'MOZ_NO_REMOTE': '1', 18:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:59 INFO - 'NO_EM_RESTART': '1', 18:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:59 INFO - 'PWD': '/builds/slave/test', 18:04:59 INFO - 'SHELL': '/bin/bash', 18:04:59 INFO - 'SHLVL': '1', 18:04:59 INFO - 'TERM': 'linux', 18:04:59 INFO - 'TMOUT': '86400', 18:04:59 INFO - 'USER': 'cltbld', 18:04:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:59 INFO - '_': '/tools/buildbot/bin/python'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 INFO - Downloading/unpacking psutil>=3.1.1 18:04:59 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 18:04:59 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 18:04:59 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 18:04:59 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 18:04:59 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 18:04:59 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 18:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:01 INFO - Installing collected packages: psutil 18:05:01 INFO - Running setup.py install for psutil 18:05:01 INFO - building 'psutil._psutil_linux' extension 18:05:01 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 18:05:02 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 18:05:02 INFO - building 'psutil._psutil_posix' extension 18:05:02 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 18:05:02 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 18:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:02 INFO - Successfully installed psutil 18:05:02 INFO - Cleaning up... 18:05:02 INFO - Return code: 0 18:05:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:05:02 INFO - Using env: {'DISPLAY': ':0', 18:05:02 INFO - 'HOME': '/home/cltbld', 18:05:02 INFO - 'LANG': 'en_US.UTF-8', 18:05:02 INFO - 'LANGUAGE': 'en_US:en', 18:05:02 INFO - 'LOGNAME': 'cltbld', 18:05:02 INFO - 'MAIL': '/var/mail/cltbld', 18:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:02 INFO - 'MOZ_NO_REMOTE': '1', 18:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:02 INFO - 'NO_EM_RESTART': '1', 18:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:02 INFO - 'PWD': '/builds/slave/test', 18:05:02 INFO - 'SHELL': '/bin/bash', 18:05:02 INFO - 'SHLVL': '1', 18:05:02 INFO - 'TERM': 'linux', 18:05:02 INFO - 'TMOUT': '86400', 18:05:02 INFO - 'USER': 'cltbld', 18:05:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:02 INFO - '_': '/tools/buildbot/bin/python'} 18:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:04 INFO - Installing collected packages: mozsystemmonitor 18:05:04 INFO - Running setup.py install for mozsystemmonitor 18:05:04 INFO - Successfully installed mozsystemmonitor 18:05:04 INFO - Cleaning up... 18:05:04 INFO - Return code: 0 18:05:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:04 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:04 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 18:05:04 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 18:05:04 INFO - Using env: {'DISPLAY': ':0', 18:05:04 INFO - 'HOME': '/home/cltbld', 18:05:04 INFO - 'LANG': 'en_US.UTF-8', 18:05:04 INFO - 'LANGUAGE': 'en_US:en', 18:05:04 INFO - 'LOGNAME': 'cltbld', 18:05:04 INFO - 'MAIL': '/var/mail/cltbld', 18:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:04 INFO - 'MOZ_NO_REMOTE': '1', 18:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:04 INFO - 'NO_EM_RESTART': '1', 18:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:04 INFO - 'PWD': '/builds/slave/test', 18:05:04 INFO - 'SHELL': '/bin/bash', 18:05:04 INFO - 'SHLVL': '1', 18:05:04 INFO - 'TERM': 'linux', 18:05:04 INFO - 'TMOUT': '86400', 18:05:04 INFO - 'USER': 'cltbld', 18:05:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:04 INFO - '_': '/tools/buildbot/bin/python'} 18:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:04 INFO - Downloading/unpacking jsonschema==2.5.1 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:04 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 18:05:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05:06 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 18:05:06 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 18:05:06 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 18:05:06 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 18:05:06 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 18:05:06 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 18:05:07 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:07 INFO - warning: no files found matching '*.txt' 18:05:07 INFO - no previously-included directories found matching 'build' 18:05:07 INFO - no previously-included directories found matching 'dist' 18:05:07 INFO - no previously-included directories found matching '.git*' 18:05:07 INFO - Installing collected packages: jsonschema, functools32 18:05:07 INFO - Running setup.py install for functools32 18:05:07 INFO - warning: no files found matching '*.txt' 18:05:07 INFO - no previously-included directories found matching 'build' 18:05:07 INFO - no previously-included directories found matching 'dist' 18:05:07 INFO - no previously-included directories found matching '.git*' 18:05:07 INFO - Successfully installed jsonschema functools32 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', '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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:05: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 functools32==3.2.3-2 18:05:07 INFO - Using env: {'DISPLAY': ':0', 18:05:07 INFO - 'HOME': '/home/cltbld', 18:05:07 INFO - 'LANG': 'en_US.UTF-8', 18:05:07 INFO - 'LANGUAGE': 'en_US:en', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MAIL': '/var/mail/cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SHLVL': '1', 18:05:07 INFO - 'TERM': 'linux', 18:05:07 INFO - 'TMOUT': '86400', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - '_': '/tools/buildbot/bin/python'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', '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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:05: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 blobuploader==1.2.4 18:05:07 INFO - Using env: {'DISPLAY': ':0', 18:05:07 INFO - 'HOME': '/home/cltbld', 18:05:07 INFO - 'LANG': 'en_US.UTF-8', 18:05:07 INFO - 'LANGUAGE': 'en_US:en', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MAIL': '/var/mail/cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SHLVL': '1', 18:05:07 INFO - 'TERM': 'linux', 18:05:07 INFO - 'TMOUT': '86400', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - '_': '/tools/buildbot/bin/python'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:09 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:10 INFO - Downloading docopt-0.6.1.tar.gz 18:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:10 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:10 INFO - Running setup.py install for blobuploader 18:05:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:10 INFO - Running setup.py install for requests 18:05:10 INFO - Running setup.py install for docopt 18:05:10 INFO - Successfully installed blobuploader requests docopt 18:05:10 INFO - Cleaning up... 18:05:11 INFO - Return code: 0 18:05:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:11 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:11 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 18:05:11 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 18:05:11 INFO - Using env: {'DISPLAY': ':0', 18:05:11 INFO - 'HOME': '/home/cltbld', 18:05:11 INFO - 'LANG': 'en_US.UTF-8', 18:05:11 INFO - 'LANGUAGE': 'en_US:en', 18:05:11 INFO - 'LOGNAME': 'cltbld', 18:05:11 INFO - 'MAIL': '/var/mail/cltbld', 18:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:11 INFO - 'MOZ_NO_REMOTE': '1', 18:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:11 INFO - 'NO_EM_RESTART': '1', 18:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:11 INFO - 'PWD': '/builds/slave/test', 18:05:11 INFO - 'SHELL': '/bin/bash', 18:05:11 INFO - 'SHLVL': '1', 18:05:11 INFO - 'TERM': 'linux', 18:05:11 INFO - 'TMOUT': '86400', 18:05:11 INFO - 'USER': 'cltbld', 18:05:11 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:11 INFO - '_': '/tools/buildbot/bin/python'} 18:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:11 INFO - Running setup.py (path:/tmp/pip-Aet2Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:11 INFO - Running setup.py (path:/tmp/pip-lNhNBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:11 INFO - Running setup.py (path:/tmp/pip-Iq0Yt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:11 INFO - Running setup.py (path:/tmp/pip-vwwsQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:11 INFO - Running setup.py (path:/tmp/pip-mRupAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:11 INFO - Running setup.py (path:/tmp/pip-iQlXT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:11 INFO - Running setup.py (path:/tmp/pip-uDMjRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:11 INFO - Running setup.py (path:/tmp/pip-ZFj1Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:11 INFO - Running setup.py (path:/tmp/pip-RKaBDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:11 INFO - Running setup.py (path:/tmp/pip-kOuWt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:11 INFO - Running setup.py (path:/tmp/pip-BbrZhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:12 INFO - Running setup.py (path:/tmp/pip-jQrN3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:12 INFO - Running setup.py (path:/tmp/pip-zZDRb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:12 INFO - Running setup.py (path:/tmp/pip-o8qz_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:12 INFO - Running setup.py (path:/tmp/pip-xm_Ytl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:12 INFO - Running setup.py (path:/tmp/pip-WYp3EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:12 INFO - Running setup.py (path:/tmp/pip-qBZC2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:12 INFO - Running setup.py install for manifestparser 18:05:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:12 INFO - Running setup.py install for mozcrash 18:05:12 INFO - Running setup.py install for mozdebug 18:05:12 INFO - Running setup.py install for mozdevice 18:05:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:12 INFO - Running setup.py install for mozfile 18:05:12 INFO - Running setup.py install for mozhttpd 18:05:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozinfo 18:05:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozInstall 18:05:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozleak 18:05:13 INFO - Running setup.py install for mozlog 18:05:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for moznetwork 18:05:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozprocess 18:05:13 INFO - Running setup.py install for mozprofile 18:05:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozrunner 18:05:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:13 INFO - Running setup.py install for mozscreenshot 18:05:14 INFO - Running setup.py install for moztest 18:05:14 INFO - Running setup.py install for mozversion 18:05:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:14 INFO - Cleaning up... 18:05:14 INFO - Return code: 0 18:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:14 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')]} 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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')]} 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:14 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 18:05:14 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 18:05:14 INFO - Using env: {'DISPLAY': ':0', 18:05:14 INFO - 'HOME': '/home/cltbld', 18:05:14 INFO - 'LANG': 'en_US.UTF-8', 18:05:14 INFO - 'LANGUAGE': 'en_US:en', 18:05:14 INFO - 'LOGNAME': 'cltbld', 18:05:14 INFO - 'MAIL': '/var/mail/cltbld', 18:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:14 INFO - 'MOZ_NO_REMOTE': '1', 18:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:14 INFO - 'NO_EM_RESTART': '1', 18:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:14 INFO - 'PWD': '/builds/slave/test', 18:05:14 INFO - 'SHELL': '/bin/bash', 18:05:14 INFO - 'SHLVL': '1', 18:05:14 INFO - 'TERM': 'linux', 18:05:14 INFO - 'TMOUT': '86400', 18:05:14 INFO - 'USER': 'cltbld', 18:05:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:14 INFO - '_': '/tools/buildbot/bin/python'} 18:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:14 INFO - Running setup.py (path:/tmp/pip-3uRIFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:14 INFO - Running setup.py (path:/tmp/pip-CEaMQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:14 INFO - Running setup.py (path:/tmp/pip-feZE7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:14 INFO - Running setup.py (path:/tmp/pip-0NPXsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:14 INFO - Running setup.py (path:/tmp/pip-PhRxuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:14 INFO - Running setup.py (path:/tmp/pip-T0Q9yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:14 INFO - Running setup.py (path:/tmp/pip-6Yru9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:14 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)) 18:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:14 INFO - Running setup.py (path:/tmp/pip-tG1FZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:15 INFO - Running setup.py (path:/tmp/pip-AyLq8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:15 INFO - Running setup.py (path:/tmp/pip-Ga9aFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:15 INFO - Running setup.py (path:/tmp/pip-VlZV4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:15 INFO - Running setup.py (path:/tmp/pip-He0pke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:15 INFO - Running setup.py (path:/tmp/pip-oQqvGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:15 INFO - Running setup.py (path:/tmp/pip-vfU_nX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:15 INFO - Running setup.py (path:/tmp/pip-d1gXm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:15 INFO - Running setup.py (path:/tmp/pip-RH6Afb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:15 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)) 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:15 INFO - Running setup.py (path:/tmp/pip-_aIIL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:15 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)) 18:05:15 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)) 18:05:15 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)) 18:05:15 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)) 18:05:15 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)) 18:05:15 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)) 18:05:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:17 INFO - Downloading blessings-1.6.tar.gz 18:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:17 INFO - Installing collected packages: blessings 18:05:17 INFO - Running setup.py install for blessings 18:05:17 INFO - Successfully installed blessings 18:05:17 INFO - Cleaning up... 18:05:17 INFO - Return code: 0 18:05:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:18 INFO - Reading from file tmpfile_stdout 18:05:18 INFO - Current package versions: 18:05:18 INFO - argparse == 1.2.1 18:05:18 INFO - blessings == 1.6 18:05:18 INFO - blobuploader == 1.2.4 18:05:18 INFO - docopt == 0.6.1 18:05:18 INFO - functools32 == 3.2.3-2 18:05:18 INFO - jsonschema == 2.5.1 18:05:18 INFO - manifestparser == 1.1 18:05:18 INFO - mozInstall == 1.12 18:05:18 INFO - mozcrash == 0.17 18:05:18 INFO - mozdebug == 0.1 18:05:18 INFO - mozdevice == 0.48 18:05:18 INFO - mozfile == 1.2 18:05:18 INFO - mozhttpd == 0.7 18:05:18 INFO - mozinfo == 0.9 18:05:18 INFO - mozleak == 0.1 18:05:18 INFO - mozlog == 3.3 18:05:18 INFO - moznetwork == 0.27 18:05:18 INFO - mozprocess == 0.23 18:05:18 INFO - mozprofile == 0.28 18:05:18 INFO - mozrunner == 6.12 18:05:18 INFO - mozscreenshot == 0.1 18:05:18 INFO - mozsystemmonitor == 0.3 18:05:18 INFO - moztest == 0.7 18:05:18 INFO - mozversion == 1.4 18:05:18 INFO - psutil == 3.1.1 18:05:18 INFO - requests == 1.2.3 18:05:18 INFO - wsgiref == 0.1.2 18:05:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:18 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')]} 18:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 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')]} 18:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:18 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 18:05:18 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 18:05:18 INFO - Using env: {'DISPLAY': ':0', 18:05:18 INFO - 'HOME': '/home/cltbld', 18:05:18 INFO - 'LANG': 'en_US.UTF-8', 18:05:18 INFO - 'LANGUAGE': 'en_US:en', 18:05:18 INFO - 'LOGNAME': 'cltbld', 18:05:18 INFO - 'MAIL': '/var/mail/cltbld', 18:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:18 INFO - 'MOZ_NO_REMOTE': '1', 18:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:18 INFO - 'NO_EM_RESTART': '1', 18:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:18 INFO - 'PWD': '/builds/slave/test', 18:05:18 INFO - 'SHELL': '/bin/bash', 18:05:18 INFO - 'SHLVL': '1', 18:05:18 INFO - 'TERM': 'linux', 18:05:18 INFO - 'TMOUT': '86400', 18:05:18 INFO - 'USER': 'cltbld', 18:05:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:18 INFO - '_': '/tools/buildbot/bin/python'} 18:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 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)) 18:05:18 INFO - Cleaning up... 18:05:18 INFO - Return code: 0 18:05:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:18 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')]} 18:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 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')]} 18:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 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 0x7f0b57e33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', '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 18:05:18 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 18:05:18 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 18:05:18 INFO - Using env: {'DISPLAY': ':0', 18:05:18 INFO - 'HOME': '/home/cltbld', 18:05:18 INFO - 'LANG': 'en_US.UTF-8', 18:05:18 INFO - 'LANGUAGE': 'en_US:en', 18:05:18 INFO - 'LOGNAME': 'cltbld', 18:05:18 INFO - 'MAIL': '/var/mail/cltbld', 18:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:18 INFO - 'MOZ_NO_REMOTE': '1', 18:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:18 INFO - 'NO_EM_RESTART': '1', 18:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:18 INFO - 'PWD': '/builds/slave/test', 18:05:18 INFO - 'SHELL': '/bin/bash', 18:05:18 INFO - 'SHLVL': '1', 18:05:18 INFO - 'TERM': 'linux', 18:05:18 INFO - 'TMOUT': '86400', 18:05:18 INFO - 'USER': 'cltbld', 18:05:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:18 INFO - '_': '/tools/buildbot/bin/python'} 18:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:18 INFO - Cleaning up... 18:05:18 INFO - Return code: 0 18:05:18 INFO - Running post-action listener: _resource_record_post_action 18:05:18 INFO - Running post-action listener: _start_resource_monitoring 18:05:18 INFO - Starting resource monitoring. 18:05:18 INFO - [mozharness: 2016-08-27 01:05:18.504235Z] Finished create-virtualenv step (success) 18:05:18 INFO - [mozharness: 2016-08-27 01:05:18.504752Z] Running install step. 18:05:18 INFO - Running pre-action listener: _resource_record_pre_action 18:05:18 INFO - Running main action method: install 18:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:18 INFO - Reading from file tmpfile_stdout 18:05:18 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:18 INFO - Reading from file tmpfile_stdout 18:05:18 INFO - Output received: 18:05:18 INFO - Usage: mozinstall [options] installer 18:05:18 INFO - Options: 18:05:18 INFO - -h, --help show this help message and exit 18:05:18 INFO - -d DEST, --destination=DEST 18:05:18 INFO - Directory to install application into. [default: 18:05:18 INFO - "/builds/slave/test"] 18:05:18 INFO - --app=APP Application being installed. [default: firefox] 18:05:18 INFO - mkdir: /builds/slave/test/build/application 18:05:18 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'] 18:05:18 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 18:05:34 INFO - Reading from file tmpfile_stdout 18:05:34 INFO - Output received: 18:05:34 INFO - /builds/slave/test/build/application/firefox/firefox 18:05:34 INFO - Running post-action listener: _resource_record_post_action 18:05:34 INFO - [mozharness: 2016-08-27 01:05:34.930097Z] Finished install step (success) 18:05:34 INFO - [mozharness: 2016-08-27 01:05:34.930379Z] Running run-tests step. 18:05:34 INFO - Running pre-action listener: _resource_record_pre_action 18:05:34 INFO - Running main action method: run_tests 18:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:34 INFO - Python 2.7.3 18:05:34 INFO - Return code: 0 18:05:34 INFO - grabbing minidump binary from tooltool 18:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:34 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 0x2326090>, '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 0x23299b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:34 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 18:05:34 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 18:05:34 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 18:05:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:35 INFO - Return code: 0 18:05:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:05:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:05:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 18:05:35 INFO - Using env: {'DISPLAY': ':0', 18:05:35 INFO - 'HOME': '/home/cltbld', 18:05:35 INFO - 'LANG': 'en_US.UTF-8', 18:05:35 INFO - 'LANGUAGE': 'en_US:en', 18:05:35 INFO - 'LOGNAME': 'cltbld', 18:05:35 INFO - 'MAIL': '/var/mail/cltbld', 18:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:35 INFO - 'MOZ_NO_REMOTE': '1', 18:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:35 INFO - 'NO_EM_RESTART': '1', 18:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:35 INFO - 'PWD': '/builds/slave/test', 18:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:35 INFO - 'SHELL': '/bin/bash', 18:05:35 INFO - 'SHLVL': '1', 18:05:35 INFO - 'TERM': 'linux', 18:05:35 INFO - 'TMOUT': '86400', 18:05:35 INFO - 'USER': 'cltbld', 18:05:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904', 18:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:35 INFO - '_': '/tools/buildbot/bin/python'} 18:05:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:05:35 INFO - mozversion application_buildid: 20160826163414 18:05:35 INFO - mozversion application_changeset: 3c4c4accb1392bbc70fed3ddebbaa42453963900 18:05:35 INFO - mozversion application_display_name: Nightly 18:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:35 INFO - mozversion application_name: Firefox 18:05:35 INFO - mozversion application_remotingname: firefox 18:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:05:35 INFO - mozversion application_vendor: Mozilla 18:05:35 INFO - mozversion application_version: 51.0a1 18:05:35 INFO - mozversion platform_buildid: 20160826163414 18:05:35 INFO - mozversion platform_changeset: 3c4c4accb1392bbc70fed3ddebbaa42453963900 18:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:05:35 INFO - mozversion platform_version: 51.0a1 18:05:35 INFO - using testdate: 1472259935 18:05:35 INFO - actual date: 1472259935 18:05:35 INFO - starting webserver on 'localhost:56058' 18:05:35 INFO - SUITE-START | Running 2 tests 18:05:35 INFO - TEST-START | dromaeo_css 18:05:35 INFO - operating with platform_type : linux_ 18:05:35 INFO - Initialising browser for dromaeo_css test... 18:05:35 INFO - TEST-INFO | started process 18266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0DYck/profile http://localhost:56058/getInfo.html) 18:05:39 INFO - PROCESS | 18266 | __metrics Screen width/height:1600/1200 18:05:39 INFO - PROCESS | 18266 | colorDepth:24 18:05:39 INFO - PROCESS | 18266 | Browser inner width/height: 1024/697 18:05:39 INFO - PROCESS | 18266 | __metrics 18:05:40 INFO - PROCESS | 18266 | 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" 18:05:40 INFO - PROCESS | 18266 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:05:40 INFO - PROCESS | 18266 | console.error: PushService: 18:05:40 INFO - PROCESS | 18266 | stateChangeProcessEnqueue: Error transitioning state 18:05:40 INFO - PROCESS | 18266 | UnknownError 18:05:41 INFO - TEST-INFO | 18266: exit 0 18:05:41 INFO - Browser initialized. 18:05:41 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:41 INFO - TEST-INFO | started process 18345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0DYck/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:53 INFO - PROCESS | 18345 | RSS: Main: 180916224 18:05:53 INFO - PROCESS | 18345 | 18:06:44 INFO - PROCESS | 18345 | 1472260004603 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] 18:08:57 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56058/tests/dromaeo/cssquery-ext.html) 18:08:59 INFO - PROCESS | 18345 | RSS: Main: 328265728 18:08:59 INFO - PROCESS | 18345 | 18:12:02 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-ext.html (next: http://localhost:56058/tests/dromaeo/cssquery-jquery.html) 18:12:02 INFO - PROCESS | 18345 | RSS: Main: 277278720 18:12:02 INFO - PROCESS | 18345 | 18:15:05 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56058/tests/dromaeo/cssquery-mootools.html) 18:15:05 INFO - PROCESS | 18345 | RSS: Main: 218849280 18:15:05 INFO - PROCESS | 18345 | 18:18:08 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56058/tests/dromaeo/cssquery-prototype.html) 18:18:08 INFO - PROCESS | 18345 | RSS: Main: 218804224 18:18:08 INFO - PROCESS | 18345 | 18:21:11 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56058/tests/dromaeo/cssquery-yui.html) 18:21:12 INFO - PROCESS | 18345 | RSS: Main: 219672576 18:21:12 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | Cycle 1(1): loaded http://localhost:56058/tests/dromaeo/cssquery-yui.html (next: http://localhost:56058/tests/dromaeo/cssquery-dojo.html) 18:24:15 INFO - PROCESS | 18345 | RSS: Main: 299966464 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | __start_tp_report 18:24:15 INFO - PROCESS | 18345 | _x_x_mozilla_page_load 18:24:15 INFO - PROCESS | 18345 | _x_x_mozilla_page_load_details 18:24:15 INFO - PROCESS | 18345 | |i|pagename|runs| 18:24:15 INFO - PROCESS | 18345 | |0;dojo.html;2614.607808828676;2614.830036047657;2625.9474810503802;2655.8140930134887;2671.0384261665795;4671.813127474897;4724.952750472494;4752.59602933751;4780.521947805218;4854.56308932196;4708.670393072486;4772.188727916254;4780.163471392501;4828.0585285869265;4845.030993801245;4287.292596721537;4322.28682267427;4359.673024523157;4407.955920440802;4559.407277053995;4449.065696203801;4489.753063581502;4507.774611269439;4533.523979982104;4545.386372839657;4300.6989510734265;4352.73883566987;4355.063661312801;4383.473983122046;4418.094290670437;4344.391785150082;4399;4403.075354175627;4471.3293006049025;4474.038081812426;4330.02574420756;4337.240982827992;4339.414179085815;4389.341598760179;4440.866773996785;4775.761211939423;4843.927341089887;4844.781984810692;4865.537773911473;4911.803527858881;4986.601071914238;5056.74716264189;5092.28707980883;5126.820561280375;5163.122269214205;5116.053530096944;5125.743712814345;5135.281060651511;5136.126858434075;5140.049090918154;4756.262779269185;4778.307145463888;4782.545658162469;4786.018866132435;4836.226203807374;4860;5146.125158723026;5209.6353255272525;5233.058049551041;5308.92036619451;5039.84880453587;5081.415637201769;5081.542661160439;5081.644284900021;5090.821821236239;5150.330457040561;5155.32980712505;5155.355580552431;5161.122609156452;5203.115470370046;4949.356583644103;5050.570701490415;5084.51697088776;5095.1847704367865;5162.845114646567;5089.923651145235;5094.261332106824;5096.668716533413;5104.640177277421;5108.514691104339;4395.033092719596;4398.318260669601;4403;4444.400005999213;4458.621017213508;3721.423179407196;3752.7185461090526;3808.3716186829415;3843.423486476995;3883.650471457582;4419.580139886705;4441.244988351988;4443.2224360736745;4480.955190448119;4496.64026877849;5181;5196.246544251062;5197.58419326453;5198.428172900978;5265.420803711589;4310.374995625617;4380.5838445347645;4411.558844115562;4432.15789000089;4434;4642.79107440166;4657.953420465752;4668.299755036772;4712.95287047132;4726.621870250372;4873.439554451213;4883.804647814048;4884.9985752921275;4885.8045678172475;4948.307236986752;5032.345795046621;5102.948970510396;5120.18077107661;5128.666636668605;5137.255098010843;4817.397825271841;4836.419629644465;4845.7577121143095;4884.462709102067;4887.90224195507;5039;5047.167217409086;5049.6465247432325;5052;5054.21659642756;4985.302057711996;5066.544011039025;5068.366454193226;5123.28274041627;5183.740812959412;4926.458089610212;4951.678140920829;4961.1070007397575;4999.15014447552;5008.123578373843;4981.551660350586;4982.6512144149565;4982.8754281143265;4985.5762260208285;5037.9924015196375;4934.851954441372;4947.653664243612;4952.4057113146655;4952.826651067196;5012.674176178537;4792.496787837227;4961.677490963076;4992.226204938241;5012.1980483122525;5101.132807422821;4871.464138944642;4876.414830220397;4879.316895634543;4927.334809800775;4938.38270216223 18:24:15 INFO - PROCESS | 18345 | |1;ext.html;566.8894565559716;579.6029719642042;584.2784161560472;589.9144624029516;592.6858764854627;27181.592276115873;37569.62430375695;38715.61284387155;38849.8345049649;42360.15279694412;39118.21763564734;39504.40743388851;39568.80215598925;39572.60427395725;39613.8019309903;7592.27873352032;7734.1492435832015;7735.381169506454;7738.264864837832;7741.109772376164;7216.711331546732;9478.857817132748;9737.172340351079;9737.269704772136;10051.296409251356;9664.468454235015;9753.707388778348;9754.512274386288;9759.658411955583;9770.413775173512;9902.059304366074;9974.900250997469;9980.600775968953;9983.90016099837;9986.35088719231;9786.608535658565;9871.901280987242;9884;9885;9894.505274736235;37285.813570932245;37620;37715.434268485995;37731.81134094339;37735;2675.0503571232125;2829.957550636742;2946.7053294670577;3060.908172754821;3488.2849015951924;27229;27788.58317125245;28238.294042648893;28375.432491350264;29612.111636650934;3411.1131105912395;3523.40102182627;3549.804760738158;3553.7157027437743;3610.584782749991;246859;247525;247605;248850;250346.74826625932;49739.25391119136;49741.502584973685;49770.50229497731;49775;50079.499205008215;28658.283542911176;29402.264943376158;29499.410011799642;29502.55746163809;29518.262043448656;14501.854981450262;14677;14677.633059173393;14684.339204735812;14688.19214943167;10198.745031374126;10545.683629491128;10562.207834412297;10564.894351056544;10880;17107.835050515696;17577.29690812353;17614.295428182984;17669.38157164493;17771.289148433916;6584.209894812558;6645.269020407752;6700.664966751643;6843.794686159423;6922.238553759083;742.921993190718;840.5755093677776;888.2893685051932;919.544825311475;953.4041224234853;26209.868950655124;27314.898126487256;27635.723642763314;28577.42845143127;31389.52915706266;878.6748902905965;902.9413088149249;903.3134817538718;924.2282693950492;1056.915446764257;13319.866801332057;14031.298435078204;14068.226247556278;14144.929275353763;14311.57065288043;391149;391568;391850;392194;392629;46191.76904115458;46517;46526;46718.06563868703;46720.299195512096;1078.3475997022003;1079.7030816525362;1100.6202860013282;1153.5616465742964;1178.505027888289;19640.803591963897;27148;28555.14505541725;30186.547201791553;30954.226144346572;45093.32360014537;46028.30957535573;46029.76985115053;46058.07883842237;46104.538954611366;338448;339414;339876;340187;340376;21341.25305614296;23540.76459235386;24696.382590435383;24760.628590571516;25387.111451099125;39288.2142357157;39382.40926386049;39405.408918865665;39479.21041579095;39517.40723889202;32446;32791.8360408206;32824.67175328312;33170.5024424629;33950;33067.66932330742;33090.33819323648;33217.33565328632;33295.66704332926;33802.49296260509;32442.675573243967;32586.34827303488;32762;33424;33804;27656.58515122315;29281.707182927898;29689.851550742107;29791.85104074466;29918.551221731257;22196.66704999394;22358.441038974157;22414.215502457337;22450;22477.887610561844 18:24:15 INFO - PROCESS | 18345 | |2;jquery.html;364.34054361605496;368.40134780980907;368.4694040581562;376.971727120466;383.0423940149626;15816.209189540534;16433.83566164338;16942.745858812126;17054.658906821856;17055.57361065974;14941.551753447387;15575.221238938064;16066.437674681389;16204.756928646051;16207.675846483093;4989.525995030475;5206.37523497179;5242.501962313593;5256.237845512398;5266.34170728659;3332.766706330558;3491.057414498084;3493.179102910814;3510.7717998330154;5049.394072711261;3647.106458917569;5960.0761881908375;5973.581849270553;6097.115918191859;6104.633721976696;5896.380880007623;5998.100284957248;6065.059915713072;6084.1786358841655;6084.2090528231265;5349.197620356938;5376.865578360533;5381.48875856793;5384.73076346185;5386.353637563518;20423.795762042337;21016.894915525325;21057.157713691435;21226.469338266666;21237.15051397942;1825.9452216433529;1904.866659333847;1908.5419499320203;2234.4190510467233;2246.887655617229;12697.301648409333;13414;13767.586972390844;13816.792748108786;14083.084599501102;1731.740238964153;1754.1404711691307;1757.8330058644408;1770.0264854330067;1796;66565;66800.6659966697;66895.3310466894;68503;69299.65350173217;8795.384323097449;8849.159329863653;9763.951180244054;9861.309708320348;10051.69844904654;13280.800787988188;13282.734345313233;13285.93357033228;13301.401436935168;13303.201807891557;7338.596377199198;7347.5224110432655;7635.503692259986;7682.464786620402;7685.654145563351;7171.103612048494;7194.388476979515;7278.599677017814;7422.294881986203;7461.216572259943;6022.247219097613;6025.126356678257;6078.848028799315;6117.602355846856;6270.153529273581;1690;1706.3174730108055;1736.8350006749338;1782.2158250369796;1797.3170195325954;797.7806103321517;831.4595512916652;892.1390857822232;936.7189843047058;936.7798567336671;16649.25078371476;17764.378246761302;17811.376601819135;17896.373626922996;17946.730799038025;828.9212524810133;861.4658911474801;866.3532906969143;870.7561882672861;898.3756289378819;9809;10066.295359324777;10070.748231294276;10151.441670708182;10160.542775575117;369509;375074;376444;393109;403552;48662.756686216344;50206.49793502091;87066;87078.56460717655;90300.5484972571;853.7438768369465;874.6763697431991;899.9955000224957;907.111031189438;921.4885738415244;15837.366505340118;15839.128847913475;15954.202289885692;16094.839051609333;16596.08721520328;37453.250934980606;38057.80971095127;39005.80497097496;39277.41083883687;39476.605233948445;48733.26900096572;50648;51008.74495627498;51544;52998.470015300896;7290.307420795016;7413.332800048021;7440;7441.739539116105;7644.961775191088;8031.277185053374;11050.447477625925;11287.717807054889;11463;11514.19400641947;10139.188864890939;10599.417032063002;10638.14894808398;10663.920535495814;10793.136549075898;10597.89402106;10716;10785.352878827296;10868.836967445337;10902.291351062155;10433.478326083818;10588.894111058791;10743.979321964402;10932.234743567873;11272.549098035986;7388.298111679382;7435.2527156117185;7605.239476052572;7867.84264314722;7913.762587122165;9900.405975641484;10003.149732272546;10455;10492.895071049192;10574.15406767472 18:24:15 INFO - PROCESS | 18345 | |3;mootools.html;1195.2350495682758;1322.120789674866;1334.61296224095;1420.2401715082426;1657.494464188422;7076.716931322748;7380.409567234622;7454.142773581033;7667.654955527;7682.0397450318715;7557.433192510556;7677.5393476391455;7679.731209407658;7690.538567685929;7691.269329413711;2188.9014994325294;2219.94450138747;2222.688823564702;2223.2218723446827;2224.0992398078806;2148.1836901977226;2187.1907394264144;2195.407240045187;2198.505336299336;2199.6480563109826;2107.9789202108013;2139.7860213978634;2144.613969485492;2147.38799442159;2176.031665908671;2146.1093646136833;2152.881591512466;2153.310940499041;2153.547754971458;2157.2880949286696;2084.3017589107517;2126.096409026173;2129.1802655977403;2132.4349047502424;2133.4346398204334;7503.3997280218;7545.320921117126;7563.357114645261;7583.886241706379;7622.085349758065;4417.425734654506;4551.2262915304145;4554.612857907082;4556.2937744649635;4562.5665561771575;2747.093459158473;2851.9714802851913;2899.913002609925;2904.8402337871407;2906.520424130016;966.9806603867884;972.7033254857266;978.2076518020355;983.7196399026243;996.9651062212861;9975.600975960953;9988.800223995551;10019.248556358229;10033.297669163161;10045.19638428924;3967.5437324708028;4022.577629348935;4083.224187404384;4146.419501269825;4161.2301724181125;8075.3539716822515;8208.958955205186;8221.917780822116;8226;8231.711890083818;4430.955690443118;4435.645148388121;4437.733735975852;4440.4227450431235;4441.71128876622;3727.5154229950476;3877.7091718121255;3941.6661276439113;4102.323116685773;4103.199876024147;3333.983330083334;3401.9659803402146;3416.7095796857047;3476.1309672581856;3476.366107929269;1043.994780026095;1048.6067724603274;1049.9002594753485;1061.930974486656;1065.4193464561833;681.6114814555656;698.1447726534954;735.8270806360501;782.4640121516779;811.472542847154;4837;5023;5066.341375621219;5125.718085505333;5217.713025783542;805.6817557064927;819.3936486999578;825.5459497276594;853.7737499562621;887.6893087419352;3343.6990670839255;3369.174552234669;3433.6051354094598;3461.9653803462147;3656.213914008501;8737.257333126629;9335.859962100574;9342.159205671522;9343.205827504602;9354.95322523383;9070.047644997181;9084.954575227213;9090.13643703847;9114.680986165453;9170.633174673072;6772.966135169391;6838.34517408634;6947.687354069079;6955.234924158339;6971.895421568681;4856.223004319292;4931.753412329441;4943.431505376784;4952.4304704959395;5070.873228169326;8304.045034821072;8429.831403372022;8541.103184165573;8557.828843423222;8592.527410992205;7887;8323.12607176262;8530.530820804915;8623.353248506262;8668.263197628272;5939.2575928009;6098.817035488943;6128.785492507741;6244.718987645476;6248;6894.24163342042;7215.098112735908;7271.709131634675;7273.527220730635;7279.59962202084;6295.9370406297185;6544.607323560602;6668.599884006975;6683.465322774264;6786.524943253925;6284.214473190851;6383.872322553616;6388.39310265524;6405.647689376942;6521.8695626088165;5800.303963524404;5812.738426770721;5847.736851841592;5886.028805247258;5909;4239.533651298417;4253.128108737639;4263.573642635712;4371.672124590732;4418.425604671372;4324.286492728665;4350.651947844228;4381.123775245028;4460.509343972096;4521.547845215452 18:24:15 INFO - PROCESS | 18345 | |4;prototype.html;303.9711227433394;308.1863879358494;310.37304644617865;314.96377916539603;314.9888856769769;4777.952220477794;4824.6381521385865;4830.372051633287;4841.983183531463;4847.878803029926;4735.408073990751;4756.619470442365;4768.856934291976;4795.664303498756;4806.927896081562;3614.7108231341563;3686.64976827201;3803.125281185329;3837.9808100959526;3844.903877403059;3786.1069465267365;3801.8669346572738;3802.1255111324413;3815.5802861685193;3817.331966905794;3788.5942426559195;3798.525184351956;3809.314323421783;3817.6945844332386;3824.521934758155;3616.951084185583;3621.040424287566;3765.5293088363956;3795.5635101963353;3802.8288727007352;3323.7839540429795;3325.567676202078;3325.9168520786934;3326.3534550397467;3331;4649.442066951988;4663.743494107823;4664.486906440289;4666.486686464486;4700.600448961843;4139.606737359946;4304.504981927087;4305.483341998949;4310.396544483775;4311.439512863308;4953.628477864138;5028.673136246132;5046.167382381903;5059.898802023976;5061.722224998728;3563.2873425315042;3587.677109060197;3594.3889538778208;3604.585472670624;3639.162992511711;5244.370675518924;5251.658642188246;5280.260763493114;5289.153735402318;5298.708571028592;3409.710174635134;3590.761187390346;3595.280943811248;3607.855685772592;3610.9097272568397;3644.726645501598;3655.287218992271;3669.1927775889794;3680.3375392429084;3681.7054635629083;4247.256730072225;4248.362745588187;4250.447441832542;4253.404523366669;4289.6139347458875;4845.806167753321;5029.497050294941;5143.8456846294675;5147.433782283946;5158.535731784157;1284.9036322275679;1303.7653222420056;1306.2684896458013;1306.3076569418224;1310.9868901311056;824.8309096635176;840.2395831772134;843.304273973974;850.4302117581235;850.5194565070715;4052.5542190359038;4077.327241005201;4339.349097635317;4373.912521749611;4486.685931984795;5225.372955245395;5231.346081739783;5233.607479439057;5244.895102097937;5245.7114858683135;4512.165249428866;4513.593776560059;4514.164879497303;4520.480144783326;4533.433320834895;2629.500394924958;2730.604062410939;2763.2677340504783;2765.267204190891;2785.4011387551773;4923.803047878045;4926.817563784666;4931.506849315111;4941.98689268699;4980.726060066659;5348.913923569811;5355.598330125256;5378.51593356592;5378.987766850576;5405.702686352289;4701.3888194535175;4946.579540739005;4974.8756281093265;4980.228064649985;4988.700677959334;5232.869178270574;5264.1575448592685;5264.842054738364;5267.788408666105;5272.947270527399;5209.791608335624;5223.973880130575;5230.869228269324;5239.738013099406;5244.213367994831;4626.282926146453;4916.852494425173;4918.8770280743265;4922.753862306942;4926.581240594446;3789.204267103939;3806.657400833938;3820.5988371220624;3832.3485007549343;3849.345611246039;5187.144121219956;5202.791888324425;5244.711540865289;5255.868603284948;5264;4901.554041557678;4976.427710813303;5011.51929794754;5021.196608542616;5059.266406371129;5004.499550045112;5034.219695947134;5034.798608055783;5043.84868453947;5046.823361182341;4978.7510624467905;4986.703457101107;4986.877952460667;4988.351514303117;5011.398632164163;4822.614190864792;4863.635227357891;5104.489551044866;5141.483262437665;5142.382914050338;4828.34441057424;4963.975180124076;4976.751162441936;4986.426560945538;4987.351644286219 18:24:15 INFO - PROCESS | 18345 | |5;yui.html;588.0666936814952;626.2015929689645;643.9452646525045;683.12928817531;709.8438343564417;11928.940355298222;12846.16499927504;12858.906992905615;12860.871391286084;12912.935435322823;11466.08271338293;11512.079033677286;11513.4818933148;11523.654290371282;11535.307881527115;609.2719200555338;737.3290305821703;739.7152096442882;758.1281526244808;760.5588758520045;759.4418102694515;767.45510687412;768.2279309294152;768.4735955870219;769.0233403577454;794.3803833010261;794.6860989908982;794.7854079398559;795.220683729945;795.2445177081767;633.6958260035162;772.6909236305466;791.7664289034749;791.9960400197991;794.8728203487415;582.4815913836688;583.2883881664362;584.3630442817307;584.4214227914376;584.6755050946723;12165.69585760363;12231.571894983721;12245.93877030618;12246.387680615933;12260.44827982743;11461.713457163554;11482.483288251966;11852.525898964117;11919.702007449796;12468.937655311756;10510.527026283835;10529.578816847317;10708.30396024256;10805.513751881184;10934.890651093467;3963.2271707017144;4046.7774272415004;4051.3315302974975;4065.7967101645095;4186.120914607905;65394.346056539776;66211.33788662148;67027.32972670309;67736.6613166936;68599.65700171467;9088.09119088086;9119.224865886474;9315.854444772147;9367.578458969363;9637.325387222967;17751.55621109457;17756.3785267515;17766.111694415227;17836.732449013274;17870.374536891148;6086.2696476422225;6086.726097325631;6093;6139.754409823647;6231.345708700612;3599.4240921452965;3600.7479476436397;3644.9817750911607;3646.507721457622;3674.9448758268645;2839.559868220429;2949.8820047197873;2954.881804727787;2955.807872488281;2955.8374289414287;515.1577171324836;530.2947080383158;539.0674133748637;544.5534661577548;544.7358031354789;480.43788767142524;506.05873076078467;508.8448023352839;508.98473045808686;519.5402069168813;6949.861002779916;7162.641867906584;7326.3406293433845;7407.8888816667795;7558.508696934681;530.1200007986723;531.4455119624779;537.6478406643655;547.0258571478214;552.3454706173197;3463.3765922133753;3494.3011397720556;3510.26284480262;3581.8925432237074;3625.9637403625625;60654.696726516086;61283;61581.07628385578;61674.69162654158;106770;88142;88692.55653721819;89553;90136;90564.5471772637;62929.68535157295;64179.35820641734;71211.2878871215;77009.61495192489;82409;6822.761203357859;7234.10820306864;7240.456965727697;7285.562866227831;7287.599182045038;40853.79573102116;41249;42527.574724252365;42800.78599606982;42937.5706242946;53569.73215133899;59225.40774592199;60294.57348838625;61872;61965;9406.482643454676;9509.429434233969;9595.808083838145;9631.133198012212;9681.806363872824;9963.153131983572;9988.300818942604;10034.598616055277;10040;10124.797504049731;10030.548625311732;10158.13655839262;10389.37663740178;10549.52527136296;10789.352638841381;9772.755681108029;10274.794504110025;10421.739456513647;10771.030607245386;10773.676789696321;9756.51217439111;9974.900250997398;10081.092701656886;10152.340097893613;10306;7985.361171106414;8269.421140520346;8385.1195624461;8439.746807595782;8609.440386374865;7639.388848892187;8659.870101948602;8768.25469835071;8791.868121978303;8854.454346863127 18:24:15 INFO - PROCESS | 18345 | __end_tp_report 18:24:15 INFO - PROCESS | 18345 | __start_cc_report 18:24:15 INFO - PROCESS | 18345 | _x_x_mozilla_cycle_collect,1380 18:24:15 INFO - PROCESS | 18345 | __end_cc_report 18:24:15 INFO - PROCESS | 18345 | __startTimestamp1472261055678__endTimestamp 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | ------- Summary: start ------- 18:24:15 INFO - PROCESS | 18345 | Number of tests: 6 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#0] dojo.html Cycles:165 Average:4738.62 Median:4879.32 stddev:496.89 (10.2%) stddev-sans-first:469.65 18:24:15 INFO - PROCESS | 18345 | Values: 2614.6 2614.8 2625.9 2655.8 2671.0 4671.8 4725.0 4752.6 4780.5 4854.6 4708.7 4772.2 4780.2 4828.1 4845.0 4287.3 4322.3 4359.7 4408.0 4559.4 4449.1 4489.8 4507.8 4533.5 4545.4 4300.7 4352.7 4355.1 4383.5 4418.1 4344.4 4399.0 4403.1 4471.3 4474.0 4330.0 4337.2 4339.4 4389.3 4440.9 4775.8 4843.9 4844.8 4865.5 4911.8 4986.6 5056.7 5092.3 5126.8 5163.1 5116.1 5125.7 5135.3 5136.1 5140.0 4756.3 4778.3 4782.5 4786.0 4836.2 4860.0 5146.1 5209.6 5233.1 5308.9 5039.8 5081.4 5081.5 5081.6 5090.8 5150.3 5155.3 5155.4 5161.1 5203.1 4949.4 5050.6 5084.5 5095.2 5162.8 5089.9 5094.3 5096.7 5104.6 5108.5 4395.0 4398.3 4403.0 4444.4 4458.6 3721.4 3752.7 3808.4 3843.4 3883.7 4419.6 4441.2 4443.2 4481.0 4496.6 5181.0 5196.2 5197.6 5198.4 5265.4 4310.4 4380.6 4411.6 4432.2 4434.0 4642.8 4658.0 4668.3 4713.0 4726.6 4873.4 4883.8 4885.0 4885.8 4948.3 5032.3 5102.9 5120.2 5128.7 5137.3 4817.4 4836.4 4845.8 4884.5 4887.9 5039.0 5047.2 5049.6 5052.0 5054.2 4985.3 5066.5 5068.4 5123.3 5183.7 4926.5 4951.7 4961.1 4999.2 5008.1 4981.6 4982.7 4982.9 4985.6 5038.0 4934.9 4947.7 4952.4 4952.8 5012.7 4792.5 4961.7 4992.2 5012.2 5101.1 4871.5 4876.4 4879.3 4927.3 4938.4 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#1] ext.html Cycles:180 Average:46762.95 Median:340281.50 stddev:87530.68 (25.7%) stddev-sans-first:87707.51 18:24:15 INFO - PROCESS | 18345 | Values: 566.9 579.6 584.3 589.9 592.7 27181.6 37569.6 38715.6 38849.8 42360.2 39118.2 39504.4 39568.8 39572.6 39613.8 7592.3 7734.1 7735.4 7738.3 7741.1 7216.7 9478.9 9737.2 9737.3 10051.3 9664.5 9753.7 9754.5 9759.7 9770.4 9902.1 9974.9 9980.6 9983.9 9986.4 9786.6 9871.9 9884.0 9885.0 9894.5 37285.8 37620.0 37715.4 37731.8 37735.0 2675.1 2830.0 2946.7 3060.9 3488.3 27229.0 27788.6 28238.3 28375.4 29612.1 3411.1 3523.4 3549.8 3553.7 3610.6 246859.0 247525.0 247605.0 248850.0 250346.7 49739.3 49741.5 49770.5 49775.0 50079.5 28658.3 29402.3 29499.4 29502.6 29518.3 14501.9 14677.0 14677.6 14684.3 14688.2 10198.7 10545.7 10562.2 10564.9 10880.0 17107.8 17577.3 17614.3 17669.4 17771.3 6584.2 6645.3 6700.7 6843.8 6922.2 742.9 840.6 888.3 919.5 953.4 26209.9 27314.9 27635.7 28577.4 31389.5 878.7 902.9 903.3 924.2 1056.9 13319.9 14031.3 14068.2 14144.9 14311.6 391149.0 391568.0 391850.0 392194.0 392629.0 46191.8 46517.0 46526.0 46718.1 46720.3 1078.3 1079.7 1100.6 1153.6 1178.5 19640.8 27148.0 28555.1 30186.5 30954.2 45093.3 46028.3 46029.8 46058.1 46104.5 338448.0 339414.0 339876.0 340187.0 340376.0 21341.3 23540.8 24696.4 24760.6 25387.1 39288.2 39382.4 39405.4 39479.2 39517.4 32446.0 32791.8 32824.7 33170.5 33950.0 33067.7 33090.3 33217.3 33295.7 33802.5 32442.7 32586.3 32762.0 33424.0 33804.0 27656.6 29281.7 29689.9 29791.9 29918.6 22196.7 22358.4 22414.2 22450.0 22477.9 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#2] jquery.html Cycles:180 Average:24181.45 Median:207250.90 stddev:63331.60 (30.6%) stddev-sans-first:63484.01 18:24:15 INFO - PROCESS | 18345 | Values: 364.3 368.4 368.5 377.0 383.0 15816.2 16433.8 16942.7 17054.7 17055.6 14941.6 15575.2 16066.4 16204.8 16207.7 4989.5 5206.4 5242.5 5256.2 5266.3 3332.8 3491.1 3493.2 3510.8 5049.4 3647.1 5960.1 5973.6 6097.1 6104.6 5896.4 5998.1 6065.1 6084.2 6084.2 5349.2 5376.9 5381.5 5384.7 5386.4 20423.8 21016.9 21057.2 21226.5 21237.2 1825.9 1904.9 1908.5 2234.4 2246.9 12697.3 13414.0 13767.6 13816.8 14083.1 1731.7 1754.1 1757.8 1770.0 1796.0 66565.0 66800.7 66895.3 68503.0 69299.7 8795.4 8849.2 9764.0 9861.3 10051.7 13280.8 13282.7 13285.9 13301.4 13303.2 7338.6 7347.5 7635.5 7682.5 7685.7 7171.1 7194.4 7278.6 7422.3 7461.2 6022.2 6025.1 6078.8 6117.6 6270.2 1690.0 1706.3 1736.8 1782.2 1797.3 797.8 831.5 892.1 936.7 936.8 16649.3 17764.4 17811.4 17896.4 17946.7 828.9 861.5 866.4 870.8 898.4 9809.0 10066.3 10070.7 10151.4 10160.5 369509.0 375074.0 376444.0 393109.0 403552.0 48662.8 50206.5 87066.0 87078.6 90300.5 853.7 874.7 900.0 907.1 921.5 15837.4 15839.1 15954.2 16094.8 16596.1 37453.3 38057.8 39005.8 39277.4 39476.6 48733.3 50648.0 51008.7 51544.0 52998.5 7290.3 7413.3 7440.0 7441.7 7645.0 8031.3 11050.4 11287.7 11463.0 11514.2 10139.2 10599.4 10638.1 10663.9 10793.1 10597.9 10716.0 10785.4 10868.8 10902.3 10433.5 10588.9 10744.0 10932.2 11272.5 7388.3 7435.3 7605.2 7867.8 7913.8 9900.4 10003.1 10455.0 10492.9 10574.2 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#3] mootools.html Cycles:180 Average:4900.81 Median:5098.30 stddev:2703.20 (53.0%) stddev-sans-first:2696.43 18:24:15 INFO - PROCESS | 18345 | Values: 1195.2 1322.1 1334.6 1420.2 1657.5 7076.7 7380.4 7454.1 7667.7 7682.0 7557.4 7677.5 7679.7 7690.5 7691.3 2188.9 2219.9 2222.7 2223.2 2224.1 2148.2 2187.2 2195.4 2198.5 2199.6 2108.0 2139.8 2144.6 2147.4 2176.0 2146.1 2152.9 2153.3 2153.5 2157.3 2084.3 2126.1 2129.2 2132.4 2133.4 7503.4 7545.3 7563.4 7583.9 7622.1 4417.4 4551.2 4554.6 4556.3 4562.6 2747.1 2852.0 2899.9 2904.8 2906.5 967.0 972.7 978.2 983.7 997.0 9975.6 9988.8 10019.2 10033.3 10045.2 3967.5 4022.6 4083.2 4146.4 4161.2 8075.4 8209.0 8221.9 8226.0 8231.7 4431.0 4435.6 4437.7 4440.4 4441.7 3727.5 3877.7 3941.7 4102.3 4103.2 3334.0 3402.0 3416.7 3476.1 3476.4 1044.0 1048.6 1049.9 1061.9 1065.4 681.6 698.1 735.8 782.5 811.5 4837.0 5023.0 5066.3 5125.7 5217.7 805.7 819.4 825.5 853.8 887.7 3343.7 3369.2 3433.6 3462.0 3656.2 8737.3 9335.9 9342.2 9343.2 9355.0 9070.0 9085.0 9090.1 9114.7 9170.6 6773.0 6838.3 6947.7 6955.2 6971.9 4856.2 4931.8 4943.4 4952.4 5070.9 8304.0 8429.8 8541.1 8557.8 8592.5 7887.0 8323.1 8530.5 8623.4 8668.3 5939.3 6098.8 6128.8 6244.7 6248.0 6894.2 7215.1 7271.7 7273.5 7279.6 6295.9 6544.6 6668.6 6683.5 6786.5 6284.2 6383.9 6388.4 6405.6 6521.9 5800.3 5812.7 5847.7 5886.0 5909.0 4239.5 4253.1 4263.6 4371.7 4418.4 4324.3 4350.7 4381.1 4460.5 4521.5 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#4] prototype.html Cycles:180 Average:4201.03 Median:4786.81 stddev:1225.82 (25.6%) stddev-sans-first:1193.85 18:24:15 INFO - PROCESS | 18345 | Values: 304.0 308.2 310.4 315.0 315.0 4778.0 4824.6 4830.4 4842.0 4847.9 4735.4 4756.6 4768.9 4795.7 4806.9 3614.7 3686.6 3803.1 3838.0 3844.9 3786.1 3801.9 3802.1 3815.6 3817.3 3788.6 3798.5 3809.3 3817.7 3824.5 3617.0 3621.0 3765.5 3795.6 3802.8 3323.8 3325.6 3325.9 3326.4 3331.0 4649.4 4663.7 4664.5 4666.5 4700.6 4139.6 4304.5 4305.5 4310.4 4311.4 4953.6 5028.7 5046.2 5059.9 5061.7 3563.3 3587.7 3594.4 3604.6 3639.2 5244.4 5251.7 5280.3 5289.2 5298.7 3409.7 3590.8 3595.3 3607.9 3610.9 3644.7 3655.3 3669.2 3680.3 3681.7 4247.3 4248.4 4250.4 4253.4 4289.6 4845.8 5029.5 5143.8 5147.4 5158.5 1284.9 1303.8 1306.3 1306.3 1311.0 824.8 840.2 843.3 850.4 850.5 4052.6 4077.3 4339.3 4373.9 4486.7 5225.4 5231.3 5233.6 5244.9 5245.7 4512.2 4513.6 4514.2 4520.5 4533.4 2629.5 2730.6 2763.3 2765.3 2785.4 4923.8 4926.8 4931.5 4942.0 4980.7 5348.9 5355.6 5378.5 5379.0 5405.7 4701.4 4946.6 4974.9 4980.2 4988.7 5232.9 5264.2 5264.8 5267.8 5272.9 5209.8 5224.0 5230.9 5239.7 5244.2 4626.3 4916.9 4918.9 4922.8 4926.6 3789.2 3806.7 3820.6 3832.3 3849.3 5187.1 5202.8 5244.7 5255.9 5264.0 4901.6 4976.4 5011.5 5021.2 5059.3 5004.5 5034.2 5034.8 5043.8 5046.8 4978.8 4986.7 4986.9 4988.4 5011.4 4822.6 4863.6 5104.5 5141.5 5142.4 4828.3 4964.0 4976.8 4986.4 4987.4 18:24:15 INFO - PROCESS | 18345 | 18:24:15 INFO - PROCESS | 18345 | [#5] yui.html Cycles:180 Average:16493.97 Median:584.55 stddev:23876.75 (4084.6%) stddev-sans-first:23913.86 18:24:15 INFO - PROCESS | 18345 | Values: 588.1 626.2 643.9 683.1 709.8 11928.9 12846.2 12858.9 12860.9 12912.9 11466.1 11512.1 11513.5 11523.7 11535.3 609.3 737.3 739.7 758.1 760.6 759.4 767.5 768.2 768.5 769.0 794.4 794.7 794.8 795.2 795.2 633.7 772.7 791.8 792.0 794.9 582.5 583.3 584.4 584.4 584.7 12165.7 12231.6 12245.9 12246.4 12260.4 11461.7 11482.5 11852.5 11919.7 12468.9 10510.5 10529.6 10708.3 10805.5 10934.9 3963.2 4046.8 4051.3 4065.8 4186.1 65394.3 66211.3 67027.3 67736.7 68599.7 9088.1 9119.2 9315.9 9367.6 9637.3 17751.6 17756.4 17766.1 17836.7 17870.4 6086.3 6086.7 6093.0 6139.8 6231.3 3599.4 3600.7 3645.0 3646.5 3674.9 2839.6 2949.9 2954.9 2955.8 2955.8 515.2 530.3 539.1 544.6 544.7 480.4 506.1 508.8 509.0 519.5 6949.9 7162.6 7326.3 7407.9 7558.5 530.1 531.4 537.6 547.0 552.3 3463.4 3494.3 3510.3 3581.9 3626.0 60654.7 61283.0 61581.1 61674.7 106770.0 88142.0 88692.6 89553.0 90136.0 90564.5 62929.7 64179.4 71211.3 77009.6 82409.0 6822.8 7234.1 7240.5 7285.6 7287.6 40853.8 41249.0 42527.6 42800.8 42937.6 53569.7 59225.4 60294.6 61872.0 61965.0 9406.5 9509.4 9595.8 9631.1 9681.8 9963.2 9988.3 10034.6 10040.0 10124.8 10030.5 10158.1 10389.4 10549.5 10789.4 9772.8 10274.8 10421.7 10771.0 10773.7 9756.5 9974.9 10081.1 10152.3 10306.0 7985.4 8269.4 8385.1 8439.7 8609.4 7639.4 8659.9 8768.3 8791.9 8854.5 18:24:15 INFO - PROCESS | 18345 | -------- Summary: end -------- 18:24:15 INFO - PROCESS | 18345 | 18:24:16 INFO - TEST-INFO | 18345: exit 0 18:24:16 INFO - TEST-OK | dromaeo_css | took 1121380ms 18:24:16 INFO - TEST-START | kraken 18:24:16 INFO - operating with platform_type : linux_ 18:24:16 INFO - Initialising browser for kraken test... 18:24:16 INFO - TEST-INFO | started process 18652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn1rVSj/profile http://localhost:56058/getInfo.html) 18:24:20 INFO - PROCESS | 18652 | __metrics Screen width/height:1600/1200 18:24:20 INFO - PROCESS | 18652 | colorDepth:24 18:24:20 INFO - PROCESS | 18652 | Browser inner width/height: 1024/697 18:24:20 INFO - PROCESS | 18652 | __metrics 18:24:21 INFO - PROCESS | 18652 | 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" 18:24:21 INFO - PROCESS | 18652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:22 INFO - PROCESS | 18652 | console.error: PushService: 18:24:22 INFO - PROCESS | 18652 | stateChangeProcessEnqueue: Error transitioning state 18:24:22 INFO - PROCESS | 18652 | UnknownError 18:24:22 INFO - TEST-INFO | 18652: exit 0 18:24:22 INFO - Browser initialized. 18:24:22 INFO - Running cycle 1/1 for kraken test... 18:24:22 INFO - TEST-INFO | started process 18732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn1rVSj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:34 INFO - PROCESS | 18732 | RSS: Main: 180736000 18:24:34 INFO - PROCESS | 18732 | 18:24:37 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56058/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:38 INFO - PROCESS | 18732 | RSS: Main: 233459712 18:24:38 INFO - PROCESS | 18732 | 18:24:41 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56058/tests/kraken/driver.html?testName=audio-dft) 18:24:41 INFO - PROCESS | 18732 | RSS: Main: 224452608 18:24:41 INFO - PROCESS | 18732 | 18:24:45 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56058/tests/kraken/driver.html?testName=audio-fft) 18:24:45 INFO - PROCESS | 18732 | RSS: Main: 303558656 18:24:45 INFO - PROCESS | 18732 | 18:24:48 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56058/tests/kraken/driver.html?testName=audio-oscillator) 18:24:48 INFO - PROCESS | 18732 | RSS: Main: 212811776 18:24:48 INFO - PROCESS | 18732 | 18:24:50 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56058/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:51 INFO - PROCESS | 18732 | RSS: Main: 214724608 18:24:51 INFO - PROCESS | 18732 | 18:24:54 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56058/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:55 INFO - PROCESS | 18732 | RSS: Main: 252923904 18:24:55 INFO - PROCESS | 18732 | 18:24:58 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56058/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:59 INFO - PROCESS | 18732 | RSS: Main: 247169024 18:24:59 INFO - PROCESS | 18732 | 18:25:02 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56058/tests/kraken/driver.html?testName=json-parse-financial) 18:25:02 INFO - PROCESS | 18732 | RSS: Main: 250834944 18:25:02 INFO - PROCESS | 18732 | 18:25:04 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56058/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:05 INFO - PROCESS | 18732 | RSS: Main: 214036480 18:25:05 INFO - PROCESS | 18732 | 18:25:06 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:07 INFO - PROCESS | 18732 | RSS: Main: 221081600 18:25:07 INFO - PROCESS | 18732 | 18:25:09 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:10 INFO - PROCESS | 18732 | RSS: Main: 221704192 18:25:10 INFO - PROCESS | 18732 | 18:25:13 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:13 INFO - PROCESS | 18732 | RSS: Main: 223301632 18:25:13 INFO - PROCESS | 18732 | 18:25:16 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:17 INFO - PROCESS | 18732 | RSS: Main: 220479488 18:25:17 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | Cycle 1(1): loaded http://localhost:56058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56058/tests/kraken/driver.html?testName=ai-astar) 18:25:19 INFO - PROCESS | 18732 | RSS: Main: 272031744 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | __start_tp_report 18:25:19 INFO - PROCESS | 18732 | _x_x_mozilla_page_load 18:25:19 INFO - PROCESS | 18732 | _x_x_mozilla_page_load_details 18:25:19 INFO - PROCESS | 18732 | |i|pagename|runs| 18:25:19 INFO - PROCESS | 18732 | |0;ai-astar;114;114;131;114;114;114;114;113;113;114 18:25:19 INFO - PROCESS | 18732 | |1;audio-beat-detection;153;123;146;189;127;152;148;125;183;131 18:25:19 INFO - PROCESS | 18732 | |2;audio-dft;163;162;163;160;160;161;161;162;162;162 18:25:19 INFO - PROCESS | 18732 | |3;audio-fft;75;75;77;184;77;76;77;76;165;77 18:25:19 INFO - PROCESS | 18732 | |4;audio-oscillator;83;83;134;83;85;83;139;83;85;131 18:25:19 INFO - PROCESS | 18732 | |5;imaging-gaussian-blur;117;117;116;117;117;116;116;117;117;116 18:25:19 INFO - PROCESS | 18732 | |6;imaging-darkroom;151;148;148;148;147;147;146;147;147;147 18:25:19 INFO - PROCESS | 18732 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;95 18:25:19 INFO - PROCESS | 18732 | |8;json-parse-financial;65;64;64;64;64;65;64;65;70;64 18:25:19 INFO - PROCESS | 18732 | |9;json-stringify-tinderbox;65;64;64;65;65;65;66;66;65;65 18:25:19 INFO - PROCESS | 18732 | |10;stanford-crypto-aes;82;81;82;81;82;81;80;87;85;82 18:25:19 INFO - PROCESS | 18732 | |11;stanford-crypto-ccm;143;137;139;132;134;143;138;137;173;132 18:25:19 INFO - PROCESS | 18732 | |12;stanford-crypto-pbkdf2;177;173;188;172;176;179;174;177;176;179 18:25:19 INFO - PROCESS | 18732 | |13;stanford-crypto-sha256-iterative;72;69;78;71;69;69;69;69;68;69 18:25:19 INFO - PROCESS | 18732 | __end_tp_report 18:25:19 INFO - PROCESS | 18732 | __start_cc_report 18:25:19 INFO - PROCESS | 18732 | _x_x_mozilla_cycle_collect,1959 18:25:19 INFO - PROCESS | 18732 | __end_cc_report 18:25:19 INFO - PROCESS | 18732 | __startTimestamp1472261119487__endTimestamp 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | ------- Summary: start ------- 18:25:19 INFO - PROCESS | 18732 | Number of tests: 14 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#0] ai-astar Cycles:10 Average:115.50 Median:114.00 stddev:5.46 (4.8%) stddev-sans-first:5.77 18:25:19 INFO - PROCESS | 18732 | Values: 114.0 114.0 131.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:150.00 stddev:23.18 (15.5%) stddev-sans-first:24.50 18:25:19 INFO - PROCESS | 18732 | Values: 153.0 123.0 146.0 189.0 127.0 152.0 148.0 125.0 183.0 131.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.01 18:25:19 INFO - PROCESS | 18732 | Values: 163.0 162.0 163.0 160.0 160.0 161.0 161.0 162.0 162.0 162.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#3] audio-fft Cycles:10 Average:95.90 Median:76.50 stddev:41.67 (54.5%) stddev-sans-first:43.51 18:25:19 INFO - PROCESS | 18732 | Values: 75.0 75.0 77.0 184.0 77.0 76.0 77.0 76.0 165.0 77.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:83.00 stddev:24.77 (29.8%) stddev-sans-first:25.59 18:25:19 INFO - PROCESS | 18732 | Values: 83.0 83.0 134.0 83.0 85.0 83.0 139.0 83.0 85.0 131.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:25:19 INFO - PROCESS | 18732 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:147.50 stddev:1.35 (0.9%) stddev-sans-first:0.67 18:25:19 INFO - PROCESS | 18732 | Values: 151.0 148.0 148.0 148.0 147.0 147.0 146.0 147.0 147.0 147.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:25:19 INFO - PROCESS | 18732 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:1.85 (2.9%) stddev-sans-first:1.96 18:25:19 INFO - PROCESS | 18732 | Values: 65.0 64.0 64.0 64.0 64.0 65.0 64.0 65.0 70.0 64.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 18:25:19 INFO - PROCESS | 18732 | Values: 65.0 64.0 64.0 65.0 65.0 65.0 66.0 66.0 65.0 65.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.00 stddev:2.11 (2.6%) stddev-sans-first:2.24 18:25:19 INFO - PROCESS | 18732 | Values: 82.0 81.0 82.0 81.0 82.0 81.0 80.0 87.0 85.0 82.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:138.50 stddev:11.96 (8.6%) stddev-sans-first:12.66 18:25:19 INFO - PROCESS | 18732 | Values: 143.0 137.0 139.0 132.0 134.0 143.0 138.0 137.0 173.0 132.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:177.00 stddev:4.48 (2.5%) stddev-sans-first:4.76 18:25:19 INFO - PROCESS | 18732 | Values: 177.0 173.0 188.0 172.0 176.0 179.0 174.0 177.0 176.0 179.0 18:25:19 INFO - PROCESS | 18732 | 18:25:19 INFO - PROCESS | 18732 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:69.00 stddev:2.95 (4.3%) stddev-sans-first:3.06 18:25:19 INFO - PROCESS | 18732 | Values: 72.0 69.0 78.0 71.0 69.0 69.0 69.0 69.0 68.0 69.0 18:25:19 INFO - PROCESS | 18732 | -------- Summary: end -------- 18:25:19 INFO - PROCESS | 18732 | 18:25:20 INFO - TEST-INFO | 18732: exit 0 18:25:20 INFO - TEST-OK | kraken | took 64222ms 18:25:20 INFO - SUITE-END | took 1185s 18:25:21 INFO - Completed test suite (00:19:45) 18:25:21 INFO - javascript benchmark 18:25:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2614.607808828676, 2614.830036047657, 2625.9474810503802, 2655.8140930134887, 2671.0384261665795, 4671.813127474897, 4724.952750472494, 4752.59602933751, 4780.521947805218, 4854.56308932196, 4708.670393072486, 4772.188727916254, 4780.163471392501, 4828.0585285869265, 4845.030993801245, 4287.292596721537, 4322.28682267427, 4359.673024523157, 4407.955920440802, 4559.407277053995, 4449.065696203801, 4489.753063581502, 4507.774611269439, 4533.523979982104, 4545.386372839657, 4300.6989510734265, 4352.73883566987, 4355.063661312801, 4383.473983122046, 4418.094290670437, 4344.391785150082, 4399.0, 4403.075354175627, 4471.3293006049025, 4474.038081812426, 4330.02574420756, 4337.240982827992, 4339.414179085815, 4389.341598760179, 4440.866773996785, 4775.761211939423, 4843.927341089887, 4844.781984810692, 4865.537773911473, 4911.803527858881, 4986.601071914238, 5056.74716264189, 5092.28707980883, 5126.820561280375, 5163.122269214205, 5116.053530096944, 5125.743712814345, 5135.281060651511, 5136.126858434075, 5140.049090918154, 4756.262779269185, 4778.307145463888, 4782.545658162469, 4786.018866132435, 4836.226203807374, 4860.0, 5146.125158723026, 5209.6353255272525, 5233.058049551041, 5308.92036619451, 5039.84880453587, 5081.415637201769, 5081.542661160439, 5081.644284900021, 5090.821821236239, 5150.330457040561, 5155.32980712505, 5155.355580552431, 5161.122609156452, 5203.115470370046, 4949.356583644103, 5050.570701490415, 5084.51697088776, 5095.1847704367865, 5162.845114646567, 5089.923651145235, 5094.261332106824, 5096.668716533413, 5104.640177277421, 5108.514691104339, 4395.033092719596, 4398.318260669601, 4403.0, 4444.400005999213, 4458.621017213508, 3721.423179407196, 3752.7185461090526, 3808.3716186829415, 3843.423486476995, 3883.650471457582, 4419.580139886705, 4441.244988351988, 4443.2224360736745, 4480.955190448119, 4496.64026877849, 5181.0, 5196.246544251062, 5197.58419326453, 5198.428172900978, 5265.420803711589, 4310.374995625617, 4380.5838445347645, 4411.558844115562, 4432.15789000089, 4434.0, 4642.79107440166, 4657.953420465752, 4668.299755036772, 4712.95287047132, 4726.621870250372, 4873.439554451213, 4883.804647814048, 4884.9985752921275, 4885.8045678172475, 4948.307236986752, 5032.345795046621, 5102.948970510396, 5120.18077107661, 5128.666636668605, 5137.255098010843, 4817.397825271841, 4836.419629644465, 4845.7577121143095, 4884.462709102067, 4887.90224195507, 5039.0, 5047.167217409086, 5049.6465247432325, 5052.0, 5054.21659642756, 4985.302057711996, 5066.544011039025, 5068.366454193226, 5123.28274041627, 5183.740812959412, 4926.458089610212, 4951.678140920829, 4961.1070007397575, 4999.15014447552, 5008.123578373843, 4981.551660350586, 4982.6512144149565, 4982.8754281143265, 4985.5762260208285, 5037.9924015196375, 4934.851954441372, 4947.653664243612, 4952.4057113146655, 4952.826651067196, 5012.674176178537, 4792.496787837227, 4961.677490963076, 4992.226204938241, 5012.1980483122525, 5101.132807422821, 4871.464138944642, 4876.414830220397, 4879.316895634543, 4927.334809800775, 4938.38270216223], "value": 4706.471717047495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.8894565559716, 579.6029719642042, 584.2784161560472, 589.9144624029516, 592.6858764854627, 27181.592276115873, 37569.62430375695, 38715.61284387155, 38849.8345049649, 42360.15279694412, 39118.21763564734, 39504.40743388851, 39568.80215598925, 39572.60427395725, 39613.8019309903, 7592.27873352032, 7734.1492435832015, 7735.381169506454, 7738.264864837832, 7741.109772376164, 7216.711331546732, 9478.857817132748, 9737.172340351079, 9737.269704772136, 10051.296409251356, 9664.468454235015, 9753.707388778348, 9754.512274386288, 9759.658411955583, 9770.413775173512, 9902.059304366074, 9974.900250997469, 9980.600775968953, 9983.90016099837, 9986.35088719231, 9786.608535658565, 9871.901280987242, 9884.0, 9885.0, 9894.505274736235, 37285.813570932245, 37620.0, 37715.434268485995, 37731.81134094339, 37735.0, 2675.0503571232125, 2829.957550636742, 2946.7053294670577, 3060.908172754821, 3488.2849015951924, 27229.0, 27788.58317125245, 28238.294042648893, 28375.432491350264, 29612.111636650934, 3411.1131105912395, 3523.40102182627, 3549.804760738158, 3553.7157027437743, 3610.584782749991, 246859.0, 247525.0, 247605.0, 248850.0, 250346.74826625932, 49739.25391119136, 49741.502584973685, 49770.50229497731, 49775.0, 50079.499205008215, 28658.283542911176, 29402.264943376158, 29499.410011799642, 29502.55746163809, 29518.262043448656, 14501.854981450262, 14677.0, 14677.633059173393, 14684.339204735812, 14688.19214943167, 10198.745031374126, 10545.683629491128, 10562.207834412297, 10564.894351056544, 10880.0, 17107.835050515696, 17577.29690812353, 17614.295428182984, 17669.38157164493, 17771.289148433916, 6584.209894812558, 6645.269020407752, 6700.664966751643, 6843.794686159423, 6922.238553759083, 742.921993190718, 840.5755093677776, 888.2893685051932, 919.544825311475, 953.4041224234853, 26209.868950655124, 27314.898126487256, 27635.723642763314, 28577.42845143127, 31389.52915706266, 878.6748902905965, 902.9413088149249, 903.3134817538718, 924.2282693950492, 1056.915446764257, 13319.866801332057, 14031.298435078204, 14068.226247556278, 14144.929275353763, 14311.57065288043, 391149.0, 391568.0, 391850.0, 392194.0, 392629.0, 46191.76904115458, 46517.0, 46526.0, 46718.06563868703, 46720.299195512096, 1078.3475997022003, 1079.7030816525362, 1100.6202860013282, 1153.5616465742964, 1178.505027888289, 19640.803591963897, 27148.0, 28555.14505541725, 30186.547201791553, 30954.226144346572, 45093.32360014537, 46028.30957535573, 46029.76985115053, 46058.07883842237, 46104.538954611366, 338448.0, 339414.0, 339876.0, 340187.0, 340376.0, 21341.25305614296, 23540.76459235386, 24696.382590435383, 24760.628590571516, 25387.111451099125, 39288.2142357157, 39382.40926386049, 39405.408918865665, 39479.21041579095, 39517.40723889202, 32446.0, 32791.8360408206, 32824.67175328312, 33170.5024424629, 33950.0, 33067.66932330742, 33090.33819323648, 33217.33565328632, 33295.66704332926, 33802.49296260509, 32442.675573243967, 32586.34827303488, 32762.0, 33424.0, 33804.0, 27656.58515122315, 29281.707182927898, 29689.851550742107, 29791.85104074466, 29918.551221731257, 22196.66704999394, 22358.441038974157, 22414.215502457337, 22450.0, 22477.887610561844], "value": 17148.169643329187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.34054361605496, 368.40134780980907, 368.4694040581562, 376.971727120466, 383.0423940149626, 15816.209189540534, 16433.83566164338, 16942.745858812126, 17054.658906821856, 17055.57361065974, 14941.551753447387, 15575.221238938064, 16066.437674681389, 16204.756928646051, 16207.675846483093, 4989.525995030475, 5206.37523497179, 5242.501962313593, 5256.237845512398, 5266.34170728659, 3332.766706330558, 3491.057414498084, 3493.179102910814, 3510.7717998330154, 5049.394072711261, 3647.106458917569, 5960.0761881908375, 5973.581849270553, 6097.115918191859, 6104.633721976696, 5896.380880007623, 5998.100284957248, 6065.059915713072, 6084.1786358841655, 6084.2090528231265, 5349.197620356938, 5376.865578360533, 5381.48875856793, 5384.73076346185, 5386.353637563518, 20423.795762042337, 21016.894915525325, 21057.157713691435, 21226.469338266666, 21237.15051397942, 1825.9452216433529, 1904.866659333847, 1908.5419499320203, 2234.4190510467233, 2246.887655617229, 12697.301648409333, 13414.0, 13767.586972390844, 13816.792748108786, 14083.084599501102, 1731.740238964153, 1754.1404711691307, 1757.8330058644408, 1770.0264854330067, 1796.0, 66565.0, 66800.6659966697, 66895.3310466894, 68503.0, 69299.65350173217, 8795.384323097449, 8849.159329863653, 9763.951180244054, 9861.309708320348, 10051.69844904654, 13280.800787988188, 13282.734345313233, 13285.93357033228, 13301.401436935168, 13303.201807891557, 7338.596377199198, 7347.5224110432655, 7635.503692259986, 7682.464786620402, 7685.654145563351, 7171.103612048494, 7194.388476979515, 7278.599677017814, 7422.294881986203, 7461.216572259943, 6022.247219097613, 6025.126356678257, 6078.848028799315, 6117.602355846856, 6270.153529273581, 1690.0, 1706.3174730108055, 1736.8350006749338, 1782.2158250369796, 1797.3170195325954, 797.7806103321517, 831.4595512916652, 892.1390857822232, 936.7189843047058, 936.7798567336671, 16649.25078371476, 17764.378246761302, 17811.376601819135, 17896.373626922996, 17946.730799038025, 828.9212524810133, 861.4658911474801, 866.3532906969143, 870.7561882672861, 898.3756289378819, 9809.0, 10066.295359324777, 10070.748231294276, 10151.441670708182, 10160.542775575117, 369509.0, 375074.0, 376444.0, 393109.0, 403552.0, 48662.756686216344, 50206.49793502091, 87066.0, 87078.56460717655, 90300.5484972571, 853.7438768369465, 874.6763697431991, 899.9955000224957, 907.111031189438, 921.4885738415244, 15837.366505340118, 15839.128847913475, 15954.202289885692, 16094.839051609333, 16596.08721520328, 37453.250934980606, 38057.80971095127, 39005.80497097496, 39277.41083883687, 39476.605233948445, 48733.26900096572, 50648.0, 51008.74495627498, 51544.0, 52998.470015300896, 7290.307420795016, 7413.332800048021, 7440.0, 7441.739539116105, 7644.961775191088, 8031.277185053374, 11050.447477625925, 11287.717807054889, 11463.0, 11514.19400641947, 10139.188864890939, 10599.417032063002, 10638.14894808398, 10663.920535495814, 10793.136549075898, 10597.89402106, 10716.0, 10785.352878827296, 10868.836967445337, 10902.291351062155, 10433.478326083818, 10588.894111058791, 10743.979321964402, 10932.234743567873, 11272.549098035986, 7388.298111679382, 7435.2527156117185, 7605.239476052572, 7867.84264314722, 7913.762587122165, 9900.405975641484, 10003.149732272546, 10455.0, 10492.895071049192, 10574.15406767472], "value": 8336.285465536044, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.2350495682758, 1322.120789674866, 1334.61296224095, 1420.2401715082426, 1657.494464188422, 7076.716931322748, 7380.409567234622, 7454.142773581033, 7667.654955527, 7682.0397450318715, 7557.433192510556, 7677.5393476391455, 7679.731209407658, 7690.538567685929, 7691.269329413711, 2188.9014994325294, 2219.94450138747, 2222.688823564702, 2223.2218723446827, 2224.0992398078806, 2148.1836901977226, 2187.1907394264144, 2195.407240045187, 2198.505336299336, 2199.6480563109826, 2107.9789202108013, 2139.7860213978634, 2144.613969485492, 2147.38799442159, 2176.031665908671, 2146.1093646136833, 2152.881591512466, 2153.310940499041, 2153.547754971458, 2157.2880949286696, 2084.3017589107517, 2126.096409026173, 2129.1802655977403, 2132.4349047502424, 2133.4346398204334, 7503.3997280218, 7545.320921117126, 7563.357114645261, 7583.886241706379, 7622.085349758065, 4417.425734654506, 4551.2262915304145, 4554.612857907082, 4556.2937744649635, 4562.5665561771575, 2747.093459158473, 2851.9714802851913, 2899.913002609925, 2904.8402337871407, 2906.520424130016, 966.9806603867884, 972.7033254857266, 978.2076518020355, 983.7196399026243, 996.9651062212861, 9975.600975960953, 9988.800223995551, 10019.248556358229, 10033.297669163161, 10045.19638428924, 3967.5437324708028, 4022.577629348935, 4083.224187404384, 4146.419501269825, 4161.2301724181125, 8075.3539716822515, 8208.958955205186, 8221.917780822116, 8226.0, 8231.711890083818, 4430.955690443118, 4435.645148388121, 4437.733735975852, 4440.4227450431235, 4441.71128876622, 3727.5154229950476, 3877.7091718121255, 3941.6661276439113, 4102.323116685773, 4103.199876024147, 3333.983330083334, 3401.9659803402146, 3416.7095796857047, 3476.1309672581856, 3476.366107929269, 1043.994780026095, 1048.6067724603274, 1049.9002594753485, 1061.930974486656, 1065.4193464561833, 681.6114814555656, 698.1447726534954, 735.8270806360501, 782.4640121516779, 811.472542847154, 4837.0, 5023.0, 5066.341375621219, 5125.718085505333, 5217.713025783542, 805.6817557064927, 819.3936486999578, 825.5459497276594, 853.7737499562621, 887.6893087419352, 3343.6990670839255, 3369.174552234669, 3433.6051354094598, 3461.9653803462147, 3656.213914008501, 8737.257333126629, 9335.859962100574, 9342.159205671522, 9343.205827504602, 9354.95322523383, 9070.047644997181, 9084.954575227213, 9090.13643703847, 9114.680986165453, 9170.633174673072, 6772.966135169391, 6838.34517408634, 6947.687354069079, 6955.234924158339, 6971.895421568681, 4856.223004319292, 4931.753412329441, 4943.431505376784, 4952.4304704959395, 5070.873228169326, 8304.045034821072, 8429.831403372022, 8541.103184165573, 8557.828843423222, 8592.527410992205, 7887.0, 8323.12607176262, 8530.530820804915, 8623.353248506262, 8668.263197628272, 5939.2575928009, 6098.817035488943, 6128.785492507741, 6244.718987645476, 6248.0, 6894.24163342042, 7215.098112735908, 7271.709131634675, 7273.527220730635, 7279.59962202084, 6295.9370406297185, 6544.607323560602, 6668.599884006975, 6683.465322774264, 6786.524943253925, 6284.214473190851, 6383.872322553616, 6388.39310265524, 6405.647689376942, 6521.8695626088165, 5800.303963524404, 5812.738426770721, 5847.736851841592, 5886.028805247258, 5909.0, 4239.533651298417, 4253.128108737639, 4263.573642635712, 4371.672124590732, 4418.425604671372, 4324.286492728665, 4350.651947844228, 4381.123775245028, 4460.509343972096, 4521.547845215452], "value": 3966.944922561729, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9711227433394, 308.1863879358494, 310.37304644617865, 314.96377916539603, 314.9888856769769, 4777.952220477794, 4824.6381521385865, 4830.372051633287, 4841.983183531463, 4847.878803029926, 4735.408073990751, 4756.619470442365, 4768.856934291976, 4795.664303498756, 4806.927896081562, 3614.7108231341563, 3686.64976827201, 3803.125281185329, 3837.9808100959526, 3844.903877403059, 3786.1069465267365, 3801.8669346572738, 3802.1255111324413, 3815.5802861685193, 3817.331966905794, 3788.5942426559195, 3798.525184351956, 3809.314323421783, 3817.6945844332386, 3824.521934758155, 3616.951084185583, 3621.040424287566, 3765.5293088363956, 3795.5635101963353, 3802.8288727007352, 3323.7839540429795, 3325.567676202078, 3325.9168520786934, 3326.3534550397467, 3331.0, 4649.442066951988, 4663.743494107823, 4664.486906440289, 4666.486686464486, 4700.600448961843, 4139.606737359946, 4304.504981927087, 4305.483341998949, 4310.396544483775, 4311.439512863308, 4953.628477864138, 5028.673136246132, 5046.167382381903, 5059.898802023976, 5061.722224998728, 3563.2873425315042, 3587.677109060197, 3594.3889538778208, 3604.585472670624, 3639.162992511711, 5244.370675518924, 5251.658642188246, 5280.260763493114, 5289.153735402318, 5298.708571028592, 3409.710174635134, 3590.761187390346, 3595.280943811248, 3607.855685772592, 3610.9097272568397, 3644.726645501598, 3655.287218992271, 3669.1927775889794, 3680.3375392429084, 3681.7054635629083, 4247.256730072225, 4248.362745588187, 4250.447441832542, 4253.404523366669, 4289.6139347458875, 4845.806167753321, 5029.497050294941, 5143.8456846294675, 5147.433782283946, 5158.535731784157, 1284.9036322275679, 1303.7653222420056, 1306.2684896458013, 1306.3076569418224, 1310.9868901311056, 824.8309096635176, 840.2395831772134, 843.304273973974, 850.4302117581235, 850.5194565070715, 4052.5542190359038, 4077.327241005201, 4339.349097635317, 4373.912521749611, 4486.685931984795, 5225.372955245395, 5231.346081739783, 5233.607479439057, 5244.895102097937, 5245.7114858683135, 4512.165249428866, 4513.593776560059, 4514.164879497303, 4520.480144783326, 4533.433320834895, 2629.500394924958, 2730.604062410939, 2763.2677340504783, 2765.267204190891, 2785.4011387551773, 4923.803047878045, 4926.817563784666, 4931.506849315111, 4941.98689268699, 4980.726060066659, 5348.913923569811, 5355.598330125256, 5378.51593356592, 5378.987766850576, 5405.702686352289, 4701.3888194535175, 4946.579540739005, 4974.8756281093265, 4980.228064649985, 4988.700677959334, 5232.869178270574, 5264.1575448592685, 5264.842054738364, 5267.788408666105, 5272.947270527399, 5209.791608335624, 5223.973880130575, 5230.869228269324, 5239.738013099406, 5244.213367994831, 4626.282926146453, 4916.852494425173, 4918.8770280743265, 4922.753862306942, 4926.581240594446, 3789.204267103939, 3806.657400833938, 3820.5988371220624, 3832.3485007549343, 3849.345611246039, 5187.144121219956, 5202.791888324425, 5244.711540865289, 5255.868603284948, 5264.0, 4901.554041557678, 4976.427710813303, 5011.51929794754, 5021.196608542616, 5059.266406371129, 5004.499550045112, 5034.219695947134, 5034.798608055783, 5043.84868453947, 5046.823361182341, 4978.7510624467905, 4986.703457101107, 4986.877952460667, 4988.351514303117, 5011.398632164163, 4822.614190864792, 4863.635227357891, 5104.489551044866, 5141.483262437665, 5142.382914050338, 4828.34441057424, 4963.975180124076, 4976.751162441936, 4986.426560945538, 4987.351644286219], "value": 3814.2262936716193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.0666936814952, 626.2015929689645, 643.9452646525045, 683.12928817531, 709.8438343564417, 11928.940355298222, 12846.16499927504, 12858.906992905615, 12860.871391286084, 12912.935435322823, 11466.08271338293, 11512.079033677286, 11513.4818933148, 11523.654290371282, 11535.307881527115, 609.2719200555338, 737.3290305821703, 739.7152096442882, 758.1281526244808, 760.5588758520045, 759.4418102694515, 767.45510687412, 768.2279309294152, 768.4735955870219, 769.0233403577454, 794.3803833010261, 794.6860989908982, 794.7854079398559, 795.220683729945, 795.2445177081767, 633.6958260035162, 772.6909236305466, 791.7664289034749, 791.9960400197991, 794.8728203487415, 582.4815913836688, 583.2883881664362, 584.3630442817307, 584.4214227914376, 584.6755050946723, 12165.69585760363, 12231.571894983721, 12245.93877030618, 12246.387680615933, 12260.44827982743, 11461.713457163554, 11482.483288251966, 11852.525898964117, 11919.702007449796, 12468.937655311756, 10510.527026283835, 10529.578816847317, 10708.30396024256, 10805.513751881184, 10934.890651093467, 3963.2271707017144, 4046.7774272415004, 4051.3315302974975, 4065.7967101645095, 4186.120914607905, 65394.346056539776, 66211.33788662148, 67027.32972670309, 67736.6613166936, 68599.65700171467, 9088.09119088086, 9119.224865886474, 9315.854444772147, 9367.578458969363, 9637.325387222967, 17751.55621109457, 17756.3785267515, 17766.111694415227, 17836.732449013274, 17870.374536891148, 6086.2696476422225, 6086.726097325631, 6093.0, 6139.754409823647, 6231.345708700612, 3599.4240921452965, 3600.7479476436397, 3644.9817750911607, 3646.507721457622, 3674.9448758268645, 2839.559868220429, 2949.8820047197873, 2954.881804727787, 2955.807872488281, 2955.8374289414287, 515.1577171324836, 530.2947080383158, 539.0674133748637, 544.5534661577548, 544.7358031354789, 480.43788767142524, 506.05873076078467, 508.8448023352839, 508.98473045808686, 519.5402069168813, 6949.861002779916, 7162.641867906584, 7326.3406293433845, 7407.8888816667795, 7558.508696934681, 530.1200007986723, 531.4455119624779, 537.6478406643655, 547.0258571478214, 552.3454706173197, 3463.3765922133753, 3494.3011397720556, 3510.26284480262, 3581.8925432237074, 3625.9637403625625, 60654.696726516086, 61283.0, 61581.07628385578, 61674.69162654158, 106770.0, 88142.0, 88692.55653721819, 89553.0, 90136.0, 90564.5471772637, 62929.68535157295, 64179.35820641734, 71211.2878871215, 77009.61495192489, 82409.0, 6822.761203357859, 7234.10820306864, 7240.456965727697, 7285.562866227831, 7287.599182045038, 40853.79573102116, 41249.0, 42527.574724252365, 42800.78599606982, 42937.5706242946, 53569.73215133899, 59225.40774592199, 60294.57348838625, 61872.0, 61965.0, 9406.482643454676, 9509.429434233969, 9595.808083838145, 9631.133198012212, 9681.806363872824, 9963.153131983572, 9988.300818942604, 10034.598616055277, 10040.0, 10124.797504049731, 10030.548625311732, 10158.13655839262, 10389.37663740178, 10549.52527136296, 10789.352638841381, 9772.755681108029, 10274.794504110025, 10421.739456513647, 10771.030607245386, 10773.676789696321, 9756.51217439111, 9974.900250997398, 10081.092701656886, 10152.340097893613, 10306.0, 7985.361171106414, 8269.421140520346, 8385.1195624461, 8439.746807595782, 8609.440386374865, 7639.388848892187, 8659.870101948602, 8768.25469835071, 8791.868121978303, 8854.454346863127], "value": 6103.329469359699, "unit": "score"}], "name": "dromaeo_css", "value": 6293.528900349616, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 131.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 146.0, 189.0, 127.0, 152.0, 148.0, 125.0, 183.0, 131.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 160.0, 160.0, 161.0, 161.0, 162.0, 162.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 184.0, 77.0, 76.0, 77.0, 76.0, 165.0, 77.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 134.0, 83.0, 85.0, 83.0, 139.0, 83.0, 85.0, 131.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 148.0, 148.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 70.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 81.0, 82.0, 81.0, 80.0, 87.0, 85.0, 82.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 137.0, 139.0, 132.0, 134.0, 143.0, 138.0, 137.0, 173.0, 132.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 173.0, 188.0, 172.0, 176.0, 179.0, 174.0, 177.0, 176.0, 179.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 78.0, 71.0, 69.0, 69.0, 69.0, 69.0, 68.0, 69.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1579.0999999999997, "alertThreshold": 2.0}]} 18:25:21 INFO - Return code: 0 18:25:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:21 INFO - # TBPL SUCCESS # 18:25:21 INFO - Running post-action listener: _resource_record_post_action 18:25:21 INFO - [mozharness: 2016-08-27 01:25:21.273700Z] Finished run-tests step (success) 18:25:21 INFO - Running post-run listener: _resource_record_post_run 18:25:21 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 28672; Write bytes: 407597056; Read time: 92; Write time: 286132 18:25:21 INFO - TinderboxPrint: CPU usage
13.0% 18:25:21 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 92 18:25:21 INFO - TinderboxPrint: I/O write bytes / time
407,597,056 / 286,132 18:25:21 INFO - TinderboxPrint: CPU idle
8,342.3 (87.0%) 18:25:21 INFO - TinderboxPrint: CPU user
1,200.3 (12.5%) 18:25:21 INFO - TinderboxPrint: Swap in / out
0 / 0 18:25:21 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 16384; Write bytes: 114495488; Read time: 68; Write time: 96552 18:25:21 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293101568; Read time: 12; Write time: 189580 18:25:21 INFO - Running post-run listener: _upload_blobber_files 18:25:21 INFO - Blob upload gear active. 18:25:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:21 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 18:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:25:22 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:26 INFO - (blobuploader) - INFO - Done attempting. 18:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:25:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:26 INFO - (blobuploader) - INFO - Done attempting. 18:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:25:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:27 INFO - (blobuploader) - INFO - Done attempting. 18:25:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:27 INFO - Return code: 0 18:25:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/495a0f935e1d96e399ed772c263edcbaecd5ec3802b261044888499367536d0172a2a20830b33a575f25cd13dff4051b2569694b778bd2ed0d60a6f7f7771de0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26a633230f04f2462d937c1dffb047f71ac7f801c5c65d353b1138207f274489158aa1488ad7c47bb104be81382a4bab950ac8810cc39388baffb877a9bd4e8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/31401928a7727e7cf0be1f0d85b0b1e96ad753becedb544ecab66d35378589d9aa28da3e06fcd9cd88a66e8f6ee2264acc7d31927df25b30ef9f61714cb85484"} 18:25:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:27 INFO - Contents: 18:25:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/495a0f935e1d96e399ed772c263edcbaecd5ec3802b261044888499367536d0172a2a20830b33a575f25cd13dff4051b2569694b778bd2ed0d60a6f7f7771de0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26a633230f04f2462d937c1dffb047f71ac7f801c5c65d353b1138207f274489158aa1488ad7c47bb104be81382a4bab950ac8810cc39388baffb877a9bd4e8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/31401928a7727e7cf0be1f0d85b0b1e96ad753becedb544ecab66d35378589d9aa28da3e06fcd9cd88a66e8f6ee2264acc7d31927df25b30ef9f61714cb85484"} 18:25:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:27 INFO - Copying logs to upload dir... 18:25:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.144842 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-26 18:25:27.754952) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 18:25:27.759659) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/495a0f935e1d96e399ed772c263edcbaecd5ec3802b261044888499367536d0172a2a20830b33a575f25cd13dff4051b2569694b778bd2ed0d60a6f7f7771de0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26a633230f04f2462d937c1dffb047f71ac7f801c5c65d353b1138207f274489158aa1488ad7c47bb104be81382a4bab950ac8810cc39388baffb877a9bd4e8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/31401928a7727e7cf0be1f0d85b0b1e96ad753becedb544ecab66d35378589d9aa28da3e06fcd9cd88a66e8f6ee2264acc7d31927df25b30ef9f61714cb85484"} build_url:https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/R3UMAqFgQy6l48VUjsZQtg/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-Non-PGO/sha512/495a0f935e1d96e399ed772c263edcbaecd5ec3802b261044888499367536d0172a2a20830b33a575f25cd13dff4051b2569694b778bd2ed0d60a6f7f7771de0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/26a633230f04f2462d937c1dffb047f71ac7f801c5c65d353b1138207f274489158aa1488ad7c47bb104be81382a4bab950ac8810cc39388baffb877a9bd4e8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/31401928a7727e7cf0be1f0d85b0b1e96ad753becedb544ecab66d35378589d9aa28da3e06fcd9cd88a66e8f6ee2264acc7d31927df25b30ef9f61714cb85484"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 18:25:27.791745) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:25:27.792130) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1472258517.643343-1022811904 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:25:27.849657) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:25:27.850100) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:25:27.850966) ========= ========= Total master_lag: 0.49 =========