builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1486603225.8 results: success (0) revision: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:25.797910) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:25.798314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:25.798637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:25.814496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:20:25.814817) ========= 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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-08 17:20:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.2M=0s 2017-02-08 17:20:27 (40.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.086624 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:20:26.915025) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:26.915354) ========= 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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015225 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:26.965321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:26.965662) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 --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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-08 17:20:27,450 truncating revision to first 12 chars 2017-02-08 17:20:27,450 Setting DEBUG logging. 2017-02-08 17:20:27,450 attempt 1/10 2017-02-08 17:20:27,451 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8dcc2332e280?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 17:20:27,754 unpacking tar archive at: mozilla-aurora-8dcc2332e280/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663131 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:27.642626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:27.642931) ========= script_repo_revision: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:27.643300) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:27.643574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:20:27.652973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-08 17:20:27.653265) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:20:28 INFO - MultiFileLogger online at 20170208 17:20:28 in /builds/slave/test 17:20:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:20:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:28 INFO - {'append_to_log': False, 17:20:28 INFO - 'base_work_dir': '/builds/slave/test', 17:20:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:20:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:28 INFO - 'branch': 'Mozilla-Aurora', 17:20:28 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:28 INFO - 'config_files': ('talos/linux_config.py',), 17:20:28 INFO - 'default_actions': ('clobber', 17:20:28 INFO - 'read-buildbot-config', 17:20:28 INFO - 'download-and-extract', 17:20:28 INFO - 'populate-webroot', 17:20:28 INFO - 'create-virtualenv', 17:20:28 INFO - 'install', 17:20:28 INFO - 'run-tests'), 17:20:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:28 INFO - 'download_minidump_stackwalk': True, 17:20:28 INFO - 'download_symbols': 'ondemand', 17:20:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:28 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:28 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:28 INFO - 'installer_path': 'installer.exe', 17:20:28 INFO - 'log_level': 'info', 17:20:28 INFO - 'log_name': 'talos', 17:20:28 INFO - 'log_to_console': True, 17:20:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:20:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:20:28 INFO - 'opt_config_files': (), 17:20:28 INFO - 'pip_index': False, 17:20:28 INFO - 'sps_profile': False, 17:20:28 INFO - 'sps_profile_interval': 0, 17:20:28 INFO - 'suite': 'dromaeojs', 17:20:28 INFO - 'system_bits': '32', 17:20:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:28 INFO - 'title': 'talos-linux64-ix-053', 17:20:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:28 INFO - 'use_talos_json': True, 17:20:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:28 INFO - 'work_dir': 'build'} 17:20:28 INFO - [mozharness: 2017-02-09 01:20:28.236480Z] Running clobber step. 17:20:28 INFO - Running pre-action listener: _resource_record_pre_action 17:20:28 INFO - Running main action method: clobber 17:20:28 INFO - rmtree: /builds/slave/test/build 17:20:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:28 INFO - Running post-action listener: _resource_record_post_action 17:20:28 INFO - [mozharness: 2017-02-09 01:20:28.706940Z] Finished clobber step (success) 17:20:28 INFO - [mozharness: 2017-02-09 01:20:28.707042Z] Running read-buildbot-config step. 17:20:28 INFO - Running pre-action listener: _resource_record_pre_action 17:20:28 INFO - Running main action method: read_buildbot_config 17:20:28 INFO - Using buildbot properties: 17:20:28 INFO - { 17:20:28 INFO - "project": "", 17:20:28 INFO - "product": "firefox", 17:20:28 INFO - "who": "jryans@gmail.com", 17:20:28 INFO - "installer_path": "public/build/target.tar.bz2", 17:20:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:20:28 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 17:20:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:20:28 INFO - "stage_platform": "linux64-pgo", 17:20:28 INFO - "basedir": "/builds/slave/test", 17:20:28 INFO - "buildnumber": 740, 17:20:28 INFO - "platform": "ubuntu64_hw", 17:20:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:20:28 INFO - "slavebuilddir": "test", 17:20:28 INFO - "taskId": "AQLwvmg9SG-9V52XSRJsjg", 17:20:28 INFO - "branch": "mozilla-aurora", 17:20:28 INFO - "script_repo_revision": "production", 17:20:28 INFO - "revision": "8dcc2332e280ee23815b44ba76f78c9919d9ebd0", 17:20:28 INFO - "slavename": "talos-linux64-ix-053", 17:20:28 INFO - "repo_path": "releases/mozilla-aurora" 17:20:28 INFO - } 17:20:28 INFO - Finding installer, test and symbols from parent task. 17:20:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQLwvmg9SG-9V52XSRJsjg'}, attempt #1 17:20:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQLwvmg9SG-9V52XSRJsjg'}, attempt #1 17:20:29 INFO - Task dependencies: QcbV8TCmT9CGQDTPsM19zw 17:20:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw'}, attempt #1 17:20:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 17:20:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/test_packages.json 17:20:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.crashreporter-symbols.zip 17:20:30 INFO - Running post-action listener: _resource_record_post_action 17:20:30 INFO - [mozharness: 2017-02-09 01:20:30.127814Z] Finished read-buildbot-config step (success) 17:20:30 INFO - [mozharness: 2017-02-09 01:20:30.128031Z] Running download-and-extract step. 17:20:30 INFO - Running pre-action listener: _resource_record_pre_action 17:20:30 INFO - Running main action method: download_and_extract 17:20:30 INFO - mkdir: /builds/slave/test/build/tests 17:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:30 INFO - trying https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.test_packages.json 17:20:30 INFO - Downloading https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:20:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:20:30 INFO - Downloaded 896 bytes. 17:20:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:20:30 INFO - Using the following test package requirements: 17:20:30 INFO - {u'common': [u'target.common.tests.zip'], 17:20:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:20:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:20:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:20:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:20:30 INFO - u'mozbase': [u'target.common.tests.zip'], 17:20:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:20:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:20:30 INFO - u'web-platform': [u'target.common.tests.zip', 17:20:30 INFO - u'target.web-platform.tests.zip'], 17:20:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:20:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:20:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip 17:20:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:20:30 INFO - Fetch https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip into memory 17:20:31 INFO - Expected file size: 21855401 17:20:31 INFO - Obtained file size: 21855401 17:20:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:20:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip 17:20:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:20:35 INFO - Fetch https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.common.tests.zip into memory 17:20:36 INFO - Expected file size: 21855401 17:20:36 INFO - Obtained file size: 21855401 17:20:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.talos.tests.zip 17:20:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:20:40 INFO - Fetch https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.talos.tests.zip into memory 17:20:41 INFO - Expected file size: 14403485 17:20:41 INFO - Obtained file size: 14403485 17:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:41 INFO - trying https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 17:20:41 INFO - Downloading https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:20:43 INFO - Downloaded 66148192 bytes. 17:20:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 17:20:43 INFO - mkdir: /builds/slave/test/properties 17:20:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:20:43 INFO - Writing to file /builds/slave/test/properties/build_url 17:20:43 INFO - Contents: 17:20:43 INFO - build_url:https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 17:20:43 INFO - Running post-action listener: _resource_record_post_action 17:20:43 INFO - Running post-action listener: set_extra_try_arguments 17:20:43 INFO - [mozharness: 2017-02-09 01:20:43.122981Z] Finished download-and-extract step (success) 17:20:43 INFO - [mozharness: 2017-02-09 01:20:43.123078Z] Running populate-webroot step. 17:20:43 INFO - Running pre-action listener: _resource_record_pre_action 17:20:43 INFO - Running main action method: populate_webroot 17:20:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:43 INFO - '--tppagecycles', 17:20:43 INFO - '7'], 17:20:43 INFO - 'tests': ['tsvgm']}}, 17:20:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:20:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['damp', 'tps']}, 17:20:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'tests': ['damp', 'tps']}, 17:20:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['dromaeo_dom']}, 17:20:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['basic_compositor_video']}, 17:20:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:20:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['a11yr', 17:20:43 INFO - 'ts_paint', 17:20:43 INFO - 'tpaint', 17:20:43 INFO - 'sessionrestore', 17:20:43 INFO - 'sessionrestore_no_auto_restore', 17:20:43 INFO - 'tabpaint']}, 17:20:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['tsvgx', 17:20:43 INFO - 'tsvgr_opacity', 17:20:43 INFO - 'tart', 17:20:43 INFO - 'tscrollx', 17:20:43 INFO - 'cart', 17:20:43 INFO - 'tsvg_static']}, 17:20:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:43 INFO - 'tsvgr_opacity', 17:20:43 INFO - 'tart', 17:20:43 INFO - 'tscrollx', 17:20:43 INFO - 'cart', 17:20:43 INFO - 'tsvg_static']}, 17:20:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'talos_options': ['--disable-e10s'], 17:20:43 INFO - 'tests': ['tp5o']}, 17:20:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:20:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'talos_options': ['--disable-e10s', 17:20:43 INFO - '--xperf_path', 17:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:43 INFO - 'tests': ['tp5n']}, 17:20:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:20:43 INFO - 'talos_options': ['--xperf_path', 17:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:43 INFO - 'tests': ['tp5n']}}, 17:20:43 INFO - 'talos.zip': {'path': '', 17:20:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:43 INFO - Running post-action listener: _resource_record_post_action 17:20:43 INFO - [mozharness: 2017-02-09 01:20:43.136204Z] Finished populate-webroot step (success) 17:20:43 INFO - [mozharness: 2017-02-09 01:20:43.136295Z] Running create-virtualenv step. 17:20:43 INFO - Running pre-action listener: _resource_record_pre_action 17:20:43 INFO - Running main action method: create_virtualenv 17:20:43 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:43 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 17:20:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:20:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:43 INFO - Using real prefix '/usr' 17:20:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:45 INFO - Installing pip.................done. 17:20:45 INFO - Return code: 0 17:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:45 INFO - Reading from file tmpfile_stdout 17:20:45 INFO - Output received: 17:20:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:20:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:45 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:45 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 17:20:45 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 17:20:45 INFO - Using env: {'DISPLAY': ':0', 17:20:45 INFO - 'HOME': '/home/cltbld', 17:20:45 INFO - 'LANG': 'en_US.UTF-8', 17:20:45 INFO - 'LANGUAGE': 'en_US:en', 17:20:45 INFO - 'LOGNAME': 'cltbld', 17:20:45 INFO - 'MAIL': '/var/mail/cltbld', 17:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:45 INFO - 'MOZ_NO_REMOTE': '1', 17:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:45 INFO - 'NO_EM_RESTART': '1', 17:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:45 INFO - 'PWD': '/builds/slave/test', 17:20:45 INFO - 'SHELL': '/bin/bash', 17:20:45 INFO - 'SHLVL': '1', 17:20:45 INFO - 'TERM': 'linux', 17:20:45 INFO - 'TMOUT': '86400', 17:20:45 INFO - 'USER': 'cltbld', 17:20:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:45 INFO - '_': '/tools/buildbot/bin/python'} 17:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:46 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 17:20:46 INFO - Cleaning up... 17:20:46 INFO - Return code: 0 17:20:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:46 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:46 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 17:20:46 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 17:20:46 INFO - Using env: {'DISPLAY': ':0', 17:20:46 INFO - 'HOME': '/home/cltbld', 17:20:46 INFO - 'LANG': 'en_US.UTF-8', 17:20:46 INFO - 'LANGUAGE': 'en_US:en', 17:20:46 INFO - 'LOGNAME': 'cltbld', 17:20:46 INFO - 'MAIL': '/var/mail/cltbld', 17:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:46 INFO - 'MOZ_NO_REMOTE': '1', 17:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:46 INFO - 'NO_EM_RESTART': '1', 17:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:46 INFO - 'PWD': '/builds/slave/test', 17:20:46 INFO - 'SHELL': '/bin/bash', 17:20:46 INFO - 'SHLVL': '1', 17:20:46 INFO - 'TERM': 'linux', 17:20:46 INFO - 'TMOUT': '86400', 17:20:46 INFO - 'USER': 'cltbld', 17:20:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:46 INFO - '_': '/tools/buildbot/bin/python'} 17:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:46 INFO - Downloading/unpacking psutil>=3.1.1 17:20:46 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 17:20:46 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 17:20:46 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 17:20:46 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 17:20:46 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 17:20:46 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 17:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:48 INFO - Installing collected packages: psutil 17:20:48 INFO - Running setup.py install for psutil 17:20:48 INFO - building 'psutil._psutil_linux' extension 17:20:48 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 17:20:48 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 17:20:48 INFO - building 'psutil._psutil_posix' extension 17:20:48 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 17:20:48 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 17:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:48 INFO - Successfully installed psutil 17:20:48 INFO - Cleaning up... 17:20:49 INFO - Return code: 0 17:20:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:20:49 INFO - Using env: {'DISPLAY': ':0', 17:20:49 INFO - 'HOME': '/home/cltbld', 17:20:49 INFO - 'LANG': 'en_US.UTF-8', 17:20:49 INFO - 'LANGUAGE': 'en_US:en', 17:20:49 INFO - 'LOGNAME': 'cltbld', 17:20:49 INFO - 'MAIL': '/var/mail/cltbld', 17:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:49 INFO - 'MOZ_NO_REMOTE': '1', 17:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:49 INFO - 'NO_EM_RESTART': '1', 17:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:49 INFO - 'PWD': '/builds/slave/test', 17:20:49 INFO - 'SHELL': '/bin/bash', 17:20:49 INFO - 'SHLVL': '1', 17:20:49 INFO - 'TERM': 'linux', 17:20:49 INFO - 'TMOUT': '86400', 17:20:49 INFO - 'USER': 'cltbld', 17:20:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:49 INFO - '_': '/tools/buildbot/bin/python'} 17:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:49 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 17:20:49 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 17:20:49 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 17:20:49 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 17:20:49 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 17:20:49 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 17:20:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:20:51 INFO - Installing collected packages: mozsystemmonitor 17:20:51 INFO - Running setup.py install for mozsystemmonitor 17:20:51 INFO - Successfully installed mozsystemmonitor 17:20:51 INFO - Cleaning up... 17:20:51 INFO - Return code: 0 17:20:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:51 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 17:20:51 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 17:20:51 INFO - Using env: {'DISPLAY': ':0', 17:20:51 INFO - 'HOME': '/home/cltbld', 17:20:51 INFO - 'LANG': 'en_US.UTF-8', 17:20:51 INFO - 'LANGUAGE': 'en_US:en', 17:20:51 INFO - 'LOGNAME': 'cltbld', 17:20:51 INFO - 'MAIL': '/var/mail/cltbld', 17:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:51 INFO - 'MOZ_NO_REMOTE': '1', 17:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:51 INFO - 'NO_EM_RESTART': '1', 17:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:51 INFO - 'PWD': '/builds/slave/test', 17:20:51 INFO - 'SHELL': '/bin/bash', 17:20:51 INFO - 'SHLVL': '1', 17:20:51 INFO - 'TERM': 'linux', 17:20:51 INFO - 'TMOUT': '86400', 17:20:51 INFO - 'USER': 'cltbld', 17:20:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:51 INFO - '_': '/tools/buildbot/bin/python'} 17:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:51 INFO - Downloading/unpacking jsonschema==2.5.1 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:20:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:53 INFO - Downloading functools32-3.2.3-2.tar.gz 17:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:20:53 INFO - warning: no files found matching '*.txt' 17:20:53 INFO - no previously-included directories found matching 'build' 17:20:53 INFO - no previously-included directories found matching 'dist' 17:20:53 INFO - no previously-included directories found matching '.git*' 17:20:53 INFO - Installing collected packages: jsonschema, functools32 17:20:54 INFO - Running setup.py install for functools32 17:20:54 INFO - warning: no files found matching '*.txt' 17:20:54 INFO - no previously-included directories found matching 'build' 17:20:54 INFO - no previously-included directories found matching 'dist' 17:20:54 INFO - no previously-included directories found matching '.git*' 17:20:54 INFO - Successfully installed jsonschema functools32 17:20:54 INFO - Cleaning up... 17:20:54 INFO - Return code: 0 17:20:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:20:54 INFO - Using env: {'DISPLAY': ':0', 17:20:54 INFO - 'HOME': '/home/cltbld', 17:20:54 INFO - 'LANG': 'en_US.UTF-8', 17:20:54 INFO - 'LANGUAGE': 'en_US:en', 17:20:54 INFO - 'LOGNAME': 'cltbld', 17:20:54 INFO - 'MAIL': '/var/mail/cltbld', 17:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:54 INFO - 'MOZ_NO_REMOTE': '1', 17:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:54 INFO - 'NO_EM_RESTART': '1', 17:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:54 INFO - 'PWD': '/builds/slave/test', 17:20:54 INFO - 'SHELL': '/bin/bash', 17:20:54 INFO - 'SHLVL': '1', 17:20:54 INFO - 'TERM': 'linux', 17:20:54 INFO - 'TMOUT': '86400', 17:20:54 INFO - 'USER': 'cltbld', 17:20:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:54 INFO - '_': '/tools/buildbot/bin/python'} 17:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:20:54 INFO - Cleaning up... 17:20:54 INFO - Return code: 0 17:20:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:54 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:54 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 17:20:54 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 17:20:54 INFO - Using env: {'DISPLAY': ':0', 17:20:54 INFO - 'HOME': '/home/cltbld', 17:20:54 INFO - 'LANG': 'en_US.UTF-8', 17:20:54 INFO - 'LANGUAGE': 'en_US:en', 17:20:54 INFO - 'LOGNAME': 'cltbld', 17:20:54 INFO - 'MAIL': '/var/mail/cltbld', 17:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:54 INFO - 'MOZ_NO_REMOTE': '1', 17:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:54 INFO - 'NO_EM_RESTART': '1', 17:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:54 INFO - 'PWD': '/builds/slave/test', 17:20:54 INFO - 'SHELL': '/bin/bash', 17:20:54 INFO - 'SHLVL': '1', 17:20:54 INFO - 'TERM': 'linux', 17:20:54 INFO - 'TMOUT': '86400', 17:20:54 INFO - 'USER': 'cltbld', 17:20:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:54 INFO - '_': '/tools/buildbot/bin/python'} 17:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:54 INFO - Downloading/unpacking blobuploader==1.2.4 17:20:54 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 17:20:54 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 17:20:54 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 17:20:54 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 17:20:54 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 17:20:54 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 17:20:56 INFO - Downloading blobuploader-1.2.4.tar.gz 17:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:20:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:20:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:57 INFO - Downloading docopt-0.6.1.tar.gz 17:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:20:57 INFO - Installing collected packages: blobuploader, requests, docopt 17:20:57 INFO - Running setup.py install for blobuploader 17:20:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:20:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:20:57 INFO - Running setup.py install for requests 17:20:57 INFO - Running setup.py install for docopt 17:20:57 INFO - Successfully installed blobuploader requests docopt 17:20:57 INFO - Cleaning up... 17:20:58 INFO - Return code: 0 17:20:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:58 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:20:58 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 17:20:58 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 17:20:58 INFO - Using env: {'DISPLAY': ':0', 17:20:58 INFO - 'HOME': '/home/cltbld', 17:20:58 INFO - 'LANG': 'en_US.UTF-8', 17:20:58 INFO - 'LANGUAGE': 'en_US:en', 17:20:58 INFO - 'LOGNAME': 'cltbld', 17:20:58 INFO - 'MAIL': '/var/mail/cltbld', 17:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:58 INFO - 'MOZ_NO_REMOTE': '1', 17:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:58 INFO - 'NO_EM_RESTART': '1', 17:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:58 INFO - 'PWD': '/builds/slave/test', 17:20:58 INFO - 'SHELL': '/bin/bash', 17:20:58 INFO - 'SHLVL': '1', 17:20:58 INFO - 'TERM': 'linux', 17:20:58 INFO - 'TMOUT': '86400', 17:20:58 INFO - 'USER': 'cltbld', 17:20:58 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:58 INFO - '_': '/tools/buildbot/bin/python'} 17:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:58 INFO - Running setup.py (path:/tmp/pip-pXobUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:58 INFO - Running setup.py (path:/tmp/pip-0JUacS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:58 INFO - Running setup.py (path:/tmp/pip-ObPBfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:58 INFO - Running setup.py (path:/tmp/pip-0okg1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:58 INFO - Running setup.py (path:/tmp/pip-avIDLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:58 INFO - Running setup.py (path:/tmp/pip-aAfXDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:58 INFO - Running setup.py (path:/tmp/pip-BYjQiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:58 INFO - Running setup.py (path:/tmp/pip-ndvGdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:58 INFO - Running setup.py (path:/tmp/pip-eexUXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:58 INFO - Running setup.py (path:/tmp/pip-nMkWmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:58 INFO - Running setup.py (path:/tmp/pip-3AOXfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:59 INFO - Running setup.py (path:/tmp/pip-RHIn92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:59 INFO - Running setup.py (path:/tmp/pip-XfOutJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:59 INFO - Running setup.py (path:/tmp/pip-7zsdTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:59 INFO - Running setup.py (path:/tmp/pip-VZGDvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:59 INFO - Running setup.py (path:/tmp/pip-4DRlah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:59 INFO - Running setup.py (path:/tmp/pip-I3zFgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:59 INFO - Running setup.py install for manifestparser 17:20:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:59 INFO - Running setup.py install for mozcrash 17:20:59 INFO - Running setup.py install for mozdebug 17:20:59 INFO - Running setup.py install for mozdevice 17:20:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:59 INFO - Running setup.py install for mozfile 17:20:59 INFO - Running setup.py install for mozhttpd 17:21:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozinfo 17:21:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozInstall 17:21:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozleak 17:21:00 INFO - Running setup.py install for mozlog 17:21:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for moznetwork 17:21:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozprocess 17:21:00 INFO - Running setup.py install for mozprofile 17:21:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozrunner 17:21:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:00 INFO - Running setup.py install for mozscreenshot 17:21:01 INFO - Running setup.py install for moztest 17:21:01 INFO - Running setup.py install for mozversion 17:21:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:01 INFO - Cleaning up... 17:21:01 INFO - Return code: 0 17:21:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:01 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:21:01 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 17:21:01 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 17:21:01 INFO - Using env: {'DISPLAY': ':0', 17:21:01 INFO - 'HOME': '/home/cltbld', 17:21:01 INFO - 'LANG': 'en_US.UTF-8', 17:21:01 INFO - 'LANGUAGE': 'en_US:en', 17:21:01 INFO - 'LOGNAME': 'cltbld', 17:21:01 INFO - 'MAIL': '/var/mail/cltbld', 17:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:01 INFO - 'MOZ_NO_REMOTE': '1', 17:21:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:01 INFO - 'NO_EM_RESTART': '1', 17:21:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:01 INFO - 'PWD': '/builds/slave/test', 17:21:01 INFO - 'SHELL': '/bin/bash', 17:21:01 INFO - 'SHLVL': '1', 17:21:01 INFO - 'TERM': 'linux', 17:21:01 INFO - 'TMOUT': '86400', 17:21:01 INFO - 'USER': 'cltbld', 17:21:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:01 INFO - '_': '/tools/buildbot/bin/python'} 17:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:01 INFO - Running setup.py (path:/tmp/pip-YlOZ2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:01 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:01 INFO - Running setup.py (path:/tmp/pip-ngwUo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:01 INFO - Running setup.py (path:/tmp/pip-Tb59b2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:01 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:01 INFO - Running setup.py (path:/tmp/pip-tr04Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:01 INFO - Running setup.py (path:/tmp/pip-QRaMZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:01 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:01 INFO - Running setup.py (path:/tmp/pip-TJfXuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:01 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)) 17:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:01 INFO - Running setup.py (path:/tmp/pip-L5admh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:02 INFO - Running setup.py (path:/tmp/pip-K5Zkqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:02 INFO - Running setup.py (path:/tmp/pip-MMREbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:02 INFO - Running setup.py (path:/tmp/pip-BINCnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:02 INFO - Running setup.py (path:/tmp/pip-Z_IAxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:02 INFO - Running setup.py (path:/tmp/pip-3xj_c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:02 INFO - Running setup.py (path:/tmp/pip-zyNltj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:02 INFO - Running setup.py (path:/tmp/pip-qjCeCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:02 INFO - Running setup.py (path:/tmp/pip-LwRZfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:02 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:02 INFO - Running setup.py (path:/tmp/pip-ZRswbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:02 INFO - Running setup.py (path:/tmp/pip-Vb8CV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:02 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)) 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:21:02 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)) 17:21:02 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:02 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:04 INFO - Downloading blessings-1.6.tar.gz 17:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:04 INFO - Installing collected packages: blessings 17:21:04 INFO - Running setup.py install for blessings 17:21:04 INFO - Successfully installed blessings 17:21:04 INFO - Cleaning up... 17:21:05 INFO - Return code: 0 17:21:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:05 INFO - Reading from file tmpfile_stdout 17:21:05 INFO - Current package versions: 17:21:05 INFO - argparse == 1.2.1 17:21:05 INFO - blessings == 1.6 17:21:05 INFO - blobuploader == 1.2.4 17:21:05 INFO - docopt == 0.6.1 17:21:05 INFO - functools32 == 3.2.3-2 17:21:05 INFO - jsonschema == 2.5.1 17:21:05 INFO - manifestparser == 1.1 17:21:05 INFO - mozInstall == 1.12 17:21:05 INFO - mozcrash == 1.0 17:21:05 INFO - mozdebug == 0.1 17:21:05 INFO - mozdevice == 0.49 17:21:05 INFO - mozfile == 1.2 17:21:05 INFO - mozhttpd == 0.7 17:21:05 INFO - mozinfo == 0.9 17:21:05 INFO - mozleak == 0.1 17:21:05 INFO - mozlog == 3.4 17:21:05 INFO - moznetwork == 0.27 17:21:05 INFO - mozprocess == 0.24 17:21:05 INFO - mozprofile == 0.28 17:21:05 INFO - mozrunner == 6.13 17:21:05 INFO - mozscreenshot == 0.1 17:21:05 INFO - mozsystemmonitor == 0.3 17:21:05 INFO - moztest == 0.8 17:21:05 INFO - mozversion == 1.4 17:21:05 INFO - psutil == 3.1.1 17:21:05 INFO - requests == 1.2.3 17:21:05 INFO - wsgiref == 0.1.2 17:21:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:21:05 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 17:21:05 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 17:21:05 INFO - Using env: {'DISPLAY': ':0', 17:21:05 INFO - 'HOME': '/home/cltbld', 17:21:05 INFO - 'LANG': 'en_US.UTF-8', 17:21:05 INFO - 'LANGUAGE': 'en_US:en', 17:21:05 INFO - 'LOGNAME': 'cltbld', 17:21:05 INFO - 'MAIL': '/var/mail/cltbld', 17:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:05 INFO - 'MOZ_NO_REMOTE': '1', 17:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:05 INFO - 'NO_EM_RESTART': '1', 17:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:05 INFO - 'PWD': '/builds/slave/test', 17:21:05 INFO - 'SHELL': '/bin/bash', 17:21:05 INFO - 'SHLVL': '1', 17:21:05 INFO - 'TERM': 'linux', 17:21:05 INFO - 'TMOUT': '86400', 17:21:05 INFO - 'USER': 'cltbld', 17:21:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:05 INFO - '_': '/tools/buildbot/bin/python'} 17:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 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)) 17:21:05 INFO - Cleaning up... 17:21:05 INFO - Return code: 0 17:21:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 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 0x11c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1539630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, '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': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', '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 17:21:05 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 17:21:05 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 17:21:05 INFO - Using env: {'DISPLAY': ':0', 17:21:05 INFO - 'HOME': '/home/cltbld', 17:21:05 INFO - 'LANG': 'en_US.UTF-8', 17:21:05 INFO - 'LANGUAGE': 'en_US:en', 17:21:05 INFO - 'LOGNAME': 'cltbld', 17:21:05 INFO - 'MAIL': '/var/mail/cltbld', 17:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:05 INFO - 'MOZ_NO_REMOTE': '1', 17:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:05 INFO - 'NO_EM_RESTART': '1', 17:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:05 INFO - 'PWD': '/builds/slave/test', 17:21:05 INFO - 'SHELL': '/bin/bash', 17:21:05 INFO - 'SHLVL': '1', 17:21:05 INFO - 'TERM': 'linux', 17:21:05 INFO - 'TMOUT': '86400', 17:21:05 INFO - 'USER': 'cltbld', 17:21:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:05 INFO - '_': '/tools/buildbot/bin/python'} 17:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:05 INFO - Cleaning up... 17:21:05 INFO - Return code: 0 17:21:05 INFO - Running post-action listener: _resource_record_post_action 17:21:05 INFO - Running post-action listener: _start_resource_monitoring 17:21:05 INFO - Starting resource monitoring. 17:21:05 INFO - [mozharness: 2017-02-09 01:21:05.715953Z] Finished create-virtualenv step (success) 17:21:05 INFO - [mozharness: 2017-02-09 01:21:05.716402Z] Running install step. 17:21:05 INFO - Running pre-action listener: _resource_record_pre_action 17:21:05 INFO - Running main action method: install 17:21:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:05 INFO - Reading from file tmpfile_stdout 17:21:05 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:06 INFO - Reading from file tmpfile_stdout 17:21:06 INFO - Output received: 17:21:06 INFO - Usage: mozinstall [options] installer 17:21:06 INFO - Options: 17:21:06 INFO - -h, --help show this help message and exit 17:21:06 INFO - -d DEST, --destination=DEST 17:21:06 INFO - Directory to install application into. [default: 17:21:06 INFO - "/builds/slave/test"] 17:21:06 INFO - --app=APP Application being installed. [default: firefox] 17:21:06 INFO - mkdir: /builds/slave/test/build/application 17:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:21:22 INFO - Reading from file tmpfile_stdout 17:21:22 INFO - Output received: 17:21:22 INFO - /builds/slave/test/build/application/firefox/firefox 17:21:22 INFO - Running post-action listener: _resource_record_post_action 17:21:22 INFO - [mozharness: 2017-02-09 01:21:22.443289Z] Finished install step (success) 17:21:22 INFO - [mozharness: 2017-02-09 01:21:22.443567Z] Running run-tests step. 17:21:22 INFO - Running pre-action listener: _resource_record_pre_action 17:21:22 INFO - Running main action method: run_tests 17:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:22 INFO - Python 2.7.3 17:21:22 INFO - Return code: 0 17:21:22 INFO - grabbing minidump binary from tooltool 17:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:22 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 0x15c2850>, '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 0x15376d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1537b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:22 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 17:21:22 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 17:21:22 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 17:21:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:22 INFO - Return code: 0 17:21:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:21:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:21:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 17:21:22 INFO - Using env: {'DISPLAY': ':0', 17:21:22 INFO - 'HOME': '/home/cltbld', 17:21:22 INFO - 'LANG': 'en_US.UTF-8', 17:21:22 INFO - 'LANGUAGE': 'en_US:en', 17:21:22 INFO - 'LOGNAME': 'cltbld', 17:21:22 INFO - 'MAIL': '/var/mail/cltbld', 17:21:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:22 INFO - 'MOZ_NO_REMOTE': '1', 17:21:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:22 INFO - 'NO_EM_RESTART': '1', 17:21:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:22 INFO - 'PWD': '/builds/slave/test', 17:21:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:22 INFO - 'SHELL': '/bin/bash', 17:21:22 INFO - 'SHLVL': '1', 17:21:22 INFO - 'TERM': 'linux', 17:21:22 INFO - 'TMOUT': '86400', 17:21:22 INFO - 'USER': 'cltbld', 17:21:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989', 17:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:22 INFO - '_': '/tools/buildbot/bin/python'} 17:21:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 17:21:22 INFO - mozversion application_buildid: 20170209001104 17:21:22 INFO - mozversion application_changeset: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 17:21:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:21:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:22 INFO - mozversion application_name: Firefox 17:21:22 INFO - mozversion application_remotingname: firefox-dev 17:21:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:21:22 INFO - mozversion application_vendor: Mozilla 17:21:22 INFO - mozversion application_version: 53.0a2 17:21:22 INFO - mozversion platform_buildid: 20170209001104 17:21:22 INFO - mozversion platform_changeset: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 17:21:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:21:22 INFO - mozversion platform_version: 53.0a2 17:21:22 INFO - using testdate: 1486603282 17:21:22 INFO - actual date: 1486603282 17:21:22 INFO - starting webserver on 'localhost:41799' 17:21:22 INFO - SUITE-START | Running 2 tests 17:21:22 INFO - TEST-START | dromaeo_css 17:21:22 INFO - operating with platform_type : linux_ 17:21:22 INFO - Initialising browser for dromaeo_css test... 17:21:22 INFO - TEST-INFO | started process 21259 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc1ft1v/profile http://localhost:41799/getInfo.html) 17:21:26 INFO - PROCESS | 21259 | __metrics Screen width/height:1600/1200 17:21:26 INFO - PROCESS | 21259 | colorDepth:24 17:21:26 INFO - PROCESS | 21259 | Browser inner width/height: 1024/697 17:21:26 INFO - PROCESS | 21259 | __metrics 17:21:27 INFO - PROCESS | 21259 | 1486603287407 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:21:27 INFO - PROCESS | 21259 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:27 INFO - PROCESS | 21259 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:28 INFO - PROCESS | 21259 | console.error: PushService: 17:21:28 INFO - PROCESS | 21259 | stateChangeProcessEnqueue: Error transitioning state 17:21:28 INFO - PROCESS | 21259 | UnknownError 17:21:28 INFO - TEST-INFO | 21259: exit 0 17:21:28 INFO - Browser initialized. 17:21:28 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:28 INFO - TEST-INFO | started process 21349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc1ft1v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:40 INFO - PROCESS | 21349 | RSS: Main: 140906496 17:21:40 INFO - PROCESS | 21349 | 17:24:40 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41799/tests/dromaeo/cssquery-ext.html) 17:24:42 INFO - PROCESS | 21349 | RSS: Main: 240328704 17:24:42 INFO - PROCESS | 21349 | 17:26:30 INFO - PROCESS | 21349 | ************************* 17:26:30 INFO - PROCESS | 21349 | A coding exception was thrown and uncaught in a Task. 17:26:30 INFO - PROCESS | 21349 | 17:26:30 ERROR - PROCESS | 21349 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:30 INFO - PROCESS | 21349 | Full stack: 17:26:30 INFO - PROCESS | 21349 | ************************* 17:27:44 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-ext.html (next: http://localhost:41799/tests/dromaeo/cssquery-jquery.html) 17:27:44 INFO - PROCESS | 21349 | RSS: Main: 227246080 17:27:44 INFO - PROCESS | 21349 | 17:30:46 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41799/tests/dromaeo/cssquery-mootools.html) 17:30:47 INFO - PROCESS | 21349 | RSS: Main: 200949760 17:30:47 INFO - PROCESS | 21349 | 17:33:49 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41799/tests/dromaeo/cssquery-prototype.html) 17:33:49 INFO - PROCESS | 21349 | RSS: Main: 171380736 17:33:49 INFO - PROCESS | 21349 | 17:36:52 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41799/tests/dromaeo/cssquery-yui.html) 17:36:53 INFO - PROCESS | 21349 | RSS: Main: 171380736 17:36:53 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | Cycle 1(1): loaded http://localhost:41799/tests/dromaeo/cssquery-yui.html (next: http://localhost:41799/tests/dromaeo/cssquery-dojo.html) 17:39:55 INFO - PROCESS | 21349 | RSS: Main: 262012928 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | __start_tp_report 17:39:55 INFO - PROCESS | 21349 | _x_x_mozilla_page_load 17:39:55 INFO - PROCESS | 21349 | _x_x_mozilla_page_load_details 17:39:55 INFO - PROCESS | 21349 | |i|pagename|runs| 17:39:55 INFO - PROCESS | 21349 | |0;dojo.html;3205.2627895584014;3226.822524761137;3236.2556611979244;3247.3180632067265;3278.9999050289653;5315.521603055724;5320.840374788758;5326.92009619856;5333.146696528561;5354.089804733195;5335.0663633864015;5358.4641535846495;5361.7587208575615;5362.383325917521;5372.194170874371;4762.5951794097455;4799.688020278688;4805.711657300556;4839.975800120995;4849.7817598207985;4763.833265835697;4777.28340748887;4813.687110337834;4878.317035615017;4902.632302577304;4716;4721.669483136182;4736.644751643623;4775.4985726498735;4816.133096042711;4823.372961514998;4825.4933232010835;4829.951700482985;4833.685810422311;4869.634777391674;4753.786079626425;4758.619310455155;4790.880227994293;4793.18515852306;4811.206150985084;5310.070737620901;5317.73411329432;5357.116075847481;5366.570674346043;5429.158480435539;5798.130280457923;5800.651960882361;5800.7679692812235;5811.128330750421;5834.154047663107;5775.682337471438;5800.622959507618;5840.941590584082;5854;5856.063029915235;5310.867228319323;5335.733213339317;5342.866428339284;5346.705931173745;5462.126059830448;5823.88352232953;5850.444207800252;5860.970695146497;5863.0325996211;5898.734556944948;5662.716864156776;5662.801801936913;5663.292088488939;5666.34836993751;5672.12082127271;5647.689377084218;5658.688772117491;5715.942840571624;5750.022496175577;5756.42435756421;5662.971685141548;5677.943220567824;5727.770889164387;5760.135979603009;5764.5676574257095;5714.657120572779;5716.5998380113;5723.685197314105;5726.198332233491;5727.77088916447;4374.147041326911;4397.890052748643;4471.575200355961;4485;4506.1663592235545;4000.0399904023407;4016.9397459038137;4048;4049.493813273341;4062.6952978526137;5619.325680918316;5623.437656234344;5624.0439125348785;5624.690642014647;5637;5653.067243904792;5709.200711900337;5720.914186287209;5864.560157988082;5872;5573.6098473106495;5616.859578510488;5619.943800562024;5636.154576813429;5640.492355688008;4939.975300123477;4943.777530011158;4949.57928576075;4949.876253093701;4978.850634480971;5914.822555323347;5933.703314834327;6033.909491357633;6048.969755151196;6049.5462840287155;5721.856953576027;5735.881503106854;5772.163036359789;5779.219805326227;5890.4993075589055;6071.453569178795;6093.086037094471;6094.2077529920825;6104.38956104386;6108;5732.942670573408;5733.770649173986;5738.68437235956;5741.540676745767;5762.855928601651;5641.041023026013;5651.660900345993;5698.487136157766;5733.541316694738;5746.655200687972;5608.85977850557;5637.351704553865;5639.548836093185;5642.85892852682;5669.234653321913;5640.097584386478;5640.971795140998;5643.633163844337;5644.689542075225;5647.124695672177;5517.089680202722;5556.416576259435;5582.748776305158;5606.159076138449;5610.85972850682;5703.344115426779;5712.02895507773;5712.685802280915;5713.342965558848;5742.339630942495;5532;5578.3584887738425;5587.106063029895;5596.692181930033;5600.27196464457 17:39:55 INFO - PROCESS | 21349 | |1;ext.html;544.6941877259724;575.0827430248753;614.9446549810517;622.2781573374883;652.6964961293004;36526.08684782882;47735.522644773584;49185.75407122968;49281;49344.51966441008;51620;52211.47788522114;52212.21681674778;52226.73886630571;52248.73875630626;8633.179847914438;8776.946766388042;9422.62309507619;9617.23062155026;9619.519024048805;11047.502862371173;11317.264377815456;11322.320660760339;11647.941760291187;11651.592194273202;11310.943445282763;11345.09239260857;11380.886191138065;11396.544138234462;11403.942980285086;11434.313941163557;11463.082953363793;11486.13853960948;11512.94243528777;11516;11073.944630276797;11122.332660040423;11146.442677866075;11148.21962462628;11152.888471115266;47589.52410475886;47913;48088.27867581989;48098;48116;4572.977135114304;4625.745583992879;4696.741679207642;4748.548887855648;4791.993681326926;35814.28371432583;35839.4624080639;36553.45169822455;36875;37269.627303726884;4666.556677115668;4669.369635099252;4746.406699162605;4758.405199350082;4995.825146119904;358074;358806;359137;359709.2014539937;359954;68766;69198.65400672964;69282.65358673174;69355.2257909688;69374.3062569378;71739.2826071743;71804.64097679478;71916;72048.63975680088;72083.63958180281;19592;20226.494337641678;20257.696134557995;20335.796642033685;20339.79660203379;12878;13156.60530184096;15282.465113720966;15743.763843542356;15795.131267780382;18791.624167516573;18871.05644717786;18886.905565472083;18887.244510219713;18889.622207556047;6944.4791640626145;7172.605506697182;7228.192951762059;7230.638468076575;7307.926920730831;982.3221976835961;989.8020395920701;1000.1248907206194;1008.037324355239;1008.1884083312857;30737.2315692105;31952;33581.16047098793;33691.49462758061;34037.82981085128;1127.0757978457586;1138.3113216504062;1163.9243449175776;1168.456667649547;1190.4345435918042;16240.431584894708;16280.837191628169;16548;16566.6686666266;16786.160691965353;516207;516428;516671;520875;520910;58294.4170558289;58309.70845145832;58580.70709646425;58655.120173197436;58757;1504.4508754304818;1539.692061587687;1554.9922250388677;1575.842415758415;1590.1731099828025;29308.41383172282;30826;30883.84558077195;30893.69106308908;31110;51432.22851657153;51932.96134077373;52065.219021715464;52120.73939630278;52154;411984;414732;415490;415527;415718;24813.62779558272;28008.719912801425;28142.43715125645;28254.717452825207;29586.260343491584;45736.54263457323;45989.540104599866;46006;46038.30942535798;46106.308405373275;38462.6153738459;38629.613703863724;38734.612653873104;39921;39984.600153998086;37612.435813462274;37844.243115138095;37925.81037094797;38264.80867595644;39192.80403598078;38039.61960380361;38062.80968595139;38470.80764596159;39979.80010099931;39997;33596;35551.64448355483;36275.63724362828;36892.81553592215;36896.26207475889;27601.309967250978;27618.171454856387;27705.722942770313;27794.583081253393;27955.301117471412 17:39:55 INFO - PROCESS | 21349 | |2;jquery.html;442.96234820040297;443.2332065526639;445.6390323837692;455.3238440915241;461.50849345447097;21231.787682123173;21503.354899353046;21994.230201942933;22052.228172013984;22258.777412225874;13786.310684465787;14139.222342771143;14197.432102715904;14237.644058898533;14500.564983050499;5283.339582552181;5546.528545073674;5592.384837667853;5676.631018983773;5681.8579535511735;4308.358354863482;4436.622887054588;5663.1505274208785;5709.514691251248;5710.428957104277;6867.553609015423;6890.724371025153;6896.448284137282;6911.688973996157;6925.34209250123;6236.469900058501;6269.780557680505;6275.843103922392;6306.054091886231;6935.514513984023;6042.456178943872;6158.414950579688;6163.167972323724;6163.907541386883;6169.506439484875;15671.608209794733;15952.2821473034;16028.439004634782;16032.679346413006;23826;3084.228942764309;3160.8103513789247;3173.2384227785165;3266.7386609071455;3272.4927636216426;11211.551537938472;11563.42182890852;11576.53693852245;11580.652580422517;11581.768364632744;1990.2735501541977;2057.2799520167973;2082.344061620585;2110.9894450527804;2150.5698860227862;86993.56503217507;97314;98527.50736246344;100258;100841;12467.563635272903;12716.745665086646;12776.041796865087;12882.613521594367;13093.934530327288;18566.350177743716;18577.25690972373;18579.90710046441;18590.907045464955;18650.253989840254;9298.814023719488;9313.674021409353;9405.764855878659;9433.103855133751;9535.284853635867;8922.152395522415;8952.418092823946;8987.236084932854;9168.679096231599;9185.678501252558;5413.918791218135;5424.430434804368;5455.481729235717;5534.501894829485;5644;1932.130785783111;2113.8097571218664;2208.3043841189974;2209.5691340188832;2265.762094980036;951.2152474208806;961.1061712607342;972.3096601412934;988.2242439684813;1002.5939494504737;20872.89563552203;21968.90155492219;22039.228626997978;22985;23558.293251202493;1048.1038711901215;1048.1562342314357;1051.7702470762033;1063.2982231727024;1069.4973362519604;11220.382878941737;11717.88282117185;11818.881811181778;11836.171467997157;11848.526058957545;397147;707493;717410;722406;745932;115334.53510719114;115576.42211789056;115846;116048.41975790236;119323;1167.3987896233446;1176.370811236885;1195.9581414650445;1209.3469526455792;1210.116614871149;17787.644247115244;18069.638607228044;18072.728909066638;18276.543086422946;18522.907385462986;47617.76191119161;49606.25590616216;50057.24914126218;50765.492345076076;50805;65826;68854;70432.64783676049;70894.29105709083;71784.64107679429;9262.768430789014;9280.211182049332;9580.425174489554;9702.126808587262;9987.25095617817;10083.596656133966;13873.930630346784;14323.355449004612;14465.204413757345;15286.235688215767;11678.88321116778;11812.940935295268;12238.286639870603;12531.373431328579;12768.6807829805;11734.706632333919;11806.881931180922;12174.513019479122;12191.085668574715;12230.938845305716;12023.819642705192;12089.274643521418;12120.151589388659;12125.029997599995;12256.938715306367;9348.672796452092;9417;9557.474338911426;9584.089511496397;9622.51887405641;8974.641014359353;11491.080713543064;11572.59495917639;11705.941470292593;11760.88239117598 17:39:55 INFO - PROCESS | 21349 | |3;mootools.html;1499.7675360319154;1503.1732547099093;1511.1159971416728;1523.3449616664834;1530.9234538273083;8718.651253949842;9214.078592140782;9222.400543964646;9224.169824715775;9227.538623068853;8015.839683206347;8273.214044665747;8308.252257296843;8583.18459746323;8960.327975401855;2521.9747802522015;2557.1305756042943;2562.6668533090674;2563.1797824696105;2572.614107883823;2474.7277799442045;2495.6131799571094;2528.671272734542;2531.9240422787348;2536;2513.987430062856;2514.9940023990366;2526.1158594491963;2531.0888080291174;2532.0757923357933;2436.6466862304956;2441.5361081394476;2461.5569197544437;2461.987690061538;2466.8889899954456;2383.4041489627593;2440.023990403835;2460.1635443949144;2484.91302804401;2512.120757734778;9063.592138353724;9139.497327646977;9139.634414623408;9142.725718228463;9167.358284920057;5516.310461192351;5705.828825135252;5714.114312281603;5715.114157305624;5716.256886604715;3483.5993860705835;3536.6993805526563;3547.255076433926;3547.3260080584605;3606.6032736398975;1487.7024595081027;1516.8710659593949;1527.2134850551975;2266.1388672304583;2288.622377307742;11603.825942610867;11609.477573509212;11649.592264270794;11657.883421165765;11700.356480393575;4633.467151277646;4765.332853400458;4826.758662066953;4832.589229915427;4950.975245123751;10074.19406447497;10076.34503757268;10083.14293285079;10091.192704583616;10092.293539452316;5609;5687.402822703557;5688.9715551422805;5690.77236910519;5694.316681998187;4643.6517261205545;4942.654014218971;4953.2074868020945;5237.423883372826;5286.180642000496;4031.838726450968;4049.331860243086;4095.651869591118;4130.793460326971;4135.731177473454;1236.4065248680683;1251.6370252626637;1260.4832018872219;1273.719781648036;1275.4643049919057;976.5507866381402;1000.4797505297205;1008.0826447932346;1011.2769369900557;1015.0103648942222;5937.673427961504;6103.786367477206;6118.10165461209;6158.599691020069;6247.125402443661;608.4949491921694;612.1277180018456;629.5498718416354;645.5610185074195;649.7108786590017;3843.8270277837564;4205.684573656988;4261.808218630169;4273.914521709548;4296.162248361539;10960.12319014477;11261.66215013551;11321.150913681508;11323.207375483636;11347.546098156146;11061.446927653746;11142.220044596801;11172.553097876156;11180.944095279472;11194.496247668874;8400.873986890201;8449.873251901226;8472.449290796201;8607.397482176188;8613.526256055977;5830.5044071254415;5865.354810970704;5897.321807992136;5911.438413350725;5934.43622855828;9652.469114198786;9673.274504412057;9676.274279429212;9813.165880900204;9920.851187232052;9660.613575456902;9674.9032509674;9753.414795112316;10161.390316581028;10271.897281027093;6902.758403455855;6954.756583519553;6982.930170698228;7067.540609860343;7588.241175882367;8897.421667591585;8965.641374344954;8971.551422428984;8974.641014359353;8980.416272942499;8007;8068.11250762404;8111.9594402027615;8131.674733010614;8180.877286840583;7761.223877612194;7808.843823123392;7826.686932522635;7855.960720196363;8002.999625046869;6818.181818181652;6910.827229319308;6967.163940327193;6972.895406569007;7004.3696067354185;5103.540681338697;5202.557782588522;5400.810971615974;5440.700761458158;5463.508284254436;5282.683039017671;5298.523132918056;5383.2216160556545;5383.380911195262;5521.5306698931045 17:39:55 INFO - PROCESS | 21349 | |4;prototype.html;410.9745047262068;414.784312157678;430.0882129884645;430.7628174270357;431.5533422907292;5269.5520880725135;5339.41266460689;5371.623986320959;5400.216968539559;5430.67415955043;5033.320501732265;5262.158054711247;5307.920381194275;5311.203319502077;5321.286144066525;3987.6012398760035;4012.95663127586;4070.8982275443204;4085.6935729820234;4177.916441671165;4109.363048727452;4115.54728979812;4127.855525056623;4132.103457402972;4144.295469770132;4130.504339479252;4133.689973252003;4141.047559061418;4151.83392664293;4154.709170358076;3937.8227979740836;4010.7794071326066;4031.57668444812;4117.279476091702;4136.627703506684;3670.302642497917;3670.9632903671154;3672.6327367263325;3678.319510890467;3688.5204923359793;5157.252198431206;5176.7739871855465;5177.663451875616;5184.7148406837605;5208.869778255536;4695.741734204617;4824.662273640846;4839.153148199086;4851;4857.805687772485;5608.971955140239;5609.700022990554;5613.101903695389;5629.662220266756;5630.15547667849;4128.195001974616;4137.958620413788;4138.13099249158;4139.234241665302;4143.751374917515;5584.692841893694;5660.971695141498;5769.365369809359;5819.505342045931;5824.679642619654;4178.540360560336;4185.874423767292;4191.532963462824;4202.096549241927;4203.768792716369;4248.277792775236;4265.317549192177;4269.7549862105025;4271.679624028148;4277.588395829369;4614.723116613014;4782.976085119552;4791.227245919033;4803.159447096744;4831.73425461603;5618.52242559387;5623.409541998113;5637;5650.406707295757;5650.7174641267775;1416.8016477693131;1417.943631994171;1418.319206780751;1424.8174015567054;1425;934.9439033657866;942.3544871762864;985.7634167799677;987.4223579206244;998.4308943901906;5234.947650523446;5426.511613954763;5554.2779438672715;5576.693281869536;5582.134769110794;5742.6554406734895;5748;5748.827535173951;5755.618651523604;5775.768969241268;5410.458954104637;5481.260029895992;5520.392756796749;5564.081926482166;5565.443455654402;2904.941901161965;2913.572349548706;2936.765058795291;2951.2326795033446;2966.8813247470293;5822.417758224144;5826.592138550261;5830.970845145747;5833.4749872511675;5834.854128646818;5428.972855135699;5573.359063707644;5612.382637909826;5622.746976385991;5680.460356266148;5729.455701708331;5749.8275051748515;5770.989162532;5785.768569257182;5792.9710351447975;5476.370217424887;5557.194206840067;5586.692731899784;5587.898716212541;5760.567957403296;5704.486596206195;5705.4009329019855;5712.65724056558;5716.314042314949;5728.914066289092;6006.969965150146;6026.156338112755;6028.849278768066;6031.36670649593;6040.610659548416;4041.777702226406;4139.503259608746;4195.1819395217635;4200.306949353322;4204.684648651302;5707.743151558273;5750.769969201185;5777.9133313001175;5784.681842498535;5793.826185214451;5419.593530485151;5611.466910643646;5645.463680950304;5652.576056795675;5666.943330566642;5600.775968961196;5610.7755689773585;5614.915776263278;5616.185653080199;5621.494065534122;5588.804391846266;5607.943920560905;5630.071038278638;5633.2113504110275;5637.830865074055;5421;5530.944690553043;5539.224508568885;5665.603407761582;5669.858253543694;5532.280803495681;5543.584231182758;5545.584081193846;5546.528545073714;5563.193336966198 17:39:55 INFO - PROCESS | 21349 | |5;yui.html;843.4096132707107;843.6920524008739;863.6977058029694;905.6015353244572;925.2967744514169;15228.238588070593;15659.530214093567;15697.215139243035;15904.045757254573;16081.437149699748;15697.21513924305;15862.603434914134;15886;15893.920530397332;16251.91874040631;706.2055187913597;732.4030914804443;825.3562221467265;836.0260296754274;838.8028813228887;807.8626633472323;834.1450013735908;842.5618678287287;843.2326582809644;843.2874221283006;894.7628878347243;895.879056327394;896.3411892259185;897.1521911793325;897.4166791585457;730.0837449001522;755.3126654744157;889.5774507109169;890.4479222881822;890.514669505118;629.6033498895666;643.1092936283235;647.0876064748682;647.4690753582065;647.5790736021577;15334.463293784664;15570.221488925621;15575.766363504557;15578.9221053894;15599.766003509956;14915.552533424014;15229.162396068212;15492.45776397832;15742.055476671436;15811.92094039534;13704.52034178799;13712.65718357039;13857.792133118011;14004.649883752885;14010.649733756636;3957.7823219722904;4535.750533720677;4564.292534657134;4598.931016034729;4653.325267836179;68929.65535172292;80698.19301806965;81285.74857005713;81892.59053704693;82808.17191828066;11880.702982425508;11915.463804128834;11962.58130965412;12033.278003319829;12191.207571507824;22308.665370019462;23075.7692423077;23079.76920230776;23085.422864428525;23261.53476930452;8972.37193396469;9252.259819214447;9259.814803703888;9275.58259878307;9330.673426430178;4726.291056341507;4762.452317983476;4799.328094066834;4849.199882019428;4898.118338699069;3811.2187001664593;3864.3237433449035;3874.728768986144;3890.455336252927;3926.312895243321;661.6063442251852;669.7354544954716;675.4191395399952;679.3647939176885;685.807973767346;849.46908182386;865.4158443050916;869;879.6041781198359;888.3781353052759;10877.728056798644;11122.777544449065;11133.220674552704;11137.498812553455;11366.659000230007;847.855864503036;854.3336197765752;872.5419154943705;874.0953113527469;880.9427387219811;5425.484578964992;5548.528375088082;5551.018792099106;5597.160425936061;5620.4660557247;83642.58178709068;84881;85103;85196.57401712952;87206;72539;72939;73969.63015184889;75182.24817751862;75744.62127689326;64147;66058;66281.33718662849;67236.32763672301;68742.65628671924;10430.16558675289;10730.517126729159;10786.838197427202;10875.293105948089;10936.343819370863;45542;45996.770016149705;46441;46727.53272467232;47117.29324060211;56024.439755601925;56282.43717562772;56291.71854140703;57023.71488142699;58364.4163558359;12602.36988150607;12849.807252891402;13129.474821007052;13138.80291795643;13378.799318010042;14866.85133148698;14910.25448727581;14972.176530290939;15021.324040418425;15030.17334046638;13041.673958151123;14545.200013999332;14744.262786860829;14796.186209758567;15088.321025553656;14898.925505372405;15152.696946061238;15266.160361179798;15362.462313818962;15821.288042038366;14385.88802454255;14443.638909027359;14474.85525144735;15046.849531504546;15157.469488567847;11355.716107097389;11829.467673954521;12116.939415302868;12138.089643276613;12205.023598111951;12680.556180533637;12774.435191457087;12806.35968201605;12945.870541294467;13037.348132593523 17:39:55 INFO - PROCESS | 21349 | __end_tp_report 17:39:55 INFO - PROCESS | 21349 | __start_cc_report 17:39:55 INFO - PROCESS | 21349 | _x_x_mozilla_cycle_collect,969 17:39:55 INFO - PROCESS | 21349 | __end_cc_report 17:39:55 INFO - PROCESS | 21349 | __startTimestamp1486604395700__endTimestamp 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | ------- Summary: start ------- 17:39:55 INFO - PROCESS | 21349 | Number of tests: 6 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#0] dojo.html Cycles:165 Average:5359.39 Median:5624.69 stddev:606.21 (10.8%) stddev-sans-first:584.04 17:39:55 INFO - PROCESS | 21349 | Values: 3205.3 3226.8 3236.3 3247.3 3279.0 5315.5 5320.8 5326.9 5333.1 5354.1 5335.1 5358.5 5361.8 5362.4 5372.2 4762.6 4799.7 4805.7 4840.0 4849.8 4763.8 4777.3 4813.7 4878.3 4902.6 4716.0 4721.7 4736.6 4775.5 4816.1 4823.4 4825.5 4830.0 4833.7 4869.6 4753.8 4758.6 4790.9 4793.2 4811.2 5310.1 5317.7 5357.1 5366.6 5429.2 5798.1 5800.7 5800.8 5811.1 5834.2 5775.7 5800.6 5840.9 5854.0 5856.1 5310.9 5335.7 5342.9 5346.7 5462.1 5823.9 5850.4 5861.0 5863.0 5898.7 5662.7 5662.8 5663.3 5666.3 5672.1 5647.7 5658.7 5715.9 5750.0 5756.4 5663.0 5677.9 5727.8 5760.1 5764.6 5714.7 5716.6 5723.7 5726.2 5727.8 4374.1 4397.9 4471.6 4485.0 4506.2 4000.0 4016.9 4048.0 4049.5 4062.7 5619.3 5623.4 5624.0 5624.7 5637.0 5653.1 5709.2 5720.9 5864.6 5872.0 5573.6 5616.9 5619.9 5636.2 5640.5 4940.0 4943.8 4949.6 4949.9 4978.9 5914.8 5933.7 6033.9 6049.0 6049.5 5721.9 5735.9 5772.2 5779.2 5890.5 6071.5 6093.1 6094.2 6104.4 6108.0 5732.9 5733.8 5738.7 5741.5 5762.9 5641.0 5651.7 5698.5 5733.5 5746.7 5608.9 5637.4 5639.5 5642.9 5669.2 5640.1 5641.0 5643.6 5644.7 5647.1 5517.1 5556.4 5582.7 5606.2 5610.9 5703.3 5712.0 5712.7 5713.3 5742.3 5532.0 5578.4 5587.1 5596.7 5600.3 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#1] ext.html Cycles:180 Average:60808.72 Median:37885.03 stddev:115115.31 (303.9%) stddev-sans-first:115349.31 17:39:55 INFO - PROCESS | 21349 | Values: 544.7 575.1 614.9 622.3 652.7 36526.1 47735.5 49185.8 49281.0 49344.5 51620.0 52211.5 52212.2 52226.7 52248.7 8633.2 8776.9 9422.6 9617.2 9619.5 11047.5 11317.3 11322.3 11647.9 11651.6 11310.9 11345.1 11380.9 11396.5 11403.9 11434.3 11463.1 11486.1 11512.9 11516.0 11073.9 11122.3 11146.4 11148.2 11152.9 47589.5 47913.0 48088.3 48098.0 48116.0 4573.0 4625.7 4696.7 4748.5 4792.0 35814.3 35839.5 36553.5 36875.0 37269.6 4666.6 4669.4 4746.4 4758.4 4995.8 358074.0 358806.0 359137.0 359709.2 359954.0 68766.0 69198.7 69282.7 69355.2 69374.3 71739.3 71804.6 71916.0 72048.6 72083.6 19592.0 20226.5 20257.7 20335.8 20339.8 12878.0 13156.6 15282.5 15743.8 15795.1 18791.6 18871.1 18886.9 18887.2 18889.6 6944.5 7172.6 7228.2 7230.6 7307.9 982.3 989.8 1000.1 1008.0 1008.2 30737.2 31952.0 33581.2 33691.5 34037.8 1127.1 1138.3 1163.9 1168.5 1190.4 16240.4 16280.8 16548.0 16566.7 16786.2 516207.0 516428.0 516671.0 520875.0 520910.0 58294.4 58309.7 58580.7 58655.1 58757.0 1504.5 1539.7 1555.0 1575.8 1590.2 29308.4 30826.0 30883.8 30893.7 31110.0 51432.2 51933.0 52065.2 52120.7 52154.0 411984.0 414732.0 415490.0 415527.0 415718.0 24813.6 28008.7 28142.4 28254.7 29586.3 45736.5 45989.5 46006.0 46038.3 46106.3 38462.6 38629.6 38734.6 39921.0 39984.6 37612.4 37844.2 37925.8 38264.8 39192.8 38039.6 38062.8 38470.8 39979.8 39997.0 33596.0 35551.6 36275.6 36892.8 36896.3 27601.3 27618.2 27705.7 27794.6 27955.3 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#2] jquery.html Cycles:180 Average:35678.45 Median:2208.94 stddev:110690.22 (5011.0%) stddev-sans-first:110969.11 17:39:55 INFO - PROCESS | 21349 | Values: 443.0 443.2 445.6 455.3 461.5 21231.8 21503.4 21994.2 22052.2 22258.8 13786.3 14139.2 14197.4 14237.6 14500.6 5283.3 5546.5 5592.4 5676.6 5681.9 4308.4 4436.6 5663.2 5709.5 5710.4 6867.6 6890.7 6896.4 6911.7 6925.3 6236.5 6269.8 6275.8 6306.1 6935.5 6042.5 6158.4 6163.2 6163.9 6169.5 15671.6 15952.3 16028.4 16032.7 23826.0 3084.2 3160.8 3173.2 3266.7 3272.5 11211.6 11563.4 11576.5 11580.7 11581.8 1990.3 2057.3 2082.3 2111.0 2150.6 86993.6 97314.0 98527.5 100258.0 100841.0 12467.6 12716.7 12776.0 12882.6 13093.9 18566.4 18577.3 18579.9 18590.9 18650.3 9298.8 9313.7 9405.8 9433.1 9535.3 8922.2 8952.4 8987.2 9168.7 9185.7 5413.9 5424.4 5455.5 5534.5 5644.0 1932.1 2113.8 2208.3 2209.6 2265.8 951.2 961.1 972.3 988.2 1002.6 20872.9 21968.9 22039.2 22985.0 23558.3 1048.1 1048.2 1051.8 1063.3 1069.5 11220.4 11717.9 11818.9 11836.2 11848.5 397147.0 707493.0 717410.0 722406.0 745932.0 115334.5 115576.4 115846.0 116048.4 119323.0 1167.4 1176.4 1196.0 1209.3 1210.1 17787.6 18069.6 18072.7 18276.5 18522.9 47617.8 49606.3 50057.2 50765.5 50805.0 65826.0 68854.0 70432.6 70894.3 71784.6 9262.8 9280.2 9580.4 9702.1 9987.3 10083.6 13873.9 14323.4 14465.2 15286.2 11678.9 11812.9 12238.3 12531.4 12768.7 11734.7 11806.9 12174.5 12191.1 12230.9 12023.8 12089.3 12120.2 12125.0 12256.9 9348.7 9417.0 9557.5 9584.1 9622.5 8974.6 11491.1 11572.6 11705.9 11760.9 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#3] mootools.html Cycles:180 Average:5897.13 Median:5219.99 stddev:3232.78 (61.9%) stddev-sans-first:3224.95 17:39:55 INFO - PROCESS | 21349 | Values: 1499.8 1503.2 1511.1 1523.3 1530.9 8718.7 9214.1 9222.4 9224.2 9227.5 8015.8 8273.2 8308.3 8583.2 8960.3 2522.0 2557.1 2562.7 2563.2 2572.6 2474.7 2495.6 2528.7 2531.9 2536.0 2514.0 2515.0 2526.1 2531.1 2532.1 2436.6 2441.5 2461.6 2462.0 2466.9 2383.4 2440.0 2460.2 2484.9 2512.1 9063.6 9139.5 9139.6 9142.7 9167.4 5516.3 5705.8 5714.1 5715.1 5716.3 3483.6 3536.7 3547.3 3547.3 3606.6 1487.7 1516.9 1527.2 2266.1 2288.6 11603.8 11609.5 11649.6 11657.9 11700.4 4633.5 4765.3 4826.8 4832.6 4951.0 10074.2 10076.3 10083.1 10091.2 10092.3 5609.0 5687.4 5689.0 5690.8 5694.3 4643.7 4942.7 4953.2 5237.4 5286.2 4031.8 4049.3 4095.7 4130.8 4135.7 1236.4 1251.6 1260.5 1273.7 1275.5 976.6 1000.5 1008.1 1011.3 1015.0 5937.7 6103.8 6118.1 6158.6 6247.1 608.5 612.1 629.5 645.6 649.7 3843.8 4205.7 4261.8 4273.9 4296.2 10960.1 11261.7 11321.2 11323.2 11347.5 11061.4 11142.2 11172.6 11180.9 11194.5 8400.9 8449.9 8472.4 8607.4 8613.5 5830.5 5865.4 5897.3 5911.4 5934.4 9652.5 9673.3 9676.3 9813.2 9920.9 9660.6 9674.9 9753.4 10161.4 10271.9 6902.8 6954.8 6982.9 7067.5 7588.2 8897.4 8965.6 8971.6 8974.6 8980.4 8007.0 8068.1 8112.0 8131.7 8180.9 7761.2 7808.8 7826.7 7856.0 8003.0 6818.2 6910.8 6967.2 6972.9 7004.4 5103.5 5202.6 5400.8 5440.7 5463.5 5282.7 5298.5 5383.2 5383.4 5521.5 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#4] prototype.html Cycles:180 Average:4718.65 Median:5453.52 stddev:1375.53 (25.2%) stddev-sans-first:1340.86 17:39:55 INFO - PROCESS | 21349 | Values: 411.0 414.8 430.1 430.8 431.6 5269.6 5339.4 5371.6 5400.2 5430.7 5033.3 5262.2 5307.9 5311.2 5321.3 3987.6 4013.0 4070.9 4085.7 4177.9 4109.4 4115.5 4127.9 4132.1 4144.3 4130.5 4133.7 4141.0 4151.8 4154.7 3937.8 4010.8 4031.6 4117.3 4136.6 3670.3 3671.0 3672.6 3678.3 3688.5 5157.3 5176.8 5177.7 5184.7 5208.9 4695.7 4824.7 4839.2 4851.0 4857.8 5609.0 5609.7 5613.1 5629.7 5630.2 4128.2 4138.0 4138.1 4139.2 4143.8 5584.7 5661.0 5769.4 5819.5 5824.7 4178.5 4185.9 4191.5 4202.1 4203.8 4248.3 4265.3 4269.8 4271.7 4277.6 4614.7 4783.0 4791.2 4803.2 4831.7 5618.5 5623.4 5637.0 5650.4 5650.7 1416.8 1417.9 1418.3 1424.8 1425.0 934.9 942.4 985.8 987.4 998.4 5234.9 5426.5 5554.3 5576.7 5582.1 5742.7 5748.0 5748.8 5755.6 5775.8 5410.5 5481.3 5520.4 5564.1 5565.4 2904.9 2913.6 2936.8 2951.2 2966.9 5822.4 5826.6 5831.0 5833.5 5834.9 5429.0 5573.4 5612.4 5622.7 5680.5 5729.5 5749.8 5771.0 5785.8 5793.0 5476.4 5557.2 5586.7 5587.9 5760.6 5704.5 5705.4 5712.7 5716.3 5728.9 6007.0 6026.2 6028.8 6031.4 6040.6 4041.8 4139.5 4195.2 4200.3 4204.7 5707.7 5750.8 5777.9 5784.7 5793.8 5419.6 5611.5 5645.5 5652.6 5666.9 5600.8 5610.8 5614.9 5616.2 5621.5 5588.8 5607.9 5630.1 5633.2 5637.8 5421.0 5530.9 5539.2 5665.6 5669.9 5532.3 5543.6 5545.6 5546.5 5563.2 17:39:55 INFO - PROCESS | 21349 | 17:39:55 INFO - PROCESS | 21349 | [#5] yui.html Cycles:180 Average:18715.27 Median:46218.89 stddev:23547.78 (50.9%) stddev-sans-first:23575.60 17:39:55 INFO - PROCESS | 21349 | Values: 843.4 843.7 863.7 905.6 925.3 15228.2 15659.5 15697.2 15904.0 16081.4 15697.2 15862.6 15886.0 15893.9 16251.9 706.2 732.4 825.4 836.0 838.8 807.9 834.1 842.6 843.2 843.3 894.8 895.9 896.3 897.2 897.4 730.1 755.3 889.6 890.4 890.5 629.6 643.1 647.1 647.5 647.6 15334.5 15570.2 15575.8 15578.9 15599.8 14915.6 15229.2 15492.5 15742.1 15811.9 13704.5 13712.7 13857.8 14004.6 14010.6 3957.8 4535.8 4564.3 4598.9 4653.3 68929.7 80698.2 81285.7 81892.6 82808.2 11880.7 11915.5 11962.6 12033.3 12191.2 22308.7 23075.8 23079.8 23085.4 23261.5 8972.4 9252.3 9259.8 9275.6 9330.7 4726.3 4762.5 4799.3 4849.2 4898.1 3811.2 3864.3 3874.7 3890.5 3926.3 661.6 669.7 675.4 679.4 685.8 849.5 865.4 869.0 879.6 888.4 10877.7 11122.8 11133.2 11137.5 11366.7 847.9 854.3 872.5 874.1 880.9 5425.5 5548.5 5551.0 5597.2 5620.5 83642.6 84881.0 85103.0 85196.6 87206.0 72539.0 72939.0 73969.6 75182.2 75744.6 64147.0 66058.0 66281.3 67236.3 68742.7 10430.2 10730.5 10786.8 10875.3 10936.3 45542.0 45996.8 46441.0 46727.5 47117.3 56024.4 56282.4 56291.7 57023.7 58364.4 12602.4 12849.8 13129.5 13138.8 13378.8 14866.9 14910.3 14972.2 15021.3 15030.2 13041.7 14545.2 14744.3 14796.2 15088.3 14898.9 15152.7 15266.2 15362.5 15821.3 14385.9 14443.6 14474.9 15046.8 15157.5 11355.7 11829.5 12116.9 12138.1 12205.0 12680.6 12774.4 12806.4 12945.9 13037.3 17:39:55 INFO - PROCESS | 21349 | -------- Summary: end -------- 17:39:55 INFO - PROCESS | 21349 | 17:39:56 INFO - TEST-INFO | 21349: exit 0 17:39:56 INFO - TEST-OK | dromaeo_css | took 1113729ms 17:39:56 INFO - TEST-START | kraken 17:39:56 INFO - operating with platform_type : linux_ 17:39:56 INFO - Initialising browser for kraken test... 17:39:56 INFO - TEST-INFO | started process 21667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMXhoVJ/profile http://localhost:41799/getInfo.html) 17:40:00 INFO - PROCESS | 21667 | __metrics Screen width/height:1600/1200 17:40:00 INFO - PROCESS | 21667 | colorDepth:24 17:40:00 INFO - PROCESS | 21667 | Browser inner width/height: 1024/697 17:40:00 INFO - PROCESS | 21667 | __metrics 17:40:01 INFO - PROCESS | 21667 | 1486604401225 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:40:01 INFO - PROCESS | 21667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:40:01 INFO - PROCESS | 21667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:40:01 INFO - PROCESS | 21667 | console.error: PushService: 17:40:01 INFO - PROCESS | 21667 | stateChangeProcessEnqueue: Error transitioning state 17:40:01 INFO - PROCESS | 21667 | UnknownError 17:40:02 INFO - TEST-INFO | 21667: exit 0 17:40:02 INFO - Browser initialized. 17:40:02 INFO - Running cycle 1/1 for kraken test... 17:40:02 INFO - TEST-INFO | started process 21759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMXhoVJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:14 INFO - PROCESS | 21759 | RSS: Main: 141844480 17:40:14 INFO - PROCESS | 21759 | 17:40:16 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41799/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:17 INFO - PROCESS | 21759 | RSS: Main: 185581568 17:40:17 INFO - PROCESS | 21759 | 17:40:20 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41799/tests/kraken/driver.html?testName=audio-dft) 17:40:20 INFO - PROCESS | 21759 | RSS: Main: 175575040 17:40:20 INFO - PROCESS | 21759 | 17:40:23 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41799/tests/kraken/driver.html?testName=audio-fft) 17:40:24 INFO - PROCESS | 21759 | RSS: Main: 267313152 17:40:24 INFO - PROCESS | 21759 | 17:40:26 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41799/tests/kraken/driver.html?testName=audio-oscillator) 17:40:26 INFO - PROCESS | 21759 | RSS: Main: 173531136 17:40:26 INFO - PROCESS | 21759 | 17:40:28 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41799/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:29 INFO - PROCESS | 21759 | RSS: Main: 175681536 17:40:29 INFO - PROCESS | 21759 | 17:40:32 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41799/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:32 INFO - PROCESS | 21759 | RSS: Main: 214736896 17:40:32 INFO - PROCESS | 21759 | 17:40:36 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41799/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:36 INFO - PROCESS | 21759 | RSS: Main: 213037056 17:40:36 INFO - PROCESS | 21759 | 17:40:39 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41799/tests/kraken/driver.html?testName=json-parse-financial) 17:40:40 INFO - PROCESS | 21759 | RSS: Main: 211738624 17:40:40 INFO - PROCESS | 21759 | 17:40:41 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41799/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:42 INFO - PROCESS | 21759 | RSS: Main: 174776320 17:40:42 INFO - PROCESS | 21759 | 17:40:44 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:44 INFO - PROCESS | 21759 | RSS: Main: 181264384 17:40:44 INFO - PROCESS | 21759 | 17:40:46 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:47 INFO - PROCESS | 21759 | RSS: Main: 187842560 17:40:47 INFO - PROCESS | 21759 | 17:40:49 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:50 INFO - PROCESS | 21759 | RSS: Main: 197672960 17:40:50 INFO - PROCESS | 21759 | 17:40:53 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:40:53 INFO - PROCESS | 21759 | RSS: Main: 193142784 17:40:53 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:41799/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41799/tests/kraken/driver.html?testName=ai-astar) 17:40:55 INFO - PROCESS | 21759 | RSS: Main: 244477952 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | __start_tp_report 17:40:55 INFO - PROCESS | 21759 | _x_x_mozilla_page_load 17:40:55 INFO - PROCESS | 21759 | _x_x_mozilla_page_load_details 17:40:55 INFO - PROCESS | 21759 | |i|pagename|runs| 17:40:55 INFO - PROCESS | 21759 | |0;ai-astar;111;111;112;112;113;112;110;112;111;111 17:40:55 INFO - PROCESS | 21759 | |1;audio-beat-detection;151;115;126;156;117;120;116;116;134;118 17:40:55 INFO - PROCESS | 21759 | |2;audio-dft;161;160;162;160;162;160;160;163;160;160 17:40:55 INFO - PROCESS | 21759 | |3;audio-fft;74;76;77;111;75;74;75;75;89;75 17:40:55 INFO - PROCESS | 21759 | |4;audio-oscillator;84;84;105;84;84;84;99;84;85;109 17:40:55 INFO - PROCESS | 21759 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;116;117;116 17:40:55 INFO - PROCESS | 21759 | |6;imaging-darkroom;148;146;146;147;149;147;146;146;146;146 17:40:55 INFO - PROCESS | 21759 | |7;imaging-desaturate;96;95;96;96;96;96;96;96;95;95 17:40:55 INFO - PROCESS | 21759 | |8;json-parse-financial;48;48;49;48;49;50;51;49;51;49 17:40:55 INFO - PROCESS | 21759 | |9;json-stringify-tinderbox;63;64;63;63;64;62;63;63;63;64 17:40:55 INFO - PROCESS | 21759 | |10;stanford-crypto-aes;78;83;76;77;81;76;83;77;74;78 17:40:55 INFO - PROCESS | 21759 | |11;stanford-crypto-ccm;121;130;131;122;131;120;120;122;162;133 17:40:55 INFO - PROCESS | 21759 | |12;stanford-crypto-pbkdf2;155;156;155;159;160;162;155;154;155;157 17:40:55 INFO - PROCESS | 21759 | |13;stanford-crypto-sha256-iterative;65;59;61;59;59;59;59;59;59;59 17:40:55 INFO - PROCESS | 21759 | __end_tp_report 17:40:55 INFO - PROCESS | 21759 | __start_cc_report 17:40:55 INFO - PROCESS | 21759 | _x_x_mozilla_cycle_collect,1567 17:40:55 INFO - PROCESS | 21759 | __end_cc_report 17:40:55 INFO - PROCESS | 21759 | __startTimestamp1486604455621__endTimestamp 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | ------- Summary: start ------- 17:40:55 INFO - PROCESS | 21759 | Number of tests: 14 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:0.85 (0.8%) stddev-sans-first:0.88 17:40:55 INFO - PROCESS | 21759 | Values: 111.0 111.0 112.0 112.0 113.0 112.0 110.0 112.0 111.0 111.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:123.00 stddev:15.21 (12.4%) stddev-sans-first:13.41 17:40:55 INFO - PROCESS | 21759 | Values: 151.0 115.0 126.0 156.0 117.0 120.0 116.0 116.0 134.0 118.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#2] audio-dft Cycles:10 Average:160.80 Median:160.50 stddev:1.14 (0.7%) stddev-sans-first:1.20 17:40:55 INFO - PROCESS | 21759 | Values: 161.0 160.0 162.0 160.0 162.0 160.0 160.0 163.0 160.0 160.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.00 stddev:11.73 (15.6%) stddev-sans-first:12.23 17:40:55 INFO - PROCESS | 21759 | Values: 74.0 76.0 77.0 111.0 75.0 74.0 75.0 75.0 89.0 75.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:84.00 stddev:10.04 (12.0%) stddev-sans-first:10.40 17:40:55 INFO - PROCESS | 21759 | Values: 84.0 84.0 105.0 84.0 84.0 84.0 99.0 84.0 85.0 109.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:40:55 INFO - PROCESS | 21759 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.50 stddev:1.06 (0.7%) stddev-sans-first:1.01 17:40:55 INFO - PROCESS | 21759 | Values: 148.0 146.0 146.0 147.0 149.0 147.0 146.0 146.0 146.0 146.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:40:55 INFO - PROCESS | 21759 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:1.14 (2.3%) stddev-sans-first:1.12 17:40:55 INFO - PROCESS | 21759 | Values: 48.0 48.0 49.0 48.0 49.0 50.0 51.0 49.0 51.0 49.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 17:40:55 INFO - PROCESS | 21759 | Values: 63.0 64.0 63.0 63.0 64.0 62.0 63.0 63.0 63.0 64.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:3.06 (3.9%) stddev-sans-first:3.24 17:40:55 INFO - PROCESS | 21759 | Values: 78.0 83.0 76.0 77.0 81.0 76.0 83.0 77.0 74.0 78.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#11] stanford-crypto-ccm Cycles:10 Average:129.20 Median:130.50 stddev:12.64 (9.7%) stddev-sans-first:13.05 17:40:55 INFO - PROCESS | 21759 | Values: 121.0 130.0 131.0 122.0 131.0 120.0 120.0 122.0 162.0 133.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.80 Median:156.50 stddev:2.66 (1.7%) stddev-sans-first:2.74 17:40:55 INFO - PROCESS | 21759 | Values: 155.0 156.0 155.0 159.0 160.0 162.0 155.0 154.0 155.0 157.0 17:40:55 INFO - PROCESS | 21759 | 17:40:55 INFO - PROCESS | 21759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:59.00 stddev:1.93 (3.3%) stddev-sans-first:0.67 17:40:55 INFO - PROCESS | 21759 | Values: 65.0 59.0 61.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 17:40:55 INFO - PROCESS | 21759 | -------- Summary: end -------- 17:40:55 INFO - PROCESS | 21759 | 17:40:56 INFO - TEST-INFO | 21759: exit 0 17:40:56 INFO - TEST-OK | kraken | took 60235ms 17:40:56 INFO - SUITE-END | took 1173s 17:40:56 INFO - Completed test suite (00:19:34) 17:40:56 INFO - javascript benchmark 17:40:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.2627895584014, 3226.822524761137, 3236.2556611979244, 3247.3180632067265, 3278.9999050289653, 5315.521603055724, 5320.840374788758, 5326.92009619856, 5333.146696528561, 5354.089804733195, 5335.0663633864015, 5358.4641535846495, 5361.7587208575615, 5362.383325917521, 5372.194170874371, 4762.5951794097455, 4799.688020278688, 4805.711657300556, 4839.975800120995, 4849.7817598207985, 4763.833265835697, 4777.28340748887, 4813.687110337834, 4878.317035615017, 4902.632302577304, 4716.0, 4721.669483136182, 4736.644751643623, 4775.4985726498735, 4816.133096042711, 4823.372961514998, 4825.4933232010835, 4829.951700482985, 4833.685810422311, 4869.634777391674, 4753.786079626425, 4758.619310455155, 4790.880227994293, 4793.18515852306, 4811.206150985084, 5310.070737620901, 5317.73411329432, 5357.116075847481, 5366.570674346043, 5429.158480435539, 5798.130280457923, 5800.651960882361, 5800.7679692812235, 5811.128330750421, 5834.154047663107, 5775.682337471438, 5800.622959507618, 5840.941590584082, 5854.0, 5856.063029915235, 5310.867228319323, 5335.733213339317, 5342.866428339284, 5346.705931173745, 5462.126059830448, 5823.88352232953, 5850.444207800252, 5860.970695146497, 5863.0325996211, 5898.734556944948, 5662.716864156776, 5662.801801936913, 5663.292088488939, 5666.34836993751, 5672.12082127271, 5647.689377084218, 5658.688772117491, 5715.942840571624, 5750.022496175577, 5756.42435756421, 5662.971685141548, 5677.943220567824, 5727.770889164387, 5760.135979603009, 5764.5676574257095, 5714.657120572779, 5716.5998380113, 5723.685197314105, 5726.198332233491, 5727.77088916447, 4374.147041326911, 4397.890052748643, 4471.575200355961, 4485.0, 4506.1663592235545, 4000.0399904023407, 4016.9397459038137, 4048.0, 4049.493813273341, 4062.6952978526137, 5619.325680918316, 5623.437656234344, 5624.0439125348785, 5624.690642014647, 5637.0, 5653.067243904792, 5709.200711900337, 5720.914186287209, 5864.560157988082, 5872.0, 5573.6098473106495, 5616.859578510488, 5619.943800562024, 5636.154576813429, 5640.492355688008, 4939.975300123477, 4943.777530011158, 4949.57928576075, 4949.876253093701, 4978.850634480971, 5914.822555323347, 5933.703314834327, 6033.909491357633, 6048.969755151196, 6049.5462840287155, 5721.856953576027, 5735.881503106854, 5772.163036359789, 5779.219805326227, 5890.4993075589055, 6071.453569178795, 6093.086037094471, 6094.2077529920825, 6104.38956104386, 6108.0, 5732.942670573408, 5733.770649173986, 5738.68437235956, 5741.540676745767, 5762.855928601651, 5641.041023026013, 5651.660900345993, 5698.487136157766, 5733.541316694738, 5746.655200687972, 5608.85977850557, 5637.351704553865, 5639.548836093185, 5642.85892852682, 5669.234653321913, 5640.097584386478, 5640.971795140998, 5643.633163844337, 5644.689542075225, 5647.124695672177, 5517.089680202722, 5556.416576259435, 5582.748776305158, 5606.159076138449, 5610.85972850682, 5703.344115426779, 5712.02895507773, 5712.685802280915, 5713.342965558848, 5742.339630942495, 5532.0, 5578.3584887738425, 5587.106063029895, 5596.692181930033, 5600.27196464457], "value": 5319.587751514527, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.6941877259724, 575.0827430248753, 614.9446549810517, 622.2781573374883, 652.6964961293004, 36526.08684782882, 47735.522644773584, 49185.75407122968, 49281.0, 49344.51966441008, 51620.0, 52211.47788522114, 52212.21681674778, 52226.73886630571, 52248.73875630626, 8633.179847914438, 8776.946766388042, 9422.62309507619, 9617.23062155026, 9619.519024048805, 11047.502862371173, 11317.264377815456, 11322.320660760339, 11647.941760291187, 11651.592194273202, 11310.943445282763, 11345.09239260857, 11380.886191138065, 11396.544138234462, 11403.942980285086, 11434.313941163557, 11463.082953363793, 11486.13853960948, 11512.94243528777, 11516.0, 11073.944630276797, 11122.332660040423, 11146.442677866075, 11148.21962462628, 11152.888471115266, 47589.52410475886, 47913.0, 48088.27867581989, 48098.0, 48116.0, 4572.977135114304, 4625.745583992879, 4696.741679207642, 4748.548887855648, 4791.993681326926, 35814.28371432583, 35839.4624080639, 36553.45169822455, 36875.0, 37269.627303726884, 4666.556677115668, 4669.369635099252, 4746.406699162605, 4758.405199350082, 4995.825146119904, 358074.0, 358806.0, 359137.0, 359709.2014539937, 359954.0, 68766.0, 69198.65400672964, 69282.65358673174, 69355.2257909688, 69374.3062569378, 71739.2826071743, 71804.64097679478, 71916.0, 72048.63975680088, 72083.63958180281, 19592.0, 20226.494337641678, 20257.696134557995, 20335.796642033685, 20339.79660203379, 12878.0, 13156.60530184096, 15282.465113720966, 15743.763843542356, 15795.131267780382, 18791.624167516573, 18871.05644717786, 18886.905565472083, 18887.244510219713, 18889.622207556047, 6944.4791640626145, 7172.605506697182, 7228.192951762059, 7230.638468076575, 7307.926920730831, 982.3221976835961, 989.8020395920701, 1000.1248907206194, 1008.037324355239, 1008.1884083312857, 30737.2315692105, 31952.0, 33581.16047098793, 33691.49462758061, 34037.82981085128, 1127.0757978457586, 1138.3113216504062, 1163.9243449175776, 1168.456667649547, 1190.4345435918042, 16240.431584894708, 16280.837191628169, 16548.0, 16566.6686666266, 16786.160691965353, 516207.0, 516428.0, 516671.0, 520875.0, 520910.0, 58294.4170558289, 58309.70845145832, 58580.70709646425, 58655.120173197436, 58757.0, 1504.4508754304818, 1539.692061587687, 1554.9922250388677, 1575.842415758415, 1590.1731099828025, 29308.41383172282, 30826.0, 30883.84558077195, 30893.69106308908, 31110.0, 51432.22851657153, 51932.96134077373, 52065.219021715464, 52120.73939630278, 52154.0, 411984.0, 414732.0, 415490.0, 415527.0, 415718.0, 24813.62779558272, 28008.719912801425, 28142.43715125645, 28254.717452825207, 29586.260343491584, 45736.54263457323, 45989.540104599866, 46006.0, 46038.30942535798, 46106.308405373275, 38462.6153738459, 38629.613703863724, 38734.612653873104, 39921.0, 39984.600153998086, 37612.435813462274, 37844.243115138095, 37925.81037094797, 38264.80867595644, 39192.80403598078, 38039.61960380361, 38062.80968595139, 38470.80764596159, 39979.80010099931, 39997.0, 33596.0, 35551.64448355483, 36275.63724362828, 36892.81553592215, 36896.26207475889, 27601.309967250978, 27618.171454856387, 27705.722942770313, 27794.583081253393, 27955.301117471412], "value": 21471.7324983768, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.96234820040297, 443.2332065526639, 445.6390323837692, 455.3238440915241, 461.50849345447097, 21231.787682123173, 21503.354899353046, 21994.230201942933, 22052.228172013984, 22258.777412225874, 13786.310684465787, 14139.222342771143, 14197.432102715904, 14237.644058898533, 14500.564983050499, 5283.339582552181, 5546.528545073674, 5592.384837667853, 5676.631018983773, 5681.8579535511735, 4308.358354863482, 4436.622887054588, 5663.1505274208785, 5709.514691251248, 5710.428957104277, 6867.553609015423, 6890.724371025153, 6896.448284137282, 6911.688973996157, 6925.34209250123, 6236.469900058501, 6269.780557680505, 6275.843103922392, 6306.054091886231, 6935.514513984023, 6042.456178943872, 6158.414950579688, 6163.167972323724, 6163.907541386883, 6169.506439484875, 15671.608209794733, 15952.2821473034, 16028.439004634782, 16032.679346413006, 23826.0, 3084.228942764309, 3160.8103513789247, 3173.2384227785165, 3266.7386609071455, 3272.4927636216426, 11211.551537938472, 11563.42182890852, 11576.53693852245, 11580.652580422517, 11581.768364632744, 1990.2735501541977, 2057.2799520167973, 2082.344061620585, 2110.9894450527804, 2150.5698860227862, 86993.56503217507, 97314.0, 98527.50736246344, 100258.0, 100841.0, 12467.563635272903, 12716.745665086646, 12776.041796865087, 12882.613521594367, 13093.934530327288, 18566.350177743716, 18577.25690972373, 18579.90710046441, 18590.907045464955, 18650.253989840254, 9298.814023719488, 9313.674021409353, 9405.764855878659, 9433.103855133751, 9535.284853635867, 8922.152395522415, 8952.418092823946, 8987.236084932854, 9168.679096231599, 9185.678501252558, 5413.918791218135, 5424.430434804368, 5455.481729235717, 5534.501894829485, 5644.0, 1932.130785783111, 2113.8097571218664, 2208.3043841189974, 2209.5691340188832, 2265.762094980036, 951.2152474208806, 961.1061712607342, 972.3096601412934, 988.2242439684813, 1002.5939494504737, 20872.89563552203, 21968.90155492219, 22039.228626997978, 22985.0, 23558.293251202493, 1048.1038711901215, 1048.1562342314357, 1051.7702470762033, 1063.2982231727024, 1069.4973362519604, 11220.382878941737, 11717.88282117185, 11818.881811181778, 11836.171467997157, 11848.526058957545, 397147.0, 707493.0, 717410.0, 722406.0, 745932.0, 115334.53510719114, 115576.42211789056, 115846.0, 116048.41975790236, 119323.0, 1167.3987896233446, 1176.370811236885, 1195.9581414650445, 1209.3469526455792, 1210.116614871149, 17787.644247115244, 18069.638607228044, 18072.728909066638, 18276.543086422946, 18522.907385462986, 47617.76191119161, 49606.25590616216, 50057.24914126218, 50765.492345076076, 50805.0, 65826.0, 68854.0, 70432.64783676049, 70894.29105709083, 71784.64107679429, 9262.768430789014, 9280.211182049332, 9580.425174489554, 9702.126808587262, 9987.25095617817, 10083.596656133966, 13873.930630346784, 14323.355449004612, 14465.204413757345, 15286.235688215767, 11678.88321116778, 11812.940935295268, 12238.286639870603, 12531.373431328579, 12768.6807829805, 11734.706632333919, 11806.881931180922, 12174.513019479122, 12191.085668574715, 12230.938845305716, 12023.819642705192, 12089.274643521418, 12120.151589388659, 12125.029997599995, 12256.938715306367, 9348.672796452092, 9417.0, 9557.474338911426, 9584.089511496397, 9622.51887405641, 8974.641014359353, 11491.080713543064, 11572.59495917639, 11705.941470292593, 11760.88239117598], "value": 10064.28204962221, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.7675360319154, 1503.1732547099093, 1511.1159971416728, 1523.3449616664834, 1530.9234538273083, 8718.651253949842, 9214.078592140782, 9222.400543964646, 9224.169824715775, 9227.538623068853, 8015.839683206347, 8273.214044665747, 8308.252257296843, 8583.18459746323, 8960.327975401855, 2521.9747802522015, 2557.1305756042943, 2562.6668533090674, 2563.1797824696105, 2572.614107883823, 2474.7277799442045, 2495.6131799571094, 2528.671272734542, 2531.9240422787348, 2536.0, 2513.987430062856, 2514.9940023990366, 2526.1158594491963, 2531.0888080291174, 2532.0757923357933, 2436.6466862304956, 2441.5361081394476, 2461.5569197544437, 2461.987690061538, 2466.8889899954456, 2383.4041489627593, 2440.023990403835, 2460.1635443949144, 2484.91302804401, 2512.120757734778, 9063.592138353724, 9139.497327646977, 9139.634414623408, 9142.725718228463, 9167.358284920057, 5516.310461192351, 5705.828825135252, 5714.114312281603, 5715.114157305624, 5716.256886604715, 3483.5993860705835, 3536.6993805526563, 3547.255076433926, 3547.3260080584605, 3606.6032736398975, 1487.7024595081027, 1516.8710659593949, 1527.2134850551975, 2266.1388672304583, 2288.622377307742, 11603.825942610867, 11609.477573509212, 11649.592264270794, 11657.883421165765, 11700.356480393575, 4633.467151277646, 4765.332853400458, 4826.758662066953, 4832.589229915427, 4950.975245123751, 10074.19406447497, 10076.34503757268, 10083.14293285079, 10091.192704583616, 10092.293539452316, 5609.0, 5687.402822703557, 5688.9715551422805, 5690.77236910519, 5694.316681998187, 4643.6517261205545, 4942.654014218971, 4953.2074868020945, 5237.423883372826, 5286.180642000496, 4031.838726450968, 4049.331860243086, 4095.651869591118, 4130.793460326971, 4135.731177473454, 1236.4065248680683, 1251.6370252626637, 1260.4832018872219, 1273.719781648036, 1275.4643049919057, 976.5507866381402, 1000.4797505297205, 1008.0826447932346, 1011.2769369900557, 1015.0103648942222, 5937.673427961504, 6103.786367477206, 6118.10165461209, 6158.599691020069, 6247.125402443661, 608.4949491921694, 612.1277180018456, 629.5498718416354, 645.5610185074195, 649.7108786590017, 3843.8270277837564, 4205.684573656988, 4261.808218630169, 4273.914521709548, 4296.162248361539, 10960.12319014477, 11261.66215013551, 11321.150913681508, 11323.207375483636, 11347.546098156146, 11061.446927653746, 11142.220044596801, 11172.553097876156, 11180.944095279472, 11194.496247668874, 8400.873986890201, 8449.873251901226, 8472.449290796201, 8607.397482176188, 8613.526256055977, 5830.5044071254415, 5865.354810970704, 5897.321807992136, 5911.438413350725, 5934.43622855828, 9652.469114198786, 9673.274504412057, 9676.274279429212, 9813.165880900204, 9920.851187232052, 9660.613575456902, 9674.9032509674, 9753.414795112316, 10161.390316581028, 10271.897281027093, 6902.758403455855, 6954.756583519553, 6982.930170698228, 7067.540609860343, 7588.241175882367, 8897.421667591585, 8965.641374344954, 8971.551422428984, 8974.641014359353, 8980.416272942499, 8007.0, 8068.11250762404, 8111.9594402027615, 8131.674733010614, 8180.877286840583, 7761.223877612194, 7808.843823123392, 7826.686932522635, 7855.960720196363, 8002.999625046869, 6818.181818181652, 6910.827229319308, 6967.163940327193, 6972.895406569007, 7004.3696067354185, 5103.540681338697, 5202.557782588522, 5400.810971615974, 5440.700761458158, 5463.508284254436, 5282.683039017671, 5298.523132918056, 5383.2216160556545, 5383.380911195262, 5521.5306698931045], "value": 4759.075310866245, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.9745047262068, 414.784312157678, 430.0882129884645, 430.7628174270357, 431.5533422907292, 5269.5520880725135, 5339.41266460689, 5371.623986320959, 5400.216968539559, 5430.67415955043, 5033.320501732265, 5262.158054711247, 5307.920381194275, 5311.203319502077, 5321.286144066525, 3987.6012398760035, 4012.95663127586, 4070.8982275443204, 4085.6935729820234, 4177.916441671165, 4109.363048727452, 4115.54728979812, 4127.855525056623, 4132.103457402972, 4144.295469770132, 4130.504339479252, 4133.689973252003, 4141.047559061418, 4151.83392664293, 4154.709170358076, 3937.8227979740836, 4010.7794071326066, 4031.57668444812, 4117.279476091702, 4136.627703506684, 3670.302642497917, 3670.9632903671154, 3672.6327367263325, 3678.319510890467, 3688.5204923359793, 5157.252198431206, 5176.7739871855465, 5177.663451875616, 5184.7148406837605, 5208.869778255536, 4695.741734204617, 4824.662273640846, 4839.153148199086, 4851.0, 4857.805687772485, 5608.971955140239, 5609.700022990554, 5613.101903695389, 5629.662220266756, 5630.15547667849, 4128.195001974616, 4137.958620413788, 4138.13099249158, 4139.234241665302, 4143.751374917515, 5584.692841893694, 5660.971695141498, 5769.365369809359, 5819.505342045931, 5824.679642619654, 4178.540360560336, 4185.874423767292, 4191.532963462824, 4202.096549241927, 4203.768792716369, 4248.277792775236, 4265.317549192177, 4269.7549862105025, 4271.679624028148, 4277.588395829369, 4614.723116613014, 4782.976085119552, 4791.227245919033, 4803.159447096744, 4831.73425461603, 5618.52242559387, 5623.409541998113, 5637.0, 5650.406707295757, 5650.7174641267775, 1416.8016477693131, 1417.943631994171, 1418.319206780751, 1424.8174015567054, 1425.0, 934.9439033657866, 942.3544871762864, 985.7634167799677, 987.4223579206244, 998.4308943901906, 5234.947650523446, 5426.511613954763, 5554.2779438672715, 5576.693281869536, 5582.134769110794, 5742.6554406734895, 5748.0, 5748.827535173951, 5755.618651523604, 5775.768969241268, 5410.458954104637, 5481.260029895992, 5520.392756796749, 5564.081926482166, 5565.443455654402, 2904.941901161965, 2913.572349548706, 2936.765058795291, 2951.2326795033446, 2966.8813247470293, 5822.417758224144, 5826.592138550261, 5830.970845145747, 5833.4749872511675, 5834.854128646818, 5428.972855135699, 5573.359063707644, 5612.382637909826, 5622.746976385991, 5680.460356266148, 5729.455701708331, 5749.8275051748515, 5770.989162532, 5785.768569257182, 5792.9710351447975, 5476.370217424887, 5557.194206840067, 5586.692731899784, 5587.898716212541, 5760.567957403296, 5704.486596206195, 5705.4009329019855, 5712.65724056558, 5716.314042314949, 5728.914066289092, 6006.969965150146, 6026.156338112755, 6028.849278768066, 6031.36670649593, 6040.610659548416, 4041.777702226406, 4139.503259608746, 4195.1819395217635, 4200.306949353322, 4204.684648651302, 5707.743151558273, 5750.769969201185, 5777.9133313001175, 5784.681842498535, 5793.826185214451, 5419.593530485151, 5611.466910643646, 5645.463680950304, 5652.576056795675, 5666.943330566642, 5600.775968961196, 5610.7755689773585, 5614.915776263278, 5616.185653080199, 5621.494065534122, 5588.804391846266, 5607.943920560905, 5630.071038278638, 5633.2113504110275, 5637.830865074055, 5421.0, 5530.944690553043, 5539.224508568885, 5665.603407761582, 5669.858253543694, 5532.280803495681, 5543.584231182758, 5545.584081193846, 5546.528545073714, 5563.193336966198], "value": 4302.78466476692, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.4096132707107, 843.6920524008739, 863.6977058029694, 905.6015353244572, 925.2967744514169, 15228.238588070593, 15659.530214093567, 15697.215139243035, 15904.045757254573, 16081.437149699748, 15697.21513924305, 15862.603434914134, 15886.0, 15893.920530397332, 16251.91874040631, 706.2055187913597, 732.4030914804443, 825.3562221467265, 836.0260296754274, 838.8028813228887, 807.8626633472323, 834.1450013735908, 842.5618678287287, 843.2326582809644, 843.2874221283006, 894.7628878347243, 895.879056327394, 896.3411892259185, 897.1521911793325, 897.4166791585457, 730.0837449001522, 755.3126654744157, 889.5774507109169, 890.4479222881822, 890.514669505118, 629.6033498895666, 643.1092936283235, 647.0876064748682, 647.4690753582065, 647.5790736021577, 15334.463293784664, 15570.221488925621, 15575.766363504557, 15578.9221053894, 15599.766003509956, 14915.552533424014, 15229.162396068212, 15492.45776397832, 15742.055476671436, 15811.92094039534, 13704.52034178799, 13712.65718357039, 13857.792133118011, 14004.649883752885, 14010.649733756636, 3957.7823219722904, 4535.750533720677, 4564.292534657134, 4598.931016034729, 4653.325267836179, 68929.65535172292, 80698.19301806965, 81285.74857005713, 81892.59053704693, 82808.17191828066, 11880.702982425508, 11915.463804128834, 11962.58130965412, 12033.278003319829, 12191.207571507824, 22308.665370019462, 23075.7692423077, 23079.76920230776, 23085.422864428525, 23261.53476930452, 8972.37193396469, 9252.259819214447, 9259.814803703888, 9275.58259878307, 9330.673426430178, 4726.291056341507, 4762.452317983476, 4799.328094066834, 4849.199882019428, 4898.118338699069, 3811.2187001664593, 3864.3237433449035, 3874.728768986144, 3890.455336252927, 3926.312895243321, 661.6063442251852, 669.7354544954716, 675.4191395399952, 679.3647939176885, 685.807973767346, 849.46908182386, 865.4158443050916, 869.0, 879.6041781198359, 888.3781353052759, 10877.728056798644, 11122.777544449065, 11133.220674552704, 11137.498812553455, 11366.659000230007, 847.855864503036, 854.3336197765752, 872.5419154943705, 874.0953113527469, 880.9427387219811, 5425.484578964992, 5548.528375088082, 5551.018792099106, 5597.160425936061, 5620.4660557247, 83642.58178709068, 84881.0, 85103.0, 85196.57401712952, 87206.0, 72539.0, 72939.0, 73969.63015184889, 75182.24817751862, 75744.62127689326, 64147.0, 66058.0, 66281.33718662849, 67236.32763672301, 68742.65628671924, 10430.16558675289, 10730.517126729159, 10786.838197427202, 10875.293105948089, 10936.343819370863, 45542.0, 45996.770016149705, 46441.0, 46727.53272467232, 47117.29324060211, 56024.439755601925, 56282.43717562772, 56291.71854140703, 57023.71488142699, 58364.4163558359, 12602.36988150607, 12849.807252891402, 13129.474821007052, 13138.80291795643, 13378.799318010042, 14866.85133148698, 14910.25448727581, 14972.176530290939, 15021.324040418425, 15030.17334046638, 13041.673958151123, 14545.200013999332, 14744.262786860829, 14796.186209758567, 15088.321025553656, 14898.925505372405, 15152.696946061238, 15266.160361179798, 15362.462313818962, 15821.288042038366, 14385.88802454255, 14443.638909027359, 14474.85525144735, 15046.849531504546, 15157.469488567847, 11355.716107097389, 11829.467673954521, 12116.939415302868, 12138.089643276613, 12205.023598111951, 12680.556180533637, 12774.435191457087, 12806.35968201605, 12945.870541294467, 13037.348132593523], "value": 7814.843264392969, "unit": "score"}], "name": "dromaeo_css", "value": 7541.561247453715, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 112.0, 112.0, 113.0, 112.0, 110.0, 112.0, 111.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 126.0, 156.0, 117.0, 120.0, 116.0, 116.0, 134.0, 118.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 160.0, 162.0, 160.0, 160.0, 163.0, 160.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 111.0, 75.0, 74.0, 75.0, 75.0, 89.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 105.0, 84.0, 84.0, 84.0, 99.0, 84.0, 85.0, 109.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 149.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 49.0, 48.0, 49.0, 50.0, 51.0, 49.0, 51.0, 49.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 64.0, 62.0, 63.0, 63.0, 63.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 76.0, 77.0, 81.0, 76.0, 83.0, 77.0, 74.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 130.0, 131.0, 122.0, 131.0, 120.0, 120.0, 122.0, 162.0, 133.0], "value": 129.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 155.0, 159.0, 160.0, 162.0, 155.0, 154.0, 155.0, 157.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 59.0, 61.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.8, "unit": "score"}], "name": "kraken", "value": 1465.1000000000001, "alertThreshold": 2.0}]} 17:40:56 INFO - Return code: 0 17:40:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:40:56 ERROR - # TBPL SUCCESS # 17:40:56 INFO - Running post-action listener: _resource_record_post_action 17:40:56 INFO - [mozharness: 2017-02-09 01:40:56.921665Z] Finished run-tests step (success) 17:40:56 INFO - Running post-run listener: _resource_record_post_run 17:40:56 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 362979328; Read time: 16; Write time: 274808 17:40:56 INFO - TinderboxPrint: CPU usage
13.0% 17:40:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 17:40:56 INFO - TinderboxPrint: I/O write bytes / time
362,979,328 / 274,808 17:40:56 INFO - TinderboxPrint: CPU idle
8,270.2 (87.0%) 17:40:56 INFO - TinderboxPrint: CPU user
1,190.1 (12.5%) 17:40:56 INFO - TinderboxPrint: Swap in / out
0 / 0 17:40:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109400064; Read time: 0; Write time: 85468 17:40:57 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252555264; Read time: 16; Write time: 189312 17:40:57 INFO - Running post-run listener: _upload_blobber_files 17:40:57 INFO - Blob upload gear active. 17:40:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:40:57 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 17:40:57 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:40:57 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:40:57 INFO - (blobuploader) - INFO - Open directory for files ... 17:40:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:40:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:40:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:59 INFO - (blobuploader) - INFO - Done attempting. 17:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:59 INFO - (blobuploader) - INFO - Done attempting. 17:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:03 INFO - (blobuploader) - INFO - Done attempting. 17:41:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:41:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:03 INFO - (blobuploader) - INFO - Done attempting. 17:41:03 INFO - (blobuploader) - INFO - Iteration through files over. 17:41:03 INFO - Return code: 0 17:41:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:41:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:41:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1523b6f13133b006795c1b972697d96806b36561263d843ddf9837ee63e290bba402c4b4ea70fee56bef256acd470ff90c08034c5bcb4f082b311a2d7f83dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88bd77297fc8f935f4f58defbe5bcdef169f1596f2ec5507bd3768e0570b814ab8aa99700cfce351cfd703877fa7ce89dec218641f62d559c584bceef992a973", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a166b290a8a0a39b507f6412eb248c9f226f2bad926b0c1b2a9814157fdef3ee1560bde667cf2fa7a84f3d910f59e79897b5d9c7327ec916f8e806f7fb7c4ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd7ee317d0c9640221af0ca07a3a85c979673380571ee09950f7280fc1048abe47214c792e5455382c886992a0a0f4ad31f02348a67df2bdb70b3012429633e2"} 17:41:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:41:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:41:03 INFO - Contents: 17:41:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1523b6f13133b006795c1b972697d96806b36561263d843ddf9837ee63e290bba402c4b4ea70fee56bef256acd470ff90c08034c5bcb4f082b311a2d7f83dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88bd77297fc8f935f4f58defbe5bcdef169f1596f2ec5507bd3768e0570b814ab8aa99700cfce351cfd703877fa7ce89dec218641f62d559c584bceef992a973", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a166b290a8a0a39b507f6412eb248c9f226f2bad926b0c1b2a9814157fdef3ee1560bde667cf2fa7a84f3d910f59e79897b5d9c7327ec916f8e806f7fb7c4ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd7ee317d0c9640221af0ca07a3a85c979673380571ee09950f7280fc1048abe47214c792e5455382c886992a0a0f4ad31f02348a67df2bdb70b3012429633e2"} 17:41:03 INFO - Running post-run listener: copy_logs_to_upload_dir 17:41:03 INFO - Copying logs to upload dir... 17:41:03 INFO - mkdir: /builds/slave/test/build/upload/logs 17:41:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.865231 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-08 17:41:03.538613) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:41:03.542003) ========= 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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1523b6f13133b006795c1b972697d96806b36561263d843ddf9837ee63e290bba402c4b4ea70fee56bef256acd470ff90c08034c5bcb4f082b311a2d7f83dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88bd77297fc8f935f4f58defbe5bcdef169f1596f2ec5507bd3768e0570b814ab8aa99700cfce351cfd703877fa7ce89dec218641f62d559c584bceef992a973", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a166b290a8a0a39b507f6412eb248c9f226f2bad926b0c1b2a9814157fdef3ee1560bde667cf2fa7a84f3d910f59e79897b5d9c7327ec916f8e806f7fb7c4ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd7ee317d0c9640221af0ca07a3a85c979673380571ee09950f7280fc1048abe47214c792e5455382c886992a0a0f4ad31f02348a67df2bdb70b3012429633e2"} build_url:https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://queue.taskcluster.net/v1/task/QcbV8TCmT9CGQDTPsM19zw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1523b6f13133b006795c1b972697d96806b36561263d843ddf9837ee63e290bba402c4b4ea70fee56bef256acd470ff90c08034c5bcb4f082b311a2d7f83dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88bd77297fc8f935f4f58defbe5bcdef169f1596f2ec5507bd3768e0570b814ab8aa99700cfce351cfd703877fa7ce89dec218641f62d559c584bceef992a973", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a166b290a8a0a39b507f6412eb248c9f226f2bad926b0c1b2a9814157fdef3ee1560bde667cf2fa7a84f3d910f59e79897b5d9c7327ec916f8e806f7fb7c4ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd7ee317d0c9640221af0ca07a3a85c979673380571ee09950f7280fc1048abe47214c792e5455382c886992a0a0f4ad31f02348a67df2bdb70b3012429633e2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:41:03.567078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:41:03.567420) ========= 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=920470bf432669ad7e3a5387000001ca-1486603225.506489-1450043989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004559 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:41:03.649582) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:41:03.649912) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:41:03.650229) ========= ========= Total master_lag: 0.17 =========