builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1472794712.26 results: success (0) buildid: 20160901193046 builduid: 3ed74c8278e94627b2611dec95ef0a7d revision: d5f20820c80514476f596090292a5d77c4b41e3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.257942) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.258480) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.258859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.273934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.274468) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 22:38:32-- 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.7M=0s 2016-09-01 22:38:32 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117481 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.406221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.406543) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020744 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.456198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:32.456561) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d5f20820c80514476f596090292a5d77c4b41e3b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d5f20820c80514476f596090292a5d77c4b41e3b --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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 22:38:32,780 truncating revision to first 12 chars 2016-09-01 22:38:32,780 Setting DEBUG logging. 2016-09-01 22:38:32,780 attempt 1/10 2016-09-01 22:38:32,780 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d5f20820c805?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 22:38:33,094 unpacking tar archive at: mozilla-central-d5f20820c805/testing/mozharness/ program finished with exit code 0 elapsedTime=0.725136 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:33.195179) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:33.195519) ========= script_repo_revision: d5f20820c80514476f596090292a5d77c4b41e3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:33.195953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:33.196245) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:38:33.206237) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 22:38:33.206512) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:38:33 INFO - MultiFileLogger online at 20160901 22:38:33 in /builds/slave/test 22:38:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:38:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:38:33 INFO - {'append_to_log': False, 22:38:33 INFO - 'base_work_dir': '/builds/slave/test', 22:38:33 INFO - 'blob_upload_branch': 'Firefox', 22:38:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:38:33 INFO - 'branch': 'Firefox', 22:38:33 INFO - 'buildbot_json_path': 'buildprops.json', 22:38:33 INFO - 'config_files': ('talos/linux_config.py',), 22:38:33 INFO - 'default_actions': ('clobber', 22:38:33 INFO - 'read-buildbot-config', 22:38:33 INFO - 'download-and-extract', 22:38:33 INFO - 'populate-webroot', 22:38:33 INFO - 'create-virtualenv', 22:38:33 INFO - 'install', 22:38:33 INFO - 'run-tests'), 22:38:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:38:33 INFO - 'download_minidump_stackwalk': True, 22:38:33 INFO - 'download_symbols': 'ondemand', 22:38:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:38:33 INFO - 'tooltool.py': '/tools/tooltool.py', 22:38:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:38:33 INFO - '/tools/misc-python/virtualenv.py')}, 22:38:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:38:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:38:33 INFO - 'installer_path': 'installer.exe', 22:38:33 INFO - 'log_level': 'info', 22:38:33 INFO - 'log_name': 'talos', 22:38:33 INFO - 'log_to_console': True, 22:38:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:38:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:38:33 INFO - 'opt_config_files': (), 22:38:33 INFO - 'pip_index': False, 22:38:33 INFO - 'sps_profile': False, 22:38:33 INFO - 'sps_profile_interval': 0, 22:38:33 INFO - 'suite': 'dromaeojs', 22:38:33 INFO - 'system_bits': '32', 22:38:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:38:33 INFO - 'title': 'talos-linux64-ix-046', 22:38:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:38:33 INFO - 'use_talos_json': True, 22:38:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:38:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:38:33 INFO - 'work_dir': 'build'} 22:38:33 INFO - [mozharness: 2016-09-02 05:38:33.622778Z] Running clobber step. 22:38:33 INFO - Running pre-action listener: _resource_record_pre_action 22:38:33 INFO - Running main action method: clobber 22:38:33 INFO - rmtree: /builds/slave/test/build 22:38:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:38:34 INFO - Running post-action listener: _resource_record_post_action 22:38:34 INFO - [mozharness: 2016-09-02 05:38:34.091012Z] Finished clobber step (success) 22:38:34 INFO - [mozharness: 2016-09-02 05:38:34.091114Z] Running read-buildbot-config step. 22:38:34 INFO - Running pre-action listener: _resource_record_pre_action 22:38:34 INFO - Running main action method: read_buildbot_config 22:38:34 INFO - Using buildbot properties: 22:38:34 INFO - { 22:38:34 INFO - "project": "", 22:38:34 INFO - "product": "firefox", 22:38:34 INFO - "script_repo_revision": "production", 22:38:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:38:34 INFO - "repository": "", 22:38:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:38:34 INFO - "buildid": "20160901193046", 22:38:34 INFO - "pgo_build": "True", 22:38:34 INFO - "basedir": "/builds/slave/test", 22:38:34 INFO - "buildnumber": 439, 22:38:34 INFO - "slavename": "talos-linux64-ix-046", 22:38:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:38:34 INFO - "platform": "ubuntu64_hw", 22:38:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:38:34 INFO - "branch": "mozilla-central", 22:38:34 INFO - "repo_path": "mozilla-central", 22:38:34 INFO - "revision": "d5f20820c80514476f596090292a5d77c4b41e3b", 22:38:34 INFO - "stage_platform": "linux64-pgo", 22:38:34 INFO - "builduid": "3ed74c8278e94627b2611dec95ef0a7d", 22:38:34 INFO - "slavebuilddir": "test" 22:38:34 INFO - } 22:38:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:38:34 INFO - Running post-action listener: _resource_record_post_action 22:38:34 INFO - [mozharness: 2016-09-02 05:38:34.093613Z] Finished read-buildbot-config step (success) 22:38:34 INFO - [mozharness: 2016-09-02 05:38:34.093701Z] Running download-and-extract step. 22:38:34 INFO - Running pre-action listener: _resource_record_pre_action 22:38:34 INFO - Running main action method: download_and_extract 22:38:34 INFO - mkdir: /builds/slave/test/build/tests 22:38: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')]} 22:38:34 INFO - trying https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:38:34 INFO - Downloading https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:34 INFO - Downloaded 1361 bytes. 22:38:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:38:34 INFO - Using the following test package requirements: 22:38:34 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:38:34 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:38:34 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:38:34 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'jsshell-linux-x86_64.zip'], 22:38:34 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:38:34 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:38:34 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:38:34 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:38:34 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:38:34 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:38:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:38:34 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:38: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')]} 22:38:34 INFO - trying https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:38:34 INFO - Downloading https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:35 INFO - Downloaded 21906005 bytes. 22:38:35 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 22:38:39 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 22:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:39 INFO - trying https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:38:39 INFO - Downloading https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:40 INFO - Downloaded 21906005 bytes. 22:38:40 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 22:38:45 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')]} 22:38:45 INFO - trying https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:46 INFO - Downloaded 14413998 bytes. 22:38:46 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 22:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:46 INFO - trying https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:38:46 INFO - Downloading https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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 22:38:47 INFO - Downloaded 59751966 bytes. 22:38:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:38:47 INFO - mkdir: /builds/slave/test/properties 22:38:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:38:47 INFO - Writing to file /builds/slave/test/properties/build_url 22:38:47 INFO - Contents: 22:38:47 INFO - build_url:https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:38:48 INFO - Running post-action listener: _resource_record_post_action 22:38:48 INFO - Running post-action listener: set_extra_try_arguments 22:38:48 INFO - [mozharness: 2016-09-02 05:38:48.068964Z] Finished download-and-extract step (success) 22:38:48 INFO - [mozharness: 2016-09-02 05:38:48.069227Z] Running populate-webroot step. 22:38:48 INFO - Running pre-action listener: _resource_record_pre_action 22:38:48 INFO - Running main action method: populate_webroot 22:38:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:38:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:38:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:38:48 INFO - '--tppagecycles', 22:38:48 INFO - '7'], 22:38:48 INFO - 'tests': ['tsvgm']}}, 22:38:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:38:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:38:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:38:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:38:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:38:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:38:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['damp', 'tps']}, 22:38:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'tests': ['damp', 'tps']}, 22:38:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['dromaeo_dom']}, 22:38:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:38:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['basic_compositor_video']}, 22:38:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:38:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'other-e10s': {'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['a11yr', 22:38:48 INFO - 'ts_paint', 22:38:48 INFO - 'tpaint', 22:38:48 INFO - 'sessionrestore', 22:38:48 INFO - 'sessionrestore_no_auto_restore', 22:38:48 INFO - 'tabpaint']}, 22:38:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['tsvgx', 22:38:48 INFO - 'tsvgr_opacity', 22:38:48 INFO - 'tart', 22:38:48 INFO - 'tscrollx', 22:38:48 INFO - 'cart']}, 22:38:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:38:48 INFO - 'tsvgr_opacity', 22:38:48 INFO - 'tart', 22:38:48 INFO - 'tscrollx', 22:38:48 INFO - 'cart']}, 22:38:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'talos_options': ['--disable-e10s'], 22:38:48 INFO - 'tests': ['tp5o']}, 22:38:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'tests': ['tp5o']}, 22:38:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'talos_options': ['--disable-e10s', 22:38:48 INFO - '--xperf_path', 22:38:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:38:48 INFO - 'tests': ['tp5n']}, 22:38:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:38:48 INFO - 'talos_options': ['--xperf_path', 22:38:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:38:48 INFO - 'tests': ['tp5n']}}, 22:38:48 INFO - 'talos.zip': {'path': '', 22:38:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:38:48 INFO - Running post-action listener: _resource_record_post_action 22:38:48 INFO - [mozharness: 2016-09-02 05:38:48.090004Z] Finished populate-webroot step (success) 22:38:48 INFO - [mozharness: 2016-09-02 05:38:48.090094Z] Running create-virtualenv step. 22:38:48 INFO - Running pre-action listener: _resource_record_pre_action 22:38:48 INFO - Running main action method: create_virtualenv 22:38:48 INFO - Creating virtualenv /builds/slave/test/build/venv 22:38:48 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 22:38:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:38:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:38:48 INFO - Using real prefix '/usr' 22:38:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:38:48 INFO - Installing distribute.............................................................................................................................................................................................done. 22:38:50 INFO - Installing pip.................done. 22:38:50 INFO - Return code: 0 22:38:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:38:50 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')]} 22:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:50 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')]} 22:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:50 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:50 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 22:38:50 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 22:38:50 INFO - Using env: {'DISPLAY': ':0', 22:38:50 INFO - 'HOME': '/home/cltbld', 22:38:50 INFO - 'LANG': 'en_US.UTF-8', 22:38:50 INFO - 'LANGUAGE': 'en_US:en', 22:38:50 INFO - 'LOGNAME': 'cltbld', 22:38:50 INFO - 'MAIL': '/var/mail/cltbld', 22:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:50 INFO - 'MOZ_NO_REMOTE': '1', 22:38:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:50 INFO - 'NO_EM_RESTART': '1', 22:38:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:50 INFO - 'PWD': '/builds/slave/test', 22:38:50 INFO - 'SHELL': '/bin/bash', 22:38:50 INFO - 'SHLVL': '1', 22:38:50 INFO - 'TERM': 'linux', 22:38:50 INFO - 'TMOUT': '86400', 22:38:50 INFO - 'USER': 'cltbld', 22:38:50 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:50 INFO - '_': '/tools/buildbot/bin/python'} 22:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:50 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 22:38:50 INFO - Cleaning up... 22:38:50 INFO - Return code: 0 22:38:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:38:50 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')]} 22:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:50 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')]} 22:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:50 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:50 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 22:38:50 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 22:38:50 INFO - Using env: {'DISPLAY': ':0', 22:38:50 INFO - 'HOME': '/home/cltbld', 22:38:50 INFO - 'LANG': 'en_US.UTF-8', 22:38:50 INFO - 'LANGUAGE': 'en_US:en', 22:38:50 INFO - 'LOGNAME': 'cltbld', 22:38:50 INFO - 'MAIL': '/var/mail/cltbld', 22:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:50 INFO - 'MOZ_NO_REMOTE': '1', 22:38:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:50 INFO - 'NO_EM_RESTART': '1', 22:38:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:50 INFO - 'PWD': '/builds/slave/test', 22:38:50 INFO - 'SHELL': '/bin/bash', 22:38:50 INFO - 'SHLVL': '1', 22:38:50 INFO - 'TERM': 'linux', 22:38:50 INFO - 'TMOUT': '86400', 22:38:50 INFO - 'USER': 'cltbld', 22:38:50 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:50 INFO - '_': '/tools/buildbot/bin/python'} 22:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:51 INFO - Downloading/unpacking psutil>=3.1.1 22:38:51 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 22:38:51 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 22:38:51 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 22:38:51 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 22:38:51 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 22:38:51 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 22:38:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:38:53 INFO - Installing collected packages: psutil 22:38:53 INFO - Running setup.py install for psutil 22:38:53 INFO - building 'psutil._psutil_linux' extension 22:38:53 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 22:38:53 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 22:38:53 INFO - building 'psutil._psutil_posix' extension 22:38:53 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 22:38:53 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 22:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:38:53 INFO - Successfully installed psutil 22:38:53 INFO - Cleaning up... 22:38:53 INFO - Return code: 0 22:38:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:53 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:53 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 22:38:53 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 22:38:53 INFO - Using env: {'DISPLAY': ':0', 22:38:53 INFO - 'HOME': '/home/cltbld', 22:38:53 INFO - 'LANG': 'en_US.UTF-8', 22:38:53 INFO - 'LANGUAGE': 'en_US:en', 22:38:53 INFO - 'LOGNAME': 'cltbld', 22:38:53 INFO - 'MAIL': '/var/mail/cltbld', 22:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:53 INFO - 'MOZ_NO_REMOTE': '1', 22:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:53 INFO - 'NO_EM_RESTART': '1', 22:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:53 INFO - 'PWD': '/builds/slave/test', 22:38:53 INFO - 'SHELL': '/bin/bash', 22:38:53 INFO - 'SHLVL': '1', 22:38:53 INFO - 'TERM': 'linux', 22:38:53 INFO - 'TMOUT': '86400', 22:38:53 INFO - 'USER': 'cltbld', 22:38:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:53 INFO - '_': '/tools/buildbot/bin/python'} 22:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:38:53 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 22:38:53 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 22:38:53 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 22:38:53 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 22:38:53 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 22:38:53 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 22:38:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:38:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:38:55 INFO - Installing collected packages: mozsystemmonitor 22:38:55 INFO - Running setup.py install for mozsystemmonitor 22:38:55 INFO - Successfully installed mozsystemmonitor 22:38:55 INFO - Cleaning up... 22:38:55 INFO - Return code: 0 22:38:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:55 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:55 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 22:38:55 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 22:38:55 INFO - Using env: {'DISPLAY': ':0', 22:38:55 INFO - 'HOME': '/home/cltbld', 22:38:55 INFO - 'LANG': 'en_US.UTF-8', 22:38:55 INFO - 'LANGUAGE': 'en_US:en', 22:38:55 INFO - 'LOGNAME': 'cltbld', 22:38:55 INFO - 'MAIL': '/var/mail/cltbld', 22:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:55 INFO - 'MOZ_NO_REMOTE': '1', 22:38:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:55 INFO - 'NO_EM_RESTART': '1', 22:38:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:55 INFO - 'PWD': '/builds/slave/test', 22:38:55 INFO - 'SHELL': '/bin/bash', 22:38:55 INFO - 'SHLVL': '1', 22:38:55 INFO - 'TERM': 'linux', 22:38:55 INFO - 'TMOUT': '86400', 22:38:55 INFO - 'USER': 'cltbld', 22:38:55 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:55 INFO - '_': '/tools/buildbot/bin/python'} 22:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:56 INFO - Downloading/unpacking jsonschema==2.5.1 22:38:56 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 22:38:56 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 22:38:56 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 22:38:56 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 22:38:56 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 22:38:56 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 22:38:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:38:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 INFO - Downloading functools32-3.2.3-2.tar.gz 22:38:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:38:58 INFO - warning: no files found matching '*.txt' 22:38:58 INFO - no previously-included directories found matching 'build' 22:38:58 INFO - no previously-included directories found matching 'dist' 22:38:58 INFO - no previously-included directories found matching '.git*' 22:38:58 INFO - Installing collected packages: jsonschema, functools32 22:38:58 INFO - Running setup.py install for functools32 22:38:58 INFO - warning: no files found matching '*.txt' 22:38:58 INFO - no previously-included directories found matching 'build' 22:38:58 INFO - no previously-included directories found matching 'dist' 22:38:58 INFO - no previously-included directories found matching '.git*' 22:38:58 INFO - Successfully installed jsonschema functools32 22:38:58 INFO - Cleaning up... 22:38:58 INFO - Return code: 0 22:38:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:58 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:58 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 22:38:58 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 22:38:58 INFO - Using env: {'DISPLAY': ':0', 22:38:58 INFO - 'HOME': '/home/cltbld', 22:38:58 INFO - 'LANG': 'en_US.UTF-8', 22:38:58 INFO - 'LANGUAGE': 'en_US:en', 22:38:58 INFO - 'LOGNAME': 'cltbld', 22:38:58 INFO - 'MAIL': '/var/mail/cltbld', 22:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:58 INFO - 'MOZ_NO_REMOTE': '1', 22:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:58 INFO - 'NO_EM_RESTART': '1', 22:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:58 INFO - 'PWD': '/builds/slave/test', 22:38:58 INFO - 'SHELL': '/bin/bash', 22:38:58 INFO - 'SHLVL': '1', 22:38:58 INFO - 'TERM': 'linux', 22:38:58 INFO - 'TMOUT': '86400', 22:38:58 INFO - 'USER': 'cltbld', 22:38:58 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:58 INFO - '_': '/tools/buildbot/bin/python'} 22:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:38:58 INFO - Cleaning up... 22:38:58 INFO - Return code: 0 22:38:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:58 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:38:58 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 22:38:58 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 22:38:58 INFO - Using env: {'DISPLAY': ':0', 22:38:58 INFO - 'HOME': '/home/cltbld', 22:38:58 INFO - 'LANG': 'en_US.UTF-8', 22:38:58 INFO - 'LANGUAGE': 'en_US:en', 22:38:58 INFO - 'LOGNAME': 'cltbld', 22:38:58 INFO - 'MAIL': '/var/mail/cltbld', 22:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:58 INFO - 'MOZ_NO_REMOTE': '1', 22:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:58 INFO - 'NO_EM_RESTART': '1', 22:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:58 INFO - 'PWD': '/builds/slave/test', 22:38:58 INFO - 'SHELL': '/bin/bash', 22:38:58 INFO - 'SHLVL': '1', 22:38:58 INFO - 'TERM': 'linux', 22:38:58 INFO - 'TMOUT': '86400', 22:38:58 INFO - 'USER': 'cltbld', 22:38:58 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:58 INFO - '_': '/tools/buildbot/bin/python'} 22:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:58 INFO - Downloading/unpacking blobuploader==1.2.4 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:38:58 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 22:39:00 INFO - Downloading blobuploader-1.2.4.tar.gz 22:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:39:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:39:00 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 22:39:00 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 22:39:00 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 22:39:00 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 22:39:00 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 22:39:00 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 22:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:39:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:39:01 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 22:39:01 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 22:39:01 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 22:39:01 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 22:39:01 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 22:39:01 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 22:39:01 INFO - Downloading docopt-0.6.1.tar.gz 22:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:39:01 INFO - Installing collected packages: blobuploader, requests, docopt 22:39:01 INFO - Running setup.py install for blobuploader 22:39:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:39:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:39:01 INFO - Running setup.py install for requests 22:39:02 INFO - Running setup.py install for docopt 22:39:02 INFO - Successfully installed blobuploader requests docopt 22:39:02 INFO - Cleaning up... 22:39:02 INFO - Return code: 0 22:39:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39: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')]} 22:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:02 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:39:02 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 22:39:02 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 22:39:02 INFO - Using env: {'DISPLAY': ':0', 22:39:02 INFO - 'HOME': '/home/cltbld', 22:39:02 INFO - 'LANG': 'en_US.UTF-8', 22:39:02 INFO - 'LANGUAGE': 'en_US:en', 22:39:02 INFO - 'LOGNAME': 'cltbld', 22:39:02 INFO - 'MAIL': '/var/mail/cltbld', 22:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:02 INFO - 'MOZ_NO_REMOTE': '1', 22:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:02 INFO - 'NO_EM_RESTART': '1', 22:39:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:02 INFO - 'PWD': '/builds/slave/test', 22:39:02 INFO - 'SHELL': '/bin/bash', 22:39:02 INFO - 'SHLVL': '1', 22:39:02 INFO - 'TERM': 'linux', 22:39:02 INFO - 'TMOUT': '86400', 22:39:02 INFO - 'USER': 'cltbld', 22:39:02 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:02 INFO - '_': '/tools/buildbot/bin/python'} 22:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:39:02 INFO - Running setup.py (path:/tmp/pip-UFt7aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:39:02 INFO - Running setup.py (path:/tmp/pip-dRAXV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:39:02 INFO - Running setup.py (path:/tmp/pip-R0RtQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:39:02 INFO - Running setup.py (path:/tmp/pip-awld5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:39:02 INFO - Running setup.py (path:/tmp/pip-FBYPnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:39:02 INFO - Running setup.py (path:/tmp/pip-zMmICY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:39:02 INFO - Running setup.py (path:/tmp/pip-29wmsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:39:02 INFO - Running setup.py (path:/tmp/pip-ixyRJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:39:02 INFO - Running setup.py (path:/tmp/pip-7_DJXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:39:03 INFO - Running setup.py (path:/tmp/pip-YtAm7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:39:03 INFO - Running setup.py (path:/tmp/pip-y8_dff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:39:03 INFO - Running setup.py (path:/tmp/pip-QC2W4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:39:03 INFO - Running setup.py (path:/tmp/pip-EBZsL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:39:03 INFO - Running setup.py (path:/tmp/pip-kxw1zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:03 INFO - Running setup.py (path:/tmp/pip-BkvEcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:39:03 INFO - Running setup.py (path:/tmp/pip-ygkwws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:39:03 INFO - Running setup.py (path:/tmp/pip-t7dVOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:39:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:39:03 INFO - Running setup.py install for manifestparser 22:39:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:39:03 INFO - Running setup.py install for mozcrash 22:39:03 INFO - Running setup.py install for mozdebug 22:39:03 INFO - Running setup.py install for mozdevice 22:39:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozfile 22:39:04 INFO - Running setup.py install for mozhttpd 22:39:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozinfo 22:39:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozInstall 22:39:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozleak 22:39:04 INFO - Running setup.py install for mozlog 22:39:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for moznetwork 22:39:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozprocess 22:39:04 INFO - Running setup.py install for mozprofile 22:39:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:39:04 INFO - Running setup.py install for mozrunner 22:39:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:39:05 INFO - Running setup.py install for mozscreenshot 22:39:05 INFO - Running setup.py install for moztest 22:39:05 INFO - Running setup.py install for mozversion 22:39:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:39:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:39:05 INFO - Cleaning up... 22:39:05 INFO - Return code: 0 22:39:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:05 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:39:05 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 22:39:05 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 22:39:05 INFO - Using env: {'DISPLAY': ':0', 22:39:05 INFO - 'HOME': '/home/cltbld', 22:39:05 INFO - 'LANG': 'en_US.UTF-8', 22:39:05 INFO - 'LANGUAGE': 'en_US:en', 22:39:05 INFO - 'LOGNAME': 'cltbld', 22:39:05 INFO - 'MAIL': '/var/mail/cltbld', 22:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:05 INFO - 'MOZ_NO_REMOTE': '1', 22:39:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:05 INFO - 'NO_EM_RESTART': '1', 22:39:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:05 INFO - 'PWD': '/builds/slave/test', 22:39:05 INFO - 'SHELL': '/bin/bash', 22:39:05 INFO - 'SHLVL': '1', 22:39:05 INFO - 'TERM': 'linux', 22:39:05 INFO - 'TMOUT': '86400', 22:39:05 INFO - 'USER': 'cltbld', 22:39:05 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:05 INFO - '_': '/tools/buildbot/bin/python'} 22:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:39:05 INFO - Running setup.py (path:/tmp/pip-yTQW8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:39:05 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)) 22:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:39:05 INFO - Running setup.py (path:/tmp/pip-mDc446-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:39:05 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)) 22:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:39:05 INFO - Running setup.py (path:/tmp/pip-I04sdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:39:05 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)) 22:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:39:05 INFO - Running setup.py (path:/tmp/pip-bOZsbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:39:05 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)) 22:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:39:05 INFO - Running setup.py (path:/tmp/pip-tMJHTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:39:06 INFO - Running setup.py (path:/tmp/pip-YbNfmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:39:06 INFO - Running setup.py (path:/tmp/pip-qPNIqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:39:06 INFO - Running setup.py (path:/tmp/pip-qMLxuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:39:06 INFO - Running setup.py (path:/tmp/pip-V7ITsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:39:06 INFO - Running setup.py (path:/tmp/pip-DSoXvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:39:06 INFO - Running setup.py (path:/tmp/pip-BSKS6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:39:06 INFO - Running setup.py (path:/tmp/pip-dsFXLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:39:06 INFO - Running setup.py (path:/tmp/pip-MxUcZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:39:06 INFO - Running setup.py (path:/tmp/pip-4Rw6X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:06 INFO - Running setup.py (path:/tmp/pip-O8yPVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:39:06 INFO - Running setup.py (path:/tmp/pip-rKZapu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:39:06 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)) 22:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:39:06 INFO - Running setup.py (path:/tmp/pip-CruDeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:39:06 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)) 22:39:06 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)) 22:39:06 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)) 22:39:06 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)) 22:39:06 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)) 22:39:06 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)) 22:39:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:39: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 22:39: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 22:39: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 22:39: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 22:39: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 22:39: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 22:39:08 INFO - Downloading blessings-1.6.tar.gz 22:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:39:08 INFO - Installing collected packages: blessings 22:39:08 INFO - Running setup.py install for blessings 22:39:08 INFO - Successfully installed blessings 22:39:08 INFO - Cleaning up... 22:39:09 INFO - Return code: 0 22:39:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:39:09 INFO - Reading from file tmpfile_stdout 22:39:09 INFO - Current package versions: 22:39:09 INFO - argparse == 1.2.1 22:39:09 INFO - blessings == 1.6 22:39:09 INFO - blobuploader == 1.2.4 22:39:09 INFO - docopt == 0.6.1 22:39:09 INFO - functools32 == 3.2.3-2 22:39:09 INFO - jsonschema == 2.5.1 22:39:09 INFO - manifestparser == 1.1 22:39:09 INFO - mozInstall == 1.12 22:39:09 INFO - mozcrash == 0.17 22:39:09 INFO - mozdebug == 0.1 22:39:09 INFO - mozdevice == 0.48 22:39:09 INFO - mozfile == 1.2 22:39:09 INFO - mozhttpd == 0.7 22:39:09 INFO - mozinfo == 0.9 22:39:09 INFO - mozleak == 0.1 22:39:09 INFO - mozlog == 3.3 22:39:09 INFO - moznetwork == 0.27 22:39:09 INFO - mozprocess == 0.23 22:39:09 INFO - mozprofile == 0.28 22:39:09 INFO - mozrunner == 6.12 22:39:09 INFO - mozscreenshot == 0.1 22:39:09 INFO - mozsystemmonitor == 0.3 22:39:09 INFO - moztest == 0.7 22:39:09 INFO - mozversion == 1.4 22:39:09 INFO - psutil == 3.1.1 22:39:09 INFO - requests == 1.2.3 22:39:09 INFO - wsgiref == 0.1.2 22:39:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:09 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 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:39:09 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 22:39:09 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 22:39:09 INFO - Using env: {'DISPLAY': ':0', 22:39:09 INFO - 'HOME': '/home/cltbld', 22:39:09 INFO - 'LANG': 'en_US.UTF-8', 22:39:09 INFO - 'LANGUAGE': 'en_US:en', 22:39:09 INFO - 'LOGNAME': 'cltbld', 22:39:09 INFO - 'MAIL': '/var/mail/cltbld', 22:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:09 INFO - 'MOZ_NO_REMOTE': '1', 22:39:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:09 INFO - 'NO_EM_RESTART': '1', 22:39:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:09 INFO - 'PWD': '/builds/slave/test', 22:39:09 INFO - 'SHELL': '/bin/bash', 22:39:09 INFO - 'SHLVL': '1', 22:39:09 INFO - 'TERM': 'linux', 22:39:09 INFO - 'TMOUT': '86400', 22:39:09 INFO - 'USER': 'cltbld', 22:39:09 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:09 INFO - '_': '/tools/buildbot/bin/python'} 22:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 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)) 22:39:09 INFO - Cleaning up... 22:39:09 INFO - Return code: 0 22:39:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ae50f1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a2850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, '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': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', '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 22:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:39:09 INFO - Using env: {'DISPLAY': ':0', 22:39:09 INFO - 'HOME': '/home/cltbld', 22:39:09 INFO - 'LANG': 'en_US.UTF-8', 22:39:09 INFO - 'LANGUAGE': 'en_US:en', 22:39:09 INFO - 'LOGNAME': 'cltbld', 22:39:09 INFO - 'MAIL': '/var/mail/cltbld', 22:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:09 INFO - 'MOZ_NO_REMOTE': '1', 22:39:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:09 INFO - 'NO_EM_RESTART': '1', 22:39:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:09 INFO - 'PWD': '/builds/slave/test', 22:39:09 INFO - 'SHELL': '/bin/bash', 22:39:09 INFO - 'SHLVL': '1', 22:39:09 INFO - 'TERM': 'linux', 22:39:09 INFO - 'TMOUT': '86400', 22:39:09 INFO - 'USER': 'cltbld', 22:39:09 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:09 INFO - '_': '/tools/buildbot/bin/python'} 22:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:39:09 INFO - Cleaning up... 22:39:09 INFO - Return code: 0 22:39:09 INFO - Running post-action listener: _resource_record_post_action 22:39:09 INFO - Running post-action listener: _start_resource_monitoring 22:39:09 INFO - Starting resource monitoring. 22:39:09 INFO - [mozharness: 2016-09-02 05:39:09.698722Z] Finished create-virtualenv step (success) 22:39:09 INFO - [mozharness: 2016-09-02 05:39:09.699225Z] Running install step. 22:39:09 INFO - Running pre-action listener: _resource_record_pre_action 22:39:09 INFO - Running main action method: install 22:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:39:09 INFO - Reading from file tmpfile_stdout 22:39:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:39:09 INFO - Reading from file tmpfile_stdout 22:39:09 INFO - Output received: 22:39:09 INFO - Usage: mozinstall [options] installer 22:39:09 INFO - Options: 22:39:09 INFO - -h, --help show this help message and exit 22:39:09 INFO - -d DEST, --destination=DEST 22:39:09 INFO - Directory to install application into. [default: 22:39:09 INFO - "/builds/slave/test"] 22:39:09 INFO - --app=APP Application being installed. [default: firefox] 22:39:09 INFO - mkdir: /builds/slave/test/build/application 22:39:09 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'] 22:39:09 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 22:39:27 INFO - Reading from file tmpfile_stdout 22:39:27 INFO - Output received: 22:39:27 INFO - /builds/slave/test/build/application/firefox/firefox 22:39:27 INFO - Running post-action listener: _resource_record_post_action 22:39:27 INFO - [mozharness: 2016-09-02 05:39:27.006564Z] Finished install step (success) 22:39:27 INFO - [mozharness: 2016-09-02 05:39:27.006908Z] Running run-tests step. 22:39:27 INFO - Running pre-action listener: _resource_record_pre_action 22:39:27 INFO - Running main action method: run_tests 22:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:39:27 INFO - Python 2.7.3 22:39:27 INFO - Return code: 0 22:39:27 INFO - grabbing minidump binary from tooltool 22:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:27 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 0x139d370>, '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 0x13a08f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a0d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:39:27 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 22:39:27 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 22:39:27 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 22:39:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:39:27 INFO - Return code: 0 22:39:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:39:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:39:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:39:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:39:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 22:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 22:39:27 INFO - Using env: {'DISPLAY': ':0', 22:39:27 INFO - 'HOME': '/home/cltbld', 22:39:27 INFO - 'LANG': 'en_US.UTF-8', 22:39:27 INFO - 'LANGUAGE': 'en_US:en', 22:39:27 INFO - 'LOGNAME': 'cltbld', 22:39:27 INFO - 'MAIL': '/var/mail/cltbld', 22:39:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:39:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:27 INFO - 'MOZ_NO_REMOTE': '1', 22:39:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:39:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:27 INFO - 'NO_EM_RESTART': '1', 22:39:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:27 INFO - 'PWD': '/builds/slave/test', 22:39:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:39:27 INFO - 'SHELL': '/bin/bash', 22:39:27 INFO - 'SHLVL': '1', 22:39:27 INFO - 'TERM': 'linux', 22:39:27 INFO - 'TMOUT': '86400', 22:39:27 INFO - 'USER': 'cltbld', 22:39:27 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918', 22:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:27 INFO - '_': '/tools/buildbot/bin/python'} 22:39:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 22:39:27 INFO - mozversion application_buildid: 20160901193046 22:39:27 INFO - mozversion application_changeset: d5f20820c80514476f596090292a5d77c4b41e3b 22:39:27 INFO - mozversion application_display_name: Nightly 22:39:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:39:27 INFO - mozversion application_name: Firefox 22:39:27 INFO - mozversion application_remotingname: firefox 22:39:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:39:27 INFO - mozversion application_vendor: Mozilla 22:39:27 INFO - mozversion application_version: 51.0a1 22:39:27 INFO - mozversion platform_buildid: 20160901193046 22:39:27 INFO - mozversion platform_changeset: d5f20820c80514476f596090292a5d77c4b41e3b 22:39:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:39:27 INFO - mozversion platform_version: 51.0a1 22:39:27 INFO - using testdate: 1472794767 22:39:27 INFO - actual date: 1472794767 22:39:27 INFO - starting webserver on 'localhost:36619' 22:39:27 INFO - SUITE-START | Running 2 tests 22:39:27 INFO - TEST-START | dromaeo_css 22:39:27 INFO - operating with platform_type : linux_ 22:39:27 INFO - Initialising browser for dromaeo_css test... 22:39:27 INFO - TEST-INFO | started process 22824 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNbLTV/profile http://localhost:36619/getInfo.html) 22:39:31 INFO - PROCESS | 22824 | __metrics Screen width/height:1600/1200 22:39:31 INFO - PROCESS | 22824 | colorDepth:24 22:39:31 INFO - PROCESS | 22824 | Browser inner width/height: 1024/697 22:39:31 INFO - PROCESS | 22824 | __metrics 22:39:32 INFO - PROCESS | 22824 | 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" 22:39:32 INFO - PROCESS | 22824 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:39:32 INFO - PROCESS | 22824 | console.error: PushService: 22:39:32 INFO - PROCESS | 22824 | stateChangeProcessEnqueue: Error transitioning state 22:39:32 INFO - PROCESS | 22824 | UnknownError 22:39:33 INFO - TEST-INFO | 22824: exit 0 22:39:33 INFO - Browser initialized. 22:39:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:39:33 INFO - TEST-INFO | started process 22904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNbLTV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:39:45 INFO - PROCESS | 22904 | RSS: Main: 178896896 22:39:45 INFO - PROCESS | 22904 | 22:40:36 INFO - PROCESS | 22904 | 1472794836495 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] 22:42:46 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36619/tests/dromaeo/cssquery-ext.html) 22:42:47 INFO - PROCESS | 22904 | RSS: Main: 308862976 22:42:47 INFO - PROCESS | 22904 | 22:45:50 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-ext.html (next: http://localhost:36619/tests/dromaeo/cssquery-jquery.html) 22:45:50 INFO - PROCESS | 22904 | RSS: Main: 268881920 22:45:50 INFO - PROCESS | 22904 | 22:48:52 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36619/tests/dromaeo/cssquery-mootools.html) 22:48:53 INFO - PROCESS | 22904 | RSS: Main: 214753280 22:48:53 INFO - PROCESS | 22904 | 22:51:55 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36619/tests/dromaeo/cssquery-prototype.html) 22:51:55 INFO - PROCESS | 22904 | RSS: Main: 212570112 22:51:55 INFO - PROCESS | 22904 | 22:54:58 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36619/tests/dromaeo/cssquery-yui.html) 22:54:59 INFO - PROCESS | 22904 | RSS: Main: 215330816 22:54:59 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | Cycle 1(1): loaded http://localhost:36619/tests/dromaeo/cssquery-yui.html (next: http://localhost:36619/tests/dromaeo/cssquery-dojo.html) 22:58:02 INFO - PROCESS | 22904 | RSS: Main: 280006656 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | __start_tp_report 22:58:02 INFO - PROCESS | 22904 | _x_x_mozilla_page_load 22:58:02 INFO - PROCESS | 22904 | _x_x_mozilla_page_load_details 22:58:02 INFO - PROCESS | 22904 | |i|pagename|runs| 22:58:02 INFO - PROCESS | 22904 | |0;dojo.html;2962.3779006408654;2974.4645963726525;3030.8939187128453;3057.8012429192113;3083.15213316338;5046.54581087702;5140.074786538422;5155.716435596046;5207.739613019348;5234.55506281966;5127.692338459695;5130.512601302879;5173.767180476878;5178.870528236786;5211.713355765441;4607.055553611518;4611.123886461578;4760.2383618621025;4797.424309082915;4848.418189817209;4743.146233677937;4771.498992605779;4784.1866882629865;4792.83225087122;4831.396075490564;4605.30920361945;4650.116477869194;4664.626829853638;4678.649101317398;4716.08036432894;4684.812607495696;4699.154152252593;4703.835365762182;4708.57622813945;4712.552307530779;4660.883477913079;4669.603083737887;4672.462666793294;4672.883177920545;4679.134360143385;5030.144875371196;5097.413797413308;5125.38495380553;5184.429712731597;5188.45521220274;5429.53848922842;5438.564914806806;5442.102053161224;5498.340199176085;5505.779768809243;5498.367687715924;5528.75120619573;5539.8892022159735;5548.889022219573;5554.777808887639;5106.4893510648635;5144.511271429208;5186.92219616706;5201.817936372209;5233.398159211702;5648.181013752983;5653.265075540236;5659.207710920475;5674.886502269931;5684.346300175533;5423.538999185034;5433;5434.266374039454;5436.048691478975;5440;5356.4107948125675;5423.674579525241;5472.370677372153;5473.06957817172;5527.53015993645;5449.264349312872;5451.209574611738;5455.727213639302;5458.808941687022;5461.399246082928;5468.179773034077;5471.261379713687;5476.205950137208;5480.6437581557075;5546.861328466821;4209.6000879916355;4240.1731662326165;4241.724287921275;4275.337322714984;4283.443152390231;3826.7321287510163;3846.11539345952;3857;3885.592012838668;3887.552931412867;5204.0632686116105;5206.167013277857;5212.40057393397;5214.061468935628;5214.243934629458;5375.81184658535;5394.811181608703;5414.29614150158;5423.105187644073;5502.8349149525575;5154.8969020620125;5156.355455568054;5159.484051594811;5160.0969830279555;5214.504622060974;4650.069986002813;4745.715257084586;4756.6432517561325;4759.095771803346;4762.119007983534;5570.97214513933;5618.353889302701;5628.352739435018;5650.519705824887;5718.28521434829;5445.972770136124;5502.257195278747;5506.91739623914;5508.228847961369;5512.80705175333;5561.916571251515;5627.324721033502;5635.432984444793;5644.125160600113;5647.068233741386;5287.9471205287455;5348.117560602457;5361.597880158925;5364.275822763877;5424.674519528841;5409.053415652324;5428.240046393429;5433.673979561239;5435.755391007335;5442.12925931849;5245.108331583564;5294.205869119663;5319.973400133129;5325.813596524103;5363.2759577456545;5308.867278318073;5334.573234141337;5336.759845807026;5344.118220493575;5416.729163541886;5290.312259406252;5291.365036195682;5295.232191332158;5296.390915044819;5297.390800058042;5281.498257665516;5365.275687782099;5375.086235339923;5377.973110134424;5379.731013449234;5287.814926477555;5329.280547126088;5350.30446042012;5368.275282836923;5427.402985671494 22:58:02 INFO - PROCESS | 22904 | |1;ext.html;574.2678085441062;614.2199406753423;627.9843003924901;633.4805459523192;664.7042066280501;42485.36271955919;44022.7798861006;44063.3390499142;44123;47046.76476617621;44624.107517849705;44977.325340119925;45153.77423112888;45305.09389812202;45319.77340113303;8847.911520884789;8994.910050899472;9003.504807235633;9003.909960900406;9013.188813006827;8510.276626486757;8597.828043439127;8598.140185981383;8601.311895048413;8605.053444121142;8938.910610893872;8978.775530611721;8979.640814367418;8980.236679882219;8982.86525702115;8928.107189281085;8937.821243575157;8960.596773145158;8963.775905602315;8966.820663586757;8812.030676625565;8878.467291962439;8881.511516866569;8884.11158884113;8894.11058894112;42259.1548169038;42621.57378426207;42716;42765;42783.35824962628;2889.7688184945155;3019.9396012079637;3063.433264846011;3102.519109538025;3710.684591809699;31430.528542071886;31791.84104079488;32014.83992580022;32442.513362299585;32801.50797738036;3712.7401081924277;3725.8882233532768;3749.250149970017;3789.5073640426845;3800.1829606634706;328970;331552;332310;332358;337653;58284.41715582875;59119.70440147772;59220.04429180615;59223;59279.40720592825;36255.4561681575;36944.63055369466;37068.44397334042;38563;38715.612843871764;16436.342546298252;16615.50153495397;16652.417165399154;16655.91672041632;16663.750043749355;13620.591382258548;13771.380287887068;13786.379612917443;13786.586402407944;13795.10331828428;19242.979487153927;19243.711344329848;19247.711284330748;19320.613587728167;19326.323578674677;6897.7585784497305;6946.895796563055;6987.5807451552055;7134.714611415589;7146.499745017799;919.8850143732034;977.4868194198103;1007.6120693532896;1041.2607048995299;1111.877004225738;31168.844155779076;31635.683643163735;31645.84177079146;31855.84072079625;32031.679683203336;939.3424602778083;1048.0253910227448;1073.672529878379;1122.3826895207603;1123.0959077942334;15357.769633455508;15420.228988550527;15484.148371839658;15511.767323490156;15827.683446331239;544650;548587;548596;548874;548955;55573.72213138989;56378.71810641002;56479.71760141173;56530.43469565252;56614.71692641593;1223.9449224784905;1317.3742472325569;1344.2942455210896;1346.1115663661935;1358.9932050339685;29969.70030299669;32130;32264.35471290608;32366.838165809022;32572.51141232836;40999.7950010248;41492;41517.584824151374;41573.58426415697;41633.16733665249;309503;310725;311207;311214;311244;25845.483090338465;28273.43453130967;29094;29108.56337154902;29634.25914352072;47374.28938565855;47864.10242310119;48061.75969120272;48206;48637.75681121572;39624.60375396209;39682.40476392799;39859.80420587387;40107.799461002505;41166.38250426186;41576.376354355314;41784.3732344009;42040.069581909534;42120.78939605282;42447.15105697807;39777;40263.396049058705;40718.796406017784;40721.592784072964;40890.1821963565;33041.834790825895;35940.4608930861;36765;37275.25449491049;37732.62267377401;28293.292667683472;28296.71703283023;28495.572566411935;28727.281817954718;28818.567721483774 22:58:02 INFO - PROCESS | 22904 | |2;jquery.html;364.99270014599693;375.99248015039683;395.8199019446152;397.703710735502;421.79753718215255;16353.591160220985;18869.056547172622;19077.71383429248;19616.60766784667;19927.501812454695;17479;17528.91235543824;17942.19260133294;17966.281348746066;18100;5497.642653227547;5524.14375771756;5736.4550367715;5739.167820666022;5740.794433169019;5576.33084029917;5636.802711905084;5670.0927851543765;5671.06427439474;5690.9146362804595;5562.3047119110115;5625.662460252398;5670.461306175906;5674.375818659944;5679.176519404684;5451.236826844245;5510;5543.639663421905;5548.500634942854;5575.972120139373;4995.350604421438;5011.323471331397;5015.22264049073;6690.899636505456;7031.472639552054;24209.878950605133;25860;26483.73516264851;26796.062137825083;26812.46375072507;2067.9483012924647;2113.3905813061324;2157.4822042709793;2214.1807531213553;2215.8227341812617;13210;13987.230702311368;14205.431782728678;14501.347439365254;14570.4900328488;1656.7183578791635;1702.174445393983;1704.2245778170902;1749.80752117267;1767.3284152022277;91538;100989;101079;103024;105541.47229263805;17128.91435542839;17556.38552650651;17591.38430154964;17622.73565896487;18070.457886263433;31740.206494837814;31860.36279274448;31913.25561398924;31957.20106997344;32063.679363206535;9317.906820931705;9442.669506567237;9623.663171788954;9735.221182305399;9765.218782497383;8368.49789012649;8478.364122690822;8669.436486628349;8841.646334146692;8967.506787126771;8481.27909127731;8777;8856.202941735275;8895.866562001574;8971.551422428984;3139.450596145711;3428.9142771430916;3442.5868895732674;3537.327907697529;3552.8223588820456;885.2121611765534;929.3169520402499;932.7714709896119;946.5267366316841;962.9181519570775;18229.63540729178;19468.123934422983;19502.90248548748;19534.90232548828;19898.303559375352;947.9383840050375;971.3297824501071;973.1339108193713;996.1632228927735;1011.4234886114843;10090.848637270446;10744.14046876248;10784.676459706221;10794.892051079545;10885.673429797118;477584;481991;496002;496616;496715;91218.54390728004;93273.5336323314;93723;95706.52146739222;96757.5162124199;944.471096186138;975.7902051059057;985.8028394321165;996.1283876607823;996.7807082441851;16229.67540649204;16701.916490417956;17129.571760706083;17561.473155805346;17722.02528860925;42016.78991605145;42077.36883946682;42298.577014229464;42584;42765.5723442774;57378.42621573731;58122.7093864528;58322.708386457794;58327.4167258322;59957.40042599518;7077.478342156461;7909.604519774103;8215.548144852091;8363.958180209303;8517.190866867637;10816.513256903301;12928.612141635766;13198.274094924871;13263;13413.731725365633;10829.51267192994;11177.273477223955;11311.321320720783;11325.660230193107;11666.533338666359;9885.406875587487;11364.54541818318;11570;11600.825987610024;11714.23857449263;11318.886811132114;11448.427578621202;11489.253198542068;11726.765464690488;11815.113866459878;6819.283975182733;7282.198958114709;8026.7591972240925;8029.317508011651;8190.959045204736;10246.743831404274;10989.945050274697;11392.658220253406;11507.367094809866;11778.175527712978 22:58:02 INFO - PROCESS | 22904 | |3;mootools.html;1188.6018183908393;1211;1306.510058727977;1338.4713038349857;1711.161530849883;6850.1094857668495;7169.390601798847;7195.2085270620255;7491.250874912506;7501.0998680158455;7259.564426134436;7333.6333183340885;7350.816229594263;7353.632318384086;7357.779266621997;1870.5697689531416;2090.5191805884656;2133.359992002405;2152.4403655049723;2155.859869108511;2108.8629239099487;2121;2127.95744085119;2130.3928380411585;2131.211451762842;2093.602215579035;2102.2431924507164;2109.841761867858;2147.5168087180414;2153.353993801861;2038.5311378382908;2050.077465140685;2057.6399130152313;2111.641021026414;2114.1015068595784;2123.8938053097436;2124.4051665533675;2137.722096127509;2141.100737690174;2143.1534543855246;7350.926490735078;7454.776356709307;7455.105387353498;7456.888146677804;7458.477906546544;4616.930746038778;4741.502142275081;4741.762911854394;4742.5731684148595;4757.333973243749;3053.442535300278;3165.2878102427;3206.8556914938886;3216.6622504637144;3243.529688195222;1063.8723353197665;1066.7386490309846;1070.8500809886623;1082.5020490574266;1091.2633972068902;10676.412797296145;10692.625758098508;10757.246992710514;10773.24587278883;10784.352938823695;4043.110515686544;4078.510578730512;4216.093539888939;4223.1975924574235;4233.068724880522;8600.440971336842;8603;8610.268127209258;8614;8620.482771033758;4701.694389864716;4703.905921881543;4709.976450117728;4716.481187069419;4717.787699553528;3891.105045839473;4176.18564379943;4273.9358909616385;4297.72064815786;4371.431713877176;3370;3472.3576138414473;3532.964670353315;3562.340966921128;3578.355895938756;930.851063829784;931.189864817613;947.6825263536792;948.3645957208687;948.8908775490769;710.6304721544768;718.3786025088261;722.241646271413;746.8244962433823;754.8940801725389;5097;5221.817236396708;5275.683458992473;5357.5446087082255;5509.090999985037;424.4333814357805;430.9181255561433;434.15773399604666;434.2574198121185;438.82885674586936;3608.8376023079027;3613.7109031277437;3638.9636103639154;3663.8351274192723;3745.793981331053;9462.763430914281;9739.756506087404;9827.508624568743;9847.704568862946;9853.655122070693;9687.660931867491;9719.271054670786;9815.11663950248;9834.557444914995;9837.262205334628;6813.148356455443;6942.479314051366;7015.473839462061;7038.753643622448;7039.648017599202;4910.8281210157475;5130.12787826078;5276.15581506957;5296.814611488733;5584.134459158837;8770.91229087701;8958.283337332867;8992.055834137322;9067.04795996411;9100;8828.337874659297;8940.86588701157;9238.722838314861;9335.06649335061;9341.019192984639;6309.432151106423;6593.439557637655;6745.156855393076;6832;6975.407090397373;7148.964255178691;7192.352688258082;7203.459740519586;7203.71185152588;7208.7116515340895;6316.589421687576;6495.610263384212;6523.902141467787;6556.967215163893;6763.797086087426;6525.771597994048;6592.934070659232;6619.735210591522;6658.866822663616;6716.966415167893;6118.357572454828;6218.81343559694;6219.595726277777;6219.782307619212;6221;4336.978315108404;4573.702709323883;4614.746188959505;4616.884577885446;4624;4498.595126438636;4597;4602.792874320597;4712.7643617818285;4869.9026019480125 22:58:02 INFO - PROCESS | 22904 | |4;prototype.html;301.710358056266;313.3873277742015;316.1653235458389;317.92210908327456;323.52118864081166;5115.207142892848;5188.403333616635;5345.984262990034;5382.3541175059;5400.594955378348;5176.275321454999;5197.48025197481;5208.921866172011;5211.166213405856;5264.973675131629;4140.378943158536;4148.3777433385;4148.668106551469;4154.480689913761;4158.6465150462245;3946.5461471930666;4092.9590704092875;4161.313383291768;4176.394422808691;4218.83981904977;4066.125782956664;4077.8368865245357;4078;4079.8572049978256;4085.428040074392;4037.465763010067;4052.844939192331;4056.371262454324;4059.0664147246152;4072.0634254121424;3551.485034669984;3553.9338198540336;3568.750187486877;3576.499290099388;3576.5886923003927;4992.525710057539;5037.596992240648;5069.315642388267;5089.51649593288;5092.388913330424;4551;4559.726416414993;4561.635069194456;4568.834947088471;4569.474510431276;5371.167469042305;5378.005069062236;5379;5379.390995367283;5390.757415916293;3752.1745216052423;3806.143617686026;3819.2361527694293;3849.172427928009;3864.478295430109;5575.2473416088715;5588.86027849303;5593.776248950037;5596.384397716289;5597.80407685729;3878.301905657009;3909.081365879016;3910.9413358799643;3924.6860251179837;3944.36890097583;3869.496965394481;3941.684665226775;3957.782321972262;3981.6018398159954;3992.9800350998057;4489.506154323022;4498;4511.255642818898;4517.367568540407;4554.681172317973;5409.972950135224;5412.431694672003;5421.918671219935;5482.205080263339;5499.395066542677;1309.2013871538354;1336.8395792504962;1358.7622166120889;1358.870907263828;1364.3723887012086;929.0987741890356;938.2728385501155;951.167728237792;957.9856302155473;958.4919992404037;5248.1340578804065;5252.428348722829;5252.711100889488;5257.78968841242;5261.421243663194;5498.285222920995;5517.862053448615;5520.66875987442;5530.446955304518;5537.335519737658;5060.468650791688;5265.420803711589;5265.919437129246;5276.15581506957;5333.466653334635;2937.911862644124;2938.5454200171052;2951.4982452983045;2955.6699485231734;2967.4658561458714;5713.885722285531;5755.8561035974435;5769.249997500298;5777.942220577825;5804.129380592861;5579.6652200868075;5716.371199168088;5743.1385292206505;5745.080787074048;5757.251557297608;5754.856128596735;5762.3577280474865;5790.118211581253;5795.5226863881335;5801.361850196475;5358.46415358461;5557.72211389437;5581.469760372758;5618.297712785902;5628.183913332462;5521.696306703009;5545.085060965057;5553.6390134641115;5554.166874968625;5560.610757246954;5749.195112684144;5756.07902735577;5758.366579676149;5773.97113014449;5804.303483581997;3785.602511736228;3912.689249101583;3929.3909444035075;3932.3511620582285;3959.1685745992513;5304.469553044664;5554.305711786027;5554.583581186772;5572.442755724395;5593.916091258716;5303.893922121459;5426.2674538936735;5427.701476418835;5453.509184173444;5466.781328746805;5436.646617969819;5442.727863606725;5461.617686761889;5490.3411590609385;5494.725263736717;5167.199084141964;5392.299001129828;5408.4321146280645;5415.6479828811;5422.322209723785;5445.101558243004;5451.509364157245;5466.098093814476;5482.259894914295;5494.642848214853;5356.384015838073;5359.928014397214;5368.677879327252;5379.757910893941;5392.622516423813 22:58:02 INFO - PROCESS | 22904 | |5;yui.html;652.849844535757;657.62186742623;692.5913710910562;711.3491155592633;712.479889680533;12663.430145643446;13582.728345433085;13594.18434893907;13620.863791362084;13666.38501267443;12374.752504949896;12469.127161098726;12487.375631218449;12489.625311240678;12494;646.2858541311854;765.372394636397;775.7982924439658;783.1737516919648;793.4921650143913;793.305857374797;802.638812534359;802.8514724775906;802.8795680647892;803.562058678019;861.315254372772;861.3496809908542;862.706679728892;863.1325517854548;863.1627321498137;678.6267552845915;816.6121092481084;839.8698201778734;848.0290067872282;854.4275335525182;613.4356392119261;614.3395849461847;614.8186285045922;614.8985417406122;615.1418770814734;12570.120091593591;12586.43361048755;12596.055295852757;12596.181248218836;12628.68428289291;11216.439178041066;11438.256513326607;11622.5471816023;11717.648470545897;12199.329036902967;10714.892851071467;10883.23817332787;11012.063974562172;11018.889811101946;11058.170637202242;3522.6653467920764;3528.400171970771;3537.6462353764673;3600.5499312585926;3621.5110960020324;81712.59143704303;83467;85296.57351713264;88254;88389.55805220998;8803.867941980876;8923.464592124363;9053.275737941081;9053.502057386775;9390.060993900692;16537.090460024814;16826.83173168277;16830.83169168317;16849.494515164566;16859.409920652717;7147.463940204506;7200.783976480714;7246.60143692102;7448.255174482509;7467;3825.2923209206388;3868.9226215475533;3925.4111883217747;3929;3945.191235796655;2812.5078111330845;2889.8555072246304;2893.594896714462;2894.1751600793673;2929.487339715541;514.7271945868696;517.5652451940388;519.2807960974078;522.874510117569;527.6702061211743;557.40636222423;579.2816907035246;593.1310629927091;595.3897255313339;646.2342124582385;9015.14356136166;9633.807323853483;9712.854307185398;9718.514074296258;9940.40357578533;582.592185470164;666.6233578028398;668.1781408867121;674.7435974329724;690.7133539581017;4282.229198744256;4623;4655.631611698817;4702.811887524461;4716.551927566876;126467.3676631611;132232.33883830518;132689;133458.30729709004;135325.3233733825;110323;111434;112312;113930.43034784772;118037.4098129521;78900;80058;95381.5230923841;99791;102029;9139.268858491172;9348.439093654402;9516.666916657883;9533.618655253713;9646.758831029281;48622.75688621676;49191.50808491869;49659.456328519955;51703;52725;55033.724831375584;55900.720496397254;77638.61180694286;79156.60421698085;82554;11840.28958262507;11906.76186476283;11985.64043078709;12001.699957501132;12202;12971.286579238233;13094.672633184247;13210.735785284432;14121.2233327168;14169.929150354596;12124.939375303067;12474.937625311815;12650.873491265338;12932.482700692244;13439.932800335935;12021.158518903592;12342.259464432163;12464.314462704639;12671.683207919876;13148.671283217996;11879.22785018971;12265.938670306592;12377.443015064164;12640.55758048464;13559.661008474472;9884.85172722424;10696.572137114428;10771.946140269249;10915.181361397768;11126.666200014013;10343.37939723619;10507;10552.78894422092;10564.524596393334;10580.576776929145 22:58:02 INFO - PROCESS | 22904 | __end_tp_report 22:58:02 INFO - PROCESS | 22904 | __start_cc_report 22:58:02 INFO - PROCESS | 22904 | _x_x_mozilla_cycle_collect,1024 22:58:02 INFO - PROCESS | 22904 | __end_cc_report 22:58:02 INFO - PROCESS | 22904 | __startTimestamp1472795882308__endTimestamp 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | ------- Summary: start ------- 22:58:02 INFO - PROCESS | 22904 | Number of tests: 6 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#0] dojo.html Cycles:165 Average:5106.88 Median:5295.23 stddev:551.66 (10.4%) stddev-sans-first:527.07 22:58:02 INFO - PROCESS | 22904 | Values: 2962.4 2974.5 3030.9 3057.8 3083.2 5046.5 5140.1 5155.7 5207.7 5234.6 5127.7 5130.5 5173.8 5178.9 5211.7 4607.1 4611.1 4760.2 4797.4 4848.4 4743.1 4771.5 4784.2 4792.8 4831.4 4605.3 4650.1 4664.6 4678.6 4716.1 4684.8 4699.2 4703.8 4708.6 4712.6 4660.9 4669.6 4672.5 4672.9 4679.1 5030.1 5097.4 5125.4 5184.4 5188.5 5429.5 5438.6 5442.1 5498.3 5505.8 5498.4 5528.8 5539.9 5548.9 5554.8 5106.5 5144.5 5186.9 5201.8 5233.4 5648.2 5653.3 5659.2 5674.9 5684.3 5423.5 5433.0 5434.3 5436.0 5440.0 5356.4 5423.7 5472.4 5473.1 5527.5 5449.3 5451.2 5455.7 5458.8 5461.4 5468.2 5471.3 5476.2 5480.6 5546.9 4209.6 4240.2 4241.7 4275.3 4283.4 3826.7 3846.1 3857.0 3885.6 3887.6 5204.1 5206.2 5212.4 5214.1 5214.2 5375.8 5394.8 5414.3 5423.1 5502.8 5154.9 5156.4 5159.5 5160.1 5214.5 4650.1 4745.7 4756.6 4759.1 4762.1 5571.0 5618.4 5628.4 5650.5 5718.3 5446.0 5502.3 5506.9 5508.2 5512.8 5561.9 5627.3 5635.4 5644.1 5647.1 5287.9 5348.1 5361.6 5364.3 5424.7 5409.1 5428.2 5433.7 5435.8 5442.1 5245.1 5294.2 5320.0 5325.8 5363.3 5308.9 5334.6 5336.8 5344.1 5416.7 5290.3 5291.4 5295.2 5296.4 5297.4 5281.5 5365.3 5375.1 5378.0 5379.7 5287.8 5329.3 5350.3 5368.3 5427.4 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#1] ext.html Cycles:180 Average:55676.55 Median:39983.80 stddev:109119.82 (272.9%) stddev-sans-first:109347.50 22:58:02 INFO - PROCESS | 22904 | Values: 574.3 614.2 628.0 633.5 664.7 42485.4 44022.8 44063.3 44123.0 47046.8 44624.1 44977.3 45153.8 45305.1 45319.8 8847.9 8994.9 9003.5 9003.9 9013.2 8510.3 8597.8 8598.1 8601.3 8605.1 8938.9 8978.8 8979.6 8980.2 8982.9 8928.1 8937.8 8960.6 8963.8 8966.8 8812.0 8878.5 8881.5 8884.1 8894.1 42259.2 42621.6 42716.0 42765.0 42783.4 2889.8 3019.9 3063.4 3102.5 3710.7 31430.5 31791.8 32014.8 32442.5 32801.5 3712.7 3725.9 3749.3 3789.5 3800.2 328970.0 331552.0 332310.0 332358.0 337653.0 58284.4 59119.7 59220.0 59223.0 59279.4 36255.5 36944.6 37068.4 38563.0 38715.6 16436.3 16615.5 16652.4 16655.9 16663.8 13620.6 13771.4 13786.4 13786.6 13795.1 19243.0 19243.7 19247.7 19320.6 19326.3 6897.8 6946.9 6987.6 7134.7 7146.5 919.9 977.5 1007.6 1041.3 1111.9 31168.8 31635.7 31645.8 31855.8 32031.7 939.3 1048.0 1073.7 1122.4 1123.1 15357.8 15420.2 15484.1 15511.8 15827.7 544650.0 548587.0 548596.0 548874.0 548955.0 55573.7 56378.7 56479.7 56530.4 56614.7 1223.9 1317.4 1344.3 1346.1 1359.0 29969.7 32130.0 32264.4 32366.8 32572.5 40999.8 41492.0 41517.6 41573.6 41633.2 309503.0 310725.0 311207.0 311214.0 311244.0 25845.5 28273.4 29094.0 29108.6 29634.3 47374.3 47864.1 48061.8 48206.0 48637.8 39624.6 39682.4 39859.8 40107.8 41166.4 41576.4 41784.4 42040.1 42120.8 42447.2 39777.0 40263.4 40718.8 40721.6 40890.2 33041.8 35940.5 36765.0 37275.3 37732.6 28293.3 28296.7 28495.6 28727.3 28818.6 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#2] jquery.html Cycles:180 Average:30495.69 Median:385.91 stddev:81112.74 (21018.8%) stddev-sans-first:81308.73 22:58:02 INFO - PROCESS | 22904 | Values: 365.0 376.0 395.8 397.7 421.8 16353.6 18869.1 19077.7 19616.6 19927.5 17479.0 17528.9 17942.2 17966.3 18100.0 5497.6 5524.1 5736.5 5739.2 5740.8 5576.3 5636.8 5670.1 5671.1 5690.9 5562.3 5625.7 5670.5 5674.4 5679.2 5451.2 5510.0 5543.6 5548.5 5576.0 4995.4 5011.3 5015.2 6690.9 7031.5 24209.9 25860.0 26483.7 26796.1 26812.5 2067.9 2113.4 2157.5 2214.2 2215.8 13210.0 13987.2 14205.4 14501.3 14570.5 1656.7 1702.2 1704.2 1749.8 1767.3 91538.0 100989.0 101079.0 103024.0 105541.5 17128.9 17556.4 17591.4 17622.7 18070.5 31740.2 31860.4 31913.3 31957.2 32063.7 9317.9 9442.7 9623.7 9735.2 9765.2 8368.5 8478.4 8669.4 8841.6 8967.5 8481.3 8777.0 8856.2 8895.9 8971.6 3139.5 3428.9 3442.6 3537.3 3552.8 885.2 929.3 932.8 946.5 962.9 18229.6 19468.1 19502.9 19534.9 19898.3 947.9 971.3 973.1 996.2 1011.4 10090.8 10744.1 10784.7 10794.9 10885.7 477584.0 481991.0 496002.0 496616.0 496715.0 91218.5 93273.5 93723.0 95706.5 96757.5 944.5 975.8 985.8 996.1 996.8 16229.7 16701.9 17129.6 17561.5 17722.0 42016.8 42077.4 42298.6 42584.0 42765.6 57378.4 58122.7 58322.7 58327.4 59957.4 7077.5 7909.6 8215.5 8364.0 8517.2 10816.5 12928.6 13198.3 13263.0 13413.7 10829.5 11177.3 11311.3 11325.7 11666.5 9885.4 11364.5 11570.0 11600.8 11714.2 11318.9 11448.4 11489.3 11726.8 11815.1 6819.3 7282.2 8026.8 8029.3 8191.0 10246.7 10989.9 11392.7 11507.4 11778.2 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#3] mootools.html Cycles:180 Average:5059.21 Median:5003.91 stddev:2867.50 (57.3%) stddev-sans-first:2860.79 22:58:02 INFO - PROCESS | 22904 | Values: 1188.6 1211.0 1306.5 1338.5 1711.2 6850.1 7169.4 7195.2 7491.3 7501.1 7259.6 7333.6 7350.8 7353.6 7357.8 1870.6 2090.5 2133.4 2152.4 2155.9 2108.9 2121.0 2128.0 2130.4 2131.2 2093.6 2102.2 2109.8 2147.5 2153.4 2038.5 2050.1 2057.6 2111.6 2114.1 2123.9 2124.4 2137.7 2141.1 2143.2 7350.9 7454.8 7455.1 7456.9 7458.5 4616.9 4741.5 4741.8 4742.6 4757.3 3053.4 3165.3 3206.9 3216.7 3243.5 1063.9 1066.7 1070.9 1082.5 1091.3 10676.4 10692.6 10757.2 10773.2 10784.4 4043.1 4078.5 4216.1 4223.2 4233.1 8600.4 8603.0 8610.3 8614.0 8620.5 4701.7 4703.9 4710.0 4716.5 4717.8 3891.1 4176.2 4273.9 4297.7 4371.4 3370.0 3472.4 3533.0 3562.3 3578.4 930.9 931.2 947.7 948.4 948.9 710.6 718.4 722.2 746.8 754.9 5097.0 5221.8 5275.7 5357.5 5509.1 424.4 430.9 434.2 434.3 438.8 3608.8 3613.7 3639.0 3663.8 3745.8 9462.8 9739.8 9827.5 9847.7 9853.7 9687.7 9719.3 9815.1 9834.6 9837.3 6813.1 6942.5 7015.5 7038.8 7039.6 4910.8 5130.1 5276.2 5296.8 5584.1 8770.9 8958.3 8992.1 9067.0 9100.0 8828.3 8940.9 9238.7 9335.1 9341.0 6309.4 6593.4 6745.2 6832.0 6975.4 7149.0 7192.4 7203.5 7203.7 7208.7 6316.6 6495.6 6523.9 6557.0 6763.8 6525.8 6592.9 6619.7 6658.9 6717.0 6118.4 6218.8 6219.6 6219.8 6221.0 4337.0 4573.7 4614.7 4616.9 4624.0 4498.6 4597.0 4602.8 4712.8 4869.9 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#4] prototype.html Cycles:180 Average:4580.17 Median:5271.04 stddev:1352.95 (25.7%) stddev-sans-first:1318.08 22:58:02 INFO - PROCESS | 22904 | Values: 301.7 313.4 316.2 317.9 323.5 5115.2 5188.4 5346.0 5382.4 5400.6 5176.3 5197.5 5208.9 5211.2 5265.0 4140.4 4148.4 4148.7 4154.5 4158.6 3946.5 4093.0 4161.3 4176.4 4218.8 4066.1 4077.8 4078.0 4079.9 4085.4 4037.5 4052.8 4056.4 4059.1 4072.1 3551.5 3553.9 3568.8 3576.5 3576.6 4992.5 5037.6 5069.3 5089.5 5092.4 4551.0 4559.7 4561.6 4568.8 4569.5 5371.2 5378.0 5379.0 5379.4 5390.8 3752.2 3806.1 3819.2 3849.2 3864.5 5575.2 5588.9 5593.8 5596.4 5597.8 3878.3 3909.1 3910.9 3924.7 3944.4 3869.5 3941.7 3957.8 3981.6 3993.0 4489.5 4498.0 4511.3 4517.4 4554.7 5410.0 5412.4 5421.9 5482.2 5499.4 1309.2 1336.8 1358.8 1358.9 1364.4 929.1 938.3 951.2 958.0 958.5 5248.1 5252.4 5252.7 5257.8 5261.4 5498.3 5517.9 5520.7 5530.4 5537.3 5060.5 5265.4 5265.9 5276.2 5333.5 2937.9 2938.5 2951.5 2955.7 2967.5 5713.9 5755.9 5769.2 5777.9 5804.1 5579.7 5716.4 5743.1 5745.1 5757.3 5754.9 5762.4 5790.1 5795.5 5801.4 5358.5 5557.7 5581.5 5618.3 5628.2 5521.7 5545.1 5553.6 5554.2 5560.6 5749.2 5756.1 5758.4 5774.0 5804.3 3785.6 3912.7 3929.4 3932.4 3959.2 5304.5 5554.3 5554.6 5572.4 5593.9 5303.9 5426.3 5427.7 5453.5 5466.8 5436.6 5442.7 5461.6 5490.3 5494.7 5167.2 5392.3 5408.4 5415.6 5422.3 5445.1 5451.5 5466.1 5482.3 5494.6 5356.4 5359.9 5368.7 5379.8 5392.6 22:58:02 INFO - PROCESS | 22904 | 22:58:02 INFO - PROCESS | 22904 | [#5] yui.html Cycles:180 Average:21146.89 Median:4452.61 stddev:33393.45 (750.0%) stddev-sans-first:33451.68 22:58:02 INFO - PROCESS | 22904 | Values: 652.8 657.6 692.6 711.3 712.5 12663.4 13582.7 13594.2 13620.9 13666.4 12374.8 12469.1 12487.4 12489.6 12494.0 646.3 765.4 775.8 783.2 793.5 793.3 802.6 802.9 802.9 803.6 861.3 861.3 862.7 863.1 863.2 678.6 816.6 839.9 848.0 854.4 613.4 614.3 614.8 614.9 615.1 12570.1 12586.4 12596.1 12596.2 12628.7 11216.4 11438.3 11622.5 11717.6 12199.3 10714.9 10883.2 11012.1 11018.9 11058.2 3522.7 3528.4 3537.6 3600.5 3621.5 81712.6 83467.0 85296.6 88254.0 88389.6 8803.9 8923.5 9053.3 9053.5 9390.1 16537.1 16826.8 16830.8 16849.5 16859.4 7147.5 7200.8 7246.6 7448.3 7467.0 3825.3 3868.9 3925.4 3929.0 3945.2 2812.5 2889.9 2893.6 2894.2 2929.5 514.7 517.6 519.3 522.9 527.7 557.4 579.3 593.1 595.4 646.2 9015.1 9633.8 9712.9 9718.5 9940.4 582.6 666.6 668.2 674.7 690.7 4282.2 4623.0 4655.6 4702.8 4716.6 126467.4 132232.3 132689.0 133458.3 135325.3 110323.0 111434.0 112312.0 113930.4 118037.4 78900.0 80058.0 95381.5 99791.0 102029.0 9139.3 9348.4 9516.7 9533.6 9646.8 48622.8 49191.5 49659.5 51703.0 52725.0 55033.7 55900.7 77638.6 79156.6 82554.0 11840.3 11906.8 11985.6 12001.7 12202.0 12971.3 13094.7 13210.7 14121.2 14169.9 12124.9 12474.9 12650.9 12932.5 13439.9 12021.2 12342.3 12464.3 12671.7 13148.7 11879.2 12265.9 12377.4 12640.6 13559.7 9884.9 10696.6 10771.9 10915.2 11126.7 10343.4 10507.0 10552.8 10564.5 10580.6 22:58:02 INFO - PROCESS | 22904 | -------- Summary: end -------- 22:58:02 INFO - PROCESS | 22904 | 22:58:03 INFO - TEST-INFO | 22904: exit 0 22:58:03 INFO - TEST-OK | dromaeo_css | took 1115865ms 22:58:03 INFO - TEST-START | kraken 22:58:03 INFO - operating with platform_type : linux_ 22:58:03 INFO - Initialising browser for kraken test... 22:58:03 INFO - TEST-INFO | started process 23210 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkkt3H7/profile http://localhost:36619/getInfo.html) 22:58:07 INFO - PROCESS | 23210 | __metrics Screen width/height:1600/1200 22:58:07 INFO - PROCESS | 23210 | colorDepth:24 22:58:07 INFO - PROCESS | 23210 | Browser inner width/height: 1024/697 22:58:07 INFO - PROCESS | 23210 | __metrics 22:58:07 INFO - PROCESS | 23210 | 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" 22:58:07 INFO - PROCESS | 23210 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:58:08 INFO - PROCESS | 23210 | console.error: PushService: 22:58:08 INFO - PROCESS | 23210 | stateChangeProcessEnqueue: Error transitioning state 22:58:08 INFO - PROCESS | 23210 | UnknownError 22:58:09 INFO - TEST-INFO | 23210: exit 0 22:58:09 INFO - Browser initialized. 22:58:09 INFO - Running cycle 1/1 for kraken test... 22:58:09 INFO - TEST-INFO | started process 23292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkkt3H7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:58:21 INFO - PROCESS | 23292 | RSS: Main: 179265536 22:58:21 INFO - PROCESS | 23292 | 22:58:23 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36619/tests/kraken/driver.html?testName=audio-beat-detection) 22:58:24 INFO - PROCESS | 23292 | RSS: Main: 238415872 22:58:24 INFO - PROCESS | 23292 | 22:58:27 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36619/tests/kraken/driver.html?testName=audio-dft) 22:58:27 INFO - PROCESS | 23292 | RSS: Main: 223657984 22:58:27 INFO - PROCESS | 23292 | 22:58:30 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36619/tests/kraken/driver.html?testName=audio-fft) 22:58:31 INFO - PROCESS | 23292 | RSS: Main: 300163072 22:58:31 INFO - PROCESS | 23292 | 22:58:33 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36619/tests/kraken/driver.html?testName=audio-oscillator) 22:58:34 INFO - PROCESS | 23292 | RSS: Main: 211238912 22:58:34 INFO - PROCESS | 23292 | 22:58:36 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36619/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:58:36 INFO - PROCESS | 23292 | RSS: Main: 213987328 22:58:36 INFO - PROCESS | 23292 | 22:58:39 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36619/tests/kraken/driver.html?testName=imaging-darkroom) 22:58:40 INFO - PROCESS | 23292 | RSS: Main: 252563456 22:58:40 INFO - PROCESS | 23292 | 22:58:43 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36619/tests/kraken/driver.html?testName=imaging-desaturate) 22:58:44 INFO - PROCESS | 23292 | RSS: Main: 251346944 22:58:44 INFO - PROCESS | 23292 | 22:58:47 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36619/tests/kraken/driver.html?testName=json-parse-financial) 22:58:47 INFO - PROCESS | 23292 | RSS: Main: 251871232 22:58:47 INFO - PROCESS | 23292 | 22:58:49 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36619/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:58:49 INFO - PROCESS | 23292 | RSS: Main: 213667840 22:58:49 INFO - PROCESS | 23292 | 22:58:51 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:58:52 INFO - PROCESS | 23292 | RSS: Main: 221151232 22:58:52 INFO - PROCESS | 23292 | 22:58:54 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:58:55 INFO - PROCESS | 23292 | RSS: Main: 222547968 22:58:55 INFO - PROCESS | 23292 | 22:58:57 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:58:58 INFO - PROCESS | 23292 | RSS: Main: 223567872 22:58:58 INFO - PROCESS | 23292 | 22:59:00 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:59:01 INFO - PROCESS | 23292 | RSS: Main: 221343744 22:59:01 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | Cycle 1(1): loaded http://localhost:36619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36619/tests/kraken/driver.html?testName=ai-astar) 22:59:03 INFO - PROCESS | 23292 | RSS: Main: 271917056 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | __start_tp_report 22:59:03 INFO - PROCESS | 23292 | _x_x_mozilla_page_load 22:59:03 INFO - PROCESS | 23292 | _x_x_mozilla_page_load_details 22:59:03 INFO - PROCESS | 23292 | |i|pagename|runs| 22:59:03 INFO - PROCESS | 23292 | |0;ai-astar;114;111;118;112;112;111;112;112;110;131 22:59:03 INFO - PROCESS | 23292 | |1;audio-beat-detection;145;118;144;181;120;151;147;117;187;123 22:59:03 INFO - PROCESS | 23292 | |2;audio-dft;162;161;161;161;161;163;162;161;161;162 22:59:03 INFO - PROCESS | 23292 | |3;audio-fft;75;74;75;181;75;76;74;75;171;75 22:59:03 INFO - PROCESS | 23292 | |4;audio-oscillator;81;81;151;81;81;82;149;81;81;143 22:59:03 INFO - PROCESS | 23292 | |5;imaging-gaussian-blur;117;116;117;116;116;117;116;117;117;117 22:59:03 INFO - PROCESS | 23292 | |6;imaging-darkroom;146;146;145;145;147;147;146;145;147;145 22:59:03 INFO - PROCESS | 23292 | |7;imaging-desaturate;95;95;95;94;95;95;95;94;95;95 22:59:03 INFO - PROCESS | 23292 | |8;json-parse-financial;50;52;52;53;53;59;54;52;52;53 22:59:03 INFO - PROCESS | 23292 | |9;json-stringify-tinderbox;62;62;62;62;62;61;61;62;62;62 22:59:03 INFO - PROCESS | 23292 | |10;stanford-crypto-aes;79;79;79;88;85;80;81;80;78;78 22:59:03 INFO - PROCESS | 23292 | |11;stanford-crypto-ccm;125;127;132;125;132;129;124;125;174;128 22:59:03 INFO - PROCESS | 23292 | |12;stanford-crypto-pbkdf2;164;167;161;166;163;164;160;161;164;165 22:59:03 INFO - PROCESS | 23292 | |13;stanford-crypto-sha256-iterative;65;69;65;68;64;69;64;64;63;63 22:59:03 INFO - PROCESS | 23292 | __end_tp_report 22:59:03 INFO - PROCESS | 23292 | __start_cc_report 22:59:03 INFO - PROCESS | 23292 | _x_x_mozilla_cycle_collect,1659 22:59:03 INFO - PROCESS | 23292 | __end_cc_report 22:59:03 INFO - PROCESS | 23292 | __startTimestamp1472795943522__endTimestamp 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | ------- Summary: start ------- 22:59:03 INFO - PROCESS | 23292 | Number of tests: 14 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#0] ai-astar Cycles:10 Average:114.30 Median:112.00 stddev:6.27 (5.6%) stddev-sans-first:6.65 22:59:03 INFO - PROCESS | 23292 | Values: 114.0 111.0 118.0 112.0 112.0 111.0 112.0 112.0 110.0 131.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:146.00 stddev:25.15 (17.2%) stddev-sans-first:26.67 22:59:03 INFO - PROCESS | 23292 | Values: 145.0 118.0 144.0 181.0 120.0 151.0 147.0 117.0 187.0 123.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 22:59:03 INFO - PROCESS | 23292 | Values: 162.0 161.0 161.0 161.0 161.0 163.0 162.0 161.0 161.0 162.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#3] audio-fft Cycles:10 Average:95.10 Median:75.00 stddev:42.71 (56.9%) stddev-sans-first:44.67 22:59:03 INFO - PROCESS | 23292 | Values: 75.0 74.0 75.0 181.0 75.0 76.0 74.0 75.0 171.0 75.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#4] audio-oscillator Cycles:10 Average:101.10 Median:81.00 stddev:32.20 (39.7%) stddev-sans-first:33.32 22:59:03 INFO - PROCESS | 23292 | Values: 81.0 81.0 151.0 81.0 81.0 82.0 149.0 81.0 81.0 143.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:59:03 INFO - PROCESS | 23292 | Values: 117.0 116.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 22:59:03 INFO - PROCESS | 23292 | Values: 146.0 146.0 145.0 145.0 147.0 147.0 146.0 145.0 147.0 145.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:59:03 INFO - PROCESS | 23292 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:2.36 (4.4%) stddev-sans-first:2.24 22:59:03 INFO - PROCESS | 23292 | Values: 50.0 52.0 52.0 53.0 53.0 59.0 54.0 52.0 52.0 53.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:59:03 INFO - PROCESS | 23292 | Values: 62.0 62.0 62.0 62.0 62.0 61.0 61.0 62.0 62.0 62.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:3.27 (4.1%) stddev-sans-first:3.41 22:59:03 INFO - PROCESS | 23292 | Values: 79.0 79.0 79.0 88.0 85.0 80.0 81.0 80.0 78.0 78.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:128.50 stddev:15.00 (11.7%) stddev-sans-first:15.69 22:59:03 INFO - PROCESS | 23292 | Values: 125.0 127.0 132.0 125.0 132.0 129.0 124.0 125.0 174.0 128.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 22:59:03 INFO - PROCESS | 23292 | Values: 164.0 167.0 161.0 166.0 163.0 164.0 160.0 161.0 164.0 165.0 22:59:03 INFO - PROCESS | 23292 | 22:59:03 INFO - PROCESS | 23292 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.00 stddev:2.37 (3.6%) stddev-sans-first:2.51 22:59:03 INFO - PROCESS | 23292 | Values: 65.0 69.0 65.0 68.0 64.0 69.0 64.0 64.0 63.0 63.0 22:59:03 INFO - PROCESS | 23292 | -------- Summary: end -------- 22:59:03 INFO - PROCESS | 23292 | 22:59:04 INFO - TEST-INFO | 23292: exit 0 22:59:04 INFO - TEST-OK | kraken | took 61558ms 22:59:04 INFO - SUITE-END | took 1177s 22:59:05 INFO - Completed test suite (00:19:37) 22:59:05 INFO - javascript benchmark 22:59:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2962.3779006408654, 2974.4645963726525, 3030.8939187128453, 3057.8012429192113, 3083.15213316338, 5046.54581087702, 5140.074786538422, 5155.716435596046, 5207.739613019348, 5234.55506281966, 5127.692338459695, 5130.512601302879, 5173.767180476878, 5178.870528236786, 5211.713355765441, 4607.055553611518, 4611.123886461578, 4760.2383618621025, 4797.424309082915, 4848.418189817209, 4743.146233677937, 4771.498992605779, 4784.1866882629865, 4792.83225087122, 4831.396075490564, 4605.30920361945, 4650.116477869194, 4664.626829853638, 4678.649101317398, 4716.08036432894, 4684.812607495696, 4699.154152252593, 4703.835365762182, 4708.57622813945, 4712.552307530779, 4660.883477913079, 4669.603083737887, 4672.462666793294, 4672.883177920545, 4679.134360143385, 5030.144875371196, 5097.413797413308, 5125.38495380553, 5184.429712731597, 5188.45521220274, 5429.53848922842, 5438.564914806806, 5442.102053161224, 5498.340199176085, 5505.779768809243, 5498.367687715924, 5528.75120619573, 5539.8892022159735, 5548.889022219573, 5554.777808887639, 5106.4893510648635, 5144.511271429208, 5186.92219616706, 5201.817936372209, 5233.398159211702, 5648.181013752983, 5653.265075540236, 5659.207710920475, 5674.886502269931, 5684.346300175533, 5423.538999185034, 5433.0, 5434.266374039454, 5436.048691478975, 5440.0, 5356.4107948125675, 5423.674579525241, 5472.370677372153, 5473.06957817172, 5527.53015993645, 5449.264349312872, 5451.209574611738, 5455.727213639302, 5458.808941687022, 5461.399246082928, 5468.179773034077, 5471.261379713687, 5476.205950137208, 5480.6437581557075, 5546.861328466821, 4209.6000879916355, 4240.1731662326165, 4241.724287921275, 4275.337322714984, 4283.443152390231, 3826.7321287510163, 3846.11539345952, 3857.0, 3885.592012838668, 3887.552931412867, 5204.0632686116105, 5206.167013277857, 5212.40057393397, 5214.061468935628, 5214.243934629458, 5375.81184658535, 5394.811181608703, 5414.29614150158, 5423.105187644073, 5502.8349149525575, 5154.8969020620125, 5156.355455568054, 5159.484051594811, 5160.0969830279555, 5214.504622060974, 4650.069986002813, 4745.715257084586, 4756.6432517561325, 4759.095771803346, 4762.119007983534, 5570.97214513933, 5618.353889302701, 5628.352739435018, 5650.519705824887, 5718.28521434829, 5445.972770136124, 5502.257195278747, 5506.91739623914, 5508.228847961369, 5512.80705175333, 5561.916571251515, 5627.324721033502, 5635.432984444793, 5644.125160600113, 5647.068233741386, 5287.9471205287455, 5348.117560602457, 5361.597880158925, 5364.275822763877, 5424.674519528841, 5409.053415652324, 5428.240046393429, 5433.673979561239, 5435.755391007335, 5442.12925931849, 5245.108331583564, 5294.205869119663, 5319.973400133129, 5325.813596524103, 5363.2759577456545, 5308.867278318073, 5334.573234141337, 5336.759845807026, 5344.118220493575, 5416.729163541886, 5290.312259406252, 5291.365036195682, 5295.232191332158, 5296.390915044819, 5297.390800058042, 5281.498257665516, 5365.275687782099, 5375.086235339923, 5377.973110134424, 5379.731013449234, 5287.814926477555, 5329.280547126088, 5350.30446042012, 5368.275282836923, 5427.402985671494], "value": 5071.416653634905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.2678085441062, 614.2199406753423, 627.9843003924901, 633.4805459523192, 664.7042066280501, 42485.36271955919, 44022.7798861006, 44063.3390499142, 44123.0, 47046.76476617621, 44624.107517849705, 44977.325340119925, 45153.77423112888, 45305.09389812202, 45319.77340113303, 8847.911520884789, 8994.910050899472, 9003.504807235633, 9003.909960900406, 9013.188813006827, 8510.276626486757, 8597.828043439127, 8598.140185981383, 8601.311895048413, 8605.053444121142, 8938.910610893872, 8978.775530611721, 8979.640814367418, 8980.236679882219, 8982.86525702115, 8928.107189281085, 8937.821243575157, 8960.596773145158, 8963.775905602315, 8966.820663586757, 8812.030676625565, 8878.467291962439, 8881.511516866569, 8884.11158884113, 8894.11058894112, 42259.1548169038, 42621.57378426207, 42716.0, 42765.0, 42783.35824962628, 2889.7688184945155, 3019.9396012079637, 3063.433264846011, 3102.519109538025, 3710.684591809699, 31430.528542071886, 31791.84104079488, 32014.83992580022, 32442.513362299585, 32801.50797738036, 3712.7401081924277, 3725.8882233532768, 3749.250149970017, 3789.5073640426845, 3800.1829606634706, 328970.0, 331552.0, 332310.0, 332358.0, 337653.0, 58284.41715582875, 59119.70440147772, 59220.04429180615, 59223.0, 59279.40720592825, 36255.4561681575, 36944.63055369466, 37068.44397334042, 38563.0, 38715.612843871764, 16436.342546298252, 16615.50153495397, 16652.417165399154, 16655.91672041632, 16663.750043749355, 13620.591382258548, 13771.380287887068, 13786.379612917443, 13786.586402407944, 13795.10331828428, 19242.979487153927, 19243.711344329848, 19247.711284330748, 19320.613587728167, 19326.323578674677, 6897.7585784497305, 6946.895796563055, 6987.5807451552055, 7134.714611415589, 7146.499745017799, 919.8850143732034, 977.4868194198103, 1007.6120693532896, 1041.2607048995299, 1111.877004225738, 31168.844155779076, 31635.683643163735, 31645.84177079146, 31855.84072079625, 32031.679683203336, 939.3424602778083, 1048.0253910227448, 1073.672529878379, 1122.3826895207603, 1123.0959077942334, 15357.769633455508, 15420.228988550527, 15484.148371839658, 15511.767323490156, 15827.683446331239, 544650.0, 548587.0, 548596.0, 548874.0, 548955.0, 55573.72213138989, 56378.71810641002, 56479.71760141173, 56530.43469565252, 56614.71692641593, 1223.9449224784905, 1317.3742472325569, 1344.2942455210896, 1346.1115663661935, 1358.9932050339685, 29969.70030299669, 32130.0, 32264.35471290608, 32366.838165809022, 32572.51141232836, 40999.7950010248, 41492.0, 41517.584824151374, 41573.58426415697, 41633.16733665249, 309503.0, 310725.0, 311207.0, 311214.0, 311244.0, 25845.483090338465, 28273.43453130967, 29094.0, 29108.56337154902, 29634.25914352072, 47374.28938565855, 47864.10242310119, 48061.75969120272, 48206.0, 48637.75681121572, 39624.60375396209, 39682.40476392799, 39859.80420587387, 40107.799461002505, 41166.38250426186, 41576.376354355314, 41784.3732344009, 42040.069581909534, 42120.78939605282, 42447.15105697807, 39777.0, 40263.396049058705, 40718.796406017784, 40721.592784072964, 40890.1821963565, 33041.834790825895, 35940.4608930861, 36765.0, 37275.25449491049, 37732.62267377401, 28293.292667683472, 28296.71703283023, 28495.572566411935, 28727.281817954718, 28818.567721483774], "value": 19361.789945834913, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.99270014599693, 375.99248015039683, 395.8199019446152, 397.703710735502, 421.79753718215255, 16353.591160220985, 18869.056547172622, 19077.71383429248, 19616.60766784667, 19927.501812454695, 17479.0, 17528.91235543824, 17942.19260133294, 17966.281348746066, 18100.0, 5497.642653227547, 5524.14375771756, 5736.4550367715, 5739.167820666022, 5740.794433169019, 5576.33084029917, 5636.802711905084, 5670.0927851543765, 5671.06427439474, 5690.9146362804595, 5562.3047119110115, 5625.662460252398, 5670.461306175906, 5674.375818659944, 5679.176519404684, 5451.236826844245, 5510.0, 5543.639663421905, 5548.500634942854, 5575.972120139373, 4995.350604421438, 5011.323471331397, 5015.22264049073, 6690.899636505456, 7031.472639552054, 24209.878950605133, 25860.0, 26483.73516264851, 26796.062137825083, 26812.46375072507, 2067.9483012924647, 2113.3905813061324, 2157.4822042709793, 2214.1807531213553, 2215.8227341812617, 13210.0, 13987.230702311368, 14205.431782728678, 14501.347439365254, 14570.4900328488, 1656.7183578791635, 1702.174445393983, 1704.2245778170902, 1749.80752117267, 1767.3284152022277, 91538.0, 100989.0, 101079.0, 103024.0, 105541.47229263805, 17128.91435542839, 17556.38552650651, 17591.38430154964, 17622.73565896487, 18070.457886263433, 31740.206494837814, 31860.36279274448, 31913.25561398924, 31957.20106997344, 32063.679363206535, 9317.906820931705, 9442.669506567237, 9623.663171788954, 9735.221182305399, 9765.218782497383, 8368.49789012649, 8478.364122690822, 8669.436486628349, 8841.646334146692, 8967.506787126771, 8481.27909127731, 8777.0, 8856.202941735275, 8895.866562001574, 8971.551422428984, 3139.450596145711, 3428.9142771430916, 3442.5868895732674, 3537.327907697529, 3552.8223588820456, 885.2121611765534, 929.3169520402499, 932.7714709896119, 946.5267366316841, 962.9181519570775, 18229.63540729178, 19468.123934422983, 19502.90248548748, 19534.90232548828, 19898.303559375352, 947.9383840050375, 971.3297824501071, 973.1339108193713, 996.1632228927735, 1011.4234886114843, 10090.848637270446, 10744.14046876248, 10784.676459706221, 10794.892051079545, 10885.673429797118, 477584.0, 481991.0, 496002.0, 496616.0, 496715.0, 91218.54390728004, 93273.5336323314, 93723.0, 95706.52146739222, 96757.5162124199, 944.471096186138, 975.7902051059057, 985.8028394321165, 996.1283876607823, 996.7807082441851, 16229.67540649204, 16701.916490417956, 17129.571760706083, 17561.473155805346, 17722.02528860925, 42016.78991605145, 42077.36883946682, 42298.577014229464, 42584.0, 42765.5723442774, 57378.42621573731, 58122.7093864528, 58322.708386457794, 58327.4167258322, 59957.40042599518, 7077.478342156461, 7909.604519774103, 8215.548144852091, 8363.958180209303, 8517.190866867637, 10816.513256903301, 12928.612141635766, 13198.274094924871, 13263.0, 13413.731725365633, 10829.51267192994, 11177.273477223955, 11311.321320720783, 11325.660230193107, 11666.533338666359, 9885.406875587487, 11364.54541818318, 11570.0, 11600.825987610024, 11714.23857449263, 11318.886811132114, 11448.427578621202, 11489.253198542068, 11726.765464690488, 11815.113866459878, 6819.283975182733, 7282.198958114709, 8026.7591972240925, 8029.317508011651, 8190.959045204736, 10246.743831404274, 10989.945050274697, 11392.658220253406, 11507.367094809866, 11778.175527712978], "value": 9884.632569018897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.6018183908393, 1211.0, 1306.510058727977, 1338.4713038349857, 1711.161530849883, 6850.1094857668495, 7169.390601798847, 7195.2085270620255, 7491.250874912506, 7501.0998680158455, 7259.564426134436, 7333.6333183340885, 7350.816229594263, 7353.632318384086, 7357.779266621997, 1870.5697689531416, 2090.5191805884656, 2133.359992002405, 2152.4403655049723, 2155.859869108511, 2108.8629239099487, 2121.0, 2127.95744085119, 2130.3928380411585, 2131.211451762842, 2093.602215579035, 2102.2431924507164, 2109.841761867858, 2147.5168087180414, 2153.353993801861, 2038.5311378382908, 2050.077465140685, 2057.6399130152313, 2111.641021026414, 2114.1015068595784, 2123.8938053097436, 2124.4051665533675, 2137.722096127509, 2141.100737690174, 2143.1534543855246, 7350.926490735078, 7454.776356709307, 7455.105387353498, 7456.888146677804, 7458.477906546544, 4616.930746038778, 4741.502142275081, 4741.762911854394, 4742.5731684148595, 4757.333973243749, 3053.442535300278, 3165.2878102427, 3206.8556914938886, 3216.6622504637144, 3243.529688195222, 1063.8723353197665, 1066.7386490309846, 1070.8500809886623, 1082.5020490574266, 1091.2633972068902, 10676.412797296145, 10692.625758098508, 10757.246992710514, 10773.24587278883, 10784.352938823695, 4043.110515686544, 4078.510578730512, 4216.093539888939, 4223.1975924574235, 4233.068724880522, 8600.440971336842, 8603.0, 8610.268127209258, 8614.0, 8620.482771033758, 4701.694389864716, 4703.905921881543, 4709.976450117728, 4716.481187069419, 4717.787699553528, 3891.105045839473, 4176.18564379943, 4273.9358909616385, 4297.72064815786, 4371.431713877176, 3370.0, 3472.3576138414473, 3532.964670353315, 3562.340966921128, 3578.355895938756, 930.851063829784, 931.189864817613, 947.6825263536792, 948.3645957208687, 948.8908775490769, 710.6304721544768, 718.3786025088261, 722.241646271413, 746.8244962433823, 754.8940801725389, 5097.0, 5221.817236396708, 5275.683458992473, 5357.5446087082255, 5509.090999985037, 424.4333814357805, 430.9181255561433, 434.15773399604666, 434.2574198121185, 438.82885674586936, 3608.8376023079027, 3613.7109031277437, 3638.9636103639154, 3663.8351274192723, 3745.793981331053, 9462.763430914281, 9739.756506087404, 9827.508624568743, 9847.704568862946, 9853.655122070693, 9687.660931867491, 9719.271054670786, 9815.11663950248, 9834.557444914995, 9837.262205334628, 6813.148356455443, 6942.479314051366, 7015.473839462061, 7038.753643622448, 7039.648017599202, 4910.8281210157475, 5130.12787826078, 5276.15581506957, 5296.814611488733, 5584.134459158837, 8770.91229087701, 8958.283337332867, 8992.055834137322, 9067.04795996411, 9100.0, 8828.337874659297, 8940.86588701157, 9238.722838314861, 9335.06649335061, 9341.019192984639, 6309.432151106423, 6593.439557637655, 6745.156855393076, 6832.0, 6975.407090397373, 7148.964255178691, 7192.352688258082, 7203.459740519586, 7203.71185152588, 7208.7116515340895, 6316.589421687576, 6495.610263384212, 6523.902141467787, 6556.967215163893, 6763.797086087426, 6525.771597994048, 6592.934070659232, 6619.735210591522, 6658.866822663616, 6716.966415167893, 6118.357572454828, 6218.81343559694, 6219.595726277777, 6219.782307619212, 6221.0, 4336.978315108404, 4573.702709323883, 4614.746188959505, 4616.884577885446, 4624.0, 4498.595126438636, 4597.0, 4602.792874320597, 4712.7643617818285, 4869.9026019480125], "value": 3989.385430657796, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.710358056266, 313.3873277742015, 316.1653235458389, 317.92210908327456, 323.52118864081166, 5115.207142892848, 5188.403333616635, 5345.984262990034, 5382.3541175059, 5400.594955378348, 5176.275321454999, 5197.48025197481, 5208.921866172011, 5211.166213405856, 5264.973675131629, 4140.378943158536, 4148.3777433385, 4148.668106551469, 4154.480689913761, 4158.6465150462245, 3946.5461471930666, 4092.9590704092875, 4161.313383291768, 4176.394422808691, 4218.83981904977, 4066.125782956664, 4077.8368865245357, 4078.0, 4079.8572049978256, 4085.428040074392, 4037.465763010067, 4052.844939192331, 4056.371262454324, 4059.0664147246152, 4072.0634254121424, 3551.485034669984, 3553.9338198540336, 3568.750187486877, 3576.499290099388, 3576.5886923003927, 4992.525710057539, 5037.596992240648, 5069.315642388267, 5089.51649593288, 5092.388913330424, 4551.0, 4559.726416414993, 4561.635069194456, 4568.834947088471, 4569.474510431276, 5371.167469042305, 5378.005069062236, 5379.0, 5379.390995367283, 5390.757415916293, 3752.1745216052423, 3806.143617686026, 3819.2361527694293, 3849.172427928009, 3864.478295430109, 5575.2473416088715, 5588.86027849303, 5593.776248950037, 5596.384397716289, 5597.80407685729, 3878.301905657009, 3909.081365879016, 3910.9413358799643, 3924.6860251179837, 3944.36890097583, 3869.496965394481, 3941.684665226775, 3957.782321972262, 3981.6018398159954, 3992.9800350998057, 4489.506154323022, 4498.0, 4511.255642818898, 4517.367568540407, 4554.681172317973, 5409.972950135224, 5412.431694672003, 5421.918671219935, 5482.205080263339, 5499.395066542677, 1309.2013871538354, 1336.8395792504962, 1358.7622166120889, 1358.870907263828, 1364.3723887012086, 929.0987741890356, 938.2728385501155, 951.167728237792, 957.9856302155473, 958.4919992404037, 5248.1340578804065, 5252.428348722829, 5252.711100889488, 5257.78968841242, 5261.421243663194, 5498.285222920995, 5517.862053448615, 5520.66875987442, 5530.446955304518, 5537.335519737658, 5060.468650791688, 5265.420803711589, 5265.919437129246, 5276.15581506957, 5333.466653334635, 2937.911862644124, 2938.5454200171052, 2951.4982452983045, 2955.6699485231734, 2967.4658561458714, 5713.885722285531, 5755.8561035974435, 5769.249997500298, 5777.942220577825, 5804.129380592861, 5579.6652200868075, 5716.371199168088, 5743.1385292206505, 5745.080787074048, 5757.251557297608, 5754.856128596735, 5762.3577280474865, 5790.118211581253, 5795.5226863881335, 5801.361850196475, 5358.46415358461, 5557.72211389437, 5581.469760372758, 5618.297712785902, 5628.183913332462, 5521.696306703009, 5545.085060965057, 5553.6390134641115, 5554.166874968625, 5560.610757246954, 5749.195112684144, 5756.07902735577, 5758.366579676149, 5773.97113014449, 5804.303483581997, 3785.602511736228, 3912.689249101583, 3929.3909444035075, 3932.3511620582285, 3959.1685745992513, 5304.469553044664, 5554.305711786027, 5554.583581186772, 5572.442755724395, 5593.916091258716, 5303.893922121459, 5426.2674538936735, 5427.701476418835, 5453.509184173444, 5466.781328746805, 5436.646617969819, 5442.727863606725, 5461.617686761889, 5490.3411590609385, 5494.725263736717, 5167.199084141964, 5392.299001129828, 5408.4321146280645, 5415.6479828811, 5422.322209723785, 5445.101558243004, 5451.509364157245, 5466.098093814476, 5482.259894914295, 5494.642848214853, 5356.384015838073, 5359.928014397214, 5368.677879327252, 5379.757910893941, 5392.622516423813], "value": 4145.412323864909, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.849844535757, 657.62186742623, 692.5913710910562, 711.3491155592633, 712.479889680533, 12663.430145643446, 13582.728345433085, 13594.18434893907, 13620.863791362084, 13666.38501267443, 12374.752504949896, 12469.127161098726, 12487.375631218449, 12489.625311240678, 12494.0, 646.2858541311854, 765.372394636397, 775.7982924439658, 783.1737516919648, 793.4921650143913, 793.305857374797, 802.638812534359, 802.8514724775906, 802.8795680647892, 803.562058678019, 861.315254372772, 861.3496809908542, 862.706679728892, 863.1325517854548, 863.1627321498137, 678.6267552845915, 816.6121092481084, 839.8698201778734, 848.0290067872282, 854.4275335525182, 613.4356392119261, 614.3395849461847, 614.8186285045922, 614.8985417406122, 615.1418770814734, 12570.120091593591, 12586.43361048755, 12596.055295852757, 12596.181248218836, 12628.68428289291, 11216.439178041066, 11438.256513326607, 11622.5471816023, 11717.648470545897, 12199.329036902967, 10714.892851071467, 10883.23817332787, 11012.063974562172, 11018.889811101946, 11058.170637202242, 3522.6653467920764, 3528.400171970771, 3537.6462353764673, 3600.5499312585926, 3621.5110960020324, 81712.59143704303, 83467.0, 85296.57351713264, 88254.0, 88389.55805220998, 8803.867941980876, 8923.464592124363, 9053.275737941081, 9053.502057386775, 9390.060993900692, 16537.090460024814, 16826.83173168277, 16830.83169168317, 16849.494515164566, 16859.409920652717, 7147.463940204506, 7200.783976480714, 7246.60143692102, 7448.255174482509, 7467.0, 3825.2923209206388, 3868.9226215475533, 3925.4111883217747, 3929.0, 3945.191235796655, 2812.5078111330845, 2889.8555072246304, 2893.594896714462, 2894.1751600793673, 2929.487339715541, 514.7271945868696, 517.5652451940388, 519.2807960974078, 522.874510117569, 527.6702061211743, 557.40636222423, 579.2816907035246, 593.1310629927091, 595.3897255313339, 646.2342124582385, 9015.14356136166, 9633.807323853483, 9712.854307185398, 9718.514074296258, 9940.40357578533, 582.592185470164, 666.6233578028398, 668.1781408867121, 674.7435974329724, 690.7133539581017, 4282.229198744256, 4623.0, 4655.631611698817, 4702.811887524461, 4716.551927566876, 126467.3676631611, 132232.33883830518, 132689.0, 133458.30729709004, 135325.3233733825, 110323.0, 111434.0, 112312.0, 113930.43034784772, 118037.4098129521, 78900.0, 80058.0, 95381.5230923841, 99791.0, 102029.0, 9139.268858491172, 9348.439093654402, 9516.666916657883, 9533.618655253713, 9646.758831029281, 48622.75688621676, 49191.50808491869, 49659.456328519955, 51703.0, 52725.0, 55033.724831375584, 55900.720496397254, 77638.61180694286, 79156.60421698085, 82554.0, 11840.28958262507, 11906.76186476283, 11985.64043078709, 12001.699957501132, 12202.0, 12971.286579238233, 13094.672633184247, 13210.735785284432, 14121.2233327168, 14169.929150354596, 12124.939375303067, 12474.937625311815, 12650.873491265338, 12932.482700692244, 13439.932800335935, 12021.158518903592, 12342.259464432163, 12464.314462704639, 12671.683207919876, 13148.671283217996, 11879.22785018971, 12265.938670306592, 12377.443015064164, 12640.55758048464, 13559.661008474472, 9884.85172722424, 10696.572137114428, 10771.946140269249, 10915.181361397768, 11126.666200014013, 10343.37939723619, 10507.0, 10552.78894422092, 10564.524596393334, 10580.576776929145], "value": 7002.959391631044, "unit": "score"}], "name": "dromaeo_css", "value": 6947.158417266033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 118.0, 112.0, 112.0, 111.0, 112.0, 112.0, 110.0, 131.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 144.0, 181.0, 120.0, 151.0, 147.0, 117.0, 187.0, 123.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 163.0, 162.0, 161.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 181.0, 75.0, 76.0, 74.0, 75.0, 171.0, 75.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 151.0, 81.0, 81.0, 82.0, 149.0, 81.0, 81.0, 143.0], "value": 101.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 147.0, 147.0, 146.0, 145.0, 147.0, 145.0], "value": 145.9, "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, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 52.0, 53.0, 53.0, 59.0, 54.0, 52.0, 52.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 88.0, 85.0, 80.0, 81.0, 80.0, 78.0, 78.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 132.0, 125.0, 132.0, 129.0, 124.0, 125.0, 174.0, 128.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 161.0, 166.0, 163.0, 164.0, 160.0, 161.0, 164.0, 165.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 65.0, 68.0, 64.0, 69.0, 64.0, 64.0, 63.0, 63.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1529.1, "alertThreshold": 2.0}]} 22:59:05 INFO - Return code: 0 22:59:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:59:05 INFO - # TBPL SUCCESS # 22:59:05 INFO - Running post-action listener: _resource_record_post_action 22:59:05 INFO - [mozharness: 2016-09-02 05:59:05.330474Z] Finished run-tests step (success) 22:59:05 INFO - Running post-run listener: _resource_record_post_run 22:59:05 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 414842880; Read time: 12; Write time: 380736 22:59:05 INFO - TinderboxPrint: CPU usage
13.0% 22:59:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:59:05 INFO - TinderboxPrint: I/O write bytes / time
414,842,880 / 380,736 22:59:05 INFO - TinderboxPrint: CPU idle
8,292.8 (87.0%) 22:59:05 INFO - TinderboxPrint: CPU user
1,190.3 (12.5%) 22:59:05 INFO - TinderboxPrint: Swap in / out
0 / 0 22:59:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 125927424; Read time: 0; Write time: 136868 22:59:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288915456; Read time: 0; Write time: 243868 22:59:05 INFO - Running post-run listener: _upload_blobber_files 22:59:05 INFO - Blob upload gear active. 22:59:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:59:05 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 22:59:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:59:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:59:06 INFO - (blobuploader) - INFO - Open directory for files ... 22:59:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:59:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:59:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:59:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:59:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:59:07 INFO - (blobuploader) - INFO - Done attempting. 22:59:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:59:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:59:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:59:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:59:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:59:08 INFO - (blobuploader) - INFO - Done attempting. 22:59:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:59:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:59:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:59:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:59:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:59:12 INFO - (blobuploader) - INFO - Done attempting. 22:59:12 INFO - (blobuploader) - INFO - Iteration through files over. 22:59:12 INFO - Return code: 0 22:59:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:59:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:59:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43bbb16509c19f0daeace593341431100e2ee159139aa91327bda6eca6de4a835dc1206721205fdf9d3b1f974ee4f40359bf0a20f6fec947c8ccb8ef2f6942ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73cdae287f6c1e341a06fac9828e27d27124c16b2d1f828729152f62010a78a4659a50644f40c26c9abd8db5ad0ded76e0f4a83f1277624ce1657591013f3880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3eb74fa8b245668d4cab9bbb54060c35cba8a7826a8889313ca33c42e7467ac8fbf42069b6aa8c4cf790435b900f81fcf319b8a1f432c028c6cd5247f4fc3979"} 22:59:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:59:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:59:12 INFO - Contents: 22:59:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43bbb16509c19f0daeace593341431100e2ee159139aa91327bda6eca6de4a835dc1206721205fdf9d3b1f974ee4f40359bf0a20f6fec947c8ccb8ef2f6942ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73cdae287f6c1e341a06fac9828e27d27124c16b2d1f828729152f62010a78a4659a50644f40c26c9abd8db5ad0ded76e0f4a83f1277624ce1657591013f3880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3eb74fa8b245668d4cab9bbb54060c35cba8a7826a8889313ca33c42e7467ac8fbf42069b6aa8c4cf790435b900f81fcf319b8a1f432c028c6cd5247f4fc3979"} 22:59:12 INFO - Running post-run listener: copy_logs_to_upload_dir 22:59:12 INFO - Copying logs to upload dir... 22:59:12 INFO - mkdir: /builds/slave/test/build/upload/logs 22:59:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.989735 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 22:59:12.214636) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 22:59:12.218415) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43bbb16509c19f0daeace593341431100e2ee159139aa91327bda6eca6de4a835dc1206721205fdf9d3b1f974ee4f40359bf0a20f6fec947c8ccb8ef2f6942ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73cdae287f6c1e341a06fac9828e27d27124c16b2d1f828729152f62010a78a4659a50644f40c26c9abd8db5ad0ded76e0f4a83f1277624ce1657591013f3880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3eb74fa8b245668d4cab9bbb54060c35cba8a7826a8889313ca33c42e7467ac8fbf42069b6aa8c4cf790435b900f81fcf319b8a1f432c028c6cd5247f4fc3979"} build_url:https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/QsNsJKyYR12XjnTZAIg9PQ/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/Firefox/sha512/43bbb16509c19f0daeace593341431100e2ee159139aa91327bda6eca6de4a835dc1206721205fdf9d3b1f974ee4f40359bf0a20f6fec947c8ccb8ef2f6942ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73cdae287f6c1e341a06fac9828e27d27124c16b2d1f828729152f62010a78a4659a50644f40c26c9abd8db5ad0ded76e0f4a83f1277624ce1657591013f3880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3eb74fa8b245668d4cab9bbb54060c35cba8a7826a8889313ca33c42e7467ac8fbf42069b6aa8c4cf790435b900f81fcf319b8a1f432c028c6cd5247f4fc3979"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 22:59:12.242920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:59:12.243220) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472794711.784077-2141526918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004732 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:59:12.325885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 22:59:12.326205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 22:59:12.326511) ========= ========= Total master_lag: 0.17 =========