builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1474509573.82 results: success (0) buildid: 20160921173859 builduid: ee6c993604504537a011b94dcfbaba06 revision: 3b8cf1e729ef97ed49808f980bed542b81a3631e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.820608) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.821113) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.821411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.836031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.836348) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 18:59:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 60.3M=0s 2016-09-21 18:59:33 (60.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.981969) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:33.982342) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019789 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.032258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.032665) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 3b8cf1e729ef97ed49808f980bed542b81a3631e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 3b8cf1e729ef97ed49808f980bed542b81a3631e --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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 18:59:34,112 truncating revision to first 12 chars 2016-09-21 18:59:34,112 Setting DEBUG logging. 2016-09-21 18:59:34,112 attempt 1/10 2016-09-21 18:59:34,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/3b8cf1e729ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 18:59:34,412 unpacking tar archive at: larch-3b8cf1e729ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.727694 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.774160) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.774500) ========= script_repo_revision: 3b8cf1e729ef97ed49808f980bed542b81a3631e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.774964) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.775265) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:59:34.784875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-21 18:59:34.785252) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:34 INFO - MultiFileLogger online at 20160921 18:59:34 in /builds/slave/test 18:59:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 18:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:34 INFO - {'append_to_log': False, 18:59:34 INFO - 'base_work_dir': '/builds/slave/test', 18:59:34 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 18:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:34 INFO - 'branch': 'Larch-Non-PGO', 18:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:34 INFO - 'config_files': ('talos/linux_config.py',), 18:59:34 INFO - 'default_actions': ('clobber', 18:59:34 INFO - 'read-buildbot-config', 18:59:34 INFO - 'download-and-extract', 18:59:34 INFO - 'populate-webroot', 18:59:34 INFO - 'create-virtualenv', 18:59:34 INFO - 'install', 18:59:34 INFO - 'run-tests'), 18:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:34 INFO - 'download_minidump_stackwalk': True, 18:59:34 INFO - 'download_symbols': 'ondemand', 18:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:34 INFO - 'installer_path': 'installer.exe', 18:59:34 INFO - 'log_level': 'info', 18:59:34 INFO - 'log_name': 'talos', 18:59:34 INFO - 'log_to_console': True, 18:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:34 INFO - 'opt_config_files': (), 18:59:34 INFO - 'pip_index': False, 18:59:34 INFO - 'sps_profile': False, 18:59:34 INFO - 'sps_profile_interval': 0, 18:59:34 INFO - 'suite': 'dromaeojs', 18:59:34 INFO - 'system_bits': '32', 18:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:34 INFO - 'title': 'talos-linux64-ix-030', 18:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:34 INFO - 'use_talos_json': True, 18:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:34 INFO - 'work_dir': 'build'} 18:59:34 INFO - [mozharness: 2016-09-22 01:59:34.965889Z] Running clobber step. 18:59:34 INFO - Running pre-action listener: _resource_record_pre_action 18:59:34 INFO - Running main action method: clobber 18:59:34 INFO - rmtree: /builds/slave/test/build 18:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:35 INFO - Running post-action listener: _resource_record_post_action 18:59:35 INFO - [mozharness: 2016-09-22 01:59:35.937498Z] Finished clobber step (success) 18:59:35 INFO - [mozharness: 2016-09-22 01:59:35.937596Z] Running read-buildbot-config step. 18:59:35 INFO - Running pre-action listener: _resource_record_pre_action 18:59:35 INFO - Running main action method: read_buildbot_config 18:59:35 INFO - Using buildbot properties: 18:59:35 INFO - { 18:59:35 INFO - "project": "", 18:59:35 INFO - "product": "firefox", 18:59:35 INFO - "script_repo_revision": "production", 18:59:35 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 18:59:35 INFO - "repository": "", 18:59:35 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 18:59:35 INFO - "buildid": "20160921173859", 18:59:35 INFO - "pgo_build": "False", 18:59:35 INFO - "basedir": "/builds/slave/test", 18:59:35 INFO - "buildnumber": 3, 18:59:35 INFO - "slavename": "talos-linux64-ix-030", 18:59:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:59:35 INFO - "platform": "ubuntu64_hw", 18:59:35 INFO - "scheduler": "tests-larch-linux64-talos", 18:59:35 INFO - "branch": "larch", 18:59:35 INFO - "repo_path": "projects/larch", 18:59:35 INFO - "revision": "3b8cf1e729ef97ed49808f980bed542b81a3631e", 18:59:35 INFO - "stage_platform": "linux64", 18:59:35 INFO - "builduid": "ee6c993604504537a011b94dcfbaba06", 18:59:35 INFO - "slavebuilddir": "test" 18:59:35 INFO - } 18:59:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:59:35 INFO - Running post-action listener: _resource_record_post_action 18:59:35 INFO - [mozharness: 2016-09-22 01:59:35.940017Z] Finished read-buildbot-config step (success) 18:59:35 INFO - [mozharness: 2016-09-22 01:59:35.940106Z] Running download-and-extract step. 18:59:35 INFO - Running pre-action listener: _resource_record_pre_action 18:59:35 INFO - Running main action method: download_and_extract 18:59:35 INFO - mkdir: /builds/slave/test/build/tests 18:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:35 INFO - trying https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:59:35 INFO - Downloading https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:59:36 INFO - Downloaded 1361 bytes. 18:59:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:59:36 INFO - Using the following test package requirements: 18:59:36 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'jsshell-linux-x86_64.zip'], 18:59:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:36 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:59:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:36 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:59:37 INFO - Expected file size: 20370724 18:59:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:59:41 INFO - Expected file size: 20370724 18:59:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:59:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:59:47 INFO - Expected file size: 14414059 18:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:47 INFO - trying https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:59:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:59:48 INFO - Downloaded 55651977 bytes. 18:59:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:59:48 INFO - mkdir: /builds/slave/test/properties 18:59:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:59:48 INFO - Writing to file /builds/slave/test/properties/build_url 18:59:48 INFO - Contents: 18:59:48 INFO - build_url:https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:59:49 INFO - Running post-action listener: _resource_record_post_action 18:59:49 INFO - Running post-action listener: set_extra_try_arguments 18:59:49 INFO - [mozharness: 2016-09-22 01:59:49.464371Z] Finished download-and-extract step (success) 18:59:49 INFO - [mozharness: 2016-09-22 01:59:49.464599Z] Running populate-webroot step. 18:59:49 INFO - Running pre-action listener: _resource_record_pre_action 18:59:49 INFO - Running main action method: populate_webroot 18:59:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:59:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:59:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:59:49 INFO - '--tppagecycles', 18:59:49 INFO - '7'], 18:59:49 INFO - 'tests': ['tsvgm']}}, 18:59:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:59:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:59:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:59:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:59:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['damp', 'tps']}, 18:59:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'tests': ['damp', 'tps']}, 18:59:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['dromaeo_dom']}, 18:59:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:59:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['basic_compositor_video']}, 18:59:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:59:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['a11yr', 18:59:49 INFO - 'ts_paint', 18:59:49 INFO - 'tpaint', 18:59:49 INFO - 'sessionrestore', 18:59:49 INFO - 'sessionrestore_no_auto_restore', 18:59:49 INFO - 'tabpaint']}, 18:59:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['tsvgx', 18:59:49 INFO - 'tsvgr_opacity', 18:59:49 INFO - 'tart', 18:59:49 INFO - 'tscrollx', 18:59:49 INFO - 'cart']}, 18:59:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:59:49 INFO - 'tsvgr_opacity', 18:59:49 INFO - 'tart', 18:59:49 INFO - 'tscrollx', 18:59:49 INFO - 'cart']}, 18:59:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'talos_options': ['--disable-e10s'], 18:59:49 INFO - 'tests': ['tp5o']}, 18:59:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'tests': ['tp5o']}, 18:59:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'talos_options': ['--disable-e10s', 18:59:49 INFO - '--xperf_path', 18:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:49 INFO - 'tests': ['tp5n']}, 18:59:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:49 INFO - 'talos_options': ['--xperf_path', 18:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:49 INFO - 'tests': ['tp5n']}}, 18:59:49 INFO - 'talos.zip': {'path': '', 18:59:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:59:49 INFO - Running post-action listener: _resource_record_post_action 18:59:49 INFO - [mozharness: 2016-09-22 01:59:49.483234Z] Finished populate-webroot step (success) 18:59:49 INFO - [mozharness: 2016-09-22 01:59:49.483323Z] Running create-virtualenv step. 18:59:49 INFO - Running pre-action listener: _resource_record_pre_action 18:59:49 INFO - Running main action method: create_virtualenv 18:59:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:59:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:59:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:59:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:59:49 INFO - Using real prefix '/usr' 18:59:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:59:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:59:52 INFO - Installing pip.................done. 18:59:52 INFO - Return code: 0 18:59:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:52 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:59:52 INFO - Using env: {'DISPLAY': ':0', 18:59:52 INFO - 'HOME': '/home/cltbld', 18:59:52 INFO - 'LANG': 'en_US.UTF-8', 18:59:52 INFO - 'LANGUAGE': 'en_US:en', 18:59:52 INFO - 'LOGNAME': 'cltbld', 18:59:52 INFO - 'MAIL': '/var/mail/cltbld', 18:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:52 INFO - 'MOZ_NO_REMOTE': '1', 18:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:52 INFO - 'NO_EM_RESTART': '1', 18:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:52 INFO - 'PWD': '/builds/slave/test', 18:59:52 INFO - 'SHELL': '/bin/bash', 18:59:52 INFO - 'SHLVL': '1', 18:59:52 INFO - 'TERM': 'linux', 18:59:52 INFO - 'TMOUT': '86400', 18:59:52 INFO - 'USER': 'cltbld', 18:59:52 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 18:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:52 INFO - '_': '/tools/buildbot/bin/python'} 18:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:59:52 INFO - Cleaning up... 18:59:52 INFO - Return code: 0 18:59:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:52 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:59:52 INFO - Using env: {'DISPLAY': ':0', 18:59:52 INFO - 'HOME': '/home/cltbld', 18:59:52 INFO - 'LANG': 'en_US.UTF-8', 18:59:52 INFO - 'LANGUAGE': 'en_US:en', 18:59:52 INFO - 'LOGNAME': 'cltbld', 18:59:52 INFO - 'MAIL': '/var/mail/cltbld', 18:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:52 INFO - 'MOZ_NO_REMOTE': '1', 18:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:52 INFO - 'NO_EM_RESTART': '1', 18:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:52 INFO - 'PWD': '/builds/slave/test', 18:59:52 INFO - 'SHELL': '/bin/bash', 18:59:52 INFO - 'SHLVL': '1', 18:59:52 INFO - 'TERM': 'linux', 18:59:52 INFO - 'TMOUT': '86400', 18:59:52 INFO - 'USER': 'cltbld', 18:59:52 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 18:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:52 INFO - '_': '/tools/buildbot/bin/python'} 18:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:52 INFO - Downloading/unpacking psutil>=3.1.1 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:59:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:54 INFO - Installing collected packages: psutil 18:59:54 INFO - Running setup.py install for psutil 18:59:54 INFO - building 'psutil._psutil_linux' extension 18:59:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:59:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:59:54 INFO - building 'psutil._psutil_posix' extension 18:59:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:59:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:59:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:54 INFO - Successfully installed psutil 18:59:54 INFO - Cleaning up... 18:59:55 INFO - Return code: 0 18:59:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:59:55 INFO - Using env: {'DISPLAY': ':0', 18:59:55 INFO - 'HOME': '/home/cltbld', 18:59:55 INFO - 'LANG': 'en_US.UTF-8', 18:59:55 INFO - 'LANGUAGE': 'en_US:en', 18:59:55 INFO - 'LOGNAME': 'cltbld', 18:59:55 INFO - 'MAIL': '/var/mail/cltbld', 18:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:55 INFO - 'MOZ_NO_REMOTE': '1', 18:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:55 INFO - 'NO_EM_RESTART': '1', 18:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:55 INFO - 'PWD': '/builds/slave/test', 18:59:55 INFO - 'SHELL': '/bin/bash', 18:59:55 INFO - 'SHLVL': '1', 18:59:55 INFO - 'TERM': 'linux', 18:59:55 INFO - 'TMOUT': '86400', 18:59:55 INFO - 'USER': 'cltbld', 18:59:55 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 18:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:55 INFO - '_': '/tools/buildbot/bin/python'} 18:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:59:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:59:57 INFO - Installing collected packages: mozsystemmonitor 18:59:57 INFO - Running setup.py install for mozsystemmonitor 18:59:57 INFO - Successfully installed mozsystemmonitor 18:59:57 INFO - Cleaning up... 18:59:57 INFO - Return code: 0 18:59:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:57 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:59:57 INFO - Using env: {'DISPLAY': ':0', 18:59:57 INFO - 'HOME': '/home/cltbld', 18:59:57 INFO - 'LANG': 'en_US.UTF-8', 18:59:57 INFO - 'LANGUAGE': 'en_US:en', 18:59:57 INFO - 'LOGNAME': 'cltbld', 18:59:57 INFO - 'MAIL': '/var/mail/cltbld', 18:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:57 INFO - 'MOZ_NO_REMOTE': '1', 18:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:57 INFO - 'NO_EM_RESTART': '1', 18:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:57 INFO - 'PWD': '/builds/slave/test', 18:59:57 INFO - 'SHELL': '/bin/bash', 18:59:57 INFO - 'SHLVL': '1', 18:59:57 INFO - 'TERM': 'linux', 18:59:57 INFO - 'TMOUT': '86400', 18:59:57 INFO - 'USER': 'cltbld', 18:59:57 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 18:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:57 INFO - '_': '/tools/buildbot/bin/python'} 18:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:57 INFO - Downloading/unpacking jsonschema==2.5.1 18:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:59:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:59:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:59:59 INFO - warning: no files found matching '*.txt' 18:59:59 INFO - no previously-included directories found matching 'build' 18:59:59 INFO - no previously-included directories found matching 'dist' 18:59:59 INFO - no previously-included directories found matching '.git*' 18:59:59 INFO - Installing collected packages: jsonschema, functools32 18:59:59 INFO - Running setup.py install for functools32 18:59:59 INFO - warning: no files found matching '*.txt' 18:59:59 INFO - no previously-included directories found matching 'build' 18:59:59 INFO - no previously-included directories found matching 'dist' 18:59:59 INFO - no previously-included directories found matching '.git*' 18:59:59 INFO - Successfully installed jsonschema functools32 18:59:59 INFO - Cleaning up... 19:00:00 INFO - Return code: 0 19:00:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:00:00 INFO - Using env: {'DISPLAY': ':0', 19:00:00 INFO - 'HOME': '/home/cltbld', 19:00:00 INFO - 'LANG': 'en_US.UTF-8', 19:00:00 INFO - 'LANGUAGE': 'en_US:en', 19:00:00 INFO - 'LOGNAME': 'cltbld', 19:00:00 INFO - 'MAIL': '/var/mail/cltbld', 19:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:00 INFO - 'MOZ_NO_REMOTE': '1', 19:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:00 INFO - 'NO_EM_RESTART': '1', 19:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:00 INFO - 'PWD': '/builds/slave/test', 19:00:00 INFO - 'SHELL': '/bin/bash', 19:00:00 INFO - 'SHLVL': '1', 19:00:00 INFO - 'TERM': 'linux', 19:00:00 INFO - 'TMOUT': '86400', 19:00:00 INFO - 'USER': 'cltbld', 19:00:00 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:00 INFO - '_': '/tools/buildbot/bin/python'} 19:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:00:00 INFO - Cleaning up... 19:00:00 INFO - Return code: 0 19:00:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:00 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:00 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 19:00:00 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 19:00:00 INFO - Using env: {'DISPLAY': ':0', 19:00:00 INFO - 'HOME': '/home/cltbld', 19:00:00 INFO - 'LANG': 'en_US.UTF-8', 19:00:00 INFO - 'LANGUAGE': 'en_US:en', 19:00:00 INFO - 'LOGNAME': 'cltbld', 19:00:00 INFO - 'MAIL': '/var/mail/cltbld', 19:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:00 INFO - 'MOZ_NO_REMOTE': '1', 19:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:00 INFO - 'NO_EM_RESTART': '1', 19:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:00 INFO - 'PWD': '/builds/slave/test', 19:00:00 INFO - 'SHELL': '/bin/bash', 19:00:00 INFO - 'SHLVL': '1', 19:00:00 INFO - 'TERM': 'linux', 19:00:00 INFO - 'TMOUT': '86400', 19:00:00 INFO - 'USER': 'cltbld', 19:00:00 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:00 INFO - '_': '/tools/buildbot/bin/python'} 19:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:00 INFO - Downloading/unpacking blobuploader==1.2.4 19:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:02 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 INFO - Downloading docopt-0.6.1.tar.gz 19:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:03 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:03 INFO - Running setup.py install for blobuploader 19:00:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:03 INFO - Running setup.py install for requests 19:00:03 INFO - Running setup.py install for docopt 19:00:03 INFO - Successfully installed blobuploader requests docopt 19:00:03 INFO - Cleaning up... 19:00:03 INFO - Return code: 0 19:00:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:03 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:03 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 19:00:03 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 19:00:03 INFO - Using env: {'DISPLAY': ':0', 19:00:03 INFO - 'HOME': '/home/cltbld', 19:00:03 INFO - 'LANG': 'en_US.UTF-8', 19:00:03 INFO - 'LANGUAGE': 'en_US:en', 19:00:03 INFO - 'LOGNAME': 'cltbld', 19:00:03 INFO - 'MAIL': '/var/mail/cltbld', 19:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:03 INFO - 'MOZ_NO_REMOTE': '1', 19:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:03 INFO - 'NO_EM_RESTART': '1', 19:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:03 INFO - 'PWD': '/builds/slave/test', 19:00:03 INFO - 'SHELL': '/bin/bash', 19:00:03 INFO - 'SHLVL': '1', 19:00:03 INFO - 'TERM': 'linux', 19:00:03 INFO - 'TMOUT': '86400', 19:00:03 INFO - 'USER': 'cltbld', 19:00:03 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:03 INFO - '_': '/tools/buildbot/bin/python'} 19:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:04 INFO - Running setup.py (path:/tmp/pip-rIHL1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:04 INFO - Running setup.py (path:/tmp/pip-moUlsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:04 INFO - Running setup.py (path:/tmp/pip-YoBvSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:04 INFO - Running setup.py (path:/tmp/pip-iervRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:04 INFO - Running setup.py (path:/tmp/pip-f4l9lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:04 INFO - Running setup.py (path:/tmp/pip-1IXqkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:04 INFO - Running setup.py (path:/tmp/pip-TXjVlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:04 INFO - Running setup.py (path:/tmp/pip-v7tQyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:04 INFO - Running setup.py (path:/tmp/pip-FXoI8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:04 INFO - Running setup.py (path:/tmp/pip-awoWFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:04 INFO - Running setup.py (path:/tmp/pip-7Rb8HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:04 INFO - Running setup.py (path:/tmp/pip-NT4Rel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:04 INFO - Running setup.py (path:/tmp/pip-fXYeqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:05 INFO - Running setup.py (path:/tmp/pip-_de5Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:05 INFO - Running setup.py (path:/tmp/pip-SIyNj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:05 INFO - Running setup.py (path:/tmp/pip-TKpF7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:05 INFO - Running setup.py (path:/tmp/pip-dKMQ6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:05 INFO - Running setup.py install for manifestparser 19:00:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:05 INFO - Running setup.py install for mozcrash 19:00:05 INFO - Running setup.py install for mozdebug 19:00:05 INFO - Running setup.py install for mozdevice 19:00:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:05 INFO - Running setup.py install for mozfile 19:00:05 INFO - Running setup.py install for mozhttpd 19:00:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:05 INFO - Running setup.py install for mozinfo 19:00:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for mozInstall 19:00:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for mozleak 19:00:06 INFO - Running setup.py install for mozlog 19:00:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for moznetwork 19:00:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for mozprocess 19:00:06 INFO - Running setup.py install for mozprofile 19:00:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for mozrunner 19:00:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:06 INFO - Running setup.py install for mozscreenshot 19:00:06 INFO - Running setup.py install for moztest 19:00:07 INFO - Running setup.py install for mozversion 19:00:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:07 INFO - Cleaning up... 19:00:07 INFO - Return code: 0 19:00:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:07 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 19:00:07 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 19:00:07 INFO - Using env: {'DISPLAY': ':0', 19:00:07 INFO - 'HOME': '/home/cltbld', 19:00:07 INFO - 'LANG': 'en_US.UTF-8', 19:00:07 INFO - 'LANGUAGE': 'en_US:en', 19:00:07 INFO - 'LOGNAME': 'cltbld', 19:00:07 INFO - 'MAIL': '/var/mail/cltbld', 19:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:07 INFO - 'MOZ_NO_REMOTE': '1', 19:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:07 INFO - 'NO_EM_RESTART': '1', 19:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:07 INFO - 'PWD': '/builds/slave/test', 19:00:07 INFO - 'SHELL': '/bin/bash', 19:00:07 INFO - 'SHLVL': '1', 19:00:07 INFO - 'TERM': 'linux', 19:00:07 INFO - 'TMOUT': '86400', 19:00:07 INFO - 'USER': 'cltbld', 19:00:07 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:07 INFO - '_': '/tools/buildbot/bin/python'} 19:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:07 INFO - Running setup.py (path:/tmp/pip-NWbBnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:07 INFO - Running setup.py (path:/tmp/pip-sScmI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:07 INFO - Running setup.py (path:/tmp/pip-VzbyEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:07 INFO - Running setup.py (path:/tmp/pip-C85DcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:07 INFO - Running setup.py (path:/tmp/pip-QPdPnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:07 INFO - Running setup.py (path:/tmp/pip-fEBf8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:07 INFO - Running setup.py (path:/tmp/pip-hkyIHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:07 INFO - Running setup.py (path:/tmp/pip-K5C36u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:07 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)) 19:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:07 INFO - Running setup.py (path:/tmp/pip-OZS65B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:08 INFO - Running setup.py (path:/tmp/pip-AYEw7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:08 INFO - Running setup.py (path:/tmp/pip-txCP8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:08 INFO - Running setup.py (path:/tmp/pip-ec5dKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:08 INFO - Running setup.py (path:/tmp/pip-GM2fBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:08 INFO - Running setup.py (path:/tmp/pip-IpLZgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:08 INFO - Running setup.py (path:/tmp/pip-x0T407-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:08 INFO - Running setup.py (path:/tmp/pip-4pZCcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:08 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)) 19:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:08 INFO - Running setup.py (path:/tmp/pip-EdJbEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:08 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)) 19:00:08 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)) 19:00:08 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)) 19:00:08 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)) 19:00:08 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)) 19:00:08 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)) 19:00:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:08 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 19:00:08 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 19:00:08 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 19:00:08 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 19:00:08 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 19:00:08 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 19:00:10 INFO - Downloading blessings-1.6.tar.gz 19:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:10 INFO - Installing collected packages: blessings 19:00:10 INFO - Running setup.py install for blessings 19:00:10 INFO - Successfully installed blessings 19:00:10 INFO - Cleaning up... 19:00:10 INFO - Return code: 0 19:00:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:10 INFO - Reading from file tmpfile_stdout 19:00:10 INFO - Current package versions: 19:00:10 INFO - argparse == 1.2.1 19:00:10 INFO - blessings == 1.6 19:00:10 INFO - blobuploader == 1.2.4 19:00:10 INFO - docopt == 0.6.1 19:00:10 INFO - functools32 == 3.2.3-2 19:00:10 INFO - jsonschema == 2.5.1 19:00:10 INFO - manifestparser == 1.1 19:00:10 INFO - mozInstall == 1.12 19:00:10 INFO - mozcrash == 0.17 19:00:10 INFO - mozdebug == 0.1 19:00:10 INFO - mozdevice == 0.48 19:00:10 INFO - mozfile == 1.2 19:00:10 INFO - mozhttpd == 0.7 19:00:10 INFO - mozinfo == 0.9 19:00:10 INFO - mozleak == 0.1 19:00:10 INFO - mozlog == 3.4 19:00:10 INFO - moznetwork == 0.27 19:00:10 INFO - mozprocess == 0.23 19:00:10 INFO - mozprofile == 0.28 19:00:10 INFO - mozrunner == 6.12 19:00:10 INFO - mozscreenshot == 0.1 19:00:10 INFO - mozsystemmonitor == 0.3 19:00:10 INFO - moztest == 0.7 19:00:10 INFO - mozversion == 1.4 19:00:10 INFO - psutil == 3.1.1 19:00:10 INFO - requests == 1.2.3 19:00:10 INFO - wsgiref == 0.1.2 19:00:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:10 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:10 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 19:00:10 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 19:00:10 INFO - Using env: {'DISPLAY': ':0', 19:00:10 INFO - 'HOME': '/home/cltbld', 19:00:10 INFO - 'LANG': 'en_US.UTF-8', 19:00:10 INFO - 'LANGUAGE': 'en_US:en', 19:00:10 INFO - 'LOGNAME': 'cltbld', 19:00:10 INFO - 'MAIL': '/var/mail/cltbld', 19:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:10 INFO - 'MOZ_NO_REMOTE': '1', 19:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:10 INFO - 'NO_EM_RESTART': '1', 19:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:10 INFO - 'PWD': '/builds/slave/test', 19:00:10 INFO - 'SHELL': '/bin/bash', 19:00:10 INFO - 'SHLVL': '1', 19:00:10 INFO - 'TERM': 'linux', 19:00:10 INFO - 'TMOUT': '86400', 19:00:10 INFO - 'USER': 'cltbld', 19:00:10 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:10 INFO - '_': '/tools/buildbot/bin/python'} 19:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 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)) 19:00:11 INFO - Cleaning up... 19:00:11 INFO - Return code: 0 19:00:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:11 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 0x7fb827d68f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fd9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, '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': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', '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 19:00:11 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 19:00:11 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 19:00:11 INFO - Using env: {'DISPLAY': ':0', 19:00:11 INFO - 'HOME': '/home/cltbld', 19:00:11 INFO - 'LANG': 'en_US.UTF-8', 19:00:11 INFO - 'LANGUAGE': 'en_US:en', 19:00:11 INFO - 'LOGNAME': 'cltbld', 19:00:11 INFO - 'MAIL': '/var/mail/cltbld', 19:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:11 INFO - 'MOZ_NO_REMOTE': '1', 19:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:11 INFO - 'NO_EM_RESTART': '1', 19:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:11 INFO - 'PWD': '/builds/slave/test', 19:00:11 INFO - 'SHELL': '/bin/bash', 19:00:11 INFO - 'SHLVL': '1', 19:00:11 INFO - 'TERM': 'linux', 19:00:11 INFO - 'TMOUT': '86400', 19:00:11 INFO - 'USER': 'cltbld', 19:00:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:11 INFO - '_': '/tools/buildbot/bin/python'} 19:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:11 INFO - Cleaning up... 19:00:11 INFO - Return code: 0 19:00:11 INFO - Running post-action listener: _resource_record_post_action 19:00:11 INFO - Running post-action listener: _start_resource_monitoring 19:00:11 INFO - Starting resource monitoring. 19:00:11 INFO - [mozharness: 2016-09-22 02:00:11.446850Z] Finished create-virtualenv step (success) 19:00:11 INFO - [mozharness: 2016-09-22 02:00:11.447308Z] Running install step. 19:00:11 INFO - Running pre-action listener: _resource_record_pre_action 19:00:11 INFO - Running main action method: install 19:00:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:11 INFO - Reading from file tmpfile_stdout 19:00:11 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:11 INFO - Reading from file tmpfile_stdout 19:00:11 INFO - Output received: 19:00:11 INFO - Usage: mozinstall [options] installer 19:00:11 INFO - Options: 19:00:11 INFO - -h, --help show this help message and exit 19:00:11 INFO - -d DEST, --destination=DEST 19:00:11 INFO - Directory to install application into. [default: 19:00:11 INFO - "/builds/slave/test"] 19:00:11 INFO - --app=APP Application being installed. [default: firefox] 19:00:11 INFO - mkdir: /builds/slave/test/build/application 19:00:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:00:27 INFO - Reading from file tmpfile_stdout 19:00:27 INFO - Output received: 19:00:27 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:27 INFO - Running post-action listener: _resource_record_post_action 19:00:27 INFO - [mozharness: 2016-09-22 02:00:27.807073Z] Finished install step (success) 19:00:27 INFO - [mozharness: 2016-09-22 02:00:27.807317Z] Running run-tests step. 19:00:27 INFO - Running pre-action listener: _resource_record_pre_action 19:00:27 INFO - Running main action method: run_tests 19:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:27 INFO - Python 2.7.3 19:00:27 INFO - Return code: 0 19:00:27 INFO - grabbing minidump binary from tooltool 19:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd6f60>, '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 0x1fd7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fd81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:00:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:00:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:00:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:27 INFO - Return code: 0 19:00:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 19:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 19:00:27 INFO - Using env: {'DISPLAY': ':0', 19:00:27 INFO - 'HOME': '/home/cltbld', 19:00:27 INFO - 'LANG': 'en_US.UTF-8', 19:00:27 INFO - 'LANGUAGE': 'en_US:en', 19:00:27 INFO - 'LOGNAME': 'cltbld', 19:00:27 INFO - 'MAIL': '/var/mail/cltbld', 19:00:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:27 INFO - 'MOZ_NO_REMOTE': '1', 19:00:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:27 INFO - 'NO_EM_RESTART': '1', 19:00:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:27 INFO - 'PWD': '/builds/slave/test', 19:00:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:27 INFO - 'SHELL': '/bin/bash', 19:00:27 INFO - 'SHLVL': '1', 19:00:27 INFO - 'TERM': 'linux', 19:00:27 INFO - 'TMOUT': '86400', 19:00:27 INFO - 'USER': 'cltbld', 19:00:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207', 19:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:27 INFO - '_': '/tools/buildbot/bin/python'} 19:00:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 19:00:28 INFO - mozversion application_buildid: 20160921173859 19:00:28 INFO - mozversion application_changeset: 3b8cf1e729ef97ed49808f980bed542b81a3631e 19:00:28 INFO - mozversion application_display_name: Nightly 19:00:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:28 INFO - mozversion application_name: Firefox 19:00:28 INFO - mozversion application_remotingname: firefox 19:00:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 19:00:28 INFO - mozversion application_vendor: Mozilla 19:00:28 INFO - mozversion application_version: 52.0a1 19:00:28 INFO - mozversion platform_buildid: 20160921173859 19:00:28 INFO - mozversion platform_changeset: 3b8cf1e729ef97ed49808f980bed542b81a3631e 19:00:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 19:00:28 INFO - mozversion platform_version: 52.0a1 19:00:28 INFO - using testdate: 1474509628 19:00:28 INFO - actual date: 1474509628 19:00:28 INFO - starting webserver on 'localhost:54070' 19:00:28 INFO - SUITE-START | Running 2 tests 19:00:28 INFO - TEST-START | dromaeo_css 19:00:28 INFO - operating with platform_type : linux_ 19:00:28 INFO - Initialising browser for dromaeo_css test... 19:00:28 INFO - TEST-INFO | started process 12747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8xCILS/profile http://localhost:54070/getInfo.html) 19:00:32 INFO - PROCESS | 12747 | __metrics Screen width/height:1600/1200 19:00:32 INFO - PROCESS | 12747 | colorDepth:24 19:00:32 INFO - PROCESS | 12747 | Browser inner width/height: 1024/697 19:00:32 INFO - PROCESS | 12747 | __metrics 19:00:33 INFO - PROCESS | 12747 | 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" 19:00:33 INFO - PROCESS | 12747 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:33 INFO - PROCESS | 12747 | console.error: PushService: 19:00:33 INFO - PROCESS | 12747 | stateChangeProcessEnqueue: Error transitioning state 19:00:33 INFO - PROCESS | 12747 | UnknownError 19:00:34 INFO - TEST-INFO | 12747: exit 0 19:00:34 INFO - Browser initialized. 19:00:34 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:34 INFO - TEST-INFO | started process 12826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8xCILS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:46 INFO - PROCESS | 12826 | RSS: Main: 177348608 19:00:46 INFO - PROCESS | 12826 | 19:01:38 INFO - PROCESS | 12826 | 1474509698355 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] 19:03:50 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54070/tests/dromaeo/cssquery-ext.html) 19:03:51 INFO - PROCESS | 12826 | RSS: Main: 249466880 19:03:51 INFO - PROCESS | 12826 | 19:06:54 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-ext.html (next: http://localhost:54070/tests/dromaeo/cssquery-jquery.html) 19:06:54 INFO - PROCESS | 12826 | RSS: Main: 274915328 19:06:54 INFO - PROCESS | 12826 | 19:09:57 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54070/tests/dromaeo/cssquery-mootools.html) 19:09:57 INFO - PROCESS | 12826 | RSS: Main: 220504064 19:09:57 INFO - PROCESS | 12826 | 19:13:00 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54070/tests/dromaeo/cssquery-prototype.html) 19:13:00 INFO - PROCESS | 12826 | RSS: Main: 221769728 19:13:00 INFO - PROCESS | 12826 | 19:16:03 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54070/tests/dromaeo/cssquery-yui.html) 19:16:04 INFO - PROCESS | 12826 | RSS: Main: 222334976 19:16:04 INFO - PROCESS | 12826 | 19:19:07 INFO - PROCESS | 12826 | Cycle 1(1): loaded http://localhost:54070/tests/dromaeo/cssquery-yui.html (next: http://localhost:54070/tests/dromaeo/cssquery-dojo.html) 19:19:08 INFO - PROCESS | 12826 | RSS: Main: 295727104 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | __start_tp_report 19:19:08 INFO - PROCESS | 12826 | _x_x_mozilla_page_load 19:19:08 INFO - PROCESS | 12826 | _x_x_mozilla_page_load_details 19:19:08 INFO - PROCESS | 12826 | |i|pagename|runs| 19:19:08 INFO - PROCESS | 12826 | |0;dojo.html;2577.8839952202147;2597.5194589001026;2641.682998040235;2642.947141057179;2650.4036591766844;4587.977060114703;4670.90658186836;4723.740194289313;4732.361131247285;4733;4666.953330466695;4676.97661511692;4684.601808846243;4688.32019357193;4701.130290896187;4237.576242375753;4260.510041345238;4263.9147217055715;4281.914361712764;4290.592393722607;4246.766427846467;4285.121550082225;4319.848805291816;4322.13557288542;4336.869893903188;414.87603792647405;4317.848875289365;4324.459442569679;4363.672724545656;4374.037711703436;4256.44666193396;4276.593723596254;4302.075053863403;4313.633341165973;4359.978200109011;4286.464191976003;4301.268784306675;4302.161078589676;4309.913801723948;4350.32569951658;4579.244424669925;4686.929696054562;4694.2723877799;4707.270373092176;4747.715137091751;4936.679115857458;4962.801487940478;4983.07813054586;5016.3980322361185;5023.422306434733;5029.849104526834;5032.773525191338;5051.696898186084;5055;5100.438951715309;4721.858344249678;4723.433188017391;4725.267583524559;4775.665703400763;4783.497732738012;5015.573676237561;5055.216441451581;5062.088824011713;5115.130427827278;5127.820526281637;4845.53967373106;4902.7058376497525;4921.7785199666105;4924.901501969941;4957.256411538298;4921.409430868319;4965.3048573199785;4967;4976.178930476431;5026.472220416804;4932.926006109911;4933.260010998307;4936.777844996984;4994.650374473752;4995.050940321327;4864.197407427806;4897.8285759998225;4918.262260660929;4926.1379258629595;4928.457869634337;4237.470316210473;4252.234597772431;4260.723053001545;4275.636570891509;4314.352847072964;3696.9815150924073;3701.8704345347783;3702.8703995360033;3736.420854767461;3746.5316835395574;4251.383549385322;4293.806778694966;4381.496127945264;4387.407699960529;4424.181526417623;4760.904781904343;5017.724025178651;5062.670926389773;5063.240513922868;5095.694258344512;4385.890352741207;4390.670699697535;4390.714603550759;4391.297392417262;4445.333200020023;4459.241928872099;4577.771111444415;4578.130155270488;4581.175388430114;4624.491305956342;4711.316859055486;4745.5966242869745;4798.1843086676045;4804.927926081042;4828.686135401188;5030.2957585937265;5032.295478633067;5034.521720436553;5077.339945807022;5086.491350865032;4776.5701086902345;4779.856604301876;4789.185838407409;4789.544993225638;4846.539578740014;4827;4877.024595081069;4914.410270767531;4932.551580382391;4934.358533390637;4990.875228119181;5023.623228257969;5029.346184995927;5082.415522214993;5086.872828179326;4874.195757700081;4881.145799485005;4883.560479556857;4890.853274401774;4954.677945933503;4734.384530011076;4781.760911954458;4873.9025219496125;4880.267959806058;4947.579455746302;4753.334533165429;4779.641526885567;4790.113828941657;4805.27920811881;4847.200211964986;4830.154722923545;4873.561379475864;4916.115099282163;4916.655834091579;4982.252662100714;4754.833580824655;4770.165221086227;4793.23308270675;4811.061842940593;4822.276658501253 19:19:08 INFO - PROCESS | 12826 | |1;ext.html;553.8421549858289;556.679909052295;561.3656568078072;562.2746656812711;573.7246121861507;27221.863890680543;42133.578664213346;43625.127497450034;43660;43699.12601747963;40078;40464.595354046454;40629.59370406295;40672.38991415124;40687.79656101715;7706;7837.608119594026;7839.372850172001;7843.803904902366;7844.058712954425;7208.927910720904;7434.070741157356;9785.755356116082;10037.447940363278;10050.648227312045;10088.546015429323;10116.94941525295;10121.139703125207;10129.088382045615;10174.44040577768;9981.351212171221;10066.597336106546;10073.043060909238;10073.546690398949;10080.092791648787;9948.154406875352;10018.899811001942;10023.69928902134;10026.097651211427;10027.849582256273;37475.940747998946;37743.245135097146;37819.810900945595;37826.05434864123;37900.24199516022;2827.5617279321737;2981.8807247710065;3001.9099427017004;3024.107888172994;3057.7248047675594;28900.277493062633;29218.561721574195;29350.91815359378;29601.70398296011;30799;3250.902472925792;3376.763626546143;3511.6839484446523;3563.1448452371505;3630.8910732678064;264138;264780;265098;266612;266900.9999999981;30705.69294307073;31769.20576985594;31776.523352149736;52971.93533228674;53944.460555394726;33093.83453082719;33146.3370732584;33150.66849331524;33328;33344.1663958403;13320.733585328433;13463.12489688167;13484.39320230592;13487.865121348857;13489.190648561118;9684.370515916444;11142.442877856074;11252.26860254081;11258.718532036764;11280.887191127984;7218.8917166242545;7485.663145158481;7773.650185741655;7801.414893882981;7808.375329973588;3025.1529571719952;3071.78497505177;3089.443900097959;3090.2892334762682;3095.1024202980884;838.5974732128612;853.4495250563352;890.8485557455248;906.4742449379344;913.8537833946536;26993.460130797666;27949.16152515428;28166.71833281641;28227.57658635122;28567.57148642772;921.166344458253;939.1172298039821;971.5336638413601;1023.3603997501561;1046.3669479964665;14342.139471631735;14840.40638374453;14932.432094630125;14977.400903963937;15095.622609434851;382292;385692;387419;387696;389199;48655.75672121617;49102.75448622734;49204.50795492071;49257;49321.260181097314;1029.6550655530332;1039.3140527251978;1173.2491205628235;1185.0282768130223;1189.5241903238773;26960.730392696605;29100.563491547222;29703.554446682883;31663.36673266568;32164.356712865145;46129.76935115303;46665.3000205004;46668.7666561665;46749.53250467452;46757;341446;345190;345672;345916;345956;24383;24896.62655060139;25068.373290667878;25321.113761018278;27659.723402765714;33225.335493289516;41613.37579936243;41918.58081419147;42264.1547169061;45701.77149114233;35641.28717425689;35789;35942.8202858984;36068.819655901556;36724.63275367319;36105.277894442486;36131.612686060325;36315.27369452649;36535.26929461343;37278;35067.47398789071;35683.643163568035;35987.46018809667;36188.27623447569;36220.55904689051;31175;32849;33112.834435827666;33445;33690;24663.260102196964;24795.256142315036;24900.12849550257;24927.501449971263;25004.249872503853 19:19:08 INFO - PROCESS | 12826 | |2;jquery.html;368.73451115197065;372.2443439817171;374.1936127644925;379.0864017717302;398.8823297127347;15447.613809654764;16609.169541522922;17208.999999999985;17317.22072506737;17322.13389330535;16092.75860862088;16365.099919504422;16703.164841757894;16789.748153777702;16848.83151168488;4840.903181936359;5078.21287700407;5079.161938280179;5079.415867175267;5083.262926875601;3638.472421498881;3815.6184381561898;4898.314236006961;5551.500364967153;5777.711114444282;5860.706964651772;5868.354481007086;5873.353931067579;5877.441643043904;5878.177055212273;5660.490555849972;5791.07342825148;5815.680137592431;5816.941830581683;5820.35976042635;4991.550760431543;5144.459831717691;5152.330197074394;5167.741612919339;5172.353455818023;19839.404817855488;20104.899475502676;20139.89930050355;20224.292149774836;20479;1885.6605810954022;1939.7284380186786;1983.871048381865;2060.6393881070753;2437.7805997460136;13051.477940882352;13082.672933176651;13241.139325943783;13585.116967397187;13965.86034139666;1655.6854197702519;1670.682570311637;1699.133441944617;1702.9914850425669;1715.1938588863213;69379.65310173467;69488;69679.3032069683;70530.64734676345;72707;9100.590473428712;9313;9401.341906066646;9671.371360861522;9715;11601;11618.128640352008;11641.6507504775;11643.941780291045;11652.184347095623;6930.861382772317;7044.506884518035;7144.642767861586;7168.319009694071;7189.0294810201;6759.864802704017;6860.588364698134;6978.406835418945;7217.783466496014;7253.202147763742;5670.8015219467125;5731.340895796953;5742.511886489611;5764.510016648632;5850.414958504116;1519.3542744333613;1562.6484041090891;1603.1022627328732;1622.3997121065045;1670.1315316035593;837.949723016621;858.1718641510972;874;887.4586502233632;923.3767207135157;16574.502764917073;16703.248353824107;17231.741523877154;17610.64778704419;17891.73162402565;845.8815765792793;877.3726785348379;886.8980067292345;905.524599585223;906.8911730592371;8186.754397368089;10039.949800250952;10066.194704423628;10137.746556336151;10263;402273;404279;412313;418256;425486;71407.64296178486;78868;81752;81865.59067204625;82996;831.1107115386478;917.3899356927702;927.0914503786328;969.0503306759415;971.1647982734843;12298;14847.40610375573;15395.769063463833;16268.430604928772;16686;38106.61893381141;38987.79487434415;39358.212835743696;40514.39228411517;40872.59127408807;53425.73287133695;54951.17573236485;56002.71998639981;56322.1551676717;56846.43153568577;7101.289871013064;7145.964270178823;7189.388901943394;7311.41508679315;7315.3781928534545;8144.859719639374;11293.37886416255;11731.41342932867;11898.76202475963;12303.630891073282;9734.318597698093;10115.544800483849;10542.52558634848;10729.570817167227;10954.945225273823;9785.461799601091;10164.542595583365;10196.286259961731;10221.131203847755;10350.689479315633;9837.557309921214;9995.800083998425;10067.899321006696;10132.13876820449;10191.337563058378;7115.893261600977;7175.390091842252;7388.593627350548;7437.181909990005;7891.723789667334;8938.329625278;10286.537105830404;10290.691279261635;10414.583416663248;10527.420991845216 19:19:08 INFO - PROCESS | 12826 | |3;mootools.html;1060.7560261139938;1080.1682704317675;1147.334545963341;1445.4073829729807;1640.8195098539165;6728.730850765964;6923.273056329089;7347.448941329401;7976.9202307976975;7981.600919953994;7516;7823.687052517893;7825.882611760814;7829.334506566948;7849.921500784991;2139.454439118023;2157.9568408631817;2178.880161591112;2182.0835249195297;2188.2122435923056;1844.7417361569392;1909.7326374307609;2148.2266384101713;2185.912563497458;2207.86752794832;1867.8599105067105;1887.7451544041585;2070.534129820793;2143.47484866208;2148.0977989244475;2117.6505876530355;2125.8511904166717;2127.87232766033;2136.305700647296;2139.155033761671;2076.2214169686367;2142.903569339368;2149.3981685128188;2152.1068756466047;2154.1814110637856;7742.264484873928;7918.841623167562;7919.208079192093;7922.168172341879;7922.564258965755;4509.864704058883;4628.467726211461;4640.118377508263;4641.002184530308;4646.4888862225125;2868.4119755450083;2946.175070980129;2973.7323640872423;2982.0606508949613;2986.8058576192693;947.8672985781927;1021.509675355826;1038.8285932821152;1053.4048262731533;1057.4184198690689;10837.241393102486;10848.18638602108;10858.565657373696;10864.293820901616;10875.510602022849;3848.5189351331082;4036.0717035082093;4135.359019352005;4153.7300075494995;4167.937480937789;9176.49529275883;9183.127602877716;9189.586468608928;9192.034836342222;9193.448393096436;4466.441694788152;4501.887452813706;4502.3921770560555;4507.918099656059;4514.954850451519;4036.656884164879;4102.794860257035;4129.628333450004;4130.277201489727;4131.669466442678;3658.579263384745;3767.6609105180664;3773.339665558516;3809.5428548573796;3915.4126880967515;1195.4022988505747;1211.2248161176854;1214.3060430699124;1224.7856625090574;1226.1233218248994;724.0840336973732;729.1723893381051;735.1252010107954;753.600591686407;760.9010828592247;4986.80052797884;5096.031753966735;5162.845114646567;5201.713905735145;5285.63000589955;786.5045021636447;877.8990416102102;893.3612467086065;893.7497500699815;901.9233365164034;3491.6682915123024;3527.470879368115;3585.229175727231;3602.4956506088643;3681.4109742441083;9284.3036772241;9655.468949207861;9656.613735450645;9657.22742180624;9666.854997175047;9507.334486585873;9543.522823858779;9575.712728618153;9644.469554174588;9649.565769540388;7239.276072392719;7345.77962661121;7351.154617218981;7352.816179595553;7371.410287177013;4973.428055773632;5112.156494178567;5132.46109158533;5149.201873709581;5340.893182136258;8935.865962010705;8942.46345219289;8975.057618950177;9005.973655927364;9086.500242486727;8573.74278771638;8978.326625502983;8986.505742184243;9065.77335566616;9088.54557272147;6484.351564843667;6611.140551728437;6641;6643;6646.8005959821285;7223.458240631869;7243.674034668347;7243.7464688735645;7255.347018768336;7256.854862902606;6337;6542.378474044958;6559.508036897348;6607.57050791697;6638.435732962752;6281.309056003927;6346.873062538631;6350.174477317919;6374;6392.744290228525;6032.849178770566;6109.7861574844665;6111.358307377839;6115.938840611537;6122.755089796537;4535.659825513034;4545.636349092131;4569.405977222939;4619.376384188092;4634.536546345338;4396.208682437064;4492.595666390041;4525.230710779109;4531.729568664864;4560.5895469407915 19:19:08 INFO - PROCESS | 12826 | |4;prototype.html;255.39981044545308;278.2931354359926;285.43911214463577;285.44908326929027;289.93621403291274;4621.8151273949;4710.76446177691;4844.6019220485905;4853.199222128344;4857.372780118664;4754.334393184957;4767.833125840596;4769.379980602525;4772.832950846721;4773.498782627827;3597.8201089945464;3716.6991552956383;3755.3052685253315;3763.322601931651;3779.9433008504893;3765.341065313573;3769.6607305342513;3774.9433758493647;3775.169462718198;3790.2987947229713;3638.2905333459985;3746.2882052409955;3754.718396120288;3772.7170462215445;3780.451834483998;3763.2285381496727;3763.9811800941093;3785.8296376663116;3792.393217085253;3800.239952009609;3355.4966754986704;3355.781874178195;3359.5296658467832;3361.764676472648;3366.7138293245102;4711.7879695413785;4715.952840471586;4717.834875779366;4740.668153229275;4757.61939044879;4070.5115386153852;4266.87199384019;4337.869863904088;4353.804078816461;4365.236083685374;5049.444561098277;5075.56857667095;5077.669951453181;5082.364704411949;5086.21163719624;3667.853285868562;3677.669009789132;3695.4826324314618;3696.242270334575;3696.5933747287777;5099.541041306263;5271.894562108736;5288.286081379041;5336.946630533723;5346.331708536433;3720.739548231652;3723.404255319136;3725.5343082114737;3733.75730577517;3747.163889694085;3810.2760475509563;3816.2939856126704;3818.789966551866;3822.4266360046217;3825.540935087807;4067.4102255172834;4104.302268614343;4117.155983023473;4330.653547716175;4353.586409291112;4922.384701912261;4960.578350840146;4964.801407943714;4986.229888389569;4997.550220480174;1285.222440423549;1298.577962162301;1299.693807723232;1301.0892375337303;1303.2050449225965;807.7423601035003;845.6152450634946;851.2126283188027;867.8177582707702;869.278498845969;4024.577419370924;4035.7174997750462;4040.4545386372465;4048.5344185419053;4050.9392359114636;4569.54304569547;4572.862814115582;4610.354550362952;4615.723056616614;4633.536646335339;4026.63760261572;4058.736182148151;4061.9593804062174;4066.369712694537;4077.5310839253866;2452.755728508395;2558.8976440942524;2753.3116720819794;2778.360976975307;2783.4293969736154;4891.3396691446915;4908.558229759267;4909.975450122726;4928.531789479994;4957.380327459067;5343.786248550092;5350.170723537858;5367.570594352443;5372.435894231049;5373.489518495737;4719.315699223594;4964.90070198594;4972.279019542146;4988.950110498994;4991.750412479361;5218.0346635870965;5224.817131400383;5229.450907654642;5251.396089449762;5255.658382205144;4859.757012149449;5129.307328581634;5163.380394352552;5174.223866420067;5175.197844334134;4943.2585112233455;4944.307796908363;4945.579625731853;4950;4959.851204463727;3567.6075631681015;3577.9284414312087;3589.4436362363876;3606.647507184806;3611.331903597843;4935.4570997190995;5005.6996580205305;5221.556167725786;5237.476252374732;5271.28683177961;5019.974900125476;5029.899402012013;5046.4196617388;5052.621053420935;5064.34163558735;5039.513343563584;5046.318746969259;5048.444671086104;5064.265681476239;5086.669366491166;4744.810207591796;4914.213725803854;4945.357103576512;4974.104661161025;4975.726335051462;5033.597312214941;5039.647224694236;5047.722375269249;5048;5068.5807973767405;4919.803207871645;4928.802847886044;4945.876353091202;4947.183714687037;4995.450500444876 19:19:08 INFO - PROCESS | 12826 | |5;yui.html;636.5734957578422;693.4071368979519;695.9965200173998;727.9008696867731;737.8930055142006;13223.338833058357;14723.558293251206;14742.705145897076;14778.778318325207;14831.925840370797;13213;13921.1647301162;14036.859631403682;14067.226302553356;14101.083429577071;742.9182789893121;762.0893032825776;763.8777795552712;764.0411283838774;764.7323436797133;551.6689986008383;596.6718304932292;611.0193140010286;737.36218171282;757.1066141952493;803.4857691077715;806.9596520173997;807.725373373053;807.9232472915064;808.0626473290984;798.0662624729052;798.8282519258387;798.8402319536116;798.8801679748026;798.9400794940402;457.63389288569346;464.5331441900902;563.8844036972451;577.6995962099685;583.12822330616;13467.797983030165;13504.257265850472;13507.25710085945;13508.662283442894;13511.66220844477;11538.192326537146;11968.162228643998;12067;12080.516779328816;12184;11593.478293476814;11646;11653.242539235007;11758.353290569015;11843.763124737543;3893.922121557553;3942.3889297158985;3946.52641682997;3970.2258059678375;3980.8805735827973;66024;67391.32608673899;70691;70730.64634676794;70819.2918070823;9167.908320916706;9276.350655454053;9399.812003759887;9433.05669433065;9528.285378596633;16308.429204978007;16359;16384.918075409547;16386.426475073316;16389.180540972902;6410.326915673881;6434.324395938453;6550.443212327005;6576.276609572943;6912.55068420551;3663.816809159531;3676.227992121631;3693.8891833245048;3694.4273637586216;3722.6091260417265;2681;2753.600727894444;2754.504189245915;2765.681946576129;2779.1245757586294;504.6593549354171;506.5112166759095;507.3809951858726;511.5293929584791;521.507175718942;404.95747946465787;409.60063937660544;417.97492150471066;438.3009100484722;537.854779209619;7587.241275872479;8205.917940820635;8257.710980115668;8496.830063398698;8530.914690853013;434.0927461605197;508.5169089365118;517.6764522173642;547.1246006389745;562.9549636029108;3543.6633519815578;3685.90785230366;3730.1607138393642;3888.51393575803;3983.342748446532;99229.50385248172;103416.48291758493;103867.48066259621;107461.46269268604;108099.45950270355;52503;56249.7187514068;69263.30736692568;70978.2902170982;96059.51970240104;44917;71280.2871971284;71290;72362;74632.62683686655;7970.960145199237;8711.303095752452;9031.683891064044;9070.092990700878;9287.396319239228;44995;45492.54507455016;46549.53450465587;47341.52658473509;48293.75853120852;44460.333095002956;53726.46273537371;55093;65143.348566515626;66785.66607166933;9781.315307928662;11325.207235493437;11350.772984540428;11521.078313734713;11864.644060678194;12811.80782288285;12898.226106433644;13898.861011390161;13999.580012599637;14166.716665666836;11679.240849344766;12140.02879769634;12536.05979551519;12663.746725065632;12785.680357990752;11338.489767960627;11485.820921380346;11778.411079445823;11842.881571184522;12158.93920530427;11440.199186056896;11794.174407791372;11880.940595296968;11991.940040299743;12567.245965242115;9434.952825236105;9869.555869986012;9948.950255248677;10050.899491004997;10136.746581335525;9096.727098187064;9460.905390946002;9499.857502137611;10184.279879098924;10202.314727277908 19:19:08 INFO - PROCESS | 12826 | __end_tp_report 19:19:08 INFO - PROCESS | 12826 | __start_cc_report 19:19:08 INFO - PROCESS | 12826 | _x_x_mozilla_cycle_collect,1259 19:19:08 INFO - PROCESS | 12826 | __end_cc_report 19:19:08 INFO - PROCESS | 12826 | __startTimestamp1474510748020__endTimestamp 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | ------- Summary: start ------- 19:19:08 INFO - PROCESS | 12826 | Number of tests: 6 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#0] dojo.html Cycles:165 Average:4609.64 Median:4779.64 stddev:579.21 (12.1%) stddev-sans-first:558.62 19:19:08 INFO - PROCESS | 12826 | Values: 2577.9 2597.5 2641.7 2642.9 2650.4 4588.0 4670.9 4723.7 4732.4 4733.0 4667.0 4677.0 4684.6 4688.3 4701.1 4237.6 4260.5 4263.9 4281.9 4290.6 4246.8 4285.1 4319.8 4322.1 4336.9 414.9 4317.8 4324.5 4363.7 4374.0 4256.4 4276.6 4302.1 4313.6 4360.0 4286.5 4301.3 4302.2 4309.9 4350.3 4579.2 4686.9 4694.3 4707.3 4747.7 4936.7 4962.8 4983.1 5016.4 5023.4 5029.8 5032.8 5051.7 5055.0 5100.4 4721.9 4723.4 4725.3 4775.7 4783.5 5015.6 5055.2 5062.1 5115.1 5127.8 4845.5 4902.7 4921.8 4924.9 4957.3 4921.4 4965.3 4967.0 4976.2 5026.5 4932.9 4933.3 4936.8 4994.7 4995.1 4864.2 4897.8 4918.3 4926.1 4928.5 4237.5 4252.2 4260.7 4275.6 4314.4 3697.0 3701.9 3702.9 3736.4 3746.5 4251.4 4293.8 4381.5 4387.4 4424.2 4760.9 5017.7 5062.7 5063.2 5095.7 4385.9 4390.7 4390.7 4391.3 4445.3 4459.2 4577.8 4578.1 4581.2 4624.5 4711.3 4745.6 4798.2 4804.9 4828.7 5030.3 5032.3 5034.5 5077.3 5086.5 4776.6 4779.9 4789.2 4789.5 4846.5 4827.0 4877.0 4914.4 4932.6 4934.4 4990.9 5023.6 5029.3 5082.4 5086.9 4874.2 4881.1 4883.6 4890.9 4954.7 4734.4 4781.8 4873.9 4880.3 4947.6 4753.3 4779.6 4790.1 4805.3 4847.2 4830.2 4873.6 4916.1 4916.7 4982.3 4754.8 4770.2 4793.2 4811.1 4822.3 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#1] ext.html Cycles:180 Average:47528.07 Median:33567.50 stddev:88619.01 (264.0%) stddev-sans-first:88797.43 19:19:08 INFO - PROCESS | 12826 | Values: 553.8 556.7 561.4 562.3 573.7 27221.9 42133.6 43625.1 43660.0 43699.1 40078.0 40464.6 40629.6 40672.4 40687.8 7706.0 7837.6 7839.4 7843.8 7844.1 7208.9 7434.1 9785.8 10037.4 10050.6 10088.5 10116.9 10121.1 10129.1 10174.4 9981.4 10066.6 10073.0 10073.5 10080.1 9948.2 10018.9 10023.7 10026.1 10027.8 37475.9 37743.2 37819.8 37826.1 37900.2 2827.6 2981.9 3001.9 3024.1 3057.7 28900.3 29218.6 29350.9 29601.7 30799.0 3250.9 3376.8 3511.7 3563.1 3630.9 264138.0 264780.0 265098.0 266612.0 266901.0 30705.7 31769.2 31776.5 52971.9 53944.5 33093.8 33146.3 33150.7 33328.0 33344.2 13320.7 13463.1 13484.4 13487.9 13489.2 9684.4 11142.4 11252.3 11258.7 11280.9 7218.9 7485.7 7773.7 7801.4 7808.4 3025.2 3071.8 3089.4 3090.3 3095.1 838.6 853.4 890.8 906.5 913.9 26993.5 27949.2 28166.7 28227.6 28567.6 921.2 939.1 971.5 1023.4 1046.4 14342.1 14840.4 14932.4 14977.4 15095.6 382292.0 385692.0 387419.0 387696.0 389199.0 48655.8 49102.8 49204.5 49257.0 49321.3 1029.7 1039.3 1173.2 1185.0 1189.5 26960.7 29100.6 29703.6 31663.4 32164.4 46129.8 46665.3 46668.8 46749.5 46757.0 341446.0 345190.0 345672.0 345916.0 345956.0 24383.0 24896.6 25068.4 25321.1 27659.7 33225.3 41613.4 41918.6 42264.2 45701.8 35641.3 35789.0 35942.8 36068.8 36724.6 36105.3 36131.6 36315.3 36535.3 37278.0 35067.5 35683.6 35987.5 36188.3 36220.6 31175.0 32849.0 33112.8 33445.0 33690.0 24663.3 24795.3 24900.1 24927.5 25004.2 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#2] jquery.html Cycles:180 Average:25236.94 Median:4869.61 stddev:68127.89 (1399.0%) stddev-sans-first:68293.42 19:19:08 INFO - PROCESS | 12826 | Values: 368.7 372.2 374.2 379.1 398.9 15447.6 16609.2 17209.0 17317.2 17322.1 16092.8 16365.1 16703.2 16789.7 16848.8 4840.9 5078.2 5079.2 5079.4 5083.3 3638.5 3815.6 4898.3 5551.5 5777.7 5860.7 5868.4 5873.4 5877.4 5878.2 5660.5 5791.1 5815.7 5816.9 5820.4 4991.6 5144.5 5152.3 5167.7 5172.4 19839.4 20104.9 20139.9 20224.3 20479.0 1885.7 1939.7 1983.9 2060.6 2437.8 13051.5 13082.7 13241.1 13585.1 13965.9 1655.7 1670.7 1699.1 1703.0 1715.2 69379.7 69488.0 69679.3 70530.6 72707.0 9100.6 9313.0 9401.3 9671.4 9715.0 11601.0 11618.1 11641.7 11643.9 11652.2 6930.9 7044.5 7144.6 7168.3 7189.0 6759.9 6860.6 6978.4 7217.8 7253.2 5670.8 5731.3 5742.5 5764.5 5850.4 1519.4 1562.6 1603.1 1622.4 1670.1 837.9 858.2 874.0 887.5 923.4 16574.5 16703.2 17231.7 17610.6 17891.7 845.9 877.4 886.9 905.5 906.9 8186.8 10039.9 10066.2 10137.7 10263.0 402273.0 404279.0 412313.0 418256.0 425486.0 71407.6 78868.0 81752.0 81865.6 82996.0 831.1 917.4 927.1 969.1 971.2 12298.0 14847.4 15395.8 16268.4 16686.0 38106.6 38987.8 39358.2 40514.4 40872.6 53425.7 54951.2 56002.7 56322.2 56846.4 7101.3 7146.0 7189.4 7311.4 7315.4 8144.9 11293.4 11731.4 11898.8 12303.6 9734.3 10115.5 10542.5 10729.6 10954.9 9785.5 10164.5 10196.3 10221.1 10350.7 9837.6 9995.8 10067.9 10132.1 10191.3 7115.9 7175.4 7388.6 7437.2 7891.7 8938.3 10286.5 10290.7 10414.6 10527.4 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#3] mootools.html Cycles:180 Average:5082.32 Median:5041.42 stddev:2880.95 (57.1%) stddev-sans-first:2873.18 19:19:08 INFO - PROCESS | 12826 | Values: 1060.8 1080.2 1147.3 1445.4 1640.8 6728.7 6923.3 7347.4 7976.9 7981.6 7516.0 7823.7 7825.9 7829.3 7849.9 2139.5 2158.0 2178.9 2182.1 2188.2 1844.7 1909.7 2148.2 2185.9 2207.9 1867.9 1887.7 2070.5 2143.5 2148.1 2117.7 2125.9 2127.9 2136.3 2139.2 2076.2 2142.9 2149.4 2152.1 2154.2 7742.3 7918.8 7919.2 7922.2 7922.6 4509.9 4628.5 4640.1 4641.0 4646.5 2868.4 2946.2 2973.7 2982.1 2986.8 947.9 1021.5 1038.8 1053.4 1057.4 10837.2 10848.2 10858.6 10864.3 10875.5 3848.5 4036.1 4135.4 4153.7 4167.9 9176.5 9183.1 9189.6 9192.0 9193.4 4466.4 4501.9 4502.4 4507.9 4515.0 4036.7 4102.8 4129.6 4130.3 4131.7 3658.6 3767.7 3773.3 3809.5 3915.4 1195.4 1211.2 1214.3 1224.8 1226.1 724.1 729.2 735.1 753.6 760.9 4986.8 5096.0 5162.8 5201.7 5285.6 786.5 877.9 893.4 893.7 901.9 3491.7 3527.5 3585.2 3602.5 3681.4 9284.3 9655.5 9656.6 9657.2 9666.9 9507.3 9543.5 9575.7 9644.5 9649.6 7239.3 7345.8 7351.2 7352.8 7371.4 4973.4 5112.2 5132.5 5149.2 5340.9 8935.9 8942.5 8975.1 9006.0 9086.5 8573.7 8978.3 8986.5 9065.8 9088.5 6484.4 6611.1 6641.0 6643.0 6646.8 7223.5 7243.7 7243.7 7255.3 7256.9 6337.0 6542.4 6559.5 6607.6 6638.4 6281.3 6346.9 6350.2 6374.0 6392.7 6032.8 6109.8 6111.4 6115.9 6122.8 4535.7 4545.6 4569.4 4619.4 4634.5 4396.2 4492.6 4525.2 4531.7 4560.6 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#4] prototype.html Cycles:180 Average:4157.82 Median:4729.99 stddev:1211.38 (25.6%) stddev-sans-first:1178.84 19:19:08 INFO - PROCESS | 12826 | Values: 255.4 278.3 285.4 285.4 289.9 4621.8 4710.8 4844.6 4853.2 4857.4 4754.3 4767.8 4769.4 4772.8 4773.5 3597.8 3716.7 3755.3 3763.3 3779.9 3765.3 3769.7 3774.9 3775.2 3790.3 3638.3 3746.3 3754.7 3772.7 3780.5 3763.2 3764.0 3785.8 3792.4 3800.2 3355.5 3355.8 3359.5 3361.8 3366.7 4711.8 4716.0 4717.8 4740.7 4757.6 4070.5 4266.9 4337.9 4353.8 4365.2 5049.4 5075.6 5077.7 5082.4 5086.2 3667.9 3677.7 3695.5 3696.2 3696.6 5099.5 5271.9 5288.3 5336.9 5346.3 3720.7 3723.4 3725.5 3733.8 3747.2 3810.3 3816.3 3818.8 3822.4 3825.5 4067.4 4104.3 4117.2 4330.7 4353.6 4922.4 4960.6 4964.8 4986.2 4997.6 1285.2 1298.6 1299.7 1301.1 1303.2 807.7 845.6 851.2 867.8 869.3 4024.6 4035.7 4040.5 4048.5 4050.9 4569.5 4572.9 4610.4 4615.7 4633.5 4026.6 4058.7 4062.0 4066.4 4077.5 2452.8 2558.9 2753.3 2778.4 2783.4 4891.3 4908.6 4910.0 4928.5 4957.4 5343.8 5350.2 5367.6 5372.4 5373.5 4719.3 4964.9 4972.3 4989.0 4991.8 5218.0 5224.8 5229.5 5251.4 5255.7 4859.8 5129.3 5163.4 5174.2 5175.2 4943.3 4944.3 4945.6 4950.0 4959.9 3567.6 3577.9 3589.4 3606.6 3611.3 4935.5 5005.7 5221.6 5237.5 5271.3 5020.0 5029.9 5046.4 5052.6 5064.3 5039.5 5046.3 5048.4 5064.3 5086.7 4744.8 4914.2 4945.4 4974.1 4975.7 5033.6 5039.6 5047.7 5048.0 5068.6 4919.8 4928.8 4945.9 4947.2 4995.5 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - PROCESS | 12826 | [#5] yui.html Cycles:180 Average:17445.41 Median:22975.09 stddev:24912.47 (108.4%) stddev-sans-first:24950.39 19:19:08 INFO - PROCESS | 12826 | Values: 636.6 693.4 696.0 727.9 737.9 13223.3 14723.6 14742.7 14778.8 14831.9 13213.0 13921.2 14036.9 14067.2 14101.1 742.9 762.1 763.9 764.0 764.7 551.7 596.7 611.0 737.4 757.1 803.5 807.0 807.7 807.9 808.1 798.1 798.8 798.8 798.9 798.9 457.6 464.5 563.9 577.7 583.1 13467.8 13504.3 13507.3 13508.7 13511.7 11538.2 11968.2 12067.0 12080.5 12184.0 11593.5 11646.0 11653.2 11758.4 11843.8 3893.9 3942.4 3946.5 3970.2 3980.9 66024.0 67391.3 70691.0 70730.6 70819.3 9167.9 9276.4 9399.8 9433.1 9528.3 16308.4 16359.0 16384.9 16386.4 16389.2 6410.3 6434.3 6550.4 6576.3 6912.6 3663.8 3676.2 3693.9 3694.4 3722.6 2681.0 2753.6 2754.5 2765.7 2779.1 504.7 506.5 507.4 511.5 521.5 405.0 409.6 418.0 438.3 537.9 7587.2 8205.9 8257.7 8496.8 8530.9 434.1 508.5 517.7 547.1 563.0 3543.7 3685.9 3730.2 3888.5 3983.3 99229.5 103416.5 103867.5 107461.5 108099.5 52503.0 56249.7 69263.3 70978.3 96059.5 44917.0 71280.3 71290.0 72362.0 74632.6 7971.0 8711.3 9031.7 9070.1 9287.4 44995.0 45492.5 46549.5 47341.5 48293.8 44460.3 53726.5 55093.0 65143.3 66785.7 9781.3 11325.2 11350.8 11521.1 11864.6 12811.8 12898.2 13898.9 13999.6 14166.7 11679.2 12140.0 12536.1 12663.7 12785.7 11338.5 11485.8 11778.4 11842.9 12158.9 11440.2 11794.2 11880.9 11991.9 12567.2 9435.0 9869.6 9949.0 10050.9 10136.7 9096.7 9460.9 9499.9 10184.3 10202.3 19:19:08 INFO - PROCESS | 12826 | -------- Summary: end -------- 19:19:08 INFO - PROCESS | 12826 | 19:19:08 INFO - TEST-INFO | 12826: exit 0 19:19:08 INFO - TEST-OK | dromaeo_css | took 1120829ms 19:19:08 INFO - TEST-START | kraken 19:19:08 INFO - operating with platform_type : linux_ 19:19:08 INFO - Initialising browser for kraken test... 19:19:08 INFO - TEST-INFO | started process 13132 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTxyBjL/profile http://localhost:54070/getInfo.html) 19:19:13 INFO - PROCESS | 13132 | __metrics Screen width/height:1600/1200 19:19:13 INFO - PROCESS | 13132 | colorDepth:24 19:19:13 INFO - PROCESS | 13132 | Browser inner width/height: 1024/697 19:19:13 INFO - PROCESS | 13132 | __metrics 19:19:13 INFO - PROCESS | 13132 | 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" 19:19:13 INFO - PROCESS | 13132 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:14 INFO - PROCESS | 13132 | console.error: PushService: 19:19:14 INFO - PROCESS | 13132 | stateChangeProcessEnqueue: Error transitioning state 19:19:14 INFO - PROCESS | 13132 | UnknownError 19:19:14 INFO - TEST-INFO | 13132: exit 0 19:19:14 INFO - Browser initialized. 19:19:14 INFO - Running cycle 1/1 for kraken test... 19:19:14 INFO - TEST-INFO | started process 13212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTxyBjL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:27 INFO - PROCESS | 13212 | RSS: Main: 176865280 19:19:27 INFO - PROCESS | 13212 | 19:19:30 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54070/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:31 INFO - PROCESS | 13212 | RSS: Main: 235642880 19:19:31 INFO - PROCESS | 13212 | 19:19:33 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54070/tests/kraken/driver.html?testName=audio-dft) 19:19:34 INFO - PROCESS | 13212 | RSS: Main: 220262400 19:19:34 INFO - PROCESS | 13212 | 19:19:37 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54070/tests/kraken/driver.html?testName=audio-fft) 19:19:38 INFO - PROCESS | 13212 | RSS: Main: 302202880 19:19:38 INFO - PROCESS | 13212 | 19:19:40 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54070/tests/kraken/driver.html?testName=audio-oscillator) 19:19:41 INFO - PROCESS | 13212 | RSS: Main: 211660800 19:19:41 INFO - PROCESS | 13212 | 19:19:43 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54070/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:19:43 INFO - PROCESS | 13212 | RSS: Main: 215105536 19:19:43 INFO - PROCESS | 13212 | 19:19:47 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54070/tests/kraken/driver.html?testName=imaging-darkroom) 19:19:47 INFO - PROCESS | 13212 | RSS: Main: 252227584 19:19:47 INFO - PROCESS | 13212 | 19:19:51 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54070/tests/kraken/driver.html?testName=imaging-desaturate) 19:19:51 INFO - PROCESS | 13212 | RSS: Main: 245788672 19:19:51 INFO - PROCESS | 13212 | 19:19:54 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54070/tests/kraken/driver.html?testName=json-parse-financial) 19:19:55 INFO - PROCESS | 13212 | RSS: Main: 248844288 19:19:55 INFO - PROCESS | 13212 | 19:19:57 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54070/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:19:57 INFO - PROCESS | 13212 | RSS: Main: 212553728 19:19:57 INFO - PROCESS | 13212 | 19:19:59 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:00 INFO - PROCESS | 13212 | RSS: Main: 219971584 19:20:00 INFO - PROCESS | 13212 | 19:20:02 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:03 INFO - PROCESS | 13212 | RSS: Main: 223375360 19:20:03 INFO - PROCESS | 13212 | 19:20:05 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:06 INFO - PROCESS | 13212 | RSS: Main: 224686080 19:20:06 INFO - PROCESS | 13212 | 19:20:09 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:10 INFO - PROCESS | 13212 | RSS: Main: 222982144 19:20:10 INFO - PROCESS | 13212 | 19:20:11 INFO - PROCESS | 13212 | Cycle 1(1): loaded http://localhost:54070/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54070/tests/kraken/driver.html?testName=ai-astar) 19:20:12 INFO - PROCESS | 13212 | RSS: Main: 273944576 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | __start_tp_report 19:20:12 INFO - PROCESS | 13212 | _x_x_mozilla_page_load 19:20:12 INFO - PROCESS | 13212 | _x_x_mozilla_page_load_details 19:20:12 INFO - PROCESS | 13212 | |i|pagename|runs| 19:20:12 INFO - PROCESS | 13212 | |0;ai-astar;111;114;115;116;113;114;114;114;115;113 19:20:12 INFO - PROCESS | 13212 | |1;audio-beat-detection;147;128;122;175;123;133;125;125;154;130 19:20:12 INFO - PROCESS | 13212 | |2;audio-dft;162;162;162;162;163;162;161;161;160;162 19:20:12 INFO - PROCESS | 13212 | |3;audio-fft;76;77;78;112;78;77;78;75;123;76 19:20:12 INFO - PROCESS | 13212 | |4;audio-oscillator;85;84;114;85;85;84;107;84;84;112 19:20:12 INFO - PROCESS | 13212 | |5;imaging-gaussian-blur;117;116;116;116;116;117;117;117;117;117 19:20:12 INFO - PROCESS | 13212 | |6;imaging-darkroom;146;146;146;146;147;147;146;146;147;146 19:20:12 INFO - PROCESS | 13212 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 19:20:12 INFO - PROCESS | 13212 | |8;json-parse-financial;64;63;63;62;62;64;72;71;71;63 19:20:12 INFO - PROCESS | 13212 | |9;json-stringify-tinderbox;66;66;66;67;66;66;66;67;66;66 19:20:12 INFO - PROCESS | 13212 | |10;stanford-crypto-aes;83;83;82;83;82;82;84;82;82;83 19:20:12 INFO - PROCESS | 13212 | |11;stanford-crypto-ccm;141;144;132;133;131;134;134;131;183;143 19:20:12 INFO - PROCESS | 13212 | |12;stanford-crypto-pbkdf2;175;177;174;174;177;172;172;171;170;170 19:20:12 INFO - PROCESS | 13212 | |13;stanford-crypto-sha256-iterative;69;69;73;69;69;69;68;69;68;67 19:20:12 INFO - PROCESS | 13212 | __end_tp_report 19:20:12 INFO - PROCESS | 13212 | __start_cc_report 19:20:12 INFO - PROCESS | 13212 | _x_x_mozilla_cycle_collect,1954 19:20:12 INFO - PROCESS | 13212 | __end_cc_report 19:20:12 INFO - PROCESS | 13212 | __startTimestamp1474510812141__endTimestamp 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | ------- Summary: start ------- 19:20:12 INFO - PROCESS | 13212 | Number of tests: 14 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 19:20:12 INFO - PROCESS | 13212 | Values: 111.0 114.0 115.0 116.0 113.0 114.0 114.0 114.0 115.0 113.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:131.50 stddev:17.26 (13.1%) stddev-sans-first:17.86 19:20:12 INFO - PROCESS | 13212 | Values: 147.0 128.0 122.0 175.0 123.0 133.0 125.0 125.0 154.0 130.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 19:20:12 INFO - PROCESS | 13212 | Values: 162.0 162.0 162.0 162.0 163.0 162.0 161.0 161.0 160.0 162.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#3] audio-fft Cycles:10 Average:85.00 Median:77.00 stddev:17.35 (22.5%) stddev-sans-first:18.10 19:20:12 INFO - PROCESS | 13212 | Values: 76.0 77.0 78.0 112.0 78.0 77.0 78.0 75.0 123.0 76.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:84.00 stddev:12.95 (15.4%) stddev-sans-first:13.46 19:20:12 INFO - PROCESS | 13212 | Values: 85.0 84.0 114.0 85.0 85.0 84.0 107.0 84.0 84.0 112.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:20:12 INFO - PROCESS | 13212 | Values: 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:20:12 INFO - PROCESS | 13212 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 146.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:20:12 INFO - PROCESS | 13212 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:64.00 stddev:4.09 (6.4%) stddev-sans-first:4.30 19:20:12 INFO - PROCESS | 13212 | Values: 64.0 63.0 63.0 62.0 62.0 64.0 72.0 71.0 71.0 63.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 19:20:12 INFO - PROCESS | 13212 | Values: 66.0 66.0 66.0 67.0 66.0 66.0 66.0 67.0 66.0 66.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 19:20:12 INFO - PROCESS | 13212 | Values: 83.0 83.0 82.0 83.0 82.0 82.0 84.0 82.0 82.0 83.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:137.50 stddev:15.70 (11.4%) stddev-sans-first:16.65 19:20:12 INFO - PROCESS | 13212 | Values: 141.0 144.0 132.0 133.0 131.0 134.0 134.0 131.0 183.0 143.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:174.00 stddev:2.62 (1.5%) stddev-sans-first:2.69 19:20:12 INFO - PROCESS | 13212 | Values: 175.0 177.0 174.0 174.0 177.0 172.0 172.0 171.0 170.0 170.0 19:20:12 INFO - PROCESS | 13212 | 19:20:12 INFO - PROCESS | 13212 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.56 (2.3%) stddev-sans-first:1.66 19:20:12 INFO - PROCESS | 13212 | Values: 69.0 69.0 73.0 69.0 69.0 69.0 68.0 69.0 68.0 67.0 19:20:12 INFO - PROCESS | 13212 | -------- Summary: end -------- 19:20:12 INFO - PROCESS | 13212 | 19:20:13 INFO - TEST-INFO | 13212: exit 0 19:20:13 INFO - TEST-OK | kraken | took 64509ms 19:20:13 INFO - SUITE-END | took 1185s 19:20:13 INFO - Completed test suite (00:19:45) 19:20:13 INFO - javascript benchmark 19:20:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2577.8839952202147, 2597.5194589001026, 2641.682998040235, 2642.947141057179, 2650.4036591766844, 4587.977060114703, 4670.90658186836, 4723.740194289313, 4732.361131247285, 4733.0, 4666.953330466695, 4676.97661511692, 4684.601808846243, 4688.32019357193, 4701.130290896187, 4237.576242375753, 4260.510041345238, 4263.9147217055715, 4281.914361712764, 4290.592393722607, 4246.766427846467, 4285.121550082225, 4319.848805291816, 4322.13557288542, 4336.869893903188, 414.87603792647405, 4317.848875289365, 4324.459442569679, 4363.672724545656, 4374.037711703436, 4256.44666193396, 4276.593723596254, 4302.075053863403, 4313.633341165973, 4359.978200109011, 4286.464191976003, 4301.268784306675, 4302.161078589676, 4309.913801723948, 4350.32569951658, 4579.244424669925, 4686.929696054562, 4694.2723877799, 4707.270373092176, 4747.715137091751, 4936.679115857458, 4962.801487940478, 4983.07813054586, 5016.3980322361185, 5023.422306434733, 5029.849104526834, 5032.773525191338, 5051.696898186084, 5055.0, 5100.438951715309, 4721.858344249678, 4723.433188017391, 4725.267583524559, 4775.665703400763, 4783.497732738012, 5015.573676237561, 5055.216441451581, 5062.088824011713, 5115.130427827278, 5127.820526281637, 4845.53967373106, 4902.7058376497525, 4921.7785199666105, 4924.901501969941, 4957.256411538298, 4921.409430868319, 4965.3048573199785, 4967.0, 4976.178930476431, 5026.472220416804, 4932.926006109911, 4933.260010998307, 4936.777844996984, 4994.650374473752, 4995.050940321327, 4864.197407427806, 4897.8285759998225, 4918.262260660929, 4926.1379258629595, 4928.457869634337, 4237.470316210473, 4252.234597772431, 4260.723053001545, 4275.636570891509, 4314.352847072964, 3696.9815150924073, 3701.8704345347783, 3702.8703995360033, 3736.420854767461, 3746.5316835395574, 4251.383549385322, 4293.806778694966, 4381.496127945264, 4387.407699960529, 4424.181526417623, 4760.904781904343, 5017.724025178651, 5062.670926389773, 5063.240513922868, 5095.694258344512, 4385.890352741207, 4390.670699697535, 4390.714603550759, 4391.297392417262, 4445.333200020023, 4459.241928872099, 4577.771111444415, 4578.130155270488, 4581.175388430114, 4624.491305956342, 4711.316859055486, 4745.5966242869745, 4798.1843086676045, 4804.927926081042, 4828.686135401188, 5030.2957585937265, 5032.295478633067, 5034.521720436553, 5077.339945807022, 5086.491350865032, 4776.5701086902345, 4779.856604301876, 4789.185838407409, 4789.544993225638, 4846.539578740014, 4827.0, 4877.024595081069, 4914.410270767531, 4932.551580382391, 4934.358533390637, 4990.875228119181, 5023.623228257969, 5029.346184995927, 5082.415522214993, 5086.872828179326, 4874.195757700081, 4881.145799485005, 4883.560479556857, 4890.853274401774, 4954.677945933503, 4734.384530011076, 4781.760911954458, 4873.9025219496125, 4880.267959806058, 4947.579455746302, 4753.334533165429, 4779.641526885567, 4790.113828941657, 4805.27920811881, 4847.200211964986, 4830.154722923545, 4873.561379475864, 4916.115099282163, 4916.655834091579, 4982.252662100714, 4754.833580824655, 4770.165221086227, 4793.23308270675, 4811.061842940593, 4822.276658501253], "value": 4575.499509104563, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.8421549858289, 556.679909052295, 561.3656568078072, 562.2746656812711, 573.7246121861507, 27221.863890680543, 42133.578664213346, 43625.127497450034, 43660.0, 43699.12601747963, 40078.0, 40464.595354046454, 40629.59370406295, 40672.38991415124, 40687.79656101715, 7706.0, 7837.608119594026, 7839.372850172001, 7843.803904902366, 7844.058712954425, 7208.927910720904, 7434.070741157356, 9785.755356116082, 10037.447940363278, 10050.648227312045, 10088.546015429323, 10116.94941525295, 10121.139703125207, 10129.088382045615, 10174.44040577768, 9981.351212171221, 10066.597336106546, 10073.043060909238, 10073.546690398949, 10080.092791648787, 9948.154406875352, 10018.899811001942, 10023.69928902134, 10026.097651211427, 10027.849582256273, 37475.940747998946, 37743.245135097146, 37819.810900945595, 37826.05434864123, 37900.24199516022, 2827.5617279321737, 2981.8807247710065, 3001.9099427017004, 3024.107888172994, 3057.7248047675594, 28900.277493062633, 29218.561721574195, 29350.91815359378, 29601.70398296011, 30799.0, 3250.902472925792, 3376.763626546143, 3511.6839484446523, 3563.1448452371505, 3630.8910732678064, 264138.0, 264780.0, 265098.0, 266612.0, 266900.9999999981, 30705.69294307073, 31769.20576985594, 31776.523352149736, 52971.93533228674, 53944.460555394726, 33093.83453082719, 33146.3370732584, 33150.66849331524, 33328.0, 33344.1663958403, 13320.733585328433, 13463.12489688167, 13484.39320230592, 13487.865121348857, 13489.190648561118, 9684.370515916444, 11142.442877856074, 11252.26860254081, 11258.718532036764, 11280.887191127984, 7218.8917166242545, 7485.663145158481, 7773.650185741655, 7801.414893882981, 7808.375329973588, 3025.1529571719952, 3071.78497505177, 3089.443900097959, 3090.2892334762682, 3095.1024202980884, 838.5974732128612, 853.4495250563352, 890.8485557455248, 906.4742449379344, 913.8537833946536, 26993.460130797666, 27949.16152515428, 28166.71833281641, 28227.57658635122, 28567.57148642772, 921.166344458253, 939.1172298039821, 971.5336638413601, 1023.3603997501561, 1046.3669479964665, 14342.139471631735, 14840.40638374453, 14932.432094630125, 14977.400903963937, 15095.622609434851, 382292.0, 385692.0, 387419.0, 387696.0, 389199.0, 48655.75672121617, 49102.75448622734, 49204.50795492071, 49257.0, 49321.260181097314, 1029.6550655530332, 1039.3140527251978, 1173.2491205628235, 1185.0282768130223, 1189.5241903238773, 26960.730392696605, 29100.563491547222, 29703.554446682883, 31663.36673266568, 32164.356712865145, 46129.76935115303, 46665.3000205004, 46668.7666561665, 46749.53250467452, 46757.0, 341446.0, 345190.0, 345672.0, 345916.0, 345956.0, 24383.0, 24896.62655060139, 25068.373290667878, 25321.113761018278, 27659.723402765714, 33225.335493289516, 41613.37579936243, 41918.58081419147, 42264.1547169061, 45701.77149114233, 35641.28717425689, 35789.0, 35942.8202858984, 36068.819655901556, 36724.63275367319, 36105.277894442486, 36131.612686060325, 36315.27369452649, 36535.26929461343, 37278.0, 35067.47398789071, 35683.643163568035, 35987.46018809667, 36188.27623447569, 36220.55904689051, 31175.0, 32849.0, 33112.834435827666, 33445.0, 33690.0, 24663.260102196964, 24795.256142315036, 24900.12849550257, 24927.501449971263, 25004.249872503853], "value": 16819.356099281904, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.73451115197065, 372.2443439817171, 374.1936127644925, 379.0864017717302, 398.8823297127347, 15447.613809654764, 16609.169541522922, 17208.999999999985, 17317.22072506737, 17322.13389330535, 16092.75860862088, 16365.099919504422, 16703.164841757894, 16789.748153777702, 16848.83151168488, 4840.903181936359, 5078.21287700407, 5079.161938280179, 5079.415867175267, 5083.262926875601, 3638.472421498881, 3815.6184381561898, 4898.314236006961, 5551.500364967153, 5777.711114444282, 5860.706964651772, 5868.354481007086, 5873.353931067579, 5877.441643043904, 5878.177055212273, 5660.490555849972, 5791.07342825148, 5815.680137592431, 5816.941830581683, 5820.35976042635, 4991.550760431543, 5144.459831717691, 5152.330197074394, 5167.741612919339, 5172.353455818023, 19839.404817855488, 20104.899475502676, 20139.89930050355, 20224.292149774836, 20479.0, 1885.6605810954022, 1939.7284380186786, 1983.871048381865, 2060.6393881070753, 2437.7805997460136, 13051.477940882352, 13082.672933176651, 13241.139325943783, 13585.116967397187, 13965.86034139666, 1655.6854197702519, 1670.682570311637, 1699.133441944617, 1702.9914850425669, 1715.1938588863213, 69379.65310173467, 69488.0, 69679.3032069683, 70530.64734676345, 72707.0, 9100.590473428712, 9313.0, 9401.341906066646, 9671.371360861522, 9715.0, 11601.0, 11618.128640352008, 11641.6507504775, 11643.941780291045, 11652.184347095623, 6930.861382772317, 7044.506884518035, 7144.642767861586, 7168.319009694071, 7189.0294810201, 6759.864802704017, 6860.588364698134, 6978.406835418945, 7217.783466496014, 7253.202147763742, 5670.8015219467125, 5731.340895796953, 5742.511886489611, 5764.510016648632, 5850.414958504116, 1519.3542744333613, 1562.6484041090891, 1603.1022627328732, 1622.3997121065045, 1670.1315316035593, 837.949723016621, 858.1718641510972, 874.0, 887.4586502233632, 923.3767207135157, 16574.502764917073, 16703.248353824107, 17231.741523877154, 17610.64778704419, 17891.73162402565, 845.8815765792793, 877.3726785348379, 886.8980067292345, 905.524599585223, 906.8911730592371, 8186.754397368089, 10039.949800250952, 10066.194704423628, 10137.746556336151, 10263.0, 402273.0, 404279.0, 412313.0, 418256.0, 425486.0, 71407.64296178486, 78868.0, 81752.0, 81865.59067204625, 82996.0, 831.1107115386478, 917.3899356927702, 927.0914503786328, 969.0503306759415, 971.1647982734843, 12298.0, 14847.40610375573, 15395.769063463833, 16268.430604928772, 16686.0, 38106.61893381141, 38987.79487434415, 39358.212835743696, 40514.39228411517, 40872.59127408807, 53425.73287133695, 54951.17573236485, 56002.71998639981, 56322.1551676717, 56846.43153568577, 7101.289871013064, 7145.964270178823, 7189.388901943394, 7311.41508679315, 7315.3781928534545, 8144.859719639374, 11293.37886416255, 11731.41342932867, 11898.76202475963, 12303.630891073282, 9734.318597698093, 10115.544800483849, 10542.52558634848, 10729.570817167227, 10954.945225273823, 9785.461799601091, 10164.542595583365, 10196.286259961731, 10221.131203847755, 10350.689479315633, 9837.557309921214, 9995.800083998425, 10067.899321006696, 10132.13876820449, 10191.337563058378, 7115.893261600977, 7175.390091842252, 7388.593627350548, 7437.181909990005, 7891.723789667334, 8938.329625278, 10286.537105830404, 10290.691279261635, 10414.583416663248, 10527.420991845216], "value": 8295.809217665706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1060.7560261139938, 1080.1682704317675, 1147.334545963341, 1445.4073829729807, 1640.8195098539165, 6728.730850765964, 6923.273056329089, 7347.448941329401, 7976.9202307976975, 7981.600919953994, 7516.0, 7823.687052517893, 7825.882611760814, 7829.334506566948, 7849.921500784991, 2139.454439118023, 2157.9568408631817, 2178.880161591112, 2182.0835249195297, 2188.2122435923056, 1844.7417361569392, 1909.7326374307609, 2148.2266384101713, 2185.912563497458, 2207.86752794832, 1867.8599105067105, 1887.7451544041585, 2070.534129820793, 2143.47484866208, 2148.0977989244475, 2117.6505876530355, 2125.8511904166717, 2127.87232766033, 2136.305700647296, 2139.155033761671, 2076.2214169686367, 2142.903569339368, 2149.3981685128188, 2152.1068756466047, 2154.1814110637856, 7742.264484873928, 7918.841623167562, 7919.208079192093, 7922.168172341879, 7922.564258965755, 4509.864704058883, 4628.467726211461, 4640.118377508263, 4641.002184530308, 4646.4888862225125, 2868.4119755450083, 2946.175070980129, 2973.7323640872423, 2982.0606508949613, 2986.8058576192693, 947.8672985781927, 1021.509675355826, 1038.8285932821152, 1053.4048262731533, 1057.4184198690689, 10837.241393102486, 10848.18638602108, 10858.565657373696, 10864.293820901616, 10875.510602022849, 3848.5189351331082, 4036.0717035082093, 4135.359019352005, 4153.7300075494995, 4167.937480937789, 9176.49529275883, 9183.127602877716, 9189.586468608928, 9192.034836342222, 9193.448393096436, 4466.441694788152, 4501.887452813706, 4502.3921770560555, 4507.918099656059, 4514.954850451519, 4036.656884164879, 4102.794860257035, 4129.628333450004, 4130.277201489727, 4131.669466442678, 3658.579263384745, 3767.6609105180664, 3773.339665558516, 3809.5428548573796, 3915.4126880967515, 1195.4022988505747, 1211.2248161176854, 1214.3060430699124, 1224.7856625090574, 1226.1233218248994, 724.0840336973732, 729.1723893381051, 735.1252010107954, 753.600591686407, 760.9010828592247, 4986.80052797884, 5096.031753966735, 5162.845114646567, 5201.713905735145, 5285.63000589955, 786.5045021636447, 877.8990416102102, 893.3612467086065, 893.7497500699815, 901.9233365164034, 3491.6682915123024, 3527.470879368115, 3585.229175727231, 3602.4956506088643, 3681.4109742441083, 9284.3036772241, 9655.468949207861, 9656.613735450645, 9657.22742180624, 9666.854997175047, 9507.334486585873, 9543.522823858779, 9575.712728618153, 9644.469554174588, 9649.565769540388, 7239.276072392719, 7345.77962661121, 7351.154617218981, 7352.816179595553, 7371.410287177013, 4973.428055773632, 5112.156494178567, 5132.46109158533, 5149.201873709581, 5340.893182136258, 8935.865962010705, 8942.46345219289, 8975.057618950177, 9005.973655927364, 9086.500242486727, 8573.74278771638, 8978.326625502983, 8986.505742184243, 9065.77335566616, 9088.54557272147, 6484.351564843667, 6611.140551728437, 6641.0, 6643.0, 6646.8005959821285, 7223.458240631869, 7243.674034668347, 7243.7464688735645, 7255.347018768336, 7256.854862902606, 6337.0, 6542.378474044958, 6559.508036897348, 6607.57050791697, 6638.435732962752, 6281.309056003927, 6346.873062538631, 6350.174477317919, 6374.0, 6392.744290228525, 6032.849178770566, 6109.7861574844665, 6111.358307377839, 6115.938840611537, 6122.755089796537, 4535.659825513034, 4545.636349092131, 4569.405977222939, 4619.376384188092, 4634.536546345338, 4396.208682437064, 4492.595666390041, 4525.230710779109, 4531.729568664864, 4560.5895469407915], "value": 4077.58514137161, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.39981044545308, 278.2931354359926, 285.43911214463577, 285.44908326929027, 289.93621403291274, 4621.8151273949, 4710.76446177691, 4844.6019220485905, 4853.199222128344, 4857.372780118664, 4754.334393184957, 4767.833125840596, 4769.379980602525, 4772.832950846721, 4773.498782627827, 3597.8201089945464, 3716.6991552956383, 3755.3052685253315, 3763.322601931651, 3779.9433008504893, 3765.341065313573, 3769.6607305342513, 3774.9433758493647, 3775.169462718198, 3790.2987947229713, 3638.2905333459985, 3746.2882052409955, 3754.718396120288, 3772.7170462215445, 3780.451834483998, 3763.2285381496727, 3763.9811800941093, 3785.8296376663116, 3792.393217085253, 3800.239952009609, 3355.4966754986704, 3355.781874178195, 3359.5296658467832, 3361.764676472648, 3366.7138293245102, 4711.7879695413785, 4715.952840471586, 4717.834875779366, 4740.668153229275, 4757.61939044879, 4070.5115386153852, 4266.87199384019, 4337.869863904088, 4353.804078816461, 4365.236083685374, 5049.444561098277, 5075.56857667095, 5077.669951453181, 5082.364704411949, 5086.21163719624, 3667.853285868562, 3677.669009789132, 3695.4826324314618, 3696.242270334575, 3696.5933747287777, 5099.541041306263, 5271.894562108736, 5288.286081379041, 5336.946630533723, 5346.331708536433, 3720.739548231652, 3723.404255319136, 3725.5343082114737, 3733.75730577517, 3747.163889694085, 3810.2760475509563, 3816.2939856126704, 3818.789966551866, 3822.4266360046217, 3825.540935087807, 4067.4102255172834, 4104.302268614343, 4117.155983023473, 4330.653547716175, 4353.586409291112, 4922.384701912261, 4960.578350840146, 4964.801407943714, 4986.229888389569, 4997.550220480174, 1285.222440423549, 1298.577962162301, 1299.693807723232, 1301.0892375337303, 1303.2050449225965, 807.7423601035003, 845.6152450634946, 851.2126283188027, 867.8177582707702, 869.278498845969, 4024.577419370924, 4035.7174997750462, 4040.4545386372465, 4048.5344185419053, 4050.9392359114636, 4569.54304569547, 4572.862814115582, 4610.354550362952, 4615.723056616614, 4633.536646335339, 4026.63760261572, 4058.736182148151, 4061.9593804062174, 4066.369712694537, 4077.5310839253866, 2452.755728508395, 2558.8976440942524, 2753.3116720819794, 2778.360976975307, 2783.4293969736154, 4891.3396691446915, 4908.558229759267, 4909.975450122726, 4928.531789479994, 4957.380327459067, 5343.786248550092, 5350.170723537858, 5367.570594352443, 5372.435894231049, 5373.489518495737, 4719.315699223594, 4964.90070198594, 4972.279019542146, 4988.950110498994, 4991.750412479361, 5218.0346635870965, 5224.817131400383, 5229.450907654642, 5251.396089449762, 5255.658382205144, 4859.757012149449, 5129.307328581634, 5163.380394352552, 5174.223866420067, 5175.197844334134, 4943.2585112233455, 4944.307796908363, 4945.579625731853, 4950.0, 4959.851204463727, 3567.6075631681015, 3577.9284414312087, 3589.4436362363876, 3606.647507184806, 3611.331903597843, 4935.4570997190995, 5005.6996580205305, 5221.556167725786, 5237.476252374732, 5271.28683177961, 5019.974900125476, 5029.899402012013, 5046.4196617388, 5052.621053420935, 5064.34163558735, 5039.513343563584, 5046.318746969259, 5048.444671086104, 5064.265681476239, 5086.669366491166, 4744.810207591796, 4914.213725803854, 4945.357103576512, 4974.104661161025, 4975.726335051462, 5033.597312214941, 5039.647224694236, 5047.722375269249, 5048.0, 5068.5807973767405, 4919.803207871645, 4928.802847886044, 4945.876353091202, 4947.183714687037, 4995.450500444876], "value": 3768.417981323877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.5734957578422, 693.4071368979519, 695.9965200173998, 727.9008696867731, 737.8930055142006, 13223.338833058357, 14723.558293251206, 14742.705145897076, 14778.778318325207, 14831.925840370797, 13213.0, 13921.1647301162, 14036.859631403682, 14067.226302553356, 14101.083429577071, 742.9182789893121, 762.0893032825776, 763.8777795552712, 764.0411283838774, 764.7323436797133, 551.6689986008383, 596.6718304932292, 611.0193140010286, 737.36218171282, 757.1066141952493, 803.4857691077715, 806.9596520173997, 807.725373373053, 807.9232472915064, 808.0626473290984, 798.0662624729052, 798.8282519258387, 798.8402319536116, 798.8801679748026, 798.9400794940402, 457.63389288569346, 464.5331441900902, 563.8844036972451, 577.6995962099685, 583.12822330616, 13467.797983030165, 13504.257265850472, 13507.25710085945, 13508.662283442894, 13511.66220844477, 11538.192326537146, 11968.162228643998, 12067.0, 12080.516779328816, 12184.0, 11593.478293476814, 11646.0, 11653.242539235007, 11758.353290569015, 11843.763124737543, 3893.922121557553, 3942.3889297158985, 3946.52641682997, 3970.2258059678375, 3980.8805735827973, 66024.0, 67391.32608673899, 70691.0, 70730.64634676794, 70819.2918070823, 9167.908320916706, 9276.350655454053, 9399.812003759887, 9433.05669433065, 9528.285378596633, 16308.429204978007, 16359.0, 16384.918075409547, 16386.426475073316, 16389.180540972902, 6410.326915673881, 6434.324395938453, 6550.443212327005, 6576.276609572943, 6912.55068420551, 3663.816809159531, 3676.227992121631, 3693.8891833245048, 3694.4273637586216, 3722.6091260417265, 2681.0, 2753.600727894444, 2754.504189245915, 2765.681946576129, 2779.1245757586294, 504.6593549354171, 506.5112166759095, 507.3809951858726, 511.5293929584791, 521.507175718942, 404.95747946465787, 409.60063937660544, 417.97492150471066, 438.3009100484722, 537.854779209619, 7587.241275872479, 8205.917940820635, 8257.710980115668, 8496.830063398698, 8530.914690853013, 434.0927461605197, 508.5169089365118, 517.6764522173642, 547.1246006389745, 562.9549636029108, 3543.6633519815578, 3685.90785230366, 3730.1607138393642, 3888.51393575803, 3983.342748446532, 99229.50385248172, 103416.48291758493, 103867.48066259621, 107461.46269268604, 108099.45950270355, 52503.0, 56249.7187514068, 69263.30736692568, 70978.2902170982, 96059.51970240104, 44917.0, 71280.2871971284, 71290.0, 72362.0, 74632.62683686655, 7970.960145199237, 8711.303095752452, 9031.683891064044, 9070.092990700878, 9287.396319239228, 44995.0, 45492.54507455016, 46549.53450465587, 47341.52658473509, 48293.75853120852, 44460.333095002956, 53726.46273537371, 55093.0, 65143.348566515626, 66785.66607166933, 9781.315307928662, 11325.207235493437, 11350.772984540428, 11521.078313734713, 11864.644060678194, 12811.80782288285, 12898.226106433644, 13898.861011390161, 13999.580012599637, 14166.716665666836, 11679.240849344766, 12140.02879769634, 12536.05979551519, 12663.746725065632, 12785.680357990752, 11338.489767960627, 11485.820921380346, 11778.411079445823, 11842.881571184522, 12158.93920530427, 11440.199186056896, 11794.174407791372, 11880.940595296968, 11991.940040299743, 12567.245965242115, 9434.952825236105, 9869.555869986012, 9948.950255248677, 10050.899491004997, 10136.746581335525, 9096.727098187064, 9460.905390946002, 9499.857502137611, 10184.279879098924, 10202.314727277908], "value": 6434.315918950831, "unit": "score"}], "name": "dromaeo_css", "value": 6310.107368460203, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 115.0, 116.0, 113.0, 114.0, 114.0, 114.0, 115.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 128.0, 122.0, 175.0, 123.0, 133.0, 125.0, 125.0, 154.0, 130.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 161.0, 161.0, 160.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 112.0, 78.0, 77.0, 78.0, 75.0, 123.0, 76.0], "value": 85.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 114.0, 85.0, 85.0, 84.0, 107.0, 84.0, 84.0, 112.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 62.0, 64.0, 72.0, 71.0, 71.0, 63.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 82.0, 82.0, 84.0, 82.0, 82.0, 83.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 132.0, 133.0, 131.0, 134.0, 134.0, 131.0, 183.0, 143.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 174.0, 174.0, 177.0, 172.0, 172.0, 171.0, 170.0, 170.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 73.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 67.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1544.0999999999997, "alertThreshold": 2.0}]} 19:20:13 INFO - Return code: 0 19:20:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:13 INFO - # TBPL SUCCESS # 19:20:13 INFO - Running post-action listener: _resource_record_post_action 19:20:13 INFO - [mozharness: 2016-09-22 02:20:13.946904Z] Finished run-tests step (success) 19:20:13 INFO - Running post-run listener: _resource_record_post_run 19:20:14 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408494080; Read time: 8; Write time: 278800 19:20:14 INFO - TinderboxPrint: CPU usage
13.0% 19:20:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 19:20:14 INFO - TinderboxPrint: I/O write bytes / time
408,494,080 / 278,800 19:20:14 INFO - TinderboxPrint: CPU idle
8,340.0 (87.0%) 19:20:14 INFO - TinderboxPrint: CPU user
1,203.0 (12.5%) 19:20:14 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:14 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115679232; Read time: 0; Write time: 73264 19:20:14 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 292814848; Read time: 0; Write time: 205536 19:20:14 INFO - Running post-run listener: _upload_blobber_files 19:20:14 INFO - Blob upload gear active. 19:20:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:14 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 19:20:14 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:20:14 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:20:14 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:15 INFO - (blobuploader) - INFO - Done attempting. 19:20:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:16 INFO - (blobuploader) - INFO - Done attempting. 19:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:22 INFO - (blobuploader) - INFO - Done attempting. 19:20:22 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:22 INFO - Return code: 0 19:20:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/27926ac35505607f7812e02cf66960fa0e03dc220b9ad5943c25ca0748d6a2468dcdc96b1b4bcb16f40470290af8f21b702a0b4e59639b44d96ce02a9d09e0e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b32a0abfa881f33eca2633199683a6d6ec1f4b288490f8fb21f8ab4c7a8c00bb141baff0236e96c24a6fce75bd757033a89d239bdeea9e86f91db38622eb5a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e72839713b211250477dc0eb1b564f0ada7141303d1fd9fe41a95a5acc8331b253529bcd12ede6ab5cea7e121e92987096a73a3b16cb43c4b94bb664829eb2a6"} 19:20:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:22 INFO - Contents: 19:20:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/27926ac35505607f7812e02cf66960fa0e03dc220b9ad5943c25ca0748d6a2468dcdc96b1b4bcb16f40470290af8f21b702a0b4e59639b44d96ce02a9d09e0e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b32a0abfa881f33eca2633199683a6d6ec1f4b288490f8fb21f8ab4c7a8c00bb141baff0236e96c24a6fce75bd757033a89d239bdeea9e86f91db38622eb5a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e72839713b211250477dc0eb1b564f0ada7141303d1fd9fe41a95a5acc8331b253529bcd12ede6ab5cea7e121e92987096a73a3b16cb43c4b94bb664829eb2a6"} 19:20:22 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:22 INFO - Copying logs to upload dir... 19:20:22 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.999332 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-21 19:20:22.805228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 19:20:22.808834) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/27926ac35505607f7812e02cf66960fa0e03dc220b9ad5943c25ca0748d6a2468dcdc96b1b4bcb16f40470290af8f21b702a0b4e59639b44d96ce02a9d09e0e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b32a0abfa881f33eca2633199683a6d6ec1f4b288490f8fb21f8ab4c7a8c00bb141baff0236e96c24a6fce75bd757033a89d239bdeea9e86f91db38622eb5a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e72839713b211250477dc0eb1b564f0ada7141303d1fd9fe41a95a5acc8331b253529bcd12ede6ab5cea7e121e92987096a73a3b16cb43c4b94bb664829eb2a6"} build_url:https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/VpIj89UgSWOfP9zijIyygw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/27926ac35505607f7812e02cf66960fa0e03dc220b9ad5943c25ca0748d6a2468dcdc96b1b4bcb16f40470290af8f21b702a0b4e59639b44d96ce02a9d09e0e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b32a0abfa881f33eca2633199683a6d6ec1f4b288490f8fb21f8ab4c7a8c00bb141baff0236e96c24a6fce75bd757033a89d239bdeea9e86f91db38622eb5a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/e72839713b211250477dc0eb1b564f0ada7141303d1fd9fe41a95a5acc8331b253529bcd12ede6ab5cea7e121e92987096a73a3b16cb43c4b94bb664829eb2a6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 19:20:22.833903) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 19:20:22.834294) ========= 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=4ac8d263c7293b36a1be1aed000001be-1474509043.3059-1227877207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004661 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 19:20:22.917182) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 19:20:22.917509) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 19:20:22.917836) ========= ========= Total master_lag: 0.17 =========