builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-041 starttime: 1468883820.2 results: success (0) buildid: 20160718145619 builduid: 5d803df2648e4bc3b8ac2fc2e57b5f2f revision: feaaf1af1065257b9178faca8b67eed9657b4a17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.203081) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.203647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.203920) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.219356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.219734) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 16:17:00-- 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% 223M=0s 2016-07-18 16:17:00 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111698 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.345375) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.345670) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021174 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:00.394904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 16:17:00.395430) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev feaaf1af1065257b9178faca8b67eed9657b4a17 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev feaaf1af1065257b9178faca8b67eed9657b4a17 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 16:17:00,955 truncating revision to first 12 chars 2016-07-18 16:17:00,955 Setting DEBUG logging. 2016-07-18 16:17:00,955 attempt 1/10 2016-07-18 16:17:00,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/feaaf1af1065?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 16:17:01,742 unpacking tar archive at: mozilla-central-feaaf1af1065/testing/mozharness/ program finished with exit code 0 elapsedTime=1.143695 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 16:17:01.553215) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:01.553564) ========= script_repo_revision: feaaf1af1065257b9178faca8b67eed9657b4a17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:01.553991) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:01.554281) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 16:17:01.565326) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-18 16:17:01.565803) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:02 INFO - MultiFileLogger online at 20160718 16:17:02 in /builds/slave/test 16:17:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:17:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:02 INFO - {'append_to_log': False, 16:17:02 INFO - 'base_work_dir': '/builds/slave/test', 16:17:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:17:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:02 INFO - 'branch': 'Firefox-Non-PGO', 16:17:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:02 INFO - 'config_files': ('talos/linux_config.py',), 16:17:02 INFO - 'default_actions': ('clobber', 16:17:02 INFO - 'read-buildbot-config', 16:17:02 INFO - 'download-and-extract', 16:17:02 INFO - 'populate-webroot', 16:17:02 INFO - 'create-virtualenv', 16:17:02 INFO - 'install', 16:17:02 INFO - 'run-tests'), 16:17:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:02 INFO - 'download_minidump_stackwalk': True, 16:17:02 INFO - 'download_symbols': 'ondemand', 16:17:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:02 INFO - 'installer_path': 'installer.exe', 16:17:02 INFO - 'log_level': 'info', 16:17:02 INFO - 'log_name': 'talos', 16:17:02 INFO - 'log_to_console': True, 16:17:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:02 INFO - 'opt_config_files': (), 16:17:02 INFO - 'pip_index': False, 16:17:02 INFO - 'sps_profile': False, 16:17:02 INFO - 'sps_profile_interval': 0, 16:17:02 INFO - 'suite': 'dromaeojs', 16:17:02 INFO - 'system_bits': '32', 16:17:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:02 INFO - 'title': 'talos-linux64-ix-041', 16:17:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:02 INFO - 'use_talos_json': True, 16:17:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:02 INFO - 'work_dir': 'build'} 16:17:02 INFO - ##### 16:17:02 INFO - ##### Running clobber step. 16:17:02 INFO - ##### 16:17:02 INFO - Running pre-action listener: _resource_record_pre_action 16:17:02 INFO - Running main action method: clobber 16:17:02 INFO - rmtree: /builds/slave/test/build 16:17:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:02 INFO - Running post-action listener: _resource_record_post_action 16:17:02 INFO - ##### 16:17:02 INFO - ##### Finished clobber step (success) 16:17:02 INFO - ##### 16:17:02 INFO - ##### 16:17:02 INFO - ##### Running read-buildbot-config step. 16:17:02 INFO - ##### 16:17:02 INFO - Running pre-action listener: _resource_record_pre_action 16:17:02 INFO - Running main action method: read_buildbot_config 16:17:02 INFO - Using buildbot properties: 16:17:02 INFO - { 16:17:02 INFO - "project": "", 16:17:02 INFO - "product": "firefox", 16:17:02 INFO - "script_repo_revision": "production", 16:17:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:17:02 INFO - "repository": "", 16:17:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:17:02 INFO - "buildid": "20160718145619", 16:17:02 INFO - "pgo_build": "False", 16:17:02 INFO - "basedir": "/builds/slave/test", 16:17:02 INFO - "buildnumber": 152, 16:17:02 INFO - "slavename": "talos-linux64-ix-041", 16:17:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:17:02 INFO - "platform": "ubuntu64_hw", 16:17:02 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:17:02 INFO - "branch": "mozilla-central", 16:17:02 INFO - "repo_path": "mozilla-central", 16:17:02 INFO - "revision": "feaaf1af1065257b9178faca8b67eed9657b4a17", 16:17:02 INFO - "stage_platform": "linux64", 16:17:02 INFO - "builduid": "5d803df2648e4bc3b8ac2fc2e57b5f2f", 16:17:02 INFO - "slavebuilddir": "test" 16:17:02 INFO - } 16:17:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:17:02 INFO - Running post-action listener: _resource_record_post_action 16:17:02 INFO - ##### 16:17:02 INFO - ##### Finished read-buildbot-config step (success) 16:17:02 INFO - ##### 16:17:02 INFO - ##### 16:17:02 INFO - ##### Running download-and-extract step. 16:17:02 INFO - ##### 16:17:02 INFO - Running pre-action listener: _resource_record_pre_action 16:17:02 INFO - Running main action method: download_and_extract 16:17:02 INFO - mkdir: /builds/slave/test/build/tests 16:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:02 INFO - https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:17:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:17:04 INFO - Downloaded 1361 bytes. 16:17:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:04 INFO - Using the following test package requirements: 16:17:04 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:17:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:17:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:17:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'jsshell-linux-x86_64.zip'], 16:17:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:17:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:17:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:17:04 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:17:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:17:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:17:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:04 INFO - https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:17:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:17:05 INFO - Downloaded 22763283 bytes. 16:17:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:17:06 INFO - Return code: 0 16:17:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:06 INFO - https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:17:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:17:15 INFO - Downloaded 22763283 bytes. 16:17:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:17:17 INFO - Return code: 0 16:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:17 INFO - https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:17:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:17:18 INFO - Downloaded 14403317 bytes. 16:17:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:17:18 INFO - Return code: 0 16:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:18 INFO - https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:17:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:17:19 INFO - Downloaded 57127463 bytes. 16:17:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:19 INFO - mkdir: /builds/slave/test/properties 16:17:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:19 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:19 INFO - Contents: 16:17:19 INFO - build_url:https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:20 INFO - Running post-action listener: _resource_record_post_action 16:17:20 INFO - Running post-action listener: set_extra_try_arguments 16:17:20 INFO - ##### 16:17:20 INFO - ##### Finished download-and-extract step (success) 16:17:20 INFO - ##### 16:17:20 INFO - ##### 16:17:20 INFO - ##### Running populate-webroot step. 16:17:20 INFO - ##### 16:17:20 INFO - Running pre-action listener: _resource_record_pre_action 16:17:20 INFO - Running main action method: populate_webroot 16:17:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:20 INFO - '--tppagecycles', 16:17:20 INFO - '7'], 16:17:20 INFO - 'tests': ['tsvgm']}}, 16:17:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['damp', 'tps']}, 16:17:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'tests': ['damp', 'tps']}, 16:17:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['dromaeo_dom']}, 16:17:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['basic_compositor_video']}, 16:17:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:17:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['a11yr', 16:17:20 INFO - 'ts_paint', 16:17:20 INFO - 'tpaint', 16:17:20 INFO - 'sessionrestore', 16:17:20 INFO - 'sessionrestore_no_auto_restore', 16:17:20 INFO - 'tabpaint']}, 16:17:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['tsvgx', 16:17:20 INFO - 'tsvgr_opacity', 16:17:20 INFO - 'tart', 16:17:20 INFO - 'tscrollx', 16:17:20 INFO - 'cart']}, 16:17:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:20 INFO - 'tsvgr_opacity', 16:17:20 INFO - 'tart', 16:17:20 INFO - 'tscrollx', 16:17:20 INFO - 'cart']}, 16:17:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'talos_options': ['--disable-e10s'], 16:17:20 INFO - 'tests': ['tp5o']}, 16:17:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'tests': ['tp5o']}, 16:17:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'talos_options': ['--disable-e10s', 16:17:20 INFO - '--xperf_path', 16:17:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:20 INFO - 'tests': ['tp5n']}, 16:17:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:20 INFO - 'talos_options': ['--xperf_path', 16:17:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:20 INFO - 'tests': ['tp5n']}}, 16:17:20 INFO - 'talos.zip': {'path': '', 16:17:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:20 INFO - Running post-action listener: _resource_record_post_action 16:17:20 INFO - ##### 16:17:20 INFO - ##### Finished populate-webroot step (success) 16:17:20 INFO - ##### 16:17:20 INFO - ##### 16:17:20 INFO - ##### Running create-virtualenv step. 16:17:20 INFO - ##### 16:17:20 INFO - Running pre-action listener: _resource_record_pre_action 16:17:20 INFO - Running main action method: create_virtualenv 16:17:20 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:20 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 16:17:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:20 INFO - Using real prefix '/usr' 16:17:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:21 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:22 INFO - Installing pip.................done. 16:17:22 INFO - Return code: 0 16:17:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:22 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:22 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 16:17:22 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 16:17:22 INFO - Using env: {'DISPLAY': ':0', 16:17:22 INFO - 'HOME': '/home/cltbld', 16:17:22 INFO - 'LANG': 'en_US.UTF-8', 16:17:22 INFO - 'LANGUAGE': 'en_US:en', 16:17:22 INFO - 'LOGNAME': 'cltbld', 16:17:22 INFO - 'MAIL': '/var/mail/cltbld', 16:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:22 INFO - 'MOZ_NO_REMOTE': '1', 16:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:22 INFO - 'NO_EM_RESTART': '1', 16:17:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:23 INFO - 'PWD': '/builds/slave/test', 16:17:23 INFO - 'SHELL': '/bin/bash', 16:17:23 INFO - 'SHLVL': '1', 16:17:23 INFO - 'TERM': 'linux', 16:17:23 INFO - 'TMOUT': '86400', 16:17:23 INFO - 'USER': 'cltbld', 16:17:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:23 INFO - '_': '/tools/buildbot/bin/python'} 16:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:23 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 16:17:23 INFO - Cleaning up... 16:17:23 INFO - Return code: 0 16:17:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:23 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:23 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 16:17:23 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 16:17:23 INFO - Using env: {'DISPLAY': ':0', 16:17:23 INFO - 'HOME': '/home/cltbld', 16:17:23 INFO - 'LANG': 'en_US.UTF-8', 16:17:23 INFO - 'LANGUAGE': 'en_US:en', 16:17:23 INFO - 'LOGNAME': 'cltbld', 16:17:23 INFO - 'MAIL': '/var/mail/cltbld', 16:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:23 INFO - 'MOZ_NO_REMOTE': '1', 16:17:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:23 INFO - 'NO_EM_RESTART': '1', 16:17:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:23 INFO - 'PWD': '/builds/slave/test', 16:17:23 INFO - 'SHELL': '/bin/bash', 16:17:23 INFO - 'SHLVL': '1', 16:17:23 INFO - 'TERM': 'linux', 16:17:23 INFO - 'TMOUT': '86400', 16:17:23 INFO - 'USER': 'cltbld', 16:17:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:23 INFO - '_': '/tools/buildbot/bin/python'} 16:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:23 INFO - Downloading/unpacking psutil>=3.1.1 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:25 INFO - Installing collected packages: psutil 16:17:25 INFO - Running setup.py install for psutil 16:17:25 INFO - building 'psutil._psutil_linux' extension 16:17:25 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 16:17:25 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 16:17:25 INFO - building 'psutil._psutil_posix' extension 16:17:25 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 16:17:25 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 16:17:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:25 INFO - Successfully installed psutil 16:17:25 INFO - Cleaning up... 16:17:25 INFO - Return code: 0 16:17:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:25 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:25 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 16:17:25 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 16:17:25 INFO - Using env: {'DISPLAY': ':0', 16:17:25 INFO - 'HOME': '/home/cltbld', 16:17:25 INFO - 'LANG': 'en_US.UTF-8', 16:17:25 INFO - 'LANGUAGE': 'en_US:en', 16:17:25 INFO - 'LOGNAME': 'cltbld', 16:17:25 INFO - 'MAIL': '/var/mail/cltbld', 16:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:25 INFO - 'MOZ_NO_REMOTE': '1', 16:17:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:25 INFO - 'NO_EM_RESTART': '1', 16:17:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:25 INFO - 'PWD': '/builds/slave/test', 16:17:25 INFO - 'SHELL': '/bin/bash', 16:17:25 INFO - 'SHLVL': '1', 16:17:25 INFO - 'TERM': 'linux', 16:17:25 INFO - 'TMOUT': '86400', 16:17:25 INFO - 'USER': 'cltbld', 16:17:25 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:25 INFO - '_': '/tools/buildbot/bin/python'} 16:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:26 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 16:17:26 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 16:17:26 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 16:17:26 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 16:17:26 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 16:17:26 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 16:17:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:28 INFO - Installing collected packages: mozsystemmonitor 16:17:28 INFO - Running setup.py install for mozsystemmonitor 16:17:28 INFO - Successfully installed mozsystemmonitor 16:17:28 INFO - Cleaning up... 16:17:28 INFO - Return code: 0 16:17:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:28 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:28 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 16:17:28 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 16:17:28 INFO - Using env: {'DISPLAY': ':0', 16:17:28 INFO - 'HOME': '/home/cltbld', 16:17:28 INFO - 'LANG': 'en_US.UTF-8', 16:17:28 INFO - 'LANGUAGE': 'en_US:en', 16:17:28 INFO - 'LOGNAME': 'cltbld', 16:17:28 INFO - 'MAIL': '/var/mail/cltbld', 16:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:28 INFO - 'MOZ_NO_REMOTE': '1', 16:17:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:28 INFO - 'NO_EM_RESTART': '1', 16:17:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:28 INFO - 'PWD': '/builds/slave/test', 16:17:28 INFO - 'SHELL': '/bin/bash', 16:17:28 INFO - 'SHLVL': '1', 16:17:28 INFO - 'TERM': 'linux', 16:17:28 INFO - 'TMOUT': '86400', 16:17:28 INFO - 'USER': 'cltbld', 16:17:28 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:28 INFO - '_': '/tools/buildbot/bin/python'} 16:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:17:28 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 16:17:28 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 16:17:28 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 16:17:28 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 16:17:28 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 16:17:28 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 16:17:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:17:30 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 16:17:30 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 16:17:30 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 16:17:30 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 16:17:30 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 16:17:30 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 16:17:30 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:30 INFO - warning: no files found matching '*.txt' 16:17:30 INFO - no previously-included directories found matching 'build' 16:17:30 INFO - no previously-included directories found matching 'dist' 16:17:30 INFO - no previously-included directories found matching '.git*' 16:17:30 INFO - Installing collected packages: jsonschema, functools32 16:17:30 INFO - Running setup.py install for functools32 16:17:30 INFO - warning: no files found matching '*.txt' 16:17:30 INFO - no previously-included directories found matching 'build' 16:17:30 INFO - no previously-included directories found matching 'dist' 16:17:30 INFO - no previously-included directories found matching '.git*' 16:17:30 INFO - Successfully installed jsonschema functools32 16:17:30 INFO - Cleaning up... 16:17:30 INFO - Return code: 0 16:17:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:17:30 INFO - Using env: {'DISPLAY': ':0', 16:17:30 INFO - 'HOME': '/home/cltbld', 16:17:30 INFO - 'LANG': 'en_US.UTF-8', 16:17:30 INFO - 'LANGUAGE': 'en_US:en', 16:17:30 INFO - 'LOGNAME': 'cltbld', 16:17:30 INFO - 'MAIL': '/var/mail/cltbld', 16:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:30 INFO - 'MOZ_NO_REMOTE': '1', 16:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:30 INFO - 'NO_EM_RESTART': '1', 16:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:30 INFO - 'PWD': '/builds/slave/test', 16:17:30 INFO - 'SHELL': '/bin/bash', 16:17:30 INFO - 'SHLVL': '1', 16:17:30 INFO - 'TERM': 'linux', 16:17:30 INFO - 'TMOUT': '86400', 16:17:30 INFO - 'USER': 'cltbld', 16:17:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:30 INFO - '_': '/tools/buildbot/bin/python'} 16:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:31 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:33 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 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 16:17:33 INFO - Downloading docopt-0.6.1.tar.gz 16:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:33 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:33 INFO - Running setup.py install for blobuploader 16:17:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:33 INFO - Running setup.py install for requests 16:17:34 INFO - Running setup.py install for docopt 16:17:34 INFO - Successfully installed blobuploader requests docopt 16:17:34 INFO - Cleaning up... 16:17:34 INFO - Return code: 0 16:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:34 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:34 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 16:17:34 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 16:17:34 INFO - Using env: {'DISPLAY': ':0', 16:17:34 INFO - 'HOME': '/home/cltbld', 16:17:34 INFO - 'LANG': 'en_US.UTF-8', 16:17:34 INFO - 'LANGUAGE': 'en_US:en', 16:17:34 INFO - 'LOGNAME': 'cltbld', 16:17:34 INFO - 'MAIL': '/var/mail/cltbld', 16:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:34 INFO - 'MOZ_NO_REMOTE': '1', 16:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:34 INFO - 'NO_EM_RESTART': '1', 16:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:34 INFO - 'PWD': '/builds/slave/test', 16:17:34 INFO - 'SHELL': '/bin/bash', 16:17:34 INFO - 'SHLVL': '1', 16:17:34 INFO - 'TERM': 'linux', 16:17:34 INFO - 'TMOUT': '86400', 16:17:34 INFO - 'USER': 'cltbld', 16:17:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:34 INFO - '_': '/tools/buildbot/bin/python'} 16:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:34 INFO - Running setup.py (path:/tmp/pip-64x8Dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:34 INFO - Running setup.py (path:/tmp/pip-Z2LM1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:34 INFO - Running setup.py (path:/tmp/pip-ssTElB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:34 INFO - Running setup.py (path:/tmp/pip-Dsmfq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:34 INFO - Running setup.py (path:/tmp/pip-2ChdNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:35 INFO - Running setup.py (path:/tmp/pip-skWou6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:35 INFO - Running setup.py (path:/tmp/pip-UiXaWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:35 INFO - Running setup.py (path:/tmp/pip-auDX6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:35 INFO - Running setup.py (path:/tmp/pip-47iJ_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:35 INFO - Running setup.py (path:/tmp/pip-2lWngm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:35 INFO - Running setup.py (path:/tmp/pip-nc3UNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:35 INFO - Running setup.py (path:/tmp/pip-zaALQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:35 INFO - Running setup.py (path:/tmp/pip-zpsoFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:35 INFO - Running setup.py (path:/tmp/pip-jnJ1f1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:35 INFO - Running setup.py (path:/tmp/pip-4rCLPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:35 INFO - Running setup.py (path:/tmp/pip-A773jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:35 INFO - Running setup.py (path:/tmp/pip-HfpjN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:35 INFO - Running setup.py install for manifestparser 16:17:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Running setup.py install for mozcrash 16:17:36 INFO - Running setup.py install for mozdebug 16:17:36 INFO - Running setup.py install for mozdevice 16:17:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Running setup.py install for mozfile 16:17:36 INFO - Running setup.py install for mozhttpd 16:17:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Running setup.py install for mozinfo 16:17:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Running setup.py install for mozInstall 16:17:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:36 INFO - Running setup.py install for mozleak 16:17:36 INFO - Running setup.py install for mozlog 16:17:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Running setup.py install for moznetwork 16:17:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Running setup.py install for mozprocess 16:17:37 INFO - Running setup.py install for mozprofile 16:17:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Running setup.py install for mozrunner 16:17:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Running setup.py install for mozscreenshot 16:17:37 INFO - Running setup.py install for moztest 16:17:37 INFO - Running setup.py install for mozversion 16:17:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:37 INFO - Cleaning up... 16:17:37 INFO - Return code: 0 16:17:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:37 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:37 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 16:17:37 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 16:17:37 INFO - Using env: {'DISPLAY': ':0', 16:17:37 INFO - 'HOME': '/home/cltbld', 16:17:37 INFO - 'LANG': 'en_US.UTF-8', 16:17:37 INFO - 'LANGUAGE': 'en_US:en', 16:17:37 INFO - 'LOGNAME': 'cltbld', 16:17:37 INFO - 'MAIL': '/var/mail/cltbld', 16:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:37 INFO - 'MOZ_NO_REMOTE': '1', 16:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:37 INFO - 'NO_EM_RESTART': '1', 16:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:37 INFO - 'PWD': '/builds/slave/test', 16:17:37 INFO - 'SHELL': '/bin/bash', 16:17:37 INFO - 'SHLVL': '1', 16:17:37 INFO - 'TERM': 'linux', 16:17:37 INFO - 'TMOUT': '86400', 16:17:37 INFO - 'USER': 'cltbld', 16:17:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:37 INFO - '_': '/tools/buildbot/bin/python'} 16:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:37 INFO - Running setup.py (path:/tmp/pip-q6xgXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:38 INFO - Running setup.py (path:/tmp/pip-syrT3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:38 INFO - Running setup.py (path:/tmp/pip-Y80xRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:38 INFO - Running setup.py (path:/tmp/pip-JnROv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:38 INFO - Running setup.py (path:/tmp/pip-32aSm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:38 INFO - Running setup.py (path:/tmp/pip-jAibLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:38 INFO - Running setup.py (path:/tmp/pip-gkenB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:38 INFO - Running setup.py (path:/tmp/pip-ZKTR8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:38 INFO - Running setup.py (path:/tmp/pip-98eIo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:38 INFO - Running setup.py (path:/tmp/pip-Q2Aiqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:38 INFO - Running setup.py (path:/tmp/pip-Ywo9VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:38 INFO - Running setup.py (path:/tmp/pip-Q3yxbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:38 INFO - Running setup.py (path:/tmp/pip-Kb2KBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:38 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)) 16:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:38 INFO - Running setup.py (path:/tmp/pip-zUlu6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:39 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)) 16:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:39 INFO - Running setup.py (path:/tmp/pip-KvIyuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:39 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)) 16:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:39 INFO - Running setup.py (path:/tmp/pip-FkHSxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:39 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)) 16:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:39 INFO - Running setup.py (path:/tmp/pip-Oxg_je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:39 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)) 16:17:39 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)) 16:17:39 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)) 16:17:39 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)) 16:17:39 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)) 16:17:39 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)) 16:17:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:41 INFO - Downloading blessings-1.6.tar.gz 16:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:41 INFO - Installing collected packages: blessings 16:17:41 INFO - Running setup.py install for blessings 16:17:41 INFO - Successfully installed blessings 16:17:41 INFO - Cleaning up... 16:17:41 INFO - Return code: 0 16:17:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:41 INFO - Reading from file tmpfile_stdout 16:17:41 INFO - Current package versions: 16:17:41 INFO - argparse == 1.2.1 16:17:41 INFO - blessings == 1.6 16:17:41 INFO - blobuploader == 1.2.4 16:17:41 INFO - docopt == 0.6.1 16:17:41 INFO - functools32 == 3.2.3-2 16:17:41 INFO - jsonschema == 2.5.1 16:17:41 INFO - manifestparser == 1.1 16:17:41 INFO - mozInstall == 1.12 16:17:41 INFO - mozcrash == 0.17 16:17:41 INFO - mozdebug == 0.1 16:17:41 INFO - mozdevice == 0.48 16:17:41 INFO - mozfile == 1.2 16:17:41 INFO - mozhttpd == 0.7 16:17:41 INFO - mozinfo == 0.9 16:17:41 INFO - mozleak == 0.1 16:17:41 INFO - mozlog == 3.2 16:17:41 INFO - moznetwork == 0.27 16:17:41 INFO - mozprocess == 0.23 16:17:41 INFO - mozprofile == 0.28 16:17:41 INFO - mozrunner == 6.12 16:17:41 INFO - mozscreenshot == 0.1 16:17:41 INFO - mozsystemmonitor == 0.3 16:17:41 INFO - moztest == 0.7 16:17:41 INFO - mozversion == 1.4 16:17:41 INFO - psutil == 3.1.1 16:17:41 INFO - requests == 1.2.3 16:17:41 INFO - wsgiref == 0.1.2 16:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:41 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:41 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 16:17:41 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 16:17:41 INFO - Using env: {'DISPLAY': ':0', 16:17:41 INFO - 'HOME': '/home/cltbld', 16:17:41 INFO - 'LANG': 'en_US.UTF-8', 16:17:41 INFO - 'LANGUAGE': 'en_US:en', 16:17:41 INFO - 'LOGNAME': 'cltbld', 16:17:41 INFO - 'MAIL': '/var/mail/cltbld', 16:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:41 INFO - 'MOZ_NO_REMOTE': '1', 16:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:41 INFO - 'NO_EM_RESTART': '1', 16:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:41 INFO - 'PWD': '/builds/slave/test', 16:17:41 INFO - 'SHELL': '/bin/bash', 16:17:41 INFO - 'SHLVL': '1', 16:17:41 INFO - 'TERM': 'linux', 16:17:41 INFO - 'TMOUT': '86400', 16:17:41 INFO - 'USER': 'cltbld', 16:17:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:41 INFO - '_': '/tools/buildbot/bin/python'} 16:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 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)) 16:17:41 INFO - Cleaning up... 16:17:41 INFO - Return code: 0 16:17:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:41 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 0x11f1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', '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 16:17:41 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 16:17:41 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 16:17:41 INFO - Using env: {'DISPLAY': ':0', 16:17:41 INFO - 'HOME': '/home/cltbld', 16:17:41 INFO - 'LANG': 'en_US.UTF-8', 16:17:41 INFO - 'LANGUAGE': 'en_US:en', 16:17:41 INFO - 'LOGNAME': 'cltbld', 16:17:41 INFO - 'MAIL': '/var/mail/cltbld', 16:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:41 INFO - 'MOZ_NO_REMOTE': '1', 16:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:41 INFO - 'NO_EM_RESTART': '1', 16:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:41 INFO - 'PWD': '/builds/slave/test', 16:17:41 INFO - 'SHELL': '/bin/bash', 16:17:41 INFO - 'SHLVL': '1', 16:17:41 INFO - 'TERM': 'linux', 16:17:41 INFO - 'TMOUT': '86400', 16:17:41 INFO - 'USER': 'cltbld', 16:17:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:41 INFO - '_': '/tools/buildbot/bin/python'} 16:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:42 INFO - Cleaning up... 16:17:42 INFO - Return code: 0 16:17:42 INFO - Running post-action listener: _resource_record_post_action 16:17:42 INFO - Running post-action listener: _start_resource_monitoring 16:17:42 INFO - Starting resource monitoring. 16:17:42 INFO - ##### 16:17:42 INFO - ##### Finished create-virtualenv step (success) 16:17:42 INFO - ##### 16:17:42 INFO - ##### 16:17:42 INFO - ##### Running install step. 16:17:42 INFO - ##### 16:17:42 INFO - Running pre-action listener: _resource_record_pre_action 16:17:42 INFO - Running main action method: install 16:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:42 INFO - Reading from file tmpfile_stdout 16:17:42 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:42 INFO - Reading from file tmpfile_stdout 16:17:42 INFO - Output received: 16:17:42 INFO - Usage: mozinstall [options] installer 16:17:42 INFO - Options: 16:17:42 INFO - -h, --help show this help message and exit 16:17:42 INFO - -d DEST, --destination=DEST 16:17:42 INFO - Directory to install application into. [default: 16:17:42 INFO - "/builds/slave/test"] 16:17:42 INFO - --app=APP Application being installed. [default: firefox] 16:17:42 INFO - mkdir: /builds/slave/test/build/application 16:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:17:58 INFO - Reading from file tmpfile_stdout 16:17:58 INFO - Output received: 16:17:58 INFO - /builds/slave/test/build/application/firefox/firefox 16:17:58 INFO - Running post-action listener: _resource_record_post_action 16:17:58 INFO - ##### 16:17:58 INFO - ##### Finished install step (success) 16:17:58 INFO - ##### 16:17:58 INFO - ##### 16:17:58 INFO - ##### Running run-tests step. 16:17:58 INFO - ##### 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: run_tests 16:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:58 INFO - Python 2.7.3 16:17:58 INFO - Return code: 0 16:17:58 INFO - grabbing minidump binary from tooltool 16:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:58 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 0x14b03b0>, '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 0x14b12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b1740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:58 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 16:17:58 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 16:17:58 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 16:17:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:58 INFO - Return code: 0 16:17:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:17:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:17:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 16:17:58 INFO - Using env: {'DISPLAY': ':0', 16:17:58 INFO - 'HOME': '/home/cltbld', 16:17:58 INFO - 'LANG': 'en_US.UTF-8', 16:17:58 INFO - 'LANGUAGE': 'en_US:en', 16:17:58 INFO - 'LOGNAME': 'cltbld', 16:17:58 INFO - 'MAIL': '/var/mail/cltbld', 16:17:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:58 INFO - 'MOZ_NO_REMOTE': '1', 16:17:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:58 INFO - 'NO_EM_RESTART': '1', 16:17:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:58 INFO - 'PWD': '/builds/slave/test', 16:17:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:58 INFO - 'SHELL': '/bin/bash', 16:17:58 INFO - 'SHLVL': '1', 16:17:58 INFO - 'TERM': 'linux', 16:17:58 INFO - 'TMOUT': '86400', 16:17:58 INFO - 'USER': 'cltbld', 16:17:58 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147', 16:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:58 INFO - '_': '/tools/buildbot/bin/python'} 16:17:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:17:58 INFO - mozversion application_buildid: 20160718145619 16:17:58 INFO - mozversion application_changeset: feaaf1af1065257b9178faca8b67eed9657b4a17 16:17:58 INFO - mozversion application_display_name: Nightly 16:17:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:58 INFO - mozversion application_name: Firefox 16:17:58 INFO - mozversion application_remotingname: firefox 16:17:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:17:58 INFO - mozversion application_vendor: Mozilla 16:17:58 INFO - mozversion application_version: 50.0a1 16:17:58 INFO - mozversion platform_buildid: 20160718145619 16:17:58 INFO - mozversion platform_changeset: feaaf1af1065257b9178faca8b67eed9657b4a17 16:17:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:17:58 INFO - mozversion platform_version: 50.0a1 16:17:58 INFO - using testdate: 1468883878 16:17:58 INFO - actual date: 1468883878 16:17:58 INFO - starting webserver on 'localhost:56316' 16:17:58 INFO - SUITE-START | Running 2 tests 16:17:58 INFO - TEST-START | dromaeo_css 16:17:58 INFO - operating with platform_type : linux_ 16:17:58 INFO - Initialising browser for dromaeo_css test... 16:17:59 INFO - TEST-INFO | started process 17440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbOB49Z/profile http://localhost:56316/getInfo.html) 16:18:03 INFO - PROCESS | 17440 | __metrics Screen width/height:1600/1200 16:18:03 INFO - PROCESS | 17440 | colorDepth:24 16:18:03 INFO - PROCESS | 17440 | Browser inner width/height: 1024/697 16:18:03 INFO - PROCESS | 17440 | __metrics 16:18:04 INFO - PROCESS | 17440 | 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" 16:18:04 INFO - PROCESS | 17440 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:05 INFO - TEST-INFO | 17440: exit 0 16:18:05 INFO - Browser initialized. 16:18:05 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:05 INFO - TEST-INFO | started process 17523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbOB49Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:17 INFO - PROCESS | 17523 | RSS: Main: 159326208 16:18:17 INFO - PROCESS | 17523 | 16:19:08 INFO - PROCESS | 17523 | 1468883948717 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] 16:21:20 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56316/tests/dromaeo/cssquery-ext.html) 16:21:21 INFO - PROCESS | 17523 | RSS: Main: 265535488 16:21:21 INFO - PROCESS | 17523 | 16:24:24 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-ext.html (next: http://localhost:56316/tests/dromaeo/cssquery-jquery.html) 16:24:25 INFO - PROCESS | 17523 | RSS: Main: 238178304 16:24:25 INFO - PROCESS | 17523 | 16:27:27 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56316/tests/dromaeo/cssquery-mootools.html) 16:27:28 INFO - PROCESS | 17523 | RSS: Main: 240685056 16:27:28 INFO - PROCESS | 17523 | 16:30:30 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56316/tests/dromaeo/cssquery-prototype.html) 16:30:31 INFO - PROCESS | 17523 | RSS: Main: 237596672 16:30:31 INFO - PROCESS | 17523 | 16:33:34 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56316/tests/dromaeo/cssquery-yui.html) 16:33:34 INFO - PROCESS | 17523 | RSS: Main: 222900224 16:33:34 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | Cycle 1(1): loaded http://localhost:56316/tests/dromaeo/cssquery-yui.html (next: http://localhost:56316/tests/dromaeo/cssquery-dojo.html) 16:36:38 INFO - PROCESS | 17523 | RSS: Main: 287404032 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | __start_tp_report 16:36:38 INFO - PROCESS | 17523 | _x_x_mozilla_page_load 16:36:38 INFO - PROCESS | 17523 | _x_x_mozilla_page_load_details 16:36:38 INFO - PROCESS | 17523 | |i|pagename|runs| 16:36:38 INFO - PROCESS | 17523 | |0;dojo.html;2614.0458732562615;2626.2252635472532;2655.4025344297534;2664.880080396382;2665.506881226974;4447.599716025565;4512.278035514319;4669.299605059238;4707.882302942428;4718.716876987379;4576.313552967056;4612.769361531919;4613.8615841524725;4652.348671186037;4696.342512048316;4277.764722940237;4297.183535128315;4301.526832048487;4356.15055064261;4401.097774956126;4343.630791382736;4389.626881715058;4410.735355878657;4418.4256046714045;4458.79935402906;4157.3140431828715;4203.894902627429;4323.935140972888;4334.046509767846;4413.735175889441;4213.5786421357925;4218.767967761772;4255.063885945087;4273.380359847836;4341.435613370273;4248.044190057243;4254.446921900133;4261.211675839949;4287.056847493546;4297.162053399588;4589.196890544142;4618.930716039262;4620.283856002291;4641.767911604406;4666.673332866701;4770.236762118079;4963.45402005779;5036.244563315496;5039;5094.083065048289;5124.769385377667;5126.871828204325;5140.3060586820675;5145.176771716545;5151.819686310999;4564.155631208171;4747.905041899212;4749.881252968704;4753.83361582343;4789.880252993668;5086.618503612244;5136.178211486219;5136.9743151284;5148.433672296044;5222.1383471727495;4970.776315065831;4991;5017.548420642087;5022.573081288131;5023.497650234947;4770.379850619397;4908.631852611068;5042.520960508746;5102.923456148161;5111.182210846321;4870.0746858096845;4917.5082491751255;4928.876778080505;4967.05625931072;4985.302057711923;4917.237828136645;4917.385326834145;4920.163572192736;4921.50784921505;4976;4153;4195.1819395217635;4256.191323648497;4259.403683484253;4318.892027699269;3719.7210209234418;3783.3379158647126;3795.7153213509096;3833.769973801525;3851.171998020439;4306.074194048295;4320.675949303814;4325.091730736581;4326.329418940069;4358.5205627380965;5084.542391184811;5089.312942752681;5089.694618322913;5091.3381260436645;5127.564157046693;4138.710290279711;4161.895952601149;4164.895877603023;4175;4181.623653871166;4402.163588918095;4431.623312018514;4432.3794668746405;4477.440319960005;4483.394741709827;4535.092981403733;4540.704854184468;4575.931361029588;4578.88552786183;4626.491085980607;5044.621653375938;5097.872553186052;5100.846974590768;5119.846404607868;5121.7695203715175;4794.904101917872;4808.951910480991;4816.831410900601;4821.975890120527;4864.318995340726;4694.2254528002495;4822.517748225149;4980.32765576657;5000.449950505369;5043.445221025758;4967.031428871335;4982.252662100569;5005.899882002267;5006.3992320921725;5053.84838454847;4853.927191092066;4869.683470574401;4871.732054737024;4873.439554451284;4919.926201106915;4714.669973101987;4874.122657921608;4889.511048895082;4906.313116163811;4924.4090709115135;4862.635302352267;4876.780544875418;4908.558229759339;4927.28554359623;4934;4961.181405068268;4971.204607262821;4971.552560269449;4971.801127954841;5018.548330650259;4645.488996210482;4716.976415117902;4737.692050016738;4778.5699287064335;4861.538153875376 16:36:38 INFO - PROCESS | 17523 | |1;ext.html;576.3919065386017;581.845810860122;591.1694069831885;592.1000079878587;592.3365830270099;30070.84964575177;30728.38543229137;30748.385032299368;38745.61254387455;40566.7971660142;42131.368029479585;42427.57572424275;42451.15097698044;42527.57472425282;42583;7934.365250779938;8073.233042860918;8076.798080048016;8077.798055048612;8081.393895457834;7943.523388596703;8037.0355557333205;8037.598120094001;8037.839243215132;8044.637991290377;8026.398020148512;8052.59737013147;8054.315383192436;8058.798530036766;8063.0324361076755;8014;8027.7190298339865;8029.237222463857;8034.035915690155;8040.758777236692;7955.204479551999;8005.719799806979;8009.239122283374;8012.519248845029;8013.0384353877325;38789.41815872764;39406.21187576261;39453.60546394528;39535;39558.60441395578;3038.2860027893425;3072.800267982574;3103.5189545620465;3131.874725010997;3163.1775738307974;29541.08053411648;29579;30260.84869575638;30330.241743956358;30472;3491.877784277538;3541.643887359228;3557.6442355764216;3582.0477839577493;3613.1339466770564;264807;265812;267154.2991990542;267173;267708;54163.45836541584;54303.45696543063;54367.72816135973;54416;54418.72790636022;32650.857219997186;33577.83211083929;50645.746771265905;50697.49302507001;50700.98598028019;15403.306851191723;15477.5356739298;15542.45601403968;15545.84454155444;15577.376904923904;10864.348139111678;10961.83557246642;10995.120390368751;10996.23026388145;11000.504977276041;18682.532936676693;18716.438506844817;18833.811661883206;18844.717329240073;18880.81119188818;6821.93178068213;7065.2228254891925;7068.787936361918;7078.1506219254015;7095.9290407096305;778.1751343575828;803.7789607857886;807.7415227127262;936.9531523423801;1033.245730616639;24624.753752462246;26468.867655661597;27551.44897102047;27904.720952790212;28022;778.283978739561;921.4195057114109;961.6538046303325;969.4425705219527;1000.0249756487367;14351.497697580535;14822.481213157487;14823.40706371733;15069.849301507064;15785.526434206991;195320;196205;196349;196850;363036;51815.481845181064;52020.739896301035;52191.73904130455;52353;52431;1107.3687997841314;1133.1218305812897;1184.2688459603767;1201.6034708546158;1224.1186345831165;25580.360490987874;28800.711992880642;29051;29477.857903997734;29785.25536861596;43506.56493435025;44272.55727442813;44339.11321773611;44346;44381.33427998518;303102;303580;305854;308629;308834;29674.851625741732;33077;33088.83455582707;35107.473387899714;35575.82212088923;39682.80158599189;41958.370624440046;42193.78903105465;42297.788511057246;43075.784621076695;35955.64044359523;36058.45912311265;36794.26411471809;36810.26379472342;36951.630483695895;36257.456138157424;36629.450558242184;36741;36780;36825.81587092047;35402.82298588491;35797.82101089582;36250;36527.817360913024;36576.634233658384;31774.364512709155;32670;32945.505817413235;32986.67013329836;33017.66982330242;24186.609037016002;24308.75691243065;24447.633285501088;24984.625230621918;25263.74736252614 16:36:38 INFO - PROCESS | 17523 | |2;jquery.html;343.4476167721896;360.80155914247166;369.21541723836845;371.5003320533881;378.0756051454194;12887;16293.429729959438;16742.0791856448;16984.660306793856;17950.10249487527;17016;17057.40299089532;17567.91216043918;17931.73102403465;18155.27378904842;5139.897202055948;5218.973905130469;5442.700651464168;5479.506844384005;5543.085390910495;3360.899173024813;5114.846554603368;5392.811251606195;5420.4850539198715;5519.668819870801;5442.183672449144;5446.128619420893;5482;5482.725863706819;5491.862703432406;5341.4925582069645;5357.1160758475;5432.456754324536;5445.237666726662;5451.700156491392;3343.5319055332275;4898.093852637237;5091.261767043758;5133.691978481265;5288.788448462095;12066.939665301705;12108.394580271039;12336.198147120498;12510.437030333656;20882.5823483531;1833.4133077415233;1967.6359873423462;1971.083446197525;2116.6507526258147;2240.9887950560305;8783.736487905373;9196.12636799503;9262.351635385527;9621.13409793115;9627.170837540845;1766.8939863608095;1814.292425953879;1954.7556555430572;1968.3307675390436;2031.065709773491;65735;68803.65598172027;69230;69630;70339.99999999949;8745.781355466164;8771.166739159897;8934.329925255632;9373.578188981512;9854.127715733988;12289.201201921847;12986.935065324613;13087.149335293367;13088.541901033608;13094.476220951246;5607.383187849415;7155.92844071563;7493.85012299751;7633.160352361236;7811.4922530035365;7213.206547279795;7364.742234021784;7407.8888816667795;7549.50928189666;7774.766756997186;3974.324364857981;4190.55999120088;4342.869713908588;4362.432883725095;4390.956090439118;1674.7236705943626;1756.4203812741764;1845.9815401846079;1851.6944704123669;1899.848012159024;838.279079991207;847.9321654267644;887.5029983209423;895.2899961074316;904.1591320072396;16336.509904702878;16601.91699041521;16980.32078716862;17159.914200428917;17405.65188696219;862.6721845698594;863.3438586674048;881.4975463985595;884.3632584539122;929.7489677787095;9518.381305215138;9561.426314421018;9593.760155996017;10015.69952901414;10690.679279621623;413728;414390;415923;416746;438930;85057;91482.08517914735;91683.54158229165;91784.54107729418;91807;880.3441436130124;911.40758506971;927.4018258223408;927.6289484206371;954.6038394066524;15781.21093945321;16056.197190140212;16107.58386242073;16183.838161618232;16382.836171638608;33409.331813364086;34164.65835341615;39335;40324.596754032085;40586.39120413255;51183.48816511787;51686;54397.18404223861;56491;56494;6079.905616988807;7232.240614735377;7549.849003020019;7568.508046976929;7919.049714034353;10347;10784.946075269887;11046.944765276123;11269.830952535882;11320.886791131983;9849;10143.137833284254;10147.137493313152;10162.136218421221;10254.128399085863;9698.46658433793;9849.359791613522;9895.50522473859;9963.302568820112;10048.296619236584;9834.409935403899;9846.803063938825;9914.504274786375;9948.154406875497;10038.397696138256;7120.465965052746;7231.132264128128;7373.8156546086775;7403.5928023959195;7653.732119375795;9859.704208873745;10105.44420056904;10168.389896605931;10563.52464139131;10726.463676816284 16:36:38 INFO - PROCESS | 17523 | |3;mootools.html;1363.5295822941084;1414.2999215388381;1473.56529823702;1688.510332003719;1690.28163030712;6572.934270657292;6799.286074962132;7251.818704532389;7784;7785.571793551352;7426.5544067355995;7455.66449509772;7460.29127232912;7682.385409167267;7742.8838567421535;2143.8284937205062;2196.703445034924;2222.92219772308;2225.6216443204694;2231.2859884836857;1818.5453636590853;2117.1319758898853;2181.9890900545474;2184.7050648162535;2199.582079404916;2172.348295511348;2179.3570896585466;2185.617516934538;2186.158329043322;2187.037703410502;2058.197303051811;2155.838312126581;2164.101897712447;2165.566886622682;2167.133146741316;2079.1683326669304;2154.827613790893;2158.6546152615665;2172.120291282033;2172.891355432222;7870.252326029018;7905.130435652131;7911.169327220674;7924.722634707758;7927.445078844483;4641.9323555582405;4765.928511072337;4766.833160839354;4767.046590681877;4772.880677983044;2821.3510892494637;2870.468963241807;2902.8693708783157;2914.664813546448;2923.166897434221;887.4763889305276;922.5055410235461;939.1359948847008;948.7296120605595;961.1253759079204;10324.174066074771;10501.579936802518;10501.894981050245;10523.84214236787;10527.368357898551;4011.1576568920855;4180.770057646859;4292.248856450109;4350.260455722471;4358.6295164911335;9050.954745226363;9085.50029748357;9095.545222738836;9112;9116.544172791333;4698.060387922429;4701.623870090384;4701.858944231679;4704.9529504704515;4709.835155769531;3367.074054634995;4285.292926667127;4293.656507479394;4294.6993710439965;4296.419983302277;3651.580068292127;3824.7896365700153;3866.7873266970587;3875.476810630585;3905.5508616509464;1211.7273613436905;1235.5490246060124;1241.3669028795198;1246.1339369138543;1250.306080125539;784.4113182191496;820.0692214336651;845.1464021338493;855.0167307596314;863.1411745313453;5120.334356533627;5202.479752024843;5324.733763311819;5342.706151161646;5597.356304025089;390.8299889548056;391.50670155604143;395.2214138147844;397.564666689974;923.8568021956473;3561.6438356164176;3809.085819403323;3830.8659196927974;3836.1176929306416;3854.113553882623;9400.764980875532;9736;9878.110970012733;9997.900020999698;10043.397396156108;9831.066048725359;9846.458444785463;9869.506524673738;9936.850947235796;9948.452835093995;7056.717731290793;7337.926620733831;7364.2635736427;7369.742059027908;7567.583782891994;5465.344158700981;5520.420355862737;5636.774529018793;5679.119736440858;5702.6863522506665;8686.869696954676;8818.162274583905;8867.290616750515;9075.500847453453;9076.137766912132;8819.514926678838;9140.314476414163;9199.540022998957;9203.493807840632;9227.077292270718;6017.458428741259;6529.183852018497;6746.224184218878;6892.000659904185;6893.965530172317;7475.140358858801;7696.461247712607;7775.339096176888;7792.454528182972;7794.88307675374;6738.427233684995;6797.93202067973;6901.413379862567;6908.861822763617;7069.858602828018;6772.864542709217;6789.592624442549;6853.280405557544;6971.058907047483;7029.894551581832;6093.146959425595;6369.172007638977;6398.0402939559435;6432.742690292524;6435.388638079375;4639.582437580634;4662.650301227353;4693.436787585511;4780.378550788375;4795.064962332312;4652.302154676826;4654.069186162713;4710.987137765397;4736.881577960578;4742.952570474389 16:36:38 INFO - PROCESS | 17523 | |4;prototype.html;299.50581540458245;300.3242703916189;304.9466343389907;310.0296073290601;311.41143239277784;4596.49438561758;4704.882377940553;4731.668783185179;4770.809167633299;4783.632102109452;4601.378813860128;4729.344729344728;4741.431028276603;4745.667803253774;4751.976240118804;3783.735138540303;3810.1427178884737;3814.218085292522;3819.2934307153128;3820.1977584707247;3625.1299688074805;3644.726645501598;3785.1483416231267;3791.2986097571898;3807.695384369244;3740.5511338639403;3761.5674197467233;3761.9059523511996;3777.6600105990456;3783.1866148778145;3744.625537446247;3763.2285381496863;3765.491658626078;3775.320442320381;3786.4320351947154;3337;3338.8664453421834;3351.949720754191;3364.7886760766105;3368.4947257911263;4563.657725670555;4617.445906491209;4640.211164102111;4647.140279048387;4662.230731929228;4261.339492378686;4271.63691086258;4295.591918767712;4303.870883873489;4317.97841010796;4890.462049174588;4950.579200767903;4955.562886762835;4963.578095861856;5010.674306170087;3301.96698033019;3507.789532628025;3534.6465353464705;3640.9259268515852;3647.288778688157;5153.613478989053;5180.507851754077;5181.196914478224;5190.792368305301;5196.29849970253;3614.150674591469;3621.348157331653;3630.5643322801434;3658.158623516553;3659.835307411173;3695.316366472211;3709.5919448860604;3712.201876596545;3718.9442158367647;3725.6646901778427;3984.521857377133;4170.019240860567;4173.311403618369;4178.310578754472;4185.58144185579;4930.457649658535;4938.58022068121;4945.530174633404;4947.233178857283;4969.130402179604;1203.458443700321;1228.4287806170173;1256.8051951947464;1257.415301884628;1258.8804063613943;851.2296371783546;857.2541888556996;866.9436486628349;878.0210065776613;878.8418084744679;4340.414044104005;4342.782860856944;4350.58669426404;4354.71377526801;4363.80362883671;5028.823991160365;5033.194688849767;5046.62150338726;5066;5072.213806859942;4300.7634580097765;4301.5698430156735;4307.676924230633;4319.222539942778;4322.740635561876;2299.896049896059;2390.195402471158;2419.5765740995257;2426.6047022961866;2426.866522341288;4222.59885310895;4226.640735537514;4249.383839343278;4267.253230684618;4289.935650965175;4571.811329054403;4591.44902611682;4593.943393019625;4608.608268297232;4629.328747331618;4047.3928910663635;4269.402283680287;4296.162248361539;4331.39360489538;4334.934975975426;4508.864734057984;4538.88652783683;4554.316852472156;4568.515232549501;4573.908521829479;4433.733975961452;4433.844815431445;4473.932891006702;4480.753558554311;4487.708298960688;4262.786860656893;4272.6154646082;4282.216559236521;4487.730736155841;4825.638077144298;3614.8192590370904;3635.1639123002387;3637.2361804021452;3640.6177351377723;3659.572766620967;4999.65002449825;5161.071007218737;5207.71357575337;5224.634275600672;5230.738463076755;4945.67853089548;5011.749412529286;5040.067587496325;5040.294358789845;5049.722265275445;5025.748712564284;5027.421846487555;5034.144195486703;5036.471170527188;5037.294778730908;4773.737444440589;4936.333594964777;4958.355413796184;4973.328600639012;4978.651494395358;5028.597712182944;5033.194688849767;5033.748312584283;5050.3182070420025;5060.974695126648;4920.089783390084;4932.654714170118;4945.357103576512;4992.028148881244;5000.824971125993 16:36:38 INFO - PROCESS | 17523 | |5;yui.html;630.6815058395509;647.3785166240409;661.9900701489479;693.7363801755336;722.8373615936413;12793.872061279384;13770.862291377083;13773.862261377382;13795.172289662614;13797.931010344946;13195.14231574947;14076.929615351935;14086.577402677936;14627.92686036571;14636.634084147901;621.1118101115403;737.0639288104104;756.4251169111487;757.8105473631592;758.5903612049486;751.5753599216453;768.9461737678365;770.2220757035378;781.6716978868889;782.3506489613635;809.4414853750893;810.6716779704229;811.0510702478085;813.0203105258149;813.369638530141;638.6902352359102;795.0618270440877;805.7502174325948;805.7663277649445;807.8424707182102;586.448738186108;587.7619564076556;588.2764200033939;589.5578316262803;590.1708100119339;14062;14264.788957711571;14343.49797757084;14344.067635603653;14345.067570607876;13710.657233569142;14247.358868850926;14343.354549045318;14374.35315410809;14389.136651800925;10809.513571889283;10858.565657373696;10930.234883558154;10983.670489885237;11059.281146725438;3309.12308238317;3321.8173000484962;3352.5474061001696;3384.8307584620916;3432.056184549244;69899.65050174767;70356.6482167586;73198.63400683015;73298.90051649282;73549.26450735531;10354.275200735876;10354.63249256831;10356.223283253636;10397.688069357931;10840.566377344818;19080.14139363732;19454.319098831755;19456.513587160152;19492.70760938587;19497.22011119568;6882.8967565486555;6885.345892140239;6931.064306318583;6937.375636192767;7000.124984376953;3713.4801127842125;3813.694904407641;3860.5946375630715;3926.764394136361;3984.7210695251056;2811.100447856707;2922.926926826804;2930.2381380841134;2931.296488842705;2932.5601159826197;473.91469535483947;476.11680332982513;480.0327340409078;501.64633933077096;517.9611529135329;500.0948283606611;571.2602180176681;573.9053056245756;577.1198921644526;620.5687047502033;8061.123020572586;8280.254777069973;8371.874421883676;8529.061803201643;8689.869651955225;555.089652969131;585.2567239605677;585.6369548870373;596.9791057312973;607.2834055814182;3907.6092390760696;3972.7616343019513;4003.979980100139;4094.263032654091;4096.973317403449;102042;102747.40090481493;103173;104198.47900760447;104423.4778826101;77012.2298777005;93378.06621933829;94315.52842235746;94540.52729736308;95190.5240473807;47810.703888930635;48151.277730834765;49247.75376123097;81798;82060.58969705114;7338.486305958745;7530.736424225345;7620.237976202336;7714.884276735741;7733.342665873239;38650.8404747858;41497;42785.786071069444;46923.41921194367;49107.754461227465;45715.31427028664;59973.70013150081;62791;64650.35349646631;65747.34252657412;11329.546818127512;11461.255018423775;11488.19582629208;11585.594504192313;11882.346470944181;12863.29251891155;13110;13273.66815829612;13472.461101555828;13906.51327203543;12101.757964840477;12105.455254513745;12115.15193936416;12667.113302068767;13083.280419577015;11646.94176529112;12310.138290319592;12386.256824590553;12667.93666031664;12806.807897881354;11127.888721112686;11958.760824783629;12098.697532561757;12254.816177757162;12329.198602090835;9206.217471514996;9275.118863707938;9322.533873306173;9553.426794392359;9654.517274136406;9546.284028697737;9831.508424578886;10314.948425257826;10388.896111038794;10480.161587072864 16:36:38 INFO - PROCESS | 17523 | __end_tp_report 16:36:38 INFO - PROCESS | 17523 | __start_cc_report 16:36:38 INFO - PROCESS | 17523 | _x_x_mozilla_cycle_collect,986 16:36:38 INFO - PROCESS | 17523 | __end_cc_report 16:36:38 INFO - PROCESS | 17523 | __startTimestamp1468884998272__endTimestamp 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | ------- Summary: start ------- 16:36:38 INFO - PROCESS | 17523 | Number of tests: 6 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#0] dojo.html Cycles:165 Average:4628.49 Median:4749.88 stddev:490.52 (10.3%) stddev-sans-first:465.87 16:36:38 INFO - PROCESS | 17523 | Values: 2614.0 2626.2 2655.4 2664.9 2665.5 4447.6 4512.3 4669.3 4707.9 4718.7 4576.3 4612.8 4613.9 4652.3 4696.3 4277.8 4297.2 4301.5 4356.2 4401.1 4343.6 4389.6 4410.7 4418.4 4458.8 4157.3 4203.9 4323.9 4334.0 4413.7 4213.6 4218.8 4255.1 4273.4 4341.4 4248.0 4254.4 4261.2 4287.1 4297.2 4589.2 4618.9 4620.3 4641.8 4666.7 4770.2 4963.5 5036.2 5039.0 5094.1 5124.8 5126.9 5140.3 5145.2 5151.8 4564.2 4747.9 4749.9 4753.8 4789.9 5086.6 5136.2 5137.0 5148.4 5222.1 4970.8 4991.0 5017.5 5022.6 5023.5 4770.4 4908.6 5042.5 5102.9 5111.2 4870.1 4917.5 4928.9 4967.1 4985.3 4917.2 4917.4 4920.2 4921.5 4976.0 4153.0 4195.2 4256.2 4259.4 4318.9 3719.7 3783.3 3795.7 3833.8 3851.2 4306.1 4320.7 4325.1 4326.3 4358.5 5084.5 5089.3 5089.7 5091.3 5127.6 4138.7 4161.9 4164.9 4175.0 4181.6 4402.2 4431.6 4432.4 4477.4 4483.4 4535.1 4540.7 4575.9 4578.9 4626.5 5044.6 5097.9 5100.8 5119.8 5121.8 4794.9 4809.0 4816.8 4822.0 4864.3 4694.2 4822.5 4980.3 5000.4 5043.4 4967.0 4982.3 5005.9 5006.4 5053.8 4853.9 4869.7 4871.7 4873.4 4919.9 4714.7 4874.1 4889.5 4906.3 4924.4 4862.6 4876.8 4908.6 4927.3 4934.0 4961.2 4971.2 4971.6 4971.8 5018.5 4645.5 4717.0 4737.7 4778.6 4861.5 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#1] ext.html Cycles:180 Average:43255.45 Median:36253.73 stddev:71088.10 (196.1%) stddev-sans-first:71215.30 16:36:38 INFO - PROCESS | 17523 | Values: 576.4 581.8 591.2 592.1 592.3 30070.8 30728.4 30748.4 38745.6 40566.8 42131.4 42427.6 42451.2 42527.6 42583.0 7934.4 8073.2 8076.8 8077.8 8081.4 7943.5 8037.0 8037.6 8037.8 8044.6 8026.4 8052.6 8054.3 8058.8 8063.0 8014.0 8027.7 8029.2 8034.0 8040.8 7955.2 8005.7 8009.2 8012.5 8013.0 38789.4 39406.2 39453.6 39535.0 39558.6 3038.3 3072.8 3103.5 3131.9 3163.2 29541.1 29579.0 30260.8 30330.2 30472.0 3491.9 3541.6 3557.6 3582.0 3613.1 264807.0 265812.0 267154.3 267173.0 267708.0 54163.5 54303.5 54367.7 54416.0 54418.7 32650.9 33577.8 50645.7 50697.5 50701.0 15403.3 15477.5 15542.5 15545.8 15577.4 10864.3 10961.8 10995.1 10996.2 11000.5 18682.5 18716.4 18833.8 18844.7 18880.8 6821.9 7065.2 7068.8 7078.2 7095.9 778.2 803.8 807.7 937.0 1033.2 24624.8 26468.9 27551.4 27904.7 28022.0 778.3 921.4 961.7 969.4 1000.0 14351.5 14822.5 14823.4 15069.8 15785.5 195320.0 196205.0 196349.0 196850.0 363036.0 51815.5 52020.7 52191.7 52353.0 52431.0 1107.4 1133.1 1184.3 1201.6 1224.1 25580.4 28800.7 29051.0 29477.9 29785.3 43506.6 44272.6 44339.1 44346.0 44381.3 303102.0 303580.0 305854.0 308629.0 308834.0 29674.9 33077.0 33088.8 35107.5 35575.8 39682.8 41958.4 42193.8 42297.8 43075.8 35955.6 36058.5 36794.3 36810.3 36951.6 36257.5 36629.5 36741.0 36780.0 36825.8 35402.8 35797.8 36250.0 36527.8 36576.6 31774.4 32670.0 32945.5 32986.7 33017.7 24186.6 24308.8 24447.6 24984.6 25263.7 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#2] jquery.html Cycles:180 Average:25332.16 Median:28452.49 stddev:69567.16 (244.5%) stddev-sans-first:69737.01 16:36:38 INFO - PROCESS | 17523 | Values: 343.4 360.8 369.2 371.5 378.1 12887.0 16293.4 16742.1 16984.7 17950.1 17016.0 17057.4 17567.9 17931.7 18155.3 5139.9 5219.0 5442.7 5479.5 5543.1 3360.9 5114.8 5392.8 5420.5 5519.7 5442.2 5446.1 5482.0 5482.7 5491.9 5341.5 5357.1 5432.5 5445.2 5451.7 3343.5 4898.1 5091.3 5133.7 5288.8 12066.9 12108.4 12336.2 12510.4 20882.6 1833.4 1967.6 1971.1 2116.7 2241.0 8783.7 9196.1 9262.4 9621.1 9627.2 1766.9 1814.3 1954.8 1968.3 2031.1 65735.0 68803.7 69230.0 69630.0 70340.0 8745.8 8771.2 8934.3 9373.6 9854.1 12289.2 12986.9 13087.1 13088.5 13094.5 5607.4 7155.9 7493.9 7633.2 7811.5 7213.2 7364.7 7407.9 7549.5 7774.8 3974.3 4190.6 4342.9 4362.4 4391.0 1674.7 1756.4 1846.0 1851.7 1899.8 838.3 847.9 887.5 895.3 904.2 16336.5 16601.9 16980.3 17159.9 17405.7 862.7 863.3 881.5 884.4 929.7 9518.4 9561.4 9593.8 10015.7 10690.7 413728.0 414390.0 415923.0 416746.0 438930.0 85057.0 91482.1 91683.5 91784.5 91807.0 880.3 911.4 927.4 927.6 954.6 15781.2 16056.2 16107.6 16183.8 16382.8 33409.3 34164.7 39335.0 40324.6 40586.4 51183.5 51686.0 54397.2 56491.0 56494.0 6079.9 7232.2 7549.8 7568.5 7919.0 10347.0 10784.9 11046.9 11269.8 11320.9 9849.0 10143.1 10147.1 10162.1 10254.1 9698.5 9849.4 9895.5 9963.3 10048.3 9834.4 9846.8 9914.5 9948.2 10038.4 7120.5 7231.1 7373.8 7403.6 7653.7 9859.7 10105.4 10168.4 10563.5 10726.5 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#3] mootools.html Cycles:180 Average:5171.70 Median:5161.41 stddev:2896.90 (56.1%) stddev-sans-first:2890.89 16:36:38 INFO - PROCESS | 17523 | Values: 1363.5 1414.3 1473.6 1688.5 1690.3 6572.9 6799.3 7251.8 7784.0 7785.6 7426.6 7455.7 7460.3 7682.4 7742.9 2143.8 2196.7 2222.9 2225.6 2231.3 1818.5 2117.1 2182.0 2184.7 2199.6 2172.3 2179.4 2185.6 2186.2 2187.0 2058.2 2155.8 2164.1 2165.6 2167.1 2079.2 2154.8 2158.7 2172.1 2172.9 7870.3 7905.1 7911.2 7924.7 7927.4 4641.9 4765.9 4766.8 4767.0 4772.9 2821.4 2870.5 2902.9 2914.7 2923.2 887.5 922.5 939.1 948.7 961.1 10324.2 10501.6 10501.9 10523.8 10527.4 4011.2 4180.8 4292.2 4350.3 4358.6 9051.0 9085.5 9095.5 9112.0 9116.5 4698.1 4701.6 4701.9 4705.0 4709.8 3367.1 4285.3 4293.7 4294.7 4296.4 3651.6 3824.8 3866.8 3875.5 3905.6 1211.7 1235.5 1241.4 1246.1 1250.3 784.4 820.1 845.1 855.0 863.1 5120.3 5202.5 5324.7 5342.7 5597.4 390.8 391.5 395.2 397.6 923.9 3561.6 3809.1 3830.9 3836.1 3854.1 9400.8 9736.0 9878.1 9997.9 10043.4 9831.1 9846.5 9869.5 9936.9 9948.5 7056.7 7337.9 7364.3 7369.7 7567.6 5465.3 5520.4 5636.8 5679.1 5702.7 8686.9 8818.2 8867.3 9075.5 9076.1 8819.5 9140.3 9199.5 9203.5 9227.1 6017.5 6529.2 6746.2 6892.0 6894.0 7475.1 7696.5 7775.3 7792.5 7794.9 6738.4 6797.9 6901.4 6908.9 7069.9 6772.9 6789.6 6853.3 6971.1 7029.9 6093.1 6369.2 6398.0 6432.7 6435.4 4639.6 4662.7 4693.4 4780.4 4795.1 4652.3 4654.1 4711.0 4736.9 4743.0 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#4] prototype.html Cycles:180 Average:4047.26 Median:4352.65 stddev:1163.35 (26.7%) stddev-sans-first:1132.09 16:36:38 INFO - PROCESS | 17523 | Values: 299.5 300.3 304.9 310.0 311.4 4596.5 4704.9 4731.7 4770.8 4783.6 4601.4 4729.3 4741.4 4745.7 4752.0 3783.7 3810.1 3814.2 3819.3 3820.2 3625.1 3644.7 3785.1 3791.3 3807.7 3740.6 3761.6 3761.9 3777.7 3783.2 3744.6 3763.2 3765.5 3775.3 3786.4 3337.0 3338.9 3351.9 3364.8 3368.5 4563.7 4617.4 4640.2 4647.1 4662.2 4261.3 4271.6 4295.6 4303.9 4318.0 4890.5 4950.6 4955.6 4963.6 5010.7 3302.0 3507.8 3534.6 3640.9 3647.3 5153.6 5180.5 5181.2 5190.8 5196.3 3614.2 3621.3 3630.6 3658.2 3659.8 3695.3 3709.6 3712.2 3718.9 3725.7 3984.5 4170.0 4173.3 4178.3 4185.6 4930.5 4938.6 4945.5 4947.2 4969.1 1203.5 1228.4 1256.8 1257.4 1258.9 851.2 857.3 866.9 878.0 878.8 4340.4 4342.8 4350.6 4354.7 4363.8 5028.8 5033.2 5046.6 5066.0 5072.2 4300.8 4301.6 4307.7 4319.2 4322.7 2299.9 2390.2 2419.6 2426.6 2426.9 4222.6 4226.6 4249.4 4267.3 4289.9 4571.8 4591.4 4593.9 4608.6 4629.3 4047.4 4269.4 4296.2 4331.4 4334.9 4508.9 4538.9 4554.3 4568.5 4573.9 4433.7 4433.8 4473.9 4480.8 4487.7 4262.8 4272.6 4282.2 4487.7 4825.6 3614.8 3635.2 3637.2 3640.6 3659.6 4999.7 5161.1 5207.7 5224.6 5230.7 4945.7 5011.7 5040.1 5040.3 5049.7 5025.7 5027.4 5034.1 5036.5 5037.3 4773.7 4936.3 4958.4 4973.3 4978.7 5028.6 5033.2 5033.7 5050.3 5061.0 4920.1 4932.7 4945.4 4992.0 5000.8 16:36:38 INFO - PROCESS | 17523 | 16:36:38 INFO - PROCESS | 17523 | [#5] yui.html Cycles:180 Average:18132.64 Median:44250.55 stddev:26197.95 (59.2%) stddev-sans-first:26238.48 16:36:38 INFO - PROCESS | 17523 | Values: 630.7 647.4 662.0 693.7 722.8 12793.9 13770.9 13773.9 13795.2 13797.9 13195.1 14076.9 14086.6 14627.9 14636.6 621.1 737.1 756.4 757.8 758.6 751.6 768.9 770.2 781.7 782.4 809.4 810.7 811.1 813.0 813.4 638.7 795.1 805.8 805.8 807.8 586.4 587.8 588.3 589.6 590.2 14062.0 14264.8 14343.5 14344.1 14345.1 13710.7 14247.4 14343.4 14374.4 14389.1 10809.5 10858.6 10930.2 10983.7 11059.3 3309.1 3321.8 3352.5 3384.8 3432.1 69899.7 70356.6 73198.6 73298.9 73549.3 10354.3 10354.6 10356.2 10397.7 10840.6 19080.1 19454.3 19456.5 19492.7 19497.2 6882.9 6885.3 6931.1 6937.4 7000.1 3713.5 3813.7 3860.6 3926.8 3984.7 2811.1 2922.9 2930.2 2931.3 2932.6 473.9 476.1 480.0 501.6 518.0 500.1 571.3 573.9 577.1 620.6 8061.1 8280.3 8371.9 8529.1 8689.9 555.1 585.3 585.6 597.0 607.3 3907.6 3972.8 4004.0 4094.3 4097.0 102042.0 102747.4 103173.0 104198.5 104423.5 77012.2 93378.1 94315.5 94540.5 95190.5 47810.7 48151.3 49247.8 81798.0 82060.6 7338.5 7530.7 7620.2 7714.9 7733.3 38650.8 41497.0 42785.8 46923.4 49107.8 45715.3 59973.7 62791.0 64650.4 65747.3 11329.5 11461.3 11488.2 11585.6 11882.3 12863.3 13110.0 13273.7 13472.5 13906.5 12101.8 12105.5 12115.2 12667.1 13083.3 11646.9 12310.1 12386.3 12667.9 12806.8 11127.9 11958.8 12098.7 12254.8 12329.2 9206.2 9275.1 9322.5 9553.4 9654.5 9546.3 9831.5 10314.9 10388.9 10480.2 16:36:38 INFO - PROCESS | 17523 | -------- Summary: end -------- 16:36:38 INFO - PROCESS | 17523 | 16:36:39 INFO - TEST-INFO | 17523: exit 0 16:36:39 INFO - TEST-OK | dromaeo_css | took 1120135ms 16:36:39 INFO - TEST-START | kraken 16:36:39 INFO - operating with platform_type : linux_ 16:36:39 INFO - Initialising browser for kraken test... 16:36:39 INFO - TEST-INFO | started process 17710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo0FGdX/profile http://localhost:56316/getInfo.html) 16:36:43 INFO - PROCESS | 17710 | __metrics Screen width/height:1600/1200 16:36:43 INFO - PROCESS | 17710 | colorDepth:24 16:36:43 INFO - PROCESS | 17710 | Browser inner width/height: 1024/697 16:36:43 INFO - PROCESS | 17710 | __metrics 16:36:44 INFO - PROCESS | 17710 | 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" 16:36:44 INFO - PROCESS | 17710 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:36:45 INFO - TEST-INFO | 17710: exit 0 16:36:45 INFO - Browser initialized. 16:36:45 INFO - Running cycle 1/1 for kraken test... 16:36:45 INFO - TEST-INFO | started process 17793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo0FGdX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:57 INFO - PROCESS | 17793 | RSS: Main: 159494144 16:36:57 INFO - PROCESS | 17793 | 16:36:59 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56316/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:00 INFO - PROCESS | 17793 | RSS: Main: 218669056 16:37:00 INFO - PROCESS | 17793 | 16:37:03 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56316/tests/kraken/driver.html?testName=audio-dft) 16:37:04 INFO - PROCESS | 17793 | RSS: Main: 206127104 16:37:04 INFO - PROCESS | 17793 | 16:37:07 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56316/tests/kraken/driver.html?testName=audio-fft) 16:37:08 INFO - PROCESS | 17793 | RSS: Main: 271024128 16:37:08 INFO - PROCESS | 17793 | 16:37:10 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56316/tests/kraken/driver.html?testName=audio-oscillator) 16:37:10 INFO - PROCESS | 17793 | RSS: Main: 182427648 16:37:10 INFO - PROCESS | 17793 | 16:37:12 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56316/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:13 INFO - PROCESS | 17793 | RSS: Main: 186212352 16:37:13 INFO - PROCESS | 17793 | 16:37:16 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56316/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:17 INFO - PROCESS | 17793 | RSS: Main: 221990912 16:37:17 INFO - PROCESS | 17793 | 16:37:20 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56316/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:21 INFO - PROCESS | 17793 | RSS: Main: 222744576 16:37:21 INFO - PROCESS | 17793 | 16:37:24 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56316/tests/kraken/driver.html?testName=json-parse-financial) 16:37:24 INFO - PROCESS | 17793 | RSS: Main: 222736384 16:37:24 INFO - PROCESS | 17793 | 16:37:26 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56316/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:27 INFO - PROCESS | 17793 | RSS: Main: 211791872 16:37:27 INFO - PROCESS | 17793 | 16:37:29 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:29 INFO - PROCESS | 17793 | RSS: Main: 214212608 16:37:29 INFO - PROCESS | 17793 | 16:37:31 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:32 INFO - PROCESS | 17793 | RSS: Main: 210968576 16:37:32 INFO - PROCESS | 17793 | 16:37:35 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:37:35 INFO - PROCESS | 17793 | RSS: Main: 227237888 16:37:35 INFO - PROCESS | 17793 | 16:37:38 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:37:39 INFO - PROCESS | 17793 | RSS: Main: 224395264 16:37:39 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | Cycle 1(1): loaded http://localhost:56316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56316/tests/kraken/driver.html?testName=ai-astar) 16:37:41 INFO - PROCESS | 17793 | RSS: Main: 268017664 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | __start_tp_report 16:37:41 INFO - PROCESS | 17793 | _x_x_mozilla_page_load 16:37:41 INFO - PROCESS | 17793 | _x_x_mozilla_page_load_details 16:37:41 INFO - PROCESS | 17793 | |i|pagename|runs| 16:37:41 INFO - PROCESS | 17793 | |0;ai-astar;104;113;116;114;115;113;113;114;112;114 16:37:41 INFO - PROCESS | 17793 | |1;audio-beat-detection;152;125;147;166;129;122;163;123;170;123 16:37:41 INFO - PROCESS | 17793 | |2;audio-dft;163;162;163;161;162;163;162;163;165;161 16:37:41 INFO - PROCESS | 17793 | |3;audio-fft;75;75;77;112;76;77;76;75;113;75 16:37:41 INFO - PROCESS | 17793 | |4;audio-oscillator;86;86;123;85;86;84;116;87;85;132 16:37:41 INFO - PROCESS | 17793 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;118 16:37:41 INFO - PROCESS | 17793 | |6;imaging-darkroom;147;148;146;147;147;146;147;147;147;146 16:37:41 INFO - PROCESS | 17793 | |7;imaging-desaturate;95;95;95;95;95;94;94;95;95;95 16:37:41 INFO - PROCESS | 17793 | |8;json-parse-financial;64;63;64;64;62;63;63;63;63;63 16:37:41 INFO - PROCESS | 17793 | |9;json-stringify-tinderbox;68;67;67;66;67;66;66;67;66;67 16:37:41 INFO - PROCESS | 17793 | |10;stanford-crypto-aes;80;79;78;77;78;81;78;80;78;80 16:37:41 INFO - PROCESS | 17793 | |11;stanford-crypto-ccm;128;136;127;137;130;131;130;134;174;140 16:37:41 INFO - PROCESS | 17793 | |12;stanford-crypto-pbkdf2;172;172;164;165;167;164;170;169;166;164 16:37:41 INFO - PROCESS | 17793 | |13;stanford-crypto-sha256-iterative;79;66;67;66;66;67;66;71;64;65 16:37:41 INFO - PROCESS | 17793 | __end_tp_report 16:37:41 INFO - PROCESS | 17793 | __start_cc_report 16:37:41 INFO - PROCESS | 17793 | _x_x_mozilla_cycle_collect,1947 16:37:41 INFO - PROCESS | 17793 | __end_cc_report 16:37:41 INFO - PROCESS | 17793 | __startTimestamp1468885061389__endTimestamp 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | ------- Summary: start ------- 16:37:41 INFO - PROCESS | 17793 | Number of tests: 14 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.29 (2.9%) stddev-sans-first:1.20 16:37:41 INFO - PROCESS | 17793 | Values: 104.0 113.0 116.0 114.0 115.0 113.0 113.0 114.0 112.0 114.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.50 stddev:19.74 (13.2%) stddev-sans-first:20.60 16:37:41 INFO - PROCESS | 17793 | Values: 152.0 125.0 147.0 166.0 129.0 122.0 163.0 123.0 170.0 123.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.18 (0.7%) stddev-sans-first:1.24 16:37:41 INFO - PROCESS | 17793 | Values: 163.0 162.0 163.0 161.0 162.0 163.0 162.0 163.0 165.0 161.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.50 stddev:15.52 (20.6%) stddev-sans-first:16.18 16:37:41 INFO - PROCESS | 17793 | Values: 75.0 75.0 77.0 112.0 76.0 77.0 76.0 75.0 113.0 75.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:85.50 stddev:18.80 (22.0%) stddev-sans-first:19.52 16:37:41 INFO - PROCESS | 17793 | Values: 86.0 86.0 123.0 85.0 86.0 84.0 116.0 87.0 85.0 132.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 16:37:41 INFO - PROCESS | 17793 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:37:41 INFO - PROCESS | 17793 | Values: 147.0 148.0 146.0 147.0 147.0 146.0 147.0 147.0 147.0 146.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:37:41 INFO - PROCESS | 17793 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 16:37:41 INFO - PROCESS | 17793 | Values: 64.0 63.0 64.0 64.0 62.0 63.0 63.0 63.0 63.0 63.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.53 16:37:41 INFO - PROCESS | 17793 | Values: 68.0 67.0 67.0 66.0 67.0 66.0 66.0 67.0 66.0 67.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.50 stddev:1.29 (1.6%) stddev-sans-first:1.30 16:37:41 INFO - PROCESS | 17793 | Values: 80.0 79.0 78.0 77.0 78.0 81.0 78.0 80.0 78.0 80.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:135.00 stddev:13.75 (10.2%) stddev-sans-first:14.22 16:37:41 INFO - PROCESS | 17793 | Values: 128.0 136.0 127.0 137.0 130.0 131.0 130.0 134.0 174.0 140.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:3.23 (1.9%) stddev-sans-first:2.95 16:37:41 INFO - PROCESS | 17793 | Values: 172.0 172.0 164.0 165.0 167.0 164.0 170.0 169.0 166.0 164.0 16:37:41 INFO - PROCESS | 17793 | 16:37:41 INFO - PROCESS | 17793 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:66.50 stddev:4.37 (6.6%) stddev-sans-first:1.94 16:37:41 INFO - PROCESS | 17793 | Values: 79.0 66.0 67.0 66.0 66.0 67.0 66.0 71.0 64.0 65.0 16:37:41 INFO - PROCESS | 17793 | -------- Summary: end -------- 16:37:41 INFO - PROCESS | 17793 | 16:37:42 INFO - TEST-INFO | 17793: exit 0 16:37:42 INFO - TEST-OK | kraken | took 63333ms 16:37:42 INFO - SUITE-END | took 1183s 16:37:42 INFO - Completed test suite (00:19:43) 16:37:42 INFO - javascript benchmark 16:37:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2614.0458732562615, 2626.2252635472532, 2655.4025344297534, 2664.880080396382, 2665.506881226974, 4447.599716025565, 4512.278035514319, 4669.299605059238, 4707.882302942428, 4718.716876987379, 4576.313552967056, 4612.769361531919, 4613.8615841524725, 4652.348671186037, 4696.342512048316, 4277.764722940237, 4297.183535128315, 4301.526832048487, 4356.15055064261, 4401.097774956126, 4343.630791382736, 4389.626881715058, 4410.735355878657, 4418.4256046714045, 4458.79935402906, 4157.3140431828715, 4203.894902627429, 4323.935140972888, 4334.046509767846, 4413.735175889441, 4213.5786421357925, 4218.767967761772, 4255.063885945087, 4273.380359847836, 4341.435613370273, 4248.044190057243, 4254.446921900133, 4261.211675839949, 4287.056847493546, 4297.162053399588, 4589.196890544142, 4618.930716039262, 4620.283856002291, 4641.767911604406, 4666.673332866701, 4770.236762118079, 4963.45402005779, 5036.244563315496, 5039.0, 5094.083065048289, 5124.769385377667, 5126.871828204325, 5140.3060586820675, 5145.176771716545, 5151.819686310999, 4564.155631208171, 4747.905041899212, 4749.881252968704, 4753.83361582343, 4789.880252993668, 5086.618503612244, 5136.178211486219, 5136.9743151284, 5148.433672296044, 5222.1383471727495, 4970.776315065831, 4991.0, 5017.548420642087, 5022.573081288131, 5023.497650234947, 4770.379850619397, 4908.631852611068, 5042.520960508746, 5102.923456148161, 5111.182210846321, 4870.0746858096845, 4917.5082491751255, 4928.876778080505, 4967.05625931072, 4985.302057711923, 4917.237828136645, 4917.385326834145, 4920.163572192736, 4921.50784921505, 4976.0, 4153.0, 4195.1819395217635, 4256.191323648497, 4259.403683484253, 4318.892027699269, 3719.7210209234418, 3783.3379158647126, 3795.7153213509096, 3833.769973801525, 3851.171998020439, 4306.074194048295, 4320.675949303814, 4325.091730736581, 4326.329418940069, 4358.5205627380965, 5084.542391184811, 5089.312942752681, 5089.694618322913, 5091.3381260436645, 5127.564157046693, 4138.710290279711, 4161.895952601149, 4164.895877603023, 4175.0, 4181.623653871166, 4402.163588918095, 4431.623312018514, 4432.3794668746405, 4477.440319960005, 4483.394741709827, 4535.092981403733, 4540.704854184468, 4575.931361029588, 4578.88552786183, 4626.491085980607, 5044.621653375938, 5097.872553186052, 5100.846974590768, 5119.846404607868, 5121.7695203715175, 4794.904101917872, 4808.951910480991, 4816.831410900601, 4821.975890120527, 4864.318995340726, 4694.2254528002495, 4822.517748225149, 4980.32765576657, 5000.449950505369, 5043.445221025758, 4967.031428871335, 4982.252662100569, 5005.899882002267, 5006.3992320921725, 5053.84838454847, 4853.927191092066, 4869.683470574401, 4871.732054737024, 4873.439554451284, 4919.926201106915, 4714.669973101987, 4874.122657921608, 4889.511048895082, 4906.313116163811, 4924.4090709115135, 4862.635302352267, 4876.780544875418, 4908.558229759339, 4927.28554359623, 4934.0, 4961.181405068268, 4971.204607262821, 4971.552560269449, 4971.801127954841, 5018.548330650259, 4645.488996210482, 4716.976415117902, 4737.692050016738, 4778.5699287064335, 4861.538153875376], "value": 4597.5052146054495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.3919065386017, 581.845810860122, 591.1694069831885, 592.1000079878587, 592.3365830270099, 30070.84964575177, 30728.38543229137, 30748.385032299368, 38745.61254387455, 40566.7971660142, 42131.368029479585, 42427.57572424275, 42451.15097698044, 42527.57472425282, 42583.0, 7934.365250779938, 8073.233042860918, 8076.798080048016, 8077.798055048612, 8081.393895457834, 7943.523388596703, 8037.0355557333205, 8037.598120094001, 8037.839243215132, 8044.637991290377, 8026.398020148512, 8052.59737013147, 8054.315383192436, 8058.798530036766, 8063.0324361076755, 8014.0, 8027.7190298339865, 8029.237222463857, 8034.035915690155, 8040.758777236692, 7955.204479551999, 8005.719799806979, 8009.239122283374, 8012.519248845029, 8013.0384353877325, 38789.41815872764, 39406.21187576261, 39453.60546394528, 39535.0, 39558.60441395578, 3038.2860027893425, 3072.800267982574, 3103.5189545620465, 3131.874725010997, 3163.1775738307974, 29541.08053411648, 29579.0, 30260.84869575638, 30330.241743956358, 30472.0, 3491.877784277538, 3541.643887359228, 3557.6442355764216, 3582.0477839577493, 3613.1339466770564, 264807.0, 265812.0, 267154.2991990542, 267173.0, 267708.0, 54163.45836541584, 54303.45696543063, 54367.72816135973, 54416.0, 54418.72790636022, 32650.857219997186, 33577.83211083929, 50645.746771265905, 50697.49302507001, 50700.98598028019, 15403.306851191723, 15477.5356739298, 15542.45601403968, 15545.84454155444, 15577.376904923904, 10864.348139111678, 10961.83557246642, 10995.120390368751, 10996.23026388145, 11000.504977276041, 18682.532936676693, 18716.438506844817, 18833.811661883206, 18844.717329240073, 18880.81119188818, 6821.93178068213, 7065.2228254891925, 7068.787936361918, 7078.1506219254015, 7095.9290407096305, 778.1751343575828, 803.7789607857886, 807.7415227127262, 936.9531523423801, 1033.245730616639, 24624.753752462246, 26468.867655661597, 27551.44897102047, 27904.720952790212, 28022.0, 778.283978739561, 921.4195057114109, 961.6538046303325, 969.4425705219527, 1000.0249756487367, 14351.497697580535, 14822.481213157487, 14823.40706371733, 15069.849301507064, 15785.526434206991, 195320.0, 196205.0, 196349.0, 196850.0, 363036.0, 51815.481845181064, 52020.739896301035, 52191.73904130455, 52353.0, 52431.0, 1107.3687997841314, 1133.1218305812897, 1184.2688459603767, 1201.6034708546158, 1224.1186345831165, 25580.360490987874, 28800.711992880642, 29051.0, 29477.857903997734, 29785.25536861596, 43506.56493435025, 44272.55727442813, 44339.11321773611, 44346.0, 44381.33427998518, 303102.0, 303580.0, 305854.0, 308629.0, 308834.0, 29674.851625741732, 33077.0, 33088.83455582707, 35107.473387899714, 35575.82212088923, 39682.80158599189, 41958.370624440046, 42193.78903105465, 42297.788511057246, 43075.784621076695, 35955.64044359523, 36058.45912311265, 36794.26411471809, 36810.26379472342, 36951.630483695895, 36257.456138157424, 36629.450558242184, 36741.0, 36780.0, 36825.81587092047, 35402.82298588491, 35797.82101089582, 36250.0, 36527.817360913024, 36576.634233658384, 31774.364512709155, 32670.0, 32945.505817413235, 32986.67013329836, 33017.66982330242, 24186.609037016002, 24308.75691243065, 24447.633285501088, 24984.625230621918, 25263.74736252614], "value": 17447.068812749265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4476167721896, 360.80155914247166, 369.21541723836845, 371.5003320533881, 378.0756051454194, 12887.0, 16293.429729959438, 16742.0791856448, 16984.660306793856, 17950.10249487527, 17016.0, 17057.40299089532, 17567.91216043918, 17931.73102403465, 18155.27378904842, 5139.897202055948, 5218.973905130469, 5442.700651464168, 5479.506844384005, 5543.085390910495, 3360.899173024813, 5114.846554603368, 5392.811251606195, 5420.4850539198715, 5519.668819870801, 5442.183672449144, 5446.128619420893, 5482.0, 5482.725863706819, 5491.862703432406, 5341.4925582069645, 5357.1160758475, 5432.456754324536, 5445.237666726662, 5451.700156491392, 3343.5319055332275, 4898.093852637237, 5091.261767043758, 5133.691978481265, 5288.788448462095, 12066.939665301705, 12108.394580271039, 12336.198147120498, 12510.437030333656, 20882.5823483531, 1833.4133077415233, 1967.6359873423462, 1971.083446197525, 2116.6507526258147, 2240.9887950560305, 8783.736487905373, 9196.12636799503, 9262.351635385527, 9621.13409793115, 9627.170837540845, 1766.8939863608095, 1814.292425953879, 1954.7556555430572, 1968.3307675390436, 2031.065709773491, 65735.0, 68803.65598172027, 69230.0, 69630.0, 70339.99999999949, 8745.781355466164, 8771.166739159897, 8934.329925255632, 9373.578188981512, 9854.127715733988, 12289.201201921847, 12986.935065324613, 13087.149335293367, 13088.541901033608, 13094.476220951246, 5607.383187849415, 7155.92844071563, 7493.85012299751, 7633.160352361236, 7811.4922530035365, 7213.206547279795, 7364.742234021784, 7407.8888816667795, 7549.50928189666, 7774.766756997186, 3974.324364857981, 4190.55999120088, 4342.869713908588, 4362.432883725095, 4390.956090439118, 1674.7236705943626, 1756.4203812741764, 1845.9815401846079, 1851.6944704123669, 1899.848012159024, 838.279079991207, 847.9321654267644, 887.5029983209423, 895.2899961074316, 904.1591320072396, 16336.509904702878, 16601.91699041521, 16980.32078716862, 17159.914200428917, 17405.65188696219, 862.6721845698594, 863.3438586674048, 881.4975463985595, 884.3632584539122, 929.7489677787095, 9518.381305215138, 9561.426314421018, 9593.760155996017, 10015.69952901414, 10690.679279621623, 413728.0, 414390.0, 415923.0, 416746.0, 438930.0, 85057.0, 91482.08517914735, 91683.54158229165, 91784.54107729418, 91807.0, 880.3441436130124, 911.40758506971, 927.4018258223408, 927.6289484206371, 954.6038394066524, 15781.21093945321, 16056.197190140212, 16107.58386242073, 16183.838161618232, 16382.836171638608, 33409.331813364086, 34164.65835341615, 39335.0, 40324.596754032085, 40586.39120413255, 51183.48816511787, 51686.0, 54397.18404223861, 56491.0, 56494.0, 6079.905616988807, 7232.240614735377, 7549.849003020019, 7568.508046976929, 7919.049714034353, 10347.0, 10784.946075269887, 11046.944765276123, 11269.830952535882, 11320.886791131983, 9849.0, 10143.137833284254, 10147.137493313152, 10162.136218421221, 10254.128399085863, 9698.46658433793, 9849.359791613522, 9895.50522473859, 9963.302568820112, 10048.296619236584, 9834.409935403899, 9846.803063938825, 9914.504274786375, 9948.154406875497, 10038.397696138256, 7120.465965052746, 7231.132264128128, 7373.8156546086775, 7403.5928023959195, 7653.732119375795, 9859.704208873745, 10105.44420056904, 10168.389896605931, 10563.52464139131, 10726.463676816284], "value": 8126.546278939127, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.5295822941084, 1414.2999215388381, 1473.56529823702, 1688.510332003719, 1690.28163030712, 6572.934270657292, 6799.286074962132, 7251.818704532389, 7784.0, 7785.571793551352, 7426.5544067355995, 7455.66449509772, 7460.29127232912, 7682.385409167267, 7742.8838567421535, 2143.8284937205062, 2196.703445034924, 2222.92219772308, 2225.6216443204694, 2231.2859884836857, 1818.5453636590853, 2117.1319758898853, 2181.9890900545474, 2184.7050648162535, 2199.582079404916, 2172.348295511348, 2179.3570896585466, 2185.617516934538, 2186.158329043322, 2187.037703410502, 2058.197303051811, 2155.838312126581, 2164.101897712447, 2165.566886622682, 2167.133146741316, 2079.1683326669304, 2154.827613790893, 2158.6546152615665, 2172.120291282033, 2172.891355432222, 7870.252326029018, 7905.130435652131, 7911.169327220674, 7924.722634707758, 7927.445078844483, 4641.9323555582405, 4765.928511072337, 4766.833160839354, 4767.046590681877, 4772.880677983044, 2821.3510892494637, 2870.468963241807, 2902.8693708783157, 2914.664813546448, 2923.166897434221, 887.4763889305276, 922.5055410235461, 939.1359948847008, 948.7296120605595, 961.1253759079204, 10324.174066074771, 10501.579936802518, 10501.894981050245, 10523.84214236787, 10527.368357898551, 4011.1576568920855, 4180.770057646859, 4292.248856450109, 4350.260455722471, 4358.6295164911335, 9050.954745226363, 9085.50029748357, 9095.545222738836, 9112.0, 9116.544172791333, 4698.060387922429, 4701.623870090384, 4701.858944231679, 4704.9529504704515, 4709.835155769531, 3367.074054634995, 4285.292926667127, 4293.656507479394, 4294.6993710439965, 4296.419983302277, 3651.580068292127, 3824.7896365700153, 3866.7873266970587, 3875.476810630585, 3905.5508616509464, 1211.7273613436905, 1235.5490246060124, 1241.3669028795198, 1246.1339369138543, 1250.306080125539, 784.4113182191496, 820.0692214336651, 845.1464021338493, 855.0167307596314, 863.1411745313453, 5120.334356533627, 5202.479752024843, 5324.733763311819, 5342.706151161646, 5597.356304025089, 390.8299889548056, 391.50670155604143, 395.2214138147844, 397.564666689974, 923.8568021956473, 3561.6438356164176, 3809.085819403323, 3830.8659196927974, 3836.1176929306416, 3854.113553882623, 9400.764980875532, 9736.0, 9878.110970012733, 9997.900020999698, 10043.397396156108, 9831.066048725359, 9846.458444785463, 9869.506524673738, 9936.850947235796, 9948.452835093995, 7056.717731290793, 7337.926620733831, 7364.2635736427, 7369.742059027908, 7567.583782891994, 5465.344158700981, 5520.420355862737, 5636.774529018793, 5679.119736440858, 5702.6863522506665, 8686.869696954676, 8818.162274583905, 8867.290616750515, 9075.500847453453, 9076.137766912132, 8819.514926678838, 9140.314476414163, 9199.540022998957, 9203.493807840632, 9227.077292270718, 6017.458428741259, 6529.183852018497, 6746.224184218878, 6892.000659904185, 6893.965530172317, 7475.140358858801, 7696.461247712607, 7775.339096176888, 7792.454528182972, 7794.88307675374, 6738.427233684995, 6797.93202067973, 6901.413379862567, 6908.861822763617, 7069.858602828018, 6772.864542709217, 6789.592624442549, 6853.280405557544, 6971.058907047483, 7029.894551581832, 6093.146959425595, 6369.172007638977, 6398.0402939559435, 6432.742690292524, 6435.388638079375, 4639.582437580634, 4662.650301227353, 4693.436787585511, 4780.378550788375, 4795.064962332312, 4652.302154676826, 4654.069186162713, 4710.987137765397, 4736.881577960578, 4742.952570474389], "value": 4117.012369187452, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.50581540458245, 300.3242703916189, 304.9466343389907, 310.0296073290601, 311.41143239277784, 4596.49438561758, 4704.882377940553, 4731.668783185179, 4770.809167633299, 4783.632102109452, 4601.378813860128, 4729.344729344728, 4741.431028276603, 4745.667803253774, 4751.976240118804, 3783.735138540303, 3810.1427178884737, 3814.218085292522, 3819.2934307153128, 3820.1977584707247, 3625.1299688074805, 3644.726645501598, 3785.1483416231267, 3791.2986097571898, 3807.695384369244, 3740.5511338639403, 3761.5674197467233, 3761.9059523511996, 3777.6600105990456, 3783.1866148778145, 3744.625537446247, 3763.2285381496863, 3765.491658626078, 3775.320442320381, 3786.4320351947154, 3337.0, 3338.8664453421834, 3351.949720754191, 3364.7886760766105, 3368.4947257911263, 4563.657725670555, 4617.445906491209, 4640.211164102111, 4647.140279048387, 4662.230731929228, 4261.339492378686, 4271.63691086258, 4295.591918767712, 4303.870883873489, 4317.97841010796, 4890.462049174588, 4950.579200767903, 4955.562886762835, 4963.578095861856, 5010.674306170087, 3301.96698033019, 3507.789532628025, 3534.6465353464705, 3640.9259268515852, 3647.288778688157, 5153.613478989053, 5180.507851754077, 5181.196914478224, 5190.792368305301, 5196.29849970253, 3614.150674591469, 3621.348157331653, 3630.5643322801434, 3658.158623516553, 3659.835307411173, 3695.316366472211, 3709.5919448860604, 3712.201876596545, 3718.9442158367647, 3725.6646901778427, 3984.521857377133, 4170.019240860567, 4173.311403618369, 4178.310578754472, 4185.58144185579, 4930.457649658535, 4938.58022068121, 4945.530174633404, 4947.233178857283, 4969.130402179604, 1203.458443700321, 1228.4287806170173, 1256.8051951947464, 1257.415301884628, 1258.8804063613943, 851.2296371783546, 857.2541888556996, 866.9436486628349, 878.0210065776613, 878.8418084744679, 4340.414044104005, 4342.782860856944, 4350.58669426404, 4354.71377526801, 4363.80362883671, 5028.823991160365, 5033.194688849767, 5046.62150338726, 5066.0, 5072.213806859942, 4300.7634580097765, 4301.5698430156735, 4307.676924230633, 4319.222539942778, 4322.740635561876, 2299.896049896059, 2390.195402471158, 2419.5765740995257, 2426.6047022961866, 2426.866522341288, 4222.59885310895, 4226.640735537514, 4249.383839343278, 4267.253230684618, 4289.935650965175, 4571.811329054403, 4591.44902611682, 4593.943393019625, 4608.608268297232, 4629.328747331618, 4047.3928910663635, 4269.402283680287, 4296.162248361539, 4331.39360489538, 4334.934975975426, 4508.864734057984, 4538.88652783683, 4554.316852472156, 4568.515232549501, 4573.908521829479, 4433.733975961452, 4433.844815431445, 4473.932891006702, 4480.753558554311, 4487.708298960688, 4262.786860656893, 4272.6154646082, 4282.216559236521, 4487.730736155841, 4825.638077144298, 3614.8192590370904, 3635.1639123002387, 3637.2361804021452, 3640.6177351377723, 3659.572766620967, 4999.65002449825, 5161.071007218737, 5207.71357575337, 5224.634275600672, 5230.738463076755, 4945.67853089548, 5011.749412529286, 5040.067587496325, 5040.294358789845, 5049.722265275445, 5025.748712564284, 5027.421846487555, 5034.144195486703, 5036.471170527188, 5037.294778730908, 4773.737444440589, 4936.333594964777, 4958.355413796184, 4973.328600639012, 4978.651494395358, 5028.597712182944, 5033.194688849767, 5033.748312584283, 5050.3182070420025, 5060.974695126648, 4920.089783390084, 4932.654714170118, 4945.357103576512, 4992.028148881244, 5000.824971125993], "value": 3683.496263487842, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.6815058395509, 647.3785166240409, 661.9900701489479, 693.7363801755336, 722.8373615936413, 12793.872061279384, 13770.862291377083, 13773.862261377382, 13795.172289662614, 13797.931010344946, 13195.14231574947, 14076.929615351935, 14086.577402677936, 14627.92686036571, 14636.634084147901, 621.1118101115403, 737.0639288104104, 756.4251169111487, 757.8105473631592, 758.5903612049486, 751.5753599216453, 768.9461737678365, 770.2220757035378, 781.6716978868889, 782.3506489613635, 809.4414853750893, 810.6716779704229, 811.0510702478085, 813.0203105258149, 813.369638530141, 638.6902352359102, 795.0618270440877, 805.7502174325948, 805.7663277649445, 807.8424707182102, 586.448738186108, 587.7619564076556, 588.2764200033939, 589.5578316262803, 590.1708100119339, 14062.0, 14264.788957711571, 14343.49797757084, 14344.067635603653, 14345.067570607876, 13710.657233569142, 14247.358868850926, 14343.354549045318, 14374.35315410809, 14389.136651800925, 10809.513571889283, 10858.565657373696, 10930.234883558154, 10983.670489885237, 11059.281146725438, 3309.12308238317, 3321.8173000484962, 3352.5474061001696, 3384.8307584620916, 3432.056184549244, 69899.65050174767, 70356.6482167586, 73198.63400683015, 73298.90051649282, 73549.26450735531, 10354.275200735876, 10354.63249256831, 10356.223283253636, 10397.688069357931, 10840.566377344818, 19080.14139363732, 19454.319098831755, 19456.513587160152, 19492.70760938587, 19497.22011119568, 6882.8967565486555, 6885.345892140239, 6931.064306318583, 6937.375636192767, 7000.124984376953, 3713.4801127842125, 3813.694904407641, 3860.5946375630715, 3926.764394136361, 3984.7210695251056, 2811.100447856707, 2922.926926826804, 2930.2381380841134, 2931.296488842705, 2932.5601159826197, 473.91469535483947, 476.11680332982513, 480.0327340409078, 501.64633933077096, 517.9611529135329, 500.0948283606611, 571.2602180176681, 573.9053056245756, 577.1198921644526, 620.5687047502033, 8061.123020572586, 8280.254777069973, 8371.874421883676, 8529.061803201643, 8689.869651955225, 555.089652969131, 585.2567239605677, 585.6369548870373, 596.9791057312973, 607.2834055814182, 3907.6092390760696, 3972.7616343019513, 4003.979980100139, 4094.263032654091, 4096.973317403449, 102042.0, 102747.40090481493, 103173.0, 104198.47900760447, 104423.4778826101, 77012.2298777005, 93378.06621933829, 94315.52842235746, 94540.52729736308, 95190.5240473807, 47810.703888930635, 48151.277730834765, 49247.75376123097, 81798.0, 82060.58969705114, 7338.486305958745, 7530.736424225345, 7620.237976202336, 7714.884276735741, 7733.342665873239, 38650.8404747858, 41497.0, 42785.786071069444, 46923.41921194367, 49107.754461227465, 45715.31427028664, 59973.70013150081, 62791.0, 64650.35349646631, 65747.34252657412, 11329.546818127512, 11461.255018423775, 11488.19582629208, 11585.594504192313, 11882.346470944181, 12863.29251891155, 13110.0, 13273.66815829612, 13472.461101555828, 13906.51327203543, 12101.757964840477, 12105.455254513745, 12115.15193936416, 12667.113302068767, 13083.280419577015, 11646.94176529112, 12310.138290319592, 12386.256824590553, 12667.93666031664, 12806.807897881354, 11127.888721112686, 11958.760824783629, 12098.697532561757, 12254.816177757162, 12329.198602090835, 9206.217471514996, 9275.118863707938, 9322.533873306173, 9553.426794392359, 9654.517274136406, 9546.284028697737, 9831.508424578886, 10314.948425257826, 10388.896111038794, 10480.161587072864], "value": 6635.3405105492375, "unit": "score"}], "name": "dromaeo_css", "value": 6350.6465281966985, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 116.0, 114.0, 115.0, 113.0, 113.0, 114.0, 112.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 147.0, 166.0, 129.0, 122.0, 163.0, 123.0, 170.0, 123.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 162.0, 163.0, 162.0, 163.0, 165.0, 161.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 112.0, 76.0, 77.0, 76.0, 75.0, 113.0, 75.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 123.0, 85.0, 86.0, 84.0, 116.0, 87.0, 85.0, 132.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 62.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 77.0, 78.0, 81.0, 78.0, 80.0, 78.0, 80.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 136.0, 127.0, 137.0, 130.0, 131.0, 130.0, 134.0, 174.0, 140.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 164.0, 165.0, 167.0, 164.0, 170.0, 169.0, 166.0, 164.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 71.0, 64.0, 65.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1536.5000000000002, "alertThreshold": 2.0}]} 16:37:42 INFO - Return code: 0 16:37:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:37:42 INFO - # TBPL SUCCESS # 16:37:42 INFO - Running post-action listener: _resource_record_post_action 16:37:42 INFO - ##### 16:37:42 INFO - ##### Finished run-tests step (success) 16:37:42 INFO - ##### 16:37:42 INFO - Running post-run listener: _resource_record_post_run 16:37:42 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536092672; Read time: 16; Write time: 369484 16:37:42 INFO - TinderboxPrint: CPU usage
13.0% 16:37:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:37:42 INFO - TinderboxPrint: I/O write bytes / time
536,092,672 / 369,484 16:37:42 INFO - TinderboxPrint: CPU idle
8,331.8 (87.0%) 16:37:42 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 16:37:42 INFO - TinderboxPrint: Swap in / out
0 / 0 16:37:42 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247029760; Read time: 0; Write time: 214884 16:37:42 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 289062912; Read time: 0; Write time: 154600 16:37:43 INFO - Running post-run listener: _upload_blobber_files 16:37:43 INFO - Blob upload gear active. 16:37:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:37:43 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 16:37:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:37:43 INFO - (blobuploader) - INFO - Open directory for files ... 16:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:37:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:52 INFO - (blobuploader) - INFO - Done attempting. 16:37:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:37:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:37:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:53 INFO - (blobuploader) - INFO - Done attempting. 16:37:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:37:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:37:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:54 INFO - (blobuploader) - INFO - Done attempting. 16:37:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:37:54 INFO - Return code: 0 16:37:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:37:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:37:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/247e72314ae3f15615d74173b9199c11712422929abe1ce6472b35a69201bbff58f126e76e68fa991b455fda678dd32ae0b258a4198a3c8480a0539573eccacf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e952a6ddf8e2bf3dc023a1ab3269acf9240eedd4d6d8e9c2e6e1c082d04532137a65deff310a35b7d5d9640946218a7c4ac6881858192874859e4e34281b17d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9383be049fce3094f42c49553d9d67ad132f310ea033ca52e7af25c84912bd56a6a3a935aa23beaba13bbf9fe21d90849a2fb9d5699210cac5494d742a6ef495"} 16:37:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:37:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:37:54 INFO - Contents: 16:37:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/247e72314ae3f15615d74173b9199c11712422929abe1ce6472b35a69201bbff58f126e76e68fa991b455fda678dd32ae0b258a4198a3c8480a0539573eccacf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e952a6ddf8e2bf3dc023a1ab3269acf9240eedd4d6d8e9c2e6e1c082d04532137a65deff310a35b7d5d9640946218a7c4ac6881858192874859e4e34281b17d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9383be049fce3094f42c49553d9d67ad132f310ea033ca52e7af25c84912bd56a6a3a935aa23beaba13bbf9fe21d90849a2fb9d5699210cac5494d742a6ef495"} 16:37:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:37:54 INFO - Copying logs to upload dir... 16:37:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:37:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.464265 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-18 16:37:54.053256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 16:37:54.056719) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/247e72314ae3f15615d74173b9199c11712422929abe1ce6472b35a69201bbff58f126e76e68fa991b455fda678dd32ae0b258a4198a3c8480a0539573eccacf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e952a6ddf8e2bf3dc023a1ab3269acf9240eedd4d6d8e9c2e6e1c082d04532137a65deff310a35b7d5d9640946218a7c4ac6881858192874859e4e34281b17d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9383be049fce3094f42c49553d9d67ad132f310ea033ca52e7af25c84912bd56a6a3a935aa23beaba13bbf9fe21d90849a2fb9d5699210cac5494d742a6ef495"} build_url:https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/CnCcgN0yQUOlHw31aFlQvA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/247e72314ae3f15615d74173b9199c11712422929abe1ce6472b35a69201bbff58f126e76e68fa991b455fda678dd32ae0b258a4198a3c8480a0539573eccacf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e952a6ddf8e2bf3dc023a1ab3269acf9240eedd4d6d8e9c2e6e1c082d04532137a65deff310a35b7d5d9640946218a7c4ac6881858192874859e4e34281b17d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9383be049fce3094f42c49553d9d67ad132f310ea033ca52e7af25c84912bd56a6a3a935aa23beaba13bbf9fe21d90849a2fb9d5699210cac5494d742a6ef495"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 16:37:54.082213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:37:54.082543) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468881416.683015-1776594147 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:37:54.168283) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 16:37:54.168599) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 16:37:54.168912) ========= ========= Total master_lag: 0.17 =========