builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-046 starttime: 1483748638.45 results: success (0) revision: 674f47d5554e32616a828a9dd48a3978707960e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.447570) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.448045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.448346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.462718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.462988) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-06 16:23:58-- 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% 222M=0s 2017-01-06 16:23:58 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082280 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.559056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.559405) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017148 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.609103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:58.609472) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 674f47d5554e32616a828a9dd48a3978707960e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev 674f47d5554e32616a828a9dd48a3978707960e5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-06 16:23:58,864 truncating revision to first 12 chars 2017-01-06 16:23:58,865 Setting DEBUG logging. 2017-01-06 16:23:58,865 attempt 1/10 2017-01-06 16:23:58,865 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/674f47d5554e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 16:23:59,305 unpacking tar archive at: graphics-674f47d5554e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.745996 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:59.368752) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:59.369096) ========= script_repo_revision: 674f47d5554e32616a828a9dd48a3978707960e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:59.369484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:59.369756) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 16:23:59.377444) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-06 16:23:59.377721) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:23:59 INFO - MultiFileLogger online at 20170106 16:23:59 in /builds/slave/test 16:23:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 16:23:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:23:59 INFO - {'append_to_log': False, 16:23:59 INFO - 'base_work_dir': '/builds/slave/test', 16:23:59 INFO - 'blob_upload_branch': 'Graphics', 16:23:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:23:59 INFO - 'branch': 'Graphics', 16:23:59 INFO - 'buildbot_json_path': 'buildprops.json', 16:23:59 INFO - 'config_files': ('talos/linux_config.py',), 16:23:59 INFO - 'default_actions': ('clobber', 16:23:59 INFO - 'read-buildbot-config', 16:23:59 INFO - 'download-and-extract', 16:23:59 INFO - 'populate-webroot', 16:23:59 INFO - 'create-virtualenv', 16:23:59 INFO - 'install', 16:23:59 INFO - 'run-tests'), 16:23:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:23:59 INFO - 'download_minidump_stackwalk': True, 16:23:59 INFO - 'download_symbols': 'ondemand', 16:23:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:23:59 INFO - 'tooltool.py': '/tools/tooltool.py', 16:23:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:23:59 INFO - '/tools/misc-python/virtualenv.py')}, 16:23:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:23:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:23:59 INFO - 'installer_path': 'installer.exe', 16:23:59 INFO - 'log_level': 'info', 16:23:59 INFO - 'log_name': 'talos', 16:23:59 INFO - 'log_to_console': True, 16:23:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:23:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:23:59 INFO - 'opt_config_files': (), 16:23:59 INFO - 'pip_index': False, 16:23:59 INFO - 'sps_profile': False, 16:23:59 INFO - 'sps_profile_interval': 0, 16:23:59 INFO - 'suite': 'dromaeojs', 16:23:59 INFO - 'system_bits': '32', 16:23:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:23:59 INFO - 'title': 'talos-linux64-ix-046', 16:23:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:23:59 INFO - 'use_talos_json': True, 16:23:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:23:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:23:59 INFO - 'work_dir': 'build'} 16:23:59 INFO - [mozharness: 2017-01-07 00:23:59.732019Z] Running clobber step. 16:23:59 INFO - Running pre-action listener: _resource_record_pre_action 16:23:59 INFO - Running main action method: clobber 16:23:59 INFO - rmtree: /builds/slave/test/build 16:23:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:24:00 INFO - Running post-action listener: _resource_record_post_action 16:24:00 INFO - [mozharness: 2017-01-07 00:24:00.194894Z] Finished clobber step (success) 16:24:00 INFO - [mozharness: 2017-01-07 00:24:00.194996Z] Running read-buildbot-config step. 16:24:00 INFO - Running pre-action listener: _resource_record_pre_action 16:24:00 INFO - Running main action method: read_buildbot_config 16:24:00 INFO - Using buildbot properties: 16:24:00 INFO - { 16:24:00 INFO - "project": "", 16:24:00 INFO - "product": "firefox", 16:24:00 INFO - "who": "kgupta@mozilla.com", 16:24:00 INFO - "installer_path": "public/build/target.tar.bz2", 16:24:00 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 16:24:00 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 16:24:00 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 16:24:00 INFO - "stage_platform": "linux64", 16:24:00 INFO - "basedir": "/builds/slave/test", 16:24:00 INFO - "buildnumber": 45, 16:24:00 INFO - "platform": "ubuntu64_hw", 16:24:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:24:00 INFO - "slavebuilddir": "test", 16:24:00 INFO - "taskId": "GcG7TMj2QLCTgTj03ES0UA", 16:24:00 INFO - "branch": "graphics", 16:24:00 INFO - "script_repo_revision": "default", 16:24:00 INFO - "revision": "674f47d5554e32616a828a9dd48a3978707960e5", 16:24:00 INFO - "slavename": "talos-linux64-ix-046", 16:24:00 INFO - "repo_path": "projects/graphics" 16:24:00 INFO - } 16:24:00 INFO - Finding installer, test and symbols from parent task. 16:24:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GcG7TMj2QLCTgTj03ES0UA'}, attempt #1 16:24:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GcG7TMj2QLCTgTj03ES0UA'}, attempt #1 16:24:01 INFO - Task dependencies: WtRpIYbXRTOoS0iba9Y7mg 16:24:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg'}, attempt #1 16:24:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 16:24:01 INFO - Set test_url: https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/test_packages.json 16:24:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.crashreporter-symbols.zip 16:24:01 INFO - Running post-action listener: _resource_record_post_action 16:24:01 INFO - [mozharness: 2017-01-07 00:24:01.664566Z] Finished read-buildbot-config step (success) 16:24:01 INFO - [mozharness: 2017-01-07 00:24:01.664785Z] Running download-and-extract step. 16:24:01 INFO - Running pre-action listener: _resource_record_pre_action 16:24:01 INFO - Running main action method: download_and_extract 16:24:01 INFO - mkdir: /builds/slave/test/build/tests 16:24: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')]} 16:24:01 INFO - trying https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.test_packages.json 16:24:01 INFO - Downloading https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:24:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:24:02 INFO - Downloaded 896 bytes. 16:24:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:24:02 INFO - Using the following test package requirements: 16:24:02 INFO - {u'common': [u'target.common.tests.zip'], 16:24:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:24:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:24:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:24:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:24:02 INFO - u'mozbase': [u'target.common.tests.zip'], 16:24:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:24:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:24:02 INFO - u'web-platform': [u'target.common.tests.zip', 16:24:02 INFO - u'target.web-platform.tests.zip'], 16:24:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:24:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:24:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip 16:24:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:24:02 INFO - Fetch https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip into memory 16:24:03 INFO - Expected file size: 20246889 16:24:03 INFO - Obtained file size: 20246889 16:24:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip 16:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.common.tests.zip into memory 16:24:07 INFO - Expected file size: 20246889 16:24:07 INFO - Obtained file size: 20246889 16:24:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.talos.tests.zip 16:24:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:24:12 INFO - Fetch https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.talos.tests.zip into memory 16:24:13 INFO - Expected file size: 14403730 16:24:13 INFO - Obtained file size: 14403730 16:24:13 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')]} 16:24:13 INFO - trying https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 16:24:13 INFO - Downloading https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:24:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:24:14 INFO - Downloaded 55849786 bytes. 16:24:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 16:24:14 INFO - mkdir: /builds/slave/test/properties 16:24:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:14 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:14 INFO - Contents: 16:24:14 INFO - build_url:https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 16:24:14 INFO - Running post-action listener: _resource_record_post_action 16:24:14 INFO - Running post-action listener: set_extra_try_arguments 16:24:14 INFO - [mozharness: 2017-01-07 00:24:14.361766Z] Finished download-and-extract step (success) 16:24:14 INFO - [mozharness: 2017-01-07 00:24:14.361864Z] Running populate-webroot step. 16:24:14 INFO - Running pre-action listener: _resource_record_pre_action 16:24:14 INFO - Running main action method: populate_webroot 16:24:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:24:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:24:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:24:14 INFO - '--tppagecycles', 16:24:14 INFO - '7'], 16:24:14 INFO - 'tests': ['tsvgm']}}, 16:24:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:24:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:24:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['damp', 'tps']}, 16:24:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'tests': ['damp', 'tps']}, 16:24:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['dromaeo_dom']}, 16:24:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['basic_compositor_video']}, 16:24:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:24:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['a11yr', 16:24:14 INFO - 'ts_paint', 16:24:14 INFO - 'tpaint', 16:24:14 INFO - 'sessionrestore', 16:24:14 INFO - 'sessionrestore_no_auto_restore', 16:24:14 INFO - 'tabpaint']}, 16:24:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['tsvgx', 16:24:14 INFO - 'tsvgr_opacity', 16:24:14 INFO - 'tart', 16:24:14 INFO - 'tscrollx', 16:24:14 INFO - 'cart', 16:24:14 INFO - 'tsvg_static']}, 16:24:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:14 INFO - 'tsvgr_opacity', 16:24:14 INFO - 'tart', 16:24:14 INFO - 'tscrollx', 16:24:14 INFO - 'cart', 16:24:14 INFO - 'tsvg_static']}, 16:24:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'talos_options': ['--disable-e10s'], 16:24:14 INFO - 'tests': ['tp5o']}, 16:24:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:24:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'talos_options': ['--disable-e10s', 16:24:14 INFO - '--xperf_path', 16:24:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:14 INFO - 'tests': ['tp5n']}, 16:24:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:24:14 INFO - 'talos_options': ['--xperf_path', 16:24:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:14 INFO - 'tests': ['tp5n']}}, 16:24:14 INFO - 'talos.zip': {'path': '', 16:24:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:24:14 INFO - Running post-action listener: _resource_record_post_action 16:24:14 INFO - [mozharness: 2017-01-07 00:24:14.375113Z] Finished populate-webroot step (success) 16:24:14 INFO - [mozharness: 2017-01-07 00:24:14.375208Z] Running create-virtualenv step. 16:24:14 INFO - Running pre-action listener: _resource_record_pre_action 16:24:14 INFO - Running main action method: create_virtualenv 16:24:14 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:24:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:24:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:14 INFO - Using real prefix '/usr' 16:24:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:14 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:16 INFO - Installing pip.................done. 16:24:16 INFO - Return code: 0 16:24:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:24:17 INFO - Reading from file tmpfile_stdout 16:24:17 INFO - Output received: 16:24:17 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) 16:24:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:24:17 INFO - Using env: {'DISPLAY': ':0', 16:24:17 INFO - 'HOME': '/home/cltbld', 16:24:17 INFO - 'LANG': 'en_US.UTF-8', 16:24:17 INFO - 'LANGUAGE': 'en_US:en', 16:24:17 INFO - 'LOGNAME': 'cltbld', 16:24:17 INFO - 'MAIL': '/var/mail/cltbld', 16:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:17 INFO - 'MOZ_NO_REMOTE': '1', 16:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:17 INFO - 'NO_EM_RESTART': '1', 16:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:17 INFO - 'PWD': '/builds/slave/test', 16:24:17 INFO - 'SHELL': '/bin/bash', 16:24:17 INFO - 'SHLVL': '1', 16:24:17 INFO - 'TERM': 'linux', 16:24:17 INFO - 'TMOUT': '86400', 16:24:17 INFO - 'USER': 'cltbld', 16:24:17 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:17 INFO - '_': '/tools/buildbot/bin/python'} 16:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:24:17 INFO - Cleaning up... 16:24:17 INFO - Return code: 0 16:24:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:24:17 INFO - Using env: {'DISPLAY': ':0', 16:24:17 INFO - 'HOME': '/home/cltbld', 16:24:17 INFO - 'LANG': 'en_US.UTF-8', 16:24:17 INFO - 'LANGUAGE': 'en_US:en', 16:24:17 INFO - 'LOGNAME': 'cltbld', 16:24:17 INFO - 'MAIL': '/var/mail/cltbld', 16:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:17 INFO - 'MOZ_NO_REMOTE': '1', 16:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:17 INFO - 'NO_EM_RESTART': '1', 16:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:17 INFO - 'PWD': '/builds/slave/test', 16:24:17 INFO - 'SHELL': '/bin/bash', 16:24:17 INFO - 'SHLVL': '1', 16:24:17 INFO - 'TERM': 'linux', 16:24:17 INFO - 'TMOUT': '86400', 16:24:17 INFO - 'USER': 'cltbld', 16:24:17 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:17 INFO - '_': '/tools/buildbot/bin/python'} 16:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:17 INFO - Downloading/unpacking psutil>=3.1.1 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:19 INFO - Installing collected packages: psutil 16:24:19 INFO - Running setup.py install for psutil 16:24:19 INFO - building 'psutil._psutil_linux' extension 16:24:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:24:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:24:20 INFO - building 'psutil._psutil_posix' extension 16:24:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:24:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:24:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:20 INFO - Successfully installed psutil 16:24:20 INFO - Cleaning up... 16:24:20 INFO - Return code: 0 16:24:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:24:20 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')]} 16:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:20 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')]} 16:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:20 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:24:20 INFO - Using env: {'DISPLAY': ':0', 16:24:20 INFO - 'HOME': '/home/cltbld', 16:24:20 INFO - 'LANG': 'en_US.UTF-8', 16:24:20 INFO - 'LANGUAGE': 'en_US:en', 16:24:20 INFO - 'LOGNAME': 'cltbld', 16:24:20 INFO - 'MAIL': '/var/mail/cltbld', 16:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:20 INFO - 'MOZ_NO_REMOTE': '1', 16:24:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:20 INFO - 'NO_EM_RESTART': '1', 16:24:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:20 INFO - 'PWD': '/builds/slave/test', 16:24:20 INFO - 'SHELL': '/bin/bash', 16:24:20 INFO - 'SHLVL': '1', 16:24:20 INFO - 'TERM': 'linux', 16:24:20 INFO - 'TMOUT': '86400', 16:24:20 INFO - 'USER': 'cltbld', 16:24:20 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:20 INFO - '_': '/tools/buildbot/bin/python'} 16:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:24:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:24:22 INFO - Installing collected packages: mozsystemmonitor 16:24:22 INFO - Running setup.py install for mozsystemmonitor 16:24:22 INFO - Successfully installed mozsystemmonitor 16:24:22 INFO - Cleaning up... 16:24:22 INFO - Return code: 0 16:24:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24: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')]} 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:24:22 INFO - Using env: {'DISPLAY': ':0', 16:24:22 INFO - 'HOME': '/home/cltbld', 16:24:22 INFO - 'LANG': 'en_US.UTF-8', 16:24:22 INFO - 'LANGUAGE': 'en_US:en', 16:24:22 INFO - 'LOGNAME': 'cltbld', 16:24:22 INFO - 'MAIL': '/var/mail/cltbld', 16:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:22 INFO - 'MOZ_NO_REMOTE': '1', 16:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:22 INFO - 'NO_EM_RESTART': '1', 16:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:22 INFO - 'PWD': '/builds/slave/test', 16:24:22 INFO - 'SHELL': '/bin/bash', 16:24:22 INFO - 'SHLVL': '1', 16:24:22 INFO - 'TERM': 'linux', 16:24:22 INFO - 'TMOUT': '86400', 16:24:22 INFO - 'USER': 'cltbld', 16:24:22 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:22 INFO - '_': '/tools/buildbot/bin/python'} 16:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:24:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - Downloading functools32-3.2.3-2.tar.gz 16:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:24:25 INFO - warning: no files found matching '*.txt' 16:24:25 INFO - no previously-included directories found matching 'build' 16:24:25 INFO - no previously-included directories found matching 'dist' 16:24:25 INFO - no previously-included directories found matching '.git*' 16:24:25 INFO - Installing collected packages: jsonschema, functools32 16:24:25 INFO - Running setup.py install for functools32 16:24:25 INFO - warning: no files found matching '*.txt' 16:24:25 INFO - no previously-included directories found matching 'build' 16:24:25 INFO - no previously-included directories found matching 'dist' 16:24:25 INFO - no previously-included directories found matching '.git*' 16:24:25 INFO - Successfully installed jsonschema functools32 16:24:25 INFO - Cleaning up... 16:24:25 INFO - Return code: 0 16:24:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:24:25 INFO - Using env: {'DISPLAY': ':0', 16:24:25 INFO - 'HOME': '/home/cltbld', 16:24:25 INFO - 'LANG': 'en_US.UTF-8', 16:24:25 INFO - 'LANGUAGE': 'en_US:en', 16:24:25 INFO - 'LOGNAME': 'cltbld', 16:24:25 INFO - 'MAIL': '/var/mail/cltbld', 16:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:25 INFO - 'MOZ_NO_REMOTE': '1', 16:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:25 INFO - 'NO_EM_RESTART': '1', 16:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:25 INFO - 'PWD': '/builds/slave/test', 16:24:25 INFO - 'SHELL': '/bin/bash', 16:24:25 INFO - 'SHLVL': '1', 16:24:25 INFO - 'TERM': 'linux', 16:24:25 INFO - 'TMOUT': '86400', 16:24:25 INFO - 'USER': 'cltbld', 16:24:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:25 INFO - '_': '/tools/buildbot/bin/python'} 16:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:24:25 INFO - Cleaning up... 16:24:25 INFO - Return code: 0 16:24:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:24:25 INFO - Using env: {'DISPLAY': ':0', 16:24:25 INFO - 'HOME': '/home/cltbld', 16:24:25 INFO - 'LANG': 'en_US.UTF-8', 16:24:25 INFO - 'LANGUAGE': 'en_US:en', 16:24:25 INFO - 'LOGNAME': 'cltbld', 16:24:25 INFO - 'MAIL': '/var/mail/cltbld', 16:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:25 INFO - 'MOZ_NO_REMOTE': '1', 16:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:25 INFO - 'NO_EM_RESTART': '1', 16:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:25 INFO - 'PWD': '/builds/slave/test', 16:24:25 INFO - 'SHELL': '/bin/bash', 16:24:25 INFO - 'SHLVL': '1', 16:24:25 INFO - 'TERM': 'linux', 16:24:25 INFO - 'TMOUT': '86400', 16:24:25 INFO - 'USER': 'cltbld', 16:24:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:25 INFO - '_': '/tools/buildbot/bin/python'} 16:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:25 INFO - Downloading/unpacking blobuploader==1.2.4 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:27 INFO - Downloading blobuploader-1.2.4.tar.gz 16:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:24:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:24:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:28 INFO - Downloading docopt-0.6.1.tar.gz 16:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:24:28 INFO - Installing collected packages: blobuploader, requests, docopt 16:24:28 INFO - Running setup.py install for blobuploader 16:24:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:24:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:24:28 INFO - Running setup.py install for requests 16:24:29 INFO - Running setup.py install for docopt 16:24:29 INFO - Successfully installed blobuploader requests docopt 16:24:29 INFO - Cleaning up... 16:24:29 INFO - Return code: 0 16:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:29 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:29 INFO - Using env: {'DISPLAY': ':0', 16:24:29 INFO - 'HOME': '/home/cltbld', 16:24:29 INFO - 'LANG': 'en_US.UTF-8', 16:24:29 INFO - 'LANGUAGE': 'en_US:en', 16:24:29 INFO - 'LOGNAME': 'cltbld', 16:24:29 INFO - 'MAIL': '/var/mail/cltbld', 16:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:29 INFO - 'MOZ_NO_REMOTE': '1', 16:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:29 INFO - 'NO_EM_RESTART': '1', 16:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:29 INFO - 'PWD': '/builds/slave/test', 16:24:29 INFO - 'SHELL': '/bin/bash', 16:24:29 INFO - 'SHLVL': '1', 16:24:29 INFO - 'TERM': 'linux', 16:24:29 INFO - 'TMOUT': '86400', 16:24:29 INFO - 'USER': 'cltbld', 16:24:29 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:29 INFO - '_': '/tools/buildbot/bin/python'} 16:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:29 INFO - Running setup.py (path:/tmp/pip-lFAXyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:29 INFO - Running setup.py (path:/tmp/pip-nceENN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:29 INFO - Running setup.py (path:/tmp/pip-pYd_l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:29 INFO - Running setup.py (path:/tmp/pip-K9gdhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:29 INFO - Running setup.py (path:/tmp/pip-ANSsvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:29 INFO - Running setup.py (path:/tmp/pip-SsBMqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:29 INFO - Running setup.py (path:/tmp/pip-DWk75K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:29 INFO - Running setup.py (path:/tmp/pip-JVaLgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:30 INFO - Running setup.py (path:/tmp/pip-645Kye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:30 INFO - Running setup.py (path:/tmp/pip-pVQjY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:30 INFO - Running setup.py (path:/tmp/pip-_xPMOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:30 INFO - Running setup.py (path:/tmp/pip-L7YQFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:30 INFO - Running setup.py (path:/tmp/pip-ecVdf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:30 INFO - Running setup.py (path:/tmp/pip-h5DLyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:30 INFO - Running setup.py (path:/tmp/pip-Ci6tiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:30 INFO - Running setup.py (path:/tmp/pip-qZ4uYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:30 INFO - Running setup.py (path:/tmp/pip-suUTSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:24:30 INFO - Running setup.py install for manifestparser 16:24:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:24:30 INFO - Running setup.py install for mozcrash 16:24:30 INFO - Running setup.py install for mozdebug 16:24:31 INFO - Running setup.py install for mozdevice 16:24:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for mozfile 16:24:31 INFO - Running setup.py install for mozhttpd 16:24:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for mozinfo 16:24:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for mozInstall 16:24:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for mozleak 16:24:31 INFO - Running setup.py install for mozlog 16:24:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for moznetwork 16:24:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:24:31 INFO - Running setup.py install for mozprocess 16:24:31 INFO - Running setup.py install for mozprofile 16:24:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:24:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:24:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:24:32 INFO - Running setup.py install for mozrunner 16:24:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:24:32 INFO - Running setup.py install for mozscreenshot 16:24:32 INFO - Running setup.py install for moztest 16:24:32 INFO - Running setup.py install for mozversion 16:24:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:24:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:24:32 INFO - Cleaning up... 16:24:32 INFO - Return code: 0 16:24:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:32 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')]} 16:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:32 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')]} 16:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:32 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:32 INFO - Using env: {'DISPLAY': ':0', 16:24:32 INFO - 'HOME': '/home/cltbld', 16:24:32 INFO - 'LANG': 'en_US.UTF-8', 16:24:32 INFO - 'LANGUAGE': 'en_US:en', 16:24:32 INFO - 'LOGNAME': 'cltbld', 16:24:32 INFO - 'MAIL': '/var/mail/cltbld', 16:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:32 INFO - 'MOZ_NO_REMOTE': '1', 16:24:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:32 INFO - 'NO_EM_RESTART': '1', 16:24:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:32 INFO - 'PWD': '/builds/slave/test', 16:24:32 INFO - 'SHELL': '/bin/bash', 16:24:32 INFO - 'SHLVL': '1', 16:24:32 INFO - 'TERM': 'linux', 16:24:32 INFO - 'TMOUT': '86400', 16:24:32 INFO - 'USER': 'cltbld', 16:24:32 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:32 INFO - '_': '/tools/buildbot/bin/python'} 16:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:32 INFO - Running setup.py (path:/tmp/pip-AII3Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:32 INFO - Running setup.py (path:/tmp/pip-T7VXKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:32 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)) 16:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:32 INFO - Running setup.py (path:/tmp/pip-NJ2CXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:32 INFO - Running setup.py (path:/tmp/pip-Zp6qpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:33 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)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:33 INFO - Running setup.py (path:/tmp/pip-LvmAkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:33 INFO - Running setup.py (path:/tmp/pip-Fw6U8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:33 INFO - Running setup.py (path:/tmp/pip-AXUdTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:33 INFO - Running setup.py (path:/tmp/pip-dqigf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:33 INFO - Running setup.py (path:/tmp/pip-f9JVZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:33 INFO - Running setup.py (path:/tmp/pip-TR4K5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:33 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)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:33 INFO - Running setup.py (path:/tmp/pip-uNLe28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:33 INFO - Running setup.py (path:/tmp/pip-sMW78I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:33 INFO - Running setup.py (path:/tmp/pip-nj_6S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:33 INFO - Running setup.py (path:/tmp/pip-n5eqPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:33 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)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:33 INFO - Running setup.py (path:/tmp/pip-mG6dY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:33 INFO - Running setup.py (path:/tmp/pip-5gKBZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:33 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)) 16:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:33 INFO - Running setup.py (path:/tmp/pip-39SP4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:24:34 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)) 16:24:34 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)) 16:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:24:34 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)) 16:24:34 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)) 16:24:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:36 INFO - Downloading blessings-1.6.tar.gz 16:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:24:36 INFO - Installing collected packages: blessings 16:24:36 INFO - Running setup.py install for blessings 16:24:36 INFO - Successfully installed blessings 16:24:36 INFO - Cleaning up... 16:24:36 INFO - Return code: 0 16:24:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:24:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:36 INFO - Reading from file tmpfile_stdout 16:24:36 INFO - Current package versions: 16:24:36 INFO - argparse == 1.2.1 16:24:36 INFO - blessings == 1.6 16:24:36 INFO - blobuploader == 1.2.4 16:24:36 INFO - docopt == 0.6.1 16:24:36 INFO - functools32 == 3.2.3-2 16:24:36 INFO - jsonschema == 2.5.1 16:24:36 INFO - manifestparser == 1.1 16:24:36 INFO - mozInstall == 1.12 16:24:36 INFO - mozcrash == 1.0 16:24:36 INFO - mozdebug == 0.1 16:24:36 INFO - mozdevice == 0.49 16:24:36 INFO - mozfile == 1.2 16:24:36 INFO - mozhttpd == 0.7 16:24:36 INFO - mozinfo == 0.9 16:24:36 INFO - mozleak == 0.1 16:24:36 INFO - mozlog == 3.4 16:24:36 INFO - moznetwork == 0.27 16:24:36 INFO - mozprocess == 0.23 16:24:36 INFO - mozprofile == 0.28 16:24:36 INFO - mozrunner == 6.13 16:24:36 INFO - mozscreenshot == 0.1 16:24:36 INFO - mozsystemmonitor == 0.3 16:24:36 INFO - moztest == 0.8 16:24:36 INFO - mozversion == 1.4 16:24:36 INFO - psutil == 3.1.1 16:24:36 INFO - requests == 1.2.3 16:24:36 INFO - wsgiref == 0.1.2 16:24:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:36 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 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:36 INFO - Using env: {'DISPLAY': ':0', 16:24:36 INFO - 'HOME': '/home/cltbld', 16:24:36 INFO - 'LANG': 'en_US.UTF-8', 16:24:36 INFO - 'LANGUAGE': 'en_US:en', 16:24:36 INFO - 'LOGNAME': 'cltbld', 16:24:36 INFO - 'MAIL': '/var/mail/cltbld', 16:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:36 INFO - 'MOZ_NO_REMOTE': '1', 16:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:36 INFO - 'NO_EM_RESTART': '1', 16:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:36 INFO - 'PWD': '/builds/slave/test', 16:24:36 INFO - 'SHELL': '/bin/bash', 16:24:36 INFO - 'SHLVL': '1', 16:24:36 INFO - 'TERM': 'linux', 16:24:36 INFO - 'TMOUT': '86400', 16:24:36 INFO - 'USER': 'cltbld', 16:24:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:36 INFO - '_': '/tools/buildbot/bin/python'} 16:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:24:36 INFO - Cleaning up... 16:24:36 INFO - Return code: 0 16:24:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x240fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:24:36 INFO - Using env: {'DISPLAY': ':0', 16:24:36 INFO - 'HOME': '/home/cltbld', 16:24:36 INFO - 'LANG': 'en_US.UTF-8', 16:24:36 INFO - 'LANGUAGE': 'en_US:en', 16:24:36 INFO - 'LOGNAME': 'cltbld', 16:24:36 INFO - 'MAIL': '/var/mail/cltbld', 16:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:36 INFO - 'MOZ_NO_REMOTE': '1', 16:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:36 INFO - 'NO_EM_RESTART': '1', 16:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:36 INFO - 'PWD': '/builds/slave/test', 16:24:36 INFO - 'SHELL': '/bin/bash', 16:24:36 INFO - 'SHLVL': '1', 16:24:36 INFO - 'TERM': 'linux', 16:24:36 INFO - 'TMOUT': '86400', 16:24:36 INFO - 'USER': 'cltbld', 16:24:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:36 INFO - '_': '/tools/buildbot/bin/python'} 16:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:24:36 INFO - Cleaning up... 16:24:36 INFO - Return code: 0 16:24:36 INFO - Running post-action listener: _resource_record_post_action 16:24:36 INFO - Running post-action listener: _start_resource_monitoring 16:24:36 INFO - Starting resource monitoring. 16:24:36 INFO - [mozharness: 2017-01-07 00:24:36.996634Z] Finished create-virtualenv step (success) 16:24:36 INFO - [mozharness: 2017-01-07 00:24:36.997098Z] Running install step. 16:24:36 INFO - Running pre-action listener: _resource_record_pre_action 16:24:36 INFO - Running main action method: install 16:24:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:37 INFO - Reading from file tmpfile_stdout 16:24:37 INFO - Detecting whether we're running mozinstall >=1.0... 16:24:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:24:37 INFO - Reading from file tmpfile_stdout 16:24:37 INFO - Output received: 16:24:37 INFO - Usage: mozinstall [options] installer 16:24:37 INFO - Options: 16:24:37 INFO - -h, --help show this help message and exit 16:24:37 INFO - -d DEST, --destination=DEST 16:24:37 INFO - Directory to install application into. [default: 16:24:37 INFO - "/builds/slave/test"] 16:24:37 INFO - --app=APP Application being installed. [default: firefox] 16:24:37 INFO - mkdir: /builds/slave/test/build/application 16:24:37 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'] 16:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:24:53 INFO - Reading from file tmpfile_stdout 16:24:53 INFO - Output received: 16:24:53 INFO - /builds/slave/test/build/application/firefox/firefox 16:24:53 INFO - Running post-action listener: _resource_record_post_action 16:24:53 INFO - [mozharness: 2017-01-07 00:24:53.416546Z] Finished install step (success) 16:24:53 INFO - [mozharness: 2017-01-07 00:24:53.416824Z] Running run-tests step. 16:24:53 INFO - Running pre-action listener: _resource_record_pre_action 16:24:53 INFO - Running main action method: run_tests 16:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:24:53 INFO - Python 2.7.3 16:24:53 INFO - Return code: 0 16:24:53 INFO - grabbing minidump binary from tooltool 16:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:53 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 0x240d3f0>, '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 0x240dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x240e1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:24:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:24:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:24:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:24:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:24:53 INFO - Return code: 0 16:24:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:24:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:24:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:24:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:24:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:24:53 INFO - Using env: {'DISPLAY': ':0', 16:24:53 INFO - 'HOME': '/home/cltbld', 16:24:53 INFO - 'LANG': 'en_US.UTF-8', 16:24:53 INFO - 'LANGUAGE': 'en_US:en', 16:24:53 INFO - 'LOGNAME': 'cltbld', 16:24:53 INFO - 'MAIL': '/var/mail/cltbld', 16:24:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:24:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:53 INFO - 'MOZ_NO_REMOTE': '1', 16:24:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:53 INFO - 'NO_EM_RESTART': '1', 16:24:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:53 INFO - 'PWD': '/builds/slave/test', 16:24:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:24:53 INFO - 'SHELL': '/bin/bash', 16:24:53 INFO - 'SHLVL': '1', 16:24:53 INFO - 'TERM': 'linux', 16:24:53 INFO - 'TMOUT': '86400', 16:24:53 INFO - 'USER': 'cltbld', 16:24:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464', 16:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:53 INFO - '_': '/tools/buildbot/bin/python'} 16:24:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:24:53 INFO - mozversion application_buildid: 20170106184946 16:24:53 INFO - mozversion application_changeset: 674f47d5554e32616a828a9dd48a3978707960e5 16:24:53 INFO - mozversion application_display_name: Nightly 16:24:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:24:53 INFO - mozversion application_name: Firefox 16:24:53 INFO - mozversion application_remotingname: firefox 16:24:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:24:53 INFO - mozversion application_vendor: Mozilla 16:24:53 INFO - mozversion application_version: 53.0a1 16:24:53 INFO - mozversion platform_buildid: 20170106184946 16:24:53 INFO - mozversion platform_changeset: 674f47d5554e32616a828a9dd48a3978707960e5 16:24:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:24:53 INFO - mozversion platform_version: 53.0a1 16:24:53 INFO - using testdate: 1483748693 16:24:53 INFO - actual date: 1483748693 16:24:53 INFO - starting webserver on 'localhost:36472' 16:24:53 INFO - SUITE-START | Running 2 tests 16:24:53 INFO - TEST-START | dromaeo_css 16:24:53 INFO - operating with platform_type : linux_ 16:24:53 INFO - Initialising browser for dromaeo_css test... 16:24:53 INFO - TEST-INFO | started process 5925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH5vnsk/profile http://localhost:36472/getInfo.html) 16:24:54 INFO - PROCESS | 5925 | 1483748694194 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 16:24:57 INFO - PROCESS | 5925 | __metrics Screen width/height:1600/1200 16:24:57 INFO - PROCESS | 5925 | colorDepth:24 16:24:57 INFO - PROCESS | 5925 | Browser inner width/height: 1024/697 16:24:57 INFO - PROCESS | 5925 | __metrics 16:24:58 INFO - PROCESS | 5925 | 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" 16:24:58 INFO - PROCESS | 5925 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:24:58 INFO - PROCESS | 5925 | console.error: PushService: 16:24:58 INFO - PROCESS | 5925 | stateChangeProcessEnqueue: Error transitioning state 16:24:58 INFO - PROCESS | 5925 | UnknownError 16:24:59 INFO - TEST-INFO | 5925: exit 0 16:24:59 INFO - Browser initialized. 16:24:59 INFO - Running cycle 1/1 for dromaeo_css test... 16:24:59 INFO - TEST-INFO | started process 6010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH5vnsk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:11 INFO - PROCESS | 6010 | RSS: Main: 151797760 16:25:11 INFO - PROCESS | 6010 | 16:28:15 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36472/tests/dromaeo/cssquery-ext.html) 16:28:17 INFO - PROCESS | 6010 | RSS: Main: 300781568 16:28:17 INFO - PROCESS | 6010 | 16:30:00 INFO - PROCESS | 6010 | ************************* 16:30:00 INFO - PROCESS | 6010 | A coding exception was thrown and uncaught in a Task. 16:30:00 INFO - PROCESS | 6010 | 16:30:00 INFO - PROCESS | 6010 | Full message: ReferenceError: fetch is not defined 16:30:00 INFO - PROCESS | 6010 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:30:00 INFO - PROCESS | 6010 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:30:00 INFO - PROCESS | 6010 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:30:00 INFO - PROCESS | 6010 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:30:00 INFO - PROCESS | 6010 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:30:00 INFO - PROCESS | 6010 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:30:00 INFO - PROCESS | 6010 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:30:00 INFO - PROCESS | 6010 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:30:00 INFO - PROCESS | 6010 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:30:00 INFO - PROCESS | 6010 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:30:00 INFO - PROCESS | 6010 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:30:00 INFO - PROCESS | 6010 | 16:30:00 INFO - PROCESS | 6010 | ************************* 16:31:20 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-ext.html (next: http://localhost:36472/tests/dromaeo/cssquery-jquery.html) 16:31:20 INFO - PROCESS | 6010 | RSS: Main: 242421760 16:31:20 INFO - PROCESS | 6010 | 16:34:23 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36472/tests/dromaeo/cssquery-mootools.html) 16:34:23 INFO - PROCESS | 6010 | RSS: Main: 183816192 16:34:23 INFO - PROCESS | 6010 | 16:37:25 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36472/tests/dromaeo/cssquery-prototype.html) 16:37:26 INFO - PROCESS | 6010 | RSS: Main: 185360384 16:37:26 INFO - PROCESS | 6010 | 16:40:29 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36472/tests/dromaeo/cssquery-yui.html) 16:40:30 INFO - PROCESS | 6010 | RSS: Main: 184365056 16:40:30 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | Cycle 1(1): loaded http://localhost:36472/tests/dromaeo/cssquery-yui.html (next: http://localhost:36472/tests/dromaeo/cssquery-dojo.html) 16:43:33 INFO - PROCESS | 6010 | RSS: Main: 272240640 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | __start_tp_report 16:43:33 INFO - PROCESS | 6010 | _x_x_mozilla_page_load 16:43:33 INFO - PROCESS | 6010 | _x_x_mozilla_page_load_details 16:43:33 INFO - PROCESS | 6010 | |i|pagename|runs| 16:43:33 INFO - PROCESS | 6010 | |0;dojo.html;2538.695356557213;2576.7809736172426;2595.416031392936;2611.2949503634018;2616.842989420634;4566.429196350457;4602.033572949685;4679.1343601433755;4701.130290896187;4710.811567537298;4665.04366604846;4709.411323584552;4715.599174070199;4742.97628511857;4807.807687692488;4231.048014196812;4234.724742891717;4267.381229721688;4313.827446902136;4375.124975005012;4292.484901811787;4353.73877567347;4380.299152135659;4389.802458889341;4434.268345722968;4235.301175306071;4259.446271984653;4366.388705581221;4373.803178856959;4440.689151759379;4242.363645453176;4280.6789490788005;4302.483701955754;4322.265214913472;4378.978105109454;4296.742195468282;4299.677524185668;4303.139372125556;4303.763293018883;4339.978300108511;4678.391809064799;4722.929156062662;4724.362211101492;4728.219843725781;4755.405574303212;4921.4094308682825;4932.926006109911;4955.603551715854;4970.453250142481;4982.5017498250245;5029.145045342264;5035.395752509686;5038.899222015539;5040.092783299005;5086.872828179288;4716.882077948045;4724.149653062482;4731.124741922756;4752.500987396343;4767.95232047682;4999.600031997432;5035.647504674637;5040.873978150576;5110.923336149961;5152.278680984665;4928.359313289249;4959.776810043557;4964.950350496449;5003.374578177728;5003.899922001612;4898;4930.383702037246;5045.142325804622;5047.369078865142;5105.33630628016;4921.9507804921495;4964.453910069889;4972.254161875748;4973.378327709036;4977.303177555145;4890.486498917634;4958.752062396866;4966.950330496648;5016.247562865599;5017.046761115376;4238.809253583596;4241.766702831374;4254.383114448449;4270.636995855325;4275.315949448073;3591.2637909228547;3608.693261072786;3662.7985460799378;3675.61406052366;3684.4841722158917;4393.692441529126;4402.163588918095;4412.691111622155;4441.244988351988;4449.310356894686;5132.615053870974;5138.640295179302;5142.434332223453;5142.922856157161;5190.403103643054;4262.041040765803;4281;4287.292596721569;4308.870733877989;4416.84541041062;4584.426946631671;4594.494605593382;4599.425071866017;4611.884702882455;4649.651276154302;4751.429828420612;4760.523947605212;4762.6189904808225;4798.1123492154065;4807.110684523374;4886.071646387175;4911.950880491149;4979.053979743837;5081.618878584195;5091.59267258611;4686.437627484724;4722.456917454399;4727.834525791581;4739.502352253102;4766.833160839354;4940.975295123501;4949.356583644248;4950.801967921243;4963.553280204799;5003.374578177728;4932.334134891744;4941.75291235444;5043.722595257296;5052.072231943219;5092.694438333712;4846.321514987975;4882.218844984785;4888.31563581106;4889.55993960531;4905.632077594123;4901.240307752304;4902.975485122552;4904.141775189256;4905.681130726492;4905.926411103907;4729.266963620645;4771.928421073617;4772.308015337826;4843.854684359475;4857.344258525054;4832.323474713613;4893.437254715611;4895.094407534618;4934.605231581537;4940.851774446773;4788.640851936049;4790.473047964651;4790.712557246577;4795.20879054952;4847.442544107332 16:43:33 INFO - PROCESS | 6010 | |1;ext.html;551.209015063384;563.5519761789377;566.5863919338883;568.6559631422991;577.1083675721011;29027.1291861244;37731.81134094329;39014.804925975404;39171;42471.57528424715;40520.392194117114;40940.38589421153;40978;40988.38517422241;41042.38436423449;7678.117016543113;7804.648790804399;7810.297073263405;7818.491798033138;7847.843043139133;7563.432742544332;7765.883511747328;9894.257930655194;10111.03945125212;10120.696379108638;9979;9987.35082219657;10020.649277275297;10031.347962382459;10049.397036177816;10033.39799612022;10097.444640544767;10114.291999560033;10125.544350504246;10126.898731012669;9928.106470417624;10008.64969726056;10009.799804003951;10014.849777253346;10015.248856335804;36646.816765916;36900.26199476022;37013.814930925444;37021.81489092564;37044.44433333502;3689.409694448902;3770.09517715749;3772.0946972726624;3806.3909774436233;3829.42558616207;28223.43553128926;28336.858315708494;28611.284717882012;28803.711962880523;29034.290164332888;3465.2722928185112;3466.376052310583;3502.387082260594;3569.3396721606587;3654.2508785699133;180002.09998950054;181220;181521;181521;181556;50063.749681251364;50239;50297.74851125721;50311;50353.24470132951;53335.19997200045;53390.7330463353;53414.19878701823;53483;53579;14300;14560.271986400638;14575.85424145745;14579.198144101963;14596.197209153599;9523.809523809623;9716.076972687584;10199.949000254952;10224.64213752515;10229.335093218917;15004.549863504113;15366.385344586315;15403.383864645513;15780.526584202493;15834.28745706446;5794.362620111784;5848.678322692208;5851.824445266649;5881.206037185011;5949.1076338549565;822.5393779483509;828.5774255129807;830.9542975136426;837.3175861672718;855.2644725536034;26892.327691807863;27762.861185693942;27801.86099069532;27886.721132788818;27917.30206744807;996.5415908682088;1005.3214080495636;1012.1245122968579;1029.330934892326;1110.6779034080025;14525.491607793889;14949.775753363709;15095.32071056805;15097.587550186669;15142.318595663222;330810;336035;336057;336584;337109;49747;49817.50182498201;49895.50104498908;49903.25145122826;49965.50034499681;1241.888230059299;1267.258653687603;1282.4036822877406;1285.0041218056267;1289.6775806048488;25452.872735636203;26275.73724262733;26813;26819.195424137306;27388.315292117855;40868.79565602272;41260.79369603133;41275.793621031706;41334.79332603318;41437.171256575304;269154;269983;270706;270949;271351;30249.395012099194;30488.798676139573;35168;35334;35351.29297414089;36262.8186859064;41060;41145.79427102965;41381.37927931023;41546.376804347354;34821.30357392889;34991;35324.641564518635;35419.11452213612;36003.27993440064;31947;34895.82552087223;35411.114722131126;35922.10194745152;36489.08777280589;31596.210094747814;32213.838930806134;34176.316473670886;34223.828880855435;34411.827940861134;26856.462870742867;28402.147935561967;28692.713072869003;29114.417711645227;29608.259793505335;22056.448588784766;22684.86575671243;22789.77210227943;22813.885930570243;22828.42928926724 16:43:33 INFO - PROCESS | 6010 | |2;jquery.html;353.4821486522246;362.71889285803513;379.61062511208974;383.46338967728474;402.5210000099881;13444.731105377887;13577.52478663247;17799.11004449777;17811;17841.553961150978;17130.914345428286;17364.56588585286;17513.299468021294;17574.736378954294;17603.687096661335;3960.722749407543;4141.068259641586;5024.874378140539;5197.506236907488;5217.895642087156;5895.469407753301;6161.199044124258;6212.347703491114;6330.588511746721;6336.96831515844;6018.187544681477;6113.23584551931;6153.384661533833;6272.184615999914;6280.748770049193;6157.969210153965;6176.629402235881;6194.5973511721595;6196.752129914798;6201.472874805647;4886.1204983103025;5091.592672586221;5092.8472145835685;5169.379674439091;5202.609804264657;20584.588308233902;20829;20999.5800083999;21152;21161;2223.12186686258;2263.173941511353;2404.603240465321;2456.680631517909;2462.409021834747;8962.193402593733;9031.322650801216;9074.274058075338;9536.28477864156;9538.475383853885;1740.730186821032;1746.1007581095746;1752.535578071812;1761.233863269469;1800.639872025587;76231;77572;79148.60425697893;80659.19340806575;80905;9876.80246395068;10205.540750666238;10234.33476824004;10278.537465813906;10308.381497110047;14344;14376.568702938928;14399.78400323996;14406.42374305037;14415.639609009859;7257.600831954188;7401.666924988388;7443.106827180773;7555.357794587411;7605;7062.540934839219;7093.538919970186;7120.074390329224;7248.637568121573;7308.269173082649;4303.677224208197;4332.895111746485;4342.283523218697;4360.542143074995;4420.84527041552;1851.8703690741518;1900.5738616250683;1910.554918482342;1915.4732448576754;1915.7030660247683;767.3439209475929;793.6705520342109;796.9521828690297;811.6307080278577;822.0382152881143;16192.352305907632;17237.56906077358;17530.386436474917;17636.735448968277;17686.646267074586;774.1136398823315;788.1999770233218;789.5460110436522;791.7980914866787;792.3542313014874;9221;9367.203787678123;9544.704882802767;9587.18508926735;9738.561764720605;486853;490268;490398;501442;503474;66205.33794661991;76884.61557692176;83820;92369.53815231015;92715;885.8626912828522;905.6830109461768;920.0477505781485;926.050797932125;929.4516235420999;14567.125972441687;14810.259487025822;14979.625509362353;15218.239088045775;15351.539453816404;36634.084147896516;38562;38661.6133838658;39766.60233397629;40369;55707.72146139244;55820;56373.15440268317;56480;56834;7721.382289416721;7781.26078022587;7851.60741962911;7898.842023159619;7977.8404431912195;10143.7464063399;11855.347955862166;12213.679955615256;12224.449899754354;12686.302253376152;10003;10129.696109116738;10135.341202821794;10396.480175991019;10993;10171.593136274761;10457.738556536147;10553.947230263799;10616.946915265375;10790.244682872124;10340.689779306633;10402.531886065297;10598.25812193139;10848.67453976382;11112.277701949348;7720.1196256491685;7723.34351580122;7877.054753429625;7904.920950790649;8259.710910118118;8847.601857916537;9834.60661573529;10078.596856125967;10323.845142323022;11003.669889903316 16:43:33 INFO - PROCESS | 6010 | |3;mootools.html;1309;1314.0867097367334;1324.7615429222735;1329.335332333833;1335.1188215777588;6604.075429439877;6977.476689248314;6982.1272340957385;7004.64976751163;7018.719251229951;6111.847203819907;6306.558540902139;6641.335866413357;6802.625855577953;6894.172699276081;2079.0768539101346;2128.19128731082;2129.7337832770904;2131.5630295789324;2133.0934352900035;2063;2082.9895850520725;2086.7182930304443;2087.332053742803;2090.205721825704;1813.4650278167971;1863.7390765292869;2023.6458619741486;2055.7533096028496;2076.771555128942;2065.824404925583;2068.4311814251128;2068.813806757399;2070.3064473401428;2072.720182775321;2011.5474018345901;2029.269462993336;2031.1266155553105;2042.1116814185727;2068.162394230339;7034.8944765828555;7087.929120708778;7089.291070892921;7090.787276381665;7102.928970710278;4271.9359209611885;4362.847300344472;4365.476142862845;4373.803178856927;4383.583559561836;2690.596410538435;2816.605675205473;2834.957475637867;2850.1307101334087;2897.0294951191377;1085.3270971997333;1122.5622007417114;1134.4554613785347;1134.7446824464512;1182.326074137742;9103.635854565875;9109.817803643957;9124.5437728114;9133.178013978708;9139.223166030895;3712.443133529938;3894.1627550076305;3914.4911161549385;3976.701747368959;3983.203359328146;7935.682572697143;7937.484063535851;7947.284744373034;7955.4033447491665;7956.920430795618;4388.166248412791;4389.582989615981;4390.626796722315;4393.780310984438;4397.2304846651705;3556.946645800315;3666.8533258669886;3876.282887665791;4022.416749571354;4029.979850100731;3330.7501937354796;3348.162959260185;3399.0822477931315;3414.197663549064;3489.900681285387;1100.6863044032411;1130.7455822439886;1130.8812574679705;1137.4085475552665;1138.7380902392497;643.3437893348759;653.8234676637281;768.2356055724479;812.8171161488737;841.3857883744778;4447.977760111179;4524.6380289576755;4756.500567440369;4943.209086546135;4961.627877909171;857.7476883749795;862.288611895189;869.860822268434;871.2942516561606;908.6047569307373;3101.7828751987145;3158.4156930967843;3279.950800737991;3354.9329013419597;3363.1928337198897;8206.794830129294;8430.494170349799;8474.533900635524;8527.872081918777;8542.829143417097;8479.533625650649;8487.787805304917;8493.872591911002;8497;8497.915020849836;6405.35946405365;6476.125723027331;6634.336566343423;6758.560693554903;6772.458203343721;4517.277235642281;4532.410786597853;4544.590986811203;4578.03861189154;4735.12400205963;7476.850462990818;7482.438817088631;7513.737019204302;7520.774376768706;7559.659815308214;7678.462507624413;7682.500637458547;7692.730754423568;7727.806804829925;7727.884081738891;5376.596755243294;5526.613137080366;5756.510696590669;5853.590248682552;5869.442402971711;6175.567710260419;6326.177596912372;6331.2085989251345;6451.1290975717675;6460.3862633049785;5796.681182535001;5857.765689372548;5868.706564671835;5891.617044892068;5938.020226662552;5781.8554536136935;5802.4197580241635;5813.09896965971;5847.766089356378;5964.8807023860145;5008.599312054954;5078.873028174177;5118.820841270538;5119.180931051014;5147.9227811583605;4067.8169482372773;4082.6121518455698;4162.895927601834;4185;4287.185434767384;4015.799210039545;4024.1951609677594;4128.298189307765;4152.2525945330135;4213.199492096492 16:43:33 INFO - PROCESS | 6010 | |4;prototype.html;331.29764898415345;348.5190437196668;349.9982500087499;352.07755680118095;353.78949525032596;4876.171050921343;4886.780094895729;4895.437024742151;4927.359443272374;4947.876303092425;4571.6571257155765;4852.126617208901;4852.611791056715;4866.683665561736;4896.265560165977;3778.338790711628;3819.6753275971505;3871.8838434846857;3895;3925.411188321746;3883.514560679915;3906.5507466641275;3906.960930390688;3923.117298607819;3931.7051221158385;3873.288697911459;3874.9806250968704;3887.611238876104;3889.2804831106196;3895.376739721645;3641.2717456508803;3731.738778285535;3882.5535063467782;3893.1629699614437;3905.3556163233034;3507.771994820354;3511.9824400877833;3513.244652399721;3517.9648203517895;3534.770239934396;4714.952850471485;4725.834595789165;4733.431988161443;4733.5503127202865;4764.714117152947;4348;4470.435347628304;4479.395281637003;4485.147821913826;4485.846793539119;4999.700017998932;5018.422881368653;5019.498050194988;5074.13739664258;5081.263216833576;3837.1558257183374;3858.2283543291173;3865.768053916746;3881.874256465622;3918.1380096378753;5097.5981605058905;5294.523492885659;5338.706371149585;5355.839324820262;5371.489708477728;3926.312895243321;3957.782321972262;3962.1868473383643;3977.3238549446664;3979.5025621797276;4024.6981476389387;4032.7580345179385;4037.2127435149864;4046.291898917678;4053.8783836484954;4433.490148632948;4468.754218518013;4475.843345482893;4477.753723545236;4500.819967201341;4875.293082503017;5024.346834911438;5067.417247016567;5070.692834760175;5100.336956195671;1337.7525157845635;1349.5276653171509;1352.3441131051432;1354.3972932045342;1356.7286542691304;917.8980425606142;980.6861804222723;983.9373476645349;986.9062439068277;997.581015973293;4256.34026725858;4362.520122786491;4376.627986621173;4398.780060996937;4401.317795741666;5075.8984820303385;5126.79492820283;5145.3568303962;5193.792248310101;5231.55531779803;4395.802188901507;4405.29515277554;4428.560717766739;4429.715382539092;4436.26801577736;2601.465135570023;2644.6297518347446;2660.9733902661114;2672.665916760405;2692.555728304808;4669.579737823544;4678.157931572349;4684.414448193976;4698.248280275208;4744.95255047452;5091.847244582668;5106.744662766847;5119.744012799345;5131.589472842164;5155.845324640267;4549.340345649863;4785.731781078087;4789.856304310876;4803.639727020487;4814.903701925942;5141.922871156861;5154.871128221825;5168.405633352213;5185.825410544548;5196.584273258055;4906.2164389721165;5018.673786203885;5046.6719663221775;5090.872728181825;5116.058266496705;4814.831480898151;4858.975705121452;4862.5380588844;4862.902741945212;4866.318715379921;3668.3030224258373;3680.5767336756617;3684.6499582539614;3688.5758137813423;3696.537932758405;5193.324867767166;5215.452377500308;5270.683758974474;5272.525472707474;5283.471652834686;4905.632077594266;5007.7245751484015;5013.523715247045;5020;5029.874253143701;4962.139197800825;5020.6234532410945;5023.81940244035;5039.874003149951;5056.266841307916;4825.927611085906;4970.403551573834;4972.776225069808;4973.303737476684;4986.900261994812;4811.759412029454;4931.408231012301;5050.873728156826;5066.2400639902835;5080.46655101209;4808.543188397097;4930.556249937523;4935.555799978019;4941.332920055746;4945.62907781925 16:43:33 INFO - PROCESS | 6010 | |5;yui.html;637.4740838807985;651.9217693876735;720.7801620505747;760.1296515489764;772.7565816767718;12321.815172772405;13235.205887646747;13285.800712989312;13287.534936277232;13444.865551344483;13268.734625307488;13277.535286264983;13337.199768013901;13381.66545836352;13475.797863032063;666.53009628212;683.8734834055691;786.8622990976585;804.613785383015;811.8863359129726;817.6075483767795;819.5001049359915;823.9988414076895;824.2705205892798;824.8680211166214;870.5995242188601;871.4596949891045;871.9564021798884;872.2672954718035;873.1661263493348;695.600029982762;713.8144082538525;862.7886167888845;866.7356456280831;867.0722327109995;612.8896798576254;626.8777588370269;631.1794666932968;631.516889579472;632.0834789555117;13597.252151131685;13637.590872273848;13695.31523423825;13696.315184240748;13699.383527741176;12546.68633284166;12671.239725616493;12728;12840.678983025498;13001;11725;11818.468168932333;11848.052155827512;12121.818172727417;12226.449809758582;3990.4413382126527;4007.298722723541;4036.5156181258144;4205.222033923704;4210.831566737327;59273;59618.525344241294;60487.46344024172;61758;61981.38018619801;9661.661841835503;9788.168005719452;9812.460314682618;9849.409035457895;9876.111149996535;16948.23732932021;16998.150092495325;17089.829101708823;17159.485215443558;17270.05014724177;6347.904781428282;6386.16979792624;6419.101325814389;6492.707828147744;6638.701258443381;3417.6411476795074;3491.4762785581856;3538.893833184957;3554.662307080823;3581.6239294873662;2777.777777777773;2806.480801051812;2823.39297051135;2827.915162545127;2846;456.4431393699681;467.9438467383936;514.2440612299913;526.9868253293622;541.0450554770754;560.4171661472105;563.884403697241;569.5386736743251;571.894199573072;572.8539222498237;8750.956245218733;8961.507117108622;9043.728688139367;9128.680496182735;9181.908180918239;574.371063685264;594.5903163893238;607.6779306967314;608.40984245282;621.3506885304868;3884.941725874114;4095.447114639545;4097.221527909687;4188.4136220929095;4203.5376108628025;62746.37253627497;64104.679476602316;66599.33400666028;66616.33383666199;67318;57377.713111434176;59860;59974.70012649996;60340.698296508235;60950;52214.73892630589;52438;52614.73692631513;53053;55256.44743552513;7656.73201437947;7799.727009554638;7931.682732690628;7949.602519873867;8116.228958248957;37348.6265137356;37476.625233748404;37797.811010944766;38173.80913095417;39052;42370.78814605907;45176.77411612921;45663.31505027361;45810.31284530801;47986.520134799604;9494.762630934281;9658.517074146124;9707.417554946725;9769.560369783234;9867.753306167404;10721.249512534048;10775.892241077803;10944.726381840199;11016.614418495314;11137.777244455228;9973.45146016947;10095.394276343442;10208.744781380525;10446.216533760151;10548.683539493828;9151.130642588943;9846.655367062118;9882.703518894445;10077.899221007696;10253.282270241012;9583;10128.746781330527;10149.695509134737;10310.07209351162;10457.68626941193;8127.715529956423;8389.454685445426;8403.621837017472;8606.182412670787;8978.640854365753;8520.3183745299;8568.614412351584;9710.514474276399;9837.294743945473;9888.208943284664 16:43:33 INFO - PROCESS | 6010 | __end_tp_report 16:43:33 INFO - PROCESS | 6010 | __start_cc_report 16:43:33 INFO - PROCESS | 6010 | _x_x_mozilla_cycle_collect,1487 16:43:33 INFO - PROCESS | 6010 | __end_cc_report 16:43:33 INFO - PROCESS | 6010 | __startTimestamp1483749813552__endTimestamp 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | ------- Summary: start ------- 16:43:33 INFO - PROCESS | 6010 | Number of tests: 6 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#0] dojo.html Cycles:165 Average:4640.87 Median:4767.95 stddev:490.82 (10.3%) stddev-sans-first:463.80 16:43:33 INFO - PROCESS | 6010 | Values: 2538.7 2576.8 2595.4 2611.3 2616.8 4566.4 4602.0 4679.1 4701.1 4710.8 4665.0 4709.4 4715.6 4743.0 4807.8 4231.0 4234.7 4267.4 4313.8 4375.1 4292.5 4353.7 4380.3 4389.8 4434.3 4235.3 4259.4 4366.4 4373.8 4440.7 4242.4 4280.7 4302.5 4322.3 4379.0 4296.7 4299.7 4303.1 4303.8 4340.0 4678.4 4722.9 4724.4 4728.2 4755.4 4921.4 4932.9 4955.6 4970.5 4982.5 5029.1 5035.4 5038.9 5040.1 5086.9 4716.9 4724.1 4731.1 4752.5 4768.0 4999.6 5035.6 5040.9 5110.9 5152.3 4928.4 4959.8 4965.0 5003.4 5003.9 4898.0 4930.4 5045.1 5047.4 5105.3 4922.0 4964.5 4972.3 4973.4 4977.3 4890.5 4958.8 4967.0 5016.2 5017.0 4238.8 4241.8 4254.4 4270.6 4275.3 3591.3 3608.7 3662.8 3675.6 3684.5 4393.7 4402.2 4412.7 4441.2 4449.3 5132.6 5138.6 5142.4 5142.9 5190.4 4262.0 4281.0 4287.3 4308.9 4416.8 4584.4 4594.5 4599.4 4611.9 4649.7 4751.4 4760.5 4762.6 4798.1 4807.1 4886.1 4912.0 4979.1 5081.6 5091.6 4686.4 4722.5 4727.8 4739.5 4766.8 4941.0 4949.4 4950.8 4963.6 5003.4 4932.3 4941.8 5043.7 5052.1 5092.7 4846.3 4882.2 4888.3 4889.6 4905.6 4901.2 4903.0 4904.1 4905.7 4905.9 4729.3 4771.9 4772.3 4843.9 4857.3 4832.3 4893.4 4895.1 4934.6 4940.9 4788.6 4790.5 4790.7 4795.2 4847.4 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#1] ext.html Cycles:180 Average:42415.44 Median:3465.82 stddev:70688.13 (2039.6%) stddev-sans-first:70816.54 16:43:33 INFO - PROCESS | 6010 | Values: 551.2 563.6 566.6 568.7 577.1 29027.1 37731.8 39014.8 39171.0 42471.6 40520.4 40940.4 40978.0 40988.4 41042.4 7678.1 7804.6 7810.3 7818.5 7847.8 7563.4 7765.9 9894.3 10111.0 10120.7 9979.0 9987.4 10020.6 10031.3 10049.4 10033.4 10097.4 10114.3 10125.5 10126.9 9928.1 10008.6 10009.8 10014.8 10015.2 36646.8 36900.3 37013.8 37021.8 37044.4 3689.4 3770.1 3772.1 3806.4 3829.4 28223.4 28336.9 28611.3 28803.7 29034.3 3465.3 3466.4 3502.4 3569.3 3654.3 180002.1 181220.0 181521.0 181521.0 181556.0 50063.7 50239.0 50297.7 50311.0 50353.2 53335.2 53390.7 53414.2 53483.0 53579.0 14300.0 14560.3 14575.9 14579.2 14596.2 9523.8 9716.1 10199.9 10224.6 10229.3 15004.5 15366.4 15403.4 15780.5 15834.3 5794.4 5848.7 5851.8 5881.2 5949.1 822.5 828.6 831.0 837.3 855.3 26892.3 27762.9 27801.9 27886.7 27917.3 996.5 1005.3 1012.1 1029.3 1110.7 14525.5 14949.8 15095.3 15097.6 15142.3 330810.0 336035.0 336057.0 336584.0 337109.0 49747.0 49817.5 49895.5 49903.3 49965.5 1241.9 1267.3 1282.4 1285.0 1289.7 25452.9 26275.7 26813.0 26819.2 27388.3 40868.8 41260.8 41275.8 41334.8 41437.2 269154.0 269983.0 270706.0 270949.0 271351.0 30249.4 30488.8 35168.0 35334.0 35351.3 36262.8 41060.0 41145.8 41381.4 41546.4 34821.3 34991.0 35324.6 35419.1 36003.3 31947.0 34895.8 35411.1 35922.1 36489.1 31596.2 32213.8 34176.3 34223.8 34411.8 26856.5 28402.1 28692.7 29114.4 29608.3 22056.4 22684.9 22789.8 22813.9 22828.4 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#2] jquery.html Cycles:180 Average:27948.34 Median:247577.06 stddev:81406.29 (32.9%) stddev-sans-first:81608.29 16:43:33 INFO - PROCESS | 6010 | Values: 353.5 362.7 379.6 383.5 402.5 13444.7 13577.5 17799.1 17811.0 17841.6 17130.9 17364.6 17513.3 17574.7 17603.7 3960.7 4141.1 5024.9 5197.5 5217.9 5895.5 6161.2 6212.3 6330.6 6337.0 6018.2 6113.2 6153.4 6272.2 6280.7 6158.0 6176.6 6194.6 6196.8 6201.5 4886.1 5091.6 5092.8 5169.4 5202.6 20584.6 20829.0 20999.6 21152.0 21161.0 2223.1 2263.2 2404.6 2456.7 2462.4 8962.2 9031.3 9074.3 9536.3 9538.5 1740.7 1746.1 1752.5 1761.2 1800.6 76231.0 77572.0 79148.6 80659.2 80905.0 9876.8 10205.5 10234.3 10278.5 10308.4 14344.0 14376.6 14399.8 14406.4 14415.6 7257.6 7401.7 7443.1 7555.4 7605.0 7062.5 7093.5 7120.1 7248.6 7308.3 4303.7 4332.9 4342.3 4360.5 4420.8 1851.9 1900.6 1910.6 1915.5 1915.7 767.3 793.7 797.0 811.6 822.0 16192.4 17237.6 17530.4 17636.7 17686.6 774.1 788.2 789.5 791.8 792.4 9221.0 9367.2 9544.7 9587.2 9738.6 486853.0 490268.0 490398.0 501442.0 503474.0 66205.3 76884.6 83820.0 92369.5 92715.0 885.9 905.7 920.0 926.1 929.5 14567.1 14810.3 14979.6 15218.2 15351.5 36634.1 38562.0 38661.6 39766.6 40369.0 55707.7 55820.0 56373.2 56480.0 56834.0 7721.4 7781.3 7851.6 7898.8 7977.8 10143.7 11855.3 12213.7 12224.4 12686.3 10003.0 10129.7 10135.3 10396.5 10993.0 10171.6 10457.7 10553.9 10616.9 10790.2 10340.7 10402.5 10598.3 10848.7 11112.3 7720.1 7723.3 7877.1 7904.9 8259.7 8847.6 9834.6 10078.6 10323.8 11003.7 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#3] mootools.html Cycles:180 Average:4560.66 Median:4656.58 stddev:2427.54 (52.1%) stddev-sans-first:2422.05 16:43:33 INFO - PROCESS | 6010 | Values: 1309.0 1314.1 1324.8 1329.3 1335.1 6604.1 6977.5 6982.1 7004.6 7018.7 6111.8 6306.6 6641.3 6802.6 6894.2 2079.1 2128.2 2129.7 2131.6 2133.1 2063.0 2083.0 2086.7 2087.3 2090.2 1813.5 1863.7 2023.6 2055.8 2076.8 2065.8 2068.4 2068.8 2070.3 2072.7 2011.5 2029.3 2031.1 2042.1 2068.2 7034.9 7087.9 7089.3 7090.8 7102.9 4271.9 4362.8 4365.5 4373.8 4383.6 2690.6 2816.6 2835.0 2850.1 2897.0 1085.3 1122.6 1134.5 1134.7 1182.3 9103.6 9109.8 9124.5 9133.2 9139.2 3712.4 3894.2 3914.5 3976.7 3983.2 7935.7 7937.5 7947.3 7955.4 7956.9 4388.2 4389.6 4390.6 4393.8 4397.2 3556.9 3666.9 3876.3 4022.4 4030.0 3330.8 3348.2 3399.1 3414.2 3489.9 1100.7 1130.7 1130.9 1137.4 1138.7 643.3 653.8 768.2 812.8 841.4 4448.0 4524.6 4756.5 4943.2 4961.6 857.7 862.3 869.9 871.3 908.6 3101.8 3158.4 3280.0 3354.9 3363.2 8206.8 8430.5 8474.5 8527.9 8542.8 8479.5 8487.8 8493.9 8497.0 8497.9 6405.4 6476.1 6634.3 6758.6 6772.5 4517.3 4532.4 4544.6 4578.0 4735.1 7476.9 7482.4 7513.7 7520.8 7559.7 7678.5 7682.5 7692.7 7727.8 7727.9 5376.6 5526.6 5756.5 5853.6 5869.4 6175.6 6326.2 6331.2 6451.1 6460.4 5796.7 5857.8 5868.7 5891.6 5938.0 5781.9 5802.4 5813.1 5847.8 5964.9 5008.6 5078.9 5118.8 5119.2 5147.9 4067.8 4082.6 4162.9 4185.0 4287.2 4015.8 4024.2 4128.3 4152.3 4213.2 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#4] prototype.html Cycles:180 Average:4229.41 Median:4754.83 stddev:1177.21 (24.8%) stddev-sans-first:1143.58 16:43:33 INFO - PROCESS | 6010 | Values: 331.3 348.5 350.0 352.1 353.8 4876.2 4886.8 4895.4 4927.4 4947.9 4571.7 4852.1 4852.6 4866.7 4896.3 3778.3 3819.7 3871.9 3895.0 3925.4 3883.5 3906.6 3907.0 3923.1 3931.7 3873.3 3875.0 3887.6 3889.3 3895.4 3641.3 3731.7 3882.6 3893.2 3905.4 3507.8 3512.0 3513.2 3518.0 3534.8 4715.0 4725.8 4733.4 4733.6 4764.7 4348.0 4470.4 4479.4 4485.1 4485.8 4999.7 5018.4 5019.5 5074.1 5081.3 3837.2 3858.2 3865.8 3881.9 3918.1 5097.6 5294.5 5338.7 5355.8 5371.5 3926.3 3957.8 3962.2 3977.3 3979.5 4024.7 4032.8 4037.2 4046.3 4053.9 4433.5 4468.8 4475.8 4477.8 4500.8 4875.3 5024.3 5067.4 5070.7 5100.3 1337.8 1349.5 1352.3 1354.4 1356.7 917.9 980.7 983.9 986.9 997.6 4256.3 4362.5 4376.6 4398.8 4401.3 5075.9 5126.8 5145.4 5193.8 5231.6 4395.8 4405.3 4428.6 4429.7 4436.3 2601.5 2644.6 2661.0 2672.7 2692.6 4669.6 4678.2 4684.4 4698.2 4745.0 5091.8 5106.7 5119.7 5131.6 5155.8 4549.3 4785.7 4789.9 4803.6 4814.9 5141.9 5154.9 5168.4 5185.8 5196.6 4906.2 5018.7 5046.7 5090.9 5116.1 4814.8 4859.0 4862.5 4862.9 4866.3 3668.3 3680.6 3684.6 3688.6 3696.5 5193.3 5215.5 5270.7 5272.5 5283.5 4905.6 5007.7 5013.5 5020.0 5029.9 4962.1 5020.6 5023.8 5039.9 5056.3 4825.9 4970.4 4972.8 4973.3 4986.9 4811.8 4931.4 5050.9 5066.2 5080.5 4808.5 4930.6 4935.6 4941.3 4945.6 16:43:33 INFO - PROCESS | 6010 | 16:43:33 INFO - PROCESS | 6010 | [#5] yui.html Cycles:180 Average:14633.06 Median:28976.04 stddev:18539.40 (64.0%) stddev-sans-first:18561.62 16:43:33 INFO - PROCESS | 6010 | Values: 637.5 651.9 720.8 760.1 772.8 12321.8 13235.2 13285.8 13287.5 13444.9 13268.7 13277.5 13337.2 13381.7 13475.8 666.5 683.9 786.9 804.6 811.9 817.6 819.5 824.0 824.3 824.9 870.6 871.5 872.0 872.3 873.2 695.6 713.8 862.8 866.7 867.1 612.9 626.9 631.2 631.5 632.1 13597.3 13637.6 13695.3 13696.3 13699.4 12546.7 12671.2 12728.0 12840.7 13001.0 11725.0 11818.5 11848.1 12121.8 12226.4 3990.4 4007.3 4036.5 4205.2 4210.8 59273.0 59618.5 60487.5 61758.0 61981.4 9661.7 9788.2 9812.5 9849.4 9876.1 16948.2 16998.2 17089.8 17159.5 17270.1 6347.9 6386.2 6419.1 6492.7 6638.7 3417.6 3491.5 3538.9 3554.7 3581.6 2777.8 2806.5 2823.4 2827.9 2846.0 456.4 467.9 514.2 527.0 541.0 560.4 563.9 569.5 571.9 572.9 8751.0 8961.5 9043.7 9128.7 9181.9 574.4 594.6 607.7 608.4 621.4 3884.9 4095.4 4097.2 4188.4 4203.5 62746.4 64104.7 66599.3 66616.3 67318.0 57377.7 59860.0 59974.7 60340.7 60950.0 52214.7 52438.0 52614.7 53053.0 55256.4 7656.7 7799.7 7931.7 7949.6 8116.2 37348.6 37476.6 37797.8 38173.8 39052.0 42370.8 45176.8 45663.3 45810.3 47986.5 9494.8 9658.5 9707.4 9769.6 9867.8 10721.2 10775.9 10944.7 11016.6 11137.8 9973.5 10095.4 10208.7 10446.2 10548.7 9151.1 9846.7 9882.7 10077.9 10253.3 9583.0 10128.7 10149.7 10310.1 10457.7 8127.7 8389.5 8403.6 8606.2 8978.6 8520.3 8568.6 9710.5 9837.3 9888.2 16:43:33 INFO - PROCESS | 6010 | -------- Summary: end -------- 16:43:33 INFO - PROCESS | 6010 | 16:43:34 INFO - TEST-INFO | 6010: exit 0 16:43:34 INFO - TEST-OK | dromaeo_css | took 1120677ms 16:43:34 INFO - TEST-START | kraken 16:43:34 INFO - operating with platform_type : linux_ 16:43:34 INFO - Initialising browser for kraken test... 16:43:34 INFO - TEST-INFO | started process 6311 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpheJAvD/profile http://localhost:36472/getInfo.html) 16:43:34 INFO - PROCESS | 6311 | 1483749814858 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 16:43:38 INFO - PROCESS | 6311 | __metrics Screen width/height:1600/1200 16:43:38 INFO - PROCESS | 6311 | colorDepth:24 16:43:38 INFO - PROCESS | 6311 | Browser inner width/height: 1024/697 16:43:38 INFO - PROCESS | 6311 | __metrics 16:43:38 INFO - PROCESS | 6311 | 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" 16:43:38 INFO - PROCESS | 6311 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:43:39 INFO - PROCESS | 6311 | console.error: PushService: 16:43:39 INFO - PROCESS | 6311 | stateChangeProcessEnqueue: Error transitioning state 16:43:39 INFO - PROCESS | 6311 | UnknownError 16:43:40 INFO - TEST-INFO | 6311: exit 0 16:43:40 INFO - Browser initialized. 16:43:40 INFO - Running cycle 1/1 for kraken test... 16:43:40 INFO - TEST-INFO | started process 6391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpheJAvD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:52 INFO - PROCESS | 6391 | RSS: Main: 151568384 16:43:52 INFO - PROCESS | 6391 | 16:43:54 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36472/tests/kraken/driver.html?testName=audio-beat-detection) 16:43:55 INFO - PROCESS | 6391 | RSS: Main: 205852672 16:43:55 INFO - PROCESS | 6391 | 16:43:58 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36472/tests/kraken/driver.html?testName=audio-dft) 16:43:59 INFO - PROCESS | 6391 | RSS: Main: 185114624 16:43:59 INFO - PROCESS | 6391 | 16:44:02 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36472/tests/kraken/driver.html?testName=audio-fft) 16:44:02 INFO - PROCESS | 6391 | RSS: Main: 268152832 16:44:02 INFO - PROCESS | 6391 | 16:44:05 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36472/tests/kraken/driver.html?testName=audio-oscillator) 16:44:05 INFO - PROCESS | 6391 | RSS: Main: 177901568 16:44:05 INFO - PROCESS | 6391 | 16:44:07 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36472/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:44:08 INFO - PROCESS | 6391 | RSS: Main: 183152640 16:44:08 INFO - PROCESS | 6391 | 16:44:11 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36472/tests/kraken/driver.html?testName=imaging-darkroom) 16:44:12 INFO - PROCESS | 6391 | RSS: Main: 221724672 16:44:12 INFO - PROCESS | 6391 | 16:44:15 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36472/tests/kraken/driver.html?testName=imaging-desaturate) 16:44:16 INFO - PROCESS | 6391 | RSS: Main: 215912448 16:44:16 INFO - PROCESS | 6391 | 16:44:19 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36472/tests/kraken/driver.html?testName=json-parse-financial) 16:44:19 INFO - PROCESS | 6391 | RSS: Main: 217501696 16:44:19 INFO - PROCESS | 6391 | 16:44:21 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36472/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:44:22 INFO - PROCESS | 6391 | RSS: Main: 181219328 16:44:22 INFO - PROCESS | 6391 | 16:44:23 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:44:24 INFO - PROCESS | 6391 | RSS: Main: 189136896 16:44:24 INFO - PROCESS | 6391 | 16:44:26 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:44:27 INFO - PROCESS | 6391 | RSS: Main: 195801088 16:44:27 INFO - PROCESS | 6391 | 16:44:30 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:44:30 INFO - PROCESS | 6391 | RSS: Main: 199987200 16:44:30 INFO - PROCESS | 6391 | 16:44:33 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:44:34 INFO - PROCESS | 6391 | RSS: Main: 202149888 16:44:34 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | Cycle 1(1): loaded http://localhost:36472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36472/tests/kraken/driver.html?testName=ai-astar) 16:44:36 INFO - PROCESS | 6391 | RSS: Main: 253218816 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | __start_tp_report 16:44:36 INFO - PROCESS | 6391 | _x_x_mozilla_page_load 16:44:36 INFO - PROCESS | 6391 | _x_x_mozilla_page_load_details 16:44:36 INFO - PROCESS | 6391 | |i|pagename|runs| 16:44:36 INFO - PROCESS | 6391 | |0;ai-astar;106;115;115;115;114;114;115;114;114;113 16:44:36 INFO - PROCESS | 6391 | |1;audio-beat-detection;156;128;131;152;132;128;122;123;151;129 16:44:36 INFO - PROCESS | 6391 | |2;audio-dft;161;161;160;164;163;163;162;162;161;161 16:44:36 INFO - PROCESS | 6391 | |3;audio-fft;76;78;76;107;76;76;76;77;91;77 16:44:36 INFO - PROCESS | 6391 | |4;audio-oscillator;85;85;111;86;85;84;97;84;85;113 16:44:36 INFO - PROCESS | 6391 | |5;imaging-gaussian-blur;117;117;117;116;117;117;118;119;118;118 16:44:36 INFO - PROCESS | 6391 | |6;imaging-darkroom;149;149;150;149;149;149;150;148;148;150 16:44:36 INFO - PROCESS | 6391 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;96;95 16:44:36 INFO - PROCESS | 6391 | |8;json-parse-financial;60;59;59;59;60;62;61;63;60;61 16:44:36 INFO - PROCESS | 6391 | |9;json-stringify-tinderbox;66;68;66;68;68;68;67;66;67;67 16:44:36 INFO - PROCESS | 6391 | |10;stanford-crypto-aes;90;93;83;84;96;84;85;85;84;84 16:44:36 INFO - PROCESS | 6391 | |11;stanford-crypto-ccm;135;147;133;143;138;134;131;130;207;135 16:44:36 INFO - PROCESS | 6391 | |12;stanford-crypto-pbkdf2;178;183;174;175;180;177;177;176;182;178 16:44:36 INFO - PROCESS | 6391 | |13;stanford-crypto-sha256-iterative;67;68;68;68;67;67;67;69;65;70 16:44:36 INFO - PROCESS | 6391 | __end_tp_report 16:44:36 INFO - PROCESS | 6391 | __start_cc_report 16:44:36 INFO - PROCESS | 6391 | _x_x_mozilla_cycle_collect,1836 16:44:36 INFO - PROCESS | 6391 | __end_cc_report 16:44:36 INFO - PROCESS | 6391 | __startTimestamp1483749876569__endTimestamp 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | ------- Summary: start ------- 16:44:36 INFO - PROCESS | 6391 | Number of tests: 14 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 16:44:36 INFO - PROCESS | 6391 | Values: 106.0 115.0 115.0 115.0 114.0 114.0 115.0 114.0 114.0 113.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:131.50 stddev:12.73 (9.7%) stddev-sans-first:11.05 16:44:36 INFO - PROCESS | 6391 | Values: 156.0 128.0 131.0 152.0 132.0 128.0 122.0 123.0 151.0 129.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 16:44:36 INFO - PROCESS | 6391 | Values: 161.0 161.0 160.0 164.0 163.0 163.0 162.0 162.0 161.0 161.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.50 stddev:10.23 (13.4%) stddev-sans-first:10.69 16:44:36 INFO - PROCESS | 6391 | Values: 76.0 78.0 76.0 107.0 76.0 76.0 76.0 77.0 91.0 77.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:85.00 stddev:11.47 (13.5%) stddev-sans-first:11.92 16:44:36 INFO - PROCESS | 6391 | Values: 85.0 85.0 111.0 86.0 85.0 84.0 97.0 84.0 85.0 113.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 16:44:36 INFO - PROCESS | 6391 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 118.0 119.0 118.0 118.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 16:44:36 INFO - PROCESS | 6391 | Values: 149.0 149.0 150.0 149.0 149.0 149.0 150.0 148.0 148.0 150.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:44:36 INFO - PROCESS | 6391 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.50 stddev:1.35 (2.2%) stddev-sans-first:1.42 16:44:36 INFO - PROCESS | 6391 | Values: 60.0 59.0 59.0 59.0 60.0 62.0 61.0 63.0 60.0 61.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 16:44:36 INFO - PROCESS | 6391 | Values: 66.0 68.0 66.0 68.0 68.0 68.0 67.0 66.0 67.0 67.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.00 stddev:4.54 (5.3%) stddev-sans-first:4.67 16:44:36 INFO - PROCESS | 6391 | Values: 90.0 93.0 83.0 84.0 96.0 84.0 85.0 85.0 84.0 84.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:136.50 stddev:22.99 (16.8%) stddev-sans-first:24.19 16:44:36 INFO - PROCESS | 6391 | Values: 135.0 147.0 133.0 143.0 138.0 134.0 131.0 130.0 207.0 135.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:178.00 stddev:2.91 (1.6%) stddev-sans-first:3.08 16:44:36 INFO - PROCESS | 6391 | Values: 178.0 183.0 174.0 175.0 180.0 177.0 177.0 176.0 182.0 178.0 16:44:36 INFO - PROCESS | 6391 | 16:44:36 INFO - PROCESS | 6391 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.41 16:44:36 INFO - PROCESS | 6391 | Values: 67.0 68.0 68.0 68.0 67.0 67.0 67.0 69.0 65.0 70.0 16:44:36 INFO - PROCESS | 6391 | -------- Summary: end -------- 16:44:36 INFO - PROCESS | 6391 | 16:44:37 INFO - TEST-INFO | 6391: exit 0 16:44:37 INFO - TEST-OK | kraken | took 63360ms 16:44:37 INFO - SUITE-END | took 1184s 16:44:38 INFO - Completed test suite (00:19:44) 16:44:38 INFO - javascript benchmark 16:44:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2538.695356557213, 2576.7809736172426, 2595.416031392936, 2611.2949503634018, 2616.842989420634, 4566.429196350457, 4602.033572949685, 4679.1343601433755, 4701.130290896187, 4710.811567537298, 4665.04366604846, 4709.411323584552, 4715.599174070199, 4742.97628511857, 4807.807687692488, 4231.048014196812, 4234.724742891717, 4267.381229721688, 4313.827446902136, 4375.124975005012, 4292.484901811787, 4353.73877567347, 4380.299152135659, 4389.802458889341, 4434.268345722968, 4235.301175306071, 4259.446271984653, 4366.388705581221, 4373.803178856959, 4440.689151759379, 4242.363645453176, 4280.6789490788005, 4302.483701955754, 4322.265214913472, 4378.978105109454, 4296.742195468282, 4299.677524185668, 4303.139372125556, 4303.763293018883, 4339.978300108511, 4678.391809064799, 4722.929156062662, 4724.362211101492, 4728.219843725781, 4755.405574303212, 4921.4094308682825, 4932.926006109911, 4955.603551715854, 4970.453250142481, 4982.5017498250245, 5029.145045342264, 5035.395752509686, 5038.899222015539, 5040.092783299005, 5086.872828179288, 4716.882077948045, 4724.149653062482, 4731.124741922756, 4752.500987396343, 4767.95232047682, 4999.600031997432, 5035.647504674637, 5040.873978150576, 5110.923336149961, 5152.278680984665, 4928.359313289249, 4959.776810043557, 4964.950350496449, 5003.374578177728, 5003.899922001612, 4898.0, 4930.383702037246, 5045.142325804622, 5047.369078865142, 5105.33630628016, 4921.9507804921495, 4964.453910069889, 4972.254161875748, 4973.378327709036, 4977.303177555145, 4890.486498917634, 4958.752062396866, 4966.950330496648, 5016.247562865599, 5017.046761115376, 4238.809253583596, 4241.766702831374, 4254.383114448449, 4270.636995855325, 4275.315949448073, 3591.2637909228547, 3608.693261072786, 3662.7985460799378, 3675.61406052366, 3684.4841722158917, 4393.692441529126, 4402.163588918095, 4412.691111622155, 4441.244988351988, 4449.310356894686, 5132.615053870974, 5138.640295179302, 5142.434332223453, 5142.922856157161, 5190.403103643054, 4262.041040765803, 4281.0, 4287.292596721569, 4308.870733877989, 4416.84541041062, 4584.426946631671, 4594.494605593382, 4599.425071866017, 4611.884702882455, 4649.651276154302, 4751.429828420612, 4760.523947605212, 4762.6189904808225, 4798.1123492154065, 4807.110684523374, 4886.071646387175, 4911.950880491149, 4979.053979743837, 5081.618878584195, 5091.59267258611, 4686.437627484724, 4722.456917454399, 4727.834525791581, 4739.502352253102, 4766.833160839354, 4940.975295123501, 4949.356583644248, 4950.801967921243, 4963.553280204799, 5003.374578177728, 4932.334134891744, 4941.75291235444, 5043.722595257296, 5052.072231943219, 5092.694438333712, 4846.321514987975, 4882.218844984785, 4888.31563581106, 4889.55993960531, 4905.632077594123, 4901.240307752304, 4902.975485122552, 4904.141775189256, 4905.681130726492, 4905.926411103907, 4729.266963620645, 4771.928421073617, 4772.308015337826, 4843.854684359475, 4857.344258525054, 4832.323474713613, 4893.437254715611, 4895.094407534618, 4934.605231581537, 4940.851774446773, 4788.640851936049, 4790.473047964651, 4790.712557246577, 4795.20879054952, 4847.442544107332], "value": 4608.808255981601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.209015063384, 563.5519761789377, 566.5863919338883, 568.6559631422991, 577.1083675721011, 29027.1291861244, 37731.81134094329, 39014.804925975404, 39171.0, 42471.57528424715, 40520.392194117114, 40940.38589421153, 40978.0, 40988.38517422241, 41042.38436423449, 7678.117016543113, 7804.648790804399, 7810.297073263405, 7818.491798033138, 7847.843043139133, 7563.432742544332, 7765.883511747328, 9894.257930655194, 10111.03945125212, 10120.696379108638, 9979.0, 9987.35082219657, 10020.649277275297, 10031.347962382459, 10049.397036177816, 10033.39799612022, 10097.444640544767, 10114.291999560033, 10125.544350504246, 10126.898731012669, 9928.106470417624, 10008.64969726056, 10009.799804003951, 10014.849777253346, 10015.248856335804, 36646.816765916, 36900.26199476022, 37013.814930925444, 37021.81489092564, 37044.44433333502, 3689.409694448902, 3770.09517715749, 3772.0946972726624, 3806.3909774436233, 3829.42558616207, 28223.43553128926, 28336.858315708494, 28611.284717882012, 28803.711962880523, 29034.290164332888, 3465.2722928185112, 3466.376052310583, 3502.387082260594, 3569.3396721606587, 3654.2508785699133, 180002.09998950054, 181220.0, 181521.0, 181521.0, 181556.0, 50063.749681251364, 50239.0, 50297.74851125721, 50311.0, 50353.24470132951, 53335.19997200045, 53390.7330463353, 53414.19878701823, 53483.0, 53579.0, 14300.0, 14560.271986400638, 14575.85424145745, 14579.198144101963, 14596.197209153599, 9523.809523809623, 9716.076972687584, 10199.949000254952, 10224.64213752515, 10229.335093218917, 15004.549863504113, 15366.385344586315, 15403.383864645513, 15780.526584202493, 15834.28745706446, 5794.362620111784, 5848.678322692208, 5851.824445266649, 5881.206037185011, 5949.1076338549565, 822.5393779483509, 828.5774255129807, 830.9542975136426, 837.3175861672718, 855.2644725536034, 26892.327691807863, 27762.861185693942, 27801.86099069532, 27886.721132788818, 27917.30206744807, 996.5415908682088, 1005.3214080495636, 1012.1245122968579, 1029.330934892326, 1110.6779034080025, 14525.491607793889, 14949.775753363709, 15095.32071056805, 15097.587550186669, 15142.318595663222, 330810.0, 336035.0, 336057.0, 336584.0, 337109.0, 49747.0, 49817.50182498201, 49895.50104498908, 49903.25145122826, 49965.50034499681, 1241.888230059299, 1267.258653687603, 1282.4036822877406, 1285.0041218056267, 1289.6775806048488, 25452.872735636203, 26275.73724262733, 26813.0, 26819.195424137306, 27388.315292117855, 40868.79565602272, 41260.79369603133, 41275.793621031706, 41334.79332603318, 41437.171256575304, 269154.0, 269983.0, 270706.0, 270949.0, 271351.0, 30249.395012099194, 30488.798676139573, 35168.0, 35334.0, 35351.29297414089, 36262.8186859064, 41060.0, 41145.79427102965, 41381.37927931023, 41546.376804347354, 34821.30357392889, 34991.0, 35324.641564518635, 35419.11452213612, 36003.27993440064, 31947.0, 34895.82552087223, 35411.114722131126, 35922.10194745152, 36489.08777280589, 31596.210094747814, 32213.838930806134, 34176.316473670886, 34223.828880855435, 34411.827940861134, 26856.462870742867, 28402.147935561967, 28692.713072869003, 29114.417711645227, 29608.259793505335, 22056.448588784766, 22684.86575671243, 22789.77210227943, 22813.885930570243, 22828.42928926724], "value": 17391.814477845113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4821486522246, 362.71889285803513, 379.61062511208974, 383.46338967728474, 402.5210000099881, 13444.731105377887, 13577.52478663247, 17799.11004449777, 17811.0, 17841.553961150978, 17130.914345428286, 17364.56588585286, 17513.299468021294, 17574.736378954294, 17603.687096661335, 3960.722749407543, 4141.068259641586, 5024.874378140539, 5197.506236907488, 5217.895642087156, 5895.469407753301, 6161.199044124258, 6212.347703491114, 6330.588511746721, 6336.96831515844, 6018.187544681477, 6113.23584551931, 6153.384661533833, 6272.184615999914, 6280.748770049193, 6157.969210153965, 6176.629402235881, 6194.5973511721595, 6196.752129914798, 6201.472874805647, 4886.1204983103025, 5091.592672586221, 5092.8472145835685, 5169.379674439091, 5202.609804264657, 20584.588308233902, 20829.0, 20999.5800083999, 21152.0, 21161.0, 2223.12186686258, 2263.173941511353, 2404.603240465321, 2456.680631517909, 2462.409021834747, 8962.193402593733, 9031.322650801216, 9074.274058075338, 9536.28477864156, 9538.475383853885, 1740.730186821032, 1746.1007581095746, 1752.535578071812, 1761.233863269469, 1800.639872025587, 76231.0, 77572.0, 79148.60425697893, 80659.19340806575, 80905.0, 9876.80246395068, 10205.540750666238, 10234.33476824004, 10278.537465813906, 10308.381497110047, 14344.0, 14376.568702938928, 14399.78400323996, 14406.42374305037, 14415.639609009859, 7257.600831954188, 7401.666924988388, 7443.106827180773, 7555.357794587411, 7605.0, 7062.540934839219, 7093.538919970186, 7120.074390329224, 7248.637568121573, 7308.269173082649, 4303.677224208197, 4332.895111746485, 4342.283523218697, 4360.542143074995, 4420.84527041552, 1851.8703690741518, 1900.5738616250683, 1910.554918482342, 1915.4732448576754, 1915.7030660247683, 767.3439209475929, 793.6705520342109, 796.9521828690297, 811.6307080278577, 822.0382152881143, 16192.352305907632, 17237.56906077358, 17530.386436474917, 17636.735448968277, 17686.646267074586, 774.1136398823315, 788.1999770233218, 789.5460110436522, 791.7980914866787, 792.3542313014874, 9221.0, 9367.203787678123, 9544.704882802767, 9587.18508926735, 9738.561764720605, 486853.0, 490268.0, 490398.0, 501442.0, 503474.0, 66205.33794661991, 76884.61557692176, 83820.0, 92369.53815231015, 92715.0, 885.8626912828522, 905.6830109461768, 920.0477505781485, 926.050797932125, 929.4516235420999, 14567.125972441687, 14810.259487025822, 14979.625509362353, 15218.239088045775, 15351.539453816404, 36634.084147896516, 38562.0, 38661.6133838658, 39766.60233397629, 40369.0, 55707.72146139244, 55820.0, 56373.15440268317, 56480.0, 56834.0, 7721.382289416721, 7781.26078022587, 7851.60741962911, 7898.842023159619, 7977.8404431912195, 10143.7464063399, 11855.347955862166, 12213.679955615256, 12224.449899754354, 12686.302253376152, 10003.0, 10129.696109116738, 10135.341202821794, 10396.480175991019, 10993.0, 10171.593136274761, 10457.738556536147, 10553.947230263799, 10616.946915265375, 10790.244682872124, 10340.689779306633, 10402.531886065297, 10598.25812193139, 10848.67453976382, 11112.277701949348, 7720.1196256491685, 7723.34351580122, 7877.054753429625, 7904.920950790649, 8259.710910118118, 8847.601857916537, 9834.60661573529, 10078.596856125967, 10323.845142323022, 11003.669889903316], "value": 8478.340508337293, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.0, 1314.0867097367334, 1324.7615429222735, 1329.335332333833, 1335.1188215777588, 6604.075429439877, 6977.476689248314, 6982.1272340957385, 7004.64976751163, 7018.719251229951, 6111.847203819907, 6306.558540902139, 6641.335866413357, 6802.625855577953, 6894.172699276081, 2079.0768539101346, 2128.19128731082, 2129.7337832770904, 2131.5630295789324, 2133.0934352900035, 2063.0, 2082.9895850520725, 2086.7182930304443, 2087.332053742803, 2090.205721825704, 1813.4650278167971, 1863.7390765292869, 2023.6458619741486, 2055.7533096028496, 2076.771555128942, 2065.824404925583, 2068.4311814251128, 2068.813806757399, 2070.3064473401428, 2072.720182775321, 2011.5474018345901, 2029.269462993336, 2031.1266155553105, 2042.1116814185727, 2068.162394230339, 7034.8944765828555, 7087.929120708778, 7089.291070892921, 7090.787276381665, 7102.928970710278, 4271.9359209611885, 4362.847300344472, 4365.476142862845, 4373.803178856927, 4383.583559561836, 2690.596410538435, 2816.605675205473, 2834.957475637867, 2850.1307101334087, 2897.0294951191377, 1085.3270971997333, 1122.5622007417114, 1134.4554613785347, 1134.7446824464512, 1182.326074137742, 9103.635854565875, 9109.817803643957, 9124.5437728114, 9133.178013978708, 9139.223166030895, 3712.443133529938, 3894.1627550076305, 3914.4911161549385, 3976.701747368959, 3983.203359328146, 7935.682572697143, 7937.484063535851, 7947.284744373034, 7955.4033447491665, 7956.920430795618, 4388.166248412791, 4389.582989615981, 4390.626796722315, 4393.780310984438, 4397.2304846651705, 3556.946645800315, 3666.8533258669886, 3876.282887665791, 4022.416749571354, 4029.979850100731, 3330.7501937354796, 3348.162959260185, 3399.0822477931315, 3414.197663549064, 3489.900681285387, 1100.6863044032411, 1130.7455822439886, 1130.8812574679705, 1137.4085475552665, 1138.7380902392497, 643.3437893348759, 653.8234676637281, 768.2356055724479, 812.8171161488737, 841.3857883744778, 4447.977760111179, 4524.6380289576755, 4756.500567440369, 4943.209086546135, 4961.627877909171, 857.7476883749795, 862.288611895189, 869.860822268434, 871.2942516561606, 908.6047569307373, 3101.7828751987145, 3158.4156930967843, 3279.950800737991, 3354.9329013419597, 3363.1928337198897, 8206.794830129294, 8430.494170349799, 8474.533900635524, 8527.872081918777, 8542.829143417097, 8479.533625650649, 8487.787805304917, 8493.872591911002, 8497.0, 8497.915020849836, 6405.35946405365, 6476.125723027331, 6634.336566343423, 6758.560693554903, 6772.458203343721, 4517.277235642281, 4532.410786597853, 4544.590986811203, 4578.03861189154, 4735.12400205963, 7476.850462990818, 7482.438817088631, 7513.737019204302, 7520.774376768706, 7559.659815308214, 7678.462507624413, 7682.500637458547, 7692.730754423568, 7727.806804829925, 7727.884081738891, 5376.596755243294, 5526.613137080366, 5756.510696590669, 5853.590248682552, 5869.442402971711, 6175.567710260419, 6326.177596912372, 6331.2085989251345, 6451.1290975717675, 6460.3862633049785, 5796.681182535001, 5857.765689372548, 5868.706564671835, 5891.617044892068, 5938.020226662552, 5781.8554536136935, 5802.4197580241635, 5813.09896965971, 5847.766089356378, 5964.8807023860145, 5008.599312054954, 5078.873028174177, 5118.820841270538, 5119.180931051014, 5147.9227811583605, 4067.8169482372773, 4082.6121518455698, 4162.895927601834, 4185.0, 4287.185434767384, 4015.799210039545, 4024.1951609677594, 4128.298189307765, 4152.2525945330135, 4213.199492096492], "value": 3764.249974093138, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.29764898415345, 348.5190437196668, 349.9982500087499, 352.07755680118095, 353.78949525032596, 4876.171050921343, 4886.780094895729, 4895.437024742151, 4927.359443272374, 4947.876303092425, 4571.6571257155765, 4852.126617208901, 4852.611791056715, 4866.683665561736, 4896.265560165977, 3778.338790711628, 3819.6753275971505, 3871.8838434846857, 3895.0, 3925.411188321746, 3883.514560679915, 3906.5507466641275, 3906.960930390688, 3923.117298607819, 3931.7051221158385, 3873.288697911459, 3874.9806250968704, 3887.611238876104, 3889.2804831106196, 3895.376739721645, 3641.2717456508803, 3731.738778285535, 3882.5535063467782, 3893.1629699614437, 3905.3556163233034, 3507.771994820354, 3511.9824400877833, 3513.244652399721, 3517.9648203517895, 3534.770239934396, 4714.952850471485, 4725.834595789165, 4733.431988161443, 4733.5503127202865, 4764.714117152947, 4348.0, 4470.435347628304, 4479.395281637003, 4485.147821913826, 4485.846793539119, 4999.700017998932, 5018.422881368653, 5019.498050194988, 5074.13739664258, 5081.263216833576, 3837.1558257183374, 3858.2283543291173, 3865.768053916746, 3881.874256465622, 3918.1380096378753, 5097.5981605058905, 5294.523492885659, 5338.706371149585, 5355.839324820262, 5371.489708477728, 3926.312895243321, 3957.782321972262, 3962.1868473383643, 3977.3238549446664, 3979.5025621797276, 4024.6981476389387, 4032.7580345179385, 4037.2127435149864, 4046.291898917678, 4053.8783836484954, 4433.490148632948, 4468.754218518013, 4475.843345482893, 4477.753723545236, 4500.819967201341, 4875.293082503017, 5024.346834911438, 5067.417247016567, 5070.692834760175, 5100.336956195671, 1337.7525157845635, 1349.5276653171509, 1352.3441131051432, 1354.3972932045342, 1356.7286542691304, 917.8980425606142, 980.6861804222723, 983.9373476645349, 986.9062439068277, 997.581015973293, 4256.34026725858, 4362.520122786491, 4376.627986621173, 4398.780060996937, 4401.317795741666, 5075.8984820303385, 5126.79492820283, 5145.3568303962, 5193.792248310101, 5231.55531779803, 4395.802188901507, 4405.29515277554, 4428.560717766739, 4429.715382539092, 4436.26801577736, 2601.465135570023, 2644.6297518347446, 2660.9733902661114, 2672.665916760405, 2692.555728304808, 4669.579737823544, 4678.157931572349, 4684.414448193976, 4698.248280275208, 4744.95255047452, 5091.847244582668, 5106.744662766847, 5119.744012799345, 5131.589472842164, 5155.845324640267, 4549.340345649863, 4785.731781078087, 4789.856304310876, 4803.639727020487, 4814.903701925942, 5141.922871156861, 5154.871128221825, 5168.405633352213, 5185.825410544548, 5196.584273258055, 4906.2164389721165, 5018.673786203885, 5046.6719663221775, 5090.872728181825, 5116.058266496705, 4814.831480898151, 4858.975705121452, 4862.5380588844, 4862.902741945212, 4866.318715379921, 3668.3030224258373, 3680.5767336756617, 3684.6499582539614, 3688.5758137813423, 3696.537932758405, 5193.324867767166, 5215.452377500308, 5270.683758974474, 5272.525472707474, 5283.471652834686, 4905.632077594266, 5007.7245751484015, 5013.523715247045, 5020.0, 5029.874253143701, 4962.139197800825, 5020.6234532410945, 5023.81940244035, 5039.874003149951, 5056.266841307916, 4825.927611085906, 4970.403551573834, 4972.776225069808, 4973.303737476684, 4986.900261994812, 4811.759412029454, 4931.408231012301, 5050.873728156826, 5066.2400639902835, 5080.46655101209, 4808.543188397097, 4930.556249937523, 4935.555799978019, 4941.332920055746, 4945.62907781925], "value": 3873.303226363919, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.4740838807985, 651.9217693876735, 720.7801620505747, 760.1296515489764, 772.7565816767718, 12321.815172772405, 13235.205887646747, 13285.800712989312, 13287.534936277232, 13444.865551344483, 13268.734625307488, 13277.535286264983, 13337.199768013901, 13381.66545836352, 13475.797863032063, 666.53009628212, 683.8734834055691, 786.8622990976585, 804.613785383015, 811.8863359129726, 817.6075483767795, 819.5001049359915, 823.9988414076895, 824.2705205892798, 824.8680211166214, 870.5995242188601, 871.4596949891045, 871.9564021798884, 872.2672954718035, 873.1661263493348, 695.600029982762, 713.8144082538525, 862.7886167888845, 866.7356456280831, 867.0722327109995, 612.8896798576254, 626.8777588370269, 631.1794666932968, 631.516889579472, 632.0834789555117, 13597.252151131685, 13637.590872273848, 13695.31523423825, 13696.315184240748, 13699.383527741176, 12546.68633284166, 12671.239725616493, 12728.0, 12840.678983025498, 13001.0, 11725.0, 11818.468168932333, 11848.052155827512, 12121.818172727417, 12226.449809758582, 3990.4413382126527, 4007.298722723541, 4036.5156181258144, 4205.222033923704, 4210.831566737327, 59273.0, 59618.525344241294, 60487.46344024172, 61758.0, 61981.38018619801, 9661.661841835503, 9788.168005719452, 9812.460314682618, 9849.409035457895, 9876.111149996535, 16948.23732932021, 16998.150092495325, 17089.829101708823, 17159.485215443558, 17270.05014724177, 6347.904781428282, 6386.16979792624, 6419.101325814389, 6492.707828147744, 6638.701258443381, 3417.6411476795074, 3491.4762785581856, 3538.893833184957, 3554.662307080823, 3581.6239294873662, 2777.777777777773, 2806.480801051812, 2823.39297051135, 2827.915162545127, 2846.0, 456.4431393699681, 467.9438467383936, 514.2440612299913, 526.9868253293622, 541.0450554770754, 560.4171661472105, 563.884403697241, 569.5386736743251, 571.894199573072, 572.8539222498237, 8750.956245218733, 8961.507117108622, 9043.728688139367, 9128.680496182735, 9181.908180918239, 574.371063685264, 594.5903163893238, 607.6779306967314, 608.40984245282, 621.3506885304868, 3884.941725874114, 4095.447114639545, 4097.221527909687, 4188.4136220929095, 4203.5376108628025, 62746.37253627497, 64104.679476602316, 66599.33400666028, 66616.33383666199, 67318.0, 57377.713111434176, 59860.0, 59974.70012649996, 60340.698296508235, 60950.0, 52214.73892630589, 52438.0, 52614.73692631513, 53053.0, 55256.44743552513, 7656.73201437947, 7799.727009554638, 7931.682732690628, 7949.602519873867, 8116.228958248957, 37348.6265137356, 37476.625233748404, 37797.811010944766, 38173.80913095417, 39052.0, 42370.78814605907, 45176.77411612921, 45663.31505027361, 45810.31284530801, 47986.520134799604, 9494.762630934281, 9658.517074146124, 9707.417554946725, 9769.560369783234, 9867.753306167404, 10721.249512534048, 10775.892241077803, 10944.726381840199, 11016.614418495314, 11137.777244455228, 9973.45146016947, 10095.394276343442, 10208.744781380525, 10446.216533760151, 10548.683539493828, 9151.130642588943, 9846.655367062118, 9882.703518894445, 10077.899221007696, 10253.282270241012, 9583.0, 10128.746781330527, 10149.695509134737, 10310.07209351162, 10457.68626941193, 8127.715529956423, 8389.454685445426, 8403.621837017472, 8606.182412670787, 8978.640854365753, 8520.3183745299, 8568.614412351584, 9710.514474276399, 9837.294743945473, 9888.208943284664], "value": 6172.516465447045, "unit": "score"}], "name": "dromaeo_css", "value": 6277.016784713965, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 131.0, 152.0, 132.0, 128.0, 122.0, 123.0, 151.0, 129.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 164.0, 163.0, 163.0, 162.0, 162.0, 161.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 107.0, 76.0, 76.0, 76.0, 77.0, 91.0, 77.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 111.0, 86.0, 85.0, 84.0, 97.0, 84.0, 85.0, 113.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 119.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 150.0, 148.0, 148.0, 150.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 60.0, 62.0, 61.0, 63.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 68.0, 68.0, 68.0, 67.0, 66.0, 67.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 83.0, 84.0, 96.0, 84.0, 85.0, 85.0, 84.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 147.0, 133.0, 143.0, 138.0, 134.0, 131.0, 130.0, 207.0, 135.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 183.0, 174.0, 175.0, 180.0, 177.0, 177.0, 176.0, 182.0, 178.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 69.0, 65.0, 70.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1547.8999999999999, "alertThreshold": 2.0}]} 16:44:38 INFO - Return code: 0 16:44:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:44:38 INFO - # TBPL SUCCESS # 16:44:38 INFO - Running post-action listener: _resource_record_post_action 16:44:38 INFO - [mozharness: 2017-01-07 00:44:38.373660Z] Finished run-tests step (success) 16:44:38 INFO - Running post-run listener: _resource_record_post_run 16:44:38 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 384348160; Read time: 12; Write time: 370888 16:44:38 INFO - TinderboxPrint: CPU usage
13.1% 16:44:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:44:38 INFO - TinderboxPrint: I/O write bytes / time
384,348,160 / 370,888 16:44:38 INFO - TinderboxPrint: CPU idle
8,331.2 (86.9%) 16:44:38 INFO - TinderboxPrint: CPU user
1,209.4 (12.6%) 16:44:38 INFO - TinderboxPrint: Swap in / out
0 / 0 16:44:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 102318080; Read time: 0; Write time: 129096 16:44:38 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 281415680; Read time: 0; Write time: 241764 16:44:39 INFO - Running post-run listener: _upload_blobber_files 16:44:39 INFO - Blob upload gear active. 16:44:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:44:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:44:39 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:44:39 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:44:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:44:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:44:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:40 INFO - (blobuploader) - INFO - Done attempting. 16:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:41 INFO - (blobuploader) - INFO - Done attempting. 16:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:42 INFO - (blobuploader) - INFO - Done attempting. 16:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:45 INFO - (blobuploader) - INFO - Done attempting. 16:44:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:44:45 INFO - Return code: 0 16:44:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:44:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:44:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bfdf31107f9f7b9d465cbfb6062685aac24b99589f607245a4df0c8b9b10647891767f1dcc6ff315b3921054298ba5d76c423ba587cea0dc0c1e7626338b5235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d774e9a350fb9b41ff40b4a4ce153ebd5c1bd7f0856fdf668c99dac933203ca488492ab0ac3e356aeaab4e8ac975d3bc23603ee7133f3f7ff0ea0fbea1831b11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f41d7865cf2b199cbb61ab8e6f70f9b79dd32d744dabbe6d3d25f6218c55c1fb9d681f43c65f446f7df3fc570a9aec51c2b0d9dc35396605792c7c1d3141bbdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/776a131afbd199bf1dc2412438d94150a4018a2d871e16b2d03a1d51c02227d52a2b635baef5d5b05f3362c5d760af059f10d8b98a98ae98f8718a276eaf220a"} 16:44:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:44:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:44:45 INFO - Contents: 16:44:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bfdf31107f9f7b9d465cbfb6062685aac24b99589f607245a4df0c8b9b10647891767f1dcc6ff315b3921054298ba5d76c423ba587cea0dc0c1e7626338b5235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d774e9a350fb9b41ff40b4a4ce153ebd5c1bd7f0856fdf668c99dac933203ca488492ab0ac3e356aeaab4e8ac975d3bc23603ee7133f3f7ff0ea0fbea1831b11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f41d7865cf2b199cbb61ab8e6f70f9b79dd32d744dabbe6d3d25f6218c55c1fb9d681f43c65f446f7df3fc570a9aec51c2b0d9dc35396605792c7c1d3141bbdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/776a131afbd199bf1dc2412438d94150a4018a2d871e16b2d03a1d51c02227d52a2b635baef5d5b05f3362c5d760af059f10d8b98a98ae98f8718a276eaf220a"} 16:44:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:44:45 INFO - Copying logs to upload dir... 16:44:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:44:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.311015 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-06 16:44:45.721692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:44:45.725846) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bfdf31107f9f7b9d465cbfb6062685aac24b99589f607245a4df0c8b9b10647891767f1dcc6ff315b3921054298ba5d76c423ba587cea0dc0c1e7626338b5235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d774e9a350fb9b41ff40b4a4ce153ebd5c1bd7f0856fdf668c99dac933203ca488492ab0ac3e356aeaab4e8ac975d3bc23603ee7133f3f7ff0ea0fbea1831b11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f41d7865cf2b199cbb61ab8e6f70f9b79dd32d744dabbe6d3d25f6218c55c1fb9d681f43c65f446f7df3fc570a9aec51c2b0d9dc35396605792c7c1d3141bbdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/776a131afbd199bf1dc2412438d94150a4018a2d871e16b2d03a1d51c02227d52a2b635baef5d5b05f3362c5d760af059f10d8b98a98ae98f8718a276eaf220a"} build_url:https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011821 build_url: 'https://queue.taskcluster.net/v1/task/WtRpIYbXRTOoS0iba9Y7mg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bfdf31107f9f7b9d465cbfb6062685aac24b99589f607245a4df0c8b9b10647891767f1dcc6ff315b3921054298ba5d76c423ba587cea0dc0c1e7626338b5235", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d774e9a350fb9b41ff40b4a4ce153ebd5c1bd7f0856fdf668c99dac933203ca488492ab0ac3e356aeaab4e8ac975d3bc23603ee7133f3f7ff0ea0fbea1831b11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f41d7865cf2b199cbb61ab8e6f70f9b79dd32d744dabbe6d3d25f6218c55c1fb9d681f43c65f446f7df3fc570a9aec51c2b0d9dc35396605792c7c1d3141bbdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/776a131afbd199bf1dc2412438d94150a4018a2d871e16b2d03a1d51c02227d52a2b635baef5d5b05f3362c5d760af059f10d8b98a98ae98f8718a276eaf220a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:44:45.758124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:44:45.758473) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1483748638.47458-329714464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:44:45.839926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:44:45.840245) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:44:45.840531) ========= ========= Total master_lag: 0.19 =========