builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1469119505.85 results: success (0) buildid: 20160721072521 builduid: 0bf7303198af461aba5565ba2dd022dc revision: e28e856b987380f55d699092f11f6997378f79a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:05.854322) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:05.854938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:05.855305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:05.896034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:05.896700) ========= 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=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 09:45:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-07-21 09:45:06 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.205812 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:06.364311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:06.364634) ========= 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=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019296 ========= master_lag: 0.52 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:06.902553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 09:45:06.902992) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e28e856b987380f55d699092f11f6997378f79a6 --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 e28e856b987380f55d699092f11f6997378f79a6 --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=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 09:45:06,987 truncating revision to first 12 chars 2016-07-21 09:45:06,988 Setting DEBUG logging. 2016-07-21 09:45:06,988 attempt 1/10 2016-07-21 09:45:06,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e28e856b9873?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 09:45:07,626 unpacking tar archive at: mozilla-central-e28e856b9873/testing/mozharness/ program finished with exit code 0 elapsedTime=0.997803 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 09:45:07.915445) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:07.915787) ========= script_repo_revision: e28e856b987380f55d699092f11f6997378f79a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:07.916197) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:07.916509) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:45:07.926526) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-21 09:45:07.926955) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:45:08 INFO - MultiFileLogger online at 20160721 09:45:08 in /builds/slave/test 09:45:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:45:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:45:08 INFO - {'append_to_log': False, 09:45:08 INFO - 'base_work_dir': '/builds/slave/test', 09:45:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:45:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:45:08 INFO - 'branch': 'Firefox-Non-PGO', 09:45:08 INFO - 'buildbot_json_path': 'buildprops.json', 09:45:08 INFO - 'config_files': ('talos/linux_config.py',), 09:45:08 INFO - 'default_actions': ('clobber', 09:45:08 INFO - 'read-buildbot-config', 09:45:08 INFO - 'download-and-extract', 09:45:08 INFO - 'populate-webroot', 09:45:08 INFO - 'create-virtualenv', 09:45:08 INFO - 'install', 09:45:08 INFO - 'run-tests'), 09:45:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:45:08 INFO - 'download_minidump_stackwalk': True, 09:45:08 INFO - 'download_symbols': 'ondemand', 09:45:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:45:08 INFO - 'tooltool.py': '/tools/tooltool.py', 09:45:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:45:08 INFO - '/tools/misc-python/virtualenv.py')}, 09:45:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:45:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:45:08 INFO - 'installer_path': 'installer.exe', 09:45:08 INFO - 'log_level': 'info', 09:45:08 INFO - 'log_name': 'talos', 09:45:08 INFO - 'log_to_console': True, 09:45:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:45:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:45:08 INFO - 'opt_config_files': (), 09:45:08 INFO - 'pip_index': False, 09:45:08 INFO - 'sps_profile': False, 09:45:08 INFO - 'sps_profile_interval': 0, 09:45:08 INFO - 'suite': 'dromaeojs', 09:45:08 INFO - 'system_bits': '32', 09:45:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:45:08 INFO - 'title': 'talos-linux64-ix-002', 09:45:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:45:08 INFO - 'use_talos_json': True, 09:45:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:45:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:45:08 INFO - 'work_dir': 'build'} 09:45:08 INFO - [mozharness: 2016-07-21 16:45:08.193755Z] Running clobber step. 09:45:08 INFO - Running pre-action listener: _resource_record_pre_action 09:45:08 INFO - Running main action method: clobber 09:45:08 INFO - rmtree: /builds/slave/test/build 09:45:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:45:08 INFO - Running post-action listener: _resource_record_post_action 09:45:08 INFO - [mozharness: 2016-07-21 16:45:08.917891Z] Finished clobber step (success) 09:45:08 INFO - [mozharness: 2016-07-21 16:45:08.917992Z] Running read-buildbot-config step. 09:45:08 INFO - Running pre-action listener: _resource_record_pre_action 09:45:08 INFO - Running main action method: read_buildbot_config 09:45:08 INFO - Using buildbot properties: 09:45:08 INFO - { 09:45:08 INFO - "project": "", 09:45:08 INFO - "product": "firefox", 09:45:08 INFO - "script_repo_revision": "production", 09:45:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:45:08 INFO - "repository": "", 09:45:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:45:08 INFO - "buildid": "20160721072521", 09:45:08 INFO - "pgo_build": "False", 09:45:08 INFO - "basedir": "/builds/slave/test", 09:45:08 INFO - "buildnumber": 160, 09:45:08 INFO - "slavename": "talos-linux64-ix-002", 09:45:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:45:08 INFO - "platform": "ubuntu64_hw", 09:45:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:45:08 INFO - "branch": "mozilla-central", 09:45:08 INFO - "repo_path": "mozilla-central", 09:45:08 INFO - "revision": "e28e856b987380f55d699092f11f6997378f79a6", 09:45:08 INFO - "stage_platform": "linux64", 09:45:08 INFO - "builduid": "0bf7303198af461aba5565ba2dd022dc", 09:45:08 INFO - "slavebuilddir": "test" 09:45:08 INFO - } 09:45:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:45:08 INFO - Running post-action listener: _resource_record_post_action 09:45:08 INFO - [mozharness: 2016-07-21 16:45:08.920397Z] Finished read-buildbot-config step (success) 09:45:08 INFO - [mozharness: 2016-07-21 16:45:08.920485Z] Running download-and-extract step. 09:45:08 INFO - Running pre-action listener: _resource_record_pre_action 09:45:08 INFO - Running main action method: download_and_extract 09:45:08 INFO - mkdir: /builds/slave/test/build/tests 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:08 INFO - https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:45:10 INFO - Downloaded 1361 bytes. 09:45:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:45:10 INFO - Using the following test package requirements: 09:45:10 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:45:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:45:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:45:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'jsshell-linux-x86_64.zip'], 09:45:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:45:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:45:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:45:10 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:45:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:45:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:45:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:45:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:10 INFO - https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:45:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:45:18 INFO - Downloaded 22729933 bytes. 09:45:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:45:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:45:19 INFO - Return code: 0 09:45:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:19 INFO - https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:45:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:45:20 INFO - Downloaded 22729933 bytes. 09:45:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:45:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:45:22 INFO - Return code: 0 09:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:22 INFO - https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:45:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:45:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:45:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:45:23 INFO - Downloaded 14403317 bytes. 09:45:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:45:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:45:23 INFO - Return code: 0 09:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:23 INFO - https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:45:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:45:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:45:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:45:25 INFO - Downloaded 57149487 bytes. 09:45:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:45:25 INFO - mkdir: /builds/slave/test/properties 09:45:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:45:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:45:25 INFO - Contents: 09:45:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:45:25 INFO - Running post-action listener: _resource_record_post_action 09:45:25 INFO - Running post-action listener: set_extra_try_arguments 09:45:25 INFO - [mozharness: 2016-07-21 16:45:25.862714Z] Finished download-and-extract step (success) 09:45:25 INFO - [mozharness: 2016-07-21 16:45:25.862955Z] Running populate-webroot step. 09:45:25 INFO - Running pre-action listener: _resource_record_pre_action 09:45:25 INFO - Running main action method: populate_webroot 09:45:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:45:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:45:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:45:25 INFO - '--tppagecycles', 09:45:25 INFO - '7'], 09:45:25 INFO - 'tests': ['tsvgm']}}, 09:45:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:45:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:45:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:45:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:45:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['damp', 'tps']}, 09:45:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'tests': ['damp', 'tps']}, 09:45:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['dromaeo_dom']}, 09:45:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:45:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['basic_compositor_video']}, 09:45:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:45:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'other-e10s': {'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['a11yr', 09:45:25 INFO - 'ts_paint', 09:45:25 INFO - 'tpaint', 09:45:25 INFO - 'sessionrestore', 09:45:25 INFO - 'sessionrestore_no_auto_restore', 09:45:25 INFO - 'tabpaint']}, 09:45:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['tsvgx', 09:45:25 INFO - 'tsvgr_opacity', 09:45:25 INFO - 'tart', 09:45:25 INFO - 'tscrollx', 09:45:25 INFO - 'cart']}, 09:45:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:45:25 INFO - 'tsvgr_opacity', 09:45:25 INFO - 'tart', 09:45:25 INFO - 'tscrollx', 09:45:25 INFO - 'cart']}, 09:45:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'talos_options': ['--disable-e10s'], 09:45:25 INFO - 'tests': ['tp5o']}, 09:45:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'tests': ['tp5o']}, 09:45:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'talos_options': ['--disable-e10s', 09:45:25 INFO - '--xperf_path', 09:45:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:25 INFO - 'tests': ['tp5n']}, 09:45:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:25 INFO - 'talos_options': ['--xperf_path', 09:45:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:25 INFO - 'tests': ['tp5n']}}, 09:45:25 INFO - 'talos.zip': {'path': '', 09:45:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:45:25 INFO - Running post-action listener: _resource_record_post_action 09:45:25 INFO - [mozharness: 2016-07-21 16:45:25.885331Z] Finished populate-webroot step (success) 09:45:25 INFO - [mozharness: 2016-07-21 16:45:25.885419Z] Running create-virtualenv step. 09:45:25 INFO - Running pre-action listener: _resource_record_pre_action 09:45:25 INFO - Running main action method: create_virtualenv 09:45:25 INFO - Creating virtualenv /builds/slave/test/build/venv 09:45:25 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 09:45:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:45:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:45:26 INFO - Using real prefix '/usr' 09:45:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:45:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:45:29 INFO - Installing pip.................done. 09:45:29 INFO - Return code: 0 09:45:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:29 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:29 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 09:45:29 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 09:45:29 INFO - Using env: {'DISPLAY': ':0', 09:45:29 INFO - 'HOME': '/home/cltbld', 09:45:29 INFO - 'LANG': 'en_US.UTF-8', 09:45:29 INFO - 'LANGUAGE': 'en_US:en', 09:45:29 INFO - 'LOGNAME': 'cltbld', 09:45:29 INFO - 'MAIL': '/var/mail/cltbld', 09:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:29 INFO - 'MOZ_NO_REMOTE': '1', 09:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:29 INFO - 'NO_EM_RESTART': '1', 09:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:29 INFO - 'PWD': '/builds/slave/test', 09:45:29 INFO - 'SHELL': '/bin/bash', 09:45:29 INFO - 'SHLVL': '1', 09:45:29 INFO - 'TERM': 'linux', 09:45:29 INFO - 'TMOUT': '86400', 09:45:29 INFO - 'USER': 'cltbld', 09:45:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:29 INFO - '_': '/tools/buildbot/bin/python'} 09:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:29 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 09:45:29 INFO - Cleaning up... 09:45:29 INFO - Return code: 0 09:45:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:29 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:29 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 09:45:29 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 09:45:29 INFO - Using env: {'DISPLAY': ':0', 09:45:29 INFO - 'HOME': '/home/cltbld', 09:45:29 INFO - 'LANG': 'en_US.UTF-8', 09:45:29 INFO - 'LANGUAGE': 'en_US:en', 09:45:29 INFO - 'LOGNAME': 'cltbld', 09:45:29 INFO - 'MAIL': '/var/mail/cltbld', 09:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:29 INFO - 'MOZ_NO_REMOTE': '1', 09:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:29 INFO - 'NO_EM_RESTART': '1', 09:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:29 INFO - 'PWD': '/builds/slave/test', 09:45:29 INFO - 'SHELL': '/bin/bash', 09:45:29 INFO - 'SHLVL': '1', 09:45:29 INFO - 'TERM': 'linux', 09:45:29 INFO - 'TMOUT': '86400', 09:45:29 INFO - 'USER': 'cltbld', 09:45:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:29 INFO - '_': '/tools/buildbot/bin/python'} 09:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:29 INFO - Downloading/unpacking psutil>=3.1.1 09:45:29 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 09:45:29 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 09:45:29 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 09:45:29 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 09:45:29 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 09:45:29 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 09:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:45:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:31 INFO - Installing collected packages: psutil 09:45:31 INFO - Running setup.py install for psutil 09:45:31 INFO - building 'psutil._psutil_linux' extension 09:45:31 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 09:45:33 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 09:45:33 INFO - building 'psutil._psutil_posix' extension 09:45:33 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 09:45:33 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 09:45:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:33 INFO - Successfully installed psutil 09:45:34 INFO - Cleaning up... 09:45:34 INFO - Return code: 0 09:45:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:34 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:34 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 09:45:34 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 09:45:34 INFO - Using env: {'DISPLAY': ':0', 09:45:34 INFO - 'HOME': '/home/cltbld', 09:45:34 INFO - 'LANG': 'en_US.UTF-8', 09:45:34 INFO - 'LANGUAGE': 'en_US:en', 09:45:34 INFO - 'LOGNAME': 'cltbld', 09:45:34 INFO - 'MAIL': '/var/mail/cltbld', 09:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:34 INFO - 'MOZ_NO_REMOTE': '1', 09:45:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:34 INFO - 'NO_EM_RESTART': '1', 09:45:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:34 INFO - 'PWD': '/builds/slave/test', 09:45:34 INFO - 'SHELL': '/bin/bash', 09:45:34 INFO - 'SHLVL': '1', 09:45:34 INFO - 'TERM': 'linux', 09:45:34 INFO - 'TMOUT': '86400', 09:45:34 INFO - 'USER': 'cltbld', 09:45:34 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:34 INFO - '_': '/tools/buildbot/bin/python'} 09:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:45:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:45:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:45:36 INFO - Installing collected packages: mozsystemmonitor 09:45:36 INFO - Running setup.py install for mozsystemmonitor 09:45:36 INFO - Successfully installed mozsystemmonitor 09:45:36 INFO - Cleaning up... 09:45:36 INFO - Return code: 0 09:45:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:36 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:36 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 09:45:36 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 09:45:36 INFO - Using env: {'DISPLAY': ':0', 09:45:36 INFO - 'HOME': '/home/cltbld', 09:45:36 INFO - 'LANG': 'en_US.UTF-8', 09:45:36 INFO - 'LANGUAGE': 'en_US:en', 09:45:36 INFO - 'LOGNAME': 'cltbld', 09:45:36 INFO - 'MAIL': '/var/mail/cltbld', 09:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:36 INFO - 'MOZ_NO_REMOTE': '1', 09:45:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:36 INFO - 'NO_EM_RESTART': '1', 09:45:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:36 INFO - 'PWD': '/builds/slave/test', 09:45:36 INFO - 'SHELL': '/bin/bash', 09:45:36 INFO - 'SHLVL': '1', 09:45:36 INFO - 'TERM': 'linux', 09:45:36 INFO - 'TMOUT': '86400', 09:45:36 INFO - 'USER': 'cltbld', 09:45:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:36 INFO - '_': '/tools/buildbot/bin/python'} 09:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:45:36 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 09:45:36 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 09:45:36 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 09:45:36 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 09:45:36 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 09:45:36 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 09:45:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:45:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:38 INFO - Downloading functools32-3.2.3-2.tar.gz 09:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:45:38 INFO - warning: no files found matching '*.txt' 09:45:38 INFO - no previously-included directories found matching 'build' 09:45:38 INFO - no previously-included directories found matching 'dist' 09:45:38 INFO - no previously-included directories found matching '.git*' 09:45:38 INFO - Installing collected packages: jsonschema, functools32 09:45:38 INFO - Running setup.py install for functools32 09:45:38 INFO - warning: no files found matching '*.txt' 09:45:38 INFO - no previously-included directories found matching 'build' 09:45:38 INFO - no previously-included directories found matching 'dist' 09:45:38 INFO - no previously-included directories found matching '.git*' 09:45:38 INFO - Successfully installed jsonschema functools32 09:45:38 INFO - Cleaning up... 09:45:38 INFO - Return code: 0 09:45:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:38 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:38 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 09:45:38 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 09:45:38 INFO - Using env: {'DISPLAY': ':0', 09:45:38 INFO - 'HOME': '/home/cltbld', 09:45:38 INFO - 'LANG': 'en_US.UTF-8', 09:45:38 INFO - 'LANGUAGE': 'en_US:en', 09:45:38 INFO - 'LOGNAME': 'cltbld', 09:45:38 INFO - 'MAIL': '/var/mail/cltbld', 09:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:38 INFO - 'MOZ_NO_REMOTE': '1', 09:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:38 INFO - 'NO_EM_RESTART': '1', 09:45:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:38 INFO - 'PWD': '/builds/slave/test', 09:45:38 INFO - 'SHELL': '/bin/bash', 09:45:38 INFO - 'SHLVL': '1', 09:45:38 INFO - 'TERM': 'linux', 09:45:38 INFO - 'TMOUT': '86400', 09:45:38 INFO - 'USER': 'cltbld', 09:45:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:38 INFO - '_': '/tools/buildbot/bin/python'} 09:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:45:39 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 09:45:39 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 09:45:39 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 09:45:39 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 09:45:39 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 09:45:39 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 09:45:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:45:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:45:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 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 09:45:41 INFO - Downloading docopt-0.6.1.tar.gz 09:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:45:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:45:41 INFO - Running setup.py install for blobuploader 09:45:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:45:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:45:41 INFO - Running setup.py install for requests 09:45:42 INFO - Running setup.py install for docopt 09:45:42 INFO - Successfully installed blobuploader requests docopt 09:45:42 INFO - Cleaning up... 09:45:42 INFO - Return code: 0 09:45:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:42 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:42 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 09:45:42 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 09:45:42 INFO - Using env: {'DISPLAY': ':0', 09:45:42 INFO - 'HOME': '/home/cltbld', 09:45:42 INFO - 'LANG': 'en_US.UTF-8', 09:45:42 INFO - 'LANGUAGE': 'en_US:en', 09:45:42 INFO - 'LOGNAME': 'cltbld', 09:45:42 INFO - 'MAIL': '/var/mail/cltbld', 09:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:42 INFO - 'MOZ_NO_REMOTE': '1', 09:45:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:42 INFO - 'NO_EM_RESTART': '1', 09:45:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:42 INFO - 'PWD': '/builds/slave/test', 09:45:42 INFO - 'SHELL': '/bin/bash', 09:45:42 INFO - 'SHLVL': '1', 09:45:42 INFO - 'TERM': 'linux', 09:45:42 INFO - 'TMOUT': '86400', 09:45:42 INFO - 'USER': 'cltbld', 09:45:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:42 INFO - '_': '/tools/buildbot/bin/python'} 09:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:42 INFO - Running setup.py (path:/tmp/pip-95t2nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:42 INFO - Running setup.py (path:/tmp/pip-mCSKxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:42 INFO - Running setup.py (path:/tmp/pip-K8JCBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:42 INFO - Running setup.py (path:/tmp/pip-uTIp8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:42 INFO - Running setup.py (path:/tmp/pip-WSd_JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:43 INFO - Running setup.py (path:/tmp/pip-YVLNJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:43 INFO - Running setup.py (path:/tmp/pip-LE9pCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:43 INFO - Running setup.py (path:/tmp/pip-6fyzQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:43 INFO - Running setup.py (path:/tmp/pip-CODNdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:43 INFO - Running setup.py (path:/tmp/pip-AOs1SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:43 INFO - Running setup.py (path:/tmp/pip-vQGL44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:43 INFO - Running setup.py (path:/tmp/pip-Ef18xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:43 INFO - Running setup.py (path:/tmp/pip-TPywXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:43 INFO - Running setup.py (path:/tmp/pip-jLqU9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:43 INFO - Running setup.py (path:/tmp/pip-xMdjmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:43 INFO - Running setup.py (path:/tmp/pip-tRBUrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:43 INFO - Running setup.py (path:/tmp/pip-VZAe5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:45:43 INFO - Running setup.py install for manifestparser 09:45:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:45:43 INFO - Running setup.py install for mozcrash 09:45:44 INFO - Running setup.py install for mozdebug 09:45:44 INFO - Running setup.py install for mozdevice 09:45:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Running setup.py install for mozfile 09:45:44 INFO - Running setup.py install for mozhttpd 09:45:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Running setup.py install for mozinfo 09:45:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Running setup.py install for mozInstall 09:45:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Running setup.py install for mozleak 09:45:44 INFO - Running setup.py install for mozlog 09:45:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:45:44 INFO - Running setup.py install for moznetwork 09:45:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Running setup.py install for mozprocess 09:45:45 INFO - Running setup.py install for mozprofile 09:45:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Running setup.py install for mozrunner 09:45:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Running setup.py install for mozscreenshot 09:45:45 INFO - Running setup.py install for moztest 09:45:45 INFO - Running setup.py install for mozversion 09:45:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:45:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:45:45 INFO - Cleaning up... 09:45:45 INFO - Return code: 0 09:45:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:45 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:45 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 09:45:45 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 09:45:45 INFO - Using env: {'DISPLAY': ':0', 09:45:45 INFO - 'HOME': '/home/cltbld', 09:45:45 INFO - 'LANG': 'en_US.UTF-8', 09:45:45 INFO - 'LANGUAGE': 'en_US:en', 09:45:45 INFO - 'LOGNAME': 'cltbld', 09:45:45 INFO - 'MAIL': '/var/mail/cltbld', 09:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:45 INFO - 'MOZ_NO_REMOTE': '1', 09:45:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:45 INFO - 'NO_EM_RESTART': '1', 09:45:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:45 INFO - 'PWD': '/builds/slave/test', 09:45:45 INFO - 'SHELL': '/bin/bash', 09:45:45 INFO - 'SHLVL': '1', 09:45:45 INFO - 'TERM': 'linux', 09:45:45 INFO - 'TMOUT': '86400', 09:45:45 INFO - 'USER': 'cltbld', 09:45:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:45 INFO - '_': '/tools/buildbot/bin/python'} 09:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:45 INFO - Running setup.py (path:/tmp/pip-Yx1p3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:45 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)) 09:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:45 INFO - Running setup.py (path:/tmp/pip-3LQRTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:46 INFO - Running setup.py (path:/tmp/pip-VbIXYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:46 INFO - Running setup.py (path:/tmp/pip-732jTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:46 INFO - Running setup.py (path:/tmp/pip-iY7bv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:46 INFO - Running setup.py (path:/tmp/pip-23q3hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:46 INFO - Running setup.py (path:/tmp/pip-Nl38y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:46 INFO - Running setup.py (path:/tmp/pip-NfjKTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:46 INFO - Running setup.py (path:/tmp/pip-4jH37G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:46 INFO - Running setup.py (path:/tmp/pip-p9BUQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:46 INFO - Running setup.py (path:/tmp/pip-mAjs5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:46 INFO - Running setup.py (path:/tmp/pip-e0SHxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:46 INFO - Running setup.py (path:/tmp/pip-rHtAAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:46 INFO - Running setup.py (path:/tmp/pip-EM8enF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:46 INFO - Running setup.py (path:/tmp/pip-fzCJ8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:46 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)) 09:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:46 INFO - Running setup.py (path:/tmp/pip-y5wbzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:47 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)) 09:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:47 INFO - Running setup.py (path:/tmp/pip-oSqIUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:47 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)) 09:45:47 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)) 09:45:47 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)) 09:45:47 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)) 09:45:47 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)) 09:45:47 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)) 09:45:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:47 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 09:45:47 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 09:45:47 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 09:45:47 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 09:45:47 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 09:45:47 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 09:45:49 INFO - Downloading blessings-1.6.tar.gz 09:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:45:49 INFO - Installing collected packages: blessings 09:45:49 INFO - Running setup.py install for blessings 09:45:49 INFO - Successfully installed blessings 09:45:49 INFO - Cleaning up... 09:45:49 INFO - Return code: 0 09:45:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:45:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:49 INFO - Reading from file tmpfile_stdout 09:45:49 INFO - Current package versions: 09:45:49 INFO - argparse == 1.2.1 09:45:49 INFO - blessings == 1.6 09:45:49 INFO - blobuploader == 1.2.4 09:45:49 INFO - docopt == 0.6.1 09:45:49 INFO - functools32 == 3.2.3-2 09:45:49 INFO - jsonschema == 2.5.1 09:45:49 INFO - manifestparser == 1.1 09:45:49 INFO - mozInstall == 1.12 09:45:49 INFO - mozcrash == 0.17 09:45:49 INFO - mozdebug == 0.1 09:45:49 INFO - mozdevice == 0.48 09:45:49 INFO - mozfile == 1.2 09:45:49 INFO - mozhttpd == 0.7 09:45:49 INFO - mozinfo == 0.9 09:45:49 INFO - mozleak == 0.1 09:45:49 INFO - mozlog == 3.3 09:45:49 INFO - moznetwork == 0.27 09:45:49 INFO - mozprocess == 0.23 09:45:49 INFO - mozprofile == 0.28 09:45:49 INFO - mozrunner == 6.12 09:45:49 INFO - mozscreenshot == 0.1 09:45:49 INFO - mozsystemmonitor == 0.3 09:45:49 INFO - moztest == 0.7 09:45:49 INFO - mozversion == 1.4 09:45:49 INFO - psutil == 3.1.1 09:45:49 INFO - requests == 1.2.3 09:45:49 INFO - wsgiref == 0.1.2 09:45:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:49 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:49 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 09:45:49 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 09:45:49 INFO - Using env: {'DISPLAY': ':0', 09:45:49 INFO - 'HOME': '/home/cltbld', 09:45:49 INFO - 'LANG': 'en_US.UTF-8', 09:45:49 INFO - 'LANGUAGE': 'en_US:en', 09:45:49 INFO - 'LOGNAME': 'cltbld', 09:45:49 INFO - 'MAIL': '/var/mail/cltbld', 09:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:49 INFO - 'MOZ_NO_REMOTE': '1', 09:45:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:49 INFO - 'NO_EM_RESTART': '1', 09:45:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:49 INFO - 'PWD': '/builds/slave/test', 09:45:49 INFO - 'SHELL': '/bin/bash', 09:45:49 INFO - 'SHLVL': '1', 09:45:49 INFO - 'TERM': 'linux', 09:45:49 INFO - 'TMOUT': '86400', 09:45:49 INFO - 'USER': 'cltbld', 09:45:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:49 INFO - '_': '/tools/buildbot/bin/python'} 09:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 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)) 09:45:49 INFO - Cleaning up... 09:45:49 INFO - Return code: 0 09:45:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:49 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 0x2a93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', '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 09:45:49 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 09:45:49 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 09:45:49 INFO - Using env: {'DISPLAY': ':0', 09:45:49 INFO - 'HOME': '/home/cltbld', 09:45:49 INFO - 'LANG': 'en_US.UTF-8', 09:45:49 INFO - 'LANGUAGE': 'en_US:en', 09:45:49 INFO - 'LOGNAME': 'cltbld', 09:45:49 INFO - 'MAIL': '/var/mail/cltbld', 09:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:49 INFO - 'MOZ_NO_REMOTE': '1', 09:45:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:49 INFO - 'NO_EM_RESTART': '1', 09:45:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:49 INFO - 'PWD': '/builds/slave/test', 09:45:49 INFO - 'SHELL': '/bin/bash', 09:45:49 INFO - 'SHLVL': '1', 09:45:49 INFO - 'TERM': 'linux', 09:45:49 INFO - 'TMOUT': '86400', 09:45:49 INFO - 'USER': 'cltbld', 09:45:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:49 INFO - '_': '/tools/buildbot/bin/python'} 09:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:45:49 INFO - Cleaning up... 09:45:49 INFO - Return code: 0 09:45:49 INFO - Running post-action listener: _resource_record_post_action 09:45:49 INFO - Running post-action listener: _start_resource_monitoring 09:45:49 INFO - Starting resource monitoring. 09:45:50 INFO - [mozharness: 2016-07-21 16:45:50.002785Z] Finished create-virtualenv step (success) 09:45:50 INFO - [mozharness: 2016-07-21 16:45:50.003771Z] Running install step. 09:45:50 INFO - Running pre-action listener: _resource_record_pre_action 09:45:50 INFO - Running main action method: install 09:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:50 INFO - Reading from file tmpfile_stdout 09:45:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:45:50 INFO - Reading from file tmpfile_stdout 09:45:50 INFO - Output received: 09:45:50 INFO - Usage: mozinstall [options] installer 09:45:50 INFO - Options: 09:45:50 INFO - -h, --help show this help message and exit 09:45:50 INFO - -d DEST, --destination=DEST 09:45:50 INFO - Directory to install application into. [default: 09:45:50 INFO - "/builds/slave/test"] 09:45:50 INFO - --app=APP Application being installed. [default: firefox] 09:45:50 INFO - mkdir: /builds/slave/test/build/application 09:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:46:06 INFO - Reading from file tmpfile_stdout 09:46:06 INFO - Output received: 09:46:06 INFO - /builds/slave/test/build/application/firefox/firefox 09:46:06 INFO - Running post-action listener: _resource_record_post_action 09:46:06 INFO - [mozharness: 2016-07-21 16:46:06.710710Z] Finished install step (success) 09:46:06 INFO - [mozharness: 2016-07-21 16:46:06.710988Z] Running run-tests step. 09:46:06 INFO - Running pre-action listener: _resource_record_pre_action 09:46:06 INFO - Running main action method: run_tests 09:46:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:46:06 INFO - Python 2.7.3 09:46:06 INFO - Return code: 0 09:46:06 INFO - grabbing minidump binary from tooltool 09:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:06 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 0x2d523b0>, '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 0x2d532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d53740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:46:06 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 09:46:06 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 09:46:06 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 09:46:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:46:07 INFO - Return code: 0 09:46:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:46:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:46:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:46:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:46:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 09:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 09:46:07 INFO - Using env: {'DISPLAY': ':0', 09:46:07 INFO - 'HOME': '/home/cltbld', 09:46:07 INFO - 'LANG': 'en_US.UTF-8', 09:46:07 INFO - 'LANGUAGE': 'en_US:en', 09:46:07 INFO - 'LOGNAME': 'cltbld', 09:46:07 INFO - 'MAIL': '/var/mail/cltbld', 09:46:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:46:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:07 INFO - 'MOZ_NO_REMOTE': '1', 09:46:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:46:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:46:07 INFO - 'NO_EM_RESTART': '1', 09:46:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:07 INFO - 'PWD': '/builds/slave/test', 09:46:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:46:07 INFO - 'SHELL': '/bin/bash', 09:46:07 INFO - 'SHLVL': '1', 09:46:07 INFO - 'TERM': 'linux', 09:46:07 INFO - 'TMOUT': '86400', 09:46:07 INFO - 'USER': 'cltbld', 09:46:07 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558', 09:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:07 INFO - '_': '/tools/buildbot/bin/python'} 09:46:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 09:46:07 INFO - mozversion application_buildid: 20160721072521 09:46:07 INFO - mozversion application_changeset: e28e856b987380f55d699092f11f6997378f79a6 09:46:07 INFO - mozversion application_display_name: Nightly 09:46:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:46:07 INFO - mozversion application_name: Firefox 09:46:07 INFO - mozversion application_remotingname: firefox 09:46:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:46:07 INFO - mozversion application_vendor: Mozilla 09:46:07 INFO - mozversion application_version: 50.0a1 09:46:07 INFO - mozversion platform_buildid: 20160721072521 09:46:07 INFO - mozversion platform_changeset: e28e856b987380f55d699092f11f6997378f79a6 09:46:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:46:07 INFO - mozversion platform_version: 50.0a1 09:46:07 INFO - using testdate: 1469119567 09:46:07 INFO - actual date: 1469119567 09:46:07 INFO - starting webserver on 'localhost:54042' 09:46:07 INFO - SUITE-START | Running 2 tests 09:46:07 INFO - TEST-START | dromaeo_css 09:46:07 INFO - operating with platform_type : linux_ 09:46:07 INFO - Initialising browser for dromaeo_css test... 09:46:07 INFO - TEST-INFO | started process 3306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsOvej1/profile http://localhost:54042/getInfo.html) 09:46:13 INFO - PROCESS | 3306 | __metrics Screen width/height:1600/1200 09:46:13 INFO - PROCESS | 3306 | colorDepth:24 09:46:13 INFO - PROCESS | 3306 | Browser inner width/height: 1024/697 09:46:13 INFO - PROCESS | 3306 | __metrics 09:46:14 INFO - PROCESS | 3306 | 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" 09:46:14 INFO - PROCESS | 3306 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:46:15 INFO - TEST-INFO | 3306: exit 0 09:46:15 INFO - Browser initialized. 09:46:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:46:15 INFO - TEST-INFO | started process 3391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsOvej1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:27 INFO - PROCESS | 3391 | RSS: Main: 158384128 09:46:27 INFO - PROCESS | 3391 | 09:47:18 INFO - PROCESS | 3391 | 1469119638673 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] 09:49:31 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54042/tests/dromaeo/cssquery-ext.html) 09:49:32 INFO - PROCESS | 3391 | RSS: Main: 270725120 09:49:32 INFO - PROCESS | 3391 | 09:52:35 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-ext.html (next: http://localhost:54042/tests/dromaeo/cssquery-jquery.html) 09:52:35 INFO - PROCESS | 3391 | RSS: Main: 247275520 09:52:35 INFO - PROCESS | 3391 | 09:55:38 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54042/tests/dromaeo/cssquery-mootools.html) 09:55:38 INFO - PROCESS | 3391 | RSS: Main: 230436864 09:55:38 INFO - PROCESS | 3391 | 09:58:40 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54042/tests/dromaeo/cssquery-prototype.html) 09:58:41 INFO - PROCESS | 3391 | RSS: Main: 192323584 09:58:41 INFO - PROCESS | 3391 | 10:01:44 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54042/tests/dromaeo/cssquery-yui.html) 10:01:44 INFO - PROCESS | 3391 | RSS: Main: 194310144 10:01:45 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:54042/tests/dromaeo/cssquery-yui.html (next: http://localhost:54042/tests/dromaeo/cssquery-dojo.html) 10:04:47 INFO - PROCESS | 3391 | RSS: Main: 281096192 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | __start_tp_report 10:04:47 INFO - PROCESS | 3391 | _x_x_mozilla_page_load 10:04:47 INFO - PROCESS | 3391 | _x_x_mozilla_page_load_details 10:04:47 INFO - PROCESS | 3391 | |i|pagename|runs| 10:04:47 INFO - PROCESS | 3391 | |0;dojo.html;2623.711391746908;2644.457886133343;2670.572708366661;2675.331167208198;2676.0901293560187;4648.163330600491;4687.064260388032;4697.013627138296;4759.09577180336;4802.279658051294;4584.404027476424;4636.791344389502;4650.372199753032;4708.293755936611;4744.4306683198065;4371.978140109295;4391.165678521087;4395.120975804836;4406.867793966187;4542.318652202163;4486.214912390335;4492.483364413085;4497.482789479219;4499.932501012488;4517.390152329443;4269.615734583871;4308.6983911126235;4312.116016216668;4375.453068366454;4383.561643835623;4353.62994145498;4356.194104090753;4356.520782713899;4358.782060896974;4363.694541382073;4094.406311084876;4282.764447955361;4337.262665346899;4338.479382474092;4350.912981740347;4687.2969054641735;4706.764661766898;4718.669693121483;4734.076855013273;4786.282057691339;5092.719900405476;5162.22566615007;5171.0692075426405;5172.508611681884;5173.430922598511;4987.975060124676;5029.924551131736;5194.844154675366;5200.947990520122;5209.218617207412;4717.410323709536;4753.83361582343;4756.500567440438;4799.904001919942;4804.2073057945745;5201.557867581298;5220.843374698765;5271.789128434896;5273.841784746463;5276.709780962007;4851.393575803025;4903.95096049035;4947.851564453073;5052.014857102829;5061.8734531637;5069.721165335942;5078;5098.46466121052;5100.081985242692;5104.770285337095;5005.6996580205305;5014.6991180529285;5018.673786203885;5021.949780502221;5072.797088116434;4856.17445034345;4955;5017.54842064216;5018.071656743514;5022.096022715946;4370.562943705604;4376.868693939187;4397.758123303249;4407.6914615976575;4408.691391602557;3783.05423644089;3799.069228039148;3800.201957588937;3834.0989867381145;3861.0347413146715;4234.06850492891;4278.743275403485;4283.057727299989;4343.913121737548;4344.804483798237;5067.949320506821;5087.567556757717;5107.61692873036;5152.92270615941;5162.92255616166;4327.675424343187;4328.567143285646;4330.458692663417;4331.7834108294455;4367.30123180296;4552.521985191574;4606.907861842745;4613.700109492872;4614.792334344963;4659.487456379796;4743.66794324394;4757.904841903073;4816.735079570657;4824.517548245148;4859.514048595112;5119.411267704113;5129.717865517432;5140.614453915897;5144.768485418091;5184.300119483973;4591.586757191869;4814.15752243363;4826.686265392738;4831.710097394168;4875.585575226004;4958.900821983612;5006.949930500794;5013.749312534432;5042.747862606781;5098.362704661918;5100.69395836251;5100.7449627517735;5104.515071068243;5106.617003724809;5123.590112790893;4849.75751212445;4951.876203094951;4971.154903666457;4984.077945580079;4987.8753031173055;4837.7581120943105;4929.2359684248995;4936.950630493649;4976.427710813158;4999.350084488993;4882.584980276716;4893.632977526771;4896.4858689838475;4946.950530494649;4949.059678661043;4807.4471435783935;4990.72551009698;5002.449730529566;5009.123403404316;5012.097822392005;4790.353302304145;4830.8792280193275;4835.6131509479865;4850.95149048505;4888.266066895828 10:04:47 INFO - PROCESS | 3391 | |1;ext.html;577.3620149734544;585.8769658371742;585.9774693160434;590.391896346763;591.8313280714995;27174.048908288212;37758;39226.41160382593;39310.410343844866;43783.12433751331;40974.7951260244;41285.58714412855;41331.793341033255;41350;41357;7751.651175697093;7887.921120788805;7890.447668663196;7891.8421631567335;7893.526388416684;7559.508631938934;9871.457069861155;10111.241656875727;10115.848262276071;10475.161987041018;10296.073353398193;10363.896361036406;10381.532831022621;10390.688279351594;10404.89595104047;7600.619969001527;10094.394336339807;10103.898961010444;10159.796804063952;10176.033276838725;9731.513424328827;10125.189984801198;10127.64553240633;10474.109700675452;10491.42297173655;38213;38556.421653675214;38648.806755966325;38675.41986870199;38718.22563548741;2869.03887197789;2908.476474234637;3017.2909366298904;3056.205386599478;3554.733394995386;29605.407891842257;29642.25894352637;29963.25091872699;30861.845690771628;30896.691033089606;3511.262634846685;3547.290541891606;3564.7682900611376;3592.2995015971896;3664.633536646341;258203;258838;260382.69808651024;260872;261126;52369;52516;52714;52752.47247527552;52755.47244527505;32312.35375292481;33651.663483365344;33761.49357759635;35529.64470355315;54980.450195498335;13475.517693053756;13567.457301707822;13575.796363054562;13580.117292375964;13593.32033398346;8747.343949203709;8917.375783695079;10128.240381971382;10139.340942838691;10155.238357123246;15952.361905523649;16032.599185020235;17069.402570910206;17926.462206133838;17942.820571794375;6731.562448440835;6972.79081627552;7021.473389495808;7100.183478899889;7143.8571228576175;792.2513225002433;943.9374965699967;959.5442164971582;999.2455695949511;1025.5231317437429;28537.857310713312;29047.56428653572;29381.559276610868;29665.555016674767;29844.55233171504;918.5991363171217;945.6879229854131;959.9616015359447;977.0766625538797;989.4904124375952;15023.877126361574;15322.15728134941;15370.846291537166;15452.227388630523;15552.300146493435;417649;421209;422960;423156;423437;49698.75150624296;50020;50366.74816625967;50407.24389134166;50451.495485045416;1160.7446361800392;1190.3572071081692;1205.2105870654734;1205.921615095016;1206.6380085974172;26450.338741530846;28247.858760706065;28307;28711.425771484035;28789.56815647809;43206.56793432026;43662.34506482342;43668.34497482604;43678.78160609177;43723.78138109289;345175;345588;346079;346469;347244;24938.62592061084;25083.74916250887;25456.872715636302;26318.342041449116;26352.472950541265;36317.45523817092;37029.44455833219;38610;40641.796791015855;43039.56960430356;36533.26933461369;36812.815935920145;37015.444768329035;37024.38467305813;37059.33233004467;37254.6274537262;37737.433938490394;37778.62221377861;37802;38846;36407.63592364149;36643.083922902144;37489.06277342979;37586.2482750338;37617.24765504729;31228.219294517818;32953;33275.667243327254;33747.325053499284;34022;25708.485830282916;25889;25940.351491212117;26241.475170496866;26412.4717505645 10:04:47 INFO - PROCESS | 3391 | |2;jquery.html;364.1606097944241;366.4722799169197;368.44181065685507;369.99075023124425;383.30621574949345;16091.75862362064;16926.91536542317;17592.648147037067;17695.29218831245;17700.91149544254;15472.458463953764;17517.386891458802;17692.82307176928;17847.55381115473;17869.838460500057;4935.358403407561;5166.793328266865;5179.404368497633;5193.272941788153;5195.090859099661;3606.459031145336;3715.5727091384433;3755.43668449732;5090.643654944155;5819.592628516006;5997.430244126801;6060.666663333537;6063.666498342589;6066.120412540179;6077.848053798646;5818.912716309259;5969.5821292509545;5990.191324171225;5997.550183736215;6006.309274433409;3430.3482338355884;4923.458419573844;5299.417064122943;5301.416844147141;5302.257683924254;20097.8995105025;21234.89382553093;21370.89314553433;21677.67483487749;21912.671309930363;1738.9130543472775;1760.375066851275;1837.0722784993493;2059.4542446251753;2317.895694693743;12987.675308117277;13208.6697832554;13463.798043029363;13752.518661846787;14049.64875878101;1755.157524388288;1764.6117854072083;1787.4548262779847;1801.3425099838464;1815.5733402650367;66353.66823165902;66860.3313966854;67458.66270668615;67511.32488675149;67640.66179669119;9642.132208101304;9662.903370966342;9919.156871665846;9958.402495850272;10000.699979000641;13420.731585368238;13436.731265374638;13437.656234376484;13442.798358024638;13444.663883402993;7004.278278346695;7149.642517874086;7305.03455083902;7377.188509263977;7393.9260607393235;6713.26154123046;6752.561083529555;6935.514513984073;6962.094927659473;7077.787666370017;6168.475679567197;6245.594036387621;6263.467605253604;6302.1177035215105;6351.396617321347;1666.2002238925384;1700.3623641134575;1724.3706047292899;1771.716525355937;1812.1935738596462;828.2131974624131;847.4068152293419;870.3994243971639;880.4629176202511;912.1699253679253;17251.05119218455;17402.65194696099;18210.726839097424;18377;18506.907465462857;855.7603870916154;860.3805260212637;930.3022732950287;969.8739163908647;976.7313988653035;10382;10418.11446027081;10467.476626168662;10572.947135264274;10595.788084238273;294222;299222;300807;398212;443357;81887.59056204681;82623;83591.91913135082;84035.5798221005;85923.5703821477;931.9720408387759;934.9111834375859;952.9761755956018;968.3608818180109;974.3325821812082;11870.406479675808;15072.24638768079;16354.918225408797;16694.833051669815;16819.66360672755;34576.82711586426;35172.47241291332;35805;36883.26233475262;39767.20465590614;49852.75073624609;49856.501434985184;52919;55052.174217387575;55880.161797573885;7682.769516914502;7688.308052275322;7708.961455192688;7801.180876008165;8029.277365037175;9856.704298871045;11991.321215029728;12079.396030198279;12392.938035309766;12768.489260429851;10423.009814067651;10734.14126869833;10891.727706807393;10931.562737490729;11268;9563.713088607354;10336.328138670962;10368.274220804471;10700.625478108228;10808.405537695502;10738.140948723927;10857.83713244286;10927.83608245861;11022.228444008842;11200.83198752003;7852.528848269122;7907.802304942423;8118.3099436548555;8161.673533058849;8369.246767790684;8271.090180080308;10049.547770350206;10255.692329229836;10615.734606634896;11010.064144547803 10:04:47 INFO - PROCESS | 3391 | |3;mootools.html;1378.510628726802;1416.5679467762334;1469.632591852037;1705.1474262868567;1730.1176400035986;7607.353374963128;8137.877931831027;8167.795805104875;8171.509709417439;8173.386995975304;7060.717571297142;7587.658555365008;7806.219378062191;7829.882551761728;8001.519908805476;2185.267935241696;2204.4268490192585;2207.8233741300737;2208.8895555222257;2213.9225127120553;2112.1234687604624;2130.4141361125735;2170.89145542723;2171.4788450771857;2184.9344519664355;1820.5266630676053;1879.9718004229946;1885.9057047147655;1889.6409682160345;2123.203798575534;2168.8915554222303;2203.9338819835352;2211.347652442533;2215.8227341812617;2216.4791274050585;2148.1085349579907;2150.8171805396405;2164.491344534033;2196.9560608787733;2197.791209835079;8131;8180.9181908181345;8184.345252379795;8185.3042491387705;8192.795180120485;4585.060062687175;4672.415948006499;4705.106029854317;4717.882052948669;4718.386609740712;2847.1316248544185;2961.7778666599875;3010.804297720641;3027.122134580969;3039.2249976256135;952.3524003677566;984.5175863826745;992.6724181020246;994.796066806303;998.0867506231789;10981.341119532774;10988.066014388867;11034.393108379036;11069.114470842313;11093.168012399103;3957.3668213086344;4089.2230476209425;4142.08874047709;4256.021115143534;4257.446531950826;9305;9336.299777516713;9338.112879276458;9338.206252468617;9340.206082483064;4643.164230438553;4648.48866624671;4649.04694537619;4652.348671186037;4653.302004699322;4154.937675934863;4234.851780187679;4242.830286788494;4246.278132717383;4246.44796176495;3621.873234436835;3653.2328211075974;3777.7544459610035;3785.8296376663116;3790.753601015925;1107.6566264458045;1159.5245949160803;1159.8608167019843;1168.7428765671539;1170.584442522909;712.4763298975249;743.1453828097731;817.0562999735315;823.2097186700835;826.5495305058757;5109.642325037212;5123.436421993578;5141.126008578551;5287.550558202519;5415.214793854869;411.8146833924746;418.4915327876644;422.0714428257784;424.7196850078933;425.79987405919167;3531.646835316448;3551.3074950384944;3565.6612621800887;3599.6220396857952;3616.150204701893;9511.619535218651;9718.416894986323;9853.802923941481;9875;9877.06167914047;9482.431054136774;9784.853227201598;9815.411075335503;9816.067473589997;9830.262730295257;7315.268473152642;7486.251374862471;7486.812829679193;7502.774916752397;7504.099508059068;4956.430010548833;5022.3219865317715;5067.695938243717;5102.821401250939;5125.461826508163;8733.956330218309;8889.733308000512;8997.685081022133;9000.234980026771;9068.274538037072;8964.68623598171;9176.770580735534;9185.357025008185;9229.030951750237;9298.907010929805;6740.764073257413;6750.32496750321;6765.458763299022;6823.761168359084;6825.317468253334;7378.926210738038;7597.240275972358;7604.277593628596;7606.011218541776;7606.315431611181;6659.866802663822;6755.3582409671;6809.455243580403;6841.315868413318;6937.687804048729;6600.60396376219;6692.196936367667;6723.058771771858;6765.154355705537;6767.932320676927;6261.624302541862;6348.174737284124;6348.936510634834;6352.745890164341;6354.3963323484195;4473.35136405226;4539.568740969603;4575.084983003346;4611.769411529343;4615.100055489282;3985.8405663773124;4065.7763822988836;4085.121698834646;4087.325591277406;4137.462129923211 10:04:47 INFO - PROCESS | 3391 | |4;prototype.html;301.6259837801127;302.93184033592445;309.61452991026175;313.1419909448113;314.565899059298;4878.487758785325;4940.752962351886;4945.406551213855;4962.230854217593;4967.230079337708;4877.5122487751205;4892.975535122328;4907.705537667743;4913.434954980178;4915.410150781902;3893.610638936105;3907.921841563167;3915.3539665955227;3918.5297764268184;3920.3531417316253;3706.499622550948;3862.2275544890995;3881.3983832505864;3931.3513270310364;3931.9213615727667;3850.114473671057;3852.614738526139;3864.3623802072625;3873.845046198163;3878.4182372644186;3829.5787463379006;3837.5394952605866;3840.8271627776817;3849.5188101487315;3866.5940076291868;3194.5847039884898;3292.506124081383;3301.422251106047;3360.512725654766;3443.1564266754554;4710.95289047112;4718.598919091882;4726.5746082852365;4734.431868175807;4756.952430475685;4377.299632058855;4386.780660966939;4391.187630288407;4404.1852257332175;4413.9117217655785;4777.641676874214;4872.8061624901775;5003.674761140551;5031.371078615173;5053.949460505385;3668.7982160981137;3669.43123815809;3675.9816200918826;3678.834452449646;3691.9815400922826;5308.814191503279;5310.893782124336;5331.306930099101;5344.973275133639;5346.492083252124;3712.888613341604;3762.2099359134886;3792.715546334036;3797.8480860765812;3808.143167787281;3845.673117784964;3848.365019771706;3849.8460061597784;3856.9807150964066;3858.3633700439113;4325.55094043499;4332.876890323719;4362.323839804835;4377.759223242752;4386.649068074546;4808.855734327975;5152.819651312261;5157.252198431206;5158.871028224325;5160.122779127557;1307.8495972963042;1338.9799153012711;1341.6042267530906;1346.6296768388574;1346.777781666014;872.254222639634;890.6882591093195;908.7364664247426;909.6907051602487;912.5574096563162;4109.239790638741;4124.154548317588;4136.524299705572;4137.91724165515;4141.440905477722;4621.445426548768;4653.464851542049;4665.883352916204;4674.672772905864;4702.106599746037;4102.856400026045;4104.332184080372;4110.568390318974;4127;4154.925355237163;2550.948981020369;2606.713261541229;2777.180731684158;2802.243394283573;2804.8878044877974;4758.714477131383;4875.07373599015;4882.36529251195;5009.44896061433;5016.874578135576;5388.2725832012175;5403.540699040625;5409.24270602116;5421.2139239810795;5422.674639521562;4740.312654665123;4883.4139903211135;4910.337104490919;4976.8755781105765;4978.327925730053;5218.947810521846;5227.529522343008;5236.240745092016;5258.9482103578675;5288.286081378965;5162.122439185272;5165.61257905651;5166.2508936204795;5174.922376164285;5214.679467636865;4827.806887724451;4955.752212389438;4970.801167953241;4984.931253529745;5015.64890457679;3602.7030269103625;3681.687056600219;3707.295613833363;3708.351038568294;3723.888283351504;5293.338332708411;5315.6279060465395;5341.652792568626;5352.705601191816;5353.705546194997;5037.672551284155;5127.692338459555;5209.426963033988;5217.713025783618;5222.6083043771105;5066.9239961401345;5070.8225212117395;5071.442141364373;5090.261912022662;5095.280445274896;5021.447640759587;5031.144705400017;5045.596352291735;5090;5107.10625640504;4969.881776600236;5066.44269130388;5080.771365288645;5153.639245252796;5165;4998.550130488274;5010.073136469765;5013.724245166551;5014.247862820606;5050.797968081236 10:04:47 INFO - PROCESS | 3391 | |5;yui.html;613.1507861611664;628.2617923939372;648.2480322825523;682.3381320119482;737.9815854121312;12519.12366134371;13521.864781352184;13627.454901803929;13649.7952530712;14645.926770366133;13713.108647937903;13968.231747253896;13976.930115349434;13981.790273145885;14005.29973501326;616.2358675242689;775.0815283888596;789.8459800338936;789.8696715042012;790.5059337913804;636.2079211381836;771.7221800151968;781.1290411191509;784.3215618490023;796.3390385979623;830.8919840420737;832.238501770882;833.1002517281322;834.2825170353491;834.7829564313292;641.7144370755016;827.8758186272107;829.1956801902176;829.3986859526885;830.4560512864084;585.7891159182691;596.8925593393187;598.539124873849;605.3462260758414;605.7243954000963;12887.871121288761;12907.741845163044;12908.87091129086;12913.483460661562;12936.48254069836;11986.64040078799;12390.876091239152;12451.929319868581;12519.874801251963;12891;11717.941410292979;11778.705532361673;11794.528218871235;11985.58050468238;12227.510899564008;4101.5488296287385;4125.463689720347;4128.649064829493;4218.894527636803;4227.387028880795;62019.069713954326;63115;63637.045444318836;64104.67947660278;64789;9759.756006099904;9954.950225248827;10194.70401107188;10416.531256093342;10418.426986515662;18135.63728725418;18533;18640.440786776417;18683.346082887034;18719.064046797605;6540.117084193573;6586.736530538725;6630.933690663128;6762.628055456898;6984.825379365557;3561.198730285717;3584.229390681016;3610.981945090258;3635.4910312556262;3642.0712718256673;2847.971520284812;2967.1543610071026;2969.569412435185;2970.77719171063;2972.940541189164;493.7457209537091;500.23713836399054;515.1371452816518;516.917293233081;517.3300575754407;390.74992005116746;423.1621389648513;469.8097270605342;485.63819954134397;534.6124060056428;7372.115346158495;7501.512401693982;7724.150343462215;7731.884021739678;7888.605569721491;402.736207783902;414.5750605629254;481.84443731331817;497.4229893323727;525.6714553404122;3591.2458383739186;3663.7069034477177;3844.308024555551;3849.287881741887;3917.9020524486546;93769.5311523438;96186.51906740421;97396;99310.5034474823;99404;85036.57481712553;85623;85714;87093.1290687085;90123.54938225266;75410.62294688601;76172;76995;77854.22145778582;78420.60789696015;6894.827629309107;7061.329173728693;7067.434605231672;7115.5730656160795;7237.565746055254;43436.565634344515;45231.54768452405;46004.309935351666;46572.30141547812;46903.29645055395;64028.35971640224;65249;65566.08254380846;65960.34039659542;66843;8216.21945915137;8361.372897032868;8418.831623367621;8627.137286271323;8650.09174036717;10309.381437113798;10332.483375831329;10363.896361036293;10560.788784224425;10565.788684226118;9713.514324283617;9765.609375624896;9843.655472058443;9883.555240014073;9918.256130790076;9002.36983411181;9145.542722863964;9209.907900920905;9221.861672075058;9268.53657317145;9436.575354108943;9441.103095205945;9506.904930950603;9626.181774549243;9958.651447199314;7801.3368863644955;7976.162502937108;8009.118996910219;8055.194480551898;8079.757607271791;8038.63826127814;8104.473209241381;8134.105248422788;8313.750587482386;8365.874511882448 10:04:47 INFO - PROCESS | 3391 | __end_tp_report 10:04:47 INFO - PROCESS | 3391 | __start_cc_report 10:04:47 INFO - PROCESS | 3391 | _x_x_mozilla_cycle_collect,1039 10:04:47 INFO - PROCESS | 3391 | __end_cc_report 10:04:47 INFO - PROCESS | 3391 | __startTimestamp1469120687980__endTimestamp 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | ------- Summary: start ------- 10:04:47 INFO - PROCESS | 3391 | Number of tests: 6 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#0] dojo.html Cycles:165 Average:4698.13 Median:4824.52 stddev:493.45 (10.2%) stddev-sans-first:467.36 10:04:47 INFO - PROCESS | 3391 | Values: 2623.7 2644.5 2670.6 2675.3 2676.1 4648.2 4687.1 4697.0 4759.1 4802.3 4584.4 4636.8 4650.4 4708.3 4744.4 4372.0 4391.2 4395.1 4406.9 4542.3 4486.2 4492.5 4497.5 4499.9 4517.4 4269.6 4308.7 4312.1 4375.5 4383.6 4353.6 4356.2 4356.5 4358.8 4363.7 4094.4 4282.8 4337.3 4338.5 4350.9 4687.3 4706.8 4718.7 4734.1 4786.3 5092.7 5162.2 5171.1 5172.5 5173.4 4988.0 5029.9 5194.8 5200.9 5209.2 4717.4 4753.8 4756.5 4799.9 4804.2 5201.6 5220.8 5271.8 5273.8 5276.7 4851.4 4904.0 4947.9 5052.0 5061.9 5069.7 5078.0 5098.5 5100.1 5104.8 5005.7 5014.7 5018.7 5021.9 5072.8 4856.2 4955.0 5017.5 5018.1 5022.1 4370.6 4376.9 4397.8 4407.7 4408.7 3783.1 3799.1 3800.2 3834.1 3861.0 4234.1 4278.7 4283.1 4343.9 4344.8 5067.9 5087.6 5107.6 5152.9 5162.9 4327.7 4328.6 4330.5 4331.8 4367.3 4552.5 4606.9 4613.7 4614.8 4659.5 4743.7 4757.9 4816.7 4824.5 4859.5 5119.4 5129.7 5140.6 5144.8 5184.3 4591.6 4814.2 4826.7 4831.7 4875.6 4958.9 5006.9 5013.7 5042.7 5098.4 5100.7 5100.7 5104.5 5106.6 5123.6 4849.8 4951.9 4971.2 4984.1 4987.9 4837.8 4929.2 4937.0 4976.4 4999.4 4882.6 4893.6 4896.5 4947.0 4949.1 4807.4 4990.7 5002.4 5009.1 5012.1 4790.4 4830.9 4835.6 4851.0 4888.3 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#1] ext.html Cycles:180 Average:49257.26 Median:19542.19 stddev:92025.57 (470.9%) stddev-sans-first:92211.14 10:04:47 INFO - PROCESS | 3391 | Values: 577.4 585.9 586.0 590.4 591.8 27174.0 37758.0 39226.4 39310.4 43783.1 40974.8 41285.6 41331.8 41350.0 41357.0 7751.7 7887.9 7890.4 7891.8 7893.5 7559.5 9871.5 10111.2 10115.8 10475.2 10296.1 10363.9 10381.5 10390.7 10404.9 7600.6 10094.4 10103.9 10159.8 10176.0 9731.5 10125.2 10127.6 10474.1 10491.4 38213.0 38556.4 38648.8 38675.4 38718.2 2869.0 2908.5 3017.3 3056.2 3554.7 29605.4 29642.3 29963.3 30861.8 30896.7 3511.3 3547.3 3564.8 3592.3 3664.6 258203.0 258838.0 260382.7 260872.0 261126.0 52369.0 52516.0 52714.0 52752.5 52755.5 32312.4 33651.7 33761.5 35529.6 54980.5 13475.5 13567.5 13575.8 13580.1 13593.3 8747.3 8917.4 10128.2 10139.3 10155.2 15952.4 16032.6 17069.4 17926.5 17942.8 6731.6 6972.8 7021.5 7100.2 7143.9 792.3 943.9 959.5 999.2 1025.5 28537.9 29047.6 29381.6 29665.6 29844.6 918.6 945.7 960.0 977.1 989.5 15023.9 15322.2 15370.8 15452.2 15552.3 417649.0 421209.0 422960.0 423156.0 423437.0 49698.8 50020.0 50366.7 50407.2 50451.5 1160.7 1190.4 1205.2 1205.9 1206.6 26450.3 28247.9 28307.0 28711.4 28789.6 43206.6 43662.3 43668.3 43678.8 43723.8 345175.0 345588.0 346079.0 346469.0 347244.0 24938.6 25083.7 25456.9 26318.3 26352.5 36317.5 37029.4 38610.0 40641.8 43039.6 36533.3 36812.8 37015.4 37024.4 37059.3 37254.6 37737.4 37778.6 37802.0 38846.0 36407.6 36643.1 37489.1 37586.2 37617.2 31228.2 32953.0 33275.7 33747.3 34022.0 25708.5 25889.0 25940.4 26241.5 26412.5 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#2] jquery.html Cycles:180 Average:23573.37 Median:2042.78 stddev:58650.42 (2871.1%) stddev-sans-first:58789.06 10:04:47 INFO - PROCESS | 3391 | Values: 364.2 366.5 368.4 370.0 383.3 16091.8 16926.9 17592.6 17695.3 17700.9 15472.5 17517.4 17692.8 17847.6 17869.8 4935.4 5166.8 5179.4 5193.3 5195.1 3606.5 3715.6 3755.4 5090.6 5819.6 5997.4 6060.7 6063.7 6066.1 6077.8 5818.9 5969.6 5990.2 5997.6 6006.3 3430.3 4923.5 5299.4 5301.4 5302.3 20097.9 21234.9 21370.9 21677.7 21912.7 1738.9 1760.4 1837.1 2059.5 2317.9 12987.7 13208.7 13463.8 13752.5 14049.6 1755.2 1764.6 1787.5 1801.3 1815.6 66353.7 66860.3 67458.7 67511.3 67640.7 9642.1 9662.9 9919.2 9958.4 10000.7 13420.7 13436.7 13437.7 13442.8 13444.7 7004.3 7149.6 7305.0 7377.2 7393.9 6713.3 6752.6 6935.5 6962.1 7077.8 6168.5 6245.6 6263.5 6302.1 6351.4 1666.2 1700.4 1724.4 1771.7 1812.2 828.2 847.4 870.4 880.5 912.2 17251.1 17402.7 18210.7 18377.0 18506.9 855.8 860.4 930.3 969.9 976.7 10382.0 10418.1 10467.5 10572.9 10595.8 294222.0 299222.0 300807.0 398212.0 443357.0 81887.6 82623.0 83591.9 84035.6 85923.6 932.0 934.9 953.0 968.4 974.3 11870.4 15072.2 16354.9 16694.8 16819.7 34576.8 35172.5 35805.0 36883.3 39767.2 49852.8 49856.5 52919.0 55052.2 55880.2 7682.8 7688.3 7709.0 7801.2 8029.3 9856.7 11991.3 12079.4 12392.9 12768.5 10423.0 10734.1 10891.7 10931.6 11268.0 9563.7 10336.3 10368.3 10700.6 10808.4 10738.1 10857.8 10927.8 11022.2 11200.8 7852.5 7907.8 8118.3 8161.7 8369.2 8271.1 10049.5 10255.7 10615.7 11010.1 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#3] mootools.html Cycles:180 Average:5164.57 Median:4989.38 stddev:2971.55 (59.6%) stddev-sans-first:2966.27 10:04:47 INFO - PROCESS | 3391 | Values: 1378.5 1416.6 1469.6 1705.1 1730.1 7607.4 8137.9 8167.8 8171.5 8173.4 7060.7 7587.7 7806.2 7829.9 8001.5 2185.3 2204.4 2207.8 2208.9 2213.9 2112.1 2130.4 2170.9 2171.5 2184.9 1820.5 1880.0 1885.9 1889.6 2123.2 2168.9 2203.9 2211.3 2215.8 2216.5 2148.1 2150.8 2164.5 2197.0 2197.8 8131.0 8180.9 8184.3 8185.3 8192.8 4585.1 4672.4 4705.1 4717.9 4718.4 2847.1 2961.8 3010.8 3027.1 3039.2 952.4 984.5 992.7 994.8 998.1 10981.3 10988.1 11034.4 11069.1 11093.2 3957.4 4089.2 4142.1 4256.0 4257.4 9305.0 9336.3 9338.1 9338.2 9340.2 4643.2 4648.5 4649.0 4652.3 4653.3 4154.9 4234.9 4242.8 4246.3 4246.4 3621.9 3653.2 3777.8 3785.8 3790.8 1107.7 1159.5 1159.9 1168.7 1170.6 712.5 743.1 817.1 823.2 826.5 5109.6 5123.4 5141.1 5287.6 5415.2 411.8 418.5 422.1 424.7 425.8 3531.6 3551.3 3565.7 3599.6 3616.2 9511.6 9718.4 9853.8 9875.0 9877.1 9482.4 9784.9 9815.4 9816.1 9830.3 7315.3 7486.3 7486.8 7502.8 7504.1 4956.4 5022.3 5067.7 5102.8 5125.5 8734.0 8889.7 8997.7 9000.2 9068.3 8964.7 9176.8 9185.4 9229.0 9298.9 6740.8 6750.3 6765.5 6823.8 6825.3 7378.9 7597.2 7604.3 7606.0 7606.3 6659.9 6755.4 6809.5 6841.3 6937.7 6600.6 6692.2 6723.1 6765.2 6767.9 6261.6 6348.2 6348.9 6352.7 6354.4 4473.4 4539.6 4575.1 4611.8 4615.1 3985.8 4065.8 4085.1 4087.3 4137.5 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#4] prototype.html Cycles:180 Average:4221.28 Median:4757.83 stddev:1217.51 (25.6%) stddev-sans-first:1184.85 10:04:47 INFO - PROCESS | 3391 | Values: 301.6 302.9 309.6 313.1 314.6 4878.5 4940.8 4945.4 4962.2 4967.2 4877.5 4893.0 4907.7 4913.4 4915.4 3893.6 3907.9 3915.4 3918.5 3920.4 3706.5 3862.2 3881.4 3931.4 3931.9 3850.1 3852.6 3864.4 3873.8 3878.4 3829.6 3837.5 3840.8 3849.5 3866.6 3194.6 3292.5 3301.4 3360.5 3443.2 4711.0 4718.6 4726.6 4734.4 4757.0 4377.3 4386.8 4391.2 4404.2 4413.9 4777.6 4872.8 5003.7 5031.4 5053.9 3668.8 3669.4 3676.0 3678.8 3692.0 5308.8 5310.9 5331.3 5345.0 5346.5 3712.9 3762.2 3792.7 3797.8 3808.1 3845.7 3848.4 3849.8 3857.0 3858.4 4325.6 4332.9 4362.3 4377.8 4386.6 4808.9 5152.8 5157.3 5158.9 5160.1 1307.8 1339.0 1341.6 1346.6 1346.8 872.3 890.7 908.7 909.7 912.6 4109.2 4124.2 4136.5 4137.9 4141.4 4621.4 4653.5 4665.9 4674.7 4702.1 4102.9 4104.3 4110.6 4127.0 4154.9 2550.9 2606.7 2777.2 2802.2 2804.9 4758.7 4875.1 4882.4 5009.4 5016.9 5388.3 5403.5 5409.2 5421.2 5422.7 4740.3 4883.4 4910.3 4976.9 4978.3 5218.9 5227.5 5236.2 5258.9 5288.3 5162.1 5165.6 5166.3 5174.9 5214.7 4827.8 4955.8 4970.8 4984.9 5015.6 3602.7 3681.7 3707.3 3708.4 3723.9 5293.3 5315.6 5341.7 5352.7 5353.7 5037.7 5127.7 5209.4 5217.7 5222.6 5066.9 5070.8 5071.4 5090.3 5095.3 5021.4 5031.1 5045.6 5090.0 5107.1 4969.9 5066.4 5080.8 5153.6 5165.0 4998.6 5010.1 5013.7 5014.2 5050.8 10:04:47 INFO - PROCESS | 3391 | 10:04:47 INFO - PROCESS | 3391 | [#5] yui.html Cycles:180 Average:17623.43 Median:32335.04 stddev:26011.77 (80.4%) stddev-sans-first:26053.38 10:04:48 INFO - PROCESS | 3391 | Values: 613.2 628.3 648.2 682.3 738.0 12519.1 13521.9 13627.5 13649.8 14645.9 13713.1 13968.2 13976.9 13981.8 14005.3 616.2 775.1 789.8 789.9 790.5 636.2 771.7 781.1 784.3 796.3 830.9 832.2 833.1 834.3 834.8 641.7 827.9 829.2 829.4 830.5 585.8 596.9 598.5 605.3 605.7 12887.9 12907.7 12908.9 12913.5 12936.5 11986.6 12390.9 12451.9 12519.9 12891.0 11717.9 11778.7 11794.5 11985.6 12227.5 4101.5 4125.5 4128.6 4218.9 4227.4 62019.1 63115.0 63637.0 64104.7 64789.0 9759.8 9955.0 10194.7 10416.5 10418.4 18135.6 18533.0 18640.4 18683.3 18719.1 6540.1 6586.7 6630.9 6762.6 6984.8 3561.2 3584.2 3611.0 3635.5 3642.1 2848.0 2967.2 2969.6 2970.8 2972.9 493.7 500.2 515.1 516.9 517.3 390.7 423.2 469.8 485.6 534.6 7372.1 7501.5 7724.2 7731.9 7888.6 402.7 414.6 481.8 497.4 525.7 3591.2 3663.7 3844.3 3849.3 3917.9 93769.5 96186.5 97396.0 99310.5 99404.0 85036.6 85623.0 85714.0 87093.1 90123.5 75410.6 76172.0 76995.0 77854.2 78420.6 6894.8 7061.3 7067.4 7115.6 7237.6 43436.6 45231.5 46004.3 46572.3 46903.3 64028.4 65249.0 65566.1 65960.3 66843.0 8216.2 8361.4 8418.8 8627.1 8650.1 10309.4 10332.5 10363.9 10560.8 10565.8 9713.5 9765.6 9843.7 9883.6 9918.3 9002.4 9145.5 9209.9 9221.9 9268.5 9436.6 9441.1 9506.9 9626.2 9958.7 7801.3 7976.2 8009.1 8055.2 8079.8 8038.6 8104.5 8134.1 8313.8 8365.9 10:04:48 INFO - PROCESS | 3391 | -------- Summary: end -------- 10:04:48 INFO - PROCESS | 3391 | 10:04:48 INFO - TEST-INFO | 3391: exit 0 10:04:48 INFO - TEST-OK | dromaeo_css | took 1121496ms 10:04:48 INFO - TEST-START | kraken 10:04:48 INFO - operating with platform_type : linux_ 10:04:48 INFO - Initialising browser for kraken test... 10:04:48 INFO - TEST-INFO | started process 3576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRZzlXP/profile http://localhost:54042/getInfo.html) 10:04:53 INFO - PROCESS | 3576 | __metrics Screen width/height:1600/1200 10:04:53 INFO - PROCESS | 3576 | colorDepth:24 10:04:53 INFO - PROCESS | 3576 | Browser inner width/height: 1024/697 10:04:53 INFO - PROCESS | 3576 | __metrics 10:04:53 INFO - PROCESS | 3576 | 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" 10:04:53 INFO - PROCESS | 3576 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:04:55 INFO - TEST-INFO | 3576: exit 0 10:04:55 INFO - Browser initialized. 10:04:55 INFO - Running cycle 1/1 for kraken test... 10:04:55 INFO - TEST-INFO | started process 3655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRZzlXP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:06 INFO - PROCESS | 3655 | RSS: Main: 158158848 10:05:06 INFO - PROCESS | 3655 | 10:05:09 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54042/tests/kraken/driver.html?testName=audio-beat-detection) 10:05:10 INFO - PROCESS | 3655 | RSS: Main: 214147072 10:05:10 INFO - PROCESS | 3655 | 10:05:13 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54042/tests/kraken/driver.html?testName=audio-dft) 10:05:13 INFO - PROCESS | 3655 | RSS: Main: 192786432 10:05:13 INFO - PROCESS | 3655 | 10:05:17 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54042/tests/kraken/driver.html?testName=audio-fft) 10:05:17 INFO - PROCESS | 3655 | RSS: Main: 271736832 10:05:17 INFO - PROCESS | 3655 | 10:05:19 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54042/tests/kraken/driver.html?testName=audio-oscillator) 10:05:20 INFO - PROCESS | 3655 | RSS: Main: 181592064 10:05:20 INFO - PROCESS | 3655 | 10:05:22 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54042/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:05:23 INFO - PROCESS | 3655 | RSS: Main: 185663488 10:05:23 INFO - PROCESS | 3655 | 10:05:26 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54042/tests/kraken/driver.html?testName=imaging-darkroom) 10:05:26 INFO - PROCESS | 3655 | RSS: Main: 222298112 10:05:26 INFO - PROCESS | 3655 | 10:05:30 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54042/tests/kraken/driver.html?testName=imaging-desaturate) 10:05:31 INFO - PROCESS | 3655 | RSS: Main: 216817664 10:05:31 INFO - PROCESS | 3655 | 10:05:33 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54042/tests/kraken/driver.html?testName=json-parse-financial) 10:05:34 INFO - PROCESS | 3655 | RSS: Main: 219529216 10:05:34 INFO - PROCESS | 3655 | 10:05:36 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54042/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:05:36 INFO - PROCESS | 3655 | RSS: Main: 185147392 10:05:36 INFO - PROCESS | 3655 | 10:05:38 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:05:39 INFO - PROCESS | 3655 | RSS: Main: 190304256 10:05:39 INFO - PROCESS | 3655 | 10:05:41 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:05:42 INFO - PROCESS | 3655 | RSS: Main: 195399680 10:05:42 INFO - PROCESS | 3655 | 10:05:44 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:05:45 INFO - PROCESS | 3655 | RSS: Main: 190636032 10:05:45 INFO - PROCESS | 3655 | 10:05:48 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:05:49 INFO - PROCESS | 3655 | RSS: Main: 192995328 10:05:49 INFO - PROCESS | 3655 | 10:05:50 INFO - PROCESS | 3655 | Cycle 1(1): loaded http://localhost:54042/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54042/tests/kraken/driver.html?testName=ai-astar) 10:05:51 INFO - PROCESS | 3655 | RSS: Main: 243503104 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | __start_tp_report 10:05:51 INFO - PROCESS | 3655 | _x_x_mozilla_page_load 10:05:51 INFO - PROCESS | 3655 | _x_x_mozilla_page_load_details 10:05:51 INFO - PROCESS | 3655 | |i|pagename|runs| 10:05:51 INFO - PROCESS | 3655 | |0;ai-astar;107;114;113;114;113;114;114;115;113;115 10:05:51 INFO - PROCESS | 3655 | |1;audio-beat-detection;150;120;127;179;125;125;145;128;156;122 10:05:51 INFO - PROCESS | 3655 | |2;audio-dft;160;161;163;160;162;160;161;162;160;161 10:05:51 INFO - PROCESS | 3655 | |3;audio-fft;76;74;77;105;75;78;75;75;100;75 10:05:51 INFO - PROCESS | 3655 | |4;audio-oscillator;85;85;125;85;85;84;124;85;85;127 10:05:51 INFO - PROCESS | 3655 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;116 10:05:51 INFO - PROCESS | 3655 | |6;imaging-darkroom;147;146;146;146;147;147;146;147;147;146 10:05:51 INFO - PROCESS | 3655 | |7;imaging-desaturate;95;94;94;96;95;95;95;95;95;94 10:05:51 INFO - PROCESS | 3655 | |8;json-parse-financial;63;62;64;63;63;63;63;62;63;62 10:05:51 INFO - PROCESS | 3655 | |9;json-stringify-tinderbox;66;67;67;66;68;67;66;65;66;66 10:05:51 INFO - PROCESS | 3655 | |10;stanford-crypto-aes;80;80;79;78;79;89;80;83;79;83 10:05:51 INFO - PROCESS | 3655 | |11;stanford-crypto-ccm;129;127;140;128;126;150;130;128;180;130 10:05:51 INFO - PROCESS | 3655 | |12;stanford-crypto-pbkdf2;166;162;168;169;162;163;165;165;167;164 10:05:51 INFO - PROCESS | 3655 | |13;stanford-crypto-sha256-iterative;68;66;67;66;66;68;66;67;65;65 10:05:51 INFO - PROCESS | 3655 | __end_tp_report 10:05:51 INFO - PROCESS | 3655 | __start_cc_report 10:05:51 INFO - PROCESS | 3655 | _x_x_mozilla_cycle_collect,1980 10:05:51 INFO - PROCESS | 3655 | __end_cc_report 10:05:51 INFO - PROCESS | 3655 | __startTimestamp1469120751028__endTimestamp 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | ------- Summary: start ------- 10:05:51 INFO - PROCESS | 3655 | Number of tests: 14 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.30 (2.0%) stddev-sans-first:0.78 10:05:51 INFO - PROCESS | 3655 | Values: 107.0 114.0 113.0 114.0 113.0 114.0 114.0 115.0 113.0 115.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:136.50 stddev:19.25 (14.1%) stddev-sans-first:19.90 10:05:51 INFO - PROCESS | 3655 | Values: 150.0 120.0 127.0 179.0 125.0 125.0 145.0 128.0 156.0 122.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 10:05:51 INFO - PROCESS | 3655 | Values: 160.0 161.0 163.0 160.0 162.0 160.0 161.0 162.0 160.0 161.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:11.45 (15.3%) stddev-sans-first:12.00 10:05:51 INFO - PROCESS | 3655 | Values: 76.0 74.0 77.0 105.0 75.0 78.0 75.0 75.0 100.0 75.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:85.00 stddev:19.57 (23.0%) stddev-sans-first:20.27 10:05:51 INFO - PROCESS | 3655 | Values: 85.0 85.0 125.0 85.0 85.0 84.0 124.0 85.0 85.0 127.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:05:51 INFO - PROCESS | 3655 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 10:05:51 INFO - PROCESS | 3655 | Values: 147.0 146.0 146.0 146.0 147.0 147.0 146.0 147.0 147.0 146.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:05:51 INFO - PROCESS | 3655 | Values: 95.0 94.0 94.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 10:05:51 INFO - PROCESS | 3655 | Values: 63.0 62.0 64.0 63.0 63.0 63.0 63.0 62.0 63.0 62.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 10:05:51 INFO - PROCESS | 3655 | Values: 66.0 67.0 67.0 66.0 68.0 67.0 66.0 65.0 66.0 66.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:80.00 stddev:3.27 (4.1%) stddev-sans-first:3.44 10:05:51 INFO - PROCESS | 3655 | Values: 80.0 80.0 79.0 78.0 79.0 89.0 80.0 83.0 79.0 83.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:130.00 stddev:16.90 (13.0%) stddev-sans-first:17.69 10:05:51 INFO - PROCESS | 3655 | Values: 129.0 127.0 140.0 128.0 126.0 150.0 130.0 128.0 180.0 130.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.50 stddev:2.42 (1.5%) stddev-sans-first:2.55 10:05:51 INFO - PROCESS | 3655 | Values: 166.0 162.0 168.0 169.0 162.0 163.0 165.0 165.0 167.0 164.0 10:05:51 INFO - PROCESS | 3655 | 10:05:51 INFO - PROCESS | 3655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:0.97 10:05:51 INFO - PROCESS | 3655 | Values: 68.0 66.0 67.0 66.0 66.0 68.0 66.0 67.0 65.0 65.0 10:05:51 INFO - PROCESS | 3655 | -------- Summary: end -------- 10:05:51 INFO - PROCESS | 3655 | 10:05:52 INFO - TEST-INFO | 3655: exit 0 10:05:52 INFO - TEST-OK | kraken | took 63191ms 10:05:52 INFO - SUITE-END | took 1184s 10:05:52 INFO - Completed test suite (00:19:44) 10:05:52 INFO - javascript benchmark 10:05:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2623.711391746908, 2644.457886133343, 2670.572708366661, 2675.331167208198, 2676.0901293560187, 4648.163330600491, 4687.064260388032, 4697.013627138296, 4759.09577180336, 4802.279658051294, 4584.404027476424, 4636.791344389502, 4650.372199753032, 4708.293755936611, 4744.4306683198065, 4371.978140109295, 4391.165678521087, 4395.120975804836, 4406.867793966187, 4542.318652202163, 4486.214912390335, 4492.483364413085, 4497.482789479219, 4499.932501012488, 4517.390152329443, 4269.615734583871, 4308.6983911126235, 4312.116016216668, 4375.453068366454, 4383.561643835623, 4353.62994145498, 4356.194104090753, 4356.520782713899, 4358.782060896974, 4363.694541382073, 4094.406311084876, 4282.764447955361, 4337.262665346899, 4338.479382474092, 4350.912981740347, 4687.2969054641735, 4706.764661766898, 4718.669693121483, 4734.076855013273, 4786.282057691339, 5092.719900405476, 5162.22566615007, 5171.0692075426405, 5172.508611681884, 5173.430922598511, 4987.975060124676, 5029.924551131736, 5194.844154675366, 5200.947990520122, 5209.218617207412, 4717.410323709536, 4753.83361582343, 4756.500567440438, 4799.904001919942, 4804.2073057945745, 5201.557867581298, 5220.843374698765, 5271.789128434896, 5273.841784746463, 5276.709780962007, 4851.393575803025, 4903.95096049035, 4947.851564453073, 5052.014857102829, 5061.8734531637, 5069.721165335942, 5078.0, 5098.46466121052, 5100.081985242692, 5104.770285337095, 5005.6996580205305, 5014.6991180529285, 5018.673786203885, 5021.949780502221, 5072.797088116434, 4856.17445034345, 4955.0, 5017.54842064216, 5018.071656743514, 5022.096022715946, 4370.562943705604, 4376.868693939187, 4397.758123303249, 4407.6914615976575, 4408.691391602557, 3783.05423644089, 3799.069228039148, 3800.201957588937, 3834.0989867381145, 3861.0347413146715, 4234.06850492891, 4278.743275403485, 4283.057727299989, 4343.913121737548, 4344.804483798237, 5067.949320506821, 5087.567556757717, 5107.61692873036, 5152.92270615941, 5162.92255616166, 4327.675424343187, 4328.567143285646, 4330.458692663417, 4331.7834108294455, 4367.30123180296, 4552.521985191574, 4606.907861842745, 4613.700109492872, 4614.792334344963, 4659.487456379796, 4743.66794324394, 4757.904841903073, 4816.735079570657, 4824.517548245148, 4859.514048595112, 5119.411267704113, 5129.717865517432, 5140.614453915897, 5144.768485418091, 5184.300119483973, 4591.586757191869, 4814.15752243363, 4826.686265392738, 4831.710097394168, 4875.585575226004, 4958.900821983612, 5006.949930500794, 5013.749312534432, 5042.747862606781, 5098.362704661918, 5100.69395836251, 5100.7449627517735, 5104.515071068243, 5106.617003724809, 5123.590112790893, 4849.75751212445, 4951.876203094951, 4971.154903666457, 4984.077945580079, 4987.8753031173055, 4837.7581120943105, 4929.2359684248995, 4936.950630493649, 4976.427710813158, 4999.350084488993, 4882.584980276716, 4893.632977526771, 4896.4858689838475, 4946.950530494649, 4949.059678661043, 4807.4471435783935, 4990.72551009698, 5002.449730529566, 5009.123403404316, 5012.097822392005, 4790.353302304145, 4830.8792280193275, 4835.6131509479865, 4850.95149048505, 4888.266066895828], "value": 4666.714903085734, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3620149734544, 585.8769658371742, 585.9774693160434, 590.391896346763, 591.8313280714995, 27174.048908288212, 37758.0, 39226.41160382593, 39310.410343844866, 43783.12433751331, 40974.7951260244, 41285.58714412855, 41331.793341033255, 41350.0, 41357.0, 7751.651175697093, 7887.921120788805, 7890.447668663196, 7891.8421631567335, 7893.526388416684, 7559.508631938934, 9871.457069861155, 10111.241656875727, 10115.848262276071, 10475.161987041018, 10296.073353398193, 10363.896361036406, 10381.532831022621, 10390.688279351594, 10404.89595104047, 7600.619969001527, 10094.394336339807, 10103.898961010444, 10159.796804063952, 10176.033276838725, 9731.513424328827, 10125.189984801198, 10127.64553240633, 10474.109700675452, 10491.42297173655, 38213.0, 38556.421653675214, 38648.806755966325, 38675.41986870199, 38718.22563548741, 2869.03887197789, 2908.476474234637, 3017.2909366298904, 3056.205386599478, 3554.733394995386, 29605.407891842257, 29642.25894352637, 29963.25091872699, 30861.845690771628, 30896.691033089606, 3511.262634846685, 3547.290541891606, 3564.7682900611376, 3592.2995015971896, 3664.633536646341, 258203.0, 258838.0, 260382.69808651024, 260872.0, 261126.0, 52369.0, 52516.0, 52714.0, 52752.47247527552, 52755.47244527505, 32312.35375292481, 33651.663483365344, 33761.49357759635, 35529.64470355315, 54980.450195498335, 13475.517693053756, 13567.457301707822, 13575.796363054562, 13580.117292375964, 13593.32033398346, 8747.343949203709, 8917.375783695079, 10128.240381971382, 10139.340942838691, 10155.238357123246, 15952.361905523649, 16032.599185020235, 17069.402570910206, 17926.462206133838, 17942.820571794375, 6731.562448440835, 6972.79081627552, 7021.473389495808, 7100.183478899889, 7143.8571228576175, 792.2513225002433, 943.9374965699967, 959.5442164971582, 999.2455695949511, 1025.5231317437429, 28537.857310713312, 29047.56428653572, 29381.559276610868, 29665.555016674767, 29844.55233171504, 918.5991363171217, 945.6879229854131, 959.9616015359447, 977.0766625538797, 989.4904124375952, 15023.877126361574, 15322.15728134941, 15370.846291537166, 15452.227388630523, 15552.300146493435, 417649.0, 421209.0, 422960.0, 423156.0, 423437.0, 49698.75150624296, 50020.0, 50366.74816625967, 50407.24389134166, 50451.495485045416, 1160.7446361800392, 1190.3572071081692, 1205.2105870654734, 1205.921615095016, 1206.6380085974172, 26450.338741530846, 28247.858760706065, 28307.0, 28711.425771484035, 28789.56815647809, 43206.56793432026, 43662.34506482342, 43668.34497482604, 43678.78160609177, 43723.78138109289, 345175.0, 345588.0, 346079.0, 346469.0, 347244.0, 24938.62592061084, 25083.74916250887, 25456.872715636302, 26318.342041449116, 26352.472950541265, 36317.45523817092, 37029.44455833219, 38610.0, 40641.796791015855, 43039.56960430356, 36533.26933461369, 36812.815935920145, 37015.444768329035, 37024.38467305813, 37059.33233004467, 37254.6274537262, 37737.433938490394, 37778.62221377861, 37802.0, 38846.0, 36407.63592364149, 36643.083922902144, 37489.06277342979, 37586.2482750338, 37617.24765504729, 31228.219294517818, 32953.0, 33275.667243327254, 33747.325053499284, 34022.0, 25708.485830282916, 25889.0, 25940.351491212117, 26241.475170496866, 26412.4717505645], "value": 17954.606508137444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.1606097944241, 366.4722799169197, 368.44181065685507, 369.99075023124425, 383.30621574949345, 16091.75862362064, 16926.91536542317, 17592.648147037067, 17695.29218831245, 17700.91149544254, 15472.458463953764, 17517.386891458802, 17692.82307176928, 17847.55381115473, 17869.838460500057, 4935.358403407561, 5166.793328266865, 5179.404368497633, 5193.272941788153, 5195.090859099661, 3606.459031145336, 3715.5727091384433, 3755.43668449732, 5090.643654944155, 5819.592628516006, 5997.430244126801, 6060.666663333537, 6063.666498342589, 6066.120412540179, 6077.848053798646, 5818.912716309259, 5969.5821292509545, 5990.191324171225, 5997.550183736215, 6006.309274433409, 3430.3482338355884, 4923.458419573844, 5299.417064122943, 5301.416844147141, 5302.257683924254, 20097.8995105025, 21234.89382553093, 21370.89314553433, 21677.67483487749, 21912.671309930363, 1738.9130543472775, 1760.375066851275, 1837.0722784993493, 2059.4542446251753, 2317.895694693743, 12987.675308117277, 13208.6697832554, 13463.798043029363, 13752.518661846787, 14049.64875878101, 1755.157524388288, 1764.6117854072083, 1787.4548262779847, 1801.3425099838464, 1815.5733402650367, 66353.66823165902, 66860.3313966854, 67458.66270668615, 67511.32488675149, 67640.66179669119, 9642.132208101304, 9662.903370966342, 9919.156871665846, 9958.402495850272, 10000.699979000641, 13420.731585368238, 13436.731265374638, 13437.656234376484, 13442.798358024638, 13444.663883402993, 7004.278278346695, 7149.642517874086, 7305.03455083902, 7377.188509263977, 7393.9260607393235, 6713.26154123046, 6752.561083529555, 6935.514513984073, 6962.094927659473, 7077.787666370017, 6168.475679567197, 6245.594036387621, 6263.467605253604, 6302.1177035215105, 6351.396617321347, 1666.2002238925384, 1700.3623641134575, 1724.3706047292899, 1771.716525355937, 1812.1935738596462, 828.2131974624131, 847.4068152293419, 870.3994243971639, 880.4629176202511, 912.1699253679253, 17251.05119218455, 17402.65194696099, 18210.726839097424, 18377.0, 18506.907465462857, 855.7603870916154, 860.3805260212637, 930.3022732950287, 969.8739163908647, 976.7313988653035, 10382.0, 10418.11446027081, 10467.476626168662, 10572.947135264274, 10595.788084238273, 294222.0, 299222.0, 300807.0, 398212.0, 443357.0, 81887.59056204681, 82623.0, 83591.91913135082, 84035.5798221005, 85923.5703821477, 931.9720408387759, 934.9111834375859, 952.9761755956018, 968.3608818180109, 974.3325821812082, 11870.406479675808, 15072.24638768079, 16354.918225408797, 16694.833051669815, 16819.66360672755, 34576.82711586426, 35172.47241291332, 35805.0, 36883.26233475262, 39767.20465590614, 49852.75073624609, 49856.501434985184, 52919.0, 55052.174217387575, 55880.161797573885, 7682.769516914502, 7688.308052275322, 7708.961455192688, 7801.180876008165, 8029.277365037175, 9856.704298871045, 11991.321215029728, 12079.396030198279, 12392.938035309766, 12768.489260429851, 10423.009814067651, 10734.14126869833, 10891.727706807393, 10931.562737490729, 11268.0, 9563.713088607354, 10336.328138670962, 10368.274220804471, 10700.625478108228, 10808.405537695502, 10738.140948723927, 10857.83713244286, 10927.83608245861, 11022.228444008842, 11200.83198752003, 7852.528848269122, 7907.802304942423, 8118.3099436548555, 8161.673533058849, 8369.246767790684, 8271.090180080308, 10049.547770350206, 10255.692329229836, 10615.734606634896, 11010.064144547803], "value": 8452.913828222076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.510628726802, 1416.5679467762334, 1469.632591852037, 1705.1474262868567, 1730.1176400035986, 7607.353374963128, 8137.877931831027, 8167.795805104875, 8171.509709417439, 8173.386995975304, 7060.717571297142, 7587.658555365008, 7806.219378062191, 7829.882551761728, 8001.519908805476, 2185.267935241696, 2204.4268490192585, 2207.8233741300737, 2208.8895555222257, 2213.9225127120553, 2112.1234687604624, 2130.4141361125735, 2170.89145542723, 2171.4788450771857, 2184.9344519664355, 1820.5266630676053, 1879.9718004229946, 1885.9057047147655, 1889.6409682160345, 2123.203798575534, 2168.8915554222303, 2203.9338819835352, 2211.347652442533, 2215.8227341812617, 2216.4791274050585, 2148.1085349579907, 2150.8171805396405, 2164.491344534033, 2196.9560608787733, 2197.791209835079, 8131.0, 8180.9181908181345, 8184.345252379795, 8185.3042491387705, 8192.795180120485, 4585.060062687175, 4672.415948006499, 4705.106029854317, 4717.882052948669, 4718.386609740712, 2847.1316248544185, 2961.7778666599875, 3010.804297720641, 3027.122134580969, 3039.2249976256135, 952.3524003677566, 984.5175863826745, 992.6724181020246, 994.796066806303, 998.0867506231789, 10981.341119532774, 10988.066014388867, 11034.393108379036, 11069.114470842313, 11093.168012399103, 3957.3668213086344, 4089.2230476209425, 4142.08874047709, 4256.021115143534, 4257.446531950826, 9305.0, 9336.299777516713, 9338.112879276458, 9338.206252468617, 9340.206082483064, 4643.164230438553, 4648.48866624671, 4649.04694537619, 4652.348671186037, 4653.302004699322, 4154.937675934863, 4234.851780187679, 4242.830286788494, 4246.278132717383, 4246.44796176495, 3621.873234436835, 3653.2328211075974, 3777.7544459610035, 3785.8296376663116, 3790.753601015925, 1107.6566264458045, 1159.5245949160803, 1159.8608167019843, 1168.7428765671539, 1170.584442522909, 712.4763298975249, 743.1453828097731, 817.0562999735315, 823.2097186700835, 826.5495305058757, 5109.642325037212, 5123.436421993578, 5141.126008578551, 5287.550558202519, 5415.214793854869, 411.8146833924746, 418.4915327876644, 422.0714428257784, 424.7196850078933, 425.79987405919167, 3531.646835316448, 3551.3074950384944, 3565.6612621800887, 3599.6220396857952, 3616.150204701893, 9511.619535218651, 9718.416894986323, 9853.802923941481, 9875.0, 9877.06167914047, 9482.431054136774, 9784.853227201598, 9815.411075335503, 9816.067473589997, 9830.262730295257, 7315.268473152642, 7486.251374862471, 7486.812829679193, 7502.774916752397, 7504.099508059068, 4956.430010548833, 5022.3219865317715, 5067.695938243717, 5102.821401250939, 5125.461826508163, 8733.956330218309, 8889.733308000512, 8997.685081022133, 9000.234980026771, 9068.274538037072, 8964.68623598171, 9176.770580735534, 9185.357025008185, 9229.030951750237, 9298.907010929805, 6740.764073257413, 6750.32496750321, 6765.458763299022, 6823.761168359084, 6825.317468253334, 7378.926210738038, 7597.240275972358, 7604.277593628596, 7606.011218541776, 7606.315431611181, 6659.866802663822, 6755.3582409671, 6809.455243580403, 6841.315868413318, 6937.687804048729, 6600.60396376219, 6692.196936367667, 6723.058771771858, 6765.154355705537, 6767.932320676927, 6261.624302541862, 6348.174737284124, 6348.936510634834, 6352.745890164341, 6354.3963323484195, 4473.35136405226, 4539.568740969603, 4575.084983003346, 4611.769411529343, 4615.100055489282, 3985.8405663773124, 4065.7763822988836, 4085.121698834646, 4087.325591277406, 4137.462129923211], "value": 4063.8567463266804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.6259837801127, 302.93184033592445, 309.61452991026175, 313.1419909448113, 314.565899059298, 4878.487758785325, 4940.752962351886, 4945.406551213855, 4962.230854217593, 4967.230079337708, 4877.5122487751205, 4892.975535122328, 4907.705537667743, 4913.434954980178, 4915.410150781902, 3893.610638936105, 3907.921841563167, 3915.3539665955227, 3918.5297764268184, 3920.3531417316253, 3706.499622550948, 3862.2275544890995, 3881.3983832505864, 3931.3513270310364, 3931.9213615727667, 3850.114473671057, 3852.614738526139, 3864.3623802072625, 3873.845046198163, 3878.4182372644186, 3829.5787463379006, 3837.5394952605866, 3840.8271627776817, 3849.5188101487315, 3866.5940076291868, 3194.5847039884898, 3292.506124081383, 3301.422251106047, 3360.512725654766, 3443.1564266754554, 4710.95289047112, 4718.598919091882, 4726.5746082852365, 4734.431868175807, 4756.952430475685, 4377.299632058855, 4386.780660966939, 4391.187630288407, 4404.1852257332175, 4413.9117217655785, 4777.641676874214, 4872.8061624901775, 5003.674761140551, 5031.371078615173, 5053.949460505385, 3668.7982160981137, 3669.43123815809, 3675.9816200918826, 3678.834452449646, 3691.9815400922826, 5308.814191503279, 5310.893782124336, 5331.306930099101, 5344.973275133639, 5346.492083252124, 3712.888613341604, 3762.2099359134886, 3792.715546334036, 3797.8480860765812, 3808.143167787281, 3845.673117784964, 3848.365019771706, 3849.8460061597784, 3856.9807150964066, 3858.3633700439113, 4325.55094043499, 4332.876890323719, 4362.323839804835, 4377.759223242752, 4386.649068074546, 4808.855734327975, 5152.819651312261, 5157.252198431206, 5158.871028224325, 5160.122779127557, 1307.8495972963042, 1338.9799153012711, 1341.6042267530906, 1346.6296768388574, 1346.777781666014, 872.254222639634, 890.6882591093195, 908.7364664247426, 909.6907051602487, 912.5574096563162, 4109.239790638741, 4124.154548317588, 4136.524299705572, 4137.91724165515, 4141.440905477722, 4621.445426548768, 4653.464851542049, 4665.883352916204, 4674.672772905864, 4702.106599746037, 4102.856400026045, 4104.332184080372, 4110.568390318974, 4127.0, 4154.925355237163, 2550.948981020369, 2606.713261541229, 2777.180731684158, 2802.243394283573, 2804.8878044877974, 4758.714477131383, 4875.07373599015, 4882.36529251195, 5009.44896061433, 5016.874578135576, 5388.2725832012175, 5403.540699040625, 5409.24270602116, 5421.2139239810795, 5422.674639521562, 4740.312654665123, 4883.4139903211135, 4910.337104490919, 4976.8755781105765, 4978.327925730053, 5218.947810521846, 5227.529522343008, 5236.240745092016, 5258.9482103578675, 5288.286081378965, 5162.122439185272, 5165.61257905651, 5166.2508936204795, 5174.922376164285, 5214.679467636865, 4827.806887724451, 4955.752212389438, 4970.801167953241, 4984.931253529745, 5015.64890457679, 3602.7030269103625, 3681.687056600219, 3707.295613833363, 3708.351038568294, 3723.888283351504, 5293.338332708411, 5315.6279060465395, 5341.652792568626, 5352.705601191816, 5353.705546194997, 5037.672551284155, 5127.692338459555, 5209.426963033988, 5217.713025783618, 5222.6083043771105, 5066.9239961401345, 5070.8225212117395, 5071.442141364373, 5090.261912022662, 5095.280445274896, 5021.447640759587, 5031.144705400017, 5045.596352291735, 5090.0, 5107.10625640504, 4969.881776600236, 5066.44269130388, 5080.771365288645, 5153.639245252796, 5165.0, 4998.550130488274, 5010.073136469765, 5013.724245166551, 5014.247862820606, 5050.797968081236], "value": 3840.117269326969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.1507861611664, 628.2617923939372, 648.2480322825523, 682.3381320119482, 737.9815854121312, 12519.12366134371, 13521.864781352184, 13627.454901803929, 13649.7952530712, 14645.926770366133, 13713.108647937903, 13968.231747253896, 13976.930115349434, 13981.790273145885, 14005.29973501326, 616.2358675242689, 775.0815283888596, 789.8459800338936, 789.8696715042012, 790.5059337913804, 636.2079211381836, 771.7221800151968, 781.1290411191509, 784.3215618490023, 796.3390385979623, 830.8919840420737, 832.238501770882, 833.1002517281322, 834.2825170353491, 834.7829564313292, 641.7144370755016, 827.8758186272107, 829.1956801902176, 829.3986859526885, 830.4560512864084, 585.7891159182691, 596.8925593393187, 598.539124873849, 605.3462260758414, 605.7243954000963, 12887.871121288761, 12907.741845163044, 12908.87091129086, 12913.483460661562, 12936.48254069836, 11986.64040078799, 12390.876091239152, 12451.929319868581, 12519.874801251963, 12891.0, 11717.941410292979, 11778.705532361673, 11794.528218871235, 11985.58050468238, 12227.510899564008, 4101.5488296287385, 4125.463689720347, 4128.649064829493, 4218.894527636803, 4227.387028880795, 62019.069713954326, 63115.0, 63637.045444318836, 64104.67947660278, 64789.0, 9759.756006099904, 9954.950225248827, 10194.70401107188, 10416.531256093342, 10418.426986515662, 18135.63728725418, 18533.0, 18640.440786776417, 18683.346082887034, 18719.064046797605, 6540.117084193573, 6586.736530538725, 6630.933690663128, 6762.628055456898, 6984.825379365557, 3561.198730285717, 3584.229390681016, 3610.981945090258, 3635.4910312556262, 3642.0712718256673, 2847.971520284812, 2967.1543610071026, 2969.569412435185, 2970.77719171063, 2972.940541189164, 493.7457209537091, 500.23713836399054, 515.1371452816518, 516.917293233081, 517.3300575754407, 390.74992005116746, 423.1621389648513, 469.8097270605342, 485.63819954134397, 534.6124060056428, 7372.115346158495, 7501.512401693982, 7724.150343462215, 7731.884021739678, 7888.605569721491, 402.736207783902, 414.5750605629254, 481.84443731331817, 497.4229893323727, 525.6714553404122, 3591.2458383739186, 3663.7069034477177, 3844.308024555551, 3849.287881741887, 3917.9020524486546, 93769.5311523438, 96186.51906740421, 97396.0, 99310.5034474823, 99404.0, 85036.57481712553, 85623.0, 85714.0, 87093.1290687085, 90123.54938225266, 75410.62294688601, 76172.0, 76995.0, 77854.22145778582, 78420.60789696015, 6894.827629309107, 7061.329173728693, 7067.434605231672, 7115.5730656160795, 7237.565746055254, 43436.565634344515, 45231.54768452405, 46004.309935351666, 46572.30141547812, 46903.29645055395, 64028.35971640224, 65249.0, 65566.08254380846, 65960.34039659542, 66843.0, 8216.21945915137, 8361.372897032868, 8418.831623367621, 8627.137286271323, 8650.09174036717, 10309.381437113798, 10332.483375831329, 10363.896361036293, 10560.788784224425, 10565.788684226118, 9713.514324283617, 9765.609375624896, 9843.655472058443, 9883.555240014073, 9918.256130790076, 9002.36983411181, 9145.542722863964, 9209.907900920905, 9221.861672075058, 9268.53657317145, 9436.575354108943, 9441.103095205945, 9506.904930950603, 9626.181774549243, 9958.651447199314, 7801.3368863644955, 7976.162502937108, 8009.118996910219, 8055.194480551898, 8079.757607271791, 8038.63826127814, 8104.473209241381, 8134.105248422788, 8313.750587482386, 8365.874511882448], "value": 6235.758994637036, "unit": "score"}], "name": "dromaeo_css", "value": 6403.285218690181, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 113.0, 114.0, 113.0, 114.0, 114.0, 115.0, 113.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 127.0, 179.0, 125.0, 125.0, 145.0, 128.0, 156.0, 122.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 160.0, 162.0, 160.0, 161.0, 162.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 77.0, 105.0, 75.0, 78.0, 75.0, 75.0, 100.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 125.0, 85.0, 85.0, 84.0, 124.0, 85.0, 85.0, 127.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 68.0, 67.0, 66.0, 65.0, 66.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 78.0, 79.0, 89.0, 80.0, 83.0, 79.0, 83.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 127.0, 140.0, 128.0, 126.0, 150.0, 130.0, 128.0, 180.0, 130.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 168.0, 169.0, 162.0, 163.0, 165.0, 165.0, 167.0, 164.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 66.0, 68.0, 66.0, 67.0, 65.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1526.4999999999998, "alertThreshold": 2.0}]} 10:05:52 INFO - Return code: 0 10:05:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:05:52 INFO - # TBPL SUCCESS # 10:05:52 INFO - Running post-action listener: _resource_record_post_action 10:05:52 INFO - [mozharness: 2016-07-21 17:05:52.328979Z] Finished run-tests step (success) 10:05:52 INFO - Running post-run listener: _resource_record_post_run 10:05:52 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 7766016; Write bytes: 305475584; Read time: 1388; Write time: 243700 10:05:52 INFO - TinderboxPrint: CPU usage
13.0% 10:05:52 INFO - TinderboxPrint: I/O read bytes / time
7,766,016 / 1,388 10:05:52 INFO - TinderboxPrint: I/O write bytes / time
305,475,584 / 243,700 10:05:52 INFO - TinderboxPrint: CPU idle
8,346.3 (87.0%) 10:05:52 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 10:05:52 INFO - TinderboxPrint: Swap in / out
0 / 0 10:05:52 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 19574784; Read time: 0; Write time: 11448 10:05:52 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 7766016; Write bytes: 264957952; Read time: 1388; Write time: 224684 10:05:52 INFO - Running post-run listener: _upload_blobber_files 10:05:52 INFO - Blob upload gear active. 10:05:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:05:52 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 10:05:52 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:05:52 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:05:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:05:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:05:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:05:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:54 INFO - (blobuploader) - INFO - Done attempting. 10:05:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:05:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:06:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:06:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:06:12 INFO - (blobuploader) - INFO - Done attempting. 10:06:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:06:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:06:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:06:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:06:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:06:13 INFO - (blobuploader) - INFO - Done attempting. 10:06:13 INFO - (blobuploader) - INFO - Iteration through files over. 10:06:13 INFO - Return code: 0 10:06:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:06:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:06:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9963d9c8aa01de1ae81a6221a549dc6b0a3dfe2265e0bef08f930884cec2f621cb8f3884a3367ed7e92536c558c238b7a4562b119dd8493a6e1d2b975f2e63f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8456888845a13aa53f379c8902bc936bc0dd70614ad5628c93d4c2543bb28a28809838b92f02859b2632afbb0a0ad39d3f68514a8957d5471486dd952391ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2eacf06fe133d238922c93e5c4506701b47df756d242c73e53e15b0fd7e9bd891b46f2c7fc2a59d0c5e690fa22eabf06568344370931e2ceccfa829e244c14"} 10:06:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:06:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:06:13 INFO - Contents: 10:06:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9963d9c8aa01de1ae81a6221a549dc6b0a3dfe2265e0bef08f930884cec2f621cb8f3884a3367ed7e92536c558c238b7a4562b119dd8493a6e1d2b975f2e63f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8456888845a13aa53f379c8902bc936bc0dd70614ad5628c93d4c2543bb28a28809838b92f02859b2632afbb0a0ad39d3f68514a8957d5471486dd952391ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2eacf06fe133d238922c93e5c4506701b47df756d242c73e53e15b0fd7e9bd891b46f2c7fc2a59d0c5e690fa22eabf06568344370931e2ceccfa829e244c14"} 10:06:13 INFO - Running post-run listener: copy_logs_to_upload_dir 10:06:13 INFO - Copying logs to upload dir... 10:06:13 INFO - mkdir: /builds/slave/test/build/upload/logs 10:06:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.443229 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-21 10:06:13.401516) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 10:06:13.408700) ========= 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=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 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-Non-PGO/sha512/d9963d9c8aa01de1ae81a6221a549dc6b0a3dfe2265e0bef08f930884cec2f621cb8f3884a3367ed7e92536c558c238b7a4562b119dd8493a6e1d2b975f2e63f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8456888845a13aa53f379c8902bc936bc0dd70614ad5628c93d4c2543bb28a28809838b92f02859b2632afbb0a0ad39d3f68514a8957d5471486dd952391ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2eacf06fe133d238922c93e5c4506701b47df756d242c73e53e15b0fd7e9bd891b46f2c7fc2a59d0c5e690fa22eabf06568344370931e2ceccfa829e244c14"} build_url:https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/Zzf4cPkmQsSzzYWmZMm1Cg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9963d9c8aa01de1ae81a6221a549dc6b0a3dfe2265e0bef08f930884cec2f621cb8f3884a3367ed7e92536c558c238b7a4562b119dd8493a6e1d2b975f2e63f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8456888845a13aa53f379c8902bc936bc0dd70614ad5628c93d4c2543bb28a28809838b92f02859b2632afbb0a0ad39d3f68514a8957d5471486dd952391ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2eacf06fe133d238922c93e5c4506701b47df756d242c73e53e15b0fd7e9bd891b46f2c7fc2a59d0c5e690fa22eabf06568344370931e2ceccfa829e244c14"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 10:06:13.436974) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:06:13.437454) ========= 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=a0d2d2de893abaa8702a9954000001a7-1469118517.503449-1266819558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:06:13.516990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:06:13.520408) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:06:13.520944) ========= ========= Total master_lag: 0.92 =========