builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-077 starttime: 1491686961.59 results: success (0) revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.590667) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.591295) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.591637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.604443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.604824) ========= 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=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-08 14:29:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 53.2K=0.2s 2017-04-08 14:29:22 (53.2 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.353016 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.970365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:21.970795) ========= 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=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016102 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.020817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.021246) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --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=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-08 14:29:23,036 truncating revision to first 12 chars 2017-04-08 14:29:23,036 Setting DEBUG logging. 2017-04-08 14:29:23,036 attempt 1/10 2017-04-08 14:29:23,036 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20c110248317?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-08 14:29:23,521 unpacking tar archive at: mozilla-aurora-20c110248317/testing/mozharness/ program finished with exit code 0 elapsedTime=0.815033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.852609) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.852998) ========= script_repo_revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.853442) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.853756) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 14:29:22.864112) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-08 14:29:22.864509) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:29:23 INFO - MultiFileLogger online at 20170408 14:29:23 in /builds/slave/test 14:29:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:29:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:29:23 INFO - {'append_to_log': False, 14:29:23 INFO - 'base_work_dir': '/builds/slave/test', 14:29:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:29:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:29:23 INFO - 'branch': 'Mozilla-Aurora', 14:29:23 INFO - 'buildbot_json_path': 'buildprops.json', 14:29:23 INFO - 'config_files': ('talos/linux_config.py',), 14:29:23 INFO - 'default_actions': ('clobber', 14:29:23 INFO - 'read-buildbot-config', 14:29:23 INFO - 'download-and-extract', 14:29:23 INFO - 'populate-webroot', 14:29:23 INFO - 'create-virtualenv', 14:29:23 INFO - 'install', 14:29:23 INFO - 'run-tests'), 14:29:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:29:23 INFO - 'download_minidump_stackwalk': True, 14:29:23 INFO - 'download_symbols': 'ondemand', 14:29:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:29:23 INFO - 'tooltool.py': '/tools/tooltool.py', 14:29:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:29:23 INFO - '/tools/misc-python/virtualenv.py')}, 14:29:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:29:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:29:23 INFO - 'gecko_profile': False, 14:29:23 INFO - 'gecko_profile_interval': 0, 14:29:23 INFO - 'installer_path': 'installer.exe', 14:29:23 INFO - 'log_level': 'info', 14:29:23 INFO - 'log_name': 'talos', 14:29:23 INFO - 'log_to_console': True, 14:29:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:29:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:29:23 INFO - 'opt_config_files': (), 14:29:23 INFO - 'pip_index': False, 14:29:23 INFO - 'suite': 'dromaeojs', 14:29:23 INFO - 'system_bits': '32', 14:29:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:29:23 INFO - 'title': 'talos-linux64-ix-077', 14:29:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:29:23 INFO - 'use_talos_json': True, 14:29:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:29:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:29:23 INFO - 'work_dir': 'build'} 14:29:23 INFO - [mozharness: 2017-04-08 21:29:23.970214Z] Running clobber step. 14:29:23 INFO - Running pre-action listener: _resource_record_pre_action 14:29:23 INFO - Running main action method: clobber 14:29:23 INFO - rmtree: /builds/slave/test/build 14:29:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:25 INFO - Running post-action listener: _resource_record_post_action 14:29:25 INFO - [mozharness: 2017-04-08 21:29:25.006417Z] Finished clobber step (success) 14:29:25 INFO - [mozharness: 2017-04-08 21:29:25.006516Z] Running read-buildbot-config step. 14:29:25 INFO - Running pre-action listener: _resource_record_pre_action 14:29:25 INFO - Running main action method: read_buildbot_config 14:29:25 INFO - Using buildbot properties: 14:29:25 INFO - { 14:29:25 INFO - "project": "", 14:29:25 INFO - "product": "firefox", 14:29:25 INFO - "who": "philringnalda@gmail.com", 14:29:25 INFO - "installer_path": "public/build/target.tar.bz2", 14:29:25 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:29:25 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 14:29:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:29:25 INFO - "stage_platform": "linux64-pgo", 14:29:25 INFO - "basedir": "/builds/slave/test", 14:29:25 INFO - "buildnumber": 1148, 14:29:25 INFO - "platform": "ubuntu64_hw", 14:29:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:29:25 INFO - "slavebuilddir": "test", 14:29:25 INFO - "taskId": "Xxd5bRYnSpqmaNFMRvWw-Q", 14:29:25 INFO - "branch": "mozilla-aurora", 14:29:25 INFO - "script_repo_revision": "production", 14:29:25 INFO - "revision": "20c110248317f6f6592d004cbb5f71fa1731639b", 14:29:25 INFO - "slavename": "talos-linux64-ix-077", 14:29:25 INFO - "repo_path": "releases/mozilla-aurora" 14:29:25 INFO - } 14:29:25 INFO - Finding installer, test and symbols from parent task. 14:29:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xxd5bRYnSpqmaNFMRvWw-Q'}, attempt #1 14:29:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xxd5bRYnSpqmaNFMRvWw-Q'}, attempt #1 14:29:25 INFO - Task dependencies: VjzAqXWxTGaVtHMH9rjiAg 14:29:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg'}, attempt #1 14:29:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 14:29:26 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/test_packages.json 14:29:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.crashreporter-symbols.zip 14:29:26 INFO - Running post-action listener: _resource_record_post_action 14:29:26 INFO - [mozharness: 2017-04-08 21:29:26.366027Z] Finished read-buildbot-config step (success) 14:29:26 INFO - [mozharness: 2017-04-08 21:29:26.366242Z] Running download-and-extract step. 14:29:26 INFO - Running pre-action listener: _resource_record_pre_action 14:29:26 INFO - Running main action method: download_and_extract 14:29:26 INFO - mkdir: /builds/slave/test/build/tests 14:29:26 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')]} 14:29:26 INFO - trying https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.test_packages.json 14:29:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:29:26 INFO - Downloaded 896 bytes. 14:29:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:29:26 INFO - Using the following test package requirements: 14:29:26 INFO - {u'common': [u'target.common.tests.zip'], 14:29:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:29:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:29:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:29:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:29:26 INFO - u'mozbase': [u'target.common.tests.zip'], 14:29:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:29:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:29:26 INFO - u'web-platform': [u'target.common.tests.zip', 14:29:26 INFO - u'target.web-platform.tests.zip'], 14:29:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:29:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip 14:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip into memory 14:29:28 INFO - Expected file size: 59540991 14:29:28 INFO - Obtained file size: 59540991 14:29:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:29:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip 14:29:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:29:37 INFO - Fetch https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.common.tests.zip into memory 14:29:39 INFO - Expected file size: 59540991 14:29:39 INFO - Obtained file size: 59540991 14:29:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.talos.tests.zip 14:29:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:29:49 INFO - Fetch https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.talos.tests.zip into memory 14:29:50 INFO - Expected file size: 14404424 14:29:50 INFO - Obtained file size: 14404424 14:29:50 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')]} 14:29:50 INFO - trying https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 14:29:50 INFO - Downloading https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:29:51 INFO - Downloaded 66694003 bytes. 14:29:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 14:29:51 INFO - mkdir: /builds/slave/test/properties 14:29:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:51 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:51 INFO - Contents: 14:29:51 INFO - build_url:https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 14:29:51 INFO - Running post-action listener: _resource_record_post_action 14:29:51 INFO - Running post-action listener: set_extra_try_arguments 14:29:51 INFO - [mozharness: 2017-04-08 21:29:51.876279Z] Finished download-and-extract step (success) 14:29:51 INFO - [mozharness: 2017-04-08 21:29:51.876375Z] Running populate-webroot step. 14:29:51 INFO - Running pre-action listener: _resource_record_pre_action 14:29:51 INFO - Running main action method: populate_webroot 14:29:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:29:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:29:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:29:51 INFO - '--tppagecycles', 14:29:51 INFO - '7'], 14:29:51 INFO - 'tests': ['tsvgm']}}, 14:29:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:29:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:29:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:29:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:29:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['damp', 'tps']}, 14:29:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'tests': ['damp', 'tps']}, 14:29:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['dromaeo_dom']}, 14:29:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:29:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['basic_compositor_video']}, 14:29:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:29:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'other-e10s': {'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['a11yr', 14:29:51 INFO - 'ts_paint', 14:29:51 INFO - 'tpaint', 14:29:51 INFO - 'sessionrestore', 14:29:51 INFO - 'sessionrestore_no_auto_restore', 14:29:51 INFO - 'tabpaint']}, 14:29:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['tsvgx', 14:29:51 INFO - 'tsvgr_opacity', 14:29:51 INFO - 'tart', 14:29:51 INFO - 'tscrollx', 14:29:51 INFO - 'cart', 14:29:51 INFO - 'tsvg_static']}, 14:29:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:29:51 INFO - 'tsvgr_opacity', 14:29:51 INFO - 'tart', 14:29:51 INFO - 'tscrollx', 14:29:51 INFO - 'cart', 14:29:51 INFO - 'tsvg_static']}, 14:29:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'talos_options': ['--disable-e10s'], 14:29:51 INFO - 'tests': ['tp5o']}, 14:29:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:29:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'talos_options': ['--disable-e10s', 14:29:51 INFO - '--xperf_path', 14:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:51 INFO - 'tests': ['tp5n']}, 14:29:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:29:51 INFO - 'talos_options': ['--xperf_path', 14:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:51 INFO - 'tests': ['tp5n']}}, 14:29:51 INFO - 'talos.zip': {'path': '', 14:29:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:29:51 INFO - Running post-action listener: _resource_record_post_action 14:29:51 INFO - [mozharness: 2017-04-08 21:29:51.889448Z] Finished populate-webroot step (success) 14:29:51 INFO - [mozharness: 2017-04-08 21:29:51.889537Z] Running create-virtualenv step. 14:29:51 INFO - Running pre-action listener: _resource_record_pre_action 14:29:51 INFO - Running main action method: create_virtualenv 14:29:51 INFO - Creating virtualenv /builds/slave/test/build/venv 14:29:51 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 14:29:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:29:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:29:51 INFO - Using real prefix '/usr' 14:29:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:29:52 INFO - Installing distribute.............................................................................................................................................................................................done. 14:29:54 INFO - Installing pip.................done. 14:29:54 INFO - Return code: 0 14:29:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:29:54 INFO - Reading from file tmpfile_stdout 14:29:54 INFO - Output received: 14:29:54 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) 14:29:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:29:54 INFO - Using env: {'DISPLAY': ':0', 14:29:54 INFO - 'HOME': '/home/cltbld', 14:29:54 INFO - 'LANG': 'en_US.UTF-8', 14:29:54 INFO - 'LANGUAGE': 'en_US:en', 14:29:54 INFO - 'LOGNAME': 'cltbld', 14:29:54 INFO - 'MAIL': '/var/mail/cltbld', 14:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:54 INFO - 'MOZ_NO_REMOTE': '1', 14:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:54 INFO - 'NO_EM_RESTART': '1', 14:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:54 INFO - 'PWD': '/builds/slave/test', 14:29:54 INFO - 'SHELL': '/bin/bash', 14:29:54 INFO - 'SHLVL': '1', 14:29:54 INFO - 'TERM': 'linux', 14:29:54 INFO - 'TMOUT': '86400', 14:29:54 INFO - 'USER': 'cltbld', 14:29:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:54 INFO - '_': '/tools/buildbot/bin/python'} 14:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:54 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 14:29:54 INFO - Cleaning up... 14:29:54 INFO - Return code: 0 14:29:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:29:54 INFO - Using env: {'DISPLAY': ':0', 14:29:54 INFO - 'HOME': '/home/cltbld', 14:29:54 INFO - 'LANG': 'en_US.UTF-8', 14:29:54 INFO - 'LANGUAGE': 'en_US:en', 14:29:54 INFO - 'LOGNAME': 'cltbld', 14:29:54 INFO - 'MAIL': '/var/mail/cltbld', 14:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:54 INFO - 'MOZ_NO_REMOTE': '1', 14:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:54 INFO - 'NO_EM_RESTART': '1', 14:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:54 INFO - 'PWD': '/builds/slave/test', 14:29:54 INFO - 'SHELL': '/bin/bash', 14:29:54 INFO - 'SHLVL': '1', 14:29:54 INFO - 'TERM': 'linux', 14:29:54 INFO - 'TMOUT': '86400', 14:29:54 INFO - 'USER': 'cltbld', 14:29:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:54 INFO - '_': '/tools/buildbot/bin/python'} 14:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:55 INFO - Downloading/unpacking psutil>=3.1.1 14:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:57 INFO - Installing collected packages: psutil 14:29:57 INFO - Running setup.py install for psutil 14:29:57 INFO - building 'psutil._psutil_linux' extension 14:29:57 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 14:29:57 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 14:29:57 INFO - building 'psutil._psutil_posix' extension 14:29:57 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 14:29:57 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 14:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:57 INFO - Successfully installed psutil 14:29:57 INFO - Cleaning up... 14:29:57 INFO - Return code: 0 14:29:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:29:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:29:57 INFO - Using env: {'DISPLAY': ':0', 14:29:57 INFO - 'HOME': '/home/cltbld', 14:29:57 INFO - 'LANG': 'en_US.UTF-8', 14:29:57 INFO - 'LANGUAGE': 'en_US:en', 14:29:57 INFO - 'LOGNAME': 'cltbld', 14:29:57 INFO - 'MAIL': '/var/mail/cltbld', 14:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:57 INFO - 'MOZ_NO_REMOTE': '1', 14:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:29:57 INFO - 'NO_EM_RESTART': '1', 14:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:57 INFO - 'PWD': '/builds/slave/test', 14:29:57 INFO - 'SHELL': '/bin/bash', 14:29:57 INFO - 'SHLVL': '1', 14:29:57 INFO - 'TERM': 'linux', 14:29:57 INFO - 'TMOUT': '86400', 14:29:57 INFO - 'USER': 'cltbld', 14:29:57 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:57 INFO - '_': '/tools/buildbot/bin/python'} 14:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:29:58 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 14:29:58 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 14:29:58 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 14:29:58 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 14:29:58 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 14:29:58 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 14:30:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:30:00 INFO - Installing collected packages: mozsystemmonitor 14:30:00 INFO - Running setup.py install for mozsystemmonitor 14:30:00 INFO - Successfully installed mozsystemmonitor 14:30:00 INFO - Cleaning up... 14:30:00 INFO - Return code: 0 14:30:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:30:00 INFO - Using env: {'DISPLAY': ':0', 14:30:00 INFO - 'HOME': '/home/cltbld', 14:30:00 INFO - 'LANG': 'en_US.UTF-8', 14:30:00 INFO - 'LANGUAGE': 'en_US:en', 14:30:00 INFO - 'LOGNAME': 'cltbld', 14:30:00 INFO - 'MAIL': '/var/mail/cltbld', 14:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:00 INFO - 'MOZ_NO_REMOTE': '1', 14:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:00 INFO - 'NO_EM_RESTART': '1', 14:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:00 INFO - 'PWD': '/builds/slave/test', 14:30:00 INFO - 'SHELL': '/bin/bash', 14:30:00 INFO - 'SHLVL': '1', 14:30:00 INFO - 'TERM': 'linux', 14:30:00 INFO - 'TMOUT': '86400', 14:30:00 INFO - 'USER': 'cltbld', 14:30:00 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:00 INFO - '_': '/tools/buildbot/bin/python'} 14:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:00 INFO - Downloading/unpacking jsonschema==2.5.1 14:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:30:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:02 INFO - Downloading functools32-3.2.3-2.tar.gz 14:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:30:02 INFO - warning: no files found matching '*.txt' 14:30:02 INFO - no previously-included directories found matching 'build' 14:30:02 INFO - no previously-included directories found matching 'dist' 14:30:02 INFO - no previously-included directories found matching '.git*' 14:30:02 INFO - Installing collected packages: jsonschema, functools32 14:30:02 INFO - Running setup.py install for functools32 14:30:03 INFO - warning: no files found matching '*.txt' 14:30:03 INFO - no previously-included directories found matching 'build' 14:30:03 INFO - no previously-included directories found matching 'dist' 14:30:03 INFO - no previously-included directories found matching '.git*' 14:30:03 INFO - Successfully installed jsonschema functools32 14:30:03 INFO - Cleaning up... 14:30:03 INFO - Return code: 0 14:30:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:03 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:03 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 14:30:03 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 14:30:03 INFO - Using env: {'DISPLAY': ':0', 14:30:03 INFO - 'HOME': '/home/cltbld', 14:30:03 INFO - 'LANG': 'en_US.UTF-8', 14:30:03 INFO - 'LANGUAGE': 'en_US:en', 14:30:03 INFO - 'LOGNAME': 'cltbld', 14:30:03 INFO - 'MAIL': '/var/mail/cltbld', 14:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:03 INFO - 'MOZ_NO_REMOTE': '1', 14:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:03 INFO - 'NO_EM_RESTART': '1', 14:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:03 INFO - 'PWD': '/builds/slave/test', 14:30:03 INFO - 'SHELL': '/bin/bash', 14:30:03 INFO - 'SHLVL': '1', 14:30:03 INFO - 'TERM': 'linux', 14:30:03 INFO - 'TMOUT': '86400', 14:30:03 INFO - 'USER': 'cltbld', 14:30:03 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:03 INFO - '_': '/tools/buildbot/bin/python'} 14:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:30:03 INFO - Cleaning up... 14:30:03 INFO - Return code: 0 14:30:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:03 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:03 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 14:30:03 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 14:30:03 INFO - Using env: {'DISPLAY': ':0', 14:30:03 INFO - 'HOME': '/home/cltbld', 14:30:03 INFO - 'LANG': 'en_US.UTF-8', 14:30:03 INFO - 'LANGUAGE': 'en_US:en', 14:30:03 INFO - 'LOGNAME': 'cltbld', 14:30:03 INFO - 'MAIL': '/var/mail/cltbld', 14:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:03 INFO - 'MOZ_NO_REMOTE': '1', 14:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:03 INFO - 'NO_EM_RESTART': '1', 14:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:03 INFO - 'PWD': '/builds/slave/test', 14:30:03 INFO - 'SHELL': '/bin/bash', 14:30:03 INFO - 'SHLVL': '1', 14:30:03 INFO - 'TERM': 'linux', 14:30:03 INFO - 'TMOUT': '86400', 14:30:03 INFO - 'USER': 'cltbld', 14:30:03 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:03 INFO - '_': '/tools/buildbot/bin/python'} 14:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:03 INFO - Downloading/unpacking blobuploader==1.2.4 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:05 INFO - Downloading blobuploader-1.2.4.tar.gz 14:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:30:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:30:05 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 14:30:05 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 14:30:05 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 14:30:05 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 14:30:05 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 14:30:05 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 14:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:30:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:30:06 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 14:30:06 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 14:30:06 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 14:30:06 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 14:30:06 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 14:30:06 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 14:30:06 INFO - Downloading docopt-0.6.1.tar.gz 14:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:30:06 INFO - Installing collected packages: blobuploader, requests, docopt 14:30:06 INFO - Running setup.py install for blobuploader 14:30:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:30:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:30:06 INFO - Running setup.py install for requests 14:30:07 INFO - Running setup.py install for docopt 14:30:07 INFO - Successfully installed blobuploader requests docopt 14:30:07 INFO - Cleaning up... 14:30:07 INFO - Return code: 0 14:30:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:07 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:07 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 14:30:07 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 14:30:07 INFO - Using env: {'DISPLAY': ':0', 14:30:07 INFO - 'HOME': '/home/cltbld', 14:30:07 INFO - 'LANG': 'en_US.UTF-8', 14:30:07 INFO - 'LANGUAGE': 'en_US:en', 14:30:07 INFO - 'LOGNAME': 'cltbld', 14:30:07 INFO - 'MAIL': '/var/mail/cltbld', 14:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:07 INFO - 'MOZ_NO_REMOTE': '1', 14:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:07 INFO - 'NO_EM_RESTART': '1', 14:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:07 INFO - 'PWD': '/builds/slave/test', 14:30:07 INFO - 'SHELL': '/bin/bash', 14:30:07 INFO - 'SHLVL': '1', 14:30:07 INFO - 'TERM': 'linux', 14:30:07 INFO - 'TMOUT': '86400', 14:30:07 INFO - 'USER': 'cltbld', 14:30:07 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:07 INFO - '_': '/tools/buildbot/bin/python'} 14:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:07 INFO - Running setup.py (path:/tmp/pip-_m6LNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:07 INFO - Running setup.py (path:/tmp/pip-2dMHex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:07 INFO - Running setup.py (path:/tmp/pip-baug3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:07 INFO - Running setup.py (path:/tmp/pip-Bu0Dxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:07 INFO - Running setup.py (path:/tmp/pip-dAiezl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:07 INFO - Running setup.py (path:/tmp/pip-xYITOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:07 INFO - Running setup.py (path:/tmp/pip-e336XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:07 INFO - Running setup.py (path:/tmp/pip-pSau3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:07 INFO - Running setup.py (path:/tmp/pip-grUrqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:08 INFO - Running setup.py (path:/tmp/pip-k1I365-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:08 INFO - Running setup.py (path:/tmp/pip-TNEJQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:08 INFO - Running setup.py (path:/tmp/pip-D4M83k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:08 INFO - Running setup.py (path:/tmp/pip-mDwMai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:08 INFO - Running setup.py (path:/tmp/pip-zmln2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:08 INFO - Running setup.py (path:/tmp/pip-cyBVx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:08 INFO - Running setup.py (path:/tmp/pip-TtW5sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:08 INFO - Running setup.py (path:/tmp/pip-jlc4jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:08 INFO - Running setup.py install for manifestparser 14:30:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:08 INFO - Running setup.py install for mozcrash 14:30:08 INFO - Running setup.py install for mozdebug 14:30:08 INFO - Running setup.py install for mozdevice 14:30:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for mozfile 14:30:09 INFO - Running setup.py install for mozhttpd 14:30:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for mozinfo 14:30:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for mozInstall 14:30:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for mozleak 14:30:09 INFO - Running setup.py install for mozlog 14:30:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for moznetwork 14:30:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Running setup.py install for mozprocess 14:30:09 INFO - Running setup.py install for mozprofile 14:30:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:30:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:30:10 INFO - Running setup.py install for mozrunner 14:30:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:30:10 INFO - Running setup.py install for mozscreenshot 14:30:10 INFO - Running setup.py install for moztest 14:30:10 INFO - Running setup.py install for mozversion 14:30:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:30:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:30:10 INFO - Cleaning up... 14:30:10 INFO - Return code: 0 14:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:10 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 14:30:10 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 14:30:10 INFO - Using env: {'DISPLAY': ':0', 14:30:10 INFO - 'HOME': '/home/cltbld', 14:30:10 INFO - 'LANG': 'en_US.UTF-8', 14:30:10 INFO - 'LANGUAGE': 'en_US:en', 14:30:10 INFO - 'LOGNAME': 'cltbld', 14:30:10 INFO - 'MAIL': '/var/mail/cltbld', 14:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:10 INFO - 'MOZ_NO_REMOTE': '1', 14:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:10 INFO - 'NO_EM_RESTART': '1', 14:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:10 INFO - 'PWD': '/builds/slave/test', 14:30:10 INFO - 'SHELL': '/bin/bash', 14:30:10 INFO - 'SHLVL': '1', 14:30:10 INFO - 'TERM': 'linux', 14:30:10 INFO - 'TMOUT': '86400', 14:30:10 INFO - 'USER': 'cltbld', 14:30:10 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:10 INFO - '_': '/tools/buildbot/bin/python'} 14:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:10 INFO - Running setup.py (path:/tmp/pip-13BhLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:10 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)) 14:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:10 INFO - Running setup.py (path:/tmp/pip-iM4Khd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:10 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)) 14:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:10 INFO - Running setup.py (path:/tmp/pip-6BariU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:10 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)) 14:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:10 INFO - Running setup.py (path:/tmp/pip-PcZkXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:10 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)) 14:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:10 INFO - Running setup.py (path:/tmp/pip-LCWhz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:11 INFO - Running setup.py (path:/tmp/pip-RVeVoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:11 INFO - Running setup.py (path:/tmp/pip-C3949y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:11 INFO - Running setup.py (path:/tmp/pip-v_oOjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:11 INFO - Running setup.py (path:/tmp/pip-FjQPkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:11 INFO - Running setup.py (path:/tmp/pip-yvhyJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:11 INFO - Running setup.py (path:/tmp/pip-XK2trC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:11 INFO - Running setup.py (path:/tmp/pip-EJJCJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:11 INFO - Running setup.py (path:/tmp/pip-qwQWtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:11 INFO - Running setup.py (path:/tmp/pip-ieExfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:11 INFO - Running setup.py (path:/tmp/pip-FXWq9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:11 INFO - Running setup.py (path:/tmp/pip-0odDSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:11 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)) 14:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:11 INFO - Running setup.py (path:/tmp/pip-yR__np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:11 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)) 14:30:11 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)) 14:30:11 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)) 14:30:11 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)) 14:30:11 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)) 14:30:11 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)) 14:30:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30:11 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 14:30:11 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 14:30:11 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 14:30:11 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 14:30:11 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 14:30:11 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 14:30:13 INFO - Downloading blessings-1.6.tar.gz 14:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:30:14 INFO - Installing collected packages: blessings 14:30:14 INFO - Running setup.py install for blessings 14:30:14 INFO - Successfully installed blessings 14:30:14 INFO - Cleaning up... 14:30:14 INFO - Return code: 0 14:30:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:14 INFO - Reading from file tmpfile_stdout 14:30:14 INFO - Current package versions: 14:30:14 INFO - argparse == 1.2.1 14:30:14 INFO - blessings == 1.6 14:30:14 INFO - blobuploader == 1.2.4 14:30:14 INFO - docopt == 0.6.1 14:30:14 INFO - functools32 == 3.2.3-2 14:30:14 INFO - jsonschema == 2.5.1 14:30:14 INFO - manifestparser == 1.1 14:30:14 INFO - mozInstall == 1.12 14:30:14 INFO - mozcrash == 1.0 14:30:14 INFO - mozdebug == 0.1 14:30:14 INFO - mozdevice == 0.49 14:30:14 INFO - mozfile == 1.2 14:30:14 INFO - mozhttpd == 0.7 14:30:14 INFO - mozinfo == 0.9 14:30:14 INFO - mozleak == 0.1 14:30:14 INFO - mozlog == 3.4 14:30:14 INFO - moznetwork == 0.27 14:30:14 INFO - mozprocess == 0.25 14:30:14 INFO - mozprofile == 0.28 14:30:14 INFO - mozrunner == 6.13 14:30:14 INFO - mozscreenshot == 0.1 14:30:14 INFO - mozsystemmonitor == 0.3 14:30:14 INFO - moztest == 0.8 14:30:14 INFO - mozversion == 1.4 14:30:14 INFO - psutil == 3.1.1 14:30:14 INFO - requests == 1.2.3 14:30:14 INFO - wsgiref == 0.1.2 14:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:14 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')]} 14:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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')]} 14:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:14 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 14:30:14 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 14:30:14 INFO - Using env: {'DISPLAY': ':0', 14:30:14 INFO - 'HOME': '/home/cltbld', 14:30:14 INFO - 'LANG': 'en_US.UTF-8', 14:30:14 INFO - 'LANGUAGE': 'en_US:en', 14:30:14 INFO - 'LOGNAME': 'cltbld', 14:30:14 INFO - 'MAIL': '/var/mail/cltbld', 14:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:14 INFO - 'MOZ_NO_REMOTE': '1', 14:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:14 INFO - 'NO_EM_RESTART': '1', 14:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:14 INFO - 'PWD': '/builds/slave/test', 14:30:14 INFO - 'SHELL': '/bin/bash', 14:30:14 INFO - 'SHLVL': '1', 14:30:14 INFO - 'TERM': 'linux', 14:30:14 INFO - 'TMOUT': '86400', 14:30:14 INFO - 'USER': 'cltbld', 14:30:14 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:14 INFO - '_': '/tools/buildbot/bin/python'} 14:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 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)) 14:30:14 INFO - Cleaning up... 14:30:14 INFO - Return code: 0 14:30:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:30:14 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')]} 14:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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')]} 14:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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 0x1f641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2201b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, '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': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', '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 14:30:14 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 14:30:14 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 14:30:14 INFO - Using env: {'DISPLAY': ':0', 14:30:14 INFO - 'HOME': '/home/cltbld', 14:30:14 INFO - 'LANG': 'en_US.UTF-8', 14:30:14 INFO - 'LANGUAGE': 'en_US:en', 14:30:14 INFO - 'LOGNAME': 'cltbld', 14:30:14 INFO - 'MAIL': '/var/mail/cltbld', 14:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:14 INFO - 'MOZ_NO_REMOTE': '1', 14:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:14 INFO - 'NO_EM_RESTART': '1', 14:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:14 INFO - 'PWD': '/builds/slave/test', 14:30:14 INFO - 'SHELL': '/bin/bash', 14:30:14 INFO - 'SHLVL': '1', 14:30:14 INFO - 'TERM': 'linux', 14:30:14 INFO - 'TMOUT': '86400', 14:30:14 INFO - 'USER': 'cltbld', 14:30:14 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:14 INFO - '_': '/tools/buildbot/bin/python'} 14:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:30:14 INFO - Cleaning up... 14:30:14 INFO - Return code: 0 14:30:14 INFO - Running post-action listener: _resource_record_post_action 14:30:14 INFO - Running post-action listener: _start_resource_monitoring 14:30:14 INFO - Starting resource monitoring. 14:30:14 INFO - [mozharness: 2017-04-08 21:30:14.888850Z] Finished create-virtualenv step (success) 14:30:14 INFO - [mozharness: 2017-04-08 21:30:14.889315Z] Running install step. 14:30:14 INFO - Running pre-action listener: _resource_record_pre_action 14:30:14 INFO - Running main action method: install 14:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:15 INFO - Reading from file tmpfile_stdout 14:30:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:30:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:30:15 INFO - Reading from file tmpfile_stdout 14:30:15 INFO - Output received: 14:30:15 INFO - Usage: mozinstall [options] installer 14:30:15 INFO - Options: 14:30:15 INFO - -h, --help show this help message and exit 14:30:15 INFO - -d DEST, --destination=DEST 14:30:15 INFO - Directory to install application into. [default: 14:30:15 INFO - "/builds/slave/test"] 14:30:15 INFO - --app=APP Application being installed. [default: firefox] 14:30:15 INFO - mkdir: /builds/slave/test/build/application 14:30:15 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'] 14:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:30:31 INFO - Reading from file tmpfile_stdout 14:30:31 INFO - Output received: 14:30:31 INFO - /builds/slave/test/build/application/firefox/firefox 14:30:31 INFO - Running post-action listener: _resource_record_post_action 14:30:31 INFO - [mozharness: 2017-04-08 21:30:31.777148Z] Finished install step (success) 14:30:31 INFO - [mozharness: 2017-04-08 21:30:31.777424Z] Running run-tests step. 14:30:31 INFO - Running pre-action listener: _resource_record_pre_action 14:30:31 INFO - Running main action method: run_tests 14:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:31 INFO - Python 2.7.3 14:30:31 INFO - Return code: 0 14:30:31 INFO - grabbing minidump binary from tooltool 14:30:31 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')]} 14:30:31 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 0x21ff290>, '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 0x21ffb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2200040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:31 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 14:30:31 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 14:30:31 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 14:30:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:31 INFO - Return code: 0 14:30:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:30:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:31 INFO - ENV: RUST_BACKTRACE is now 1 14:30:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:30:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 14:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --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 14:30:31 INFO - Using env: {'DISPLAY': ':0', 14:30:31 INFO - 'HOME': '/home/cltbld', 14:30:31 INFO - 'LANG': 'en_US.UTF-8', 14:30:31 INFO - 'LANGUAGE': 'en_US:en', 14:30:31 INFO - 'LOGNAME': 'cltbld', 14:30:31 INFO - 'MAIL': '/var/mail/cltbld', 14:30:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:31 INFO - 'MOZ_NO_REMOTE': '1', 14:30:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:31 INFO - 'NO_EM_RESTART': '1', 14:30:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:31 INFO - 'PWD': '/builds/slave/test', 14:30:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:31 INFO - 'RUST_BACKTRACE': '1', 14:30:31 INFO - 'SHELL': '/bin/bash', 14:30:31 INFO - 'SHLVL': '1', 14:30:31 INFO - 'TERM': 'linux', 14:30:31 INFO - 'TMOUT': '86400', 14:30:31 INFO - 'USER': 'cltbld', 14:30:31 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572', 14:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:31 INFO - '_': '/tools/buildbot/bin/python'} 14:30:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 14:30:32 INFO - mozversion application_buildid: 20170408202840 14:30:32 INFO - mozversion application_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 14:30:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:30:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:32 INFO - mozversion application_name: Firefox 14:30:32 INFO - mozversion application_remotingname: firefox-dev 14:30:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:30:32 INFO - mozversion application_vendor: Mozilla 14:30:32 INFO - mozversion application_version: 54.0a2 14:30:32 INFO - mozversion platform_buildid: 20170408202840 14:30:32 INFO - mozversion platform_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 14:30:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:30:32 INFO - mozversion platform_version: 54.0a2 14:30:32 INFO - using testdate: 1491687032 14:30:32 INFO - actual date: 1491687032 14:30:32 INFO - starting webserver on 'localhost:33678' 14:30:32 INFO - SUITE-START | Running 2 tests 14:30:32 INFO - TEST-START | dromaeo_css 14:30:32 INFO - operating with platform_type : linux_ 14:30:32 INFO - Initialising browser for dromaeo_css test... 14:30:32 INFO - TEST-INFO | started process 16211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkFcqwn/profile http://localhost:33678/getInfo.html) 14:30:36 INFO - PROCESS | 16211 | __metrics Screen width/height:1600/1200 14:30:36 INFO - PROCESS | 16211 | colorDepth:24 14:30:36 INFO - PROCESS | 16211 | Browser inner width/height: 1024/697 14:30:36 INFO - PROCESS | 16211 | __metrics 14:30:36 INFO - PROCESS | 16211 | 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" 14:30:36 INFO - PROCESS | 16211 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:30:37 INFO - TEST-INFO | 16211: exit 0 14:30:37 INFO - Browser initialized. 14:30:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:37 INFO - TEST-INFO | started process 16312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkFcqwn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:49 INFO - PROCESS | 16312 | RSS: Main: 139870208 14:30:49 INFO - PROCESS | 16312 | 14:33:51 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33678/tests/dromaeo/cssquery-ext.html) 14:33:52 INFO - PROCESS | 16312 | RSS: Main: 273309696 14:33:52 INFO - PROCESS | 16312 | 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | A coding exception was thrown and uncaught in a Task. 14:35:40 INFO - PROCESS | 16312 | 14:35:40 ERROR - PROCESS | 16312 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:35:40 INFO - PROCESS | 16312 | Full stack: 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | A coding exception was thrown and uncaught in a Task. 14:35:40 INFO - PROCESS | 16312 | 14:35:40 ERROR - PROCESS | 16312 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:35:40 INFO - PROCESS | 16312 | Full stack: 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | A coding exception was thrown and uncaught in a Task. 14:35:40 INFO - PROCESS | 16312 | 14:35:40 ERROR - PROCESS | 16312 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:35:40 INFO - PROCESS | 16312 | Full stack: 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | ************************* 14:35:40 INFO - PROCESS | 16312 | A coding exception was thrown and uncaught in a Task. 14:35:40 INFO - PROCESS | 16312 | 14:35:40 ERROR - PROCESS | 16312 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:35:40 INFO - PROCESS | 16312 | Full stack: 14:35:40 INFO - PROCESS | 16312 | ************************* 14:36:54 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-ext.html (next: http://localhost:33678/tests/dromaeo/cssquery-jquery.html) 14:36:55 INFO - PROCESS | 16312 | RSS: Main: 223997952 14:36:55 INFO - PROCESS | 16312 | 14:39:57 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33678/tests/dromaeo/cssquery-mootools.html) 14:39:57 INFO - PROCESS | 16312 | RSS: Main: 215597056 14:39:57 INFO - PROCESS | 16312 | 14:42:59 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33678/tests/dromaeo/cssquery-prototype.html) 14:43:00 INFO - PROCESS | 16312 | RSS: Main: 169914368 14:43:00 INFO - PROCESS | 16312 | 14:46:03 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33678/tests/dromaeo/cssquery-yui.html) 14:46:03 INFO - PROCESS | 16312 | RSS: Main: 176025600 14:46:03 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | Cycle 1(1): loaded http://localhost:33678/tests/dromaeo/cssquery-yui.html (next: http://localhost:33678/tests/dromaeo/cssquery-dojo.html) 14:49:06 INFO - PROCESS | 16312 | RSS: Main: 245153792 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | __start_tp_report 14:49:06 INFO - PROCESS | 16312 | _x_x_mozilla_page_load 14:49:06 INFO - PROCESS | 16312 | _x_x_mozilla_page_load_details 14:49:06 INFO - PROCESS | 16312 | |i|pagename|runs| 14:49:06 INFO - PROCESS | 16312 | |0;dojo.html;3464.549608550888;3481.495183198437;3486.320167567324;3514.648535146484;3538.769979951303;5787.247657804484;5868.85327866803;5876.236089308384;5884.382139875305;5902.675352855591;5883.0881213411885;5909.084091965741;5914.083317085859;5933.554983376253;5965.373635768247;5347.144456886889;5372.382176049765;5377.435369286227;5401.783928642869;5450.591205659592;5170.431252562215;5199;5435.83692489226;5436.700981446019;5445.9727701361435;5252.6323157379;5316.627836051478;5344.893102137955;5351.839444816642;5367.75845086972;5373.919391209115;5388.676679399248;5393.2449457076045;5419.187121931703;5423.105187644034;5145.897082058338;5300.1519756838725;5346.545543628797;5360.2227676987;5423.240746295522;5712.600117991742;5778.27771528559;5813.186153938452;5844.707764611752;5848.707564621752;6279.686015699197;6316.463100636452;6338.683065846689;6344.936550634527;6345.016522438983;6378.075179099051;6384.7126879290545;6385.361463853577;6388;6392.968035159841;5670.262865827416;5694.4590263924865;5864.882702345972;5886.1465087562065;5974.2233509643465;6433.388828061326;6448.000559913221;6448.032795080775;6454.19322584677;6456.257530383972;6141.662208578571;6173.135760993465;6174.722137503823;6175.042868355412;6190.535709821781;5944.405559444108;6079.361667024988;6187.721552530147;6273.3099359071375;6279.654618995999;6265.373462653789;6273.937260627426;6274.717637706314;6291.370862913672;6300.495960323163;5997.970010149921;6023.518118550505;6027.60820546663;6191.938080619227;6270.404311590391;4648.047150334173;4693.155232058262;4697.6241900647865;4701.4358277006295;4706.435227772689;4108.4864391951005;4266.274733295348;4291.463567054118;4303.483581970184;4304.418903448057;5859.941400586024;5915.497182739516;5948.940510594839;6052.243469566304;6056.848578785565;6268.279147897959;6312.084747711556;6316.431521163117;6316.62100273985;6320.083587879823;5958.076498142795;5966.731497082642;5967.376212404314;5969.73136208863;6004.249468816398;5146.125158722951;5183.740812959337;5277.525022747971;5284.3658760948165;5337.679739215582;6496.058071579594;6532.314107018885;6538.673066346663;6545.345465453512;6546.116274303004;6284.842878928063;6292.433680968735;6325.493960483058;6333.493320534439;6341.143945567289;6186.938130618816;6225.968870155621;6384.553081284451;6519.934800651933;6532.738690452328;6206.937930620636;6211.596246243979;6212.254529456494;6230.408111229426;6234.314225435289;6281.15204447412;6291.779787707408;6293.874122517615;6314.936850631435;6344.492440604833;5928.140419639042;5967.104934259895;6133.355997620186;6184.907226391698;6192.071189321638;6118.510519158368;6129.2032035835055;6137.070562331885;6158.230221222348;6186.505079593531;6149.292831324276;6154.630722156506;6161.938380616316;6163.630182189083;6165.475934545614;5920.407959204045;5950.047992321208;5971.701414929149;6133.355997620186;6169.660668663267;6055.212822333068;6062.060380641008;6073.848153796191;6106.41989011061;6106.572539922163 14:49:06 INFO - PROCESS | 16312 | |1;ext.html;767.1561282589153;839.78165676924;858.8325276571069;918.5866360137935;939.6711151097113;31238;44100;44666.106677866504;44955.10089798202;45002.549974500245;45391.77304113475;45593.7720311398;45733.314000290025;45746.31380529286;45765.77117114418;7735.6518956646805;7879.960600196991;9239.999999999984;9393.29550283728;9402.62389504419;10715.35707857527;10926.235163538555;10930.672079837617;10935.83596246057;11274.267172633752;11054.170937179704;11075.390853503053;11080.667579972614;11089.889101109007;11102.167337449724;11034.944825275863;11045.613403530879;11048;11068.446577671164;11071.16966227536;10794.082502987256;10860;10864.674059778219;10866.945665271702;10869.29349592274;42689.14621707548;42720;42827.785861070806;42858.78570607127;42871.35692964608;4297.162053399588;4321.330193819963;4322.956770432287;4450.243458612012;4458.866234012985;29879.40241195164;30183.547246791317;30339.393212135856;31154.688453115632;31713;4242.406063151161;4335.523092459827;4441.395355773966;4489.6408287336935;4533.637309015271;233967;234029;234727;235085;235128;47042.5295747045;47122;47194.5280547197;47403;47425.28862067072;48901.51098489041;49085.509144908094;49205;49352.50647493551;49372.25941610878;16146.677066458606;16249.187540623157;16380.180990950405;16395;16408.75386869198;12132.332721700392;12237.75524489523;13537.255450950095;14153.646158846112;14173.291335433187;12839.03707221962;12900.225986440844;12934.288614126126;12985.480580776852;12996;5301.1783173608155;5369.919451208235;5426.430224826416;5460.126379779216;5595.300587426572;1040.4069680282166;1042.1714736784274;1044.3994703045628;1048.6749107776614;1067.77576708892;28027.159185224475;28083.859580701966;28144.155675329774;28259.434811303658;28478.857605712252;1102.2284400919389;1116.4752566293828;1125.3810404277615;1144.3134119528384;1151.8099513580332;14451.78322325166;15154.16652084124;15312.540623781304;15343.15612641293;15610.453634122978;276502;279490;280311;281669;282015;52717.209241861405;53121.46878531243;53138.734306328224;53191.808491490636;53234.46765532372;1488.5757559095823;1531.9387224511117;1550.2636247782439;1562.9296681649353;1565.866901313401;25132.62301065522;26244;26483.470330593667;26590;26750;37216.6278337224;47269.52730472789;47710.522894770605;47864.7606761964;47918.52081479141;370387;370609;371412;372835;372938;32985.67014329922;33000.834995824865;33258;33270.00189994304;33487.330253394306;34600.48099278563;39626;41116.38325425181;43682.12635747331;43858.122837543706;31094.378112438077;31381;31451.84274078615;31468.370632587677;31608.525872111477;31903;31968.200794980312;32295.192620183745;32566.185845354055;33058.5041224377;36766.44850327301;36974;37532.24935501329;37628.623713763605;37749.62250377571;30153;30246.243843903198;30331.39337213199;30389.544156838107;30676;24412;25364;25406.237812865642;25448.491030179663;25731.35671608225 14:49:06 INFO - PROCESS | 16312 | |2;jquery.html;415.88978920586067;462.8912205631678;471.12606115655467;472.388257206917;489.76001759138023;19357.128929198203;20133.798662013374;20633.896830515845;20818.16727330909;20865.478363040933;20976;21471.57056858862;21533.353999380004;21857.562848743015;22704;6610.735570577195;6669.699863506142;6720.495962802785;6831.965840170792;6870;6669.866602667944;6777.830554236159;6784.389404953553;6786.423154031889;6787.185537735479;6557.409833115019;6678.198616166067;6689.83275418116;6702.7654032108885;6785.321467853225;6611.801645950629;6630.038644396535;6702.832429189285;6765.797026089225;6775.864482710318;6551.639659818708;6594.901076483857;6596.868062638769;6600.867982640369;6606;25006.874965625237;25302.74697253022;25550.361240969123;25776.871115644488;26040.34899127518;3216.388886111631;3225.225945773021;3231.9173077019227;3272.083816531375;3374.5106959490736;16067.758983615255;16073.196340182943;16120.516384508484;16126.516204513884;16177.919110404373;2136.9679454808193;2192.451887028243;2231.241377931511;2255.1768604459417;2264.479169790941;114597.4270128644;117534.41232793867;117795.41102294519;117982;118890.40554797256;14065.845632086546;17894.373696920546;18365.81634183668;18489.5377615558;20149.597008059758;42021.579784202375;42393;43271.78364108222;43849.56150438519;43863;12311.19977201497;12437.315947622968;12531.373431328397;12610.81083783744;12654;9839.606415743434;9905.702828915144;10053.69838904834;10340.224483163793;10555.736106597396;10765.677029689123;10910.181736369805;11131.775504694639;11174.497147628375;11188.104951603853;3821.5796262411113;3824.9235015299537;4074.511058672919;4183.6234738873645;4212.102308172177;1177.2759752751997;1178.4696886401155;1180.5808937827119;1192.2071822238115;1212.3089838791805;20223.797762022485;20227;20415.59168816645;20427.4893127673;20621;1172.3200543684643;1173.2549830857467;1197.275648232824;1205.951761929513;1226.2764968668528;10548.525316360781;11222.494987725571;11366.318020918772;11368.204225704121;11405.201635885574;688208;705612;709688;723411;727981;117699;117728.41135794266;118944;119417.40291298661;120899.39550302192;1300.115921173611;1343.06656873472;1357.2263809628614;1358.4769863602585;1379.5999160243416;17945.192466338784;19067.71398428997;20155.193792248145;20279;20336.288229912472;53983.73008134934;54762.45237547573;54766.45233547773;54918.450815492935;55371;63317.68341158265;66539.33460665331;67968;68724.6563767198;69130.65434672794;7690.461667683208;11639.53441862316;12378.75242495163;12700.36498175106;12919.418626161658;12718.491260349483;13801.171929684251;14143.292835358397;14393.352299146354;14647.487337943121;12535.498580057061;12601.306928118674;12719.300438475973;12901.677458063623;12920.160189587647;11842.28946263227;11886.16796824214;11905.40472976365;12021.278723276631;12022.27866328023;11359.432028398713;12915.547955821501;13040.934795325962;13138.211707297593;13208.622447959075;12404.75190496167;12417.62747117588;12540.122191446511;12571.87428125707;12640.431180596712;11557.595484158013;12311.56909508163;12367.381630918238;12438.937805310916;12939.805902911652 14:49:06 INFO - PROCESS | 16312 | |3;mootools.html;1488.33768972807;1517.5219805761185;1532.5325775638432;1563.8670712989388;1573.622330640646;10612.469376531171;11386.259893106942;11395.202335836473;11407.429628518572;11415.31508109514;11268;11299.548018079267;11636.883631163686;11650.417479126052;11658.242214256088;2391.9282421527337;2421.721502027272;2429.1255148146743;2433.5497932882386;2435.683361163055;2336.778006089419;2415.8671273080063;2428.2229686500327;2429.101232543961;2433.245693834908;2347.2840783561005;2373.3473294843884;2382.106709983756;2446.755324467557;2449.9877500612474;2286.325533967484;2320.895559699809;2324.9883750581225;2330.8018818400456;2349.1308215960034;2307.734610519795;2326.8836558172193;2336.299110266913;2337.0651739304412;2338.018032426385;11234.269772464764;11440.9703126719;11447.141464390204;11456.198066135374;11469.139814513861;5801.100829371454;5944.554158438134;5946.613470124428;5957.255343082114;5965.164876917192;3425.6231814500384;3443.3974054540604;3505.7545971781988;3527.9118022049656;3566.1084728817796;1254.2223821230896;1267.7084270617809;1292.0503429978958;1306.9019823513179;1359.7348517039181;13902.304884755722;14244.145351278852;15670.373185072584;16904.98570085799;17140;4738.95261047392;4768.998510312804;4865.805367785249;4891.926621100686;4900.730459824744;13147.802782958262;13237.205767653972;13254;13264.336783160803;13264.73470530584;6017.608855424383;6028.698565071729;6028.969855150696;6033.4871535919065;6037.1246169305205;5362.973185134049;5425.864353391197;5431.864203394868;5481.506664400223;5499.1476321170285;3889.4360317753767;4001.079751657135;4029.294873397144;4061.918761624751;4080.1431699342875;992.980140397188;1001.7846163074973;1004.3421558878946;1008.6016023670609;1015.5582321690089;928.962841486333;929.990700093004;936.2977766674994;986.6842610364614;1108.490094556513;7180.497365184491;7364.22675619063;7476.887846682304;7845.0978137513775;7868.724594639275;1124.173732306754;1137.6700756780606;1141.1897552737482;1154.982675259855;1160.1067178272683;4807.95192048075;4879.707217566957;4935.407751069894;4954.1082605130705;5019.799208031711;14005.579832605039;14625.561233163022;14629.341679624442;14641.633959150893;14654.926725366518;14268.215248161243;14404.741153913112;14634.634134146732;14690.41238350454;14694.485693000694;8797.51613661242;10750.73123171927;10800.945995269973;10926.781464370828;10930.672079837617;6526.5105117117355;7558.055243094614;7602.543847369176;7713.074431068308;7728.806779830325;12713.42789574453;12823;12852;13194.868051319363;13671.111377760413;13683;13700.65748356299;13872.791908121184;13882;13950.511732089328;9128.726138215865;9287.303452240974;9358.906410936075;9363.85954210701;9385.85921211196;10743.925607439194;10745.516451759846;10838.403887786248;10848.132149428184;10874.78250434971;9369.156775890202;9452.338336316392;9465.763355916157;9571.090746379083;9757.170640495637;9107.134822191882;9135.314851386305;9150.130737579919;9164.862527061967;9335.813283734424;8399.202075802788;8534.103919088406;8542.06037335905;8544.615492302986;8550.529720865412;6478.449331806849;6493.642849643315;6526.44525215343;6543.083968244355;6633.270340262471;6553.967230163818;6566.310537393505;6636.701348439428;6771.830704232433;6800.9319906802275 14:49:06 INFO - PROCESS | 16312 | |4;prototype.html;416.4502856229774;436.95848894355026;438.9912201755965;443.3704140121028;444.4200318584248;5342.252084708139;5371.677699338033;5405.216243644674;5410.296661434018;5420.83737487875;5145.588352931766;5312.203169524573;5317.2821669074665;5324.5740340772745;5348.358197016354;4430.291153415455;4503.504614492404;4504.954950450495;4531.977340113295;4537.614302784251;4478.686491945565;4525.343825145352;4536.5236650151755;4545.227311357077;4577.382053422795;4392.124944788257;4538.228501154795;4539.116266749312;4564.8174073037035;4567.584048944821;4448.822047118128;4469.374287599706;4480.48474425442;4484.932726009112;4486.730796152241;4226.197022565702;4228.1966426378885;4230.619244267999;4245.957540424587;4246.808893599796;5184.755658641937;5192.001079584349;5201.375834899836;5207.973960130175;5216.035812782275;4985.077760614298;5146.58468921046;5175.715335656538;5176.301199338079;5178.98628562863;5573.888522229533;5590.888182236333;5598.58010649203;5602.498438475953;5630.239917611111;4417.889552761175;4676.976615116937;4691.906161876777;4710.929336059962;4720.881977950578;5542.889142217174;5616.494515493625;5742.425757424266;5794;5806.825795226107;4753.433146101067;4756.9286460703115;4762.976185119052;4788.695799898059;4821.590164836029;4791.736454495036;4804.804804804766;4866.124097662455;4866.321119213865;4868.196747536687;4840.806367745251;4876.95123048765;5154.9484505155215;5185.455527169593;5200.803815122467;5566.916496252559;5588.413216612197;5591.496765291062;5596.524295434933;5609.326880774333;1656.5941344370706;1669.5408762590384;1669.5791102900841;1679.3954176496452;1682.0832646207846;1253.5988483685132;1278.9808152877715;1290.2838924396863;1295.8639342868862;1306.73865226955;5239.371275446971;5459.945400546023;5510.972445137829;5601.663900166003;5630.971845140748;5730.455606717355;5750.971245143747;5765.827025189251;5768.625039372428;5809.767609295581;5473.01485732564;5536.640118392292;5546.55627549795;5560.944390556124;5561.304836895388;2910.4324656691742;3008.9398212035635;3014.5026070698527;3024.803387779787;3033.605631267921;5738;5864.237649105674;5879.706014699248;5886.529077673861;5905.43898329658;5562.888742225133;5776.33572139201;5812.808374283261;5881.647101173943;5922.881542369215;5839.284732599277;5852.600433822113;5890.911636325459;5903.793367232126;5917.372722501346;5686.943130568641;5735.8852822944145;5736.311642602915;5753.683547404933;5787.247657804627;5426.430224826337;5609.951044060378;5633.2395126657375;5699.629524080921;5716.314042314949;5660.660360378391;5735.369109398046;5765.308163020464;5961.463468287875;5976.6712830792985;4779.187538118459;4789.281607758725;4884.340614017062;4897.265410188358;4906.362172917497;5488.506034456918;5705.7432415542235;5782.797602084075;5783.392743761844;5888.116782482662;5587.385387607279;5625.718714064362;5663;5665.365604993698;5691.971540142273;5638.915416268841;5651;5688.658680479184;5726.484616384545;5735.598508104393;5398.568114550748;5607.756897241136;5680.460356266148;5696.715164241854;5697.544196464356;5642.971785141048;5649.5762817789655;5675.687837168995;5695.658260504383;5702.543796496353;5405.324334458192;5545;5569.805056822992;5591.4688104629995;5597.412271711574 14:49:06 INFO - PROCESS | 16312 | |5;yui.html;838.6519594368337;855.2217482091294;915.5954101063064;918.081918081918;924.9213816825569;16984.660306793856;18013.369532066365;18135.546611334725;18180.727289090657;18230.27078916842;16069.83930160701;16308.51074467765;16322.591935201595;16346.34614615414;17132.400365987192;668.5387082912799;683.4464084091901;854.2482615298528;866.1814585216972;880.4585180114216;865.3855762408666;873.0614589316478;876.3909083187187;878.714417814213;878.9516576588285;933.5145724223399;934.1359242700509;934.8036912248436;934.9859752103724;935.504182783126;704.0952376196589;719.4136778525485;898.2364989758718;923.0999775219175;929.832630126577;682.9180498340231;683.3576438148125;683.3883674111692;683.6581709145428;683.6718375179942;17320.480385588453;17613.119344032748;17643.20605572752;17686.115694215237;17921.641567168714;14841.480548180762;15065.774013389808;15307.923460382739;15399.23003849803;15865.286062127232;14724;14808;15093.47172848945;15238.847611523854;15271.923640381838;4880.585150262232;4894.730789806595;4909.852704418873;5019.899602007976;5108.514691104339;61623.38376616266;88795.55602222012;90169.54915225448;90481.54759226162;92383.53808230982;12395.566155184526;12538.749225015632;12601.306928118858;12680.365981700877;12793.232406055666;22651.660225096635;22796.202132925268;22815.88592057029;22824.65763013556;22925.197618083286;8565.229129378386;8691.695790647296;8805.559722013873;8899.377043606886;9019.549022548847;5084.694918304839;5155.252488389163;5218.869528261824;5233.947660523422;5271.235670827708;3773.188764415609;3876.379779235309;3883.514560679915;3895.6493915547735;3903.4144878268485;651.3453978751419;659.0838734159522;667.7662818013656;671.4359937652298;673.0610797936948;853.3301358433599;871.2690092796178;890.0832142892831;905.3752910491739;910.6129894794685;11695.590654327058;11759.000969835148;11831.76336473283;12152.149349545447;12497.062720295833;843.2789964580318;850.5194565070715;859.7721603775004;865.5066612031081;869.1221865915471;5435.537979271806;5667.971660141673;5689.715514224272;5724.685142317213;5748.798792042258;80133;81037;82698.58650706708;85166.57416712877;85881;71582;71991.64004179946;72305.2769472309;73705.26294737091;74920.62539687267;64666.35333646603;65992.6700366495;66078;66111;66420.33579664238;12224.633261002184;12287.631371058882;12333.198342108093;12380.938095309466;12496.502657980182;44302.335464968695;45149.54850451453;45562.31656525088;45587;45734.313985289584;50858.49141508537;50918.490815091376;52859.41421757353;53633;56634.43365566291;13822.93088534551;13985.44058237659;14048.367823447765;14060.296985150906;14061.648458788612;16525;16621.41825036118;16721.832781672027;16821.66356672835;17077.060761658227;12779.680507987374;16057;16331.83668163303;16880.915595422437;17555.736663950305;15060.698786023999;15562.610934726723;15577.532674019798;15847.128407937675;16173;14944.775828362366;15216.239188040776;15369.231538422811;15708.68582628364;16054.117023563349;11708.414579271173;12254.387280635754;12411.193272437262;12649.747005060031;13095.869041309465;12834.16577922432;13240.337983100613;13427.395767190696;13666.385012674255;13681.179129252278 14:49:06 INFO - PROCESS | 16312 | __end_tp_report 14:49:06 INFO - PROCESS | 16312 | __start_cc_report 14:49:06 INFO - PROCESS | 16312 | _x_x_mozilla_cycle_collect,1308 14:49:06 INFO - PROCESS | 16312 | __end_cc_report 14:49:06 INFO - PROCESS | 16312 | __startTimestamp1491688146805__endTimestamp 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | ------- Summary: start ------- 14:49:06 INFO - PROCESS | 16312 | Number of tests: 6 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#0] dojo.html Cycles:165 Average:5824.51 Median:6052.24 stddev:660.87 (10.9%) stddev-sans-first:636.44 14:49:06 INFO - PROCESS | 16312 | Values: 3464.5 3481.5 3486.3 3514.6 3538.8 5787.2 5868.9 5876.2 5884.4 5902.7 5883.1 5909.1 5914.1 5933.6 5965.4 5347.1 5372.4 5377.4 5401.8 5450.6 5170.4 5199.0 5435.8 5436.7 5446.0 5252.6 5316.6 5344.9 5351.8 5367.8 5373.9 5388.7 5393.2 5419.2 5423.1 5145.9 5300.2 5346.5 5360.2 5423.2 5712.6 5778.3 5813.2 5844.7 5848.7 6279.7 6316.5 6338.7 6344.9 6345.0 6378.1 6384.7 6385.4 6388.0 6393.0 5670.3 5694.5 5864.9 5886.1 5974.2 6433.4 6448.0 6448.0 6454.2 6456.3 6141.7 6173.1 6174.7 6175.0 6190.5 5944.4 6079.4 6187.7 6273.3 6279.7 6265.4 6273.9 6274.7 6291.4 6300.5 5998.0 6023.5 6027.6 6191.9 6270.4 4648.0 4693.2 4697.6 4701.4 4706.4 4108.5 4266.3 4291.5 4303.5 4304.4 5859.9 5915.5 5948.9 6052.2 6056.8 6268.3 6312.1 6316.4 6316.6 6320.1 5958.1 5966.7 5967.4 5969.7 6004.2 5146.1 5183.7 5277.5 5284.4 5337.7 6496.1 6532.3 6538.7 6545.3 6546.1 6284.8 6292.4 6325.5 6333.5 6341.1 6186.9 6226.0 6384.6 6519.9 6532.7 6206.9 6211.6 6212.3 6230.4 6234.3 6281.2 6291.8 6293.9 6314.9 6344.5 5928.1 5967.1 6133.4 6184.9 6192.1 6118.5 6129.2 6137.1 6158.2 6186.5 6149.3 6154.6 6161.9 6163.6 6165.5 5920.4 5950.0 5971.7 6133.4 6169.7 6055.2 6062.1 6073.8 6106.4 6106.6 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#1] ext.html Cycles:180 Average:46013.10 Median:31309.50 stddev:78790.83 (251.7%) stddev-sans-first:78938.62 14:49:06 INFO - PROCESS | 16312 | Values: 767.2 839.8 858.8 918.6 939.7 31238.0 44100.0 44666.1 44955.1 45002.5 45391.8 45593.8 45733.3 45746.3 45765.8 7735.7 7880.0 9240.0 9393.3 9402.6 10715.4 10926.2 10930.7 10935.8 11274.3 11054.2 11075.4 11080.7 11089.9 11102.2 11034.9 11045.6 11048.0 11068.4 11071.2 10794.1 10860.0 10864.7 10866.9 10869.3 42689.1 42720.0 42827.8 42858.8 42871.4 4297.2 4321.3 4323.0 4450.2 4458.9 29879.4 30183.5 30339.4 31154.7 31713.0 4242.4 4335.5 4441.4 4489.6 4533.6 233967.0 234029.0 234727.0 235085.0 235128.0 47042.5 47122.0 47194.5 47403.0 47425.3 48901.5 49085.5 49205.0 49352.5 49372.3 16146.7 16249.2 16380.2 16395.0 16408.8 12132.3 12237.8 13537.3 14153.6 14173.3 12839.0 12900.2 12934.3 12985.5 12996.0 5301.2 5369.9 5426.4 5460.1 5595.3 1040.4 1042.2 1044.4 1048.7 1067.8 28027.2 28083.9 28144.2 28259.4 28478.9 1102.2 1116.5 1125.4 1144.3 1151.8 14451.8 15154.2 15312.5 15343.2 15610.5 276502.0 279490.0 280311.0 281669.0 282015.0 52717.2 53121.5 53138.7 53191.8 53234.5 1488.6 1531.9 1550.3 1562.9 1565.9 25132.6 26244.0 26483.5 26590.0 26750.0 37216.6 47269.5 47710.5 47864.8 47918.5 370387.0 370609.0 371412.0 372835.0 372938.0 32985.7 33000.8 33258.0 33270.0 33487.3 34600.5 39626.0 41116.4 43682.1 43858.1 31094.4 31381.0 31451.8 31468.4 31608.5 31903.0 31968.2 32295.2 32566.2 33058.5 36766.4 36974.0 37532.2 37628.6 37749.6 30153.0 30246.2 30331.4 30389.5 30676.0 24412.0 25364.0 25406.2 25448.5 25731.4 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#2] jquery.html Cycles:180 Average:39816.28 Median:20152.40 stddev:117075.62 (581.0%) stddev-sans-first:117366.67 14:49:06 INFO - PROCESS | 16312 | Values: 415.9 462.9 471.1 472.4 489.8 19357.1 20133.8 20633.9 20818.2 20865.5 20976.0 21471.6 21533.4 21857.6 22704.0 6610.7 6669.7 6720.5 6832.0 6870.0 6669.9 6777.8 6784.4 6786.4 6787.2 6557.4 6678.2 6689.8 6702.8 6785.3 6611.8 6630.0 6702.8 6765.8 6775.9 6551.6 6594.9 6596.9 6600.9 6606.0 25006.9 25302.7 25550.4 25776.9 26040.3 3216.4 3225.2 3231.9 3272.1 3374.5 16067.8 16073.2 16120.5 16126.5 16177.9 2137.0 2192.5 2231.2 2255.2 2264.5 114597.4 117534.4 117795.4 117982.0 118890.4 14065.8 17894.4 18365.8 18489.5 20149.6 42021.6 42393.0 43271.8 43849.6 43863.0 12311.2 12437.3 12531.4 12610.8 12654.0 9839.6 9905.7 10053.7 10340.2 10555.7 10765.7 10910.2 11131.8 11174.5 11188.1 3821.6 3824.9 4074.5 4183.6 4212.1 1177.3 1178.5 1180.6 1192.2 1212.3 20223.8 20227.0 20415.6 20427.5 20621.0 1172.3 1173.3 1197.3 1206.0 1226.3 10548.5 11222.5 11366.3 11368.2 11405.2 688208.0 705612.0 709688.0 723411.0 727981.0 117699.0 117728.4 118944.0 119417.4 120899.4 1300.1 1343.1 1357.2 1358.5 1379.6 17945.2 19067.7 20155.2 20279.0 20336.3 53983.7 54762.5 54766.5 54918.5 55371.0 63317.7 66539.3 67968.0 68724.7 69130.7 7690.5 11639.5 12378.8 12700.4 12919.4 12718.5 13801.2 14143.3 14393.4 14647.5 12535.5 12601.3 12719.3 12901.7 12920.2 11842.3 11886.2 11905.4 12021.3 12022.3 11359.4 12915.5 13040.9 13138.2 13208.6 12404.8 12417.6 12540.1 12571.9 12640.4 11557.6 12311.6 12367.4 12438.9 12939.8 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#3] mootools.html Cycles:180 Average:7147.71 Median:2424.97 stddev:4500.97 (185.6%) stddev-sans-first:4493.50 14:49:06 INFO - PROCESS | 16312 | Values: 1488.3 1517.5 1532.5 1563.9 1573.6 10612.5 11386.3 11395.2 11407.4 11415.3 11268.0 11299.5 11636.9 11650.4 11658.2 2391.9 2421.7 2429.1 2433.5 2435.7 2336.8 2415.9 2428.2 2429.1 2433.2 2347.3 2373.3 2382.1 2446.8 2450.0 2286.3 2320.9 2325.0 2330.8 2349.1 2307.7 2326.9 2336.3 2337.1 2338.0 11234.3 11441.0 11447.1 11456.2 11469.1 5801.1 5944.6 5946.6 5957.3 5965.2 3425.6 3443.4 3505.8 3527.9 3566.1 1254.2 1267.7 1292.1 1306.9 1359.7 13902.3 14244.1 15670.4 16905.0 17140.0 4739.0 4769.0 4865.8 4891.9 4900.7 13147.8 13237.2 13254.0 13264.3 13264.7 6017.6 6028.7 6029.0 6033.5 6037.1 5363.0 5425.9 5431.9 5481.5 5499.1 3889.4 4001.1 4029.3 4061.9 4080.1 993.0 1001.8 1004.3 1008.6 1015.6 929.0 930.0 936.3 986.7 1108.5 7180.5 7364.2 7476.9 7845.1 7868.7 1124.2 1137.7 1141.2 1155.0 1160.1 4808.0 4879.7 4935.4 4954.1 5019.8 14005.6 14625.6 14629.3 14641.6 14654.9 14268.2 14404.7 14634.6 14690.4 14694.5 8797.5 10750.7 10800.9 10926.8 10930.7 6526.5 7558.1 7602.5 7713.1 7728.8 12713.4 12823.0 12852.0 13194.9 13671.1 13683.0 13700.7 13872.8 13882.0 13950.5 9128.7 9287.3 9358.9 9363.9 9385.9 10743.9 10745.5 10838.4 10848.1 10874.8 9369.2 9452.3 9465.8 9571.1 9757.2 9107.1 9135.3 9150.1 9164.9 9335.8 8399.2 8534.1 8542.1 8544.6 8550.5 6478.4 6493.6 6526.4 6543.1 6633.3 6554.0 6566.3 6636.7 6771.8 6800.9 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#4] prototype.html Cycles:180 Average:4891.95 Median:5407.81 stddev:1296.23 (24.0%) stddev-sans-first:1255.59 14:49:06 INFO - PROCESS | 16312 | Values: 416.5 437.0 439.0 443.4 444.4 5342.3 5371.7 5405.2 5410.3 5420.8 5145.6 5312.2 5317.3 5324.6 5348.4 4430.3 4503.5 4505.0 4532.0 4537.6 4478.7 4525.3 4536.5 4545.2 4577.4 4392.1 4538.2 4539.1 4564.8 4567.6 4448.8 4469.4 4480.5 4484.9 4486.7 4226.2 4228.2 4230.6 4246.0 4246.8 5184.8 5192.0 5201.4 5208.0 5216.0 4985.1 5146.6 5175.7 5176.3 5179.0 5573.9 5590.9 5598.6 5602.5 5630.2 4417.9 4677.0 4691.9 4710.9 4720.9 5542.9 5616.5 5742.4 5794.0 5806.8 4753.4 4756.9 4763.0 4788.7 4821.6 4791.7 4804.8 4866.1 4866.3 4868.2 4840.8 4877.0 5154.9 5185.5 5200.8 5566.9 5588.4 5591.5 5596.5 5609.3 1656.6 1669.5 1669.6 1679.4 1682.1 1253.6 1279.0 1290.3 1295.9 1306.7 5239.4 5459.9 5511.0 5601.7 5631.0 5730.5 5751.0 5765.8 5768.6 5809.8 5473.0 5536.6 5546.6 5560.9 5561.3 2910.4 3008.9 3014.5 3024.8 3033.6 5738.0 5864.2 5879.7 5886.5 5905.4 5562.9 5776.3 5812.8 5881.6 5922.9 5839.3 5852.6 5890.9 5903.8 5917.4 5686.9 5735.9 5736.3 5753.7 5787.2 5426.4 5610.0 5633.2 5699.6 5716.3 5660.7 5735.4 5765.3 5961.5 5976.7 4779.2 4789.3 4884.3 4897.3 4906.4 5488.5 5705.7 5782.8 5783.4 5888.1 5587.4 5625.7 5663.0 5665.4 5692.0 5638.9 5651.0 5688.7 5726.5 5735.6 5398.6 5607.8 5680.5 5696.7 5697.5 5643.0 5649.6 5675.7 5695.7 5702.5 5405.3 5545.0 5569.8 5591.5 5597.4 14:49:06 INFO - PROCESS | 16312 | 14:49:06 INFO - PROCESS | 16312 | [#5] yui.html Cycles:180 Average:19060.36 Median:4887.66 stddev:23459.83 (480.0%) stddev-sans-first:23485.73 14:49:06 INFO - PROCESS | 16312 | Values: 838.7 855.2 915.6 918.1 924.9 16984.7 18013.4 18135.5 18180.7 18230.3 16069.8 16308.5 16322.6 16346.3 17132.4 668.5 683.4 854.2 866.2 880.5 865.4 873.1 876.4 878.7 879.0 933.5 934.1 934.8 935.0 935.5 704.1 719.4 898.2 923.1 929.8 682.9 683.4 683.4 683.7 683.7 17320.5 17613.1 17643.2 17686.1 17921.6 14841.5 15065.8 15307.9 15399.2 15865.3 14724.0 14808.0 15093.5 15238.8 15271.9 4880.6 4894.7 4909.9 5019.9 5108.5 61623.4 88795.6 90169.5 90481.5 92383.5 12395.6 12538.7 12601.3 12680.4 12793.2 22651.7 22796.2 22815.9 22824.7 22925.2 8565.2 8691.7 8805.6 8899.4 9019.5 5084.7 5155.3 5218.9 5233.9 5271.2 3773.2 3876.4 3883.5 3895.6 3903.4 651.3 659.1 667.8 671.4 673.1 853.3 871.3 890.1 905.4 910.6 11695.6 11759.0 11831.8 12152.1 12497.1 843.3 850.5 859.8 865.5 869.1 5435.5 5668.0 5689.7 5724.7 5748.8 80133.0 81037.0 82698.6 85166.6 85881.0 71582.0 71991.6 72305.3 73705.3 74920.6 64666.4 65992.7 66078.0 66111.0 66420.3 12224.6 12287.6 12333.2 12380.9 12496.5 44302.3 45149.5 45562.3 45587.0 45734.3 50858.5 50918.5 52859.4 53633.0 56634.4 13822.9 13985.4 14048.4 14060.3 14061.6 16525.0 16621.4 16721.8 16821.7 17077.1 12779.7 16057.0 16331.8 16880.9 17555.7 15060.7 15562.6 15577.5 15847.1 16173.0 14944.8 15216.2 15369.2 15708.7 16054.1 11708.4 12254.4 12411.2 12649.7 13095.9 12834.2 13240.3 13427.4 13666.4 13681.2 14:49:06 INFO - PROCESS | 16312 | -------- Summary: end -------- 14:49:06 INFO - PROCESS | 16312 | 14:49:07 INFO - TEST-INFO | 16312: exit 0 14:49:07 INFO - TEST-OK | dromaeo_css | took 1115584ms 14:49:07 INFO - TEST-START | kraken 14:49:07 INFO - operating with platform_type : linux_ 14:49:07 INFO - Initialising browser for kraken test... 14:49:07 INFO - TEST-INFO | started process 16631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphyce4K/profile http://localhost:33678/getInfo.html) 14:49:11 INFO - PROCESS | 16631 | __metrics Screen width/height:1600/1200 14:49:11 INFO - PROCESS | 16631 | colorDepth:24 14:49:11 INFO - PROCESS | 16631 | Browser inner width/height: 1024/697 14:49:11 INFO - PROCESS | 16631 | __metrics 14:49:12 INFO - PROCESS | 16631 | 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" 14:49:12 INFO - PROCESS | 16631 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:13 INFO - TEST-INFO | 16631: exit 0 14:49:13 INFO - Browser initialized. 14:49:13 INFO - Running cycle 1/1 for kraken test... 14:49:13 INFO - TEST-INFO | started process 16722 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphyce4K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:25 INFO - PROCESS | 16722 | RSS: Main: 140070912 14:49:25 INFO - PROCESS | 16722 | 14:49:27 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33678/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:28 INFO - PROCESS | 16722 | RSS: Main: 183574528 14:49:28 INFO - PROCESS | 16722 | 14:49:31 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33678/tests/kraken/driver.html?testName=audio-dft) 14:49:31 INFO - PROCESS | 16722 | RSS: Main: 174166016 14:49:31 INFO - PROCESS | 16722 | 14:49:34 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33678/tests/kraken/driver.html?testName=audio-fft) 14:49:35 INFO - PROCESS | 16722 | RSS: Main: 265797632 14:49:35 INFO - PROCESS | 16722 | 14:49:37 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33678/tests/kraken/driver.html?testName=audio-oscillator) 14:49:37 INFO - PROCESS | 16722 | RSS: Main: 171913216 14:49:37 INFO - PROCESS | 16722 | 14:49:39 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33678/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:40 INFO - PROCESS | 16722 | RSS: Main: 174624768 14:49:40 INFO - PROCESS | 16722 | 14:49:43 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33678/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:43 INFO - PROCESS | 16722 | RSS: Main: 213229568 14:49:43 INFO - PROCESS | 16722 | 14:49:47 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33678/tests/kraken/driver.html?testName=imaging-desaturate) 14:49:47 INFO - PROCESS | 16722 | RSS: Main: 211570688 14:49:47 INFO - PROCESS | 16722 | 14:49:50 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33678/tests/kraken/driver.html?testName=json-parse-financial) 14:49:51 INFO - PROCESS | 16722 | RSS: Main: 210358272 14:49:51 INFO - PROCESS | 16722 | 14:49:52 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33678/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:53 INFO - PROCESS | 16722 | RSS: Main: 172711936 14:49:53 INFO - PROCESS | 16722 | 14:49:55 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:49:55 INFO - PROCESS | 16722 | RSS: Main: 179683328 14:49:55 INFO - PROCESS | 16722 | 14:49:57 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:49:58 INFO - PROCESS | 16722 | RSS: Main: 185077760 14:49:58 INFO - PROCESS | 16722 | 14:50:00 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:01 INFO - PROCESS | 16722 | RSS: Main: 180740096 14:50:01 INFO - PROCESS | 16722 | 14:50:04 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:04 INFO - PROCESS | 16722 | RSS: Main: 184836096 14:50:04 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | Cycle 1(1): loaded http://localhost:33678/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33678/tests/kraken/driver.html?testName=ai-astar) 14:50:06 INFO - PROCESS | 16722 | RSS: Main: 237322240 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | __start_tp_report 14:50:06 INFO - PROCESS | 16722 | _x_x_mozilla_page_load 14:50:06 INFO - PROCESS | 16722 | _x_x_mozilla_page_load_details 14:50:06 INFO - PROCESS | 16722 | |i|pagename|runs| 14:50:06 INFO - PROCESS | 16722 | |0;ai-astar;103;115;111;113;112;113;112;112;112;113 14:50:06 INFO - PROCESS | 16722 | |1;audio-beat-detection;144;121;119;164;121;115;117;115;133;116 14:50:06 INFO - PROCESS | 16722 | |2;audio-dft;160;160;161;175;162;162;161;161;160;162 14:50:06 INFO - PROCESS | 16722 | |3;audio-fft;75;75;76;110;76;76;75;77;105;75 14:50:06 INFO - PROCESS | 16722 | |4;audio-oscillator;83;82;104;82;82;81;98;83;82;101 14:50:06 INFO - PROCESS | 16722 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;112 14:50:06 INFO - PROCESS | 16722 | |6;imaging-darkroom;147;146;146;146;146;147;147;146;146;147 14:50:06 INFO - PROCESS | 16722 | |7;imaging-desaturate;94;94;94;94;93;94;94;93;94;93 14:50:06 INFO - PROCESS | 16722 | |8;json-parse-financial;54;54;56;54;54;55;55;54;55;55 14:50:06 INFO - PROCESS | 16722 | |9;json-stringify-tinderbox;54;54;53;53;53;53;53;54;54;54 14:50:06 INFO - PROCESS | 16722 | |10;stanford-crypto-aes;86;76;79;86;86;85;80;82;80;102 14:50:06 INFO - PROCESS | 16722 | |11;stanford-crypto-ccm;123;124;120;133;118;118;129;122;126;136 14:50:06 INFO - PROCESS | 16722 | |12;stanford-crypto-pbkdf2;164;160;158;163;161;162;161;175;158;163 14:50:06 INFO - PROCESS | 16722 | |13;stanford-crypto-sha256-iterative;61;65;60;61;60;62;60;59;59;60 14:50:06 INFO - PROCESS | 16722 | __end_tp_report 14:50:06 INFO - PROCESS | 16722 | __start_cc_report 14:50:06 INFO - PROCESS | 16722 | _x_x_mozilla_cycle_collect,1601 14:50:06 INFO - PROCESS | 16722 | __end_cc_report 14:50:06 INFO - PROCESS | 16722 | __startTimestamp1491688206745__endTimestamp 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | ------- Summary: start ------- 14:50:06 INFO - PROCESS | 16722 | Number of tests: 14 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.20 (2.8%) stddev-sans-first:1.13 14:50:06 INFO - PROCESS | 16722 | Values: 103.0 115.0 111.0 113.0 112.0 113.0 112.0 112.0 112.0 113.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:121.00 stddev:16.11 (13.3%) stddev-sans-first:15.80 14:50:06 INFO - PROCESS | 16722 | Values: 144.0 121.0 119.0 164.0 121.0 115.0 117.0 115.0 133.0 116.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#2] audio-dft Cycles:10 Average:162.40 Median:161.50 stddev:4.50 (2.8%) stddev-sans-first:4.69 14:50:06 INFO - PROCESS | 16722 | Values: 160.0 160.0 161.0 175.0 162.0 162.0 161.0 161.0 160.0 162.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.50 stddev:13.51 (17.9%) stddev-sans-first:14.09 14:50:06 INFO - PROCESS | 16722 | Values: 75.0 75.0 76.0 110.0 76.0 76.0 75.0 77.0 105.0 75.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.00 stddev:9.24 (11.3%) stddev-sans-first:9.63 14:50:06 INFO - PROCESS | 16722 | Values: 83.0 82.0 104.0 82.0 82.0 81.0 98.0 83.0 82.0 101.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:50:06 INFO - PROCESS | 16722 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 14:50:06 INFO - PROCESS | 16722 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:50:06 INFO - PROCESS | 16722 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 93.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 14:50:06 INFO - PROCESS | 16722 | Values: 54.0 54.0 56.0 54.0 54.0 55.0 55.0 54.0 55.0 55.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#9] json-stringify-tinderbox Cycles:10 Average:53.50 Median:54.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 14:50:06 INFO - PROCESS | 16722 | Values: 54.0 54.0 53.0 53.0 53.0 53.0 53.0 54.0 54.0 54.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:83.50 stddev:7.16 (8.6%) stddev-sans-first:7.57 14:50:06 INFO - PROCESS | 16722 | Values: 86.0 76.0 79.0 86.0 86.0 85.0 80.0 82.0 80.0 102.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:125.00 stddev:6.14 (4.9%) stddev-sans-first:6.47 14:50:06 INFO - PROCESS | 16722 | Values: 123.0 124.0 120.0 133.0 118.0 118.0 129.0 122.0 126.0 136.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.50 stddev:4.84 (3.0%) stddev-sans-first:5.10 14:50:06 INFO - PROCESS | 16722 | Values: 164.0 160.0 158.0 163.0 161.0 162.0 161.0 175.0 158.0 163.0 14:50:06 INFO - PROCESS | 16722 | 14:50:06 INFO - PROCESS | 16722 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:60.50 stddev:1.77 (2.9%) stddev-sans-first:1.87 14:50:06 INFO - PROCESS | 16722 | Values: 61.0 65.0 60.0 61.0 60.0 62.0 60.0 59.0 59.0 60.0 14:50:06 INFO - PROCESS | 16722 | -------- Summary: end -------- 14:50:06 INFO - PROCESS | 16722 | 14:50:07 INFO - TEST-INFO | 16722: exit 0 14:50:07 INFO - TEST-OK | kraken | took 60191ms 14:50:07 INFO - SUITE-END | took 1175s 14:50:07 INFO - Completed test suite (00:19:35) 14:50:07 INFO - javascript benchmark 14:50:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7974.767079611539, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3464.549608550888, 3481.495183198437, 3486.320167567324, 3514.648535146484, 3538.769979951303, 5787.247657804484, 5868.85327866803, 5876.236089308384, 5884.382139875305, 5902.675352855591, 5883.0881213411885, 5909.084091965741, 5914.083317085859, 5933.554983376253, 5965.373635768247, 5347.144456886889, 5372.382176049765, 5377.435369286227, 5401.783928642869, 5450.591205659592, 5170.431252562215, 5199.0, 5435.83692489226, 5436.700981446019, 5445.9727701361435, 5252.6323157379, 5316.627836051478, 5344.893102137955, 5351.839444816642, 5367.75845086972, 5373.919391209115, 5388.676679399248, 5393.2449457076045, 5419.187121931703, 5423.105187644034, 5145.897082058338, 5300.1519756838725, 5346.545543628797, 5360.2227676987, 5423.240746295522, 5712.600117991742, 5778.27771528559, 5813.186153938452, 5844.707764611752, 5848.707564621752, 6279.686015699197, 6316.463100636452, 6338.683065846689, 6344.936550634527, 6345.016522438983, 6378.075179099051, 6384.7126879290545, 6385.361463853577, 6388.0, 6392.968035159841, 5670.262865827416, 5694.4590263924865, 5864.882702345972, 5886.1465087562065, 5974.2233509643465, 6433.388828061326, 6448.000559913221, 6448.032795080775, 6454.19322584677, 6456.257530383972, 6141.662208578571, 6173.135760993465, 6174.722137503823, 6175.042868355412, 6190.535709821781, 5944.405559444108, 6079.361667024988, 6187.721552530147, 6273.3099359071375, 6279.654618995999, 6265.373462653789, 6273.937260627426, 6274.717637706314, 6291.370862913672, 6300.495960323163, 5997.970010149921, 6023.518118550505, 6027.60820546663, 6191.938080619227, 6270.404311590391, 4648.047150334173, 4693.155232058262, 4697.6241900647865, 4701.4358277006295, 4706.435227772689, 4108.4864391951005, 4266.274733295348, 4291.463567054118, 4303.483581970184, 4304.418903448057, 5859.941400586024, 5915.497182739516, 5948.940510594839, 6052.243469566304, 6056.848578785565, 6268.279147897959, 6312.084747711556, 6316.431521163117, 6316.62100273985, 6320.083587879823, 5958.076498142795, 5966.731497082642, 5967.376212404314, 5969.73136208863, 6004.249468816398, 5146.125158722951, 5183.740812959337, 5277.525022747971, 5284.3658760948165, 5337.679739215582, 6496.058071579594, 6532.314107018885, 6538.673066346663, 6545.345465453512, 6546.116274303004, 6284.842878928063, 6292.433680968735, 6325.493960483058, 6333.493320534439, 6341.143945567289, 6186.938130618816, 6225.968870155621, 6384.553081284451, 6519.934800651933, 6532.738690452328, 6206.937930620636, 6211.596246243979, 6212.254529456494, 6230.408111229426, 6234.314225435289, 6281.15204447412, 6291.779787707408, 6293.874122517615, 6314.936850631435, 6344.492440604833, 5928.140419639042, 5967.104934259895, 6133.355997620186, 6184.907226391698, 6192.071189321638, 6118.510519158368, 6129.2032035835055, 6137.070562331885, 6158.230221222348, 6186.505079593531, 6149.292831324276, 6154.630722156506, 6161.938380616316, 6163.630182189083, 6165.475934545614, 5920.407959204045, 5950.047992321208, 5971.701414929149, 6133.355997620186, 6169.660668663267, 6055.212822333068, 6062.060380641008, 6073.848153796191, 6106.41989011061, 6106.572539922163], "value": 5780.666988741355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.1561282589153, 839.78165676924, 858.8325276571069, 918.5866360137935, 939.6711151097113, 31238.0, 44100.0, 44666.106677866504, 44955.10089798202, 45002.549974500245, 45391.77304113475, 45593.7720311398, 45733.314000290025, 45746.31380529286, 45765.77117114418, 7735.6518956646805, 7879.960600196991, 9239.999999999984, 9393.29550283728, 9402.62389504419, 10715.35707857527, 10926.235163538555, 10930.672079837617, 10935.83596246057, 11274.267172633752, 11054.170937179704, 11075.390853503053, 11080.667579972614, 11089.889101109007, 11102.167337449724, 11034.944825275863, 11045.613403530879, 11048.0, 11068.446577671164, 11071.16966227536, 10794.082502987256, 10860.0, 10864.674059778219, 10866.945665271702, 10869.29349592274, 42689.14621707548, 42720.0, 42827.785861070806, 42858.78570607127, 42871.35692964608, 4297.162053399588, 4321.330193819963, 4322.956770432287, 4450.243458612012, 4458.866234012985, 29879.40241195164, 30183.547246791317, 30339.393212135856, 31154.688453115632, 31713.0, 4242.406063151161, 4335.523092459827, 4441.395355773966, 4489.6408287336935, 4533.637309015271, 233967.0, 234029.0, 234727.0, 235085.0, 235128.0, 47042.5295747045, 47122.0, 47194.5280547197, 47403.0, 47425.28862067072, 48901.51098489041, 49085.509144908094, 49205.0, 49352.50647493551, 49372.25941610878, 16146.677066458606, 16249.187540623157, 16380.180990950405, 16395.0, 16408.75386869198, 12132.332721700392, 12237.75524489523, 13537.255450950095, 14153.646158846112, 14173.291335433187, 12839.03707221962, 12900.225986440844, 12934.288614126126, 12985.480580776852, 12996.0, 5301.1783173608155, 5369.919451208235, 5426.430224826416, 5460.126379779216, 5595.300587426572, 1040.4069680282166, 1042.1714736784274, 1044.3994703045628, 1048.6749107776614, 1067.77576708892, 28027.159185224475, 28083.859580701966, 28144.155675329774, 28259.434811303658, 28478.857605712252, 1102.2284400919389, 1116.4752566293828, 1125.3810404277615, 1144.3134119528384, 1151.8099513580332, 14451.78322325166, 15154.16652084124, 15312.540623781304, 15343.15612641293, 15610.453634122978, 276502.0, 279490.0, 280311.0, 281669.0, 282015.0, 52717.209241861405, 53121.46878531243, 53138.734306328224, 53191.808491490636, 53234.46765532372, 1488.5757559095823, 1531.9387224511117, 1550.2636247782439, 1562.9296681649353, 1565.866901313401, 25132.62301065522, 26244.0, 26483.470330593667, 26590.0, 26750.0, 37216.6278337224, 47269.52730472789, 47710.522894770605, 47864.7606761964, 47918.52081479141, 370387.0, 370609.0, 371412.0, 372835.0, 372938.0, 32985.67014329922, 33000.834995824865, 33258.0, 33270.00189994304, 33487.330253394306, 34600.48099278563, 39626.0, 41116.38325425181, 43682.12635747331, 43858.122837543706, 31094.378112438077, 31381.0, 31451.84274078615, 31468.370632587677, 31608.525872111477, 31903.0, 31968.200794980312, 32295.192620183745, 32566.185845354055, 33058.5041224377, 36766.44850327301, 36974.0, 37532.24935501329, 37628.623713763605, 37749.62250377571, 30153.0, 30246.243843903198, 30331.39337213199, 30389.544156838107, 30676.0, 24412.0, 25364.0, 25406.237812865642, 25448.491030179663, 25731.35671608225], "value": 18897.809809275943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.88978920586067, 462.8912205631678, 471.12606115655467, 472.388257206917, 489.76001759138023, 19357.128929198203, 20133.798662013374, 20633.896830515845, 20818.16727330909, 20865.478363040933, 20976.0, 21471.57056858862, 21533.353999380004, 21857.562848743015, 22704.0, 6610.735570577195, 6669.699863506142, 6720.495962802785, 6831.965840170792, 6870.0, 6669.866602667944, 6777.830554236159, 6784.389404953553, 6786.423154031889, 6787.185537735479, 6557.409833115019, 6678.198616166067, 6689.83275418116, 6702.7654032108885, 6785.321467853225, 6611.801645950629, 6630.038644396535, 6702.832429189285, 6765.797026089225, 6775.864482710318, 6551.639659818708, 6594.901076483857, 6596.868062638769, 6600.867982640369, 6606.0, 25006.874965625237, 25302.74697253022, 25550.361240969123, 25776.871115644488, 26040.34899127518, 3216.388886111631, 3225.225945773021, 3231.9173077019227, 3272.083816531375, 3374.5106959490736, 16067.758983615255, 16073.196340182943, 16120.516384508484, 16126.516204513884, 16177.919110404373, 2136.9679454808193, 2192.451887028243, 2231.241377931511, 2255.1768604459417, 2264.479169790941, 114597.4270128644, 117534.41232793867, 117795.41102294519, 117982.0, 118890.40554797256, 14065.845632086546, 17894.373696920546, 18365.81634183668, 18489.5377615558, 20149.597008059758, 42021.579784202375, 42393.0, 43271.78364108222, 43849.56150438519, 43863.0, 12311.19977201497, 12437.315947622968, 12531.373431328397, 12610.81083783744, 12654.0, 9839.606415743434, 9905.702828915144, 10053.69838904834, 10340.224483163793, 10555.736106597396, 10765.677029689123, 10910.181736369805, 11131.775504694639, 11174.497147628375, 11188.104951603853, 3821.5796262411113, 3824.9235015299537, 4074.511058672919, 4183.6234738873645, 4212.102308172177, 1177.2759752751997, 1178.4696886401155, 1180.5808937827119, 1192.2071822238115, 1212.3089838791805, 20223.797762022485, 20227.0, 20415.59168816645, 20427.4893127673, 20621.0, 1172.3200543684643, 1173.2549830857467, 1197.275648232824, 1205.951761929513, 1226.2764968668528, 10548.525316360781, 11222.494987725571, 11366.318020918772, 11368.204225704121, 11405.201635885574, 688208.0, 705612.0, 709688.0, 723411.0, 727981.0, 117699.0, 117728.41135794266, 118944.0, 119417.40291298661, 120899.39550302192, 1300.115921173611, 1343.06656873472, 1357.2263809628614, 1358.4769863602585, 1379.5999160243416, 17945.192466338784, 19067.71398428997, 20155.193792248145, 20279.0, 20336.288229912472, 53983.73008134934, 54762.45237547573, 54766.45233547773, 54918.450815492935, 55371.0, 63317.68341158265, 66539.33460665331, 67968.0, 68724.6563767198, 69130.65434672794, 7690.461667683208, 11639.53441862316, 12378.75242495163, 12700.36498175106, 12919.418626161658, 12718.491260349483, 13801.171929684251, 14143.292835358397, 14393.352299146354, 14647.487337943121, 12535.498580057061, 12601.306928118674, 12719.300438475973, 12901.677458063623, 12920.160189587647, 11842.28946263227, 11886.16796824214, 11905.40472976365, 12021.278723276631, 12022.27866328023, 11359.432028398713, 12915.547955821501, 13040.934795325962, 13138.211707297593, 13208.622447959075, 12404.75190496167, 12417.62747117588, 12540.122191446511, 12571.87428125707, 12640.431180596712, 11557.595484158013, 12311.56909508163, 12367.381630918238, 12438.937805310916, 12939.805902911652], "value": 11901.249414691441, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.33768972807, 1517.5219805761185, 1532.5325775638432, 1563.8670712989388, 1573.622330640646, 10612.469376531171, 11386.259893106942, 11395.202335836473, 11407.429628518572, 11415.31508109514, 11268.0, 11299.548018079267, 11636.883631163686, 11650.417479126052, 11658.242214256088, 2391.9282421527337, 2421.721502027272, 2429.1255148146743, 2433.5497932882386, 2435.683361163055, 2336.778006089419, 2415.8671273080063, 2428.2229686500327, 2429.101232543961, 2433.245693834908, 2347.2840783561005, 2373.3473294843884, 2382.106709983756, 2446.755324467557, 2449.9877500612474, 2286.325533967484, 2320.895559699809, 2324.9883750581225, 2330.8018818400456, 2349.1308215960034, 2307.734610519795, 2326.8836558172193, 2336.299110266913, 2337.0651739304412, 2338.018032426385, 11234.269772464764, 11440.9703126719, 11447.141464390204, 11456.198066135374, 11469.139814513861, 5801.100829371454, 5944.554158438134, 5946.613470124428, 5957.255343082114, 5965.164876917192, 3425.6231814500384, 3443.3974054540604, 3505.7545971781988, 3527.9118022049656, 3566.1084728817796, 1254.2223821230896, 1267.7084270617809, 1292.0503429978958, 1306.9019823513179, 1359.7348517039181, 13902.304884755722, 14244.145351278852, 15670.373185072584, 16904.98570085799, 17140.0, 4738.95261047392, 4768.998510312804, 4865.805367785249, 4891.926621100686, 4900.730459824744, 13147.802782958262, 13237.205767653972, 13254.0, 13264.336783160803, 13264.73470530584, 6017.608855424383, 6028.698565071729, 6028.969855150696, 6033.4871535919065, 6037.1246169305205, 5362.973185134049, 5425.864353391197, 5431.864203394868, 5481.506664400223, 5499.1476321170285, 3889.4360317753767, 4001.079751657135, 4029.294873397144, 4061.918761624751, 4080.1431699342875, 992.980140397188, 1001.7846163074973, 1004.3421558878946, 1008.6016023670609, 1015.5582321690089, 928.962841486333, 929.990700093004, 936.2977766674994, 986.6842610364614, 1108.490094556513, 7180.497365184491, 7364.22675619063, 7476.887846682304, 7845.0978137513775, 7868.724594639275, 1124.173732306754, 1137.6700756780606, 1141.1897552737482, 1154.982675259855, 1160.1067178272683, 4807.95192048075, 4879.707217566957, 4935.407751069894, 4954.1082605130705, 5019.799208031711, 14005.579832605039, 14625.561233163022, 14629.341679624442, 14641.633959150893, 14654.926725366518, 14268.215248161243, 14404.741153913112, 14634.634134146732, 14690.41238350454, 14694.485693000694, 8797.51613661242, 10750.73123171927, 10800.945995269973, 10926.781464370828, 10930.672079837617, 6526.5105117117355, 7558.055243094614, 7602.543847369176, 7713.074431068308, 7728.806779830325, 12713.42789574453, 12823.0, 12852.0, 13194.868051319363, 13671.111377760413, 13683.0, 13700.65748356299, 13872.791908121184, 13882.0, 13950.511732089328, 9128.726138215865, 9287.303452240974, 9358.906410936075, 9363.85954210701, 9385.85921211196, 10743.925607439194, 10745.516451759846, 10838.403887786248, 10848.132149428184, 10874.78250434971, 9369.156775890202, 9452.338336316392, 9465.763355916157, 9571.090746379083, 9757.170640495637, 9107.134822191882, 9135.314851386305, 9150.130737579919, 9164.862527061967, 9335.813283734424, 8399.202075802788, 8534.103919088406, 8542.06037335905, 8544.615492302986, 8550.529720865412, 6478.449331806849, 6493.642849643315, 6526.44525215343, 6543.083968244355, 6633.270340262471, 6553.967230163818, 6566.310537393505, 6636.701348439428, 6771.830704232433, 6800.9319906802275], "value": 5406.638067444252, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.4502856229774, 436.95848894355026, 438.9912201755965, 443.3704140121028, 444.4200318584248, 5342.252084708139, 5371.677699338033, 5405.216243644674, 5410.296661434018, 5420.83737487875, 5145.588352931766, 5312.203169524573, 5317.2821669074665, 5324.5740340772745, 5348.358197016354, 4430.291153415455, 4503.504614492404, 4504.954950450495, 4531.977340113295, 4537.614302784251, 4478.686491945565, 4525.343825145352, 4536.5236650151755, 4545.227311357077, 4577.382053422795, 4392.124944788257, 4538.228501154795, 4539.116266749312, 4564.8174073037035, 4567.584048944821, 4448.822047118128, 4469.374287599706, 4480.48474425442, 4484.932726009112, 4486.730796152241, 4226.197022565702, 4228.1966426378885, 4230.619244267999, 4245.957540424587, 4246.808893599796, 5184.755658641937, 5192.001079584349, 5201.375834899836, 5207.973960130175, 5216.035812782275, 4985.077760614298, 5146.58468921046, 5175.715335656538, 5176.301199338079, 5178.98628562863, 5573.888522229533, 5590.888182236333, 5598.58010649203, 5602.498438475953, 5630.239917611111, 4417.889552761175, 4676.976615116937, 4691.906161876777, 4710.929336059962, 4720.881977950578, 5542.889142217174, 5616.494515493625, 5742.425757424266, 5794.0, 5806.825795226107, 4753.433146101067, 4756.9286460703115, 4762.976185119052, 4788.695799898059, 4821.590164836029, 4791.736454495036, 4804.804804804766, 4866.124097662455, 4866.321119213865, 4868.196747536687, 4840.806367745251, 4876.95123048765, 5154.9484505155215, 5185.455527169593, 5200.803815122467, 5566.916496252559, 5588.413216612197, 5591.496765291062, 5596.524295434933, 5609.326880774333, 1656.5941344370706, 1669.5408762590384, 1669.5791102900841, 1679.3954176496452, 1682.0832646207846, 1253.5988483685132, 1278.9808152877715, 1290.2838924396863, 1295.8639342868862, 1306.73865226955, 5239.371275446971, 5459.945400546023, 5510.972445137829, 5601.663900166003, 5630.971845140748, 5730.455606717355, 5750.971245143747, 5765.827025189251, 5768.625039372428, 5809.767609295581, 5473.01485732564, 5536.640118392292, 5546.55627549795, 5560.944390556124, 5561.304836895388, 2910.4324656691742, 3008.9398212035635, 3014.5026070698527, 3024.803387779787, 3033.605631267921, 5738.0, 5864.237649105674, 5879.706014699248, 5886.529077673861, 5905.43898329658, 5562.888742225133, 5776.33572139201, 5812.808374283261, 5881.647101173943, 5922.881542369215, 5839.284732599277, 5852.600433822113, 5890.911636325459, 5903.793367232126, 5917.372722501346, 5686.943130568641, 5735.8852822944145, 5736.311642602915, 5753.683547404933, 5787.247657804627, 5426.430224826337, 5609.951044060378, 5633.2395126657375, 5699.629524080921, 5716.314042314949, 5660.660360378391, 5735.369109398046, 5765.308163020464, 5961.463468287875, 5976.6712830792985, 4779.187538118459, 4789.281607758725, 4884.340614017062, 4897.265410188358, 4906.362172917497, 5488.506034456918, 5705.7432415542235, 5782.797602084075, 5783.392743761844, 5888.116782482662, 5587.385387607279, 5625.718714064362, 5663.0, 5665.365604993698, 5691.971540142273, 5638.915416268841, 5651.0, 5688.658680479184, 5726.484616384545, 5735.598508104393, 5398.568114550748, 5607.756897241136, 5680.460356266148, 5696.715164241854, 5697.544196464356, 5642.971785141048, 5649.5762817789655, 5675.687837168995, 5695.658260504383, 5702.543796496353, 5405.324334458192, 5545.0, 5569.805056822992, 5591.4688104629995, 5597.412271711574], "value": 4516.590846903102, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.6519594368337, 855.2217482091294, 915.5954101063064, 918.081918081918, 924.9213816825569, 16984.660306793856, 18013.369532066365, 18135.546611334725, 18180.727289090657, 18230.27078916842, 16069.83930160701, 16308.51074467765, 16322.591935201595, 16346.34614615414, 17132.400365987192, 668.5387082912799, 683.4464084091901, 854.2482615298528, 866.1814585216972, 880.4585180114216, 865.3855762408666, 873.0614589316478, 876.3909083187187, 878.714417814213, 878.9516576588285, 933.5145724223399, 934.1359242700509, 934.8036912248436, 934.9859752103724, 935.504182783126, 704.0952376196589, 719.4136778525485, 898.2364989758718, 923.0999775219175, 929.832630126577, 682.9180498340231, 683.3576438148125, 683.3883674111692, 683.6581709145428, 683.6718375179942, 17320.480385588453, 17613.119344032748, 17643.20605572752, 17686.115694215237, 17921.641567168714, 14841.480548180762, 15065.774013389808, 15307.923460382739, 15399.23003849803, 15865.286062127232, 14724.0, 14808.0, 15093.47172848945, 15238.847611523854, 15271.923640381838, 4880.585150262232, 4894.730789806595, 4909.852704418873, 5019.899602007976, 5108.514691104339, 61623.38376616266, 88795.55602222012, 90169.54915225448, 90481.54759226162, 92383.53808230982, 12395.566155184526, 12538.749225015632, 12601.306928118858, 12680.365981700877, 12793.232406055666, 22651.660225096635, 22796.202132925268, 22815.88592057029, 22824.65763013556, 22925.197618083286, 8565.229129378386, 8691.695790647296, 8805.559722013873, 8899.377043606886, 9019.549022548847, 5084.694918304839, 5155.252488389163, 5218.869528261824, 5233.947660523422, 5271.235670827708, 3773.188764415609, 3876.379779235309, 3883.514560679915, 3895.6493915547735, 3903.4144878268485, 651.3453978751419, 659.0838734159522, 667.7662818013656, 671.4359937652298, 673.0610797936948, 853.3301358433599, 871.2690092796178, 890.0832142892831, 905.3752910491739, 910.6129894794685, 11695.590654327058, 11759.000969835148, 11831.76336473283, 12152.149349545447, 12497.062720295833, 843.2789964580318, 850.5194565070715, 859.7721603775004, 865.5066612031081, 869.1221865915471, 5435.537979271806, 5667.971660141673, 5689.715514224272, 5724.685142317213, 5748.798792042258, 80133.0, 81037.0, 82698.58650706708, 85166.57416712877, 85881.0, 71582.0, 71991.64004179946, 72305.2769472309, 73705.26294737091, 74920.62539687267, 64666.35333646603, 65992.6700366495, 66078.0, 66111.0, 66420.33579664238, 12224.633261002184, 12287.631371058882, 12333.198342108093, 12380.938095309466, 12496.502657980182, 44302.335464968695, 45149.54850451453, 45562.31656525088, 45587.0, 45734.313985289584, 50858.49141508537, 50918.490815091376, 52859.41421757353, 53633.0, 56634.43365566291, 13822.93088534551, 13985.44058237659, 14048.367823447765, 14060.296985150906, 14061.648458788612, 16525.0, 16621.41825036118, 16721.832781672027, 16821.66356672835, 17077.060761658227, 12779.680507987374, 16057.0, 16331.83668163303, 16880.915595422437, 17555.736663950305, 15060.698786023999, 15562.610934726723, 15577.532674019798, 15847.128407937675, 16173.0, 14944.775828362366, 15216.239188040776, 15369.231538422811, 15708.68582628364, 16054.117023563349, 11708.414579271173, 12254.387280635754, 12411.193272437262, 12649.747005060031, 13095.869041309465, 12834.16577922432, 13240.337983100613, 13427.395767190696, 13666.385012674255, 13681.179129252278], "value": 8101.213612955574, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 111.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 121.0, 119.0, 164.0, 121.0, 115.0, 117.0, 115.0, 133.0, 116.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 175.0, 162.0, 162.0, 161.0, 161.0, 160.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 110.0, 76.0, 76.0, 75.0, 77.0, 105.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 104.0, 82.0, 82.0, 81.0, 98.0, 83.0, 82.0, 101.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 76.0, 79.0, 86.0, 86.0, 85.0, 80.0, 82.0, 80.0, 102.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 120.0, 133.0, 118.0, 118.0, 129.0, 122.0, 126.0, 136.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 158.0, 163.0, 161.0, 162.0, 161.0, 175.0, 158.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 60.0, 61.0, 60.0, 62.0, 60.0, 59.0, 59.0, 60.0], "value": 60.7, "unit": "score"}]}]} 14:50:07 INFO - Return code: 0 14:50:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:08 ERROR - # TBPL SUCCESS # 14:50:08 INFO - Running post-action listener: _resource_record_post_action 14:50:08 INFO - [mozharness: 2017-04-08 21:50:08.077261Z] Finished run-tests step (success) 14:50:08 INFO - Running post-run listener: _resource_record_post_run 14:50:08 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 365150208; Read time: 0; Write time: 203988 14:50:08 INFO - TinderboxPrint: CPU usage
13.1% 14:50:08 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:50:08 INFO - TinderboxPrint: I/O write bytes / time
365,150,208 / 203,988 14:50:08 INFO - TinderboxPrint: CPU idle
8,270.6 (86.9%) 14:50:08 INFO - TinderboxPrint: CPU user
1,203.0 (12.6%) 14:50:08 INFO - TinderboxPrint: Swap in / out
0 / 0 14:50:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111005696; Read time: 0; Write time: 87692 14:50:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 254144512; Read time: 0; Write time: 116296 14:50:08 INFO - Running post-run listener: _upload_blobber_files 14:50:08 INFO - Blob upload gear active. 14:50:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:08 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 14:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:50:09 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:09 INFO - (blobuploader) - INFO - Done attempting. 14:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:50:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:10 INFO - (blobuploader) - INFO - Done attempting. 14:50:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:50:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:11 INFO - (blobuploader) - INFO - Done attempting. 14:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:15 INFO - (blobuploader) - INFO - Done attempting. 14:50:15 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:15 INFO - Return code: 0 14:50:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94dc7b3cf02cc6a95007af78d3fd415fa9bcf39e87837bf9f2e385c4f981f85471b1a603526b195a2bcad5c3fb84f18408d5d156d1560683d8f78c8f8a102387", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3216c8f84f6d2b32c886267702898a45c76f87ac8ae2a8044e039dca469626734f3c8a8a91230e8c30d2791e8bd49d6f994ee9eac6088e12671b3e16ab65b5d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/283ae77aeae9b082a118b9f22f5deda200e9bf902c3f23fef52c8afacb4b06a4c0ab35fcf212aa8872986349b90f3bc7758cb56ec86e9bbed2bea00cfb4bb305", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83c062c40a72ddbaa6e7655da50b1779526fc83b02ed72192e8df6aaf52cfa5c7d88ecae5c0cd2217a35a60baa9e42cb133b5a43ba378d76b7ebcda17c233498"} 14:50:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:15 INFO - Contents: 14:50:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94dc7b3cf02cc6a95007af78d3fd415fa9bcf39e87837bf9f2e385c4f981f85471b1a603526b195a2bcad5c3fb84f18408d5d156d1560683d8f78c8f8a102387", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3216c8f84f6d2b32c886267702898a45c76f87ac8ae2a8044e039dca469626734f3c8a8a91230e8c30d2791e8bd49d6f994ee9eac6088e12671b3e16ab65b5d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/283ae77aeae9b082a118b9f22f5deda200e9bf902c3f23fef52c8afacb4b06a4c0ab35fcf212aa8872986349b90f3bc7758cb56ec86e9bbed2bea00cfb4bb305", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83c062c40a72ddbaa6e7655da50b1779526fc83b02ed72192e8df6aaf52cfa5c7d88ecae5c0cd2217a35a60baa9e42cb133b5a43ba378d76b7ebcda17c233498"} 14:50:15 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:15 INFO - Copying logs to upload dir... 14:50:15 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.850454 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-08 14:50:14.731917) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 14:50:14.735623) ========= 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=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94dc7b3cf02cc6a95007af78d3fd415fa9bcf39e87837bf9f2e385c4f981f85471b1a603526b195a2bcad5c3fb84f18408d5d156d1560683d8f78c8f8a102387", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3216c8f84f6d2b32c886267702898a45c76f87ac8ae2a8044e039dca469626734f3c8a8a91230e8c30d2791e8bd49d6f994ee9eac6088e12671b3e16ab65b5d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/283ae77aeae9b082a118b9f22f5deda200e9bf902c3f23fef52c8afacb4b06a4c0ab35fcf212aa8872986349b90f3bc7758cb56ec86e9bbed2bea00cfb4bb305", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83c062c40a72ddbaa6e7655da50b1779526fc83b02ed72192e8df6aaf52cfa5c7d88ecae5c0cd2217a35a60baa9e42cb133b5a43ba378d76b7ebcda17c233498"} build_url:https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/VjzAqXWxTGaVtHMH9rjiAg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94dc7b3cf02cc6a95007af78d3fd415fa9bcf39e87837bf9f2e385c4f981f85471b1a603526b195a2bcad5c3fb84f18408d5d156d1560683d8f78c8f8a102387", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3216c8f84f6d2b32c886267702898a45c76f87ac8ae2a8044e039dca469626734f3c8a8a91230e8c30d2791e8bd49d6f994ee9eac6088e12671b3e16ab65b5d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/283ae77aeae9b082a118b9f22f5deda200e9bf902c3f23fef52c8afacb4b06a4c0ab35fcf212aa8872986349b90f3bc7758cb56ec86e9bbed2bea00cfb4bb305", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83c062c40a72ddbaa6e7655da50b1779526fc83b02ed72192e8df6aaf52cfa5c7d88ecae5c0cd2217a35a60baa9e42cb133b5a43ba378d76b7ebcda17c233498"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 14:50:14.760779) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:50:14.761098) ========= 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=20e20d796406ba5117c4a6f1000001ba-1491686961.941915-2105892572 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004331 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:50:14.844148) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 14:50:14.844468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 14:50:14.844755) ========= ========= Total master_lag: 0.17 =========